builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0133 starttime: 1473731721.58 results: success (0) buildid: 20160912165308 builduid: c1c8d272802d4cf58ada2be86d27c4bc revision: 7b1cb8b0be7caa5e785159bb7c3650079d244303 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 18:55:21.584062) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 18:55:21.584529) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 18:55:21.584813) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 18:55:21.613713) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 18:55:21.614131) ========= 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.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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-12 18:55:21-- 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% 9.80M=0.001s 2016-09-12 18:55:21 (9.80 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097752 ========= master_lag: 1.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 18:55:22.799098) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 18:55:22.799462) ========= 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.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.027344 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 18:55:22.850399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-09-12 18:55:22.851015) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7b1cb8b0be7caa5e785159bb7c3650079d244303 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 7b1cb8b0be7caa5e785159bb7c3650079d244303 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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-12 18:55:22,928 truncating revision to first 12 chars 2016-09-12 18:55:22,928 Setting DEBUG logging. 2016-09-12 18:55:22,928 attempt 1/10 2016-09-12 18:55:22,929 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7b1cb8b0be7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 18:55:24,349 unpacking tar archive at: mozilla-central-7b1cb8b0be7c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.688312 ========= master_lag: 1.65 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-09-12 18:55:26.191435) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 18:55:26.191772) ========= script_repo_revision: 7b1cb8b0be7caa5e785159bb7c3650079d244303 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 18:55:26.192198) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 18:55:26.192476) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 18:55:26.304029) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-12 18:55:26.304454) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:55:26 INFO - MultiFileLogger online at 20160912 18:55:26 in /builds/slave/test 18:55:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:55:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:55:26 INFO - {'append_to_log': False, 18:55:26 INFO - 'base_work_dir': '/builds/slave/test', 18:55:26 INFO - 'blob_upload_branch': 'Firefox', 18:55:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:55:26 INFO - 'branch': 'Firefox', 18:55:26 INFO - 'buildbot_json_path': 'buildprops.json', 18:55:26 INFO - 'config_files': ('talos/mac_config.py',), 18:55:26 INFO - 'default_actions': ('clobber', 18:55:26 INFO - 'read-buildbot-config', 18:55:26 INFO - 'download-and-extract', 18:55:26 INFO - 'populate-webroot', 18:55:26 INFO - 'create-virtualenv', 18:55:26 INFO - 'install', 18:55:26 INFO - 'run-tests'), 18:55:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:55:26 INFO - 'download_minidump_stackwalk': True, 18:55:26 INFO - 'download_symbols': 'ondemand', 18:55:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:55:26 INFO - 'tooltool.py': '/tools/tooltool.py', 18:55:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:55:26 INFO - '/tools/misc-python/virtualenv.py')}, 18:55:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:55:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:55:26 INFO - 'installer_path': 'installer.exe', 18:55:26 INFO - 'log_level': 'info', 18:55:26 INFO - 'log_name': 'talos', 18:55:26 INFO - 'log_to_console': True, 18:55:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:55:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:55:26 INFO - 'opt_config_files': (), 18:55:26 INFO - 'pip_index': False, 18:55:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:55:26 INFO - 'cmd': ('bash', 18:55:26 INFO - '-c', 18:55:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:55:26 INFO - 'enabled': True, 18:55:26 INFO - 'halt_on_failure': False, 18:55:26 INFO - 'name': 'check_screen_resolution'},), 18:55:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:55:26 INFO - 'cmd': ('bash', 18:55:26 INFO - '-c', 18:55:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:55:26 INFO - 'enabled': True, 18:55:26 INFO - 'halt_on_failure': False, 18:55:26 INFO - 'name': 'check_screen_resolution'},), 18:55:26 INFO - 'run_cmd_checks_enabled': True, 18:55:26 INFO - 'sps_profile': False, 18:55:26 INFO - 'sps_profile_interval': 0, 18:55:26 INFO - 'suite': 'dromaeojs-e10s', 18:55:26 INFO - 'system_bits': '32', 18:55:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:55:26 INFO - 'title': 't-yosemite-r7-0133', 18:55:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:55:26 INFO - 'use_talos_json': True, 18:55:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:55:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:55:26 INFO - 'work_dir': 'build'} 18:55:26 INFO - [mozharness: 2016-09-13 01:55:26.505752Z] Running clobber step. 18:55:26 INFO - Running pre-action listener: _resource_record_pre_action 18:55:26 INFO - Running main action method: clobber 18:55:26 INFO - rmtree: /builds/slave/test/build 18:55:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:55:28 INFO - Running post-action listener: _resource_record_post_action 18:55:28 INFO - [mozharness: 2016-09-13 01:55:28.565490Z] Finished clobber step (success) 18:55:28 INFO - [mozharness: 2016-09-13 01:55:28.565620Z] Running read-buildbot-config step. 18:55:28 INFO - Running pre-action listener: _resource_record_pre_action 18:55:28 INFO - Running main action method: read_buildbot_config 18:55:28 INFO - Using buildbot properties: 18:55:28 INFO - { 18:55:28 INFO - "project": "", 18:55:28 INFO - "product": "firefox", 18:55:28 INFO - "script_repo_revision": "production", 18:55:28 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:55:28 INFO - "repository": "", 18:55:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:55:28 INFO - "buildid": "20160912165308", 18:55:28 INFO - "pgo_build": "False", 18:55:28 INFO - "basedir": "/builds/slave/test", 18:55:28 INFO - "buildnumber": 25, 18:55:28 INFO - "slavename": "t-yosemite-r7-0133", 18:55:28 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 18:55:28 INFO - "platform": "yosemite_r7", 18:55:28 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:55:28 INFO - "branch": "mozilla-central", 18:55:28 INFO - "repo_path": "mozilla-central", 18:55:28 INFO - "revision": "7b1cb8b0be7caa5e785159bb7c3650079d244303", 18:55:28 INFO - "stage_platform": "macosx64", 18:55:28 INFO - "builduid": "c1c8d272802d4cf58ada2be86d27c4bc", 18:55:28 INFO - "slavebuilddir": "test" 18:55:28 INFO - } 18:55:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:55:28 INFO - Running post-action listener: _resource_record_post_action 18:55:28 INFO - [mozharness: 2016-09-13 01:55:28.569116Z] Finished read-buildbot-config step (success) 18:55:28 INFO - [mozharness: 2016-09-13 01:55:28.569248Z] Running download-and-extract step. 18:55:28 INFO - Running pre-action listener: _resource_record_pre_action 18:55:28 INFO - Running main action method: download_and_extract 18:55:28 INFO - mkdir: /builds/slave/test/build/tests 18:55: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')]} 18:55:28 INFO - trying https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:55:28 INFO - Downloading https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/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 18:55:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/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 18:55:29 INFO - Downloaded 1199 bytes. 18:55:29 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:55:29 INFO - Using the following test package requirements: 18:55:29 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:55:29 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:55:29 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:55:29 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:55:29 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:55:29 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:55:29 INFO - u'jsshell-mac.zip'], 18:55:29 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:55:29 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:55:29 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:55:29 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:55:29 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:55:29 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:55:29 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:55:29 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:55:29 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:55:29 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:55:29 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:55:29 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:55:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:55:30 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:55:37 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 18:55:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:55:37 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:55:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:55:45 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:55:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:46 INFO - trying https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:55:46 INFO - Downloading https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:55:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/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 18:55:49 INFO - Downloaded 111646471 bytes. 18:55:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:55:49 INFO - mkdir: /builds/slave/test/properties 18:55:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:55:49 INFO - Writing to file /builds/slave/test/properties/build_url 18:55:49 INFO - Contents: 18:55:49 INFO - build_url:https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:55:49 INFO - Running post-action listener: _resource_record_post_action 18:55:49 INFO - Running post-action listener: set_extra_try_arguments 18:55:49 INFO - [mozharness: 2016-09-13 01:55:49.943760Z] Finished download-and-extract step (success) 18:55:49 INFO - [mozharness: 2016-09-13 01:55:49.943923Z] Running populate-webroot step. 18:55:49 INFO - Running pre-action listener: _resource_record_pre_action 18:55:49 INFO - Running main action method: populate_webroot 18:55:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:55:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:55:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:55:49 INFO - '--tppagecycles', 18:55:49 INFO - '7'], 18:55:49 INFO - 'tests': ['tsvgm']}}, 18:55:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:55:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:55:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:55:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:55:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:49 INFO - 'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:55:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:55:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:49 INFO - 'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['damp', 'tps']}, 18:55:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:49 INFO - 'tests': ['damp', 'tps']}, 18:55:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['dromaeo_dom']}, 18:55:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:55:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['basic_compositor_video']}, 18:55:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:55:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['a11yr', 18:55:49 INFO - 'ts_paint', 18:55:49 INFO - 'tpaint', 18:55:49 INFO - 'sessionrestore', 18:55:49 INFO - 'sessionrestore_no_auto_restore', 18:55:49 INFO - 'tabpaint']}, 18:55:49 INFO - 'other-e10s': {'tests': ['a11yr', 18:55:49 INFO - 'ts_paint', 18:55:49 INFO - 'tpaint', 18:55:49 INFO - 'sessionrestore', 18:55:49 INFO - 'sessionrestore_no_auto_restore', 18:55:49 INFO - 'tabpaint']}, 18:55:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:55:49 INFO - 'ts_paint', 18:55:49 INFO - 'tpaint', 18:55:49 INFO - 'sessionrestore', 18:55:49 INFO - 'sessionrestore_no_auto_restore', 18:55:49 INFO - 'tabpaint']}, 18:55:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:55:49 INFO - 'ts_paint', 18:55:49 INFO - 'tpaint', 18:55:49 INFO - 'sessionrestore', 18:55:49 INFO - 'sessionrestore_no_auto_restore', 18:55:49 INFO - 'tabpaint']}, 18:55:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['a11yr', 18:55:49 INFO - 'ts_paint', 18:55:49 INFO - 'tpaint', 18:55:49 INFO - 'sessionrestore', 18:55:49 INFO - 'sessionrestore_no_auto_restore', 18:55:49 INFO - 'tabpaint']}, 18:55:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['a11yr', 18:55:49 INFO - 'ts_paint', 18:55:49 INFO - 'tpaint', 18:55:49 INFO - 'sessionrestore', 18:55:49 INFO - 'sessionrestore_no_auto_restore', 18:55:49 INFO - 'tabpaint']}, 18:55:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['tsvgx', 18:55:49 INFO - 'tsvgr_opacity', 18:55:49 INFO - 'tart', 18:55:49 INFO - 'tscrollx', 18:55:49 INFO - 'cart']}, 18:55:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:55:49 INFO - 'tsvgr_opacity', 18:55:49 INFO - 'tart', 18:55:49 INFO - 'tscrollx', 18:55:49 INFO - 'cart']}, 18:55:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:49 INFO - 'talos_options': ['--disable-e10s'], 18:55:49 INFO - 'tests': ['tp5o']}, 18:55:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:49 INFO - 'tests': ['tp5o']}, 18:55:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:49 INFO - 'talos_options': ['--disable-e10s', 18:55:49 INFO - '--xperf_path', 18:55:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:55:49 INFO - 'tests': ['tp5n']}, 18:55:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:55:49 INFO - 'talos_options': ['--xperf_path', 18:55:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:55:49 INFO - 'tests': ['tp5n']}}, 18:55:49 INFO - 'talos.zip': {'path': '', 18:55:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:55:49 INFO - Running post-action listener: _resource_record_post_action 18:55:49 INFO - [mozharness: 2016-09-13 01:55:49.962843Z] Finished populate-webroot step (success) 18:55:49 INFO - [mozharness: 2016-09-13 01:55:49.962981Z] Running create-virtualenv step. 18:55:49 INFO - Running pre-action listener: _resource_record_pre_action 18:55:49 INFO - Running main action method: create_virtualenv 18:55:49 INFO - Creating virtualenv /builds/slave/test/build/venv 18:55:49 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 18:55:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:55:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:55:50 INFO - Using real prefix '/tools/python27' 18:55:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:55:50 INFO - Installing distribute.............................................................................................................................................................................................done. 18:55:53 INFO - Installing pip.................done. 18:55:53 INFO - Return code: 0 18:55:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf28cee170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107729d30>, '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 0x10754fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107721b20>, '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.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:55:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:55:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:55:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4KYXBdqLjj/Render', 18:55:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:53 INFO - 'HOME': '/Users/cltbld', 18:55:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:53 INFO - 'LOGNAME': 'cltbld', 18:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:53 INFO - 'MOZ_NO_REMOTE': '1', 18:55:53 INFO - 'NO_EM_RESTART': '1', 18:55:53 INFO - 'PAGER': '/bin/cat', 18:55:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:53 INFO - 'PWD': '/builds/slave/test', 18:55:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:53 INFO - 'SHELL': '/bin/bash', 18:55:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YzuoQM1f1Q/Listeners', 18:55:53 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:55:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:53 INFO - 'USER': 'cltbld', 18:55:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:53 INFO - 'XPC_FLAGS': '0x0', 18:55:53 INFO - 'XPC_SERVICE_NAME': '0', 18:55:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:54 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 18:55:54 INFO - Cleaning up... 18:55:54 INFO - Return code: 0 18:55:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:55:54 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')]} 18:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:54 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')]} 18:55:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:54 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 0x107915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf28cee170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107729d30>, '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 0x10754fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107721b20>, '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.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:55:54 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 18:55:54 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 18:55:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4KYXBdqLjj/Render', 18:55:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:54 INFO - 'HOME': '/Users/cltbld', 18:55:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:54 INFO - 'LOGNAME': 'cltbld', 18:55:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:54 INFO - 'MOZ_NO_REMOTE': '1', 18:55:54 INFO - 'NO_EM_RESTART': '1', 18:55:54 INFO - 'PAGER': '/bin/cat', 18:55:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:54 INFO - 'PWD': '/builds/slave/test', 18:55:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:54 INFO - 'SHELL': '/bin/bash', 18:55:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YzuoQM1f1Q/Listeners', 18:55:54 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:55:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:54 INFO - 'USER': 'cltbld', 18:55:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:54 INFO - 'XPC_FLAGS': '0x0', 18:55:54 INFO - 'XPC_SERVICE_NAME': '0', 18:55:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:54 INFO - Downloading/unpacking psutil>=3.1.1 18:55:54 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 18:55:54 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 18:55:54 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 18:55:54 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 18:55:54 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 18:55:54 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 18:55:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:55:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:55:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:55:57 INFO - Installing collected packages: psutil 18:55:57 INFO - Running setup.py install for psutil 18:55:57 INFO - building 'psutil._psutil_osx' extension 18:55:57 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 18:55:57 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 18:55:57 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 18:55:58 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 18:55:58 INFO - building 'psutil._psutil_posix' extension 18:55:58 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 18:55:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:55:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:55:58 INFO - ^ 18:55:58 INFO - 1 warning generated. 18:55:58 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 18:55:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:55:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:55:58 INFO - Successfully installed psutil 18:55:58 INFO - Cleaning up... 18:55:58 INFO - Return code: 0 18:55:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:55:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:55:58 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 0x107915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf28cee170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107729d30>, '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 0x10754fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107721b20>, '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.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:55:58 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 18:55:58 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 18:55:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4KYXBdqLjj/Render', 18:55:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:58 INFO - 'HOME': '/Users/cltbld', 18:55:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:58 INFO - 'LOGNAME': 'cltbld', 18:55:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:58 INFO - 'MOZ_NO_REMOTE': '1', 18:55:58 INFO - 'NO_EM_RESTART': '1', 18:55:58 INFO - 'PAGER': '/bin/cat', 18:55:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:58 INFO - 'PWD': '/builds/slave/test', 18:55:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:58 INFO - 'SHELL': '/bin/bash', 18:55:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YzuoQM1f1Q/Listeners', 18:55:58 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:55:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:58 INFO - 'USER': 'cltbld', 18:55:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:58 INFO - 'XPC_FLAGS': '0x0', 18:55:58 INFO - 'XPC_SERVICE_NAME': '0', 18:55:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:55:58 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 18:55:58 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 18:55:58 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 18:55:58 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 18:55:58 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 18:55:58 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 18:56:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:56:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:56:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:56:01 INFO - Installing collected packages: mozsystemmonitor 18:56:01 INFO - Running setup.py install for mozsystemmonitor 18:56:01 INFO - Successfully installed mozsystemmonitor 18:56:01 INFO - Cleaning up... 18:56:01 INFO - Return code: 0 18:56:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:56:01 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')]} 18:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:01 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')]} 18:56:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:01 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 0x107915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf28cee170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107729d30>, '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 0x10754fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107721b20>, '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.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:56:01 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 18:56:01 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 18:56:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4KYXBdqLjj/Render', 18:56:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:01 INFO - 'HOME': '/Users/cltbld', 18:56:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:01 INFO - 'LOGNAME': 'cltbld', 18:56:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:01 INFO - 'MOZ_NO_REMOTE': '1', 18:56:01 INFO - 'NO_EM_RESTART': '1', 18:56:01 INFO - 'PAGER': '/bin/cat', 18:56:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:01 INFO - 'PWD': '/builds/slave/test', 18:56:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:01 INFO - 'SHELL': '/bin/bash', 18:56:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YzuoQM1f1Q/Listeners', 18:56:01 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:56:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:01 INFO - 'USER': 'cltbld', 18:56:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:01 INFO - 'XPC_FLAGS': '0x0', 18:56:01 INFO - 'XPC_SERVICE_NAME': '0', 18:56:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:01 INFO - Downloading/unpacking jsonschema==2.5.1 18:56:01 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 18:56:01 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 18:56:01 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 18:56:01 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 18:56:01 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 18:56:01 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 18:56:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:56:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:56:04 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 18:56:04 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 18:56:04 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 18:56:04 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 18:56:04 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 18:56:04 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 18:56:04 INFO - Downloading functools32-3.2.3-2.tar.gz 18:56:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:56:04 INFO - warning: no files found matching '*.txt' 18:56:04 INFO - no previously-included directories found matching 'build' 18:56:04 INFO - no previously-included directories found matching 'dist' 18:56:04 INFO - no previously-included directories found matching '.git*' 18:56:04 INFO - Installing collected packages: jsonschema, functools32 18:56:04 INFO - Running setup.py install for functools32 18:56:04 INFO - warning: no files found matching '*.txt' 18:56:04 INFO - no previously-included directories found matching 'build' 18:56:04 INFO - no previously-included directories found matching 'dist' 18:56:04 INFO - no previously-included directories found matching '.git*' 18:56:05 INFO - Successfully installed jsonschema functools32 18:56:05 INFO - Cleaning up... 18:56:05 INFO - Return code: 0 18:56:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:56:05 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')]} 18:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:05 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')]} 18:56:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:05 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 0x107915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf28cee170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107729d30>, '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 0x10754fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107721b20>, '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.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:56:05 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 18:56:05 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 18:56:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4KYXBdqLjj/Render', 18:56:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:05 INFO - 'HOME': '/Users/cltbld', 18:56:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:05 INFO - 'LOGNAME': 'cltbld', 18:56:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:05 INFO - 'MOZ_NO_REMOTE': '1', 18:56:05 INFO - 'NO_EM_RESTART': '1', 18:56:05 INFO - 'PAGER': '/bin/cat', 18:56:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:05 INFO - 'PWD': '/builds/slave/test', 18:56:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:05 INFO - 'SHELL': '/bin/bash', 18:56:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YzuoQM1f1Q/Listeners', 18:56:05 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:56:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:05 INFO - 'USER': 'cltbld', 18:56:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:05 INFO - 'XPC_FLAGS': '0x0', 18:56:05 INFO - 'XPC_SERVICE_NAME': '0', 18:56:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:56:05 INFO - Cleaning up... 18:56:05 INFO - Return code: 0 18:56:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:56:05 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')]} 18:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:05 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')]} 18:56:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:05 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 0x107915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf28cee170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107729d30>, '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 0x10754fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107721b20>, '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.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:56:05 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 18:56:05 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 18:56:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4KYXBdqLjj/Render', 18:56:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:05 INFO - 'HOME': '/Users/cltbld', 18:56:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:05 INFO - 'LOGNAME': 'cltbld', 18:56:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:05 INFO - 'MOZ_NO_REMOTE': '1', 18:56:05 INFO - 'NO_EM_RESTART': '1', 18:56:05 INFO - 'PAGER': '/bin/cat', 18:56:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:05 INFO - 'PWD': '/builds/slave/test', 18:56:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:05 INFO - 'SHELL': '/bin/bash', 18:56:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YzuoQM1f1Q/Listeners', 18:56:05 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:56:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:05 INFO - 'USER': 'cltbld', 18:56:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:05 INFO - 'XPC_FLAGS': '0x0', 18:56:05 INFO - 'XPC_SERVICE_NAME': '0', 18:56:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:05 INFO - Downloading/unpacking blobuploader==1.2.4 18:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:08 INFO - Downloading blobuploader-1.2.4.tar.gz 18:56:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:56:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:56:08 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 18:56:08 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 18:56:08 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 18:56:08 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 18:56:08 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 18:56:08 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 18:56:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:56:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:56:08 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 18:56:08 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 18:56:08 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 18:56:08 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 18:56:08 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 18:56:08 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 18:56:09 INFO - Downloading docopt-0.6.1.tar.gz 18:56:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:56:09 INFO - Installing collected packages: blobuploader, requests, docopt 18:56:09 INFO - Running setup.py install for blobuploader 18:56:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:56:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:56:09 INFO - Running setup.py install for requests 18:56:10 INFO - Running setup.py install for docopt 18:56:10 INFO - Successfully installed blobuploader requests docopt 18:56:10 INFO - Cleaning up... 18:56:10 INFO - Return code: 0 18:56:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:10 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')]} 18:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:10 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')]} 18:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:10 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 0x107915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf28cee170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107729d30>, '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 0x10754fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107721b20>, '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.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:56:10 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 18:56:10 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 18:56:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4KYXBdqLjj/Render', 18:56:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:10 INFO - 'HOME': '/Users/cltbld', 18:56:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:10 INFO - 'LOGNAME': 'cltbld', 18:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:10 INFO - 'MOZ_NO_REMOTE': '1', 18:56:10 INFO - 'NO_EM_RESTART': '1', 18:56:10 INFO - 'PAGER': '/bin/cat', 18:56:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:10 INFO - 'PWD': '/builds/slave/test', 18:56:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:10 INFO - 'SHELL': '/bin/bash', 18:56:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YzuoQM1f1Q/Listeners', 18:56:10 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:56:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:10 INFO - 'USER': 'cltbld', 18:56:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:10 INFO - 'XPC_FLAGS': '0x0', 18:56:10 INFO - 'XPC_SERVICE_NAME': '0', 18:56:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:10 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-OLWiwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:10 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-j5R4Ux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:10 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-sbwWbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:10 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-hitrd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:10 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-7msxeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-KbjnDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-jlatZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-RDizEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-7qTAwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Qf4KrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-YP51tJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-cbLMFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-yRVgGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-vmMADJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-o5kJRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:12 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-p37IJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:12 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-QBXE9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:56:12 INFO - Running setup.py install for manifestparser 18:56:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:56:12 INFO - Running setup.py install for mozcrash 18:56:12 INFO - Running setup.py install for mozdebug 18:56:12 INFO - Running setup.py install for mozdevice 18:56:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:56:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:56:12 INFO - Running setup.py install for mozfile 18:56:13 INFO - Running setup.py install for mozhttpd 18:56:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:56:13 INFO - Running setup.py install for mozinfo 18:56:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:56:13 INFO - Running setup.py install for mozInstall 18:56:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:56:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:56:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:56:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:56:13 INFO - Running setup.py install for mozleak 18:56:13 INFO - Running setup.py install for mozlog 18:56:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:56:13 INFO - Running setup.py install for moznetwork 18:56:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:56:13 INFO - Running setup.py install for mozprocess 18:56:14 INFO - Running setup.py install for mozprofile 18:56:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:56:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:56:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:56:14 INFO - Running setup.py install for mozrunner 18:56:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:56:14 INFO - Running setup.py install for mozscreenshot 18:56:14 INFO - Running setup.py install for moztest 18:56:14 INFO - Running setup.py install for mozversion 18:56:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:56:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:56:14 INFO - Cleaning up... 18:56:14 INFO - Return code: 0 18:56:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:14 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 0x107915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf28cee170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107729d30>, '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 0x10754fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107721b20>, '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.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:56:14 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 18:56:14 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 18:56:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4KYXBdqLjj/Render', 18:56:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:14 INFO - 'HOME': '/Users/cltbld', 18:56:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:14 INFO - 'LOGNAME': 'cltbld', 18:56:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:14 INFO - 'MOZ_NO_REMOTE': '1', 18:56:14 INFO - 'NO_EM_RESTART': '1', 18:56:14 INFO - 'PAGER': '/bin/cat', 18:56:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:14 INFO - 'PWD': '/builds/slave/test', 18:56:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:14 INFO - 'SHELL': '/bin/bash', 18:56:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YzuoQM1f1Q/Listeners', 18:56:14 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:56:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:14 INFO - 'USER': 'cltbld', 18:56:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:14 INFO - 'XPC_FLAGS': '0x0', 18:56:14 INFO - 'XPC_SERVICE_NAME': '0', 18:56:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:15 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-NZQ5jV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:15 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)) 18:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:15 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-m9zaZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:15 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)) 18:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:15 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-c3Bpei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:15 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)) 18:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:15 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-scGr57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:15 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)) 18:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:15 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-nzaIAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:15 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)) 18:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:15 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-dkUb9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:15 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)) 18:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:15 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-SUfxnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:15 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)) 18:56:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:15 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-m_OfDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:16 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)) 18:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:16 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-XHWAPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:16 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)) 18:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:16 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-SsvFvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:16 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)) 18:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:16 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Tq80py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:16 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)) 18:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:16 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-RZ8YKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:16 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)) 18:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:16 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-giJl08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:16 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)) 18:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:16 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-n1MYwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:16 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)) 18:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:16 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-eQYwUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:16 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)) 18:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:16 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-kWIGtQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:16 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)) 18:56:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:16 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-iD_sud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:16 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)) 18:56:16 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)) 18:56:16 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)) 18:56:16 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)) 18:56:16 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)) 18:56:16 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)) 18:56:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:19 INFO - Downloading blessings-1.6.tar.gz 18:56:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:56:19 INFO - Installing collected packages: blessings 18:56:19 INFO - Running setup.py install for blessings 18:56:19 INFO - Successfully installed blessings 18:56:19 INFO - Cleaning up... 18:56:19 INFO - Return code: 0 18:56:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:56:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:19 INFO - Reading from file tmpfile_stdout 18:56:19 INFO - Current package versions: 18:56:19 INFO - blessings == 1.6 18:56:19 INFO - blobuploader == 1.2.4 18:56:19 INFO - docopt == 0.6.1 18:56:19 INFO - functools32 == 3.2.3-2 18:56:19 INFO - jsonschema == 2.5.1 18:56:19 INFO - manifestparser == 1.1 18:56:19 INFO - mozInstall == 1.12 18:56:19 INFO - mozcrash == 0.17 18:56:19 INFO - mozdebug == 0.1 18:56:19 INFO - mozdevice == 0.48 18:56:19 INFO - mozfile == 1.2 18:56:19 INFO - mozhttpd == 0.7 18:56:19 INFO - mozinfo == 0.9 18:56:19 INFO - mozleak == 0.1 18:56:19 INFO - mozlog == 3.3 18:56:19 INFO - moznetwork == 0.27 18:56:19 INFO - mozprocess == 0.23 18:56:19 INFO - mozprofile == 0.28 18:56:19 INFO - mozrunner == 6.12 18:56:19 INFO - mozscreenshot == 0.1 18:56:19 INFO - mozsystemmonitor == 0.3 18:56:19 INFO - moztest == 0.7 18:56:19 INFO - mozversion == 1.4 18:56:19 INFO - psutil == 3.1.1 18:56:19 INFO - requests == 1.2.3 18:56:19 INFO - wsgiref == 0.1.2 18:56:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:20 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')]} 18:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:20 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')]} 18:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:20 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 0x107915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf28cee170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107729d30>, '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 0x10754fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107721b20>, '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.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:56:20 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 18:56:20 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 18:56:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4KYXBdqLjj/Render', 18:56:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:20 INFO - 'HOME': '/Users/cltbld', 18:56:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:20 INFO - 'LOGNAME': 'cltbld', 18:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:20 INFO - 'MOZ_NO_REMOTE': '1', 18:56:20 INFO - 'NO_EM_RESTART': '1', 18:56:20 INFO - 'PAGER': '/bin/cat', 18:56:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:20 INFO - 'PWD': '/builds/slave/test', 18:56:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:20 INFO - 'SHELL': '/bin/bash', 18:56:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YzuoQM1f1Q/Listeners', 18:56:20 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:56:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:20 INFO - 'USER': 'cltbld', 18:56:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:20 INFO - 'XPC_FLAGS': '0x0', 18:56:20 INFO - 'XPC_SERVICE_NAME': '0', 18:56:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:20 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)) 18:56:20 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)) 18:56:20 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)) 18:56:20 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)) 18:56:20 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)) 18:56:20 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)) 18:56:20 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)) 18:56:20 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)) 18:56:20 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)) 18:56:20 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)) 18:56:20 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)) 18:56:20 INFO - Cleaning up... 18:56:20 INFO - Return code: 0 18:56:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:56:20 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')]} 18:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:20 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')]} 18:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:20 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 0x107915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10788b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf28cee170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107729d30>, '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 0x10754fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107721b20>, '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.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:56:20 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 18:56:20 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 18:56:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4KYXBdqLjj/Render', 18:56:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:20 INFO - 'HOME': '/Users/cltbld', 18:56:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:20 INFO - 'LOGNAME': 'cltbld', 18:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:20 INFO - 'MOZ_NO_REMOTE': '1', 18:56:20 INFO - 'NO_EM_RESTART': '1', 18:56:20 INFO - 'PAGER': '/bin/cat', 18:56:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:20 INFO - 'PWD': '/builds/slave/test', 18:56:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:20 INFO - 'SHELL': '/bin/bash', 18:56:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YzuoQM1f1Q/Listeners', 18:56:20 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:56:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:20 INFO - 'USER': 'cltbld', 18:56:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:20 INFO - 'XPC_FLAGS': '0x0', 18:56:20 INFO - 'XPC_SERVICE_NAME': '0', 18:56:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:56:20 INFO - Cleaning up... 18:56:20 INFO - Return code: 0 18:56:20 INFO - Running post-action listener: _resource_record_post_action 18:56:20 INFO - Running post-action listener: _start_resource_monitoring 18:56:20 INFO - Starting resource monitoring. 18:56:20 INFO - [mozharness: 2016-09-13 01:56:20.913255Z] Finished create-virtualenv step (success) 18:56:20 INFO - [mozharness: 2016-09-13 01:56:20.914215Z] Running install step. 18:56:20 INFO - Running pre-action listener: _resource_record_pre_action 18:56:20 INFO - Running main action method: install 18:56:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:21 INFO - Reading from file tmpfile_stdout 18:56:21 INFO - Detecting whether we're running mozinstall >=1.0... 18:56:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:56:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:56:21 INFO - Reading from file tmpfile_stdout 18:56:21 INFO - Output received: 18:56:21 INFO - Usage: mozinstall [options] installer 18:56:21 INFO - Options: 18:56:21 INFO - -h, --help show this help message and exit 18:56:21 INFO - -d DEST, --destination=DEST 18:56:21 INFO - Directory to install application into. [default: 18:56:21 INFO - "/builds/slave/test"] 18:56:21 INFO - --app=APP Application being installed. [default: firefox] 18:56:21 INFO - mkdir: /builds/slave/test/build/application 18:56:21 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'] 18:56:21 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 18:56:55 INFO - Reading from file tmpfile_stdout 18:56:55 INFO - Output received: 18:56:55 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:56:55 INFO - Running post-action listener: _resource_record_post_action 18:56:55 INFO - [mozharness: 2016-09-13 01:56:55.921120Z] Finished install step (success) 18:56:55 INFO - [mozharness: 2016-09-13 01:56:55.921256Z] Running run-tests step. 18:56:55 INFO - Running pre-action listener: _resource_record_pre_action 18:56:55 INFO - Running main action method: run_tests 18:56:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:56:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:56:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:56:55 INFO - 2016-09-12 18:56:55.935 screenresolution[1636:7566] starting screenresolution argv=screenresolution get 18:56:55 INFO - 2016-09-12 18:56:55.938 screenresolution[1636:7566] Display 0: 1600x1200x32@60 18:56:55 INFO - 2016-09-12 18:56:55.944 screenresolution[1637:7568] starting screenresolution argv=screenresolution list 18:56:55 INFO - Available Modes on Display 0 18:56:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:56:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:56:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:56:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:56:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:56:56 INFO - Intel Iris: 18:56:56 INFO - Chipset Model: Intel Iris 18:56:56 INFO - Type: GPU 18:56:56 INFO - Bus: Built-In 18:56:56 INFO - VRAM (Dynamic, Max): 1536 MB 18:56:56 INFO - Vendor: Intel (0x8086) 18:56:56 INFO - Device ID: 0x0a2e 18:56:56 INFO - Revision ID: 0x0009 18:56:56 INFO - Displays: 18:56:56 INFO - Display: 18:56:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:56:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:56:56 INFO - Display Serial Number: 1600x1200 60 18:56:56 INFO - Main Display: Yes 18:56:56 INFO - Mirror: Off 18:56:56 INFO - Online: Yes 18:56:56 INFO - Rotation: Supported 18:56:56 INFO - Return code: 0 18:56:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:56:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:56:56 INFO - Python 2.7.3 18:56:56 INFO - Return code: 0 18:56:56 INFO - grabbing minidump binary from tooltool 18:56:56 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')]} 18:56:56 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 0x107729d30>, '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 0x10754fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107721b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:56:56 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 18:56:56 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 18:56:56 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 18:56:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:56:56 INFO - Return code: 0 18:56:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:56:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:56:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:56:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:56:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:56:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--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 18:56:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0133 --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 18:56:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4KYXBdqLjj/Render', 18:56:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:56 INFO - 'HOME': '/Users/cltbld', 18:56:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:56 INFO - 'LOGNAME': 'cltbld', 18:56:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:56:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:56:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:56 INFO - 'MOZ_NO_REMOTE': '1', 18:56:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:56:56 INFO - 'NO_EM_RESTART': '1', 18:56:56 INFO - 'PAGER': '/bin/cat', 18:56:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:56 INFO - 'PWD': '/builds/slave/test', 18:56:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:56:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:56 INFO - 'SHELL': '/bin/bash', 18:56:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YzuoQM1f1Q/Listeners', 18:56:56 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:56:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:56 INFO - 'USER': 'cltbld', 18:56:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:56 INFO - 'XPC_FLAGS': '0x0', 18:56:56 INFO - 'XPC_SERVICE_NAME': '0', 18:56:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--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 18:56:56 INFO - mozversion application_buildid: 20160912165308 18:56:56 INFO - mozversion application_changeset: 7b1cb8b0be7caa5e785159bb7c3650079d244303 18:56:56 INFO - mozversion application_display_name: Nightly 18:56:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:56:56 INFO - mozversion application_name: Firefox 18:56:56 INFO - mozversion application_remotingname: firefox 18:56:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:56:56 INFO - mozversion application_vendor: Mozilla 18:56:56 INFO - mozversion application_version: 51.0a1 18:56:56 INFO - mozversion platform_buildid: 20160912165308 18:56:56 INFO - mozversion platform_changeset: 7b1cb8b0be7caa5e785159bb7c3650079d244303 18:56:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:56:56 INFO - mozversion platform_version: 51.0a1 18:56:56 INFO - using testdate: 1473731816 18:56:56 INFO - actual date: 1473731816 18:56:56 INFO - starting webserver on 'localhost:49273' 18:56:56 INFO - SUITE-START | Running 2 tests 18:56:56 INFO - TEST-START | dromaeo_css 18:56:56 INFO - operating with platform_type : mac_ 18:56:56 INFO - Initialising browser for dromaeo_css test... 18:56:56 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpRJNg77/profile http://localhost:49273/getInfo.html) 18:56:59 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 18:56:59 INFO - PROCESS | 1647 | colorDepth:24 18:56:59 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 18:56:59 INFO - PROCESS | 1647 | __metrics 18:56:59 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 18:57:00 INFO - TEST-INFO | 1647: exit 0 18:57:00 INFO - Browser initialized. 18:57:00 INFO - Running cycle 1/1 for dromaeo_css test... 18:57:00 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpRJNg77/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:57:12 INFO - PROCESS | 1650 | RSS: Main: 244166656 18:57:12 INFO - PROCESS | 1650 | 18:58:02 INFO - PROCESS | 1650 | 1473731882069 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] 19:00:14 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 19:00:15 INFO - PROCESS | 1650 | RSS: Main: 262725632 19:00:15 INFO - PROCESS | 1650 | 19:03:17 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 19:03:18 INFO - PROCESS | 1650 | RSS: Main: 267460608 19:03:18 INFO - PROCESS | 1650 | 19:06:20 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 19:06:20 INFO - PROCESS | 1650 | RSS: Main: 271163392 19:06:20 INFO - PROCESS | 1650 | 19:09:22 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 19:09:23 INFO - PROCESS | 1650 | RSS: Main: 269647872 19:09:23 INFO - PROCESS | 1650 | 19:12:26 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 19:12:26 INFO - PROCESS | 1650 | RSS: Main: 275361792 19:12:26 INFO - PROCESS | 1650 | 19:15:29 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 19:15:29 INFO - PROCESS | 1650 | RSS: Main: 278118400 19:15:29 INFO - PROCESS | 1650 | 19:15:29 INFO - PROCESS | 1650 | __start_tp_report 19:15:29 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 19:15:29 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 19:15:29 INFO - PROCESS | 1650 | |i|pagename|runs| 19:15:29 INFO - PROCESS | 1650 | |0;dojo.html;3378.9662103378964;3385.0691059958494;3408.6420925802804;3408.948865767014;3416.7608267421274;4543.341215523747;4860.8055677772845;5687.289088863892;5708.172315014325;5772.653640781556;5071.79712811488;5093.185090385539;5612.35457922338;5674.432556744324;5783.971080144604;5196.246544251081;5227.581793456515;5288.524032837044;5293.41772405037;5295.417504074549;5177.249298851664;5328.120860058086;5338.092524270884;5360.16917377807;5384.757685904144;5158.871028224287;5232.372115346145;5263.789448422059;5313.840584782462;5351.973240133813;5233.816816411445;5254.290670759437;5272.6836389816735;5294.576433885319;5317.601179911522;5222.529972302511;5238.528532432061;5249.842504724865;5262.947410517873;5289.391719952217;5560.887822435529;5573.554115670737;5669.092945128801;5774.653520788766;5794.246747922785;6243.125962365227;6261.843453913643;6292.874142517124;6300.527460440439;6306.180196574462;6266.968665156691;6283.7172327244925;6303.117563541108;6333.904991425133;6338.429541341256;5674.602777805555;5759.395263497357;5856.472917437451;5870.943230218516;5899.439553242435;5282.207668849626;6293.748250070037;6294.874102517924;6301.306856245809;6328.841778955563;6115.235595550556;6202.72087756052;6243.937560624427;6252.187215662026;6289.528285378615;6080.14877917092;6138.878900475427;6218.875622487525;6299.6535190564955;6320.55756097078;4720.95279047212;6205.5345849061505;6209.96895015522;6225.346338634468;6241.032639940817;6096.72564734588;6195.628262304276;6221.564490485623;6226.62640241587;6265;4939.604831613462;4983.526564976321;4984.327115839388;4985.052839960396;5009.423916249605;4461.821527138943;4482.327650852399;4482.887927801831;4584.701994370355;4600.125976064537;5792.88414231713;5808.07070868659;5882.852928676817;5907.793227237112;5980.760769569169;5228.581713462914;6209.813705588839;6235.127082208494;6267.71795269214;6309.495240380758;5802.65184088956;5857.355690874;5883.470487656046;5894.528437724972;5967.045272756338;5455.372632147274;5464.890702186013;5472.835814925558;5483.424240454775;5487.5884308677005;4806.615470762261;5368.275282836845;6332;6339.302676705651;6358.682065896686;6159.507239420746;6230.657313847782;6249.843753906189;6296.275928268308;6302.338254483397;5602.187682785891;6054.3642917493025;6259.186305780219;6321.96839015802;6335.936640633719;5811.186433899173;6154.476869466145;6221.9377806221355;6247.094171345038;6247.31279559239;6222.968885155546;6298.716557755004;6312.400321969406;6314.179156709598;6325.557210995186;5418.83743487696;6019.277686677451;6119.571629985859;6134.478569321479;6145.446909778141;6045;6066.757329706762;6216.813495595139;6220.657863817352;6255.093011513396;4736.692115012514;6018.3379828217985;6045.21412216409;6052.0921861719335;6106.9083963739695;6156;6169.537284703575;6171.413715697002;6191.380861913773;6203.379662033761;5892.852678683067;6051.425114614105;6081.847953801013;6091.482224011009;6102.328743838263 19:15:29 INFO - PROCESS | 1650 | |1;ext.html;516.795865633075;530.8513616187466;534.9251104845322;542.6894050867156;556.2185129892501;32183.19542011451;39278.41082383763;39713;41106.97232569187;41742;40148.99627509314;40682;40689.593104068954;40689.982750431256;40754.18491630173;9647.565859536302;9833.459159746211;9848.704538863809;9854.704358869245;9901.207903367713;9686.612535498572;9735.221182305399;9759.024097590254;9760.4631745254;9789.167920726712;9707.369021013646;9779.217662586976;9787.804243915118;9797.853032204523;9800.95099524505;9723;9766.658166964122;9794.314397992144;9794.608215671436;9817.214622830157;9599.712008639752;9685.951570242174;9687.225021998294;9706.126448619658;9710.028997100304;38808;39160.216795664215;39269.607303927165;39295;39314.017149571206;3175.8094514329214;3221.0336898930464;3289.9177520562175;3489.45913383426;3801.1067399161175;29855;30302.84848575765;30617.540736888743;31340.373192536248;31342.529862052088;3207.461442059506;3233.983830080858;3240.4653232216656;3246.091094493522;3305.0415379539904;418222;462260;499688;500230;501547;55133.724331378085;55300.72349638306;55417;55569;55572;33125.66874331274;33923.83038084794;57447.425525744206;58091;58198.709006454694;15902.443414480438;16232.918835405984;16382.672346553003;16382.918085409498;16391.672166556604;11152.330860148255;12618.369081545887;12626.494940202472;12681.365931703378;12706.364681765876;17855.642887142185;17881.28474861017;17977.460676179737;18022.36921707734;19317.22731090769;6539.6403197824575;7461.962690186514;7702.114256860533;7813.648385822652;7826.373890088779;825.9256666900008;843.5402705525503;901.6258252825136;977.413551868873;1016.1312078173056;28822.855885720855;29918.85040574783;30751.538726919112;30804.383912322075;30916.53625195624;985.3348989432247;1026.594495174402;1060.5757696921294;1079.2768844873954;1085.2240647936762;13890.097143685629;14608.415663373346;14685.632859178606;14953.626159346104;15105.244737763069;573943;575765;579011;580837.0958145267;587692;51555;51751.74124129355;51796.223056654184;51838.48161518412;52025.7398713004;1285.4279845468864;1309.1490531154636;1334.9199048057149;1339.8727120923497;1340.544214966916;23302.184423545772;26786;27080.729192707822;28560.714392855807;28940.56589151122;50088;52210.694732631986;52420.47579524156;52443.213351800514;52513.737431314126;630826;637249;637524;637829;638793;25654;26321.210363688355;40065.1986960265;40492;40592;43073;43641.78179109084;44380.11239775122;44678.88302792456;44692;32819.83590082034;36285.45571816372;37335;37555.62444375631;37593.2481350366;36990.815045924595;36993.07517311981;37123.07192320214;37253.441198382585;37615.81192094022;31616.841915790275;33054.33891322112;36502;36571;36755.264894701424;30312.393752125274;33193.336133277684;33588.496172556945;33724.662753372155;33980.830095849364;24205.394865128514;24838.006479741332;25083.24750257495;25124.62313065269;25218.11736589284 19:15:29 INFO - PROCESS | 1650 | |2;jquery.html;361.82451511017166;362.34234863722327;368.24142266930136;370.2945888083201;385.8982604663685;15515.534533963984;19654.115564799584;19668.311609093686;20343.59312813743;20588.69116963243;15799;19176.328828491005;19605.705914411294;19629;19996;5707.686077265748;5789.536837053026;6006.639601623895;6045.6070355426755;6066.181065556138;5010.749462526877;5211.869703257411;5754.65472071675;5846.912296315559;5913.231279933602;5697.8575535611735;5748.913766293509;5753.712314384285;5879.324392548125;5945.37573554777;5774.624649397776;5850.73671684773;5874.295084589855;5885.440883116118;5885.764569417218;4332.198543269474;5010.298558201818;5057.848264552033;5057.9494205057845;5137.768800403991;25280.747192528022;25328;25384.492310153695;25551.85016674254;25580.848861801263;2195.4072400451946;2203.5152266501345;2364.3616223619697;2430.4895971845935;2995.7603391728608;12199.93900030503;14785.556433307018;15181.544553663407;15249;15297.235138243042;1646.7859178306862;1857.7120546315343;1875.7373967644542;1899.335232668555;1997.2111016148685;119953.40023299915;121806;122637.38681306626;122657.38671306586;123361.9999999991;10326.122279606167;10468.47657617116;10871.945640271906;10963.945180274048;11037.889621103846;13013.804792928113;13294.734105317839;13313.866861331457;13324.933375333061;13336.133151345326;8254.42219044673;8423.747287581382;8594.914050859412;8626.482411055356;8699.478031318142;8651.6971905983;8675.826483470295;8706.91293087061;8745.463815493436;8864.468131912106;5462.8361149165585;6373.5857169283845;6429.38920802523;6485.3514648534765;6517.185351831021;1708.7693161423297;1717.8196289389684;1830.1215416600107;1858.9535261618569;1862.7392165096896;753.4989232160661;867.3841572483607;929.9953500232456;957.9089986451276;982.9950850245702;19083.713744293847;21872.01575929087;21905.452363691034;22099.801037802765;22156.33530994073;737.3290305821677;769.7767647382196;843.3969711656073;947.9383840050375;949.9810003799886;8577.871331930026;10059.949700251453;10675.83986240207;11547.422628868522;11554.537818487335;585700;586323;591737;604886;605647;110118.89881101086;114490;125926;131170.34414828056;139689;923.9214666753267;962.2206013129388;967.0474582536197;967.7387105481481;996.8454889492141;18458.446246612624;18553;18793.812061879205;19086.7136992948;19115.904420477807;54976.450235498734;57778.66440576597;58111.4188858106;58765.11852322304;58770.82458350895;65236;70049;73164.5860726002;84268;85193;7665.118511371264;7769.7669069928;7931.960340198262;8029.678812847421;8194.959025204837;10943.343399396063;12322.445489953154;12399.13206075566;12492.500299988262;12673.93663031679;10552.683419497427;10680.89319106799;10842.728931776768;11002.064824490008;11135.944320278346;9089.59096840657;10818.56725730962;10905.454727263446;10947;11109.666709998713;9027.638894444412;10836.403997780199;10857.511411986321;11070.723231919266;11139.387333696724;6473.93180125274;7317.414606831547;7784.023418668309;8177;8183.427160098736;8119.147489513516;9648.75878103053;10653.946730266609;11031.282966607145;11222.775544489228 19:15:29 INFO - PROCESS | 1650 | |3;mootools.html;1486.2536519589482;1496.603400098974;1679.7480377943307;1794.3899074314747;1832.8991905445203;7951.602419878997;8216.548089855056;8444.704435344764;9023.819523609523;9054.683086091987;8471.407001509897;8729.738107856758;8758.91241087589;8817.867731984026;8842.513661748617;2357.3163782503098;2399.568077746005;2404.302752201859;2407.109369533275;2414.9396265093424;2353.8116950643907;2387.2957477544164;2388.3790214544256;2392.760723927602;2397.7482364351754;2378.52429514097;2383.1778036577352;2387;2400.7959323457526;2407.4462873539096;1940.8544359173118;2347.307544274443;2358.882055897207;2374.3114496795993;2382.321038504035;2353.376355265856;2363.8936247868714;2365.7989070928993;2378.5718570657273;2379;7088.362047415757;8222.643263861455;8663.653453861838;8828.293736501064;8982.505962172077;4714.57568818808;5363;5366.248725178478;5375.65058271215;5384.7846086156505;3541.34485120251;3565.7682250653884;3606.5672119345586;3613.1689711366535;3639.6178401267753;1070.86078809755;1087.0868470484752;1088.9564417423294;1090.6837017264984;1097.259349938788;11702.297862128215;11750.353730544817;11751.882481175164;11811.93692567673;11828.940855295754;4545.863624091283;4647.767611619473;4778.832740854054;4830.420349558005;4855.077535268288;9913.504324783731;9992.600295988224;9998.05018523239;10008.849867251996;10024.097831195228;5342.038433082005;5346.545543628835;5347.786088556414;5348.598855085884;5353.491418315254;4370.431843860278;4518.480374756945;4714.4106986626675;4836.661433699607;4896.6572339935865;3353.3796247693767;3812.390017597171;3880.534335879656;3896.1623251000606;3952.782596957195;1294.50161687749;1297.344840855362;1298.6753311672082;1299.9480020799062;1318.3605951113702;806.7216810200472;861.6682577207695;892.901780804111;904.909583951337;955.3933252384646;5512;5716.714164291769;6007.8197654070445;6017.729202185912;6038.667873266924;919.4391421233042;931.2131249094505;943.9811203776023;952.4808979106404;976.345848281653;3257;3901.45379646844;3952.7825969571372;3981.243563722883;4071.145059537471;10515.579376824995;10922.071623912056;10973.780524389467;10976.286541374786;11015.118790496741;8949.105089490999;9902.455364954996;10916.962888525577;10924.617638382777;10933.671989840317;6242.875142497124;7741.535507869546;8191.8771218430575;8194.631241594143;8307.08622051574;5292.179712144623;5646.322441307069;5944.1975333331175;6176.81469555914;6191.628502289877;8033.799155021171;10048.899511004796;10130.392176469435;10211.897881021094;10299.742506437398;10174.389536627827;10273;10358.948205259227;10408.583656653953;10478.318909270873;7560.034391058474;7574.318311351784;7586;7620.047494063242;7684.193159718149;8340.207680270365;8394.958025209835;8399.370047246359;8406.201410865959;8408.075111737826;7364.226756190737;7466.925330746623;7500.062492188476;7510.324070833652;7628.923710763043;7278.745243916437;7377.557346559223;7400.629968501661;7419.888701669372;7600.733974310872;6833.350831670983;6922.7923162305215;6947.3747362737595;6966.512344135862;7004.914238293073;4789.161896668139;4919.1883339248625;5025.547700706954;5106.642535022661;5125.948740512547;5026;5058.494150584912;5206.271122042993;5222.712750798591;5250.264962905273 19:15:29 INFO - PROCESS | 1650 | |4;prototype.html;265.271828829862;297.5165356296019;308.27093922872405;310.56210742852585;311.95008798592204;5336.439673834249;5343.064963631368;5522.696251706155;5615.522680572151;5662.6036177467595;5337.678110022143;5488.588355873316;5550.69471179085;5553.694546799924;5592.832215033545;3798.5309548793366;4259.424977628019;4271.295236286009;4275;4304.160688665712;4124.505059392862;4248.746619747167;4252.744835309876;4259.847712692616;4285.335772955182;3631.6186800385976;4207.347861081537;4212.6409210841875;4226.260404429228;4244.97877510612;3473.548438702965;4017.718759686823;4253.404523366731;4271.551487093841;4274.572542745732;3697.2975134724315;3703.0742314421395;3705.6109108543483;3719.5722491913507;3724.2737666155112;3987.0264219211385;4778.522147785228;5422.267993820823;5439.782408703647;5464.23500709901;4476.552344765498;4558.082826990729;4764;4788.760561971888;4789.664723469358;4562.726236425825;4835.443923948756;5838.474537291664;5881.85295367615;5899.410058994109;3217.3404452087266;3526.2947410518;3538.2141109013364;3931.5478719947;3935.3900145477496;5725.398833122504;6174.413430724074;6193.6903154842075;6194.380561943814;6212.161358216628;3970.563238043813;3987.003249187703;3994.940075898864;4002.9192118127944;4025.637692607621;3519.630438803889;3876.980615096963;3903.590123037097;4026.516817981861;4038.3750562134846;3986.9202615948097;3996.8800935971967;4291.2405913575285;4689.8812289827565;4728.267118596623;4660.813567457331;4877.707337559757;5096.23556466533;5494.81861399801;5888.409099995988;1256.7674980128706;1297.5912587534897;1355.6814148675053;1371.2526672963259;1378.400395827818;800.5636927874323;805.2792750488397;814.3810703865105;839.345310657688;840.5083026429608;4331;4352;4384.583464570861;4416.558344165558;4580.129775342674;3956.485656864589;4757.452892917289;4983.8006479741125;5089.277236678359;5108.463611320758;4196.412502249687;4436.889077773081;4573.748443835554;4588.747618880927;4613.861584152481;2450.5051918329805;2511.460036092249;2546.3888666720222;2561.0652111978993;2689.9596506052426;4287.442632457761;4555.908881822411;4713.316569097462;4748.169070412664;5275.393329767049;4623.722576645345;4907.460179380265;5201.401838788512;5224.9738751306;5226.099514769339;4533.738286011139;4545.2954792007295;5110.892483482203;5141.734998276122;5228.764705588258;5047.747612619428;5074.16276314404;5082.898342033212;5091.363579552556;5095.0574143783515;3975.5229372475487;4168.812403441913;4415.094905544292;4699.436067671902;4894.828680996148;3714.8514059438403;4667;5138.047541934041;5241.31862857826;5281.920771188512;3088.2279430142466;3274.639789623092;3438.948415773715;3441.8623255069515;3469.548958635361;5093.268288781684;5109.3613298337705;5117.385913690381;5119.308893299357;5139.637995931156;5156.716380598953;5426.864328391822;5455.618106732491;5614.7192640367;5933.851653708692;5904.911426328695;5909.881802364014;5915.615484993462;5960.5827592068135;5968.537708261504;5191.870203244798;5580.5535557156145;5638.36487418644;5670.347909990404;5870.475695450997;5056.7471626419265;5192.714400707846;5304.628675992798;5895.233619629421;5956.910646340395;4984.327115839315;5076.578558003765;5122.538971492583;5145.945081258258;5458.945410546003 19:15:29 INFO - PROCESS | 1650 | |5;yui.html;632.029834204496;666;681.437813803612;739.0466298474964;773.0838955837331;15081.547553573397;15431.759330540572;15477.226138693048;15502.534923952271;15575.532734017983;10286.074253317218;10863.619773307935;13117;14130.152190868555;14237.501687440941;583.0176153181902;659.9241087274977;796.760971708488;820.3437250199831;828.0891019878146;828.9295409890136;832.2551316571664;833.6040380819123;835.4402550291319;838.1409055717877;750.9812254693649;859.0593300336122;894.1416240409214;894.311380237217;895.1853813030144;850.8468475674376;853.7780177153923;891.8260939116875;894.5706061090648;894.9931327256836;562.7777028073888;651.4592887903029;660.9867802643969;662.7183447035039;665.2117241069349;14530.636734081627;14695.044822086531;14800.40798368064;14919.552413427615;14927.626809329746;11559.94220028903;12477.75044499114;13773.931130344385;14721.631959200999;14756.262186890719;11729.53081876724;11817.290962542189;11866.406679666068;11947.223430477077;11973.94013029938;3581.9641803582153;3713.1830997180577;3737.869654032282;3764.3976963685955;3893.10458594522;124192.51614967741;124277;124733.51598904094;128215;129261.70738292685;10903.400312982862;10962.80743851217;11008.944955275332;11177.44112794357;11296.491657875415;23235;23245.3026409208;23290.7670923292;23319.883400582887;23397;7914.2588630501195;7965.402594805297;8091.150429204967;8143.633536490872;8143.75568732939;4417.624501917308;4523.5024147343765;4581.656375771764;4697.765111744399;4703.506131856174;3601.369760291991;3667.7982710951155;3720.200156966211;3722.2555488902326;3724.566042073821;571.9370869204384;582.19366177844;582.7814569536424;587;588.4791959116213;606.169547719627;630.7540059376847;659.4131223211346;660.8149718078946;669.7521916890781;7921.128675845653;9154.130357616015;9201.813175005356;9306.348555601044;9510.857337139949;573.068763259703;646.3633321178635;649.331188875459;661.3882159002903;667.5827607745159;3567.554055743032;4289.420928174719;4439.4006809080365;4605.125026245003;4711.95288047122;106108;149997;162360;165688;175001;117784.41107794407;125969;136420.31789840988;142958;145557;121308;122197.38901305436;122704.77295226934;125335;128181;7368.926310736824;8845.601947912488;8919.464832109834;8930.310612984984;8982;51118.48881511286;65636.0154597672;66201;66261.00608490974;68342.65828670825;69916;86627;87311.56344218238;91224;99495;13058.869411306145;13076.542321018718;13087.149335293176;13190.406431710404;13640.590782276548;14361.928190358982;14706.41174353014;14724.337404816595;15002.681178129687;15114.697706046038;13488.460461581819;13719.382627781973;13802.65493362674;13963.650908726957;14228.800223102862;13458.730825383633;13619.908155877383;13621.79567306511;14001.929990349983;14004;12897.935510322388;13415.932920335335;13419.060665753304;13500.054996150175;13696.00956508751;11121.201744852498;11297.71755706114;11458.026067784333;11464.140189485988;11937.28376297425;8183.315860416212;9442.244620430487;11440.427978601203;11576.94211528937;11718.589849355232 19:15:29 INFO - PROCESS | 1650 | __end_tp_report 19:15:29 INFO - PROCESS | 1650 | __start_cc_report 19:15:29 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,379 19:15:29 INFO - PROCESS | 1650 | __end_cc_report 19:15:29 INFO - PROCESS | 1650 | __startTimestamp1473732929949__endTimestamp 19:15:29 INFO - PROCESS | 1650 | 19:15:29 INFO - PROCESS | 1650 | ------- Summary: start ------- 19:15:29 INFO - PROCESS | 1650 | Number of tests: 6 19:15:29 INFO - PROCESS | 1650 | 19:15:29 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5721.70 Median:5907.79 stddev:651.50 (11.0%) stddev-sans-first:627.04 19:15:29 INFO - PROCESS | 1650 | Values: 3379.0 3385.1 3408.6 3408.9 3416.8 4543.3 4860.8 5687.3 5708.2 5772.7 5071.8 5093.2 5612.4 5674.4 5784.0 5196.2 5227.6 5288.5 5293.4 5295.4 5177.2 5328.1 5338.1 5360.2 5384.8 5158.9 5232.4 5263.8 5313.8 5352.0 5233.8 5254.3 5272.7 5294.6 5317.6 5222.5 5238.5 5249.8 5262.9 5289.4 5560.9 5573.6 5669.1 5774.7 5794.2 6243.1 6261.8 6292.9 6300.5 6306.2 6267.0 6283.7 6303.1 6333.9 6338.4 5674.6 5759.4 5856.5 5870.9 5899.4 5282.2 6293.7 6294.9 6301.3 6328.8 6115.2 6202.7 6243.9 6252.2 6289.5 6080.1 6138.9 6218.9 6299.7 6320.6 4721.0 6205.5 6210.0 6225.3 6241.0 6096.7 6195.6 6221.6 6226.6 6265.0 4939.6 4983.5 4984.3 4985.1 5009.4 4461.8 4482.3 4482.9 4584.7 4600.1 5792.9 5808.1 5882.9 5907.8 5980.8 5228.6 6209.8 6235.1 6267.7 6309.5 5802.7 5857.4 5883.5 5894.5 5967.0 5455.4 5464.9 5472.8 5483.4 5487.6 4806.6 5368.3 6332.0 6339.3 6358.7 6159.5 6230.7 6249.8 6296.3 6302.3 5602.2 6054.4 6259.2 6322.0 6335.9 5811.2 6154.5 6221.9 6247.1 6247.3 6223.0 6298.7 6312.4 6314.2 6325.6 5418.8 6019.3 6119.6 6134.5 6145.4 6045.0 6066.8 6216.8 6220.7 6255.1 4736.7 6018.3 6045.2 6052.1 6106.9 6156.0 6169.5 6171.4 6191.4 6203.4 5892.9 6051.4 6081.8 6091.5 6102.3 19:15:29 INFO - PROCESS | 1650 | 19:15:29 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:68866.31 Median:39513.51 stddev:151959.68 (384.6%) stddev-sans-first:152299.32 19:15:29 INFO - PROCESS | 1650 | Values: 516.8 530.9 534.9 542.7 556.2 32183.2 39278.4 39713.0 41107.0 41742.0 40149.0 40682.0 40689.6 40690.0 40754.2 9647.6 9833.5 9848.7 9854.7 9901.2 9686.6 9735.2 9759.0 9760.5 9789.2 9707.4 9779.2 9787.8 9797.9 9801.0 9723.0 9766.7 9794.3 9794.6 9817.2 9599.7 9686.0 9687.2 9706.1 9710.0 38808.0 39160.2 39269.6 39295.0 39314.0 3175.8 3221.0 3289.9 3489.5 3801.1 29855.0 30302.8 30617.5 31340.4 31342.5 3207.5 3234.0 3240.5 3246.1 3305.0 418222.0 462260.0 499688.0 500230.0 501547.0 55133.7 55300.7 55417.0 55569.0 55572.0 33125.7 33923.8 57447.4 58091.0 58198.7 15902.4 16232.9 16382.7 16382.9 16391.7 11152.3 12618.4 12626.5 12681.4 12706.4 17855.6 17881.3 17977.5 18022.4 19317.2 6539.6 7462.0 7702.1 7813.6 7826.4 825.9 843.5 901.6 977.4 1016.1 28822.9 29918.9 30751.5 30804.4 30916.5 985.3 1026.6 1060.6 1079.3 1085.2 13890.1 14608.4 14685.6 14953.6 15105.2 573943.0 575765.0 579011.0 580837.1 587692.0 51555.0 51751.7 51796.2 51838.5 52025.7 1285.4 1309.1 1334.9 1339.9 1340.5 23302.2 26786.0 27080.7 28560.7 28940.6 50088.0 52210.7 52420.5 52443.2 52513.7 630826.0 637249.0 637524.0 637829.0 638793.0 25654.0 26321.2 40065.2 40492.0 40592.0 43073.0 43641.8 44380.1 44678.9 44692.0 32819.8 36285.5 37335.0 37555.6 37593.2 36990.8 36993.1 37123.1 37253.4 37615.8 31616.8 33054.3 36502.0 36571.0 36755.3 30312.4 33193.3 33588.5 33724.7 33980.8 24205.4 24838.0 25083.2 25124.6 25218.1 19:15:29 INFO - PROCESS | 1650 | 19:15:29 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:34935.46 Median:362.08 stddev:99362.24 (27441.8%) stddev-sans-first:99607.07 19:15:29 INFO - PROCESS | 1650 | Values: 361.8 362.3 368.2 370.3 385.9 15515.5 19654.1 19668.3 20343.6 20588.7 15799.0 19176.3 19605.7 19629.0 19996.0 5707.7 5789.5 6006.6 6045.6 6066.2 5010.7 5211.9 5754.7 5846.9 5913.2 5697.9 5748.9 5753.7 5879.3 5945.4 5774.6 5850.7 5874.3 5885.4 5885.8 4332.2 5010.3 5057.8 5057.9 5137.8 25280.7 25328.0 25384.5 25551.9 25580.8 2195.4 2203.5 2364.4 2430.5 2995.8 12199.9 14785.6 15181.5 15249.0 15297.2 1646.8 1857.7 1875.7 1899.3 1997.2 119953.4 121806.0 122637.4 122657.4 123362.0 10326.1 10468.5 10871.9 10963.9 11037.9 13013.8 13294.7 13313.9 13324.9 13336.1 8254.4 8423.7 8594.9 8626.5 8699.5 8651.7 8675.8 8706.9 8745.5 8864.5 5462.8 6373.6 6429.4 6485.4 6517.2 1708.8 1717.8 1830.1 1859.0 1862.7 753.5 867.4 930.0 957.9 983.0 19083.7 21872.0 21905.5 22099.8 22156.3 737.3 769.8 843.4 947.9 950.0 8577.9 10059.9 10675.8 11547.4 11554.5 585700.0 586323.0 591737.0 604886.0 605647.0 110118.9 114490.0 125926.0 131170.3 139689.0 923.9 962.2 967.0 967.7 996.8 18458.4 18553.0 18793.8 19086.7 19115.9 54976.5 57778.7 58111.4 58765.1 58770.8 65236.0 70049.0 73164.6 84268.0 85193.0 7665.1 7769.8 7932.0 8029.7 8195.0 10943.3 12322.4 12399.1 12492.5 12673.9 10552.7 10680.9 10842.7 11002.1 11135.9 9089.6 10818.6 10905.5 10947.0 11109.7 9027.6 10836.4 10857.5 11070.7 11139.4 6473.9 7317.4 7784.0 8177.0 8183.4 8119.1 9648.8 10653.9 11031.3 11222.8 19:15:29 INFO - PROCESS | 1650 | 19:15:29 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5694.01 Median:4907.92 stddev:3191.75 (65.0%) stddev-sans-first:3185.04 19:15:29 INFO - PROCESS | 1650 | Values: 1486.3 1496.6 1679.7 1794.4 1832.9 7951.6 8216.5 8444.7 9023.8 9054.7 8471.4 8729.7 8758.9 8817.9 8842.5 2357.3 2399.6 2404.3 2407.1 2414.9 2353.8 2387.3 2388.4 2392.8 2397.7 2378.5 2383.2 2387.0 2400.8 2407.4 1940.9 2347.3 2358.9 2374.3 2382.3 2353.4 2363.9 2365.8 2378.6 2379.0 7088.4 8222.6 8663.7 8828.3 8982.5 4714.6 5363.0 5366.2 5375.7 5384.8 3541.3 3565.8 3606.6 3613.2 3639.6 1070.9 1087.1 1089.0 1090.7 1097.3 11702.3 11750.4 11751.9 11811.9 11828.9 4545.9 4647.8 4778.8 4830.4 4855.1 9913.5 9992.6 9998.1 10008.8 10024.1 5342.0 5346.5 5347.8 5348.6 5353.5 4370.4 4518.5 4714.4 4836.7 4896.7 3353.4 3812.4 3880.5 3896.2 3952.8 1294.5 1297.3 1298.7 1299.9 1318.4 806.7 861.7 892.9 904.9 955.4 5512.0 5716.7 6007.8 6017.7 6038.7 919.4 931.2 944.0 952.5 976.3 3257.0 3901.5 3952.8 3981.2 4071.1 10515.6 10922.1 10973.8 10976.3 11015.1 8949.1 9902.5 10917.0 10924.6 10933.7 6242.9 7741.5 8191.9 8194.6 8307.1 5292.2 5646.3 5944.2 6176.8 6191.6 8033.8 10048.9 10130.4 10211.9 10299.7 10174.4 10273.0 10358.9 10408.6 10478.3 7560.0 7574.3 7586.0 7620.0 7684.2 8340.2 8395.0 8399.4 8406.2 8408.1 7364.2 7466.9 7500.1 7510.3 7628.9 7278.7 7377.6 7400.6 7419.9 7600.7 6833.4 6922.8 6947.4 6966.5 7004.9 4789.2 4919.2 5025.5 5106.6 5125.9 5026.0 5058.5 5206.3 5222.7 5250.3 19:15:29 INFO - PROCESS | 1650 | 19:15:29 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4370.11 Median:4706.38 stddev:1338.85 (28.4%) stddev-sans-first:1306.67 19:15:29 INFO - PROCESS | 1650 | Values: 265.3 297.5 308.3 310.6 312.0 5336.4 5343.1 5522.7 5615.5 5662.6 5337.7 5488.6 5550.7 5553.7 5592.8 3798.5 4259.4 4271.3 4275.0 4304.2 4124.5 4248.7 4252.7 4259.8 4285.3 3631.6 4207.3 4212.6 4226.3 4245.0 3473.5 4017.7 4253.4 4271.6 4274.6 3697.3 3703.1 3705.6 3719.6 3724.3 3987.0 4778.5 5422.3 5439.8 5464.2 4476.6 4558.1 4764.0 4788.8 4789.7 4562.7 4835.4 5838.5 5881.9 5899.4 3217.3 3526.3 3538.2 3931.5 3935.4 5725.4 6174.4 6193.7 6194.4 6212.2 3970.6 3987.0 3994.9 4002.9 4025.6 3519.6 3877.0 3903.6 4026.5 4038.4 3986.9 3996.9 4291.2 4689.9 4728.3 4660.8 4877.7 5096.2 5494.8 5888.4 1256.8 1297.6 1355.7 1371.3 1378.4 800.6 805.3 814.4 839.3 840.5 4331.0 4352.0 4384.6 4416.6 4580.1 3956.5 4757.5 4983.8 5089.3 5108.5 4196.4 4436.9 4573.7 4588.7 4613.9 2450.5 2511.5 2546.4 2561.1 2690.0 4287.4 4555.9 4713.3 4748.2 5275.4 4623.7 4907.5 5201.4 5225.0 5226.1 4533.7 4545.3 5110.9 5141.7 5228.8 5047.7 5074.2 5082.9 5091.4 5095.1 3975.5 4168.8 4415.1 4699.4 4894.8 3714.9 4667.0 5138.0 5241.3 5281.9 3088.2 3274.6 3438.9 3441.9 3469.5 5093.3 5109.4 5117.4 5119.3 5139.6 5156.7 5426.9 5455.6 5614.7 5933.9 5904.9 5909.9 5915.6 5960.6 5968.5 5191.9 5580.6 5638.4 5670.3 5870.5 5056.7 5192.7 5304.6 5895.2 5956.9 4984.3 5076.6 5122.5 5145.9 5458.9 19:15:29 INFO - PROCESS | 1650 | 19:15:29 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:25774.95 Median:3634.58 stddev:42220.34 (1161.6%) stddev-sans-first:42296.58 19:15:29 INFO - PROCESS | 1650 | Values: 632.0 666.0 681.4 739.0 773.1 15081.5 15431.8 15477.2 15502.5 15575.5 10286.1 10863.6 13117.0 14130.2 14237.5 583.0 659.9 796.8 820.3 828.1 828.9 832.3 833.6 835.4 838.1 751.0 859.1 894.1 894.3 895.2 850.8 853.8 891.8 894.6 895.0 562.8 651.5 661.0 662.7 665.2 14530.6 14695.0 14800.4 14919.6 14927.6 11559.9 12477.8 13773.9 14721.6 14756.3 11729.5 11817.3 11866.4 11947.2 11973.9 3582.0 3713.2 3737.9 3764.4 3893.1 124192.5 124277.0 124733.5 128215.0 129261.7 10903.4 10962.8 11008.9 11177.4 11296.5 23235.0 23245.3 23290.8 23319.9 23397.0 7914.3 7965.4 8091.2 8143.6 8143.8 4417.6 4523.5 4581.7 4697.8 4703.5 3601.4 3667.8 3720.2 3722.3 3724.6 571.9 582.2 582.8 587.0 588.5 606.2 630.8 659.4 660.8 669.8 7921.1 9154.1 9201.8 9306.3 9510.9 573.1 646.4 649.3 661.4 667.6 3567.6 4289.4 4439.4 4605.1 4712.0 106108.0 149997.0 162360.0 165688.0 175001.0 117784.4 125969.0 136420.3 142958.0 145557.0 121308.0 122197.4 122704.8 125335.0 128181.0 7368.9 8845.6 8919.5 8930.3 8982.0 51118.5 65636.0 66201.0 66261.0 68342.7 69916.0 86627.0 87311.6 91224.0 99495.0 13058.9 13076.5 13087.1 13190.4 13640.6 14361.9 14706.4 14724.3 15002.7 15114.7 13488.5 13719.4 13802.7 13963.7 14228.8 13458.7 13619.9 13621.8 14001.9 14004.0 12897.9 13415.9 13419.1 13500.1 13696.0 11121.2 11297.7 11458.0 11464.1 11937.3 8183.3 9442.2 11440.4 11576.9 11718.6 19:15:29 INFO - PROCESS | 1650 | -------- Summary: end -------- 19:15:29 INFO - PROCESS | 1650 | 19:15:30 INFO - TEST-INFO | 1650: exit 0 19:15:30 INFO - TEST-OK | dromaeo_css | took 1114183ms 19:15:30 INFO - TEST-START | kraken 19:15:30 INFO - operating with platform_type : mac_ 19:15:30 INFO - Initialising browser for kraken test... 19:15:30 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpMPeAd8/profile http://localhost:49273/getInfo.html) 19:15:33 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 19:15:33 INFO - PROCESS | 1661 | colorDepth:24 19:15:33 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 19:15:33 INFO - PROCESS | 1661 | __metrics 19:15:33 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 19:15:34 INFO - TEST-INFO | 1661: exit 0 19:15:34 INFO - Browser initialized. 19:15:34 INFO - Running cycle 1/1 for kraken test... 19:15:34 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpMPeAd8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:15:46 INFO - PROCESS | 1663 | RSS: Main: 244371456 19:15:46 INFO - PROCESS | 1663 | 19:15:49 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 19:15:50 INFO - PROCESS | 1663 | RSS: Main: 257175552 19:15:50 INFO - PROCESS | 1663 | 19:15:52 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 19:15:53 INFO - PROCESS | 1663 | RSS: Main: 265818112 19:15:53 INFO - PROCESS | 1663 | 19:15:56 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 19:15:57 INFO - PROCESS | 1663 | RSS: Main: 265629696 19:15:57 INFO - PROCESS | 1663 | 19:15:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 19:16:00 INFO - PROCESS | 1663 | RSS: Main: 266960896 19:16:00 INFO - PROCESS | 1663 | 19:16:02 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:16:02 INFO - PROCESS | 1663 | RSS: Main: 268607488 19:16:02 INFO - PROCESS | 1663 | 19:16:05 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 19:16:06 INFO - PROCESS | 1663 | RSS: Main: 268894208 19:16:06 INFO - PROCESS | 1663 | 19:16:09 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 19:16:10 INFO - PROCESS | 1663 | RSS: Main: 269107200 19:16:10 INFO - PROCESS | 1663 | 19:16:13 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 19:16:13 INFO - PROCESS | 1663 | RSS: Main: 269193216 19:16:13 INFO - PROCESS | 1663 | 19:16:15 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:16:16 INFO - PROCESS | 1663 | RSS: Main: 268857344 19:16:16 INFO - PROCESS | 1663 | 19:16:18 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:16:18 INFO - PROCESS | 1663 | RSS: Main: 268877824 19:16:18 INFO - PROCESS | 1663 | 19:16:21 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:16:21 INFO - PROCESS | 1663 | RSS: Main: 268984320 19:16:21 INFO - PROCESS | 1663 | 19:16:24 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:16:25 INFO - PROCESS | 1663 | RSS: Main: 269062144 19:16:25 INFO - PROCESS | 1663 | 19:16:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:16:28 INFO - PROCESS | 1663 | RSS: Main: 269082624 19:16:28 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 19:16:30 INFO - PROCESS | 1663 | RSS: Main: 271253504 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | __start_tp_report 19:16:30 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 19:16:30 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 19:16:30 INFO - PROCESS | 1663 | |i|pagename|runs| 19:16:30 INFO - PROCESS | 1663 | |0;ai-astar;116;140;140;115;116;115;101;116;115;117 19:16:30 INFO - PROCESS | 1663 | |1;audio-beat-detection;176;96;170;199;139;132;129;113;177;142 19:16:30 INFO - PROCESS | 1663 | |2;audio-dft;201;205;220;197;195;199;188;199;201;194 19:16:30 INFO - PROCESS | 1663 | |3;audio-fft;71;103;76;146;61;61;60;56;129;57 19:16:30 INFO - PROCESS | 1663 | |4;audio-oscillator;70;76;118;73;70;87;110;69;75;111 19:16:30 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;135;130;91;90;92;87;131;88;95;88 19:16:30 INFO - PROCESS | 1663 | |6;imaging-darkroom;97;97;109;152;92;151;153;108;139;141 19:16:30 INFO - PROCESS | 1663 | |7;imaging-desaturate;147;146;94;94;107;75;150;145;94;91 19:16:30 INFO - PROCESS | 1663 | |8;json-parse-financial;56;60;66;60;49;58;77;87;88;84 19:16:30 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;68;68;88;65;86;61;57;68;105;105 19:16:30 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;88;86;105;107;105;87;86;84;89;85 19:16:30 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;149;145;135;156;163;163;141;145;173;152 19:16:30 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;173;171;168;171;199;231;182;175;172;170 19:16:30 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;67;69;66;69;77;90;72;68;69;67 19:16:30 INFO - PROCESS | 1663 | __end_tp_report 19:16:30 INFO - PROCESS | 1663 | __start_cc_report 19:16:30 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,968 19:16:30 INFO - PROCESS | 1663 | __end_cc_report 19:16:30 INFO - PROCESS | 1663 | __startTimestamp1473732990978__endTimestamp 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | ------- Summary: start ------- 19:16:30 INFO - PROCESS | 1663 | Number of tests: 14 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:119.10 Median:116.00 stddev:11.95 (10.3%) stddev-sans-first:12.62 19:16:30 INFO - PROCESS | 1663 | Values: 116.0 140.0 140.0 115.0 116.0 115.0 101.0 116.0 115.0 117.0 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:147.30 Median:173.00 stddev:32.26 (18.6%) stddev-sans-first:32.51 19:16:30 INFO - PROCESS | 1663 | Values: 176.0 96.0 170.0 199.0 139.0 132.0 129.0 113.0 177.0 142.0 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:199.90 Median:200.00 stddev:8.45 (4.2%) stddev-sans-first:8.96 19:16:30 INFO - PROCESS | 1663 | Values: 201.0 205.0 220.0 197.0 195.0 199.0 188.0 199.0 201.0 194.0 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:82.00 Median:60.50 stddev:32.61 (53.9%) stddev-sans-first:34.34 19:16:30 INFO - PROCESS | 1663 | Values: 71.0 103.0 76.0 146.0 61.0 61.0 60.0 56.0 129.0 57.0 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:85.90 Median:71.50 stddev:19.48 (27.3%) stddev-sans-first:19.80 19:16:30 INFO - PROCESS | 1663 | Values: 70.0 76.0 118.0 73.0 70.0 87.0 110.0 69.0 75.0 111.0 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:102.70 Median:89.00 stddev:20.39 (22.9%) stddev-sans-first:17.96 19:16:30 INFO - PROCESS | 1663 | Values: 135.0 130.0 91.0 90.0 92.0 87.0 131.0 88.0 95.0 88.0 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:123.90 Median:152.50 stddev:25.45 (16.7%) stddev-sans-first:25.07 19:16:30 INFO - PROCESS | 1663 | Values: 97.0 97.0 109.0 152.0 92.0 151.0 153.0 108.0 139.0 141.0 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:114.30 Median:83.00 stddev:29.19 (35.2%) stddev-sans-first:28.46 19:16:30 INFO - PROCESS | 1663 | Values: 147.0 146.0 94.0 94.0 107.0 75.0 150.0 145.0 94.0 91.0 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:68.50 Median:71.50 stddev:14.27 (20.0%) stddev-sans-first:14.40 19:16:30 INFO - PROCESS | 1663 | Values: 56.0 60.0 66.0 60.0 49.0 58.0 77.0 87.0 88.0 84.0 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:77.10 Median:68.00 stddev:17.68 (26.0%) stddev-sans-first:18.44 19:16:30 INFO - PROCESS | 1663 | Values: 68.0 68.0 88.0 65.0 86.0 61.0 57.0 68.0 105.0 105.0 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:86.00 stddev:9.41 (10.9%) stddev-sans-first:9.86 19:16:30 INFO - PROCESS | 1663 | Values: 88.0 86.0 105.0 107.0 105.0 87.0 86.0 84.0 89.0 85.0 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:152.20 Median:154.00 stddev:11.62 (7.5%) stddev-sans-first:12.27 19:16:30 INFO - PROCESS | 1663 | Values: 149.0 145.0 135.0 156.0 163.0 163.0 141.0 145.0 173.0 152.0 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.20 Median:174.00 stddev:19.71 (11.3%) stddev-sans-first:20.68 19:16:30 INFO - PROCESS | 1663 | Values: 173.0 171.0 168.0 171.0 199.0 231.0 182.0 175.0 172.0 170.0 19:16:30 INFO - PROCESS | 1663 | 19:16:30 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.40 Median:69.00 stddev:7.26 (10.5%) stddev-sans-first:7.52 19:16:30 INFO - PROCESS | 1663 | Values: 67.0 69.0 66.0 69.0 77.0 90.0 72.0 68.0 69.0 67.0 19:16:30 INFO - PROCESS | 1663 | -------- Summary: end -------- 19:16:30 INFO - PROCESS | 1663 | 19:16:31 INFO - TEST-INFO | 1663: exit 0 19:16:31 INFO - TEST-OK | kraken | took 61122ms 19:16:31 INFO - SUITE-END | took 1175s 19:16:32 INFO - Completed test suite (00:19:35) 19:16:32 INFO - javascript benchmark 19:16:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7274.884462160113, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3378.9662103378964, 3385.0691059958494, 3408.6420925802804, 3408.948865767014, 3416.7608267421274, 4543.341215523747, 4860.8055677772845, 5687.289088863892, 5708.172315014325, 5772.653640781556, 5071.79712811488, 5093.185090385539, 5612.35457922338, 5674.432556744324, 5783.971080144604, 5196.246544251081, 5227.581793456515, 5288.524032837044, 5293.41772405037, 5295.417504074549, 5177.249298851664, 5328.120860058086, 5338.092524270884, 5360.16917377807, 5384.757685904144, 5158.871028224287, 5232.372115346145, 5263.789448422059, 5313.840584782462, 5351.973240133813, 5233.816816411445, 5254.290670759437, 5272.6836389816735, 5294.576433885319, 5317.601179911522, 5222.529972302511, 5238.528532432061, 5249.842504724865, 5262.947410517873, 5289.391719952217, 5560.887822435529, 5573.554115670737, 5669.092945128801, 5774.653520788766, 5794.246747922785, 6243.125962365227, 6261.843453913643, 6292.874142517124, 6300.527460440439, 6306.180196574462, 6266.968665156691, 6283.7172327244925, 6303.117563541108, 6333.904991425133, 6338.429541341256, 5674.602777805555, 5759.395263497357, 5856.472917437451, 5870.943230218516, 5899.439553242435, 5282.207668849626, 6293.748250070037, 6294.874102517924, 6301.306856245809, 6328.841778955563, 6115.235595550556, 6202.72087756052, 6243.937560624427, 6252.187215662026, 6289.528285378615, 6080.14877917092, 6138.878900475427, 6218.875622487525, 6299.6535190564955, 6320.55756097078, 4720.95279047212, 6205.5345849061505, 6209.96895015522, 6225.346338634468, 6241.032639940817, 6096.72564734588, 6195.628262304276, 6221.564490485623, 6226.62640241587, 6265.0, 4939.604831613462, 4983.526564976321, 4984.327115839388, 4985.052839960396, 5009.423916249605, 4461.821527138943, 4482.327650852399, 4482.887927801831, 4584.701994370355, 4600.125976064537, 5792.88414231713, 5808.07070868659, 5882.852928676817, 5907.793227237112, 5980.760769569169, 5228.581713462914, 6209.813705588839, 6235.127082208494, 6267.71795269214, 6309.495240380758, 5802.65184088956, 5857.355690874, 5883.470487656046, 5894.528437724972, 5967.045272756338, 5455.372632147274, 5464.890702186013, 5472.835814925558, 5483.424240454775, 5487.5884308677005, 4806.615470762261, 5368.275282836845, 6332.0, 6339.302676705651, 6358.682065896686, 6159.507239420746, 6230.657313847782, 6249.843753906189, 6296.275928268308, 6302.338254483397, 5602.187682785891, 6054.3642917493025, 6259.186305780219, 6321.96839015802, 6335.936640633719, 5811.186433899173, 6154.476869466145, 6221.9377806221355, 6247.094171345038, 6247.31279559239, 6222.968885155546, 6298.716557755004, 6312.400321969406, 6314.179156709598, 6325.557210995186, 5418.83743487696, 6019.277686677451, 6119.571629985859, 6134.478569321479, 6145.446909778141, 6045.0, 6066.757329706762, 6216.813495595139, 6220.657863817352, 6255.093011513396, 4736.692115012514, 6018.3379828217985, 6045.21412216409, 6052.0921861719335, 6106.9083963739695, 6156.0, 6169.537284703575, 6171.413715697002, 6191.380861913773, 6203.379662033761, 5892.852678683067, 6051.425114614105, 6081.847953801013, 6091.482224011009, 6102.328743838263], "value": 5684.115161506049, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.795865633075, 530.8513616187466, 534.9251104845322, 542.6894050867156, 556.2185129892501, 32183.19542011451, 39278.41082383763, 39713.0, 41106.97232569187, 41742.0, 40148.99627509314, 40682.0, 40689.593104068954, 40689.982750431256, 40754.18491630173, 9647.565859536302, 9833.459159746211, 9848.704538863809, 9854.704358869245, 9901.207903367713, 9686.612535498572, 9735.221182305399, 9759.024097590254, 9760.4631745254, 9789.167920726712, 9707.369021013646, 9779.217662586976, 9787.804243915118, 9797.853032204523, 9800.95099524505, 9723.0, 9766.658166964122, 9794.314397992144, 9794.608215671436, 9817.214622830157, 9599.712008639752, 9685.951570242174, 9687.225021998294, 9706.126448619658, 9710.028997100304, 38808.0, 39160.216795664215, 39269.607303927165, 39295.0, 39314.017149571206, 3175.8094514329214, 3221.0336898930464, 3289.9177520562175, 3489.45913383426, 3801.1067399161175, 29855.0, 30302.84848575765, 30617.540736888743, 31340.373192536248, 31342.529862052088, 3207.461442059506, 3233.983830080858, 3240.4653232216656, 3246.091094493522, 3305.0415379539904, 418222.0, 462260.0, 499688.0, 500230.0, 501547.0, 55133.724331378085, 55300.72349638306, 55417.0, 55569.0, 55572.0, 33125.66874331274, 33923.83038084794, 57447.425525744206, 58091.0, 58198.709006454694, 15902.443414480438, 16232.918835405984, 16382.672346553003, 16382.918085409498, 16391.672166556604, 11152.330860148255, 12618.369081545887, 12626.494940202472, 12681.365931703378, 12706.364681765876, 17855.642887142185, 17881.28474861017, 17977.460676179737, 18022.36921707734, 19317.22731090769, 6539.6403197824575, 7461.962690186514, 7702.114256860533, 7813.648385822652, 7826.373890088779, 825.9256666900008, 843.5402705525503, 901.6258252825136, 977.413551868873, 1016.1312078173056, 28822.855885720855, 29918.85040574783, 30751.538726919112, 30804.383912322075, 30916.53625195624, 985.3348989432247, 1026.594495174402, 1060.5757696921294, 1079.2768844873954, 1085.2240647936762, 13890.097143685629, 14608.415663373346, 14685.632859178606, 14953.626159346104, 15105.244737763069, 573943.0, 575765.0, 579011.0, 580837.0958145267, 587692.0, 51555.0, 51751.74124129355, 51796.223056654184, 51838.48161518412, 52025.7398713004, 1285.4279845468864, 1309.1490531154636, 1334.9199048057149, 1339.8727120923497, 1340.544214966916, 23302.184423545772, 26786.0, 27080.729192707822, 28560.714392855807, 28940.56589151122, 50088.0, 52210.694732631986, 52420.47579524156, 52443.213351800514, 52513.737431314126, 630826.0, 637249.0, 637524.0, 637829.0, 638793.0, 25654.0, 26321.210363688355, 40065.1986960265, 40492.0, 40592.0, 43073.0, 43641.78179109084, 44380.11239775122, 44678.88302792456, 44692.0, 32819.83590082034, 36285.45571816372, 37335.0, 37555.62444375631, 37593.2481350366, 36990.815045924595, 36993.07517311981, 37123.07192320214, 37253.441198382585, 37615.81192094022, 31616.841915790275, 33054.33891322112, 36502.0, 36571.0, 36755.264894701424, 30312.393752125274, 33193.336133277684, 33588.496172556945, 33724.662753372155, 33980.830095849364, 24205.394865128514, 24838.006479741332, 25083.24750257495, 25124.62313065269, 25218.11736589284], "value": 19554.759442520404, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.82451511017166, 362.34234863722327, 368.24142266930136, 370.2945888083201, 385.8982604663685, 15515.534533963984, 19654.115564799584, 19668.311609093686, 20343.59312813743, 20588.69116963243, 15799.0, 19176.328828491005, 19605.705914411294, 19629.0, 19996.0, 5707.686077265748, 5789.536837053026, 6006.639601623895, 6045.6070355426755, 6066.181065556138, 5010.749462526877, 5211.869703257411, 5754.65472071675, 5846.912296315559, 5913.231279933602, 5697.8575535611735, 5748.913766293509, 5753.712314384285, 5879.324392548125, 5945.37573554777, 5774.624649397776, 5850.73671684773, 5874.295084589855, 5885.440883116118, 5885.764569417218, 4332.198543269474, 5010.298558201818, 5057.848264552033, 5057.9494205057845, 5137.768800403991, 25280.747192528022, 25328.0, 25384.492310153695, 25551.85016674254, 25580.848861801263, 2195.4072400451946, 2203.5152266501345, 2364.3616223619697, 2430.4895971845935, 2995.7603391728608, 12199.93900030503, 14785.556433307018, 15181.544553663407, 15249.0, 15297.235138243042, 1646.7859178306862, 1857.7120546315343, 1875.7373967644542, 1899.335232668555, 1997.2111016148685, 119953.40023299915, 121806.0, 122637.38681306626, 122657.38671306586, 123361.9999999991, 10326.122279606167, 10468.47657617116, 10871.945640271906, 10963.945180274048, 11037.889621103846, 13013.804792928113, 13294.734105317839, 13313.866861331457, 13324.933375333061, 13336.133151345326, 8254.42219044673, 8423.747287581382, 8594.914050859412, 8626.482411055356, 8699.478031318142, 8651.6971905983, 8675.826483470295, 8706.91293087061, 8745.463815493436, 8864.468131912106, 5462.8361149165585, 6373.5857169283845, 6429.38920802523, 6485.3514648534765, 6517.185351831021, 1708.7693161423297, 1717.8196289389684, 1830.1215416600107, 1858.9535261618569, 1862.7392165096896, 753.4989232160661, 867.3841572483607, 929.9953500232456, 957.9089986451276, 982.9950850245702, 19083.713744293847, 21872.01575929087, 21905.452363691034, 22099.801037802765, 22156.33530994073, 737.3290305821677, 769.7767647382196, 843.3969711656073, 947.9383840050375, 949.9810003799886, 8577.871331930026, 10059.949700251453, 10675.83986240207, 11547.422628868522, 11554.537818487335, 585700.0, 586323.0, 591737.0, 604886.0, 605647.0, 110118.89881101086, 114490.0, 125926.0, 131170.34414828056, 139689.0, 923.9214666753267, 962.2206013129388, 967.0474582536197, 967.7387105481481, 996.8454889492141, 18458.446246612624, 18553.0, 18793.812061879205, 19086.7136992948, 19115.904420477807, 54976.450235498734, 57778.66440576597, 58111.4188858106, 58765.11852322304, 58770.82458350895, 65236.0, 70049.0, 73164.5860726002, 84268.0, 85193.0, 7665.118511371264, 7769.7669069928, 7931.960340198262, 8029.678812847421, 8194.959025204837, 10943.343399396063, 12322.445489953154, 12399.13206075566, 12492.500299988262, 12673.93663031679, 10552.683419497427, 10680.89319106799, 10842.728931776768, 11002.064824490008, 11135.944320278346, 9089.59096840657, 10818.56725730962, 10905.454727263446, 10947.0, 11109.666709998713, 9027.638894444412, 10836.403997780199, 10857.511411986321, 11070.723231919266, 11139.387333696724, 6473.93180125274, 7317.414606831547, 7784.023418668309, 8177.0, 8183.427160098736, 8119.147489513516, 9648.75878103053, 10653.946730266609, 11031.282966607145, 11222.775544489228], "value": 9505.45083227548, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1486.2536519589482, 1496.603400098974, 1679.7480377943307, 1794.3899074314747, 1832.8991905445203, 7951.602419878997, 8216.548089855056, 8444.704435344764, 9023.819523609523, 9054.683086091987, 8471.407001509897, 8729.738107856758, 8758.91241087589, 8817.867731984026, 8842.513661748617, 2357.3163782503098, 2399.568077746005, 2404.302752201859, 2407.109369533275, 2414.9396265093424, 2353.8116950643907, 2387.2957477544164, 2388.3790214544256, 2392.760723927602, 2397.7482364351754, 2378.52429514097, 2383.1778036577352, 2387.0, 2400.7959323457526, 2407.4462873539096, 1940.8544359173118, 2347.307544274443, 2358.882055897207, 2374.3114496795993, 2382.321038504035, 2353.376355265856, 2363.8936247868714, 2365.7989070928993, 2378.5718570657273, 2379.0, 7088.362047415757, 8222.643263861455, 8663.653453861838, 8828.293736501064, 8982.505962172077, 4714.57568818808, 5363.0, 5366.248725178478, 5375.65058271215, 5384.7846086156505, 3541.34485120251, 3565.7682250653884, 3606.5672119345586, 3613.1689711366535, 3639.6178401267753, 1070.86078809755, 1087.0868470484752, 1088.9564417423294, 1090.6837017264984, 1097.259349938788, 11702.297862128215, 11750.353730544817, 11751.882481175164, 11811.93692567673, 11828.940855295754, 4545.863624091283, 4647.767611619473, 4778.832740854054, 4830.420349558005, 4855.077535268288, 9913.504324783731, 9992.600295988224, 9998.05018523239, 10008.849867251996, 10024.097831195228, 5342.038433082005, 5346.545543628835, 5347.786088556414, 5348.598855085884, 5353.491418315254, 4370.431843860278, 4518.480374756945, 4714.4106986626675, 4836.661433699607, 4896.6572339935865, 3353.3796247693767, 3812.390017597171, 3880.534335879656, 3896.1623251000606, 3952.782596957195, 1294.50161687749, 1297.344840855362, 1298.6753311672082, 1299.9480020799062, 1318.3605951113702, 806.7216810200472, 861.6682577207695, 892.901780804111, 904.909583951337, 955.3933252384646, 5512.0, 5716.714164291769, 6007.8197654070445, 6017.729202185912, 6038.667873266924, 919.4391421233042, 931.2131249094505, 943.9811203776023, 952.4808979106404, 976.345848281653, 3257.0, 3901.45379646844, 3952.7825969571372, 3981.243563722883, 4071.145059537471, 10515.579376824995, 10922.071623912056, 10973.780524389467, 10976.286541374786, 11015.118790496741, 8949.105089490999, 9902.455364954996, 10916.962888525577, 10924.617638382777, 10933.671989840317, 6242.875142497124, 7741.535507869546, 8191.8771218430575, 8194.631241594143, 8307.08622051574, 5292.179712144623, 5646.322441307069, 5944.1975333331175, 6176.81469555914, 6191.628502289877, 8033.799155021171, 10048.899511004796, 10130.392176469435, 10211.897881021094, 10299.742506437398, 10174.389536627827, 10273.0, 10358.948205259227, 10408.583656653953, 10478.318909270873, 7560.034391058474, 7574.318311351784, 7586.0, 7620.047494063242, 7684.193159718149, 8340.207680270365, 8394.958025209835, 8399.370047246359, 8406.201410865959, 8408.075111737826, 7364.226756190737, 7466.925330746623, 7500.062492188476, 7510.324070833652, 7628.923710763043, 7278.745243916437, 7377.557346559223, 7400.629968501661, 7419.888701669372, 7600.733974310872, 6833.350831670983, 6922.7923162305215, 6947.3747362737595, 6966.512344135862, 7004.914238293073, 4789.161896668139, 4919.1883339248625, 5025.547700706954, 5106.642535022661, 5125.948740512547, 5026.0, 5058.494150584912, 5206.271122042993, 5222.712750798591, 5250.264962905273], "value": 4586.195233138253, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.271828829862, 297.5165356296019, 308.27093922872405, 310.56210742852585, 311.95008798592204, 5336.439673834249, 5343.064963631368, 5522.696251706155, 5615.522680572151, 5662.6036177467595, 5337.678110022143, 5488.588355873316, 5550.69471179085, 5553.694546799924, 5592.832215033545, 3798.5309548793366, 4259.424977628019, 4271.295236286009, 4275.0, 4304.160688665712, 4124.505059392862, 4248.746619747167, 4252.744835309876, 4259.847712692616, 4285.335772955182, 3631.6186800385976, 4207.347861081537, 4212.6409210841875, 4226.260404429228, 4244.97877510612, 3473.548438702965, 4017.718759686823, 4253.404523366731, 4271.551487093841, 4274.572542745732, 3697.2975134724315, 3703.0742314421395, 3705.6109108543483, 3719.5722491913507, 3724.2737666155112, 3987.0264219211385, 4778.522147785228, 5422.267993820823, 5439.782408703647, 5464.23500709901, 4476.552344765498, 4558.082826990729, 4764.0, 4788.760561971888, 4789.664723469358, 4562.726236425825, 4835.443923948756, 5838.474537291664, 5881.85295367615, 5899.410058994109, 3217.3404452087266, 3526.2947410518, 3538.2141109013364, 3931.5478719947, 3935.3900145477496, 5725.398833122504, 6174.413430724074, 6193.6903154842075, 6194.380561943814, 6212.161358216628, 3970.563238043813, 3987.003249187703, 3994.940075898864, 4002.9192118127944, 4025.637692607621, 3519.630438803889, 3876.980615096963, 3903.590123037097, 4026.516817981861, 4038.3750562134846, 3986.9202615948097, 3996.8800935971967, 4291.2405913575285, 4689.8812289827565, 4728.267118596623, 4660.813567457331, 4877.707337559757, 5096.23556466533, 5494.81861399801, 5888.409099995988, 1256.7674980128706, 1297.5912587534897, 1355.6814148675053, 1371.2526672963259, 1378.400395827818, 800.5636927874323, 805.2792750488397, 814.3810703865105, 839.345310657688, 840.5083026429608, 4331.0, 4352.0, 4384.583464570861, 4416.558344165558, 4580.129775342674, 3956.485656864589, 4757.452892917289, 4983.8006479741125, 5089.277236678359, 5108.463611320758, 4196.412502249687, 4436.889077773081, 4573.748443835554, 4588.747618880927, 4613.861584152481, 2450.5051918329805, 2511.460036092249, 2546.3888666720222, 2561.0652111978993, 2689.9596506052426, 4287.442632457761, 4555.908881822411, 4713.316569097462, 4748.169070412664, 5275.393329767049, 4623.722576645345, 4907.460179380265, 5201.401838788512, 5224.9738751306, 5226.099514769339, 4533.738286011139, 4545.2954792007295, 5110.892483482203, 5141.734998276122, 5228.764705588258, 5047.747612619428, 5074.16276314404, 5082.898342033212, 5091.363579552556, 5095.0574143783515, 3975.5229372475487, 4168.812403441913, 4415.094905544292, 4699.436067671902, 4894.828680996148, 3714.8514059438403, 4667.0, 5138.047541934041, 5241.31862857826, 5281.920771188512, 3088.2279430142466, 3274.639789623092, 3438.948415773715, 3441.8623255069515, 3469.548958635361, 5093.268288781684, 5109.3613298337705, 5117.385913690381, 5119.308893299357, 5139.637995931156, 5156.716380598953, 5426.864328391822, 5455.618106732491, 5614.7192640367, 5933.851653708692, 5904.911426328695, 5909.881802364014, 5915.615484993462, 5960.5827592068135, 5968.537708261504, 5191.870203244798, 5580.5535557156145, 5638.36487418644, 5670.347909990404, 5870.475695450997, 5056.7471626419265, 5192.714400707846, 5304.628675992798, 5895.233619629421, 5956.910646340395, 4984.327115839315, 5076.578558003765, 5122.538971492583, 5145.945081258258, 5458.945410546003], "value": 3944.793205926245, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.029834204496, 666.0, 681.437813803612, 739.0466298474964, 773.0838955837331, 15081.547553573397, 15431.759330540572, 15477.226138693048, 15502.534923952271, 15575.532734017983, 10286.074253317218, 10863.619773307935, 13117.0, 14130.152190868555, 14237.501687440941, 583.0176153181902, 659.9241087274977, 796.760971708488, 820.3437250199831, 828.0891019878146, 828.9295409890136, 832.2551316571664, 833.6040380819123, 835.4402550291319, 838.1409055717877, 750.9812254693649, 859.0593300336122, 894.1416240409214, 894.311380237217, 895.1853813030144, 850.8468475674376, 853.7780177153923, 891.8260939116875, 894.5706061090648, 894.9931327256836, 562.7777028073888, 651.4592887903029, 660.9867802643969, 662.7183447035039, 665.2117241069349, 14530.636734081627, 14695.044822086531, 14800.40798368064, 14919.552413427615, 14927.626809329746, 11559.94220028903, 12477.75044499114, 13773.931130344385, 14721.631959200999, 14756.262186890719, 11729.53081876724, 11817.290962542189, 11866.406679666068, 11947.223430477077, 11973.94013029938, 3581.9641803582153, 3713.1830997180577, 3737.869654032282, 3764.3976963685955, 3893.10458594522, 124192.51614967741, 124277.0, 124733.51598904094, 128215.0, 129261.70738292685, 10903.400312982862, 10962.80743851217, 11008.944955275332, 11177.44112794357, 11296.491657875415, 23235.0, 23245.3026409208, 23290.7670923292, 23319.883400582887, 23397.0, 7914.2588630501195, 7965.402594805297, 8091.150429204967, 8143.633536490872, 8143.75568732939, 4417.624501917308, 4523.5024147343765, 4581.656375771764, 4697.765111744399, 4703.506131856174, 3601.369760291991, 3667.7982710951155, 3720.200156966211, 3722.2555488902326, 3724.566042073821, 571.9370869204384, 582.19366177844, 582.7814569536424, 587.0, 588.4791959116213, 606.169547719627, 630.7540059376847, 659.4131223211346, 660.8149718078946, 669.7521916890781, 7921.128675845653, 9154.130357616015, 9201.813175005356, 9306.348555601044, 9510.857337139949, 573.068763259703, 646.3633321178635, 649.331188875459, 661.3882159002903, 667.5827607745159, 3567.554055743032, 4289.420928174719, 4439.4006809080365, 4605.125026245003, 4711.95288047122, 106108.0, 149997.0, 162360.0, 165688.0, 175001.0, 117784.41107794407, 125969.0, 136420.31789840988, 142958.0, 145557.0, 121308.0, 122197.38901305436, 122704.77295226934, 125335.0, 128181.0, 7368.926310736824, 8845.601947912488, 8919.464832109834, 8930.310612984984, 8982.0, 51118.48881511286, 65636.0154597672, 66201.0, 66261.00608490974, 68342.65828670825, 69916.0, 86627.0, 87311.56344218238, 91224.0, 99495.0, 13058.869411306145, 13076.542321018718, 13087.149335293176, 13190.406431710404, 13640.590782276548, 14361.928190358982, 14706.41174353014, 14724.337404816595, 15002.681178129687, 15114.697706046038, 13488.460461581819, 13719.382627781973, 13802.65493362674, 13963.650908726957, 14228.800223102862, 13458.730825383633, 13619.908155877383, 13621.79567306511, 14001.929990349983, 14004.0, 12897.935510322388, 13415.932920335335, 13419.060665753304, 13500.054996150175, 13696.00956508751, 11121.201744852498, 11297.71755706114, 11458.026067784333, 11464.140189485988, 11937.28376297425, 8183.315860416212, 9442.244620430487, 11440.427978601203, 11576.94211528937, 11718.589849355232], "value": 7754.3403169625735, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1617.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 140.0, 140.0, 115.0, 116.0, 115.0, 101.0, 116.0, 115.0, 117.0], "value": 119.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 96.0, 170.0, 199.0, 139.0, 132.0, 129.0, 113.0, 177.0, 142.0], "value": 147.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 205.0, 220.0, 197.0, 195.0, 199.0, 188.0, 199.0, 201.0, 194.0], "value": 199.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 103.0, 76.0, 146.0, 61.0, 61.0, 60.0, 56.0, 129.0, 57.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 76.0, 118.0, 73.0, 70.0, 87.0, 110.0, 69.0, 75.0, 111.0], "value": 85.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 130.0, 91.0, 90.0, 92.0, 87.0, 131.0, 88.0, 95.0, 88.0], "value": 102.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 109.0, 152.0, 92.0, 151.0, 153.0, 108.0, 139.0, 141.0], "value": 123.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 94.0, 94.0, 107.0, 75.0, 150.0, 145.0, 94.0, 91.0], "value": 114.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 66.0, 60.0, 49.0, 58.0, 77.0, 87.0, 88.0, 84.0], "value": 68.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 88.0, 65.0, 86.0, 61.0, 57.0, 68.0, 105.0, 105.0], "value": 77.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 105.0, 107.0, 105.0, 87.0, 86.0, 84.0, 89.0, 85.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 145.0, 135.0, 156.0, 163.0, 163.0, 141.0, 145.0, 173.0, 152.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 171.0, 168.0, 171.0, 199.0, 231.0, 182.0, 175.0, 172.0, 170.0], "value": 181.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 66.0, 69.0, 77.0, 90.0, 72.0, 68.0, 69.0, 67.0], "value": 71.4, "unit": "score"}]}]} 19:16:32 INFO - Return code: 0 19:16:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:16:32 ERROR - # TBPL SUCCESS # 19:16:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:16:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:16:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:16:32 INFO - 2016-09-12 19:16:32.320 screenresolution[1668:10801] starting screenresolution argv=screenresolution get 19:16:32 INFO - 2016-09-12 19:16:32.323 screenresolution[1668:10801] Display 0: 1600x1200x32@60 19:16:32 INFO - 2016-09-12 19:16:32.329 screenresolution[1669:10804] starting screenresolution argv=screenresolution list 19:16:32 INFO - Available Modes on Display 0 19:16:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:16:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:16:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:16:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:16:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:16:32 INFO - Intel Iris: 19:16:32 INFO - Chipset Model: Intel Iris 19:16:32 INFO - Type: GPU 19:16:32 INFO - Bus: Built-In 19:16:32 INFO - VRAM (Dynamic, Max): 1536 MB 19:16:32 INFO - Vendor: Intel (0x8086) 19:16:32 INFO - Device ID: 0x0a2e 19:16:32 INFO - Revision ID: 0x0009 19:16:32 INFO - Displays: 19:16:32 INFO - Display: 19:16:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:16:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:16:32 INFO - Display Serial Number: 1600x1200 60 19:16:32 INFO - Main Display: Yes 19:16:32 INFO - Mirror: Off 19:16:32 INFO - Online: Yes 19:16:32 INFO - Rotation: Supported 19:16:32 INFO - Return code: 0 19:16:32 INFO - Running post-action listener: _resource_record_post_action 19:16:32 INFO - [mozharness: 2016-09-13 02:16:32.563514Z] Finished run-tests step (success) 19:16:32 INFO - Running post-run listener: _resource_record_post_run 19:16:32 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 42013696; Write bytes: 707562496; Read time: 519; Write time: 1995 19:16:32 INFO - TinderboxPrint: CPU usage
54.8% 19:16:32 INFO - TinderboxPrint: I/O read bytes / time
42,013,696 / 519 19:16:32 INFO - TinderboxPrint: I/O write bytes / time
707,562,496 / 1,995 19:16:32 INFO - TinderboxPrint: CPU idle
2,191.3 (45.2%) 19:16:32 INFO - TinderboxPrint: CPU system
982.2 (20.3%) 19:16:32 INFO - TinderboxPrint: CPU user
1,672.2 (34.5%) 19:16:32 INFO - TinderboxPrint: Swap in / out
447,897,600 / 0 19:16:32 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 384016896; Write bytes: 377550336; Read time: 29029; Write time: 726 19:16:32 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 41059328; Write bytes: 299566592; Read time: 504; Write time: 1212 19:16:33 INFO - Running post-run listener: _upload_blobber_files 19:16:33 INFO - Blob upload gear active. 19:16:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:16:33 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 19:16:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:16:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:16:33 INFO - (blobuploader) - INFO - Open directory for files ... 19:16:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:16:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:16:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:16:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:16:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:16:34 INFO - (blobuploader) - INFO - Done attempting. 19:16:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:16:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:16:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:16:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:16:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:16:35 INFO - (blobuploader) - INFO - Done attempting. 19:16:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:16:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:16:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:16:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:16:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:16:37 INFO - (blobuploader) - INFO - Done attempting. 19:16:37 INFO - (blobuploader) - INFO - Iteration through files over. 19:16:37 INFO - Return code: 0 19:16:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:16:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:16:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21c0e28bbd5c7eab0657526292e1f705b48fd59f1f58770e1c5a5dd32c79327a888a56bd1ecba82503f006b2087ac814041dd2895be73adf013c2d88b2235f4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9cf21361ee6f47dd031da112d79a9c3fbc132c8cbd823dd84089c1929b5719963f72a1a5c3e65643a342fb6f44d7e53d8f99a84ce8bd7dcc0396d49b421537", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb2c6c18ab8341bf1037f5702198e06ada889bb2e6926223d7d2df9610fc20cedacf6033d8eb33923947d666786b4112fad65a0daa6500d124caac1a64af98a5"} 19:16:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:16:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:16:37 INFO - Contents: 19:16:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21c0e28bbd5c7eab0657526292e1f705b48fd59f1f58770e1c5a5dd32c79327a888a56bd1ecba82503f006b2087ac814041dd2895be73adf013c2d88b2235f4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9cf21361ee6f47dd031da112d79a9c3fbc132c8cbd823dd84089c1929b5719963f72a1a5c3e65643a342fb6f44d7e53d8f99a84ce8bd7dcc0396d49b421537", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb2c6c18ab8341bf1037f5702198e06ada889bb2e6926223d7d2df9610fc20cedacf6033d8eb33923947d666786b4112fad65a0daa6500d124caac1a64af98a5"} 19:16:37 INFO - Running post-run listener: copy_logs_to_upload_dir 19:16:37 INFO - Copying logs to upload dir... 19:16:37 INFO - mkdir: /builds/slave/test/build/upload/logs 19:16:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.475985 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-12 19:16:37.821039) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 19:16:37.824482) ========= 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.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21c0e28bbd5c7eab0657526292e1f705b48fd59f1f58770e1c5a5dd32c79327a888a56bd1ecba82503f006b2087ac814041dd2895be73adf013c2d88b2235f4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9cf21361ee6f47dd031da112d79a9c3fbc132c8cbd823dd84089c1929b5719963f72a1a5c3e65643a342fb6f44d7e53d8f99a84ce8bd7dcc0396d49b421537", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb2c6c18ab8341bf1037f5702198e06ada889bb2e6926223d7d2df9610fc20cedacf6033d8eb33923947d666786b4112fad65a0daa6500d124caac1a64af98a5"} build_url:https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011056 build_url: 'https://queue.taskcluster.net/v1/task/YHCR7vIzQZKe7mlAJvRINA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21c0e28bbd5c7eab0657526292e1f705b48fd59f1f58770e1c5a5dd32c79327a888a56bd1ecba82503f006b2087ac814041dd2895be73adf013c2d88b2235f4d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9cf21361ee6f47dd031da112d79a9c3fbc132c8cbd823dd84089c1929b5719963f72a1a5c3e65643a342fb6f44d7e53d8f99a84ce8bd7dcc0396d49b421537", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb2c6c18ab8341bf1037f5702198e06ada889bb2e6926223d7d2df9610fc20cedacf6033d8eb33923947d666786b4112fad65a0daa6500d124caac1a64af98a5"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 19:16:37.861549) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 19:16:37.861901) ========= 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.4KYXBdqLjj/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.YzuoQM1f1Q/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.005264 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 19:16:37.911273) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 19:16:37.911596) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 19:16:37.912036) ========= ========= Total master_lag: 2.87 =========