builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0040 starttime: 1473358734.48 results: success (0) buildid: 20160908090930 builduid: a13fb52505784452b93709472ebdea06 revision: 5d854c8d076533b9426bc3040979ccf8e0b2404b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 11:18:54.477190) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 11:18:54.478250) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 11:18:54.478992) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 11:18:54.506415) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 11:18:54.507179) ========= 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.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners TMPDIR=/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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-08 11:18:54-- 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% 193M=0s 2016-09-08 11:18:54 (193 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129750 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 11:18:54.665930) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 11:18:54.666487) ========= 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.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners TMPDIR=/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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.028776 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 11:18:54.719143) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 11:18:54.719973) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 5d854c8d076533b9426bc3040979ccf8e0b2404b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 5d854c8d076533b9426bc3040979ccf8e0b2404b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners TMPDIR=/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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-08 11:18:54,798 truncating revision to first 12 chars 2016-09-08 11:18:54,798 Setting DEBUG logging. 2016-09-08 11:18:54,798 attempt 1/10 2016-09-08 11:18:54,798 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/5d854c8d0765?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 11:18:55,104 unpacking tar archive at: autoland-5d854c8d0765/testing/mozharness/ program finished with exit code 0 elapsedTime=0.575823 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 11:18:55.325096) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 11:18:55.325925) ========= script_repo_revision: 5d854c8d076533b9426bc3040979ccf8e0b2404b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 11:18:55.326639) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 11:18:55.326968) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 11:18:55.346354) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-08 11:18:55.347079) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners TMPDIR=/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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 11:18:55 INFO - MultiFileLogger online at 20160908 11:18:55 in /builds/slave/test 11:18:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 11:18:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:18:55 INFO - {'append_to_log': False, 11:18:55 INFO - 'base_work_dir': '/builds/slave/test', 11:18:55 INFO - 'blob_upload_branch': 'Autoland', 11:18:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:18:55 INFO - 'branch': 'Autoland', 11:18:55 INFO - 'buildbot_json_path': 'buildprops.json', 11:18:55 INFO - 'config_files': ('talos/mac_config.py',), 11:18:55 INFO - 'default_actions': ('clobber', 11:18:55 INFO - 'read-buildbot-config', 11:18:55 INFO - 'download-and-extract', 11:18:55 INFO - 'populate-webroot', 11:18:55 INFO - 'create-virtualenv', 11:18:55 INFO - 'install', 11:18:55 INFO - 'run-tests'), 11:18:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:18:55 INFO - 'download_minidump_stackwalk': True, 11:18:55 INFO - 'download_symbols': 'ondemand', 11:18:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:18:55 INFO - 'tooltool.py': '/tools/tooltool.py', 11:18:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:18:55 INFO - '/tools/misc-python/virtualenv.py')}, 11:18:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:18:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:18:55 INFO - 'installer_path': 'installer.exe', 11:18:55 INFO - 'log_level': 'info', 11:18:55 INFO - 'log_name': 'talos', 11:18:55 INFO - 'log_to_console': True, 11:18:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:18:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:18:55 INFO - 'opt_config_files': (), 11:18:55 INFO - 'pip_index': False, 11:18:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:18:55 INFO - 'cmd': ('bash', 11:18:55 INFO - '-c', 11:18:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:18:55 INFO - 'enabled': True, 11:18:55 INFO - 'halt_on_failure': False, 11:18:55 INFO - 'name': 'check_screen_resolution'},), 11:18:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:18:55 INFO - 'cmd': ('bash', 11:18:55 INFO - '-c', 11:18:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:18:55 INFO - 'enabled': True, 11:18:55 INFO - 'halt_on_failure': False, 11:18:55 INFO - 'name': 'check_screen_resolution'},), 11:18:55 INFO - 'run_cmd_checks_enabled': True, 11:18:55 INFO - 'sps_profile': False, 11:18:55 INFO - 'sps_profile_interval': 0, 11:18:55 INFO - 'suite': 'dromaeojs-e10s', 11:18:55 INFO - 'system_bits': '32', 11:18:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:18:55 INFO - 'title': 't-yosemite-r7-0040', 11:18:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:18:55 INFO - 'use_talos_json': True, 11:18:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:18:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:18:55 INFO - 'work_dir': 'build'} 11:18:55 INFO - [mozharness: 2016-09-08 18:18:55.546461Z] Running clobber step. 11:18:55 INFO - Running pre-action listener: _resource_record_pre_action 11:18:55 INFO - Running main action method: clobber 11:18:55 INFO - rmtree: /builds/slave/test/build 11:18:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:18:57 INFO - Running post-action listener: _resource_record_post_action 11:18:57 INFO - [mozharness: 2016-09-08 18:18:57.708403Z] Finished clobber step (success) 11:18:57 INFO - [mozharness: 2016-09-08 18:18:57.708541Z] Running read-buildbot-config step. 11:18:57 INFO - Running pre-action listener: _resource_record_pre_action 11:18:57 INFO - Running main action method: read_buildbot_config 11:18:57 INFO - Using buildbot properties: 11:18:57 INFO - { 11:18:57 INFO - "project": "", 11:18:57 INFO - "product": "firefox", 11:18:57 INFO - "script_repo_revision": "production", 11:18:57 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 11:18:57 INFO - "repository": "", 11:18:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 11:18:57 INFO - "buildid": "20160908090930", 11:18:57 INFO - "pgo_build": "False", 11:18:57 INFO - "basedir": "/builds/slave/test", 11:18:57 INFO - "buildnumber": 172, 11:18:57 INFO - "slavename": "t-yosemite-r7-0040", 11:18:57 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 11:18:57 INFO - "platform": "yosemite_r7", 11:18:57 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 11:18:57 INFO - "branch": "autoland", 11:18:57 INFO - "repo_path": "integration/autoland", 11:18:57 INFO - "revision": "5d854c8d076533b9426bc3040979ccf8e0b2404b", 11:18:57 INFO - "stage_platform": "macosx64", 11:18:57 INFO - "builduid": "a13fb52505784452b93709472ebdea06", 11:18:57 INFO - "slavebuilddir": "test" 11:18:57 INFO - } 11:18:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:18:57 INFO - Running post-action listener: _resource_record_post_action 11:18:57 INFO - [mozharness: 2016-09-08 18:18:57.711789Z] Finished read-buildbot-config step (success) 11:18:57 INFO - [mozharness: 2016-09-08 18:18:57.711911Z] Running download-and-extract step. 11:18:57 INFO - Running pre-action listener: _resource_record_pre_action 11:18:57 INFO - Running main action method: download_and_extract 11:18:57 INFO - mkdir: /builds/slave/test/build/tests 11:18:57 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')]} 11:18:57 INFO - trying https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:18:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/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 11:18:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/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 11:18:58 INFO - Downloaded 1199 bytes. 11:18:58 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:18:58 INFO - Using the following test package requirements: 11:18:58 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:18:58 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:18:58 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:18:58 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:18:58 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:18:58 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:18:58 INFO - u'jsshell-mac.zip'], 11:18:58 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:18:58 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:18:58 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:18:58 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:18:58 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:18:58 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:18:58 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:18:58 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:18:58 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:18:58 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:18:58 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:18:58 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:18:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:18:59 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:19:06 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 11:19:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:19:07 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:19:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:19:14 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:19:15 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')]} 11:19:15 INFO - trying https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:19:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:19:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/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 11:19:18 INFO - Downloaded 111525143 bytes. 11:19:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:19:18 INFO - mkdir: /builds/slave/test/properties 11:19:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:19:18 INFO - Writing to file /builds/slave/test/properties/build_url 11:19:18 INFO - Contents: 11:19:18 INFO - build_url:https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:19:19 INFO - Running post-action listener: _resource_record_post_action 11:19:19 INFO - Running post-action listener: set_extra_try_arguments 11:19:19 INFO - [mozharness: 2016-09-08 18:19:19.496574Z] Finished download-and-extract step (success) 11:19:19 INFO - [mozharness: 2016-09-08 18:19:19.496683Z] Running populate-webroot step. 11:19:19 INFO - Running pre-action listener: _resource_record_pre_action 11:19:19 INFO - Running main action method: populate_webroot 11:19:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:19:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:19:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:19:19 INFO - '--tppagecycles', 11:19:19 INFO - '7'], 11:19:19 INFO - 'tests': ['tsvgm']}}, 11:19:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:19:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:19:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:19:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:19:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:19:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:19:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:19 INFO - 'talos_options': ['--disable-e10s'], 11:19:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:19:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:19:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:19 INFO - 'talos_options': ['--disable-e10s'], 11:19:19 INFO - 'tests': ['damp', 'tps']}, 11:19:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:19 INFO - 'tests': ['damp', 'tps']}, 11:19:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:19:19 INFO - 'tests': ['dromaeo_dom']}, 11:19:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:19:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:19:19 INFO - 'tests': ['basic_compositor_video']}, 11:19:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:19:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:19:19 INFO - 'tests': ['a11yr', 11:19:19 INFO - 'ts_paint', 11:19:19 INFO - 'tpaint', 11:19:19 INFO - 'sessionrestore', 11:19:19 INFO - 'sessionrestore_no_auto_restore', 11:19:19 INFO - 'tabpaint']}, 11:19:19 INFO - 'other-e10s': {'tests': ['a11yr', 11:19:19 INFO - 'ts_paint', 11:19:19 INFO - 'tpaint', 11:19:19 INFO - 'sessionrestore', 11:19:19 INFO - 'sessionrestore_no_auto_restore', 11:19:19 INFO - 'tabpaint']}, 11:19:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:19:19 INFO - 'ts_paint', 11:19:19 INFO - 'tpaint', 11:19:19 INFO - 'sessionrestore', 11:19:19 INFO - 'sessionrestore_no_auto_restore', 11:19:19 INFO - 'tabpaint']}, 11:19:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:19:19 INFO - 'ts_paint', 11:19:19 INFO - 'tpaint', 11:19:19 INFO - 'sessionrestore', 11:19:19 INFO - 'sessionrestore_no_auto_restore', 11:19:19 INFO - 'tabpaint']}, 11:19:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:19:19 INFO - 'tests': ['a11yr', 11:19:19 INFO - 'ts_paint', 11:19:19 INFO - 'tpaint', 11:19:19 INFO - 'sessionrestore', 11:19:19 INFO - 'sessionrestore_no_auto_restore', 11:19:19 INFO - 'tabpaint']}, 11:19:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:19:19 INFO - 'tests': ['a11yr', 11:19:19 INFO - 'ts_paint', 11:19:19 INFO - 'tpaint', 11:19:19 INFO - 'sessionrestore', 11:19:19 INFO - 'sessionrestore_no_auto_restore', 11:19:19 INFO - 'tabpaint']}, 11:19:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:19:19 INFO - 'tests': ['tsvgx', 11:19:19 INFO - 'tsvgr_opacity', 11:19:19 INFO - 'tart', 11:19:19 INFO - 'tscrollx', 11:19:19 INFO - 'cart']}, 11:19:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:19:19 INFO - 'tsvgr_opacity', 11:19:19 INFO - 'tart', 11:19:19 INFO - 'tscrollx', 11:19:19 INFO - 'cart']}, 11:19:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:19 INFO - 'talos_options': ['--disable-e10s'], 11:19:19 INFO - 'tests': ['tp5o']}, 11:19:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:19 INFO - 'tests': ['tp5o']}, 11:19:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:19 INFO - 'talos_options': ['--disable-e10s', 11:19:19 INFO - '--xperf_path', 11:19:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:19:19 INFO - 'tests': ['tp5n']}, 11:19:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:19 INFO - 'talos_options': ['--xperf_path', 11:19:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:19:19 INFO - 'tests': ['tp5n']}}, 11:19:19 INFO - 'talos.zip': {'path': '', 11:19:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:19:19 INFO - Running post-action listener: _resource_record_post_action 11:19:19 INFO - [mozharness: 2016-09-08 18:19:19.514920Z] Finished populate-webroot step (success) 11:19:19 INFO - [mozharness: 2016-09-08 18:19:19.515052Z] Running create-virtualenv step. 11:19:19 INFO - Running pre-action listener: _resource_record_pre_action 11:19:19 INFO - Running main action method: create_virtualenv 11:19:19 INFO - Creating virtualenv /builds/slave/test/build/venv 11:19:19 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 11:19:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:19:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:19:19 INFO - Using real prefix '/tools/python27' 11:19:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:19:20 INFO - Installing distribute.............................................................................................................................................................................................done. 11:19:23 INFO - Installing pip.................done. 11:19:23 INFO - Return code: 0 11:19:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107bc3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c58618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86d958a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b27d30>, '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 0x10788cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1fb20>, '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.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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 11:19:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:19:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IUaBfCVomZ/Render', 11:19:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:23 INFO - 'HOME': '/Users/cltbld', 11:19:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:23 INFO - 'LOGNAME': 'cltbld', 11:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:23 INFO - 'MOZ_NO_REMOTE': '1', 11:19:23 INFO - 'NO_EM_RESTART': '1', 11:19:23 INFO - 'PAGER': '/bin/cat', 11:19:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:23 INFO - 'PWD': '/builds/slave/test', 11:19:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:23 INFO - 'SHELL': '/bin/bash', 11:19:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZAMlF40Lw6/Listeners', 11:19:23 INFO - 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/', 11:19:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:23 INFO - 'USER': 'cltbld', 11:19:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:23 INFO - 'XPC_FLAGS': '0x0', 11:19:23 INFO - 'XPC_SERVICE_NAME': '0', 11:19:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:23 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 11:19:23 INFO - Cleaning up... 11:19:23 INFO - Return code: 0 11:19:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107bc3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c58618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86d958a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b27d30>, '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 0x10788cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1fb20>, '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.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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 11:19:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:19:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IUaBfCVomZ/Render', 11:19:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:23 INFO - 'HOME': '/Users/cltbld', 11:19:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:23 INFO - 'LOGNAME': 'cltbld', 11:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:23 INFO - 'MOZ_NO_REMOTE': '1', 11:19:23 INFO - 'NO_EM_RESTART': '1', 11:19:23 INFO - 'PAGER': '/bin/cat', 11:19:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:23 INFO - 'PWD': '/builds/slave/test', 11:19:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:23 INFO - 'SHELL': '/bin/bash', 11:19:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZAMlF40Lw6/Listeners', 11:19:23 INFO - 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/', 11:19:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:23 INFO - 'USER': 'cltbld', 11:19:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:23 INFO - 'XPC_FLAGS': '0x0', 11:19:23 INFO - 'XPC_SERVICE_NAME': '0', 11:19:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:23 INFO - Downloading/unpacking psutil>=3.1.1 11:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:19:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:19:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:19:27 INFO - Installing collected packages: psutil 11:19:27 INFO - Running setup.py install for psutil 11:19:27 INFO - building 'psutil._psutil_osx' extension 11:19:27 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 11:19:27 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 11:19:27 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 11:19:27 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 11:19:28 INFO - building 'psutil._psutil_posix' extension 11:19:28 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 11:19:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:19:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:19:28 INFO - ^ 11:19:28 INFO - 1 warning generated. 11:19:28 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 11:19:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:19:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:19:28 INFO - Successfully installed psutil 11:19:28 INFO - Cleaning up... 11:19:28 INFO - Return code: 0 11:19:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:28 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 0x107bc3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c58618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86d958a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b27d30>, '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 0x10788cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1fb20>, '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.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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 11:19:28 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 11:19:28 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 11:19:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IUaBfCVomZ/Render', 11:19:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:28 INFO - 'HOME': '/Users/cltbld', 11:19:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:28 INFO - 'LOGNAME': 'cltbld', 11:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:28 INFO - 'MOZ_NO_REMOTE': '1', 11:19:28 INFO - 'NO_EM_RESTART': '1', 11:19:28 INFO - 'PAGER': '/bin/cat', 11:19:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:28 INFO - 'PWD': '/builds/slave/test', 11:19:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:28 INFO - 'SHELL': '/bin/bash', 11:19:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZAMlF40Lw6/Listeners', 11:19:28 INFO - 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/', 11:19:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:28 INFO - 'USER': 'cltbld', 11:19:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:28 INFO - 'XPC_FLAGS': '0x0', 11:19:28 INFO - 'XPC_SERVICE_NAME': '0', 11:19:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:19:28 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 11:19:28 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 11:19:28 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 11:19:28 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 11:19:28 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 11:19:28 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 11:19:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:19:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:19:31 INFO - Installing collected packages: mozsystemmonitor 11:19:31 INFO - Running setup.py install for mozsystemmonitor 11:19:31 INFO - Successfully installed mozsystemmonitor 11:19:31 INFO - Cleaning up... 11:19:31 INFO - Return code: 0 11:19:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:19: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')]} 11:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19: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')]} 11:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:31 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 0x107bc3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c58618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86d958a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b27d30>, '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 0x10788cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1fb20>, '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.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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 11:19:31 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 11:19:31 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 11:19:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IUaBfCVomZ/Render', 11:19:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:31 INFO - 'HOME': '/Users/cltbld', 11:19:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:31 INFO - 'LOGNAME': 'cltbld', 11:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:31 INFO - 'MOZ_NO_REMOTE': '1', 11:19:31 INFO - 'NO_EM_RESTART': '1', 11:19:31 INFO - 'PAGER': '/bin/cat', 11:19:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:31 INFO - 'PWD': '/builds/slave/test', 11:19:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:31 INFO - 'SHELL': '/bin/bash', 11:19:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZAMlF40Lw6/Listeners', 11:19:31 INFO - 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/', 11:19:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:31 INFO - 'USER': 'cltbld', 11:19:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:31 INFO - 'XPC_FLAGS': '0x0', 11:19:31 INFO - 'XPC_SERVICE_NAME': '0', 11:19:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:31 INFO - Downloading/unpacking jsonschema==2.5.1 11:19:31 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 11:19:31 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 11:19:31 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 11:19:31 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 11:19:31 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 11:19:31 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 11:19:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:19:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:34 INFO - Downloading functools32-3.2.3-2.tar.gz 11:19:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:19:34 INFO - warning: no files found matching '*.txt' 11:19:34 INFO - no previously-included directories found matching 'build' 11:19:34 INFO - no previously-included directories found matching 'dist' 11:19:34 INFO - no previously-included directories found matching '.git*' 11:19:34 INFO - Installing collected packages: jsonschema, functools32 11:19:34 INFO - Running setup.py install for functools32 11:19:34 INFO - warning: no files found matching '*.txt' 11:19:34 INFO - no previously-included directories found matching 'build' 11:19:34 INFO - no previously-included directories found matching 'dist' 11:19:34 INFO - no previously-included directories found matching '.git*' 11:19:34 INFO - Successfully installed jsonschema functools32 11:19:34 INFO - Cleaning up... 11:19:34 INFO - Return code: 0 11:19:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:19:34 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')]} 11:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:34 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')]} 11:19:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:34 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 0x107bc3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c58618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86d958a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b27d30>, '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 0x10788cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1fb20>, '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.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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 11:19:34 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 11:19:34 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 11:19:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IUaBfCVomZ/Render', 11:19:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:34 INFO - 'HOME': '/Users/cltbld', 11:19:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:34 INFO - 'LOGNAME': 'cltbld', 11:19:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:34 INFO - 'MOZ_NO_REMOTE': '1', 11:19:34 INFO - 'NO_EM_RESTART': '1', 11:19:34 INFO - 'PAGER': '/bin/cat', 11:19:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:34 INFO - 'PWD': '/builds/slave/test', 11:19:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:34 INFO - 'SHELL': '/bin/bash', 11:19:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZAMlF40Lw6/Listeners', 11:19:34 INFO - 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/', 11:19:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:34 INFO - 'USER': 'cltbld', 11:19:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:34 INFO - 'XPC_FLAGS': '0x0', 11:19:34 INFO - 'XPC_SERVICE_NAME': '0', 11:19:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:19:35 INFO - Cleaning up... 11:19:35 INFO - Return code: 0 11:19:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:19:35 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')]} 11:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:35 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')]} 11:19:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:35 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 0x107bc3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c58618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86d958a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b27d30>, '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 0x10788cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1fb20>, '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.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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 11:19:35 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 11:19:35 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 11:19:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IUaBfCVomZ/Render', 11:19:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:35 INFO - 'HOME': '/Users/cltbld', 11:19:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:35 INFO - 'LOGNAME': 'cltbld', 11:19:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:35 INFO - 'MOZ_NO_REMOTE': '1', 11:19:35 INFO - 'NO_EM_RESTART': '1', 11:19:35 INFO - 'PAGER': '/bin/cat', 11:19:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:35 INFO - 'PWD': '/builds/slave/test', 11:19:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:35 INFO - 'SHELL': '/bin/bash', 11:19:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZAMlF40Lw6/Listeners', 11:19:35 INFO - 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/', 11:19:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:35 INFO - 'USER': 'cltbld', 11:19:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:35 INFO - 'XPC_FLAGS': '0x0', 11:19:35 INFO - 'XPC_SERVICE_NAME': '0', 11:19:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:35 INFO - Downloading/unpacking blobuploader==1.2.4 11:19:35 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 11:19:35 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 11:19:35 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 11:19:35 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 11:19:35 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 11:19:35 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 11:19:37 INFO - Downloading blobuploader-1.2.4.tar.gz 11:19:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:19:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:19:38 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 11:19:38 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 11:19:38 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 11:19:38 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 11:19:38 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 11:19:38 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 11:19:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:19:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:19:38 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 11:19:38 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 11:19:38 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 11:19:38 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 11:19:38 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 11:19:38 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 11:19:39 INFO - Downloading docopt-0.6.1.tar.gz 11:19:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:19:39 INFO - Installing collected packages: blobuploader, requests, docopt 11:19:39 INFO - Running setup.py install for blobuploader 11:19:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:19:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:19:39 INFO - Running setup.py install for requests 11:19:39 INFO - Running setup.py install for docopt 11:19:39 INFO - Successfully installed blobuploader requests docopt 11:19:39 INFO - Cleaning up... 11:19:40 INFO - Return code: 0 11:19:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:40 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 0x107bc3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c58618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86d958a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b27d30>, '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 0x10788cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1fb20>, '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.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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 11:19:40 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 11:19:40 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 11:19:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IUaBfCVomZ/Render', 11:19:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:40 INFO - 'HOME': '/Users/cltbld', 11:19:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:40 INFO - 'LOGNAME': 'cltbld', 11:19:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:40 INFO - 'MOZ_NO_REMOTE': '1', 11:19:40 INFO - 'NO_EM_RESTART': '1', 11:19:40 INFO - 'PAGER': '/bin/cat', 11:19:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:40 INFO - 'PWD': '/builds/slave/test', 11:19:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:40 INFO - 'SHELL': '/bin/bash', 11:19:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZAMlF40Lw6/Listeners', 11:19:40 INFO - 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/', 11:19:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:40 INFO - 'USER': 'cltbld', 11:19:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:40 INFO - 'XPC_FLAGS': '0x0', 11:19:40 INFO - 'XPC_SERVICE_NAME': '0', 11:19:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:19:40 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-oL9dq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:19:40 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-MYs5x9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:19:40 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-P9nNZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:19:40 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-4zeTpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:19:40 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-UCM0sO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:19:40 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-IMZfRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:19:40 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-5KxV61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:19:41 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-kLcvwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:19:41 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-csvvYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:19:41 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-entwtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:19:41 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-LpFHqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:19:41 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-TbFbCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:19:41 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-bZLzEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:19:41 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-Iyt3pg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:41 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-8WbPjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:19:41 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-gdsteg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:19:42 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-uhjjAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:19:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:19:42 INFO - Running setup.py install for manifestparser 11:19:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:19:42 INFO - Running setup.py install for mozcrash 11:19:42 INFO - Running setup.py install for mozdebug 11:19:42 INFO - Running setup.py install for mozdevice 11:19:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:19:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:19:42 INFO - Running setup.py install for mozfile 11:19:42 INFO - Running setup.py install for mozhttpd 11:19:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:19:43 INFO - Running setup.py install for mozinfo 11:19:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:19:43 INFO - Running setup.py install for mozInstall 11:19:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:19:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:19:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:19:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:19:43 INFO - Running setup.py install for mozleak 11:19:43 INFO - Running setup.py install for mozlog 11:19:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:19:43 INFO - Running setup.py install for moznetwork 11:19:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:19:43 INFO - Running setup.py install for mozprocess 11:19:43 INFO - Running setup.py install for mozprofile 11:19:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:19:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:19:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:19:44 INFO - Running setup.py install for mozrunner 11:19:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:19:44 INFO - Running setup.py install for mozscreenshot 11:19:44 INFO - Running setup.py install for moztest 11:19:44 INFO - Running setup.py install for mozversion 11:19:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:19:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:19:44 INFO - Cleaning up... 11:19:44 INFO - Return code: 0 11:19:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:19:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:44 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 0x107bc3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c58618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86d958a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b27d30>, '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 0x10788cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1fb20>, '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.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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 11:19:44 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 11:19:44 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 11:19:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IUaBfCVomZ/Render', 11:19:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:44 INFO - 'HOME': '/Users/cltbld', 11:19:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:44 INFO - 'LOGNAME': 'cltbld', 11:19:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:44 INFO - 'MOZ_NO_REMOTE': '1', 11:19:44 INFO - 'NO_EM_RESTART': '1', 11:19:44 INFO - 'PAGER': '/bin/cat', 11:19:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:44 INFO - 'PWD': '/builds/slave/test', 11:19:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:44 INFO - 'SHELL': '/bin/bash', 11:19:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZAMlF40Lw6/Listeners', 11:19:44 INFO - 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/', 11:19:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:44 INFO - 'USER': 'cltbld', 11:19:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:44 INFO - 'XPC_FLAGS': '0x0', 11:19:44 INFO - 'XPC_SERVICE_NAME': '0', 11:19:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:19:45 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-kU8AO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:19:45 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)) 11:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:19:45 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-2cK_J1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:19:45 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)) 11:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:19:45 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-QLaK2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:19:45 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)) 11:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:19:45 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-KqGL4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:19:45 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)) 11:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:19:45 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-D10hXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:19:45 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)) 11:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:19:45 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-eyTmQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:19:45 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)) 11:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:19:45 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-HRpZlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:19:45 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)) 11:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:19:45 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-MZHBDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:19:45 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)) 11:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:19:45 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-BJN3tF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:19:45 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)) 11:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:19:46 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-gU0Zyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:19:46 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)) 11:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:19:46 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-iflNHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:19:46 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)) 11:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:19:46 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-4RTfFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:19:46 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)) 11:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:19:46 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-n3FGdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:19:46 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)) 11:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:19:46 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-9CoECv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:19:46 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)) 11:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:46 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-NlM6RL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:46 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)) 11:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:19:46 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-mskfIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:19:46 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)) 11:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:19:46 INFO - Running setup.py (path:/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/pip-ZtMs3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:19:46 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)) 11:19:46 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)) 11:19:46 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)) 11:19:46 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)) 11:19:46 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)) 11:19:46 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)) 11:19:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:49 INFO - Downloading blessings-1.6.tar.gz 11:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:19:49 INFO - Installing collected packages: blessings 11:19:49 INFO - Running setup.py install for blessings 11:19:49 INFO - Successfully installed blessings 11:19:49 INFO - Cleaning up... 11:19:49 INFO - Return code: 0 11:19:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:19:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:19:49 INFO - Reading from file tmpfile_stdout 11:19:49 INFO - Current package versions: 11:19:49 INFO - blessings == 1.6 11:19:49 INFO - blobuploader == 1.2.4 11:19:49 INFO - docopt == 0.6.1 11:19:49 INFO - functools32 == 3.2.3-2 11:19:49 INFO - jsonschema == 2.5.1 11:19:49 INFO - manifestparser == 1.1 11:19:49 INFO - mozInstall == 1.12 11:19:49 INFO - mozcrash == 0.17 11:19:49 INFO - mozdebug == 0.1 11:19:49 INFO - mozdevice == 0.48 11:19:49 INFO - mozfile == 1.2 11:19:49 INFO - mozhttpd == 0.7 11:19:49 INFO - mozinfo == 0.9 11:19:49 INFO - mozleak == 0.1 11:19:49 INFO - mozlog == 3.3 11:19:49 INFO - moznetwork == 0.27 11:19:49 INFO - mozprocess == 0.23 11:19:49 INFO - mozprofile == 0.28 11:19:49 INFO - mozrunner == 6.12 11:19:49 INFO - mozscreenshot == 0.1 11:19:49 INFO - mozsystemmonitor == 0.3 11:19:49 INFO - moztest == 0.7 11:19:49 INFO - mozversion == 1.4 11:19:49 INFO - psutil == 3.1.1 11:19:49 INFO - requests == 1.2.3 11:19:49 INFO - wsgiref == 0.1.2 11:19:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:50 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')]} 11:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:50 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 0x107bc3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c58618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86d958a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b27d30>, '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 0x10788cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1fb20>, '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.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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 11:19:50 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 11:19:50 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 11:19:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IUaBfCVomZ/Render', 11:19:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:50 INFO - 'HOME': '/Users/cltbld', 11:19:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:50 INFO - 'LOGNAME': 'cltbld', 11:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:50 INFO - 'MOZ_NO_REMOTE': '1', 11:19:50 INFO - 'NO_EM_RESTART': '1', 11:19:50 INFO - 'PAGER': '/bin/cat', 11:19:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:50 INFO - 'PWD': '/builds/slave/test', 11:19:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:50 INFO - 'SHELL': '/bin/bash', 11:19:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZAMlF40Lw6/Listeners', 11:19:50 INFO - 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/', 11:19:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:50 INFO - 'USER': 'cltbld', 11:19:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:50 INFO - 'XPC_FLAGS': '0x0', 11:19:50 INFO - 'XPC_SERVICE_NAME': '0', 11:19:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:50 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)) 11:19:50 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)) 11:19:50 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)) 11:19:50 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)) 11:19:50 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)) 11:19:50 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)) 11:19:50 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)) 11:19:50 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)) 11:19:50 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)) 11:19:50 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)) 11:19:50 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)) 11:19:50 INFO - Cleaning up... 11:19:50 INFO - Return code: 0 11:19:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:19:50 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')]} 11:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:50 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')]} 11:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:50 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 0x107bc3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c58618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86d958a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b27d30>, '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 0x10788cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1fb20>, '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.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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 11:19:50 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 11:19:50 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 11:19:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IUaBfCVomZ/Render', 11:19:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:50 INFO - 'HOME': '/Users/cltbld', 11:19:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:50 INFO - 'LOGNAME': 'cltbld', 11:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:50 INFO - 'MOZ_NO_REMOTE': '1', 11:19:50 INFO - 'NO_EM_RESTART': '1', 11:19:50 INFO - 'PAGER': '/bin/cat', 11:19:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:50 INFO - 'PWD': '/builds/slave/test', 11:19:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:50 INFO - 'SHELL': '/bin/bash', 11:19:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZAMlF40Lw6/Listeners', 11:19:50 INFO - 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/', 11:19:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:50 INFO - 'USER': 'cltbld', 11:19:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:50 INFO - 'XPC_FLAGS': '0x0', 11:19:50 INFO - 'XPC_SERVICE_NAME': '0', 11:19:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:19:50 INFO - Cleaning up... 11:19:50 INFO - Return code: 0 11:19:50 INFO - Running post-action listener: _resource_record_post_action 11:19:50 INFO - Running post-action listener: _start_resource_monitoring 11:19:50 INFO - Starting resource monitoring. 11:19:50 INFO - [mozharness: 2016-09-08 18:19:50.837854Z] Finished create-virtualenv step (success) 11:19:50 INFO - [mozharness: 2016-09-08 18:19:50.838862Z] Running install step. 11:19:50 INFO - Running pre-action listener: _resource_record_pre_action 11:19:50 INFO - Running main action method: install 11:19:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:19:51 INFO - Reading from file tmpfile_stdout 11:19:51 INFO - Detecting whether we're running mozinstall >=1.0... 11:19:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:19:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:19:51 INFO - Reading from file tmpfile_stdout 11:19:51 INFO - Output received: 11:19:51 INFO - Usage: mozinstall [options] installer 11:19:51 INFO - Options: 11:19:51 INFO - -h, --help show this help message and exit 11:19:51 INFO - -d DEST, --destination=DEST 11:19:51 INFO - Directory to install application into. [default: 11:19:51 INFO - "/builds/slave/test"] 11:19:51 INFO - --app=APP Application being installed. [default: firefox] 11:19:51 INFO - mkdir: /builds/slave/test/build/application 11:19:51 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'] 11:19:51 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 11:20:25 INFO - Reading from file tmpfile_stdout 11:20:25 INFO - Output received: 11:20:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:20:25 INFO - Running post-action listener: _resource_record_post_action 11:20:25 INFO - [mozharness: 2016-09-08 18:20:25.031840Z] Finished install step (success) 11:20:25 INFO - [mozharness: 2016-09-08 18:20:25.031969Z] Running run-tests step. 11:20:25 INFO - Running pre-action listener: _resource_record_pre_action 11:20:25 INFO - Running main action method: run_tests 11:20:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:20:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:20:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:20:25 INFO - 2016-09-08 11:20:25.045 screenresolution[1638:7569] starting screenresolution argv=screenresolution get 11:20:25 INFO - 2016-09-08 11:20:25.047 screenresolution[1638:7569] Display 0: 1600x1200x32@60 11:20:25 INFO - 2016-09-08 11:20:25.053 screenresolution[1639:7571] starting screenresolution argv=screenresolution list 11:20:25 INFO - Available Modes on Display 0 11:20:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:20:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:20:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:20:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:20:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:20:25 INFO - Intel Iris: 11:20:25 INFO - Chipset Model: Intel Iris 11:20:25 INFO - Type: GPU 11:20:25 INFO - Bus: Built-In 11:20:25 INFO - VRAM (Dynamic, Max): 1536 MB 11:20:25 INFO - Vendor: Intel (0x8086) 11:20:25 INFO - Device ID: 0x0a2e 11:20:25 INFO - Revision ID: 0x0009 11:20:25 INFO - Displays: 11:20:25 INFO - Display: 11:20:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:20:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:20:25 INFO - Display Serial Number: 1600x1200 60 11:20:25 INFO - Main Display: Yes 11:20:25 INFO - Mirror: Off 11:20:25 INFO - Online: Yes 11:20:25 INFO - Rotation: Supported 11:20:25 INFO - Return code: 0 11:20:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:20:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:20:25 INFO - Python 2.7.3 11:20:25 INFO - Return code: 0 11:20:25 INFO - grabbing minidump binary from tooltool 11:20:25 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')]} 11:20:25 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 0x107b27d30>, '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 0x10788cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b1fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:20:25 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 11:20:25 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 11:20:25 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 11:20:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:20:25 INFO - Return code: 0 11:20:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:20:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:20:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:20:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:20:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:20:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:20:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0040 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:20:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IUaBfCVomZ/Render', 11:20:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:20:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:20:25 INFO - 'HOME': '/Users/cltbld', 11:20:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:20:25 INFO - 'LOGNAME': 'cltbld', 11:20:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:20:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:20:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:20:25 INFO - 'MOZ_NO_REMOTE': '1', 11:20:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:20:25 INFO - 'NO_EM_RESTART': '1', 11:20:25 INFO - 'PAGER': '/bin/cat', 11:20:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:20:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:20:25 INFO - 'PWD': '/builds/slave/test', 11:20:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:20:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:20:25 INFO - 'SHELL': '/bin/bash', 11:20:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZAMlF40Lw6/Listeners', 11:20:25 INFO - 'TMPDIR': '/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/', 11:20:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:20:25 INFO - 'USER': 'cltbld', 11:20:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:20:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:20:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:20:25 INFO - 'XPC_FLAGS': '0x0', 11:20:25 INFO - 'XPC_SERVICE_NAME': '0', 11:20:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:20:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:20:25 INFO - mozversion application_buildid: 20160908090930 11:20:25 INFO - mozversion application_changeset: 5d854c8d076533b9426bc3040979ccf8e0b2404b 11:20:25 INFO - mozversion application_display_name: Nightly 11:20:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:20:25 INFO - mozversion application_name: Firefox 11:20:25 INFO - mozversion application_remotingname: firefox 11:20:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 11:20:25 INFO - mozversion application_vendor: Mozilla 11:20:25 INFO - mozversion application_version: 51.0a1 11:20:25 INFO - mozversion platform_buildid: 20160908090930 11:20:25 INFO - mozversion platform_changeset: 5d854c8d076533b9426bc3040979ccf8e0b2404b 11:20:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 11:20:25 INFO - mozversion platform_version: 51.0a1 11:20:25 INFO - using testdate: 1473358825 11:20:25 INFO - actual date: 1473358825 11:20:25 INFO - starting webserver on 'localhost:49267' 11:20:25 INFO - SUITE-START | Running 2 tests 11:20:25 INFO - TEST-START | dromaeo_css 11:20:25 INFO - operating with platform_type : mac_ 11:20:25 INFO - Initialising browser for dromaeo_css test... 11:20:25 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/tmphNyDRG/profile http://localhost:49267/getInfo.html) 11:20:28 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 11:20:28 INFO - PROCESS | 1649 | colorDepth:24 11:20:28 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 11:20:28 INFO - PROCESS | 1649 | __metrics 11:20:28 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 11:20:29 INFO - TEST-INFO | 1649: exit 0 11:20:29 INFO - Browser initialized. 11:20:29 INFO - Running cycle 1/1 for dromaeo_css test... 11:20:29 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/tmphNyDRG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:20:41 INFO - PROCESS | 1652 | RSS: Main: 244260864 11:20:41 INFO - PROCESS | 1652 | 11:21:30 INFO - PROCESS | 1652 | 1473358890986 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] 11:23:43 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 11:23:44 INFO - PROCESS | 1652 | RSS: Main: 264908800 11:23:44 INFO - PROCESS | 1652 | 11:26:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 11:26:47 INFO - PROCESS | 1652 | RSS: Main: 270831616 11:26:47 INFO - PROCESS | 1652 | 11:29:49 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 11:29:50 INFO - PROCESS | 1652 | RSS: Main: 275316736 11:29:50 INFO - PROCESS | 1652 | 11:32:52 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 11:32:52 INFO - PROCESS | 1652 | RSS: Main: 271753216 11:32:52 INFO - PROCESS | 1652 | 11:35:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 11:35:56 INFO - PROCESS | 1652 | RSS: Main: 277151744 11:35:56 INFO - PROCESS | 1652 | 11:38:58 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 11:38:59 INFO - PROCESS | 1652 | RSS: Main: 280862720 11:38:59 INFO - PROCESS | 1652 | 11:38:59 INFO - PROCESS | 1652 | __start_tp_report 11:38:59 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 11:38:59 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 11:38:59 INFO - PROCESS | 1652 | |i|pagename|runs| 11:38:59 INFO - PROCESS | 1652 | |0;dojo.html;3317.568716066911;3363.327334533094;3363.8822641207553;3389.949150762738;3396.422608156613;4022.477077979866;5509.504144626982;5643.351014633318;5668.489835914766;5694.259746232994;5692.857678558028;5697.173909783079;5715.371309156;5726.141078838176;5776.191333213354;5106.5659418949435;5157.845264642048;5163.070647283506;5217.426083130853;5309.33633295838;5238.371395432553;5245.947540524584;5267.710275934822;5299;5321.547668448186;4159.251334759742;5070.594352451813;5138.486151384832;5202.661826981234;5249.895002099936;4382.408374869384;5195.68825870449;5234.738263086869;5246.291750613656;5254.789808407659;5097.898042039175;5140.974295128501;5188.7146206958605;5200.141976573861;5216.947830521684;4391.451068616423;5531.695756733378;5801.216835727165;5841.386654401269;5923.822285331447;5036.370453693288;5401.891962160735;5791.362950075489;6127.754889804447;6145.508359331243;4500.819967201308;5147.7683504242395;5198.2722418861385;5428.4842939920645;6241.531885108635;4286;5737.799177028784;5760.481556659921;5775.971120144456;5783.277090363705;6248.250209974741;6248.4688801451475;6253.187085678833;6270.623762574261;6272.874542509125;5824;6007.098935159761;6087.4521293083835;6102.389761023951;6110.480609148183;6145.569810113249;6147.0779383092;6166.969165154235;6185.8453538661;6209.5032397408095;6140.570160088841;6152.16945712332;6160.414760597826;6185.969070154621;6208.286047104551;6057.121717351048;6207.720652570645;6233.065040243909;6235.127082208494;6252.6873656317;4841.491643377394;4849.660523763303;4859.757012149378;4876.049170411736;4891.217405215149;4347.804348804311;4372.103718737651;4377.60601545856;4394.96718271206;4402.977985110054;5565.666060036411;5601.747921343468;5680.943190568124;5707.714614269353;5733.684647344436;6136.917362303815;6146.877062458726;6156.384361563808;6200.596961197508;6214.782482613087;5687.374388817227;5692.6015178937905;5701.572382071361;5715.314162300468;5721.942780572141;5281.630285880028;5390.97304513475;5394.190871369247;5394.811181608625;5410.3778065522965;6181.381861813783;6195.101710251988;6220.7511699531515;6322.494200464043;6337.588056776295;5147.279380886754;6189.473894718999;6196.845078873064;6209.099680546386;6240.719167637377;6113.476306051762;6214.875702485834;6255.4855182319625;6286.308506064238;6307.463865571478;4985.6011519077665;6057.091436284592;6098.8140919041825;6115.541334399849;6132.386761323832;6063.544264572855;6119.106526814713;6140.596275277727;6169.876602467836;6198.039303907902;5063.822766203312;6095.8243603132;6137.140800288053;6140.17107690456;6158.291796443466;5988.820335389945;6063.5452341073715;6097.878042439038;6140.109684095871;6168.753249870135;5932.199153114274;5943.554233432424;5965.73154208053;6010.939890601038;6053.697315134314;6067.24159480065;6104.511639068953;6113.419225173601;6127.479164271087;6151.108089326933;5782.335031471434;5924.229850119457;5944.613600115978;5984.700764961647;6008.699565021819 11:38:59 INFO - PROCESS | 1652 | |1;ext.html;513.9717315547645;536.6887205420858;537.094994933537;543.2096299883665;544.5670691800019;36922.815385923066;39656.801715991416;39657.80171099144;39717.40423893644;39745.403818942665;40371.19257614846;41048.38427423584;41082.38376424349;41088.972775680624;41270.58729412705;9597.616095356196;9654.082862128122;9781.951090244538;9831.967643397447;9835.754106147331;9642.180414664763;9677.177439917616;9717.659881904136;9810.852837207447;9824.803503929917;9794.216462683004;9807.41155530667;9809.117179453848;9823.557939892722;9837.163841073481;9725.56234969428;9780.972997835268;9785.412875227474;9817.705468835946;9829.262805289633;9725.416475011521;9785;9786.755331116708;9794.90205097947;9806;38870.80564597187;39028.804855975824;39060.60939390598;39343.60656393428;39381.40927886084;3031.5301128325145;3260.8206548639814;3391.491276308549;3851.634094761021;4008.3386241270155;24658.876705616356;30178.20202876143;31093.844530777427;31269.8436507816;31324.903628372896;3223.7098661120376;3244.30247496787;3267.803931764102;3335.7664963452567;3397.456406974872;337269;337512;338455;342453;345123.274383629;31963;33052;57010.714946425;57308.426915730306;57543.42456575464;28783.56824647632;32532.186695332333;33859;34182;34260.97217083492;14292.642683932985;14421.711565768626;14425.711485770225;14446.566603001927;14524.782128268085;12124.333161676192;12241.898229159418;12246.44890979908;12323.568675096329;12374;7571.205023472456;7581.696732130763;7598.696052157962;7650.655720492591;7668.731594394169;3202.679732026825;3224.3067740435918;3268.428025095599;3272.1655977725527;3293.1108600678162;747.4730315127822;759.8898159766803;791.4459878085363;827.9047909490365;855.3584811391456;26971;28329.71670283271;29162;29443;30729.692703072684;925.4956048953337;984.414273507262;1019.5819713917258;1034.1003327105466;1110.8722496912958;14398.136111833324;14974.401023958919;15401.922990384975;15451.84548154504;15609.921950390175;178395.10802445904;655842.720786393;676441;676708;868897.5885844092;54938.72530637401;56368.718156408955;56501.86996260052;56768.43231567631;57171.85656286934;1334.6263046347037;1354.4852955876704;1371.4788380415378;1371.7050834070724;1372.4715984346099;29399.55900661449;29438.264043399085;29789.25526861759;30288.394232115676;30303.090907272817;48168;49136.01727965349;49234;49371.75314123406;49416.011679766925;455664;461198;463909;464418;465071;26167.345816353984;26202.344941376617;26509.734902650725;26536.867315663298;26570.7342926576;43439.13121737611;43620.78189609158;43874.341884871115;44743.328850066624;45124.77437612791;30982.53526197064;36907.63092369042;37086.258274834894;37311.81344093262;38010.859674209816;30918.454077296494;35519.11202219965;37168.814155929045;37675;38049.2390152201;35835.28329433449;36520.08699782527;37003;37023.81488092542;37368.06579835526;32241.35517289688;33387.332253355286;33442.49836252507;33476;33580;24559.754402455746;25125;25158.049277417944;25357.74642253628;25487.617685734356 11:38:59 INFO - PROCESS | 1652 | |2;jquery.html;369.47534501008585;370.8664880642968;374.3822692557281;380.45594799436793;398.0685196639864;15701.84298157018;19948.90025549872;20423.48941276465;21070.367888963316;21289;17539.649207015886;19890.900545497254;20058.398248052545;20077.799222007776;20469.590608187827;5012.949870501285;5479.712267617117;5714.914276285859;5776.740046697888;6053.939460605382;5937.079752638348;6056.364081771417;6071.332153463116;6077.756889724428;6084.9351363511205;5835.153902684129;5981.312149102843;6030.758769649209;6051.697415129248;6103.511719062464;5192.299039629639;5384.6769193848295;5630.91553626698;6074.72663730131;6120.7245673944335;4799.2321228603605;5605.495505404492;5863.413658634103;5906.645601263895;5908.084246941731;12849.164804287784;13066;13066.889314408287;19773.406797796088;24102.397440063964;2128.9467763306043;2374.7625237476286;2432.5013372258645;2697.029069534966;2899.558115287217;9351.39215950961;9862.65480708179;10037.274223288407;10219.437930913797;10263.025012623788;1825.2881376263135;1832.8808627439173;1850.3986204483597;1874.278402814926;1878.821511956362;105519;106931;116257.4187129059;118436.22345664822;120014;7673.501222420525;8043.718469853527;8462.19609137131;8513.616887240089;9765.072318129767;11934.641960741192;12069.0344772418;12180.025597952143;12194;12199.695007624703;7686.923130768732;7932.603369831485;7936.801579960547;8034.879476807852;8054.838903221903;7023.648817559101;7113.589574463866;8267.751967440987;8309.127541608164;8327.20891515305;3340.5323254744353;3560.8219589020446;3779.111908701453;3798.582155962842;3851.9037024074623;1877.5963167918962;1912.6270377276298;1915.2721965652963;1919.5585015446525;1928.836048935828;809.2433574607761;833.2708879730236;844.8099177684971;899.2850683706338;942.58997336159;19920.3027894023;20767.273145439838;20793;21120;21256.111094448053;995.6664517386756;999.205631522941;1006.0844631385551;1039.2517387481003;1044.728370623628;10251.026152515498;10959.83560246597;10976.615818446317;10978.670639880816;10981.12151027916;529573;532277;532879;538184;541103;88067.35797926072;100569.49715251377;126407.7359226396;126978;127899.36050319689;906.6871929184421;935.5789894547481;959.2661613865398;961.7403301108801;966.7631430299481;18439.26242950267;18709.812901870806;18830.811691882907;18914.621707566046;19044.714329284794;56313.8737225245;56620.43379566151;56753.71623141858;57345.566360841316;57790;50764.74617626888;67611.66194168998;71060.64469677619;76250;84622;6338.302786693368;6613.834654133685;7945.443818932619;8061.072976607765;8078.919210807817;10486.423246721504;12546.87453125457;12701.55544555973;12802.551910683082;13056.869431305946;10851.40317282557;11232.101431885267;11326.150538709466;11422.657320280405;11453.770924581628;4908.460069392436;7574.128975167706;10538.947305263424;11143.274202726376;11175.608853690082;9709.757256068373;11146.442677866236;11561.479733412185;11666.941665291619;11885.940570297093;8041.070143165715;8171.591420429074;8201.835963280582;8237.752867413987;8333.250007499355;10804.567817287221;10913.072388847037;10949.616763413242;11100.61147859821;11177.72055698614 11:38:59 INFO - PROCESS | 1652 | |3;mootools.html;1262.9115961882667;1496.9101853888774;1525.8703010244133;1669.7578851066596;1697.9066151361678;7943.165967573408;8484.533350665719;8581.141885811416;8999.010108888026;9005.324600654953;8679.739607811776;8818.030016698158;8822.647094116244;8826.691065812698;8847.469151850893;2379.3218932604213;2409.2531315292226;2411.4333131714034;2422.103821586016;2429.137656132074;2312.595295823224;2363.161077817376;2407.6749638798715;2411.9758802411925;2425.0542288507495;2381.988090059547;2400.519896020794;2401.9159329423474;2407.8555286682767;2421.140495124232;2375.8930848111877;2382.3686723018413;2383.21353953195;2386.940326491834;2402.7356990731005;1935.3032908153054;2368.2658375903525;2369.052379048377;2377.0610608809598;2379.7382287948312;8797.560121993874;8841.425307355066;8928.10718928102;8938;8952.418092824011;5262.131748261532;5435.994341046919;5442.346918369782;5450.073487507094;5458.972705136489;3582.0507565495163;3686.1706116123923;3729.3287208302495;3742.4760533525327;3746.3443897318134;1096.1121491591757;1105.160078340467;1110.6779034080107;1110.8333749937574;1125.4935279124345;9551;11337.886621133766;11635.767284654345;11718.882811171865;11753.236039657395;4363.738175709467;4554.089182163514;4637.5130611286;4668.95331046692;4685.508021657745;8983.505907175168;8999.280057595377;9006.819863602823;9054.547272636342;9118.680846170351;5275.050490911596;5286.3920649125075;5294.073537130987;5298.761555730001;5304.23088652151;4344.847930322424;4434.623057040123;4585.839495617637;4786.353842231324;4965.4289756678445;3726.869559565402;3761.3229618668356;3840.116773142193;3850.4801851749653;3862.4399462078404;1187.7327601289642;1193.7672153930073;1197.449173380255;1202.7353982123918;1204.1570900369777;726.6221564786282;732.2457868395561;880.1330689271063;912.8174365127002;952.3999880075509;4645.907081858411;5796.942030579725;5996.430339117776;6094.116353128771;6135;792.6393490961599;839.8992120945403;941.4022095969109;974.3958745577787;1008.3899240959261;3586.5158203642172;3841.63621914222;3928.744631598937;3955.881323560298;4082.999665082074;9586.185174260267;9846.621472993787;10825.891741082489;10879.891201088047;10893.019628233496;10580.312279701795;10817.729556761144;10824.5128969195;10841;10842.241043126905;8098;8178.959105204436;8323.001239851257;8323.875141872997;8331.250187483156;5791.333996590446;5834.883302334015;5933.042096108187;5979.133025711334;6022.939770602238;10025.298229123891;10227.181825453798;10245.129164021142;10264.127549158107;10295.3822770634;9678.225741940487;10078.344907580984;10140.847887281549;10269.435181065113;10302.793944121226;6949.513534052568;7136.6788494518905;7257.129144502694;7479.139898911695;7663.770086897402;8348.206920342558;8408.621612027597;8493.660253589787;8499.405041647025;8505.659773609234;7365.889511657437;7498.287662672037;7521.473496855387;7565.130010048915;7605.163432022584;7289.671964761493;7320.890186647098;7337.486375953632;7492.550446973199;7495.812604684708;6914.5159838812815;7045.330693584101;7050.23744063984;7064.187618423947;7070;4584.106099310602;5014.122528557561;5080.822171223989;5083.898322033612;5156.2523434102595;4952.504749525018;5007;5144.6398752087;5201.089809283436;5261.710605916712 11:38:59 INFO - PROCESS | 1652 | |4;prototype.html;252.88746507804024;303.76154718545945;308.7113548831841;313.8399416297689;317.5601791518746;4801.855944321676;5539.639923404976;5604.383517813042;5647.407022262665;5653.48419953121;4190.224808410446;5254.81608143715;5530.253415788868;5550.2507161533185;5643.6049476536555;3987.760734355948;4085.3667681509264;4160.0015996160855;4194.30793919003;4240.830366785325;3996.7601943883315;3997.2804895118866;4166.562510936354;4199.643030342425;4274.5084315303675;3576.7853928764225;3863.652271295583;4037.7375470594466;4086.7752273624938;4221.514525829522;3606.6573675500786;4048.5951404859575;4103.569125241836;4214.599613036757;4258.87223383299;3320.684534969174;3599.784012959231;3627.437747149196;3631.1481144616437;3633.291508155911;4238.660907127422;4356.8257269709175;5404.405515393303;5406.837794866121;5508.91736623951;4348.434703488526;4631.814727410933;4778.904421911542;4838.758062096881;4856.862980288697;4958.454569997298;5260.342457192851;5314.92027619586;5556.444355564452;5930.703464826742;3361.571332183812;4035.717499775017;4038.3942408638645;4051.189762047573;4051.270771261172;5923.5853490255695;6165.260168779775;6228.22147231596;6245.75016999324;6247.718852651642;4054.614811592894;4075.735077219971;4092.3043082676018;4105.630493255621;4107.815148318333;3817.370133927871;3844.59631738669;4173.728707633994;4186.597489840938;4187.288161012635;4691.554302341272;4841.346418233564;4861.805527778849;4869.513048695102;4877.731724755173;5867.442592953663;5901.704914754245;5912.3496415394275;5947.851303717442;5957.463828255478;1158.7276989907366;1301.011231464075;1390.9374078166506;1416.221078406872;1429.7712366021594;708.4544900426642;859.7549698335944;878.4904755241944;881.4446898453933;883.9160279773412;4595.471520775087;4796.688215265996;5224.608154388436;5300.761465734051;5518.654571806966;5101.540861322499;6028;6076.574639775262;6114.602550834182;6144.262688477412;4602.861914142581;4990.975045124824;5446.673199608036;5454.345478542521;5496.835094947158;2593.6239245309325;2700.594910763401;2820.027090653721;3083.753299736016;3173.984325015973;4977.427595826454;5172.948270517322;6235.251769787564;6260.530460215502;6262.1546091278005;5395.568354531628;5483.8080667176455;6037.671712223304;6194.150690682247;6219.657918814418;5203.453637368022;5543.891221755665;5818.272715910512;6230.688465576703;6303.527235457267;4472.9105417892115;5304.946950530445;5469.753861076181;5982;6045.6070355426755;5115.516500215045;5344.679319240858;5571.442855714558;5919.970400147971;5974.8490543405205;5168.741562921914;6160.815175544741;6214.875702486015;6297.2128483939505;6342.968285158545;3717.3494638438706;3757.4176002719623;3968.464257325224;4032.778197199065;4056.9797151015237;5043.596512278936;5208.374995000624;5466.562674985912;6067.757289708539;6146.401935496714;5476.726163691879;5868.302969129421;5979.910301345396;6013.128096425906;6033.215681961317;4874.53691899269;5038.319826823478;5046.369203849519;5411.78352865881;5529.612927095226;5102.948970510248;5316.920246196388;5796.913046304225;5881.500072493886;5921.993261145702;5338.572914166935;5609.80365687199;5760.588655779361;5858;5860.150278209721;4819.759012049453;5401.864953376197;5821;5841.737121829443;5880.588358814842 11:38:59 INFO - PROCESS | 1652 | |5;yui.html;703.5884007855406;777.8872063550785;783.1838254222856;790.8378782349619;791.7783020754188;14649.70700585989;15051.698966020673;15525.92237038816;15531.84468155318;15586.298616562253;13938.094023888465;14082.788758168635;14202.14787112774;14216.289185540732;14257.429702811874;674.6525539347216;833.0461621443444;865.139186509424;866.6490071521044;866.7919699272161;837.5602808525542;847.1147650705028;863.6329559937033;880.6037283222544;883.1565854608833;852.2627926843298;900.8243392538458;903.7876099116702;907.5825120444604;909.9863502047441;838.7399906029151;869.1829680100685;892.5804871710351;896.5203616065373;897.9865302020474;501.8619879533121;624;639.8528338482175;673.5958424945043;678.2268214235775;11429.542818287258;13125.474981000749;14381.137131772128;14423.567292981123;14428.422863085463;14007.229602371866;14156.221407822566;14212.218327991955;14359.92820035893;14506.492272770402;11712;11760.411979400995;11801.527938882435;11844.585439509576;12013;3720.795356255405;3733.906652333686;3738.196287798109;3757.755745876509;3897.279003384383;93259.53370233106;115037;117997.41001295025;120460.39769801183;122117.38941305237;10947.781044379068;10970.493637253538;11043.282186657842;11054.00513953748;11217.214794964275;22318.776812231994;22331.3300600979;22365.888170559043;22387.440313991956;22418.32745017652;7433.628318584158;7438.367738742268;7527.2849079337375;7647.808804779925;7763.68945242184;4312.633426158749;4317.07182955666;4358.346248062816;4397.098594788061;4521.773911304422;3415.692587667122;3507.1582820122985;3512.964870351315;3523.647635236507;3786.2238241160494;706.5584009993754;711.7295427737427;713.7644577289482;718.9036719003561;719.665355609644;522.5767128625826;570.7888081409905;583.4894467341077;646.3471893387618;653.7548419342746;9245.491497967541;9268.95365523168;9274.768130796783;9344.29917756171;9408.200302974186;531.5322516185731;554.9445055494499;582.3099626942087;592.683627858112;635.3995474276857;4365.476142862877;4498.370228168059;4538.2511885538515;4580.839670611513;4671.5328467153695;140079.29960350133;157780;161561;162512;165192;137379.31310343585;138359;139358.30320848533;139856.30071849577;141889.29055354657;89658;106922;110131;110896;114766;8645.740627781177;8724.389292749447;8778.297736181217;8791.692290769792;8920;65901.67049164724;65990.34009659842;66038;66336.33663663302;68755.65622172058;54425;73383.26616733764;83814.16185838307;91693;93362.53318733363;12973.935130324287;13117.934410327887;13141.806549976649;13403.42442182327;13816.792748108586;11132.276402033842;14784;14822.258887055388;14902.1803800792;15325.846741532889;11265.324080555192;13554.525591604246;13591.592252232449;14352.210628415538;14392.678868552512;11739.647810565697;13457.05800593949;13678.179309241477;13820.516281930084;13921.721565568834;9459.716208513755;13225.603231903058;13653.044286899822;13815.516456923959;13927.025108242731;10752.537696855718;11172.776544469227;11328.093752499944;11355.205135640426;11421.629404471516;9769.413835169913;10334.379937203792;10357.747882411559;11401.486933087865;11424.143189261005 11:38:59 INFO - PROCESS | 1652 | __end_tp_report 11:38:59 INFO - PROCESS | 1652 | __start_cc_report 11:38:59 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,394 11:38:59 INFO - PROCESS | 1652 | __end_cc_report 11:38:59 INFO - PROCESS | 1652 | __startTimestamp1473359939154__endTimestamp 11:38:59 INFO - PROCESS | 1652 | 11:38:59 INFO - PROCESS | 1652 | ------- Summary: start ------- 11:38:59 INFO - PROCESS | 1652 | Number of tests: 6 11:38:59 INFO - PROCESS | 1652 | 11:38:59 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5622.22 Median:5791.36 stddev:678.61 (11.7%) stddev-sans-first:656.16 11:38:59 INFO - PROCESS | 1652 | Values: 3317.6 3363.3 3363.9 3389.9 3396.4 4022.5 5509.5 5643.4 5668.5 5694.3 5692.9 5697.2 5715.4 5726.1 5776.2 5106.6 5157.8 5163.1 5217.4 5309.3 5238.4 5245.9 5267.7 5299.0 5321.5 4159.3 5070.6 5138.5 5202.7 5249.9 4382.4 5195.7 5234.7 5246.3 5254.8 5097.9 5141.0 5188.7 5200.1 5216.9 4391.5 5531.7 5801.2 5841.4 5923.8 5036.4 5401.9 5791.4 6127.8 6145.5 4500.8 5147.8 5198.3 5428.5 6241.5 4286.0 5737.8 5760.5 5776.0 5783.3 6248.3 6248.5 6253.2 6270.6 6272.9 5824.0 6007.1 6087.5 6102.4 6110.5 6145.6 6147.1 6167.0 6185.8 6209.5 6140.6 6152.2 6160.4 6186.0 6208.3 6057.1 6207.7 6233.1 6235.1 6252.7 4841.5 4849.7 4859.8 4876.0 4891.2 4347.8 4372.1 4377.6 4395.0 4403.0 5565.7 5601.7 5680.9 5707.7 5733.7 6136.9 6146.9 6156.4 6200.6 6214.8 5687.4 5692.6 5701.6 5715.3 5721.9 5281.6 5391.0 5394.2 5394.8 5410.4 6181.4 6195.1 6220.8 6322.5 6337.6 5147.3 6189.5 6196.8 6209.1 6240.7 6113.5 6214.9 6255.5 6286.3 6307.5 4985.6 6057.1 6098.8 6115.5 6132.4 6063.5 6119.1 6140.6 6169.9 6198.0 5063.8 6095.8 6137.1 6140.2 6158.3 5988.8 6063.5 6097.9 6140.1 6168.8 5932.2 5943.6 5965.7 6010.9 6053.7 6067.2 6104.5 6113.4 6127.5 6151.1 5782.3 5924.2 5944.6 5984.7 6008.7 11:38:59 INFO - PROCESS | 1652 | 11:38:59 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:59899.78 Median:37339.94 stddev:134999.90 (361.5%) stddev-sans-first:135304.98 11:38:59 INFO - PROCESS | 1652 | Values: 514.0 536.7 537.1 543.2 544.6 36922.8 39656.8 39657.8 39717.4 39745.4 40371.2 41048.4 41082.4 41089.0 41270.6 9597.6 9654.1 9782.0 9832.0 9835.8 9642.2 9677.2 9717.7 9810.9 9824.8 9794.2 9807.4 9809.1 9823.6 9837.2 9725.6 9781.0 9785.4 9817.7 9829.3 9725.4 9785.0 9786.8 9794.9 9806.0 38870.8 39028.8 39060.6 39343.6 39381.4 3031.5 3260.8 3391.5 3851.6 4008.3 24658.9 30178.2 31093.8 31269.8 31324.9 3223.7 3244.3 3267.8 3335.8 3397.5 337269.0 337512.0 338455.0 342453.0 345123.3 31963.0 33052.0 57010.7 57308.4 57543.4 28783.6 32532.2 33859.0 34182.0 34261.0 14292.6 14421.7 14425.7 14446.6 14524.8 12124.3 12241.9 12246.4 12323.6 12374.0 7571.2 7581.7 7598.7 7650.7 7668.7 3202.7 3224.3 3268.4 3272.2 3293.1 747.5 759.9 791.4 827.9 855.4 26971.0 28329.7 29162.0 29443.0 30729.7 925.5 984.4 1019.6 1034.1 1110.9 14398.1 14974.4 15401.9 15451.8 15609.9 178395.1 655842.7 676441.0 676708.0 868897.6 54938.7 56368.7 56501.9 56768.4 57171.9 1334.6 1354.5 1371.5 1371.7 1372.5 29399.6 29438.3 29789.3 30288.4 30303.1 48168.0 49136.0 49234.0 49371.8 49416.0 455664.0 461198.0 463909.0 464418.0 465071.0 26167.3 26202.3 26509.7 26536.9 26570.7 43439.1 43620.8 43874.3 44743.3 45124.8 30982.5 36907.6 37086.3 37311.8 38010.9 30918.5 35519.1 37168.8 37675.0 38049.2 35835.3 36520.1 37003.0 37023.8 37368.1 32241.4 33387.3 33442.5 33476.0 33580.0 24559.8 25125.0 25158.0 25357.7 25487.6 11:38:59 INFO - PROCESS | 1652 | 11:38:59 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:32064.37 Median:372.62 stddev:89508.51 (24021.1%) stddev-sans-first:89727.96 11:38:59 INFO - PROCESS | 1652 | Values: 369.5 370.9 374.4 380.5 398.1 15701.8 19948.9 20423.5 21070.4 21289.0 17539.6 19890.9 20058.4 20077.8 20469.6 5012.9 5479.7 5714.9 5776.7 6053.9 5937.1 6056.4 6071.3 6077.8 6084.9 5835.2 5981.3 6030.8 6051.7 6103.5 5192.3 5384.7 5630.9 6074.7 6120.7 4799.2 5605.5 5863.4 5906.6 5908.1 12849.2 13066.0 13066.9 19773.4 24102.4 2128.9 2374.8 2432.5 2697.0 2899.6 9351.4 9862.7 10037.3 10219.4 10263.0 1825.3 1832.9 1850.4 1874.3 1878.8 105519.0 106931.0 116257.4 118436.2 120014.0 7673.5 8043.7 8462.2 8513.6 9765.1 11934.6 12069.0 12180.0 12194.0 12199.7 7686.9 7932.6 7936.8 8034.9 8054.8 7023.6 7113.6 8267.8 8309.1 8327.2 3340.5 3560.8 3779.1 3798.6 3851.9 1877.6 1912.6 1915.3 1919.6 1928.8 809.2 833.3 844.8 899.3 942.6 19920.3 20767.3 20793.0 21120.0 21256.1 995.7 999.2 1006.1 1039.3 1044.7 10251.0 10959.8 10976.6 10978.7 10981.1 529573.0 532277.0 532879.0 538184.0 541103.0 88067.4 100569.5 126407.7 126978.0 127899.4 906.7 935.6 959.3 961.7 966.8 18439.3 18709.8 18830.8 18914.6 19044.7 56313.9 56620.4 56753.7 57345.6 57790.0 50764.7 67611.7 71060.6 76250.0 84622.0 6338.3 6613.8 7945.4 8061.1 8078.9 10486.4 12546.9 12701.6 12802.6 13056.9 10851.4 11232.1 11326.2 11422.7 11453.8 4908.5 7574.1 10538.9 11143.3 11175.6 9709.8 11146.4 11561.5 11666.9 11885.9 8041.1 8171.6 8201.8 8237.8 8333.3 10804.6 10913.1 10949.6 11100.6 11177.7 11:38:59 INFO - PROCESS | 1652 | 11:38:59 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5678.84 Median:5010.56 stddev:3165.22 (63.2%) stddev-sans-first:3156.69 11:38:59 INFO - PROCESS | 1652 | Values: 1262.9 1496.9 1525.9 1669.8 1697.9 7943.2 8484.5 8581.1 8999.0 9005.3 8679.7 8818.0 8822.6 8826.7 8847.5 2379.3 2409.3 2411.4 2422.1 2429.1 2312.6 2363.2 2407.7 2412.0 2425.1 2382.0 2400.5 2401.9 2407.9 2421.1 2375.9 2382.4 2383.2 2386.9 2402.7 1935.3 2368.3 2369.1 2377.1 2379.7 8797.6 8841.4 8928.1 8938.0 8952.4 5262.1 5436.0 5442.3 5450.1 5459.0 3582.1 3686.2 3729.3 3742.5 3746.3 1096.1 1105.2 1110.7 1110.8 1125.5 9551.0 11337.9 11635.8 11718.9 11753.2 4363.7 4554.1 4637.5 4669.0 4685.5 8983.5 8999.3 9006.8 9054.5 9118.7 5275.1 5286.4 5294.1 5298.8 5304.2 4344.8 4434.6 4585.8 4786.4 4965.4 3726.9 3761.3 3840.1 3850.5 3862.4 1187.7 1193.8 1197.4 1202.7 1204.2 726.6 732.2 880.1 912.8 952.4 4645.9 5796.9 5996.4 6094.1 6135.0 792.6 839.9 941.4 974.4 1008.4 3586.5 3841.6 3928.7 3955.9 4083.0 9586.2 9846.6 10825.9 10879.9 10893.0 10580.3 10817.7 10824.5 10841.0 10842.2 8098.0 8179.0 8323.0 8323.9 8331.3 5791.3 5834.9 5933.0 5979.1 6022.9 10025.3 10227.2 10245.1 10264.1 10295.4 9678.2 10078.3 10140.8 10269.4 10302.8 6949.5 7136.7 7257.1 7479.1 7663.8 8348.2 8408.6 8493.7 8499.4 8505.7 7365.9 7498.3 7521.5 7565.1 7605.2 7289.7 7320.9 7337.5 7492.6 7495.8 6914.5 7045.3 7050.2 7064.2 7070.0 4584.1 5014.1 5080.8 5083.9 5156.3 4952.5 5007.0 5144.6 5201.1 5261.7 11:38:59 INFO - PROCESS | 1652 | 11:38:59 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4666.72 Median:5188.20 stddev:1457.12 (28.1%) stddev-sans-first:1423.05 11:38:59 INFO - PROCESS | 1652 | Values: 252.9 303.8 308.7 313.8 317.6 4801.9 5539.6 5604.4 5647.4 5653.5 4190.2 5254.8 5530.3 5550.3 5643.6 3987.8 4085.4 4160.0 4194.3 4240.8 3996.8 3997.3 4166.6 4199.6 4274.5 3576.8 3863.7 4037.7 4086.8 4221.5 3606.7 4048.6 4103.6 4214.6 4258.9 3320.7 3599.8 3627.4 3631.1 3633.3 4238.7 4356.8 5404.4 5406.8 5508.9 4348.4 4631.8 4778.9 4838.8 4856.9 4958.5 5260.3 5314.9 5556.4 5930.7 3361.6 4035.7 4038.4 4051.2 4051.3 5923.6 6165.3 6228.2 6245.8 6247.7 4054.6 4075.7 4092.3 4105.6 4107.8 3817.4 3844.6 4173.7 4186.6 4187.3 4691.6 4841.3 4861.8 4869.5 4877.7 5867.4 5901.7 5912.3 5947.9 5957.5 1158.7 1301.0 1390.9 1416.2 1429.8 708.5 859.8 878.5 881.4 883.9 4595.5 4796.7 5224.6 5300.8 5518.7 5101.5 6028.0 6076.6 6114.6 6144.3 4602.9 4991.0 5446.7 5454.3 5496.8 2593.6 2700.6 2820.0 3083.8 3174.0 4977.4 5172.9 6235.3 6260.5 6262.2 5395.6 5483.8 6037.7 6194.2 6219.7 5203.5 5543.9 5818.3 6230.7 6303.5 4472.9 5304.9 5469.8 5982.0 6045.6 5115.5 5344.7 5571.4 5920.0 5974.8 5168.7 6160.8 6214.9 6297.2 6343.0 3717.3 3757.4 3968.5 4032.8 4057.0 5043.6 5208.4 5466.6 6067.8 6146.4 5476.7 5868.3 5979.9 6013.1 6033.2 4874.5 5038.3 5046.4 5411.8 5529.6 5102.9 5316.9 5796.9 5881.5 5922.0 5338.6 5609.8 5760.6 5858.0 5860.2 4819.8 5401.9 5821.0 5841.7 5880.6 11:38:59 INFO - PROCESS | 1652 | 11:38:59 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:25082.37 Median:3727.35 stddev:40962.56 (1099.0%) stddev-sans-first:41036.58 11:38:59 INFO - PROCESS | 1652 | Values: 703.6 777.9 783.2 790.8 791.8 14649.7 15051.7 15525.9 15531.8 15586.3 13938.1 14082.8 14202.1 14216.3 14257.4 674.7 833.0 865.1 866.6 866.8 837.6 847.1 863.6 880.6 883.2 852.3 900.8 903.8 907.6 910.0 838.7 869.2 892.6 896.5 898.0 501.9 624.0 639.9 673.6 678.2 11429.5 13125.5 14381.1 14423.6 14428.4 14007.2 14156.2 14212.2 14359.9 14506.5 11712.0 11760.4 11801.5 11844.6 12013.0 3720.8 3733.9 3738.2 3757.8 3897.3 93259.5 115037.0 117997.4 120460.4 122117.4 10947.8 10970.5 11043.3 11054.0 11217.2 22318.8 22331.3 22365.9 22387.4 22418.3 7433.6 7438.4 7527.3 7647.8 7763.7 4312.6 4317.1 4358.3 4397.1 4521.8 3415.7 3507.2 3513.0 3523.6 3786.2 706.6 711.7 713.8 718.9 719.7 522.6 570.8 583.5 646.3 653.8 9245.5 9269.0 9274.8 9344.3 9408.2 531.5 554.9 582.3 592.7 635.4 4365.5 4498.4 4538.3 4580.8 4671.5 140079.3 157780.0 161561.0 162512.0 165192.0 137379.3 138359.0 139358.3 139856.3 141889.3 89658.0 106922.0 110131.0 110896.0 114766.0 8645.7 8724.4 8778.3 8791.7 8920.0 65901.7 65990.3 66038.0 66336.3 68755.7 54425.0 73383.3 83814.2 91693.0 93362.5 12973.9 13117.9 13141.8 13403.4 13816.8 11132.3 14784.0 14822.3 14902.2 15325.8 11265.3 13554.5 13591.6 14352.2 14392.7 11739.6 13457.1 13678.2 13820.5 13921.7 9459.7 13225.6 13653.0 13815.5 13927.0 10752.5 11172.8 11328.1 11355.2 11421.6 9769.4 10334.4 10357.7 11401.5 11424.1 11:38:59 INFO - PROCESS | 1652 | -------- Summary: end -------- 11:38:59 INFO - PROCESS | 1652 | 11:38:59 INFO - TEST-INFO | 1652: exit 0 11:38:59 INFO - TEST-OK | dromaeo_css | took 1114146ms 11:38:59 INFO - TEST-START | kraken 11:38:59 INFO - operating with platform_type : mac_ 11:38:59 INFO - Initialising browser for kraken test... 11:38:59 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/tmpcrPles/profile http://localhost:49267/getInfo.html) 11:39:02 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 11:39:02 INFO - PROCESS | 1665 | colorDepth:24 11:39:02 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 11:39:02 INFO - PROCESS | 1665 | __metrics 11:39:02 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 11:39:03 INFO - TEST-INFO | 1665: exit 0 11:39:03 INFO - Browser initialized. 11:39:03 INFO - Running cycle 1/1 for kraken test... 11:39:03 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/tmpcrPles/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:39:15 INFO - PROCESS | 1667 | RSS: Main: 244027392 11:39:15 INFO - PROCESS | 1667 | 11:39:18 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 11:39:19 INFO - PROCESS | 1667 | RSS: Main: 256946176 11:39:19 INFO - PROCESS | 1667 | 11:39:22 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 11:39:22 INFO - PROCESS | 1667 | RSS: Main: 265531392 11:39:22 INFO - PROCESS | 1667 | 11:39:26 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 11:39:26 INFO - PROCESS | 1667 | RSS: Main: 263184384 11:39:26 INFO - PROCESS | 1667 | 11:39:28 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 11:39:29 INFO - PROCESS | 1667 | RSS: Main: 265375744 11:39:29 INFO - PROCESS | 1667 | 11:39:31 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:39:32 INFO - PROCESS | 1667 | RSS: Main: 266096640 11:39:32 INFO - PROCESS | 1667 | 11:39:34 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 11:39:35 INFO - PROCESS | 1667 | RSS: Main: 266571776 11:39:35 INFO - PROCESS | 1667 | 11:39:38 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 11:39:39 INFO - PROCESS | 1667 | RSS: Main: 266829824 11:39:39 INFO - PROCESS | 1667 | 11:39:42 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 11:39:43 INFO - PROCESS | 1667 | RSS: Main: 267210752 11:39:43 INFO - PROCESS | 1667 | 11:39:44 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:39:45 INFO - PROCESS | 1667 | RSS: Main: 266465280 11:39:45 INFO - PROCESS | 1667 | 11:39:47 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:39:48 INFO - PROCESS | 1667 | RSS: Main: 266362880 11:39:48 INFO - PROCESS | 1667 | 11:39:50 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:39:51 INFO - PROCESS | 1667 | RSS: Main: 266784768 11:39:51 INFO - PROCESS | 1667 | 11:39:54 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:39:54 INFO - PROCESS | 1667 | RSS: Main: 266891264 11:39:54 INFO - PROCESS | 1667 | 11:39:57 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:39:58 INFO - PROCESS | 1667 | RSS: Main: 266981376 11:39:58 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 11:40:00 INFO - PROCESS | 1667 | RSS: Main: 269066240 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | __start_tp_report 11:40:00 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 11:40:00 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 11:40:00 INFO - PROCESS | 1667 | |i|pagename|runs| 11:40:00 INFO - PROCESS | 1667 | |0;ai-astar;107;98;172;162;138;123;170;149;171;166 11:40:00 INFO - PROCESS | 1667 | |1;audio-beat-detection;144;111;138;159;110;136;132;112;164;121 11:40:00 INFO - PROCESS | 1667 | |2;audio-dft;205;232;206;202;197;203;197;198;191;190 11:40:00 INFO - PROCESS | 1667 | |3;audio-fft;71;83;76;147;59;61;61;61;135;60 11:40:00 INFO - PROCESS | 1667 | |4;audio-oscillator;72;73;121;69;74;102;143;102;82;114 11:40:00 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;88;86;91;102;136;95;89;86;88;85 11:40:00 INFO - PROCESS | 1667 | |6;imaging-darkroom;93;95;110;150;123;94;95;96;138;153 11:40:00 INFO - PROCESS | 1667 | |7;imaging-desaturate;146;112;141;145;91;92;77;147;148;90 11:40:00 INFO - PROCESS | 1667 | |8;json-parse-financial;55;58;59;58;58;58;58;60;58;57 11:40:00 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;68;104;99;101;77;75;72;73;71;71 11:40:00 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;109;112;117;114;86;101;88;90;89;85 11:40:00 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;125;141;143;159;162;160;156;167;203;163 11:40:00 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;173;171;171;181;178;173;169;205;198;171 11:40:00 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;67;86;69;64;67;76;69;69;65;69 11:40:00 INFO - PROCESS | 1667 | __end_tp_report 11:40:00 INFO - PROCESS | 1667 | __start_cc_report 11:40:00 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,974 11:40:00 INFO - PROCESS | 1667 | __end_cc_report 11:40:00 INFO - PROCESS | 1667 | __startTimestamp1473360000328__endTimestamp 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | ------- Summary: start ------- 11:40:00 INFO - PROCESS | 1667 | Number of tests: 14 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:145.60 Median:168.00 stddev:27.81 (16.6%) stddev-sans-first:25.75 11:40:00 INFO - PROCESS | 1667 | Values: 107.0 98.0 172.0 162.0 138.0 123.0 170.0 149.0 171.0 166.0 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:137.00 stddev:19.41 (14.2%) stddev-sans-first:20.15 11:40:00 INFO - PROCESS | 1667 | Values: 144.0 111.0 138.0 159.0 110.0 136.0 132.0 112.0 164.0 121.0 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:202.10 Median:202.50 stddev:11.82 (5.8%) stddev-sans-first:12.49 11:40:00 INFO - PROCESS | 1667 | Values: 205.0 232.0 206.0 202.0 197.0 203.0 197.0 198.0 191.0 190.0 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:81.40 Median:61.00 stddev:32.53 (53.3%) stddev-sans-first:34.29 11:40:00 INFO - PROCESS | 1667 | Values: 71.0 83.0 76.0 147.0 59.0 61.0 61.0 61.0 135.0 60.0 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:70.50 stddev:25.25 (35.8%) stddev-sans-first:25.35 11:40:00 INFO - PROCESS | 1667 | Values: 72.0 73.0 121.0 69.0 74.0 102.0 143.0 102.0 82.0 114.0 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:94.60 Median:88.00 stddev:15.42 (17.5%) stddev-sans-first:16.17 11:40:00 INFO - PROCESS | 1667 | Values: 88.0 86.0 91.0 102.0 136.0 95.0 89.0 86.0 88.0 85.0 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:114.70 Median:93.50 stddev:24.43 (26.1%) stddev-sans-first:24.62 11:40:00 INFO - PROCESS | 1667 | Values: 93.0 95.0 110.0 150.0 123.0 94.0 95.0 96.0 138.0 153.0 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:118.90 Median:112.50 stddev:29.21 (26.0%) stddev-sans-first:29.29 11:40:00 INFO - PROCESS | 1667 | Values: 146.0 112.0 141.0 145.0 91.0 92.0 77.0 147.0 148.0 90.0 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:58.00 stddev:1.29 (2.2%) stddev-sans-first:0.83 11:40:00 INFO - PROCESS | 1667 | Values: 55.0 58.0 59.0 58.0 58.0 58.0 58.0 60.0 58.0 57.0 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:81.10 Median:72.50 stddev:14.22 (19.6%) stddev-sans-first:14.27 11:40:00 INFO - PROCESS | 1667 | Values: 68.0 104.0 99.0 101.0 77.0 75.0 72.0 73.0 71.0 71.0 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:99.10 Median:85.50 stddev:12.86 (15.0%) stddev-sans-first:13.13 11:40:00 INFO - PROCESS | 1667 | Values: 109.0 112.0 117.0 114.0 86.0 101.0 88.0 90.0 89.0 85.0 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:157.90 Median:161.00 stddev:20.44 (12.7%) stddev-sans-first:17.88 11:40:00 INFO - PROCESS | 1667 | Values: 125.0 141.0 143.0 159.0 162.0 160.0 156.0 167.0 203.0 163.0 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.00 Median:175.50 stddev:12.50 (7.1%) stddev-sans-first:13.07 11:40:00 INFO - PROCESS | 1667 | Values: 173.0 171.0 171.0 181.0 178.0 173.0 169.0 205.0 198.0 171.0 11:40:00 INFO - PROCESS | 1667 | 11:40:00 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:69.00 stddev:6.45 (9.4%) stddev-sans-first:6.75 11:40:00 INFO - PROCESS | 1667 | Values: 67.0 86.0 69.0 64.0 67.0 76.0 69.0 69.0 65.0 69.0 11:40:00 INFO - PROCESS | 1667 | -------- Summary: end -------- 11:40:00 INFO - PROCESS | 1667 | 11:40:01 INFO - TEST-INFO | 1667: exit 0 11:40:01 INFO - TEST-OK | kraken | took 61367ms 11:40:01 INFO - SUITE-END | took 1175s 11:40:01 INFO - Completed test suite (00:19:35) 11:40:01 INFO - javascript benchmark 11:40:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7153.170682272667, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3317.568716066911, 3363.327334533094, 3363.8822641207553, 3389.949150762738, 3396.422608156613, 4022.477077979866, 5509.504144626982, 5643.351014633318, 5668.489835914766, 5694.259746232994, 5692.857678558028, 5697.173909783079, 5715.371309156, 5726.141078838176, 5776.191333213354, 5106.5659418949435, 5157.845264642048, 5163.070647283506, 5217.426083130853, 5309.33633295838, 5238.371395432553, 5245.947540524584, 5267.710275934822, 5299.0, 5321.547668448186, 4159.251334759742, 5070.594352451813, 5138.486151384832, 5202.661826981234, 5249.895002099936, 4382.408374869384, 5195.68825870449, 5234.738263086869, 5246.291750613656, 5254.789808407659, 5097.898042039175, 5140.974295128501, 5188.7146206958605, 5200.141976573861, 5216.947830521684, 4391.451068616423, 5531.695756733378, 5801.216835727165, 5841.386654401269, 5923.822285331447, 5036.370453693288, 5401.891962160735, 5791.362950075489, 6127.754889804447, 6145.508359331243, 4500.819967201308, 5147.7683504242395, 5198.2722418861385, 5428.4842939920645, 6241.531885108635, 4286.0, 5737.799177028784, 5760.481556659921, 5775.971120144456, 5783.277090363705, 6248.250209974741, 6248.4688801451475, 6253.187085678833, 6270.623762574261, 6272.874542509125, 5824.0, 6007.098935159761, 6087.4521293083835, 6102.389761023951, 6110.480609148183, 6145.569810113249, 6147.0779383092, 6166.969165154235, 6185.8453538661, 6209.5032397408095, 6140.570160088841, 6152.16945712332, 6160.414760597826, 6185.969070154621, 6208.286047104551, 6057.121717351048, 6207.720652570645, 6233.065040243909, 6235.127082208494, 6252.6873656317, 4841.491643377394, 4849.660523763303, 4859.757012149378, 4876.049170411736, 4891.217405215149, 4347.804348804311, 4372.103718737651, 4377.60601545856, 4394.96718271206, 4402.977985110054, 5565.666060036411, 5601.747921343468, 5680.943190568124, 5707.714614269353, 5733.684647344436, 6136.917362303815, 6146.877062458726, 6156.384361563808, 6200.596961197508, 6214.782482613087, 5687.374388817227, 5692.6015178937905, 5701.572382071361, 5715.314162300468, 5721.942780572141, 5281.630285880028, 5390.97304513475, 5394.190871369247, 5394.811181608625, 5410.3778065522965, 6181.381861813783, 6195.101710251988, 6220.7511699531515, 6322.494200464043, 6337.588056776295, 5147.279380886754, 6189.473894718999, 6196.845078873064, 6209.099680546386, 6240.719167637377, 6113.476306051762, 6214.875702485834, 6255.4855182319625, 6286.308506064238, 6307.463865571478, 4985.6011519077665, 6057.091436284592, 6098.8140919041825, 6115.541334399849, 6132.386761323832, 6063.544264572855, 6119.106526814713, 6140.596275277727, 6169.876602467836, 6198.039303907902, 5063.822766203312, 6095.8243603132, 6137.140800288053, 6140.17107690456, 6158.291796443466, 5988.820335389945, 6063.5452341073715, 6097.878042439038, 6140.109684095871, 6168.753249870135, 5932.199153114274, 5943.554233432424, 5965.73154208053, 6010.939890601038, 6053.697315134314, 6067.24159480065, 6104.511639068953, 6113.419225173601, 6127.479164271087, 6151.108089326933, 5782.335031471434, 5924.229850119457, 5944.613600115978, 5984.700764961647, 6008.699565021819], "value": 5582.523764341596, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.9717315547645, 536.6887205420858, 537.094994933537, 543.2096299883665, 544.5670691800019, 36922.815385923066, 39656.801715991416, 39657.80171099144, 39717.40423893644, 39745.403818942665, 40371.19257614846, 41048.38427423584, 41082.38376424349, 41088.972775680624, 41270.58729412705, 9597.616095356196, 9654.082862128122, 9781.951090244538, 9831.967643397447, 9835.754106147331, 9642.180414664763, 9677.177439917616, 9717.659881904136, 9810.852837207447, 9824.803503929917, 9794.216462683004, 9807.41155530667, 9809.117179453848, 9823.557939892722, 9837.163841073481, 9725.56234969428, 9780.972997835268, 9785.412875227474, 9817.705468835946, 9829.262805289633, 9725.416475011521, 9785.0, 9786.755331116708, 9794.90205097947, 9806.0, 38870.80564597187, 39028.804855975824, 39060.60939390598, 39343.60656393428, 39381.40927886084, 3031.5301128325145, 3260.8206548639814, 3391.491276308549, 3851.634094761021, 4008.3386241270155, 24658.876705616356, 30178.20202876143, 31093.844530777427, 31269.8436507816, 31324.903628372896, 3223.7098661120376, 3244.30247496787, 3267.803931764102, 3335.7664963452567, 3397.456406974872, 337269.0, 337512.0, 338455.0, 342453.0, 345123.274383629, 31963.0, 33052.0, 57010.714946425, 57308.426915730306, 57543.42456575464, 28783.56824647632, 32532.186695332333, 33859.0, 34182.0, 34260.97217083492, 14292.642683932985, 14421.711565768626, 14425.711485770225, 14446.566603001927, 14524.782128268085, 12124.333161676192, 12241.898229159418, 12246.44890979908, 12323.568675096329, 12374.0, 7571.205023472456, 7581.696732130763, 7598.696052157962, 7650.655720492591, 7668.731594394169, 3202.679732026825, 3224.3067740435918, 3268.428025095599, 3272.1655977725527, 3293.1108600678162, 747.4730315127822, 759.8898159766803, 791.4459878085363, 827.9047909490365, 855.3584811391456, 26971.0, 28329.71670283271, 29162.0, 29443.0, 30729.692703072684, 925.4956048953337, 984.414273507262, 1019.5819713917258, 1034.1003327105466, 1110.8722496912958, 14398.136111833324, 14974.401023958919, 15401.922990384975, 15451.84548154504, 15609.921950390175, 178395.10802445904, 655842.720786393, 676441.0, 676708.0, 868897.5885844092, 54938.72530637401, 56368.718156408955, 56501.86996260052, 56768.43231567631, 57171.85656286934, 1334.6263046347037, 1354.4852955876704, 1371.4788380415378, 1371.7050834070724, 1372.4715984346099, 29399.55900661449, 29438.264043399085, 29789.25526861759, 30288.394232115676, 30303.090907272817, 48168.0, 49136.01727965349, 49234.0, 49371.75314123406, 49416.011679766925, 455664.0, 461198.0, 463909.0, 464418.0, 465071.0, 26167.345816353984, 26202.344941376617, 26509.734902650725, 26536.867315663298, 26570.7342926576, 43439.13121737611, 43620.78189609158, 43874.341884871115, 44743.328850066624, 45124.77437612791, 30982.53526197064, 36907.63092369042, 37086.258274834894, 37311.81344093262, 38010.859674209816, 30918.454077296494, 35519.11202219965, 37168.814155929045, 37675.0, 38049.2390152201, 35835.28329433449, 36520.08699782527, 37003.0, 37023.81488092542, 37368.06579835526, 32241.35517289688, 33387.332253355286, 33442.49836252507, 33476.0, 33580.0, 24559.754402455746, 25125.0, 25158.049277417944, 25357.74642253628, 25487.617685734356], "value": 17889.65711166093, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.47534501008585, 370.8664880642968, 374.3822692557281, 380.45594799436793, 398.0685196639864, 15701.84298157018, 19948.90025549872, 20423.48941276465, 21070.367888963316, 21289.0, 17539.649207015886, 19890.900545497254, 20058.398248052545, 20077.799222007776, 20469.590608187827, 5012.949870501285, 5479.712267617117, 5714.914276285859, 5776.740046697888, 6053.939460605382, 5937.079752638348, 6056.364081771417, 6071.332153463116, 6077.756889724428, 6084.9351363511205, 5835.153902684129, 5981.312149102843, 6030.758769649209, 6051.697415129248, 6103.511719062464, 5192.299039629639, 5384.6769193848295, 5630.91553626698, 6074.72663730131, 6120.7245673944335, 4799.2321228603605, 5605.495505404492, 5863.413658634103, 5906.645601263895, 5908.084246941731, 12849.164804287784, 13066.0, 13066.889314408287, 19773.406797796088, 24102.397440063964, 2128.9467763306043, 2374.7625237476286, 2432.5013372258645, 2697.029069534966, 2899.558115287217, 9351.39215950961, 9862.65480708179, 10037.274223288407, 10219.437930913797, 10263.025012623788, 1825.2881376263135, 1832.8808627439173, 1850.3986204483597, 1874.278402814926, 1878.821511956362, 105519.0, 106931.0, 116257.4187129059, 118436.22345664822, 120014.0, 7673.501222420525, 8043.718469853527, 8462.19609137131, 8513.616887240089, 9765.072318129767, 11934.641960741192, 12069.0344772418, 12180.025597952143, 12194.0, 12199.695007624703, 7686.923130768732, 7932.603369831485, 7936.801579960547, 8034.879476807852, 8054.838903221903, 7023.648817559101, 7113.589574463866, 8267.751967440987, 8309.127541608164, 8327.20891515305, 3340.5323254744353, 3560.8219589020446, 3779.111908701453, 3798.582155962842, 3851.9037024074623, 1877.5963167918962, 1912.6270377276298, 1915.2721965652963, 1919.5585015446525, 1928.836048935828, 809.2433574607761, 833.2708879730236, 844.8099177684971, 899.2850683706338, 942.58997336159, 19920.3027894023, 20767.273145439838, 20793.0, 21120.0, 21256.111094448053, 995.6664517386756, 999.205631522941, 1006.0844631385551, 1039.2517387481003, 1044.728370623628, 10251.026152515498, 10959.83560246597, 10976.615818446317, 10978.670639880816, 10981.12151027916, 529573.0, 532277.0, 532879.0, 538184.0, 541103.0, 88067.35797926072, 100569.49715251377, 126407.7359226396, 126978.0, 127899.36050319689, 906.6871929184421, 935.5789894547481, 959.2661613865398, 961.7403301108801, 966.7631430299481, 18439.26242950267, 18709.812901870806, 18830.811691882907, 18914.621707566046, 19044.714329284794, 56313.8737225245, 56620.43379566151, 56753.71623141858, 57345.566360841316, 57790.0, 50764.74617626888, 67611.66194168998, 71060.64469677619, 76250.0, 84622.0, 6338.302786693368, 6613.834654133685, 7945.443818932619, 8061.072976607765, 8078.919210807817, 10486.423246721504, 12546.87453125457, 12701.55544555973, 12802.551910683082, 13056.869431305946, 10851.40317282557, 11232.101431885267, 11326.150538709466, 11422.657320280405, 11453.770924581628, 4908.460069392436, 7574.128975167706, 10538.947305263424, 11143.274202726376, 11175.608853690082, 9709.757256068373, 11146.442677866236, 11561.479733412185, 11666.941665291619, 11885.940570297093, 8041.070143165715, 8171.591420429074, 8201.835963280582, 8237.752867413987, 8333.250007499355, 10804.567817287221, 10913.072388847037, 10949.616763413242, 11100.61147859821, 11177.72055698614], "value": 9069.735608253515, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1262.9115961882667, 1496.9101853888774, 1525.8703010244133, 1669.7578851066596, 1697.9066151361678, 7943.165967573408, 8484.533350665719, 8581.141885811416, 8999.010108888026, 9005.324600654953, 8679.739607811776, 8818.030016698158, 8822.647094116244, 8826.691065812698, 8847.469151850893, 2379.3218932604213, 2409.2531315292226, 2411.4333131714034, 2422.103821586016, 2429.137656132074, 2312.595295823224, 2363.161077817376, 2407.6749638798715, 2411.9758802411925, 2425.0542288507495, 2381.988090059547, 2400.519896020794, 2401.9159329423474, 2407.8555286682767, 2421.140495124232, 2375.8930848111877, 2382.3686723018413, 2383.21353953195, 2386.940326491834, 2402.7356990731005, 1935.3032908153054, 2368.2658375903525, 2369.052379048377, 2377.0610608809598, 2379.7382287948312, 8797.560121993874, 8841.425307355066, 8928.10718928102, 8938.0, 8952.418092824011, 5262.131748261532, 5435.994341046919, 5442.346918369782, 5450.073487507094, 5458.972705136489, 3582.0507565495163, 3686.1706116123923, 3729.3287208302495, 3742.4760533525327, 3746.3443897318134, 1096.1121491591757, 1105.160078340467, 1110.6779034080107, 1110.8333749937574, 1125.4935279124345, 9551.0, 11337.886621133766, 11635.767284654345, 11718.882811171865, 11753.236039657395, 4363.738175709467, 4554.089182163514, 4637.5130611286, 4668.95331046692, 4685.508021657745, 8983.505907175168, 8999.280057595377, 9006.819863602823, 9054.547272636342, 9118.680846170351, 5275.050490911596, 5286.3920649125075, 5294.073537130987, 5298.761555730001, 5304.23088652151, 4344.847930322424, 4434.623057040123, 4585.839495617637, 4786.353842231324, 4965.4289756678445, 3726.869559565402, 3761.3229618668356, 3840.116773142193, 3850.4801851749653, 3862.4399462078404, 1187.7327601289642, 1193.7672153930073, 1197.449173380255, 1202.7353982123918, 1204.1570900369777, 726.6221564786282, 732.2457868395561, 880.1330689271063, 912.8174365127002, 952.3999880075509, 4645.907081858411, 5796.942030579725, 5996.430339117776, 6094.116353128771, 6135.0, 792.6393490961599, 839.8992120945403, 941.4022095969109, 974.3958745577787, 1008.3899240959261, 3586.5158203642172, 3841.63621914222, 3928.744631598937, 3955.881323560298, 4082.999665082074, 9586.185174260267, 9846.621472993787, 10825.891741082489, 10879.891201088047, 10893.019628233496, 10580.312279701795, 10817.729556761144, 10824.5128969195, 10841.0, 10842.241043126905, 8098.0, 8178.959105204436, 8323.001239851257, 8323.875141872997, 8331.250187483156, 5791.333996590446, 5834.883302334015, 5933.042096108187, 5979.133025711334, 6022.939770602238, 10025.298229123891, 10227.181825453798, 10245.129164021142, 10264.127549158107, 10295.3822770634, 9678.225741940487, 10078.344907580984, 10140.847887281549, 10269.435181065113, 10302.793944121226, 6949.513534052568, 7136.6788494518905, 7257.129144502694, 7479.139898911695, 7663.770086897402, 8348.206920342558, 8408.621612027597, 8493.660253589787, 8499.405041647025, 8505.659773609234, 7365.889511657437, 7498.287662672037, 7521.473496855387, 7565.130010048915, 7605.163432022584, 7289.671964761493, 7320.890186647098, 7337.486375953632, 7492.550446973199, 7495.812604684708, 6914.5159838812815, 7045.330693584101, 7050.23744063984, 7064.187618423947, 7070.0, 4584.106099310602, 5014.122528557561, 5080.822171223989, 5083.898322033612, 5156.2523434102595, 4952.504749525018, 5007.0, 5144.6398752087, 5201.089809283436, 5261.710605916712], "value": 4559.540812794589, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [252.88746507804024, 303.76154718545945, 308.7113548831841, 313.8399416297689, 317.5601791518746, 4801.855944321676, 5539.639923404976, 5604.383517813042, 5647.407022262665, 5653.48419953121, 4190.224808410446, 5254.81608143715, 5530.253415788868, 5550.2507161533185, 5643.6049476536555, 3987.760734355948, 4085.3667681509264, 4160.0015996160855, 4194.30793919003, 4240.830366785325, 3996.7601943883315, 3997.2804895118866, 4166.562510936354, 4199.643030342425, 4274.5084315303675, 3576.7853928764225, 3863.652271295583, 4037.7375470594466, 4086.7752273624938, 4221.514525829522, 3606.6573675500786, 4048.5951404859575, 4103.569125241836, 4214.599613036757, 4258.87223383299, 3320.684534969174, 3599.784012959231, 3627.437747149196, 3631.1481144616437, 3633.291508155911, 4238.660907127422, 4356.8257269709175, 5404.405515393303, 5406.837794866121, 5508.91736623951, 4348.434703488526, 4631.814727410933, 4778.904421911542, 4838.758062096881, 4856.862980288697, 4958.454569997298, 5260.342457192851, 5314.92027619586, 5556.444355564452, 5930.703464826742, 3361.571332183812, 4035.717499775017, 4038.3942408638645, 4051.189762047573, 4051.270771261172, 5923.5853490255695, 6165.260168779775, 6228.22147231596, 6245.75016999324, 6247.718852651642, 4054.614811592894, 4075.735077219971, 4092.3043082676018, 4105.630493255621, 4107.815148318333, 3817.370133927871, 3844.59631738669, 4173.728707633994, 4186.597489840938, 4187.288161012635, 4691.554302341272, 4841.346418233564, 4861.805527778849, 4869.513048695102, 4877.731724755173, 5867.442592953663, 5901.704914754245, 5912.3496415394275, 5947.851303717442, 5957.463828255478, 1158.7276989907366, 1301.011231464075, 1390.9374078166506, 1416.221078406872, 1429.7712366021594, 708.4544900426642, 859.7549698335944, 878.4904755241944, 881.4446898453933, 883.9160279773412, 4595.471520775087, 4796.688215265996, 5224.608154388436, 5300.761465734051, 5518.654571806966, 5101.540861322499, 6028.0, 6076.574639775262, 6114.602550834182, 6144.262688477412, 4602.861914142581, 4990.975045124824, 5446.673199608036, 5454.345478542521, 5496.835094947158, 2593.6239245309325, 2700.594910763401, 2820.027090653721, 3083.753299736016, 3173.984325015973, 4977.427595826454, 5172.948270517322, 6235.251769787564, 6260.530460215502, 6262.1546091278005, 5395.568354531628, 5483.8080667176455, 6037.671712223304, 6194.150690682247, 6219.657918814418, 5203.453637368022, 5543.891221755665, 5818.272715910512, 6230.688465576703, 6303.527235457267, 4472.9105417892115, 5304.946950530445, 5469.753861076181, 5982.0, 6045.6070355426755, 5115.516500215045, 5344.679319240858, 5571.442855714558, 5919.970400147971, 5974.8490543405205, 5168.741562921914, 6160.815175544741, 6214.875702486015, 6297.2128483939505, 6342.968285158545, 3717.3494638438706, 3757.4176002719623, 3968.464257325224, 4032.778197199065, 4056.9797151015237, 5043.596512278936, 5208.374995000624, 5466.562674985912, 6067.757289708539, 6146.401935496714, 5476.726163691879, 5868.302969129421, 5979.910301345396, 6013.128096425906, 6033.215681961317, 4874.53691899269, 5038.319826823478, 5046.369203849519, 5411.78352865881, 5529.612927095226, 5102.948970510248, 5316.920246196388, 5796.913046304225, 5881.500072493886, 5921.993261145702, 5338.572914166935, 5609.80365687199, 5760.588655779361, 5858.0, 5860.150278209721, 4819.759012049453, 5401.864953376197, 5821.0, 5841.737121829443, 5880.588358814842], "value": 4193.239638556974, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.5884007855406, 777.8872063550785, 783.1838254222856, 790.8378782349619, 791.7783020754188, 14649.70700585989, 15051.698966020673, 15525.92237038816, 15531.84468155318, 15586.298616562253, 13938.094023888465, 14082.788758168635, 14202.14787112774, 14216.289185540732, 14257.429702811874, 674.6525539347216, 833.0461621443444, 865.139186509424, 866.6490071521044, 866.7919699272161, 837.5602808525542, 847.1147650705028, 863.6329559937033, 880.6037283222544, 883.1565854608833, 852.2627926843298, 900.8243392538458, 903.7876099116702, 907.5825120444604, 909.9863502047441, 838.7399906029151, 869.1829680100685, 892.5804871710351, 896.5203616065373, 897.9865302020474, 501.8619879533121, 624.0, 639.8528338482175, 673.5958424945043, 678.2268214235775, 11429.542818287258, 13125.474981000749, 14381.137131772128, 14423.567292981123, 14428.422863085463, 14007.229602371866, 14156.221407822566, 14212.218327991955, 14359.92820035893, 14506.492272770402, 11712.0, 11760.411979400995, 11801.527938882435, 11844.585439509576, 12013.0, 3720.795356255405, 3733.906652333686, 3738.196287798109, 3757.755745876509, 3897.279003384383, 93259.53370233106, 115037.0, 117997.41001295025, 120460.39769801183, 122117.38941305237, 10947.781044379068, 10970.493637253538, 11043.282186657842, 11054.00513953748, 11217.214794964275, 22318.776812231994, 22331.3300600979, 22365.888170559043, 22387.440313991956, 22418.32745017652, 7433.628318584158, 7438.367738742268, 7527.2849079337375, 7647.808804779925, 7763.68945242184, 4312.633426158749, 4317.07182955666, 4358.346248062816, 4397.098594788061, 4521.773911304422, 3415.692587667122, 3507.1582820122985, 3512.964870351315, 3523.647635236507, 3786.2238241160494, 706.5584009993754, 711.7295427737427, 713.7644577289482, 718.9036719003561, 719.665355609644, 522.5767128625826, 570.7888081409905, 583.4894467341077, 646.3471893387618, 653.7548419342746, 9245.491497967541, 9268.95365523168, 9274.768130796783, 9344.29917756171, 9408.200302974186, 531.5322516185731, 554.9445055494499, 582.3099626942087, 592.683627858112, 635.3995474276857, 4365.476142862877, 4498.370228168059, 4538.2511885538515, 4580.839670611513, 4671.5328467153695, 140079.29960350133, 157780.0, 161561.0, 162512.0, 165192.0, 137379.31310343585, 138359.0, 139358.30320848533, 139856.30071849577, 141889.29055354657, 89658.0, 106922.0, 110131.0, 110896.0, 114766.0, 8645.740627781177, 8724.389292749447, 8778.297736181217, 8791.692290769792, 8920.0, 65901.67049164724, 65990.34009659842, 66038.0, 66336.33663663302, 68755.65622172058, 54425.0, 73383.26616733764, 83814.16185838307, 91693.0, 93362.53318733363, 12973.935130324287, 13117.934410327887, 13141.806549976649, 13403.42442182327, 13816.792748108586, 11132.276402033842, 14784.0, 14822.258887055388, 14902.1803800792, 15325.846741532889, 11265.324080555192, 13554.525591604246, 13591.592252232449, 14352.210628415538, 14392.678868552512, 11739.647810565697, 13457.05800593949, 13678.179309241477, 13820.516281930084, 13921.721565568834, 9459.716208513755, 13225.603231903058, 13653.044286899822, 13815.516456923959, 13927.025108242731, 10752.537696855718, 11172.776544469227, 11328.093752499944, 11355.205135640426, 11421.629404471516, 9769.413835169913, 10334.379937203792, 10357.747882411559, 11401.486933087865, 11424.143189261005], "value": 7734.867576496568, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1630.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 98.0, 172.0, 162.0, 138.0, 123.0, 170.0, 149.0, 171.0, 166.0], "value": 145.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 111.0, 138.0, 159.0, 110.0, 136.0, 132.0, 112.0, 164.0, 121.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 232.0, 206.0, 202.0, 197.0, 203.0, 197.0, 198.0, 191.0, 190.0], "value": 202.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 83.0, 76.0, 147.0, 59.0, 61.0, 61.0, 61.0, 135.0, 60.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 121.0, 69.0, 74.0, 102.0, 143.0, 102.0, 82.0, 114.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 91.0, 102.0, 136.0, 95.0, 89.0, 86.0, 88.0, 85.0], "value": 94.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 110.0, 150.0, 123.0, 94.0, 95.0, 96.0, 138.0, 153.0], "value": 114.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 112.0, 141.0, 145.0, 91.0, 92.0, 77.0, 147.0, 148.0, 90.0], "value": 118.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 59.0, 58.0, 58.0, 58.0, 58.0, 60.0, 58.0, 57.0], "value": 57.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 104.0, 99.0, 101.0, 77.0, 75.0, 72.0, 73.0, 71.0, 71.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 117.0, 114.0, 86.0, 101.0, 88.0, 90.0, 89.0, 85.0], "value": 99.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 141.0, 143.0, 159.0, 162.0, 160.0, 156.0, 167.0, 203.0, 163.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 171.0, 171.0, 181.0, 178.0, 173.0, 169.0, 205.0, 198.0, 171.0], "value": 179.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 86.0, 69.0, 64.0, 67.0, 76.0, 69.0, 69.0, 65.0, 69.0], "value": 70.1, "unit": "score"}]}]} 11:40:01 INFO - Return code: 0 11:40:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:40:01 ERROR - # TBPL SUCCESS # 11:40:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:40:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:40:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:40:01 INFO - 2016-09-08 11:40:01.681 screenresolution[1672:10753] starting screenresolution argv=screenresolution get 11:40:01 INFO - 2016-09-08 11:40:01.684 screenresolution[1672:10753] Display 0: 1600x1200x32@60 11:40:01 INFO - 2016-09-08 11:40:01.690 screenresolution[1673:10756] starting screenresolution argv=screenresolution list 11:40:01 INFO - Available Modes on Display 0 11:40:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:40:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:40:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:40:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:40:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:40:01 INFO - Intel Iris: 11:40:01 INFO - Chipset Model: Intel Iris 11:40:01 INFO - Type: GPU 11:40:01 INFO - Bus: Built-In 11:40:01 INFO - VRAM (Dynamic, Max): 1536 MB 11:40:01 INFO - Vendor: Intel (0x8086) 11:40:01 INFO - Device ID: 0x0a2e 11:40:01 INFO - Revision ID: 0x0009 11:40:01 INFO - Displays: 11:40:01 INFO - Display: 11:40:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:40:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:40:01 INFO - Display Serial Number: 1600x1200 60 11:40:01 INFO - Main Display: Yes 11:40:01 INFO - Mirror: Off 11:40:01 INFO - Online: Yes 11:40:01 INFO - Rotation: Supported 11:40:01 INFO - Return code: 0 11:40:01 INFO - Running post-action listener: _resource_record_post_action 11:40:01 INFO - [mozharness: 2016-09-08 18:40:01.927445Z] Finished run-tests step (success) 11:40:01 INFO - Running post-run listener: _resource_record_post_run 11:40:01 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 38777856; Write bytes: 708050944; Read time: 386; Write time: 1967 11:40:01 INFO - TinderboxPrint: CPU usage
54.5% 11:40:01 INFO - TinderboxPrint: I/O read bytes / time
38,777,856 / 386 11:40:01 INFO - TinderboxPrint: I/O write bytes / time
708,050,944 / 1,967 11:40:01 INFO - TinderboxPrint: CPU idle
2,202.9 (45.5%) 11:40:01 INFO - TinderboxPrint: CPU system
981.4 (20.3%) 11:40:01 INFO - TinderboxPrint: CPU user
1,657.3 (34.2%) 11:40:01 INFO - TinderboxPrint: Swap in / out
444,669,952 / 0 11:40:01 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 406596096; Write bytes: 403613696; Read time: 29805; Write time: 769 11:40:02 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 34620416; Write bytes: 297928704; Read time: 350; Write time: 1177 11:40:02 INFO - Running post-run listener: _upload_blobber_files 11:40:02 INFO - Blob upload gear active. 11:40:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:40:02 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 11:40:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:40:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:40:02 INFO - (blobuploader) - INFO - Open directory for files ... 11:40:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:40:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:40:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:40:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:40:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:40:03 INFO - (blobuploader) - INFO - Done attempting. 11:40:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:40:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:40:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:40:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:40:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:40:04 INFO - (blobuploader) - INFO - Done attempting. 11:40:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:40:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:40:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:40:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:40:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:40:07 INFO - (blobuploader) - INFO - Done attempting. 11:40:07 INFO - (blobuploader) - INFO - Iteration through files over. 11:40:07 INFO - Return code: 0 11:40:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:40:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:40:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a8f6a6428cf020f12dc0267b66cbd2585490573f1ed101b04da671fea109dde081b66c387b658889ff6eeafe4227affc5cc09c729c60b66d4d2bce7d39ea08f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ab5b488c4a209c41429b1b82c58140ea5fbc3fb5aec6af12b7a7bd4e9cca92a5573d2a4ea24fd1849670674fe6c2c4440dcf3dc859c53d372f8e15a987c97b0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bacd1f6386d5d1a32c0b41ea44c4cf294d2ba209b10e0f0ac101f740583b85604e955a9715173785a181070944df25c2eb392b1b6fe89357fbe8529446416b3a"} 11:40:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:40:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:40:07 INFO - Contents: 11:40:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a8f6a6428cf020f12dc0267b66cbd2585490573f1ed101b04da671fea109dde081b66c387b658889ff6eeafe4227affc5cc09c729c60b66d4d2bce7d39ea08f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ab5b488c4a209c41429b1b82c58140ea5fbc3fb5aec6af12b7a7bd4e9cca92a5573d2a4ea24fd1849670674fe6c2c4440dcf3dc859c53d372f8e15a987c97b0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bacd1f6386d5d1a32c0b41ea44c4cf294d2ba209b10e0f0ac101f740583b85604e955a9715173785a181070944df25c2eb392b1b6fe89357fbe8529446416b3a"} 11:40:07 INFO - Running post-run listener: copy_logs_to_upload_dir 11:40:07 INFO - Copying logs to upload dir... 11:40:07 INFO - mkdir: /builds/slave/test/build/upload/logs 11:40:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.019349 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-08 11:40:07.419984) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 11:40:07.423159) ========= 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.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners TMPDIR=/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a8f6a6428cf020f12dc0267b66cbd2585490573f1ed101b04da671fea109dde081b66c387b658889ff6eeafe4227affc5cc09c729c60b66d4d2bce7d39ea08f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ab5b488c4a209c41429b1b82c58140ea5fbc3fb5aec6af12b7a7bd4e9cca92a5573d2a4ea24fd1849670674fe6c2c4440dcf3dc859c53d372f8e15a987c97b0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bacd1f6386d5d1a32c0b41ea44c4cf294d2ba209b10e0f0ac101f740583b85604e955a9715173785a181070944df25c2eb392b1b6fe89357fbe8529446416b3a"} build_url:https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011130 build_url: 'https://queue.taskcluster.net/v1/task/Nn_xrIj0TsKpUFbRJitSqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a8f6a6428cf020f12dc0267b66cbd2585490573f1ed101b04da671fea109dde081b66c387b658889ff6eeafe4227affc5cc09c729c60b66d4d2bce7d39ea08f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ab5b488c4a209c41429b1b82c58140ea5fbc3fb5aec6af12b7a7bd4e9cca92a5573d2a4ea24fd1849670674fe6c2c4440dcf3dc859c53d372f8e15a987c97b0a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bacd1f6386d5d1a32c0b41ea44c4cf294d2ba209b10e0f0ac101f740583b85604e955a9715173785a181070944df25c2eb392b1b6fe89357fbe8529446416b3a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 11:40:07.456727) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 11:40:07.457098) ========= 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.IUaBfCVomZ/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.ZAMlF40Lw6/Listeners TMPDIR=/var/folders/3_/7trspq7n5235lqw2h9mfsq5800000w/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.005243 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 11:40:07.506498) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 11:40:07.506869) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 11:40:07.507259) ========= ========= Total master_lag: 0.20 =========