builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0025 starttime: 1473384438.59 results: success (0) buildid: 20160908153031 builduid: ae1721cabca0425aa754c629b9885d63 revision: 4cb8b7cc27e113f269ab8ecede30f832ffede2dc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 18:27:18.592182) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 18:27:18.592704) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 18:27:18.592994) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 18:27:18.636121) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 18:27:18.636491) ========= 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.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-08 18:27:18-- 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% 16.4M=0.001s 2016-09-08 18:27:19 (16.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.578974 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 18:27:19.238121) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 18:27:19.238454) ========= 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.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.027749 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 18:27:19.287121) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 18:27:19.287458) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 4cb8b7cc27e113f269ab8ecede30f832ffede2dc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 4cb8b7cc27e113f269ab8ecede30f832ffede2dc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-08 18:27:19,361 truncating revision to first 12 chars 2016-09-08 18:27:19,361 Setting DEBUG logging. 2016-09-08 18:27:19,361 attempt 1/10 2016-09-08 18:27:19,362 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/4cb8b7cc27e1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 18:27:20,405 unpacking tar archive at: autoland-4cb8b7cc27e1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.449554 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-08 18:27:20.759746) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 18:27:20.760022) ========= script_repo_revision: 4cb8b7cc27e113f269ab8ecede30f832ffede2dc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 18:27:20.760408) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 18:27:20.760676) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 18:27:20.780056) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-08 18:27:20.780347) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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:27:20 INFO - MultiFileLogger online at 20160908 18:27:20 in /builds/slave/test 18:27:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 18:27:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:27:20 INFO - {'append_to_log': False, 18:27:20 INFO - 'base_work_dir': '/builds/slave/test', 18:27:20 INFO - 'blob_upload_branch': 'Autoland', 18:27:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:27:20 INFO - 'branch': 'Autoland', 18:27:20 INFO - 'buildbot_json_path': 'buildprops.json', 18:27:20 INFO - 'config_files': ('talos/mac_config.py',), 18:27:20 INFO - 'default_actions': ('clobber', 18:27:20 INFO - 'read-buildbot-config', 18:27:20 INFO - 'download-and-extract', 18:27:20 INFO - 'populate-webroot', 18:27:20 INFO - 'create-virtualenv', 18:27:20 INFO - 'install', 18:27:20 INFO - 'run-tests'), 18:27:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:27:20 INFO - 'download_minidump_stackwalk': True, 18:27:20 INFO - 'download_symbols': 'ondemand', 18:27:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:27:20 INFO - 'tooltool.py': '/tools/tooltool.py', 18:27:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:27:20 INFO - '/tools/misc-python/virtualenv.py')}, 18:27:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:27:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:27:20 INFO - 'installer_path': 'installer.exe', 18:27:20 INFO - 'log_level': 'info', 18:27:20 INFO - 'log_name': 'talos', 18:27:20 INFO - 'log_to_console': True, 18:27:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:27:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:27:20 INFO - 'opt_config_files': (), 18:27:20 INFO - 'pip_index': False, 18:27:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:27:20 INFO - 'cmd': ('bash', 18:27:20 INFO - '-c', 18:27:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:27:20 INFO - 'enabled': True, 18:27:20 INFO - 'halt_on_failure': False, 18:27:20 INFO - 'name': 'check_screen_resolution'},), 18:27:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:27:20 INFO - 'cmd': ('bash', 18:27:20 INFO - '-c', 18:27:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:27:20 INFO - 'enabled': True, 18:27:20 INFO - 'halt_on_failure': False, 18:27:20 INFO - 'name': 'check_screen_resolution'},), 18:27:20 INFO - 'run_cmd_checks_enabled': True, 18:27:20 INFO - 'sps_profile': False, 18:27:20 INFO - 'sps_profile_interval': 0, 18:27:20 INFO - 'suite': 'dromaeojs-e10s', 18:27:20 INFO - 'system_bits': '32', 18:27:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:27:20 INFO - 'title': 't-yosemite-r7-0025', 18:27:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:27:20 INFO - 'use_talos_json': True, 18:27:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:27:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:27:20 INFO - 'work_dir': 'build'} 18:27:20 INFO - [mozharness: 2016-09-09 01:27:20.990709Z] Running clobber step. 18:27:20 INFO - Running pre-action listener: _resource_record_pre_action 18:27:20 INFO - Running main action method: clobber 18:27:20 INFO - rmtree: /builds/slave/test/build 18:27:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:27:23 INFO - Running post-action listener: _resource_record_post_action 18:27:23 INFO - [mozharness: 2016-09-09 01:27:23.186954Z] Finished clobber step (success) 18:27:23 INFO - [mozharness: 2016-09-09 01:27:23.187144Z] Running read-buildbot-config step. 18:27:23 INFO - Running pre-action listener: _resource_record_pre_action 18:27:23 INFO - Running main action method: read_buildbot_config 18:27:23 INFO - Using buildbot properties: 18:27:23 INFO - { 18:27:23 INFO - "project": "", 18:27:23 INFO - "product": "firefox", 18:27:23 INFO - "script_repo_revision": "production", 18:27:23 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 18:27:23 INFO - "repository": "", 18:27:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 18:27:23 INFO - "buildid": "20160908153031", 18:27:23 INFO - "pgo_build": "False", 18:27:23 INFO - "basedir": "/builds/slave/test", 18:27:23 INFO - "buildnumber": 153, 18:27:23 INFO - "slavename": "t-yosemite-r7-0025", 18:27:23 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:27:23 INFO - "platform": "yosemite_r7", 18:27:23 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 18:27:23 INFO - "branch": "autoland", 18:27:23 INFO - "repo_path": "integration/autoland", 18:27:23 INFO - "revision": "4cb8b7cc27e113f269ab8ecede30f832ffede2dc", 18:27:23 INFO - "stage_platform": "macosx64", 18:27:23 INFO - "builduid": "ae1721cabca0425aa754c629b9885d63", 18:27:23 INFO - "slavebuilddir": "test" 18:27:23 INFO - } 18:27:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:27:23 INFO - Running post-action listener: _resource_record_post_action 18:27:23 INFO - [mozharness: 2016-09-09 01:27:23.190527Z] Finished read-buildbot-config step (success) 18:27:23 INFO - [mozharness: 2016-09-09 01:27:23.190637Z] Running download-and-extract step. 18:27:23 INFO - Running pre-action listener: _resource_record_pre_action 18:27:23 INFO - Running main action method: download_and_extract 18:27:23 INFO - mkdir: /builds/slave/test/build/tests 18:27:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:23 INFO - trying https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:27:23 INFO - Downloading https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/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:27:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/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:27:24 INFO - Downloaded 1199 bytes. 18:27:24 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:27:24 INFO - Using the following test package requirements: 18:27:24 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:27:24 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:27:24 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:27:24 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:27:24 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:27:24 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:27:24 INFO - u'jsshell-mac.zip'], 18:27:24 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:27:24 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:27:24 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:27:24 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:27:24 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:27:24 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:27:24 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:27:24 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:27:24 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:27:24 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:27:24 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:27:24 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:27:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:27:25 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:27:32 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:27:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:27:33 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:27:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:27:40 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:27:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:40 INFO - trying https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:27:40 INFO - Downloading https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:27:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/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:27:45 INFO - Downloaded 111538116 bytes. 18:27:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:27:45 INFO - mkdir: /builds/slave/test/properties 18:27:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:27:45 INFO - Writing to file /builds/slave/test/properties/build_url 18:27:45 INFO - Contents: 18:27:45 INFO - build_url:https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:27:46 INFO - Running post-action listener: _resource_record_post_action 18:27:46 INFO - Running post-action listener: set_extra_try_arguments 18:27:46 INFO - [mozharness: 2016-09-09 01:27:46.619928Z] Finished download-and-extract step (success) 18:27:46 INFO - [mozharness: 2016-09-09 01:27:46.620058Z] Running populate-webroot step. 18:27:46 INFO - Running pre-action listener: _resource_record_pre_action 18:27:46 INFO - Running main action method: populate_webroot 18:27:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:27:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:27:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:27:46 INFO - '--tppagecycles', 18:27:46 INFO - '7'], 18:27:46 INFO - 'tests': ['tsvgm']}}, 18:27:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:27:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:27:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:27:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:27:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:27:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:27:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'talos_options': ['--disable-e10s'], 18:27:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:27:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:27:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'talos_options': ['--disable-e10s'], 18:27:46 INFO - 'tests': ['damp', 'tps']}, 18:27:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'tests': ['damp', 'tps']}, 18:27:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:27:46 INFO - 'tests': ['dromaeo_dom']}, 18:27:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:27:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:27:46 INFO - 'tests': ['basic_compositor_video']}, 18:27:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:27:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:27:46 INFO - 'tests': ['a11yr', 18:27:46 INFO - 'ts_paint', 18:27:46 INFO - 'tpaint', 18:27:46 INFO - 'sessionrestore', 18:27:46 INFO - 'sessionrestore_no_auto_restore', 18:27:46 INFO - 'tabpaint']}, 18:27:46 INFO - 'other-e10s': {'tests': ['a11yr', 18:27:46 INFO - 'ts_paint', 18:27:46 INFO - 'tpaint', 18:27:46 INFO - 'sessionrestore', 18:27:46 INFO - 'sessionrestore_no_auto_restore', 18:27:46 INFO - 'tabpaint']}, 18:27:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:27:46 INFO - 'ts_paint', 18:27:46 INFO - 'tpaint', 18:27:46 INFO - 'sessionrestore', 18:27:46 INFO - 'sessionrestore_no_auto_restore', 18:27:46 INFO - 'tabpaint']}, 18:27:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:27:46 INFO - 'ts_paint', 18:27:46 INFO - 'tpaint', 18:27:46 INFO - 'sessionrestore', 18:27:46 INFO - 'sessionrestore_no_auto_restore', 18:27:46 INFO - 'tabpaint']}, 18:27:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:27:46 INFO - 'tests': ['a11yr', 18:27:46 INFO - 'ts_paint', 18:27:46 INFO - 'tpaint', 18:27:46 INFO - 'sessionrestore', 18:27:46 INFO - 'sessionrestore_no_auto_restore', 18:27:46 INFO - 'tabpaint']}, 18:27:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:27:46 INFO - 'tests': ['a11yr', 18:27:46 INFO - 'ts_paint', 18:27:46 INFO - 'tpaint', 18:27:46 INFO - 'sessionrestore', 18:27:46 INFO - 'sessionrestore_no_auto_restore', 18:27:46 INFO - 'tabpaint']}, 18:27:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:27:46 INFO - 'tests': ['tsvgx', 18:27:46 INFO - 'tsvgr_opacity', 18:27:46 INFO - 'tart', 18:27:46 INFO - 'tscrollx', 18:27:46 INFO - 'cart']}, 18:27:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:27:46 INFO - 'tsvgr_opacity', 18:27:46 INFO - 'tart', 18:27:46 INFO - 'tscrollx', 18:27:46 INFO - 'cart']}, 18:27:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'talos_options': ['--disable-e10s'], 18:27:46 INFO - 'tests': ['tp5o']}, 18:27:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'tests': ['tp5o']}, 18:27:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'talos_options': ['--disable-e10s', 18:27:46 INFO - '--xperf_path', 18:27:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:27:46 INFO - 'tests': ['tp5n']}, 18:27:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:27:46 INFO - 'talos_options': ['--xperf_path', 18:27:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:27:46 INFO - 'tests': ['tp5n']}}, 18:27:46 INFO - 'talos.zip': {'path': '', 18:27:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:27:46 INFO - Running post-action listener: _resource_record_post_action 18:27:46 INFO - [mozharness: 2016-09-09 01:27:46.638843Z] Finished populate-webroot step (success) 18:27:46 INFO - [mozharness: 2016-09-09 01:27:46.638952Z] Running create-virtualenv step. 18:27:46 INFO - Running pre-action listener: _resource_record_pre_action 18:27:46 INFO - Running main action method: create_virtualenv 18:27:46 INFO - Creating virtualenv /builds/slave/test/build/venv 18:27:46 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:27:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:27:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:27:46 INFO - Using real prefix '/tools/python27' 18:27:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:27:47 INFO - Installing distribute.............................................................................................................................................................................................done. 18:27:50 INFO - Installing pip.................done. 18:27:50 INFO - Return code: 0 18:27:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b66618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa411d9aed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35d30>, '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 0x10179adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a2db20>, '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.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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:27:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:27:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:27:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZPVcrFvsMe/Render', 18:27:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:50 INFO - 'HOME': '/Users/cltbld', 18:27:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:50 INFO - 'LOGNAME': 'cltbld', 18:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:50 INFO - 'MOZ_NO_REMOTE': '1', 18:27:50 INFO - 'NO_EM_RESTART': '1', 18:27:50 INFO - 'PAGER': '/bin/cat', 18:27:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:50 INFO - 'PWD': '/builds/slave/test', 18:27:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:50 INFO - 'SHELL': '/bin/bash', 18:27:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e5C7XYgmZ7/Listeners', 18:27:50 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:27:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:50 INFO - 'USER': 'cltbld', 18:27:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:50 INFO - 'XPC_FLAGS': '0x0', 18:27:50 INFO - 'XPC_SERVICE_NAME': '0', 18:27:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:50 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:27:50 INFO - Cleaning up... 18:27:50 INFO - Return code: 0 18:27:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101ad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b66618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa411d9aed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35d30>, '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 0x10179adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a2db20>, '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.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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:27:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:27:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:27:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZPVcrFvsMe/Render', 18:27:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:50 INFO - 'HOME': '/Users/cltbld', 18:27:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:50 INFO - 'LOGNAME': 'cltbld', 18:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:50 INFO - 'MOZ_NO_REMOTE': '1', 18:27:50 INFO - 'NO_EM_RESTART': '1', 18:27:50 INFO - 'PAGER': '/bin/cat', 18:27:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:50 INFO - 'PWD': '/builds/slave/test', 18:27:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:50 INFO - 'SHELL': '/bin/bash', 18:27:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e5C7XYgmZ7/Listeners', 18:27:50 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:27:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:50 INFO - 'USER': 'cltbld', 18:27:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:50 INFO - 'XPC_FLAGS': '0x0', 18:27:50 INFO - 'XPC_SERVICE_NAME': '0', 18:27:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:50 INFO - Downloading/unpacking psutil>=3.1.1 18:27:50 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:27:50 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:27:50 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:27:50 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:27:50 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:27:50 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:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:27:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:27:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:27:54 INFO - Installing collected packages: psutil 18:27:54 INFO - Running setup.py install for psutil 18:27:54 INFO - building 'psutil._psutil_osx' extension 18:27:54 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:27:54 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:27:54 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:27:55 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:27:55 INFO - building 'psutil._psutil_posix' extension 18:27:55 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:27:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:27:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:27:55 INFO - ^ 18:27:55 INFO - 1 warning generated. 18:27:55 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:27:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:27:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:27:55 INFO - Successfully installed psutil 18:27:55 INFO - Cleaning up... 18:27:55 INFO - Return code: 0 18:27:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:27:55 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:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:55 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:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27:55 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 0x101ad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b66618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa411d9aed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35d30>, '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 0x10179adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a2db20>, '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.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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:27:55 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:27:55 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:27:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZPVcrFvsMe/Render', 18:27:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:55 INFO - 'HOME': '/Users/cltbld', 18:27:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:55 INFO - 'LOGNAME': 'cltbld', 18:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:55 INFO - 'MOZ_NO_REMOTE': '1', 18:27:55 INFO - 'NO_EM_RESTART': '1', 18:27:55 INFO - 'PAGER': '/bin/cat', 18:27:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:55 INFO - 'PWD': '/builds/slave/test', 18:27:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:55 INFO - 'SHELL': '/bin/bash', 18:27:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e5C7XYgmZ7/Listeners', 18:27:55 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:27:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:55 INFO - 'USER': 'cltbld', 18:27:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:55 INFO - 'XPC_FLAGS': '0x0', 18:27:55 INFO - 'XPC_SERVICE_NAME': '0', 18:27:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:27:55 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:27:55 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:27:55 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:27:55 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:27:55 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:27:55 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:27:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:27:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:27:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:27:58 INFO - Installing collected packages: mozsystemmonitor 18:27:58 INFO - Running setup.py install for mozsystemmonitor 18:27:58 INFO - Successfully installed mozsystemmonitor 18:27:58 INFO - Cleaning up... 18:27:58 INFO - Return code: 0 18:27:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:27: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:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:27:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27: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:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:27:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:27: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', '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 0x101ad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b66618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa411d9aed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35d30>, '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 0x10179adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a2db20>, '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.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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:27: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:27: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 jsonschema==2.5.1 18:27:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZPVcrFvsMe/Render', 18:27:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:58 INFO - 'HOME': '/Users/cltbld', 18:27:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:58 INFO - 'LOGNAME': 'cltbld', 18:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:58 INFO - 'MOZ_NO_REMOTE': '1', 18:27:58 INFO - 'NO_EM_RESTART': '1', 18:27:58 INFO - 'PAGER': '/bin/cat', 18:27:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:58 INFO - 'PWD': '/builds/slave/test', 18:27:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:58 INFO - 'SHELL': '/bin/bash', 18:27:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e5C7XYgmZ7/Listeners', 18:27:58 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:27:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:58 INFO - 'USER': 'cltbld', 18:27:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:58 INFO - 'XPC_FLAGS': '0x0', 18:27:58 INFO - 'XPC_SERVICE_NAME': '0', 18:27:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:27:58 INFO - Downloading/unpacking jsonschema==2.5.1 18:27: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:27: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:27: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:27: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:27: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:27: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:28:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:28:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:28: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:28: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:28: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:28: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:28: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:28: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:28:01 INFO - Downloading functools32-3.2.3-2.tar.gz 18:28:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:28:01 INFO - warning: no files found matching '*.txt' 18:28:01 INFO - no previously-included directories found matching 'build' 18:28:01 INFO - no previously-included directories found matching 'dist' 18:28:01 INFO - no previously-included directories found matching '.git*' 18:28:01 INFO - Installing collected packages: jsonschema, functools32 18:28:01 INFO - Running setup.py install for functools32 18:28:01 INFO - warning: no files found matching '*.txt' 18:28:01 INFO - no previously-included directories found matching 'build' 18:28:01 INFO - no previously-included directories found matching 'dist' 18:28:01 INFO - no previously-included directories found matching '.git*' 18:28:01 INFO - Successfully installed jsonschema functools32 18:28:01 INFO - Cleaning up... 18:28:02 INFO - Return code: 0 18:28:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:28:02 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:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:02 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:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:02 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 0x101ad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b66618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa411d9aed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35d30>, '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 0x10179adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a2db20>, '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.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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:28:02 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:28:02 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:28:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZPVcrFvsMe/Render', 18:28:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:02 INFO - 'HOME': '/Users/cltbld', 18:28:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:02 INFO - 'LOGNAME': 'cltbld', 18:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:02 INFO - 'MOZ_NO_REMOTE': '1', 18:28:02 INFO - 'NO_EM_RESTART': '1', 18:28:02 INFO - 'PAGER': '/bin/cat', 18:28:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:02 INFO - 'PWD': '/builds/slave/test', 18:28:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:02 INFO - 'SHELL': '/bin/bash', 18:28:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e5C7XYgmZ7/Listeners', 18:28:02 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:28:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:02 INFO - 'USER': 'cltbld', 18:28:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:02 INFO - 'XPC_FLAGS': '0x0', 18:28:02 INFO - 'XPC_SERVICE_NAME': '0', 18:28:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:28:02 INFO - Cleaning up... 18:28:02 INFO - Return code: 0 18:28:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:28:02 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:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:02 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:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:02 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 0x101ad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b66618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa411d9aed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35d30>, '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 0x10179adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a2db20>, '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.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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:28:02 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:28:02 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:28:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZPVcrFvsMe/Render', 18:28:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:02 INFO - 'HOME': '/Users/cltbld', 18:28:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:02 INFO - 'LOGNAME': 'cltbld', 18:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:02 INFO - 'MOZ_NO_REMOTE': '1', 18:28:02 INFO - 'NO_EM_RESTART': '1', 18:28:02 INFO - 'PAGER': '/bin/cat', 18:28:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:02 INFO - 'PWD': '/builds/slave/test', 18:28:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:02 INFO - 'SHELL': '/bin/bash', 18:28:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e5C7XYgmZ7/Listeners', 18:28:02 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:28:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:02 INFO - 'USER': 'cltbld', 18:28:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:02 INFO - 'XPC_FLAGS': '0x0', 18:28:02 INFO - 'XPC_SERVICE_NAME': '0', 18:28:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:02 INFO - Downloading/unpacking blobuploader==1.2.4 18:28:02 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:28:02 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:28:02 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:28:02 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:28:02 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:28:02 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:28:05 INFO - Downloading blobuploader-1.2.4.tar.gz 18:28:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:28:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:28: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:28: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:28: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:28: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:28: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:28: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:28:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:28:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:28: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:28: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:28: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:28: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:28: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:28: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:28:06 INFO - Downloading docopt-0.6.1.tar.gz 18:28:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:28:06 INFO - Installing collected packages: blobuploader, requests, docopt 18:28:06 INFO - Running setup.py install for blobuploader 18:28:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:28:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:28:06 INFO - Running setup.py install for requests 18:28:06 INFO - Running setup.py install for docopt 18:28:07 INFO - Successfully installed blobuploader requests docopt 18:28:07 INFO - Cleaning up... 18:28:07 INFO - Return code: 0 18:28:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:28:07 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:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:07 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:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:07 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 0x101ad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b66618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa411d9aed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35d30>, '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 0x10179adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a2db20>, '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.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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:28:07 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:28:07 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:28:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZPVcrFvsMe/Render', 18:28:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:07 INFO - 'HOME': '/Users/cltbld', 18:28:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:07 INFO - 'LOGNAME': 'cltbld', 18:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:07 INFO - 'MOZ_NO_REMOTE': '1', 18:28:07 INFO - 'NO_EM_RESTART': '1', 18:28:07 INFO - 'PAGER': '/bin/cat', 18:28:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:07 INFO - 'PWD': '/builds/slave/test', 18:28:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:07 INFO - 'SHELL': '/bin/bash', 18:28:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e5C7XYgmZ7/Listeners', 18:28:07 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:28:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:07 INFO - 'USER': 'cltbld', 18:28:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:07 INFO - 'XPC_FLAGS': '0x0', 18:28:07 INFO - 'XPC_SERVICE_NAME': '0', 18:28:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:28:07 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-WdZ6K0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:28:07 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-lWHBNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:28:07 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-HHVxG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:28:07 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-JOmOUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:28:07 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-0FluE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:28:07 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-6wg9kA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:28:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-2IYAvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:28:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-8IX07O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:28:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-k4nZaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:28:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-2Gohat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:28:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-NxGdRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:28:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-aOpA00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:28:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-l5oI9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:28:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-gmBbn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-1maFi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:28:08 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-TF7L4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:28:09 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-qQ0Pph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:28:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:28:09 INFO - Running setup.py install for manifestparser 18:28:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:28:09 INFO - Running setup.py install for mozcrash 18:28:09 INFO - Running setup.py install for mozdebug 18:28:09 INFO - Running setup.py install for mozdevice 18:28:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:28:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:28:09 INFO - Running setup.py install for mozfile 18:28:09 INFO - Running setup.py install for mozhttpd 18:28:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:28:10 INFO - Running setup.py install for mozinfo 18:28:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:28:10 INFO - Running setup.py install for mozInstall 18:28:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:28:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:28:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:28:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:28:10 INFO - Running setup.py install for mozleak 18:28:10 INFO - Running setup.py install for mozlog 18:28:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:28:10 INFO - Running setup.py install for moznetwork 18:28:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:28:10 INFO - Running setup.py install for mozprocess 18:28:10 INFO - Running setup.py install for mozprofile 18:28:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:28:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:28:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:28:11 INFO - Running setup.py install for mozrunner 18:28:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:28:11 INFO - Running setup.py install for mozscreenshot 18:28:11 INFO - Running setup.py install for moztest 18:28:11 INFO - Running setup.py install for mozversion 18:28:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:28:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:28:11 INFO - Cleaning up... 18:28:11 INFO - Return code: 0 18:28:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:28:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:11 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 0x101ad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b66618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa411d9aed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35d30>, '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 0x10179adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a2db20>, '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.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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:28:11 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:28:11 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:28:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZPVcrFvsMe/Render', 18:28:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:11 INFO - 'HOME': '/Users/cltbld', 18:28:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:11 INFO - 'LOGNAME': 'cltbld', 18:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:11 INFO - 'MOZ_NO_REMOTE': '1', 18:28:11 INFO - 'NO_EM_RESTART': '1', 18:28:11 INFO - 'PAGER': '/bin/cat', 18:28:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:11 INFO - 'PWD': '/builds/slave/test', 18:28:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:11 INFO - 'SHELL': '/bin/bash', 18:28:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e5C7XYgmZ7/Listeners', 18:28:11 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:28:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:11 INFO - 'USER': 'cltbld', 18:28:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:11 INFO - 'XPC_FLAGS': '0x0', 18:28:11 INFO - 'XPC_SERVICE_NAME': '0', 18:28:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:28:11 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-0UY8tX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:28:12 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:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:28:12 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-oflmCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:28:12 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:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:28:12 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-RsE0P2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:28:12 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:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:28:12 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-BvOFSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:28:12 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:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:28:12 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-XwyMHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:28:12 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:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:28:12 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-akQyxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:28:12 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:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:28:12 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-V_j8ms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:28:12 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:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:28:12 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-tCLIJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:28:12 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:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:28:12 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-rGE0kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:28:12 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:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:28:12 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-qC8ahC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:28:13 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:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:28:13 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-TCjXgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:28:13 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:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:28:13 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-s7kufT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:28:13 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:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:28:13 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-zs5c94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:28:13 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:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:28:13 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-bXr_GL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:28:13 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:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:13 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-fkQT3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:28:13 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:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:28:13 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-2hnSw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:28:13 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:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:28:13 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-13xWdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:28:13 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:28:13 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:28:13 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:28:13 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:28:13 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:28:13 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:28:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:28:13 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:28:13 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:28:13 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:28:13 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:28:13 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:28:13 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:28:16 INFO - Downloading blessings-1.6.tar.gz 18:28:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:28:16 INFO - Installing collected packages: blessings 18:28:16 INFO - Running setup.py install for blessings 18:28:16 INFO - Successfully installed blessings 18:28:16 INFO - Cleaning up... 18:28:16 INFO - Return code: 0 18:28:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:28:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:28:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:28:16 INFO - Reading from file tmpfile_stdout 18:28:16 INFO - Current package versions: 18:28:16 INFO - blessings == 1.6 18:28:16 INFO - blobuploader == 1.2.4 18:28:16 INFO - docopt == 0.6.1 18:28:16 INFO - functools32 == 3.2.3-2 18:28:16 INFO - jsonschema == 2.5.1 18:28:16 INFO - manifestparser == 1.1 18:28:16 INFO - mozInstall == 1.12 18:28:16 INFO - mozcrash == 0.17 18:28:16 INFO - mozdebug == 0.1 18:28:16 INFO - mozdevice == 0.48 18:28:16 INFO - mozfile == 1.2 18:28:16 INFO - mozhttpd == 0.7 18:28:16 INFO - mozinfo == 0.9 18:28:16 INFO - mozleak == 0.1 18:28:16 INFO - mozlog == 3.3 18:28:16 INFO - moznetwork == 0.27 18:28:16 INFO - mozprocess == 0.23 18:28:16 INFO - mozprofile == 0.28 18:28:16 INFO - mozrunner == 6.12 18:28:16 INFO - mozscreenshot == 0.1 18:28:16 INFO - mozsystemmonitor == 0.3 18:28:16 INFO - moztest == 0.7 18:28:16 INFO - mozversion == 1.4 18:28:16 INFO - psutil == 3.1.1 18:28:16 INFO - requests == 1.2.3 18:28:16 INFO - wsgiref == 0.1.2 18:28:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:28:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:16 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 0x101ad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b66618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa411d9aed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35d30>, '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 0x10179adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a2db20>, '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.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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:28:16 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:28:16 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:28:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZPVcrFvsMe/Render', 18:28:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:16 INFO - 'HOME': '/Users/cltbld', 18:28:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:16 INFO - 'LOGNAME': 'cltbld', 18:28:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:16 INFO - 'MOZ_NO_REMOTE': '1', 18:28:16 INFO - 'NO_EM_RESTART': '1', 18:28:16 INFO - 'PAGER': '/bin/cat', 18:28:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:16 INFO - 'PWD': '/builds/slave/test', 18:28:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:16 INFO - 'SHELL': '/bin/bash', 18:28:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e5C7XYgmZ7/Listeners', 18:28:16 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:28:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:16 INFO - 'USER': 'cltbld', 18:28:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:16 INFO - 'XPC_FLAGS': '0x0', 18:28:16 INFO - 'XPC_SERVICE_NAME': '0', 18:28:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:17 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:28:17 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:28:17 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:28:17 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:28:17 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:28:17 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:28:17 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:28:17 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:28:17 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:28:17 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:28:17 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:28:17 INFO - Cleaning up... 18:28:17 INFO - Return code: 0 18:28:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:28:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:28:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:28:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:28:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:28:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b66618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa411d9aed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35d30>, '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 0x10179adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a2db20>, '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.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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:28:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:28:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:28:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZPVcrFvsMe/Render', 18:28:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:17 INFO - 'HOME': '/Users/cltbld', 18:28:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:17 INFO - 'LOGNAME': 'cltbld', 18:28:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:17 INFO - 'MOZ_NO_REMOTE': '1', 18:28:17 INFO - 'NO_EM_RESTART': '1', 18:28:17 INFO - 'PAGER': '/bin/cat', 18:28:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:17 INFO - 'PWD': '/builds/slave/test', 18:28:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:17 INFO - 'SHELL': '/bin/bash', 18:28:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e5C7XYgmZ7/Listeners', 18:28:17 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:28:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:17 INFO - 'USER': 'cltbld', 18:28:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:17 INFO - 'XPC_FLAGS': '0x0', 18:28:17 INFO - 'XPC_SERVICE_NAME': '0', 18:28:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:28:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:28:17 INFO - Cleaning up... 18:28:17 INFO - Return code: 0 18:28:17 INFO - Running post-action listener: _resource_record_post_action 18:28:17 INFO - Running post-action listener: _start_resource_monitoring 18:28:17 INFO - Starting resource monitoring. 18:28:17 INFO - [mozharness: 2016-09-09 01:28:17.400875Z] Finished create-virtualenv step (success) 18:28:17 INFO - [mozharness: 2016-09-09 01:28:17.401811Z] Running install step. 18:28:17 INFO - Running pre-action listener: _resource_record_pre_action 18:28:17 INFO - Running main action method: install 18:28:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:28:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:28:17 INFO - Reading from file tmpfile_stdout 18:28:17 INFO - Detecting whether we're running mozinstall >=1.0... 18:28:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:28:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:28:17 INFO - Reading from file tmpfile_stdout 18:28:17 INFO - Output received: 18:28:17 INFO - Usage: mozinstall [options] installer 18:28:17 INFO - Options: 18:28:17 INFO - -h, --help show this help message and exit 18:28:17 INFO - -d DEST, --destination=DEST 18:28:17 INFO - Directory to install application into. [default: 18:28:17 INFO - "/builds/slave/test"] 18:28:17 INFO - --app=APP Application being installed. [default: firefox] 18:28:17 INFO - mkdir: /builds/slave/test/build/application 18:28:17 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:28:17 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:28:51 INFO - Reading from file tmpfile_stdout 18:28:51 INFO - Output received: 18:28:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:28:51 INFO - Running post-action listener: _resource_record_post_action 18:28:51 INFO - [mozharness: 2016-09-09 01:28:51.991058Z] Finished install step (success) 18:28:51 INFO - [mozharness: 2016-09-09 01:28:51.991246Z] Running run-tests step. 18:28:51 INFO - Running pre-action listener: _resource_record_pre_action 18:28:51 INFO - Running main action method: run_tests 18:28:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:28:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:28:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:28:52 INFO - 2016-09-08 18:28:52.005 screenresolution[1643:7928] starting screenresolution argv=screenresolution get 18:28:52 INFO - 2016-09-08 18:28:52.008 screenresolution[1643:7928] Display 0: 1600x1200x32@60 18:28:52 INFO - 2016-09-08 18:28:52.014 screenresolution[1644:7930] starting screenresolution argv=screenresolution list 18:28:52 INFO - Available Modes on Display 0 18:28:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:28:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:28:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:28:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:28:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:28:52 INFO - Intel Iris: 18:28:52 INFO - Chipset Model: Intel Iris 18:28:52 INFO - Type: GPU 18:28:52 INFO - Bus: Built-In 18:28:52 INFO - VRAM (Dynamic, Max): 1536 MB 18:28:52 INFO - Vendor: Intel (0x8086) 18:28:52 INFO - Device ID: 0x0a2e 18:28:52 INFO - Revision ID: 0x0009 18:28:52 INFO - Displays: 18:28:52 INFO - Display: 18:28:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:28:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:28:52 INFO - Display Serial Number: 1600x1200 60 18:28:52 INFO - Main Display: Yes 18:28:52 INFO - Mirror: Off 18:28:52 INFO - Online: Yes 18:28:52 INFO - Rotation: Supported 18:28:52 INFO - Return code: 0 18:28:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:28:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:28:52 INFO - Python 2.7.3 18:28:52 INFO - Return code: 0 18:28:52 INFO - grabbing minidump binary from tooltool 18:28:52 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:28:52 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 0x101a35d30>, '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 0x10179adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a2db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:28:52 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:28:52 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:28:52 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:28:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:28:52 INFO - Return code: 0 18:28:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:28:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:28:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:28:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:28:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:28:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--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:28:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0025 --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:28:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZPVcrFvsMe/Render', 18:28:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:28:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:28:52 INFO - 'HOME': '/Users/cltbld', 18:28:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:28:52 INFO - 'LOGNAME': 'cltbld', 18:28:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:28:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:52 INFO - 'MOZ_NO_REMOTE': '1', 18:28:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:28:52 INFO - 'NO_EM_RESTART': '1', 18:28:52 INFO - 'PAGER': '/bin/cat', 18:28:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:52 INFO - 'PWD': '/builds/slave/test', 18:28:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:28:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:28:52 INFO - 'SHELL': '/bin/bash', 18:28:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e5C7XYgmZ7/Listeners', 18:28:52 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 18:28:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:28:52 INFO - 'USER': 'cltbld', 18:28:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:28:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:52 INFO - 'XPC_FLAGS': '0x0', 18:28:52 INFO - 'XPC_SERVICE_NAME': '0', 18:28:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:28:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--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:28:52 INFO - mozversion application_buildid: 20160908153031 18:28:52 INFO - mozversion application_changeset: 4cb8b7cc27e113f269ab8ecede30f832ffede2dc 18:28:52 INFO - mozversion application_display_name: Nightly 18:28:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:28:52 INFO - mozversion application_name: Firefox 18:28:52 INFO - mozversion application_remotingname: firefox 18:28:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:28:52 INFO - mozversion application_vendor: Mozilla 18:28:52 INFO - mozversion application_version: 51.0a1 18:28:52 INFO - mozversion platform_buildid: 20160908153031 18:28:52 INFO - mozversion platform_changeset: 4cb8b7cc27e113f269ab8ecede30f832ffede2dc 18:28:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:28:52 INFO - mozversion platform_version: 51.0a1 18:28:52 INFO - using testdate: 1473384532 18:28:52 INFO - actual date: 1473384532 18:28:52 INFO - starting webserver on 'localhost:49267' 18:28:52 INFO - SUITE-START | Running 2 tests 18:28:52 INFO - TEST-START | dromaeo_css 18:28:52 INFO - operating with platform_type : mac_ 18:28:52 INFO - Initialising browser for dromaeo_css test... 18:28:52 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpOSQCPP/profile http://localhost:49267/getInfo.html) 18:28:55 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 18:28:55 INFO - PROCESS | 1654 | colorDepth:24 18:28:55 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 18:28:55 INFO - PROCESS | 1654 | __metrics 18:28:55 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 18:28:56 INFO - TEST-INFO | 1654: exit 0 18:28:56 INFO - Browser initialized. 18:28:56 INFO - Running cycle 1/1 for dromaeo_css test... 18:28:56 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpOSQCPP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:29:08 INFO - PROCESS | 1658 | RSS: Main: 243904512 18:29:08 INFO - PROCESS | 1658 | 18:29:57 INFO - PROCESS | 1658 | 1473384597709 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] 18:32:09 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 18:32:11 INFO - PROCESS | 1658 | RSS: Main: 264581120 18:32:11 INFO - PROCESS | 1658 | 18:35:13 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 18:35:14 INFO - PROCESS | 1658 | RSS: Main: 272269312 18:35:14 INFO - PROCESS | 1658 | 18:38:16 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 18:38:16 INFO - PROCESS | 1658 | RSS: Main: 272826368 18:38:16 INFO - PROCESS | 1658 | 18:41:18 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 18:41:19 INFO - PROCESS | 1658 | RSS: Main: 268967936 18:41:19 INFO - PROCESS | 1658 | 18:44:22 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 18:44:22 INFO - PROCESS | 1658 | RSS: Main: 275939328 18:44:22 INFO - PROCESS | 1658 | 18:47:25 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 18:47:25 INFO - PROCESS | 1658 | RSS: Main: 278867968 18:47:25 INFO - PROCESS | 1658 | 18:47:25 INFO - PROCESS | 1658 | __start_tp_report 18:47:25 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 18:47:25 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 18:47:25 INFO - PROCESS | 1658 | |i|pagename|runs| 18:47:25 INFO - PROCESS | 1658 | |0;dojo.html;3149.952750708739;3397.4224381855083;3427.79433234006;3451.1544671555466;3462.359463499253;5682.857928551788;5787.855303617412;5812.360640329561;5915.911261331078;5955.2853657561045;5811.73847176877;5831.416858314177;5836.270466191726;5860.736266867991;5965.075413310944;5222.112240919053;5356.437574054727;5382.461753824606;5400.567954563626;5407.810726624569;5213.739313034352;5294.2852714883575;5345.091334473149;5398.271233383502;5401.729913504329;5242;5244.396894357141;5269.446708095633;5282.4189339173045;5296.549793267577;5266.183741520071;5271.551918086967;5292.497212764782;5294.867628309323;5353.946460535384;5139.871503212449;5241.318628578299;5256.526912577886;5258.921116183222;5300.072487314705;4363.759993200372;5838.299404071496;5861.88276234473;5937.881242375129;5966.791162309299;6221.253449586034;6247.125402443661;6264.9686751566405;6268.65522396268;6271.655058971754;6190.597611155261;6197.25632924052;6234.282064588235;6313.210848643919;6326.335734747877;5889.7644094236175;5982.102684597346;6012.669303188279;6024.728887200086;6046.3349031606485;6355.713992870332;6363.459105975951;6388.361163883575;6397.552171348054;6434.678266086677;6124.938750612437;6172.845678858064;6294.748210071637;6298.02380631003;6305.527085468608;6187.443130118311;6289.842753931189;6297.244330680348;6298.779542715983;6301.52738544611;6218.19163508741;6235.875282494325;6263.6241825490615;6273.498120150377;6288.654124023131;6130.826205142326;6136.73353236607;6249.375062493714;6262.467690246289;6269.153664255358;5016.473270306638;5036.471170527115;5059.342285502861;5090.516400941905;5098.413682426569;4474.328850672425;4500.257457519473;4507.2788353863225;4511.932321015187;4587.197240482902;4381.518033016366;4668.836590719308;5203.6470517665675;5826.388229235954;5895.55783316253;5289.153735402318;6198.659073750987;6236.251649802053;6283.403076707706;6284.937150628527;5082.771275292621;5871.647701137946;5971.9701401493585;6017.548683848729;6019.127226552126;4660.953390466052;5425.077736784756;5467.17992301158;5555.694436805934;5592.328920529481;6287.836750201227;6322.462590679752;6478.5141114415665;6490.4807615390655;6533.542652014408;5378.865528361822;6279.246490421178;6280.717367718372;6284.528660350401;6296.653684047421;6231.657258850625;6447.161868957006;6475.061116138039;6475.158229430144;6500.772472963424;6143.938560614515;6156.507479401726;6228.408301211559;6242.719077641608;6251.9687401562705;6145.539084568586;6258.937410625836;6270.968645156745;6277.780277690259;6295.685215739286;6118.388161183846;6143.757528502154;6255.437010668879;6264.686765661608;6269.749210031548;6145.907811382744;6210.161628180138;6221.253449586078;6256.749730010931;6275.6548389839;5955.880882382415;6065.181200537871;6070.757169713162;6089.147519347206;6150.386085913558;6075.453209211192;6140.596275277727;6213.568956130383;6267.310595834546;6304.810855674337;6097.5731698780655;6105.995946598671;6140.718981675845;6149.692515374303;6153.784617538365 18:47:25 INFO - PROCESS | 1658 | |1;ext.html;471.76647559458075;500.1147968096467;508.07023147639813;522.4696932613398;528.3817933018369;31306.373872522538;39908.60091399085;40015.599844001554;40096.599034009654;40132;40483.59516404835;41078.383824242585;41121;41143.38284925729;41253.96865078375;8283.544405057719;9457.479838608857;9644.807103857918;9654.565544550476;9711.077447642463;9553;9664.80670386592;9678.612855485771;9681.806363872718;9705.563249653782;9672.274579406538;9687.903120968806;9688.515574221296;9705.175060119898;9729.854052189223;8988.865167022435;9013.774655633597;9616.519174041305;9677.274204434696;9677.370970886905;9515.048495150499;9610.038996100404;9621.422714637074;9624.278179136592;9624.759381015461;39038.414423783666;39094;39289.01777455558;39447.8027609863;39565.40651890224;2961.741259964528;3264.3144939562485;3336.366090442808;3723.8138093095235;3839.6352346527037;25767.484650306887;27410.90356385742;30520.452360421426;31071.533926991113;31344.529832052536;2788.344738986337;3190.7925984810913;3195.1692559934586;3244.4646633305474;3304.5538852254876;416532;417885;418047;418096;419119;32155.51766723501;45744.54255457469;56614.7169264151;56930.146047809314;57070.7146464265;32710.83644581762;33734;34085.48871766926;34449.827750861084;34461.48307775385;14384.928075359556;14452.349644266033;14545.98178127542;14554;14580.416783328548;12266.938665306794;12305.138640295272;12337;12453.505579330538;12460.314682692358;6997.370236678723;7576.204498527685;7591.962040189874;7613.314801667876;7661.233876612406;2987.536931775578;3181.618205815317;3195.0414875537244;3195.440797860365;3207.294395233045;841.2554888923347;919.2645883293338;931.9608636370398;940.9623615055458;961.2118063188119;29076.91154048033;29565.547296588666;29883.25761618152;30131.548026779616;30211.244718881764;991.7768502086973;1045.1220974381556;1073.313079629038;1112.3270421395102;1123.662901129658;15024.17367044823;15396.153211573246;15484;15530.903328050794;15533.84466155324;416617;509138;555456;561069;570227;44579.33131003038;55408.44591554033;55513;56031;56049.159262611094;1145.2613064573304;1299.2594221293962;1320.386020500472;1356.1014579588655;1360.6462319796924;27943.72056279411;29137.417251654424;29216.26959326034;29825.70174298316;29879.402411951203;42673.78663106664;43054.35418468663;47772;47841.04317913553;47898;451393;453001;487603;513719;515926;25522.489550209262;25816.225513234633;26111.73888261093;26727.86636066885;26885;45534.54465455435;46072.539274606825;46175.53824461712;46352.768236158605;47106;32531.837340813145;38405.61594384132;38421.61578384292;38927.02670528916;39649.2070158601;38537.614623853406;38790.6120938787;39590.802045989585;39841.40237896492;39935;37270.44094338533;37443.43834842425;37758.43362349512;38254.808725956194;38405.61594384132;26374.604380934685;34692;34812.12969675779;34952.65047349494;35061.47407788834;25393;25400.74599253984;25457.74542254628;25560.616590750782;26056 18:47:25 INFO - PROCESS | 1658 | |2;jquery.html;368.274499236504;369.30386221971594;374.7096000599536;377.55825683949774;395.0439935356437;19473.928933908646;20434.386968390954;20762.16951321949;20845.37463876084;21051.2632057878;19847.30534431295;20232.89883550584;20480.975951202454;20537.383878483633;20809.959502024914;5217.452167522412;5863.413658634135;5993.700314984255;6093.756249750027;6099.878002439948;5254.738862672969;5985.461308482236;6052.667103309316;6085.117657939596;6123.969380153093;5733.455321744448;5911.3201981772;5942.257217847769;5954.761809527614;5956.768109050549;5167.741612919358;5671.943280567224;5693.459121383462;5955.076963070731;5984.281886173643;5113.309703190059;5150.613703972217;5151.5363617274625;5157.097507936133;5192.402873669538;23609.40976475585;25471.235862924143;25704.357391065187;25959.091431799796;26153;2220.744614369352;2255.0866898905806;2279.601069812776;2354.7409784923643;2393.892274847636;9953.85069223955;10066.144377727902;10088.546015429323;10305.12406445453;10492.475376231234;1828.6708392489347;1831.496338506908;1866.2908094923841;1896.9620607587908;1919.3090487424515;123430.38284808518;127905;129242.3537882314;130917;131822.99999999904;10830.566777328977;11395.829062564067;11414.029807466291;11415.60045398407;11847.881521184678;11866.288022718665;12279.754404911851;12762.489500419879;13631.113977591425;14275.14349139055;8184.713535026364;8450.070492245848;8462.746117616482;8471.95764021176;8506.235473471146;8385.203405676451;8425.873611895826;8468.703595374132;8556.058833528306;8581.184787445183;3571.624979377128;3634.582023067328;4405.757683327448;4520.706154099973;4597.011642496879;1851.1669748613178;1907.818757218062;1908.532409559639;1908.866379353432;1910.522369407648;805.4603415711488;873.7204094689765;899.8830152080187;947.3226642950186;968.1190116993503;19306.71039934402;20778.84423115383;20912.68630970537;20918.79081209199;20957.68563471549;894.7449976756724;943.4103685196752;945.9716208513755;950.2493030505962;966.5553845231273;10537.157027437786;10908.018278354986;10940;10953;10953.673299046832;534377;535854;541988;544286;551158;97823.0217697814;121934.3903280478;131182.34408827894;132972;133541.3322933379;948.1182500274673;959.4531117263233;1018.6536577563515;1023.3859684189426;1024.52871679027;16593.66812663764;18264.81735182631;19753.012349382185;19873.503162421057;19974.50063748418;51293.48706512887;56545.43454565402;56603;56821.71589142028;58418.4158158413;84540.57729711312;85293.57353213443;85303.5734821322;86328.56835716033;86497;6809.5233333666165;7932.16712245206;8123.47197432165;8231.053428855757;8430;11071.778564428505;12508.249505029728;12880.291583962971;12926.095173337777;13312.134711243738;10779.48420747632;11232.887671123184;11372.829407559058;11415.942920285346;11530.59642912494;10708.571657133627;11316.20786544934;11433.942830285796;11540.596079137189;11805;11417.657470275904;11517.596884109016;11914.344711040976;11958.760824783629;12042.976347010252;8136.674533018613;8182.959085204536;8236.50580965144;8356.955380577429;8401.32789376836;10000.399976001463;11386.487608057823;11704.94147529291;11706.648800535997;11820.70448238801 18:47:25 INFO - PROCESS | 1658 | |3;mootools.html;1501.294391635931;1519.4681861348527;1708.5045336852313;1830.6796310645634;1851.3520267906242;7882.172371900954;8439.240468357846;8533.146685331463;9018.909810901898;9058.411203271791;7832.647530861111;8389.91610083899;8733.170348816868;8741.388102832805;8759.386842920998;1931.0537836460173;2269.6822444857735;2371.9762802372015;2375.904963801446;2386.7016622922133;2336.0889253191267;2369.3958040699686;2382.5711371953043;2382.8212884033765;2388.773066558674;2356.739144557662;2370.9051637934463;2375.821813363996;2376.0377047295874;2379.3456799380137;2331.347222777625;2347.0494449747794;2359.044586942291;2360.681308023412;2361.728401233854;2346.530693861235;2347.542229265294;2350.318407661776;2350.576896158691;2361.055577768889;8341.249287564087;8873.023967363584;8877.73366798997;8889.377743557954;8902.821943561092;5198.636095473318;5244.39689435716;5337.306150200449;5339.839804805862;5369.436209198017;3193.7924034937655;3459.550258466383;3461.1866211440292;3628.401313783249;3641.326354624403;898.3890954151175;1047.2564479219764;1085.7339951711801;1092.6066616018227;1108.9390083545402;11639.825402618968;11671.241369310968;11706;11774.352410617412;11778.175527713063;4529.433820772403;4652;4786.042791441726;4859.829905953275;4859.878503037452;9787.510624468749;9887;9897.010298970046;9921.950390248003;9926.305158638826;5297.364316282071;5324.440933701983;5326.5472434842695;5333.5199832015305;5339.652922560022;4380.5838445347645;4756.405449318835;4847.321375007502;4882.7558622068755;4898.975505122523;3854.768713877176;3926.1362500249897;3947.980260098681;4041.131156801302;4053.1285773558825;1260.8108783682337;1264.424686767537;1272.1285919145414;1277.6933535951491;1286.1897004887048;855.794609293765;858.7123313689859;901.7700486376064;904.9592768325441;927.0821886332617;5630.155476678531;5710.828675139669;5854.648721076749;5962.672053037038;5991.940080599225;792.6987744656993;938.1603464899014;942.8774259346241;946.3990366117565;988.7379844341256;3847.826847791856;3973.562908080109;4036.374361973899;4077.062275676611;4219.894502637459;10654.840177397346;10872.075873550837;10876.510557024953;10899.073578745736;10902.291351062155;10723.195760318007;10857.728556785985;10862.239643224899;10868.130549556017;10888.128949684005;8033.156518565584;8242.464239824392;8255.711050113217;8263.99999999988;8280.58597070144;5837.503812176013;5902.881942361215;6077.149199112217;6087.299960504599;6092;9168.587413566538;9864.950675246579;10105.595776168871;10114.494275286059;10141.036601522843;9488.857667135137;10311.22665800083;10319.638812641522;10347.63783267582;10348.896511034793;6869.828254293682;7287.8542429152185;7431.516951398142;7500;7618.085829700472;8392.538410387244;8486.872696909428;8496.957515212385;8502.957485212535;8504.277136443472;7336.7798966031105;7440.73957411488;7588.165301816685;7637.579933103732;7694.730684426018;6049.455549000611;7167.820804479721;7432.814179645552;7531.510451820614;7537.585432801029;5593.049181639212;5762.971185144047;5953.719950210725;6234.220722409699;6985.511014228955;4074.714769966074;4847.466778654275;4909.214525675875;4981.750912454435;5133.2300154975555;4939.357883475125;5073.822416215415;5085.92371114441;5099.84700458972;5172.844814655567 18:47:25 INFO - PROCESS | 1658 | |4;prototype.html;258.7632316430466;273.6374304047138;307.9907602771916;312.7404254468791;314.5282076884673;4939.333190019346;5131.538161565459;5604.551635869131;5662.405447428017;5739.167820666006;5574.129323398453;5585.553155747531;5591.888162236753;5632.662040277586;5658.858528536789;3621.203335266244;3653.5067765851536;4142.585741425848;4233.872983810491;4257.297545904922;3470.826458677069;4207;4233.23801715691;4247.150569886036;4260.76565788883;3514.1917359007443;3790.5072340595684;4224.831006759726;4228.5982831630945;4248.129133527619;3273.639899611041;3456.654334566536;3558.87543935961;3767.9449754068905;4180.770057646828;3084.784065115443;3540.539729835131;3557.4663800429885;3562.447820587813;3577.302426026926;5150.587952963754;5469.453054694499;5490.176473528963;5543.8891222175735;5558.749856256438;3816.587862490896;4463.888402789924;4598.770061496912;4799.2801079837955;4815.253635686474;5147.3308469898675;5256.8685782855355;5687.886242275173;5807.622504537191;5878.323992740847;3927.6465118139217;3927.6465118139504;3930.489036425275;3948.368261078214;3963.54419241788;5123.718195499246;5878.529717622623;6230.750769969196;6252.968735156341;6299.779507717254;3627.4014787560286;3880.728349015542;3930.331843586597;3930.3711406174875;3988.3419235825763;3240.643529211785;3577.338192434408;3901.4733011043713;4034.7982600870428;4048.412980117925;3978.980105099456;4172.436721042621;4733.053389322149;4778.259369797687;4787.880302992384;5722.799702010493;5919.9936010878255;5921.703914804242;5950.285965684146;5995.190649262294;1352.263016655925;1355.4307190979614;1359.7008658095203;1384.0519244317675;1384.813050238225;697.4838619421591;818.9181081891883;835.7409203147043;835.9247667709814;840.5083026429485;5266.657667251617;5499.697516636544;5519.613627046148;5525.613207075547;5610.97194514033;5212.869678258074;5608.298962629671;5931.6441013539325;6139.785107521305;6181.258249010148;4675.392199014174;5173.5343819055715;5621.297337832771;5629.130801856447;5654.886902261932;2720.5375086235385;3043.691212778482;3209.743220542351;3212.582364292674;3232.3050544133125;5235.586391674303;5314.130369600372;5906.2495315474125;6386.808395748135;6440.388163124496;5551.3893471718075;6239.532035097386;6263.686815659199;6271.090691849748;6355.110284560165;4888.655619704538;5805.82582522525;6328.588641738273;6381.457576106083;6473.676316184266;5382.4886635769535;5802.738876750641;6070.878582428415;6144.815655530341;6194.39835269197;6121.3572574879;6135.877282454415;6155.4767844731905;6180.443760061616;6182.165407669907;6236.719347629277;6431;6488.578242414228;6513.576617519846;6577.612529547383;3457.2540866862073;3649.726270529668;4063.227986682521;4169.499660040815;4173.728707633994;5190.688558686491;5512.310961129859;5573.749181286771;5815.720541480868;6173.59871608344;4518;5402.864928376822;5791;5947.394203564927;5967.015442451947;4912.066707325596;5136.691798492103;5354;5967.776605795714;5999.220101386792;4925.6305777066145;5842.620229685057;5856.560757943086;5856.795012174553;5863.94136058651;5248.475152484873;5905.911411328748;5957.063954214984;5975.34271230173;5991.670458124845;4755.928661070017;5594.468525490072;5839.802840417619;5854.297484301911;5872.911906321494 18:47:25 INFO - PROCESS | 1658 | |5;yui.html;582.9417058294168;666.4967949198356;703.2650879830577;708.316474602009;759.5822297736246;15039.473618423357;15806.762898556502;15852.20738963053;15872.127033013194;15904.920475397621;14173.92913035436;14251.57245282643;14257.144571325727;14286.142831430121;14291.428342866298;664.9202095748517;822.2271065198709;830.2818062376061;844.9915500845005;854.8033952191;750.8047907544051;844.1431946574232;856.4433118473012;856.6145234644404;856.751542052804;909.9863502047475;910.0535443139128;915.5971372596036;917.1607978699482;917.7613820406675;897.7755561109723;900.2977677411629;905.968291109808;907.6596276396351;908.2370808520841;670.4435318685479;670.6546128743701;672.8149758816315;672.9427998620123;673.1753601837759;14693.33879975393;14773.261336933218;14880.851191488055;14915.627109322246;14971.850281497263;14570.781438278435;14623.634409139857;14638;14711.779323310158;14940;10053.949730251376;11867.821982670266;12056.758864822654;12230.755384892342;12459.06557008219;3736.3461394255896;3809.3333666608232;3845.6731177849915;3849.1339448623835;3887.3391523441082;112576.43711781471;113063.43468282688;113184;114076;118339;9185.265178785681;9258.35191536586;11312.71718207035;11429.542818287342;11670.183087183985;23275.30174094746;23292.534149316918;23306.417339566306;23336.183233586744;23356.649650255258;6799.93200067993;6890.965545172242;7819.609019549;8112.553809540414;8132.75601731949;3802.2015376770637;4347.891048723284;4389.956100439019;4397.626201772821;4516.3902873112365;3560.2523470071055;3649.39784935488;3680;3680.9263814723554;3697.5193224880595;646.5054233511368;652.451940370082;660.2736989311719;675.5136301862651;677.4005005570008;551.7930775959015;554.4040156831422;623.4731651754261;646.0480417599144;652.5106170372221;7639.388848892076;8820.691275805317;8938.642454301755;8986.101389861093;9420.90579094214;653.3564439027554;658.6114192626374;662.7978466567647;664.2726214794792;664.5182242873877;4218.810153543098;4438.800253988578;4614.831388844448;4632.2584501359715;4759.690620109682;119770;147805;152847;152938;157823;136700;137235;137676;140308;140949;58850.82298354009;75516.24483755202;76238.09404764925;76518;113334;8827.19042596279;8834.29325653959;8879.556022198993;8964.506952117696;9111.635534578807;61832;64853.67573162104;65805.68388632296;66967.33032669807;67699.66150169217;90139;95547;95899;96069.47166340084;98997.50501247447;11230.7753844921;11440.141989351;11483.48324325424;11743.706407339885;12449.25304481734;12487.687807804878;12551.87448125507;12627.494900204256;12791.488340466278;12931.482740690268;14054.718905621627;14158.858411415755;14212.076215045989;14262.786058209344;14350.856491434952;13617.659558511117;13690.383932722852;14001.929990349983;14212.431502740188;14335.139891606537;13451.529196478075;13506.93246533761;13701.314934253447;13990.370433330321;14286.285685715466;11040.91902205635;11454.599089031844;11476.311421314747;11651.47568359409;11679.649610511698;9320.440773553608;10564.418956957441;11419.885801141882;11598.768024639628;11659.417029148679 18:47:25 INFO - PROCESS | 1658 | __end_tp_report 18:47:25 INFO - PROCESS | 1658 | __start_cc_report 18:47:25 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,387 18:47:25 INFO - PROCESS | 1658 | __end_cc_report 18:47:25 INFO - PROCESS | 1658 | __startTimestamp1473385645875__endTimestamp 18:47:25 INFO - PROCESS | 1658 | 18:47:25 INFO - PROCESS | 1658 | ------- Summary: start ------- 18:47:25 INFO - PROCESS | 1658 | Number of tests: 6 18:47:25 INFO - PROCESS | 1658 | 18:47:25 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5794.38 Median:6097.57 stddev:674.33 (11.1%) stddev-sans-first:643.70 18:47:25 INFO - PROCESS | 1658 | Values: 3150.0 3397.4 3427.8 3451.2 3462.4 5682.9 5787.9 5812.4 5915.9 5955.3 5811.7 5831.4 5836.3 5860.7 5965.1 5222.1 5356.4 5382.5 5400.6 5407.8 5213.7 5294.3 5345.1 5398.3 5401.7 5242.0 5244.4 5269.4 5282.4 5296.5 5266.2 5271.6 5292.5 5294.9 5353.9 5139.9 5241.3 5256.5 5258.9 5300.1 4363.8 5838.3 5861.9 5937.9 5966.8 6221.3 6247.1 6265.0 6268.7 6271.7 6190.6 6197.3 6234.3 6313.2 6326.3 5889.8 5982.1 6012.7 6024.7 6046.3 6355.7 6363.5 6388.4 6397.6 6434.7 6124.9 6172.8 6294.7 6298.0 6305.5 6187.4 6289.8 6297.2 6298.8 6301.5 6218.2 6235.9 6263.6 6273.5 6288.7 6130.8 6136.7 6249.4 6262.5 6269.2 5016.5 5036.5 5059.3 5090.5 5098.4 4474.3 4500.3 4507.3 4511.9 4587.2 4381.5 4668.8 5203.6 5826.4 5895.6 5289.2 6198.7 6236.3 6283.4 6284.9 5082.8 5871.6 5972.0 6017.5 6019.1 4661.0 5425.1 5467.2 5555.7 5592.3 6287.8 6322.5 6478.5 6490.5 6533.5 5378.9 6279.2 6280.7 6284.5 6296.7 6231.7 6447.2 6475.1 6475.2 6500.8 6143.9 6156.5 6228.4 6242.7 6252.0 6145.5 6258.9 6271.0 6277.8 6295.7 6118.4 6143.8 6255.4 6264.7 6269.7 6145.9 6210.2 6221.3 6256.7 6275.7 5955.9 6065.2 6070.8 6089.1 6150.4 6075.5 6140.6 6213.6 6267.3 6304.8 6097.6 6106.0 6140.7 6149.7 6153.8 18:47:25 INFO - PROCESS | 1658 | 18:47:25 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:60317.79 Median:39506.60 stddev:127443.83 (322.6%) stddev-sans-first:127722.13 18:47:25 INFO - PROCESS | 1658 | Values: 471.8 500.1 508.1 522.5 528.4 31306.4 39908.6 40015.6 40096.6 40132.0 40483.6 41078.4 41121.0 41143.4 41254.0 8283.5 9457.5 9644.8 9654.6 9711.1 9553.0 9664.8 9678.6 9681.8 9705.6 9672.3 9687.9 9688.5 9705.2 9729.9 8988.9 9013.8 9616.5 9677.3 9677.4 9515.0 9610.0 9621.4 9624.3 9624.8 39038.4 39094.0 39289.0 39447.8 39565.4 2961.7 3264.3 3336.4 3723.8 3839.6 25767.5 27410.9 30520.5 31071.5 31344.5 2788.3 3190.8 3195.2 3244.5 3304.6 416532.0 417885.0 418047.0 418096.0 419119.0 32155.5 45744.5 56614.7 56930.1 57070.7 32710.8 33734.0 34085.5 34449.8 34461.5 14384.9 14452.3 14546.0 14554.0 14580.4 12266.9 12305.1 12337.0 12453.5 12460.3 6997.4 7576.2 7592.0 7613.3 7661.2 2987.5 3181.6 3195.0 3195.4 3207.3 841.3 919.3 932.0 941.0 961.2 29076.9 29565.5 29883.3 30131.5 30211.2 991.8 1045.1 1073.3 1112.3 1123.7 15024.2 15396.2 15484.0 15530.9 15533.8 416617.0 509138.0 555456.0 561069.0 570227.0 44579.3 55408.4 55513.0 56031.0 56049.2 1145.3 1299.3 1320.4 1356.1 1360.6 27943.7 29137.4 29216.3 29825.7 29879.4 42673.8 43054.4 47772.0 47841.0 47898.0 451393.0 453001.0 487603.0 513719.0 515926.0 25522.5 25816.2 26111.7 26727.9 26885.0 45534.5 46072.5 46175.5 46352.8 47106.0 32531.8 38405.6 38421.6 38927.0 39649.2 38537.6 38790.6 39590.8 39841.4 39935.0 37270.4 37443.4 37758.4 38254.8 38405.6 26374.6 34692.0 34812.1 34952.7 35061.5 25393.0 25400.7 25457.7 25560.6 26056.0 18:47:25 INFO - PROCESS | 1658 | 18:47:25 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:33877.59 Median:13001.65 stddev:91292.56 (702.2%) stddev-sans-first:91513.98 18:47:25 INFO - PROCESS | 1658 | Values: 368.3 369.3 374.7 377.6 395.0 19473.9 20434.4 20762.2 20845.4 21051.3 19847.3 20232.9 20481.0 20537.4 20810.0 5217.5 5863.4 5993.7 6093.8 6099.9 5254.7 5985.5 6052.7 6085.1 6124.0 5733.5 5911.3 5942.3 5954.8 5956.8 5167.7 5671.9 5693.5 5955.1 5984.3 5113.3 5150.6 5151.5 5157.1 5192.4 23609.4 25471.2 25704.4 25959.1 26153.0 2220.7 2255.1 2279.6 2354.7 2393.9 9953.9 10066.1 10088.5 10305.1 10492.5 1828.7 1831.5 1866.3 1897.0 1919.3 123430.4 127905.0 129242.4 130917.0 131823.0 10830.6 11395.8 11414.0 11415.6 11847.9 11866.3 12279.8 12762.5 13631.1 14275.1 8184.7 8450.1 8462.7 8472.0 8506.2 8385.2 8425.9 8468.7 8556.1 8581.2 3571.6 3634.6 4405.8 4520.7 4597.0 1851.2 1907.8 1908.5 1908.9 1910.5 805.5 873.7 899.9 947.3 968.1 19306.7 20778.8 20912.7 20918.8 20957.7 894.7 943.4 946.0 950.2 966.6 10537.2 10908.0 10940.0 10953.0 10953.7 534377.0 535854.0 541988.0 544286.0 551158.0 97823.0 121934.4 131182.3 132972.0 133541.3 948.1 959.5 1018.7 1023.4 1024.5 16593.7 18264.8 19753.0 19873.5 19974.5 51293.5 56545.4 56603.0 56821.7 58418.4 84540.6 85293.6 85303.6 86328.6 86497.0 6809.5 7932.2 8123.5 8231.1 8430.0 11071.8 12508.2 12880.3 12926.1 13312.1 10779.5 11232.9 11372.8 11415.9 11530.6 10708.6 11316.2 11433.9 11540.6 11805.0 11417.7 11517.6 11914.3 11958.8 12043.0 8136.7 8183.0 8236.5 8357.0 8401.3 10000.4 11386.5 11704.9 11706.6 11820.7 18:47:25 INFO - PROCESS | 1658 | 18:47:25 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5688.97 Median:5027.79 stddev:3203.54 (63.7%) stddev-sans-first:3197.07 18:47:25 INFO - PROCESS | 1658 | Values: 1501.3 1519.5 1708.5 1830.7 1851.4 7882.2 8439.2 8533.1 9018.9 9058.4 7832.6 8389.9 8733.2 8741.4 8759.4 1931.1 2269.7 2372.0 2375.9 2386.7 2336.1 2369.4 2382.6 2382.8 2388.8 2356.7 2370.9 2375.8 2376.0 2379.3 2331.3 2347.0 2359.0 2360.7 2361.7 2346.5 2347.5 2350.3 2350.6 2361.1 8341.2 8873.0 8877.7 8889.4 8902.8 5198.6 5244.4 5337.3 5339.8 5369.4 3193.8 3459.6 3461.2 3628.4 3641.3 898.4 1047.3 1085.7 1092.6 1108.9 11639.8 11671.2 11706.0 11774.4 11778.2 4529.4 4652.0 4786.0 4859.8 4859.9 9787.5 9887.0 9897.0 9922.0 9926.3 5297.4 5324.4 5326.5 5333.5 5339.7 4380.6 4756.4 4847.3 4882.8 4899.0 3854.8 3926.1 3948.0 4041.1 4053.1 1260.8 1264.4 1272.1 1277.7 1286.2 855.8 858.7 901.8 905.0 927.1 5630.2 5710.8 5854.6 5962.7 5991.9 792.7 938.2 942.9 946.4 988.7 3847.8 3973.6 4036.4 4077.1 4219.9 10654.8 10872.1 10876.5 10899.1 10902.3 10723.2 10857.7 10862.2 10868.1 10888.1 8033.2 8242.5 8255.7 8264.0 8280.6 5837.5 5902.9 6077.1 6087.3 6092.0 9168.6 9865.0 10105.6 10114.5 10141.0 9488.9 10311.2 10319.6 10347.6 10348.9 6869.8 7287.9 7431.5 7500.0 7618.1 8392.5 8486.9 8497.0 8503.0 8504.3 7336.8 7440.7 7588.2 7637.6 7694.7 6049.5 7167.8 7432.8 7531.5 7537.6 5593.0 5763.0 5953.7 6234.2 6985.5 4074.7 4847.5 4909.2 4981.8 5133.2 4939.4 5073.8 5085.9 5099.8 5172.8 18:47:25 INFO - PROCESS | 1658 | 18:47:25 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4720.46 Median:5368.24 stddev:1525.77 (28.4%) stddev-sans-first:1492.84 18:47:25 INFO - PROCESS | 1658 | Values: 258.8 273.6 308.0 312.7 314.5 4939.3 5131.5 5604.6 5662.4 5739.2 5574.1 5585.6 5591.9 5632.7 5658.9 3621.2 3653.5 4142.6 4233.9 4257.3 3470.8 4207.0 4233.2 4247.2 4260.8 3514.2 3790.5 4224.8 4228.6 4248.1 3273.6 3456.7 3558.9 3767.9 4180.8 3084.8 3540.5 3557.5 3562.4 3577.3 5150.6 5469.5 5490.2 5543.9 5558.7 3816.6 4463.9 4598.8 4799.3 4815.3 5147.3 5256.9 5687.9 5807.6 5878.3 3927.6 3927.6 3930.5 3948.4 3963.5 5123.7 5878.5 6230.8 6253.0 6299.8 3627.4 3880.7 3930.3 3930.4 3988.3 3240.6 3577.3 3901.5 4034.8 4048.4 3979.0 4172.4 4733.1 4778.3 4787.9 5722.8 5920.0 5921.7 5950.3 5995.2 1352.3 1355.4 1359.7 1384.1 1384.8 697.5 818.9 835.7 835.9 840.5 5266.7 5499.7 5519.6 5525.6 5611.0 5212.9 5608.3 5931.6 6139.8 6181.3 4675.4 5173.5 5621.3 5629.1 5654.9 2720.5 3043.7 3209.7 3212.6 3232.3 5235.6 5314.1 5906.2 6386.8 6440.4 5551.4 6239.5 6263.7 6271.1 6355.1 4888.7 5805.8 6328.6 6381.5 6473.7 5382.5 5802.7 6070.9 6144.8 6194.4 6121.4 6135.9 6155.5 6180.4 6182.2 6236.7 6431.0 6488.6 6513.6 6577.6 3457.3 3649.7 4063.2 4169.5 4173.7 5190.7 5512.3 5573.7 5815.7 6173.6 4518.0 5402.9 5791.0 5947.4 5967.0 4912.1 5136.7 5354.0 5967.8 5999.2 4925.6 5842.6 5856.6 5856.8 5863.9 5248.5 5905.9 5957.1 5975.3 5991.7 4755.9 5594.5 5839.8 5854.3 5872.9 18:47:25 INFO - PROCESS | 1658 | 18:47:25 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:24499.61 Median:3827.50 stddev:39353.99 (1028.2%) stddev-sans-first:39423.42 18:47:25 INFO - PROCESS | 1658 | Values: 582.9 666.5 703.3 708.3 759.6 15039.5 15806.8 15852.2 15872.1 15904.9 14173.9 14251.6 14257.1 14286.1 14291.4 664.9 822.2 830.3 845.0 854.8 750.8 844.1 856.4 856.6 856.8 910.0 910.1 915.6 917.2 917.8 897.8 900.3 906.0 907.7 908.2 670.4 670.7 672.8 672.9 673.2 14693.3 14773.3 14880.9 14915.6 14971.9 14570.8 14623.6 14638.0 14711.8 14940.0 10053.9 11867.8 12056.8 12230.8 12459.1 3736.3 3809.3 3845.7 3849.1 3887.3 112576.4 113063.4 113184.0 114076.0 118339.0 9185.3 9258.4 11312.7 11429.5 11670.2 23275.3 23292.5 23306.4 23336.2 23356.6 6799.9 6891.0 7819.6 8112.6 8132.8 3802.2 4347.9 4390.0 4397.6 4516.4 3560.3 3649.4 3680.0 3680.9 3697.5 646.5 652.5 660.3 675.5 677.4 551.8 554.4 623.5 646.0 652.5 7639.4 8820.7 8938.6 8986.1 9420.9 653.4 658.6 662.8 664.3 664.5 4218.8 4438.8 4614.8 4632.3 4759.7 119770.0 147805.0 152847.0 152938.0 157823.0 136700.0 137235.0 137676.0 140308.0 140949.0 58850.8 75516.2 76238.1 76518.0 113334.0 8827.2 8834.3 8879.6 8964.5 9111.6 61832.0 64853.7 65805.7 66967.3 67699.7 90139.0 95547.0 95899.0 96069.5 98997.5 11230.8 11440.1 11483.5 11743.7 12449.3 12487.7 12551.9 12627.5 12791.5 12931.5 14054.7 14158.9 14212.1 14262.8 14350.9 13617.7 13690.4 14001.9 14212.4 14335.1 13451.5 13506.9 13701.3 13990.4 14286.3 11040.9 11454.6 11476.3 11651.5 11679.6 9320.4 10564.4 11419.9 11598.8 11659.4 18:47:25 INFO - PROCESS | 1658 | -------- Summary: end -------- 18:47:25 INFO - PROCESS | 1658 | 18:47:26 INFO - TEST-INFO | 1658: exit 0 18:47:26 INFO - TEST-OK | dromaeo_css | took 1113871ms 18:47:26 INFO - TEST-START | kraken 18:47:26 INFO - operating with platform_type : mac_ 18:47:26 INFO - Initialising browser for kraken test... 18:47:26 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmp26c_PO/profile http://localhost:49267/getInfo.html) 18:47:29 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 18:47:29 INFO - PROCESS | 1666 | colorDepth:24 18:47:29 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 18:47:29 INFO - PROCESS | 1666 | __metrics 18:47:29 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 18:47:30 INFO - TEST-INFO | 1666: exit 0 18:47:30 INFO - Browser initialized. 18:47:30 INFO - Running cycle 1/1 for kraken test... 18:47:30 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmp26c_PO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:47:42 INFO - PROCESS | 1668 | RSS: Main: 243838976 18:47:42 INFO - PROCESS | 1668 | 18:47:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 18:47:45 INFO - PROCESS | 1668 | RSS: Main: 256729088 18:47:45 INFO - PROCESS | 1668 | 18:47:48 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 18:47:49 INFO - PROCESS | 1668 | RSS: Main: 265416704 18:47:49 INFO - PROCESS | 1668 | 18:47:53 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 18:47:53 INFO - PROCESS | 1668 | RSS: Main: 264273920 18:47:53 INFO - PROCESS | 1668 | 18:47:55 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 18:47:56 INFO - PROCESS | 1668 | RSS: Main: 267046912 18:47:56 INFO - PROCESS | 1668 | 18:47:58 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:47:59 INFO - PROCESS | 1668 | RSS: Main: 266924032 18:47:59 INFO - PROCESS | 1668 | 18:48:01 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 18:48:02 INFO - PROCESS | 1668 | RSS: Main: 267685888 18:48:02 INFO - PROCESS | 1668 | 18:48:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 18:48:06 INFO - PROCESS | 1668 | RSS: Main: 268275712 18:48:06 INFO - PROCESS | 1668 | 18:48:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 18:48:09 INFO - PROCESS | 1668 | RSS: Main: 268398592 18:48:09 INFO - PROCESS | 1668 | 18:48:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:48:12 INFO - PROCESS | 1668 | RSS: Main: 267751424 18:48:12 INFO - PROCESS | 1668 | 18:48:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:48:14 INFO - PROCESS | 1668 | RSS: Main: 267591680 18:48:14 INFO - PROCESS | 1668 | 18:48:16 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:48:17 INFO - PROCESS | 1668 | RSS: Main: 267628544 18:48:17 INFO - PROCESS | 1668 | 18:48:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:48:21 INFO - PROCESS | 1668 | RSS: Main: 267812864 18:48:21 INFO - PROCESS | 1668 | 18:48:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:48:25 INFO - PROCESS | 1668 | RSS: Main: 267853824 18:48:25 INFO - PROCESS | 1668 | 18:48:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 18:48:27 INFO - PROCESS | 1668 | RSS: Main: 269930496 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | __start_tp_report 18:48:27 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 18:48:27 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 18:48:27 INFO - PROCESS | 1668 | |i|pagename|runs| 18:48:27 INFO - PROCESS | 1668 | |0;ai-astar;107;117;117;112;117;120;119;116;122;128 18:48:27 INFO - PROCESS | 1668 | |1;audio-beat-detection;146;148;181;194;120;166;160;148;159;108 18:48:27 INFO - PROCESS | 1668 | |2;audio-dft;194;224;253;272;239;285;191;200;271;240 18:48:27 INFO - PROCESS | 1668 | |3;audio-fft;70;82;77;157;73;82;85;65;134;84 18:48:27 INFO - PROCESS | 1668 | |4;audio-oscillator;68;72;124;72;71;79;115;72;63;121 18:48:27 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;90;87;90;85;89;86;103;117;89;104 18:48:27 INFO - PROCESS | 1668 | |6;imaging-darkroom;97;99;129;167;116;98;99;132;98;100 18:48:27 INFO - PROCESS | 1668 | |7;imaging-desaturate;93;105;145;129;93;91;91;90;93;90 18:48:27 INFO - PROCESS | 1668 | |8;json-parse-financial;56;56;83;87;90;89;73;58;58;64 18:48:27 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;57;61;72;110;108;72;65;83;63;61 18:48:27 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;85;87;90;85;88;88;87;81;92;77 18:48:27 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;138;155;153;140;138;145;139;160;190;166 18:48:27 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;178;172;178;172;160;218;186;170;173;232 18:48:27 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;68;67;72;74;82;83;75;66;68;68 18:48:27 INFO - PROCESS | 1668 | __end_tp_report 18:48:27 INFO - PROCESS | 1668 | __start_cc_report 18:48:27 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,993 18:48:27 INFO - PROCESS | 1668 | __end_cc_report 18:48:27 INFO - PROCESS | 1668 | __startTimestamp1473385707033__endTimestamp 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | ------- Summary: start ------- 18:48:27 INFO - PROCESS | 1668 | Number of tests: 14 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:117.50 Median:118.00 stddev:5.60 (4.7%) stddev-sans-first:4.47 18:48:27 INFO - PROCESS | 1668 | Values: 107.0 117.0 117.0 112.0 117.0 120.0 119.0 116.0 122.0 128.0 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:153.00 Median:159.50 stddev:25.67 (16.1%) stddev-sans-first:27.11 18:48:27 INFO - PROCESS | 1668 | Values: 146.0 148.0 181.0 194.0 120.0 166.0 160.0 148.0 159.0 108.0 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:236.90 Median:246.50 stddev:34.09 (13.8%) stddev-sans-first:32.43 18:48:27 INFO - PROCESS | 1668 | Values: 194.0 224.0 253.0 272.0 239.0 285.0 191.0 200.0 271.0 240.0 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:90.90 Median:79.50 stddev:29.98 (37.7%) stddev-sans-first:30.83 18:48:27 INFO - PROCESS | 1668 | Values: 70.0 82.0 77.0 157.0 73.0 82.0 85.0 65.0 134.0 84.0 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:85.70 Median:71.50 stddev:24.09 (33.7%) stddev-sans-first:24.69 18:48:27 INFO - PROCESS | 1668 | Values: 68.0 72.0 124.0 72.0 71.0 79.0 115.0 72.0 63.0 121.0 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:94.00 Median:88.00 stddev:10.47 (11.9%) stddev-sans-first:11.00 18:48:27 INFO - PROCESS | 1668 | Values: 90.0 87.0 90.0 85.0 89.0 86.0 103.0 117.0 89.0 104.0 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:113.50 Median:97.50 stddev:23.11 (23.7%) stddev-sans-first:23.73 18:48:27 INFO - PROCESS | 1668 | Values: 97.0 99.0 129.0 167.0 116.0 98.0 99.0 132.0 98.0 100.0 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:102.00 Median:91.00 stddev:19.32 (21.2%) stddev-sans-first:20.22 18:48:27 INFO - PROCESS | 1668 | Values: 93.0 105.0 145.0 129.0 93.0 91.0 91.0 90.0 93.0 90.0 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:71.40 Median:78.00 stddev:14.62 (18.7%) stddev-sans-first:14.41 18:48:27 INFO - PROCESS | 1668 | Values: 56.0 56.0 83.0 87.0 90.0 89.0 73.0 58.0 58.0 64.0 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:75.20 Median:64.00 stddev:19.31 (30.2%) stddev-sans-first:19.32 18:48:27 INFO - PROCESS | 1668 | Values: 57.0 61.0 72.0 110.0 108.0 72.0 65.0 83.0 63.0 61.0 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:87.50 stddev:4.35 (5.0%) stddev-sans-first:4.59 18:48:27 INFO - PROCESS | 1668 | Values: 85.0 87.0 90.0 85.0 88.0 88.0 87.0 81.0 92.0 77.0 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:152.40 Median:154.00 stddev:16.55 (10.7%) stddev-sans-first:16.72 18:48:27 INFO - PROCESS | 1668 | Values: 138.0 155.0 153.0 140.0 138.0 145.0 139.0 160.0 190.0 166.0 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.90 Median:178.00 stddev:22.89 (12.9%) stddev-sans-first:24.18 18:48:27 INFO - PROCESS | 1668 | Values: 178.0 172.0 178.0 172.0 160.0 218.0 186.0 170.0 173.0 232.0 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:73.00 stddev:6.17 (8.4%) stddev-sans-first:6.34 18:48:27 INFO - PROCESS | 1668 | Values: 68.0 67.0 72.0 74.0 82.0 83.0 75.0 66.0 68.0 68.0 18:48:27 INFO - PROCESS | 1668 | -------- Summary: end -------- 18:48:27 INFO - PROCESS | 1668 | 18:48:27 INFO - TEST-INFO | 1668: exit 0 18:48:28 INFO - TEST-OK | kraken | took 61567ms 18:48:28 INFO - SUITE-END | took 1175s 18:48:28 INFO - Completed test suite (00:19:35) 18:48:28 INFO - javascript benchmark 18:48:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7261.691445411383, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3149.952750708739, 3397.4224381855083, 3427.79433234006, 3451.1544671555466, 3462.359463499253, 5682.857928551788, 5787.855303617412, 5812.360640329561, 5915.911261331078, 5955.2853657561045, 5811.73847176877, 5831.416858314177, 5836.270466191726, 5860.736266867991, 5965.075413310944, 5222.112240919053, 5356.437574054727, 5382.461753824606, 5400.567954563626, 5407.810726624569, 5213.739313034352, 5294.2852714883575, 5345.091334473149, 5398.271233383502, 5401.729913504329, 5242.0, 5244.396894357141, 5269.446708095633, 5282.4189339173045, 5296.549793267577, 5266.183741520071, 5271.551918086967, 5292.497212764782, 5294.867628309323, 5353.946460535384, 5139.871503212449, 5241.318628578299, 5256.526912577886, 5258.921116183222, 5300.072487314705, 4363.759993200372, 5838.299404071496, 5861.88276234473, 5937.881242375129, 5966.791162309299, 6221.253449586034, 6247.125402443661, 6264.9686751566405, 6268.65522396268, 6271.655058971754, 6190.597611155261, 6197.25632924052, 6234.282064588235, 6313.210848643919, 6326.335734747877, 5889.7644094236175, 5982.102684597346, 6012.669303188279, 6024.728887200086, 6046.3349031606485, 6355.713992870332, 6363.459105975951, 6388.361163883575, 6397.552171348054, 6434.678266086677, 6124.938750612437, 6172.845678858064, 6294.748210071637, 6298.02380631003, 6305.527085468608, 6187.443130118311, 6289.842753931189, 6297.244330680348, 6298.779542715983, 6301.52738544611, 6218.19163508741, 6235.875282494325, 6263.6241825490615, 6273.498120150377, 6288.654124023131, 6130.826205142326, 6136.73353236607, 6249.375062493714, 6262.467690246289, 6269.153664255358, 5016.473270306638, 5036.471170527115, 5059.342285502861, 5090.516400941905, 5098.413682426569, 4474.328850672425, 4500.257457519473, 4507.2788353863225, 4511.932321015187, 4587.197240482902, 4381.518033016366, 4668.836590719308, 5203.6470517665675, 5826.388229235954, 5895.55783316253, 5289.153735402318, 6198.659073750987, 6236.251649802053, 6283.403076707706, 6284.937150628527, 5082.771275292621, 5871.647701137946, 5971.9701401493585, 6017.548683848729, 6019.127226552126, 4660.953390466052, 5425.077736784756, 5467.17992301158, 5555.694436805934, 5592.328920529481, 6287.836750201227, 6322.462590679752, 6478.5141114415665, 6490.4807615390655, 6533.542652014408, 5378.865528361822, 6279.246490421178, 6280.717367718372, 6284.528660350401, 6296.653684047421, 6231.657258850625, 6447.161868957006, 6475.061116138039, 6475.158229430144, 6500.772472963424, 6143.938560614515, 6156.507479401726, 6228.408301211559, 6242.719077641608, 6251.9687401562705, 6145.539084568586, 6258.937410625836, 6270.968645156745, 6277.780277690259, 6295.685215739286, 6118.388161183846, 6143.757528502154, 6255.437010668879, 6264.686765661608, 6269.749210031548, 6145.907811382744, 6210.161628180138, 6221.253449586078, 6256.749730010931, 6275.6548389839, 5955.880882382415, 6065.181200537871, 6070.757169713162, 6089.147519347206, 6150.386085913558, 6075.453209211192, 6140.596275277727, 6213.568956130383, 6267.310595834546, 6304.810855674337, 6097.5731698780655, 6105.995946598671, 6140.718981675845, 6149.692515374303, 6153.784617538365], "value": 5752.194087829509, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.76647559458075, 500.1147968096467, 508.07023147639813, 522.4696932613398, 528.3817933018369, 31306.373872522538, 39908.60091399085, 40015.599844001554, 40096.599034009654, 40132.0, 40483.59516404835, 41078.383824242585, 41121.0, 41143.38284925729, 41253.96865078375, 8283.544405057719, 9457.479838608857, 9644.807103857918, 9654.565544550476, 9711.077447642463, 9553.0, 9664.80670386592, 9678.612855485771, 9681.806363872718, 9705.563249653782, 9672.274579406538, 9687.903120968806, 9688.515574221296, 9705.175060119898, 9729.854052189223, 8988.865167022435, 9013.774655633597, 9616.519174041305, 9677.274204434696, 9677.370970886905, 9515.048495150499, 9610.038996100404, 9621.422714637074, 9624.278179136592, 9624.759381015461, 39038.414423783666, 39094.0, 39289.01777455558, 39447.8027609863, 39565.40651890224, 2961.741259964528, 3264.3144939562485, 3336.366090442808, 3723.8138093095235, 3839.6352346527037, 25767.484650306887, 27410.90356385742, 30520.452360421426, 31071.533926991113, 31344.529832052536, 2788.344738986337, 3190.7925984810913, 3195.1692559934586, 3244.4646633305474, 3304.5538852254876, 416532.0, 417885.0, 418047.0, 418096.0, 419119.0, 32155.51766723501, 45744.54255457469, 56614.7169264151, 56930.146047809314, 57070.7146464265, 32710.83644581762, 33734.0, 34085.48871766926, 34449.827750861084, 34461.48307775385, 14384.928075359556, 14452.349644266033, 14545.98178127542, 14554.0, 14580.416783328548, 12266.938665306794, 12305.138640295272, 12337.0, 12453.505579330538, 12460.314682692358, 6997.370236678723, 7576.204498527685, 7591.962040189874, 7613.314801667876, 7661.233876612406, 2987.536931775578, 3181.618205815317, 3195.0414875537244, 3195.440797860365, 3207.294395233045, 841.2554888923347, 919.2645883293338, 931.9608636370398, 940.9623615055458, 961.2118063188119, 29076.91154048033, 29565.547296588666, 29883.25761618152, 30131.548026779616, 30211.244718881764, 991.7768502086973, 1045.1220974381556, 1073.313079629038, 1112.3270421395102, 1123.662901129658, 15024.17367044823, 15396.153211573246, 15484.0, 15530.903328050794, 15533.84466155324, 416617.0, 509138.0, 555456.0, 561069.0, 570227.0, 44579.33131003038, 55408.44591554033, 55513.0, 56031.0, 56049.159262611094, 1145.2613064573304, 1299.2594221293962, 1320.386020500472, 1356.1014579588655, 1360.6462319796924, 27943.72056279411, 29137.417251654424, 29216.26959326034, 29825.70174298316, 29879.402411951203, 42673.78663106664, 43054.35418468663, 47772.0, 47841.04317913553, 47898.0, 451393.0, 453001.0, 487603.0, 513719.0, 515926.0, 25522.489550209262, 25816.225513234633, 26111.73888261093, 26727.86636066885, 26885.0, 45534.54465455435, 46072.539274606825, 46175.53824461712, 46352.768236158605, 47106.0, 32531.837340813145, 38405.61594384132, 38421.61578384292, 38927.02670528916, 39649.2070158601, 38537.614623853406, 38790.6120938787, 39590.802045989585, 39841.40237896492, 39935.0, 37270.44094338533, 37443.43834842425, 37758.43362349512, 38254.808725956194, 38405.61594384132, 26374.604380934685, 34692.0, 34812.12969675779, 34952.65047349494, 35061.47407788834, 25393.0, 25400.74599253984, 25457.74542254628, 25560.616590750782, 26056.0], "value": 17955.487848757133, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.274499236504, 369.30386221971594, 374.7096000599536, 377.55825683949774, 395.0439935356437, 19473.928933908646, 20434.386968390954, 20762.16951321949, 20845.37463876084, 21051.2632057878, 19847.30534431295, 20232.89883550584, 20480.975951202454, 20537.383878483633, 20809.959502024914, 5217.452167522412, 5863.413658634135, 5993.700314984255, 6093.756249750027, 6099.878002439948, 5254.738862672969, 5985.461308482236, 6052.667103309316, 6085.117657939596, 6123.969380153093, 5733.455321744448, 5911.3201981772, 5942.257217847769, 5954.761809527614, 5956.768109050549, 5167.741612919358, 5671.943280567224, 5693.459121383462, 5955.076963070731, 5984.281886173643, 5113.309703190059, 5150.613703972217, 5151.5363617274625, 5157.097507936133, 5192.402873669538, 23609.40976475585, 25471.235862924143, 25704.357391065187, 25959.091431799796, 26153.0, 2220.744614369352, 2255.0866898905806, 2279.601069812776, 2354.7409784923643, 2393.892274847636, 9953.85069223955, 10066.144377727902, 10088.546015429323, 10305.12406445453, 10492.475376231234, 1828.6708392489347, 1831.496338506908, 1866.2908094923841, 1896.9620607587908, 1919.3090487424515, 123430.38284808518, 127905.0, 129242.3537882314, 130917.0, 131822.99999999904, 10830.566777328977, 11395.829062564067, 11414.029807466291, 11415.60045398407, 11847.881521184678, 11866.288022718665, 12279.754404911851, 12762.489500419879, 13631.113977591425, 14275.14349139055, 8184.713535026364, 8450.070492245848, 8462.746117616482, 8471.95764021176, 8506.235473471146, 8385.203405676451, 8425.873611895826, 8468.703595374132, 8556.058833528306, 8581.184787445183, 3571.624979377128, 3634.582023067328, 4405.757683327448, 4520.706154099973, 4597.011642496879, 1851.1669748613178, 1907.818757218062, 1908.532409559639, 1908.866379353432, 1910.522369407648, 805.4603415711488, 873.7204094689765, 899.8830152080187, 947.3226642950186, 968.1190116993503, 19306.71039934402, 20778.84423115383, 20912.68630970537, 20918.79081209199, 20957.68563471549, 894.7449976756724, 943.4103685196752, 945.9716208513755, 950.2493030505962, 966.5553845231273, 10537.157027437786, 10908.018278354986, 10940.0, 10953.0, 10953.673299046832, 534377.0, 535854.0, 541988.0, 544286.0, 551158.0, 97823.0217697814, 121934.3903280478, 131182.34408827894, 132972.0, 133541.3322933379, 948.1182500274673, 959.4531117263233, 1018.6536577563515, 1023.3859684189426, 1024.52871679027, 16593.66812663764, 18264.81735182631, 19753.012349382185, 19873.503162421057, 19974.50063748418, 51293.48706512887, 56545.43454565402, 56603.0, 56821.71589142028, 58418.4158158413, 84540.57729711312, 85293.57353213443, 85303.5734821322, 86328.56835716033, 86497.0, 6809.5233333666165, 7932.16712245206, 8123.47197432165, 8231.053428855757, 8430.0, 11071.778564428505, 12508.249505029728, 12880.291583962971, 12926.095173337777, 13312.134711243738, 10779.48420747632, 11232.887671123184, 11372.829407559058, 11415.942920285346, 11530.59642912494, 10708.571657133627, 11316.20786544934, 11433.942830285796, 11540.596079137189, 11805.0, 11417.657470275904, 11517.596884109016, 11914.344711040976, 11958.760824783629, 12042.976347010252, 8136.674533018613, 8182.959085204536, 8236.50580965144, 8356.955380577429, 8401.32789376836, 10000.399976001463, 11386.487608057823, 11704.94147529291, 11706.648800535997, 11820.70448238801], "value": 9532.295447160828, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1501.294391635931, 1519.4681861348527, 1708.5045336852313, 1830.6796310645634, 1851.3520267906242, 7882.172371900954, 8439.240468357846, 8533.146685331463, 9018.909810901898, 9058.411203271791, 7832.647530861111, 8389.91610083899, 8733.170348816868, 8741.388102832805, 8759.386842920998, 1931.0537836460173, 2269.6822444857735, 2371.9762802372015, 2375.904963801446, 2386.7016622922133, 2336.0889253191267, 2369.3958040699686, 2382.5711371953043, 2382.8212884033765, 2388.773066558674, 2356.739144557662, 2370.9051637934463, 2375.821813363996, 2376.0377047295874, 2379.3456799380137, 2331.347222777625, 2347.0494449747794, 2359.044586942291, 2360.681308023412, 2361.728401233854, 2346.530693861235, 2347.542229265294, 2350.318407661776, 2350.576896158691, 2361.055577768889, 8341.249287564087, 8873.023967363584, 8877.73366798997, 8889.377743557954, 8902.821943561092, 5198.636095473318, 5244.39689435716, 5337.306150200449, 5339.839804805862, 5369.436209198017, 3193.7924034937655, 3459.550258466383, 3461.1866211440292, 3628.401313783249, 3641.326354624403, 898.3890954151175, 1047.2564479219764, 1085.7339951711801, 1092.6066616018227, 1108.9390083545402, 11639.825402618968, 11671.241369310968, 11706.0, 11774.352410617412, 11778.175527713063, 4529.433820772403, 4652.0, 4786.042791441726, 4859.829905953275, 4859.878503037452, 9787.510624468749, 9887.0, 9897.010298970046, 9921.950390248003, 9926.305158638826, 5297.364316282071, 5324.440933701983, 5326.5472434842695, 5333.5199832015305, 5339.652922560022, 4380.5838445347645, 4756.405449318835, 4847.321375007502, 4882.7558622068755, 4898.975505122523, 3854.768713877176, 3926.1362500249897, 3947.980260098681, 4041.131156801302, 4053.1285773558825, 1260.8108783682337, 1264.424686767537, 1272.1285919145414, 1277.6933535951491, 1286.1897004887048, 855.794609293765, 858.7123313689859, 901.7700486376064, 904.9592768325441, 927.0821886332617, 5630.155476678531, 5710.828675139669, 5854.648721076749, 5962.672053037038, 5991.940080599225, 792.6987744656993, 938.1603464899014, 942.8774259346241, 946.3990366117565, 988.7379844341256, 3847.826847791856, 3973.562908080109, 4036.374361973899, 4077.062275676611, 4219.894502637459, 10654.840177397346, 10872.075873550837, 10876.510557024953, 10899.073578745736, 10902.291351062155, 10723.195760318007, 10857.728556785985, 10862.239643224899, 10868.130549556017, 10888.128949684005, 8033.156518565584, 8242.464239824392, 8255.711050113217, 8263.99999999988, 8280.58597070144, 5837.503812176013, 5902.881942361215, 6077.149199112217, 6087.299960504599, 6092.0, 9168.587413566538, 9864.950675246579, 10105.595776168871, 10114.494275286059, 10141.036601522843, 9488.857667135137, 10311.22665800083, 10319.638812641522, 10347.63783267582, 10348.896511034793, 6869.828254293682, 7287.8542429152185, 7431.516951398142, 7500.0, 7618.085829700472, 8392.538410387244, 8486.872696909428, 8496.957515212385, 8502.957485212535, 8504.277136443472, 7336.7798966031105, 7440.73957411488, 7588.165301816685, 7637.579933103732, 7694.730684426018, 6049.455549000611, 7167.820804479721, 7432.814179645552, 7531.510451820614, 7537.585432801029, 5593.049181639212, 5762.971185144047, 5953.719950210725, 6234.220722409699, 6985.511014228955, 4074.714769966074, 4847.466778654275, 4909.214525675875, 4981.750912454435, 5133.2300154975555, 4939.357883475125, 5073.822416215415, 5085.92371114441, 5099.84700458972, 5172.844814655567], "value": 4570.315856164031, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [258.7632316430466, 273.6374304047138, 307.9907602771916, 312.7404254468791, 314.5282076884673, 4939.333190019346, 5131.538161565459, 5604.551635869131, 5662.405447428017, 5739.167820666006, 5574.129323398453, 5585.553155747531, 5591.888162236753, 5632.662040277586, 5658.858528536789, 3621.203335266244, 3653.5067765851536, 4142.585741425848, 4233.872983810491, 4257.297545904922, 3470.826458677069, 4207.0, 4233.23801715691, 4247.150569886036, 4260.76565788883, 3514.1917359007443, 3790.5072340595684, 4224.831006759726, 4228.5982831630945, 4248.129133527619, 3273.639899611041, 3456.654334566536, 3558.87543935961, 3767.9449754068905, 4180.770057646828, 3084.784065115443, 3540.539729835131, 3557.4663800429885, 3562.447820587813, 3577.302426026926, 5150.587952963754, 5469.453054694499, 5490.176473528963, 5543.8891222175735, 5558.749856256438, 3816.587862490896, 4463.888402789924, 4598.770061496912, 4799.2801079837955, 4815.253635686474, 5147.3308469898675, 5256.8685782855355, 5687.886242275173, 5807.622504537191, 5878.323992740847, 3927.6465118139217, 3927.6465118139504, 3930.489036425275, 3948.368261078214, 3963.54419241788, 5123.718195499246, 5878.529717622623, 6230.750769969196, 6252.968735156341, 6299.779507717254, 3627.4014787560286, 3880.728349015542, 3930.331843586597, 3930.3711406174875, 3988.3419235825763, 3240.643529211785, 3577.338192434408, 3901.4733011043713, 4034.7982600870428, 4048.412980117925, 3978.980105099456, 4172.436721042621, 4733.053389322149, 4778.259369797687, 4787.880302992384, 5722.799702010493, 5919.9936010878255, 5921.703914804242, 5950.285965684146, 5995.190649262294, 1352.263016655925, 1355.4307190979614, 1359.7008658095203, 1384.0519244317675, 1384.813050238225, 697.4838619421591, 818.9181081891883, 835.7409203147043, 835.9247667709814, 840.5083026429485, 5266.657667251617, 5499.697516636544, 5519.613627046148, 5525.613207075547, 5610.97194514033, 5212.869678258074, 5608.298962629671, 5931.6441013539325, 6139.785107521305, 6181.258249010148, 4675.392199014174, 5173.5343819055715, 5621.297337832771, 5629.130801856447, 5654.886902261932, 2720.5375086235385, 3043.691212778482, 3209.743220542351, 3212.582364292674, 3232.3050544133125, 5235.586391674303, 5314.130369600372, 5906.2495315474125, 6386.808395748135, 6440.388163124496, 5551.3893471718075, 6239.532035097386, 6263.686815659199, 6271.090691849748, 6355.110284560165, 4888.655619704538, 5805.82582522525, 6328.588641738273, 6381.457576106083, 6473.676316184266, 5382.4886635769535, 5802.738876750641, 6070.878582428415, 6144.815655530341, 6194.39835269197, 6121.3572574879, 6135.877282454415, 6155.4767844731905, 6180.443760061616, 6182.165407669907, 6236.719347629277, 6431.0, 6488.578242414228, 6513.576617519846, 6577.612529547383, 3457.2540866862073, 3649.726270529668, 4063.227986682521, 4169.499660040815, 4173.728707633994, 5190.688558686491, 5512.310961129859, 5573.749181286771, 5815.720541480868, 6173.59871608344, 4518.0, 5402.864928376822, 5791.0, 5947.394203564927, 5967.015442451947, 4912.066707325596, 5136.691798492103, 5354.0, 5967.776605795714, 5999.220101386792, 4925.6305777066145, 5842.620229685057, 5856.560757943086, 5856.795012174553, 5863.94136058651, 5248.475152484873, 5905.911411328748, 5957.063954214984, 5975.34271230173, 5991.670458124845, 4755.928661070017, 5594.468525490072, 5839.802840417619, 5854.297484301911, 5872.911906321494], "value": 4220.380050208768, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.9417058294168, 666.4967949198356, 703.2650879830577, 708.316474602009, 759.5822297736246, 15039.473618423357, 15806.762898556502, 15852.20738963053, 15872.127033013194, 15904.920475397621, 14173.92913035436, 14251.57245282643, 14257.144571325727, 14286.142831430121, 14291.428342866298, 664.9202095748517, 822.2271065198709, 830.2818062376061, 844.9915500845005, 854.8033952191, 750.8047907544051, 844.1431946574232, 856.4433118473012, 856.6145234644404, 856.751542052804, 909.9863502047475, 910.0535443139128, 915.5971372596036, 917.1607978699482, 917.7613820406675, 897.7755561109723, 900.2977677411629, 905.968291109808, 907.6596276396351, 908.2370808520841, 670.4435318685479, 670.6546128743701, 672.8149758816315, 672.9427998620123, 673.1753601837759, 14693.33879975393, 14773.261336933218, 14880.851191488055, 14915.627109322246, 14971.850281497263, 14570.781438278435, 14623.634409139857, 14638.0, 14711.779323310158, 14940.0, 10053.949730251376, 11867.821982670266, 12056.758864822654, 12230.755384892342, 12459.06557008219, 3736.3461394255896, 3809.3333666608232, 3845.6731177849915, 3849.1339448623835, 3887.3391523441082, 112576.43711781471, 113063.43468282688, 113184.0, 114076.0, 118339.0, 9185.265178785681, 9258.35191536586, 11312.71718207035, 11429.542818287342, 11670.183087183985, 23275.30174094746, 23292.534149316918, 23306.417339566306, 23336.183233586744, 23356.649650255258, 6799.93200067993, 6890.965545172242, 7819.609019549, 8112.553809540414, 8132.75601731949, 3802.2015376770637, 4347.891048723284, 4389.956100439019, 4397.626201772821, 4516.3902873112365, 3560.2523470071055, 3649.39784935488, 3680.0, 3680.9263814723554, 3697.5193224880595, 646.5054233511368, 652.451940370082, 660.2736989311719, 675.5136301862651, 677.4005005570008, 551.7930775959015, 554.4040156831422, 623.4731651754261, 646.0480417599144, 652.5106170372221, 7639.388848892076, 8820.691275805317, 8938.642454301755, 8986.101389861093, 9420.90579094214, 653.3564439027554, 658.6114192626374, 662.7978466567647, 664.2726214794792, 664.5182242873877, 4218.810153543098, 4438.800253988578, 4614.831388844448, 4632.2584501359715, 4759.690620109682, 119770.0, 147805.0, 152847.0, 152938.0, 157823.0, 136700.0, 137235.0, 137676.0, 140308.0, 140949.0, 58850.82298354009, 75516.24483755202, 76238.09404764925, 76518.0, 113334.0, 8827.19042596279, 8834.29325653959, 8879.556022198993, 8964.506952117696, 9111.635534578807, 61832.0, 64853.67573162104, 65805.68388632296, 66967.33032669807, 67699.66150169217, 90139.0, 95547.0, 95899.0, 96069.47166340084, 98997.50501247447, 11230.7753844921, 11440.141989351, 11483.48324325424, 11743.706407339885, 12449.25304481734, 12487.687807804878, 12551.87448125507, 12627.494900204256, 12791.488340466278, 12931.482740690268, 14054.718905621627, 14158.858411415755, 14212.076215045989, 14262.786058209344, 14350.856491434952, 13617.659558511117, 13690.383932722852, 14001.929990349983, 14212.431502740188, 14335.139891606537, 13451.529196478075, 13506.93246533761, 13701.314934253447, 13990.370433330321, 14286.285685715466, 11040.91902205635, 11454.599089031844, 11476.311421314747, 11651.47568359409, 11679.649610511698, 9320.440773553608, 10564.418956957441, 11419.885801141882, 11598.768024639628, 11659.417029148679], "value": 7720.761868640901, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1634.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 117.0, 112.0, 117.0, 120.0, 119.0, 116.0, 122.0, 128.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 181.0, 194.0, 120.0, 166.0, 160.0, 148.0, 159.0, 108.0], "value": 153.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 224.0, 253.0, 272.0, 239.0, 285.0, 191.0, 200.0, 271.0, 240.0], "value": 236.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 77.0, 157.0, 73.0, 82.0, 85.0, 65.0, 134.0, 84.0], "value": 90.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 124.0, 72.0, 71.0, 79.0, 115.0, 72.0, 63.0, 121.0], "value": 85.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 85.0, 89.0, 86.0, 103.0, 117.0, 89.0, 104.0], "value": 94.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 129.0, 167.0, 116.0, 98.0, 99.0, 132.0, 98.0, 100.0], "value": 113.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 105.0, 145.0, 129.0, 93.0, 91.0, 91.0, 90.0, 93.0, 90.0], "value": 102.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 83.0, 87.0, 90.0, 89.0, 73.0, 58.0, 58.0, 64.0], "value": 71.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 72.0, 110.0, 108.0, 72.0, 65.0, 83.0, 63.0, 61.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 90.0, 85.0, 88.0, 88.0, 87.0, 81.0, 92.0, 77.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 155.0, 153.0, 140.0, 138.0, 145.0, 139.0, 160.0, 190.0, 166.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 172.0, 178.0, 172.0, 160.0, 218.0, 186.0, 170.0, 173.0, 232.0], "value": 183.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 72.0, 74.0, 82.0, 83.0, 75.0, 66.0, 68.0, 68.0], "value": 72.3, "unit": "score"}]}]} 18:48:28 INFO - Return code: 0 18:48:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:48:28 ERROR - # TBPL SUCCESS # 18:48:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:48:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:48:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:48:28 INFO - 2016-09-08 18:48:28.343 screenresolution[1674:10938] starting screenresolution argv=screenresolution get 18:48:28 INFO - 2016-09-08 18:48:28.346 screenresolution[1674:10938] Display 0: 1600x1200x32@60 18:48:28 INFO - 2016-09-08 18:48:28.352 screenresolution[1675:10940] starting screenresolution argv=screenresolution list 18:48:28 INFO - Available Modes on Display 0 18:48:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:48:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:48:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:48:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:48:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:48:28 INFO - Intel Iris: 18:48:28 INFO - Chipset Model: Intel Iris 18:48:28 INFO - Type: GPU 18:48:28 INFO - Bus: Built-In 18:48:28 INFO - VRAM (Dynamic, Max): 1536 MB 18:48:28 INFO - Vendor: Intel (0x8086) 18:48:28 INFO - Device ID: 0x0a2e 18:48:28 INFO - Revision ID: 0x0009 18:48:28 INFO - Displays: 18:48:28 INFO - Display: 18:48:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:48:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:48:28 INFO - Display Serial Number: 1600x1200 60 18:48:28 INFO - Main Display: Yes 18:48:28 INFO - Mirror: Off 18:48:28 INFO - Online: Yes 18:48:28 INFO - Rotation: Supported 18:48:28 INFO - Return code: 0 18:48:28 INFO - Running post-action listener: _resource_record_post_action 18:48:28 INFO - [mozharness: 2016-09-09 01:48:28.594047Z] Finished run-tests step (success) 18:48:28 INFO - Running post-run listener: _resource_record_post_run 18:48:28 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32893952; Write bytes: 708814336; Read time: 312; Write time: 2034 18:48:28 INFO - TinderboxPrint: CPU usage
54.4% 18:48:28 INFO - TinderboxPrint: I/O read bytes / time
32,893,952 / 312 18:48:28 INFO - TinderboxPrint: I/O write bytes / time
708,814,336 / 2,034 18:48:28 INFO - TinderboxPrint: CPU idle
2,208.6 (45.6%) 18:48:28 INFO - TinderboxPrint: CPU system
975.5 (20.1%) 18:48:28 INFO - TinderboxPrint: CPU user
1,657.7 (34.2%) 18:48:28 INFO - TinderboxPrint: Swap in / out
434,638,848 / 0 18:48:28 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 396409344; Write bytes: 390538752; Read time: 29767; Write time: 749 18:48:28 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 31120384; Write bytes: 299606016; Read time: 292; Write time: 1239 18:48:29 INFO - Running post-run listener: _upload_blobber_files 18:48:29 INFO - Blob upload gear active. 18:48:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:48:29 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 18:48:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:48:29 INFO - (blobuploader) - INFO - Open directory for files ... 18:48:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:48:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:48:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:30 INFO - (blobuploader) - INFO - Done attempting. 18:48:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:48:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:48:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:31 INFO - (blobuploader) - INFO - Done attempting. 18:48:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:48:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:48:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:48:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:48:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:48:33 INFO - (blobuploader) - INFO - Done attempting. 18:48:33 INFO - (blobuploader) - INFO - Iteration through files over. 18:48:33 INFO - Return code: 0 18:48:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:48:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:48:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/77f46ea912e3f05b34240fec01f9157fed3cc0c5ec07043c8e7be25fd494df64a1288d4c847b96c72b94bccfcbe5d82642d06f23d198fddce0092273d86d6b38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8cbbc89a3b370f38245931c4a6e875dafcea608145630aa9e4e13ed992a1a026920aee6a2470f382593569b3404a0aad68c2d06446591fb9f36ab0d071d066f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8556ca936c4b4bf8e7191c4aa1032399dd08dbe2b07fd20693568e5e590d496e7c447c94bcac03d6262a39a1cd340a074fb3db6597371e4c12ce20c8ea36f785"} 18:48:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:48:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:48:33 INFO - Contents: 18:48:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/77f46ea912e3f05b34240fec01f9157fed3cc0c5ec07043c8e7be25fd494df64a1288d4c847b96c72b94bccfcbe5d82642d06f23d198fddce0092273d86d6b38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8cbbc89a3b370f38245931c4a6e875dafcea608145630aa9e4e13ed992a1a026920aee6a2470f382593569b3404a0aad68c2d06446591fb9f36ab0d071d066f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8556ca936c4b4bf8e7191c4aa1032399dd08dbe2b07fd20693568e5e590d496e7c447c94bcac03d6262a39a1cd340a074fb3db6597371e4c12ce20c8ea36f785"} 18:48:33 INFO - Running post-run listener: copy_logs_to_upload_dir 18:48:33 INFO - Copying logs to upload dir... 18:48:33 INFO - mkdir: /builds/slave/test/build/upload/logs 18:48:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.207083 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-08 18:48:34.036249) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 18:48:34.039912) ========= 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.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/77f46ea912e3f05b34240fec01f9157fed3cc0c5ec07043c8e7be25fd494df64a1288d4c847b96c72b94bccfcbe5d82642d06f23d198fddce0092273d86d6b38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8cbbc89a3b370f38245931c4a6e875dafcea608145630aa9e4e13ed992a1a026920aee6a2470f382593569b3404a0aad68c2d06446591fb9f36ab0d071d066f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8556ca936c4b4bf8e7191c4aa1032399dd08dbe2b07fd20693568e5e590d496e7c447c94bcac03d6262a39a1cd340a074fb3db6597371e4c12ce20c8ea36f785"} build_url:https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010891 build_url: 'https://queue.taskcluster.net/v1/task/W6G-0OJPRt2Ha_EZEF2W2A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/77f46ea912e3f05b34240fec01f9157fed3cc0c5ec07043c8e7be25fd494df64a1288d4c847b96c72b94bccfcbe5d82642d06f23d198fddce0092273d86d6b38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8cbbc89a3b370f38245931c4a6e875dafcea608145630aa9e4e13ed992a1a026920aee6a2470f382593569b3404a0aad68c2d06446591fb9f36ab0d071d066f5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8556ca936c4b4bf8e7191c4aa1032399dd08dbe2b07fd20693568e5e590d496e7c447c94bcac03d6262a39a1cd340a074fb3db6597371e4c12ce20c8ea36f785"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 18:48:34.075398) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 18:48:34.076021) ========= 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.ZPVcrFvsMe/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.e5C7XYgmZ7/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.005223 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 18:48:34.125329) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 18:48:34.125654) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 18:48:34.126108) ========= ========= Total master_lag: 0.18 =========