builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0113 starttime: 1492041373.77 results: success (0) revision: fbde81258254c01b704bf76ee54ef2fe735109a6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 16:56:13.773122) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 16:56:13.773939) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 16:56:13.774474) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 16:56:13.802992) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:56:13.803438) ========= 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.z5iMhPPorD/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.xlaQvid0nb/Listeners TMPDIR=/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 --2017-04-12 16:56:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.5M=0.001s 2017-04-12 16:56:13 (14.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.146002 ========= master_lag: 0.24 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:56:14.191859) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:56:14.192233) ========= 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.z5iMhPPorD/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.xlaQvid0nb/Listeners TMPDIR=/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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.028600 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:56:14.244323) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-12 16:56:14.244732) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev fbde81258254c01b704bf76ee54ef2fe735109a6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/pine --rev fbde81258254c01b704bf76ee54ef2fe735109a6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.z5iMhPPorD/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.xlaQvid0nb/Listeners TMPDIR=/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 2017-04-12 16:56:14,353 truncating revision to first 12 chars 2017-04-12 16:56:14,353 Setting DEBUG logging. 2017-04-12 16:56:14,353 attempt 1/10 2017-04-12 16:56:14,353 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/fbde81258254?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-12 16:56:15,746 unpacking tar archive at: pine-fbde81258254/testing/mozharness/ program finished with exit code 0 elapsedTime=1.677341 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-12 16:56:15.948631) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 16:56:15.949026) ========= script_repo_revision: fbde81258254c01b704bf76ee54ef2fe735109a6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 16:56:15.949455) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 16:56:15.949789) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 16:56:15.970007) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-12 16:56:15.970423) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.z5iMhPPorD/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.xlaQvid0nb/Listeners TMPDIR=/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 16:56:16 INFO - MultiFileLogger online at 20170412 16:56:16 in /builds/slave/test 16:56:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 16:56:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:56:16 INFO - {'append_to_log': False, 16:56:16 INFO - 'base_work_dir': '/builds/slave/test', 16:56:16 INFO - 'blob_upload_branch': 'Pine', 16:56:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:56:16 INFO - 'branch': 'Pine', 16:56:16 INFO - 'buildbot_json_path': 'buildprops.json', 16:56:16 INFO - 'config_files': ('talos/mac_config.py',), 16:56:16 INFO - 'default_actions': ('clobber', 16:56:16 INFO - 'read-buildbot-config', 16:56:16 INFO - 'download-and-extract', 16:56:16 INFO - 'populate-webroot', 16:56:16 INFO - 'create-virtualenv', 16:56:16 INFO - 'install', 16:56:16 INFO - 'run-tests'), 16:56:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:56:16 INFO - 'download_minidump_stackwalk': True, 16:56:16 INFO - 'download_symbols': 'ondemand', 16:56:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:56:16 INFO - 'tooltool.py': '/tools/tooltool.py', 16:56:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:56:16 INFO - '/tools/misc-python/virtualenv.py')}, 16:56:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:56:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:56:16 INFO - 'gecko_profile': False, 16:56:16 INFO - 'gecko_profile_interval': 0, 16:56:16 INFO - 'installer_path': 'installer.exe', 16:56:16 INFO - 'log_level': 'info', 16:56:16 INFO - 'log_name': 'talos', 16:56:16 INFO - 'log_to_console': True, 16:56:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:56:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:56:16 INFO - 'opt_config_files': (), 16:56:16 INFO - 'pip_index': False, 16:56:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:56:16 INFO - 'cmd': ('bash', 16:56:16 INFO - '-c', 16:56:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:56:16 INFO - 'enabled': True, 16:56:16 INFO - 'halt_on_failure': False, 16:56:16 INFO - 'name': 'check_screen_resolution'},), 16:56:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:56:16 INFO - 'cmd': ('bash', 16:56:16 INFO - '-c', 16:56:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:56:16 INFO - 'enabled': True, 16:56:16 INFO - 'halt_on_failure': False, 16:56:16 INFO - 'name': 'check_screen_resolution'},), 16:56:16 INFO - 'run_cmd_checks_enabled': True, 16:56:16 INFO - 'suite': 'dromaeojs-e10s', 16:56:16 INFO - 'system_bits': '32', 16:56:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:56:16 INFO - 'title': 't-yosemite-r7-0113', 16:56:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:56:16 INFO - 'use_talos_json': True, 16:56:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:56:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:56:16 INFO - 'work_dir': 'build'} 16:56:16 INFO - [mozharness: 2017-04-12 23:56:16.185798Z] Running clobber step. 16:56:16 INFO - Running pre-action listener: _resource_record_pre_action 16:56:16 INFO - Running main action method: clobber 16:56:16 INFO - rmtree: /builds/slave/test/build 16:56:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:56:18 INFO - Running post-action listener: _resource_record_post_action 16:56:18 INFO - [mozharness: 2017-04-12 23:56:18.601324Z] Finished clobber step (success) 16:56:18 INFO - [mozharness: 2017-04-12 23:56:18.601448Z] Running read-buildbot-config step. 16:56:18 INFO - Running pre-action listener: _resource_record_pre_action 16:56:18 INFO - Running main action method: read_buildbot_config 16:56:18 INFO - Using buildbot properties: 16:56:18 INFO - { 16:56:18 INFO - "upload_to_task_id": "OZ_PPCr4T9ylw866crjYBg", 16:56:18 INFO - "project": "", 16:56:18 INFO - "product": "firefox", 16:56:18 INFO - "who": "dmosedale@mozilla.com", 16:56:18 INFO - "script_repo_revision": "default", 16:56:18 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 16:56:18 INFO - "repository": "", 16:56:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 16:56:18 INFO - "stage_platform": "macosx64", 16:56:18 INFO - "basedir": "/builds/slave/test", 16:56:18 INFO - "buildnumber": 10, 16:56:18 INFO - "platform": "yosemite_r7", 16:56:18 INFO - "parent_task_id": "CRoPsfIuQZGYeKeksZAj3Q", 16:56:18 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 16:56:18 INFO - "slavebuilddir": "test", 16:56:18 INFO - "taskId": "V5GhuttvR2Ci6ef87yMeLA", 16:56:18 INFO - "branch": "pine", 16:56:18 INFO - "revision": "fbde81258254c01b704bf76ee54ef2fe735109a6", 16:56:18 INFO - "slavename": "t-yosemite-r7-0113", 16:56:18 INFO - "repo_path": "projects/pine" 16:56:18 INFO - } 16:56:18 INFO - Finding installer, test and symbols from parent task. 16:56:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V5GhuttvR2Ci6ef87yMeLA'}, attempt #1 16:56:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V5GhuttvR2Ci6ef87yMeLA'}, attempt #1 16:56:19 INFO - Task dependencies: V5GhuttvR2Ci6ef87yMeLA 16:56:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q'}, attempt #1 16:56:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/buildbot_properties.json'}, attempt #1 16:56:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:56:21 INFO - Set test_url: https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:56:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols-full.zip 16:56:21 INFO - Running post-action listener: _resource_record_post_action 16:56:21 INFO - [mozharness: 2017-04-12 23:56:21.348316Z] Finished read-buildbot-config step (success) 16:56:21 INFO - [mozharness: 2017-04-12 23:56:21.348425Z] Running download-and-extract step. 16:56:21 INFO - Running pre-action listener: _resource_record_pre_action 16:56:21 INFO - Running main action method: download_and_extract 16:56:21 INFO - mkdir: /builds/slave/test/build/tests 16:56:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:21 INFO - trying https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:56:21 INFO - Downloading https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:56:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 16:56:21 INFO - Downloaded 1325 bytes. 16:56:21 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:56:21 INFO - Using the following test package requirements: 16:56:21 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:56:21 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:56:21 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:56:21 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:56:21 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:56:21 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:56:21 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:56:21 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:56:21 INFO - u'jsshell-mac.zip'], 16:56:21 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:56:21 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:56:21 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:56:21 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:56:21 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:56:21 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:56:21 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:56:21 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:56:21 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:56:21 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:56:21 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:56:21 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:56:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:56:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:56:21 INFO - Fetch https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:56:23 INFO - Expected file size: 52581681 16:56:23 INFO - Obtained file size: 52581681 16:56:40 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:56:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:56:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:56:40 INFO - Fetch https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:56:41 INFO - Expected file size: 52581681 16:56:41 INFO - Obtained file size: 52581681 16:56:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:56:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:56:56 INFO - Fetch https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:56:58 INFO - Expected file size: 14405596 16:56:58 INFO - Obtained file size: 14405596 16:56: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')]} 16:56:58 INFO - trying https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:56:58 INFO - Downloading https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:56:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 16:57:00 INFO - Downloaded 70998468 bytes. 16:57:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:57:00 INFO - mkdir: /builds/slave/test/properties 16:57:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:57:00 INFO - Writing to file /builds/slave/test/properties/build_url 16:57:00 INFO - Contents: 16:57:00 INFO - build_url:https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:57:00 INFO - Running post-action listener: _resource_record_post_action 16:57:00 INFO - Running post-action listener: set_extra_try_arguments 16:57:00 INFO - [mozharness: 2017-04-12 23:57:00.561732Z] Finished download-and-extract step (success) 16:57:00 INFO - [mozharness: 2017-04-12 23:57:00.561887Z] Running populate-webroot step. 16:57:00 INFO - Running pre-action listener: _resource_record_pre_action 16:57:00 INFO - Running main action method: populate_webroot 16:57:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:57:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:57:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:57:00 INFO - '--tppagecycles', 16:57:00 INFO - '7'], 16:57:00 INFO - 'tests': ['tsvgm']}}, 16:57:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:57:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:57:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:57:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:57:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:57:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:57:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:57:00 INFO - 'talos_options': ['--disable-e10s'], 16:57:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:57:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:57:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:57:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:57:00 INFO - 'talos_options': ['--disable-e10s'], 16:57:00 INFO - 'tests': ['damp', 'tps']}, 16:57:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:57:00 INFO - 'tests': ['damp', 'tps']}, 16:57:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:57:00 INFO - 'tests': ['dromaeo_dom']}, 16:57:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:57:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:57:00 INFO - 'tests': ['basic_compositor_video']}, 16:57:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:57:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:57:00 INFO - 'tests': ['a11yr', 16:57:00 INFO - 'ts_paint', 16:57:00 INFO - 'tpaint', 16:57:00 INFO - 'sessionrestore', 16:57:00 INFO - 'sessionrestore_no_auto_restore', 16:57:00 INFO - 'tabpaint']}, 16:57:00 INFO - 'other-e10s': {'tests': ['a11yr', 16:57:00 INFO - 'ts_paint', 16:57:00 INFO - 'tpaint', 16:57:00 INFO - 'sessionrestore', 16:57:00 INFO - 'sessionrestore_no_auto_restore', 16:57:00 INFO - 'tabpaint']}, 16:57:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:57:00 INFO - 'ts_paint', 16:57:00 INFO - 'tpaint', 16:57:00 INFO - 'sessionrestore', 16:57:00 INFO - 'sessionrestore_no_auto_restore', 16:57:00 INFO - 'tabpaint']}, 16:57:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:57:00 INFO - 'ts_paint', 16:57:00 INFO - 'tpaint', 16:57:00 INFO - 'sessionrestore', 16:57:00 INFO - 'sessionrestore_no_auto_restore', 16:57:00 INFO - 'tabpaint']}, 16:57:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:57:00 INFO - 'tests': ['a11yr', 16:57:00 INFO - 'ts_paint', 16:57:00 INFO - 'tpaint', 16:57:00 INFO - 'sessionrestore', 16:57:00 INFO - 'sessionrestore_no_auto_restore', 16:57:00 INFO - 'tabpaint']}, 16:57:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:57:00 INFO - 'tests': ['a11yr', 16:57:00 INFO - 'ts_paint', 16:57:00 INFO - 'tpaint', 16:57:00 INFO - 'sessionrestore', 16:57:00 INFO - 'sessionrestore_no_auto_restore', 16:57:00 INFO - 'tabpaint']}, 16:57:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:57:00 INFO - 'tests': ['tsvgx', 16:57:00 INFO - 'tsvgr_opacity', 16:57:00 INFO - 'tart', 16:57:00 INFO - 'tscrollx', 16:57:00 INFO - 'cart', 16:57:00 INFO - 'tsvg_static']}, 16:57:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:57:00 INFO - 'tsvgr_opacity', 16:57:00 INFO - 'tart', 16:57:00 INFO - 'tscrollx', 16:57:00 INFO - 'cart', 16:57:00 INFO - 'tsvg_static']}, 16:57:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:57:00 INFO - 'talos_options': ['--disable-e10s'], 16:57:00 INFO - 'tests': ['tp5o']}, 16:57:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:57:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:57:00 INFO - 'talos_options': ['--disable-e10s', 16:57:00 INFO - '--xperf_path', 16:57:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:57:00 INFO - 'tests': ['tp5n']}, 16:57:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:57:00 INFO - 'talos_options': ['--xperf_path', 16:57:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:57:00 INFO - 'tests': ['tp5n']}}, 16:57:00 INFO - 'talos.zip': {'path': '', 16:57:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:57:00 INFO - Running post-action listener: _resource_record_post_action 16:57:00 INFO - [mozharness: 2017-04-12 23:57:00.582367Z] Finished populate-webroot step (success) 16:57:00 INFO - [mozharness: 2017-04-12 23:57:00.582493Z] Running create-virtualenv step. 16:57:00 INFO - Running pre-action listener: _resource_record_pre_action 16:57:00 INFO - Running main action method: create_virtualenv 16:57:00 INFO - Creating virtualenv /builds/slave/test/build/venv 16:57:00 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 16:57:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:57:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:57:00 INFO - Using real prefix '/tools/python27' 16:57:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:57:01 INFO - Installing distribute.............................................................................................................................................................................................done. 16:57:04 INFO - Installing pip.................done. 16:57:04 INFO - Return code: 0 16:57:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:57:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:57:04 INFO - Reading from file tmpfile_stdout 16:57:04 INFO - Output received: 16:57:04 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:57:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:57:04 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')]} 16:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:57:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:57:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:57:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109a59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e0594fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109837d30>, '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 0x10975edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10982cbe8>, '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.z5iMhPPorD/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.xlaQvid0nb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 16:57:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:57:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:57:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z5iMhPPorD/Render', 16:57:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:57:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:57:05 INFO - 'HOME': '/Users/cltbld', 16:57:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:57:05 INFO - 'LOGNAME': 'cltbld', 16:57:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:57:05 INFO - 'MOZ_NO_REMOTE': '1', 16:57:05 INFO - 'NO_EM_RESTART': '1', 16:57:05 INFO - 'PAGER': '/bin/cat', 16:57:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:57:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:57:05 INFO - 'PWD': '/builds/slave/test', 16:57:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:57:05 INFO - 'SHELL': '/bin/bash', 16:57:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xlaQvid0nb/Listeners', 16:57:05 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 16:57:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:57:05 INFO - 'USER': 'cltbld', 16:57:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:57:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:57:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:57:05 INFO - 'XPC_FLAGS': '0x0', 16:57:05 INFO - 'XPC_SERVICE_NAME': '0', 16:57:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:57:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:57:05 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 16:57:05 INFO - Cleaning up... 16:57:05 INFO - Return code: 0 16:57:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:57:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:57:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:57:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:57:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:06 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 0x109a59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e0594fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109837d30>, '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 0x10975edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10982cbe8>, '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.z5iMhPPorD/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.xlaQvid0nb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 16:57:06 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 16:57:06 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 16:57:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z5iMhPPorD/Render', 16:57:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:57:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:57:06 INFO - 'HOME': '/Users/cltbld', 16:57:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:57:06 INFO - 'LOGNAME': 'cltbld', 16:57:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:57:06 INFO - 'MOZ_NO_REMOTE': '1', 16:57:06 INFO - 'NO_EM_RESTART': '1', 16:57:06 INFO - 'PAGER': '/bin/cat', 16:57:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:57:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:57:06 INFO - 'PWD': '/builds/slave/test', 16:57:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:57:06 INFO - 'SHELL': '/bin/bash', 16:57:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xlaQvid0nb/Listeners', 16:57:06 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 16:57:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:57:06 INFO - 'USER': 'cltbld', 16:57:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:57:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:57:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:57:06 INFO - 'XPC_FLAGS': '0x0', 16:57:06 INFO - 'XPC_SERVICE_NAME': '0', 16:57:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:57:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:57:06 INFO - Downloading/unpacking psutil>=3.1.1 16:57:06 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 16:57:06 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 16:57:06 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 16:57:06 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 16:57:06 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 16:57:06 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 16:57:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:57:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:57:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:57:10 INFO - Installing collected packages: psutil 16:57:10 INFO - Running setup.py install for psutil 16:57:10 INFO - building 'psutil._psutil_osx' extension 16:57:10 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 16:57:10 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 16:57:10 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 16:57:10 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 16:57:11 INFO - building 'psutil._psutil_posix' extension 16:57:11 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 16:57:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:57:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:57:11 INFO - ^ 16:57:11 INFO - 1 warning generated. 16:57:11 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 16:57:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:57:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:57:11 INFO - Successfully installed psutil 16:57:11 INFO - Cleaning up... 16:57:11 INFO - Return code: 0 16:57:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:57: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')]} 16:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:57:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57: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')]} 16:57:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:57:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109a59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e0594fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109837d30>, '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 0x10975edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10982cbe8>, '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.z5iMhPPorD/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.xlaQvid0nb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 16:57:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:57:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z5iMhPPorD/Render', 16:57:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:57:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:57:11 INFO - 'HOME': '/Users/cltbld', 16:57:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:57:11 INFO - 'LOGNAME': 'cltbld', 16:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:57:11 INFO - 'MOZ_NO_REMOTE': '1', 16:57:11 INFO - 'NO_EM_RESTART': '1', 16:57:11 INFO - 'PAGER': '/bin/cat', 16:57:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:57:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:57:11 INFO - 'PWD': '/builds/slave/test', 16:57:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:57:11 INFO - 'SHELL': '/bin/bash', 16:57:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xlaQvid0nb/Listeners', 16:57:11 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 16:57:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:57:11 INFO - 'USER': 'cltbld', 16:57:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:57:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:57:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:57:11 INFO - 'XPC_FLAGS': '0x0', 16:57:11 INFO - 'XPC_SERVICE_NAME': '0', 16:57:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:57:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:57:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:57:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:57:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:57:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:57:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:57:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:57:14 INFO - Installing collected packages: mozsystemmonitor 16:57:14 INFO - Running setup.py install for mozsystemmonitor 16:57:14 INFO - Successfully installed mozsystemmonitor 16:57:14 INFO - Cleaning up... 16:57:14 INFO - Return code: 0 16:57:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:57:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:57:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:57:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:57:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109a59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e0594fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109837d30>, '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 0x10975edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10982cbe8>, '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.z5iMhPPorD/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.xlaQvid0nb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 16:57:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:57:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:57:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z5iMhPPorD/Render', 16:57:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:57:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:57:14 INFO - 'HOME': '/Users/cltbld', 16:57:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:57:14 INFO - 'LOGNAME': 'cltbld', 16:57:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:57:14 INFO - 'MOZ_NO_REMOTE': '1', 16:57:14 INFO - 'NO_EM_RESTART': '1', 16:57:14 INFO - 'PAGER': '/bin/cat', 16:57:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:57:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:57:14 INFO - 'PWD': '/builds/slave/test', 16:57:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:57:14 INFO - 'SHELL': '/bin/bash', 16:57:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xlaQvid0nb/Listeners', 16:57:14 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 16:57:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:57:14 INFO - 'USER': 'cltbld', 16:57:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:57:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:57:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:57:14 INFO - 'XPC_FLAGS': '0x0', 16:57:14 INFO - 'XPC_SERVICE_NAME': '0', 16:57:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:57:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:57:14 INFO - Downloading/unpacking jsonschema==2.5.1 16:57:14 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 16:57:14 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 16:57:14 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 16:57:14 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 16:57:14 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 16:57:14 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 16:57:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:57:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:57:17 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 16:57:17 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 16:57:17 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 16:57:17 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 16:57:17 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 16:57:17 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 16:57:18 INFO - Downloading functools32-3.2.3-2.tar.gz 16:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:57:18 INFO - warning: no files found matching '*.txt' 16:57:18 INFO - no previously-included directories found matching 'build' 16:57:18 INFO - no previously-included directories found matching 'dist' 16:57:18 INFO - no previously-included directories found matching '.git*' 16:57:18 INFO - Installing collected packages: jsonschema, functools32 16:57:18 INFO - Running setup.py install for functools32 16:57:18 INFO - warning: no files found matching '*.txt' 16:57:18 INFO - no previously-included directories found matching 'build' 16:57:18 INFO - no previously-included directories found matching 'dist' 16:57:18 INFO - no previously-included directories found matching '.git*' 16:57:18 INFO - Successfully installed jsonschema functools32 16:57:18 INFO - Cleaning up... 16:57:18 INFO - Return code: 0 16:57:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:57:18 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')]} 16:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:18 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')]} 16:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:18 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 0x109a59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e0594fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109837d30>, '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 0x10975edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10982cbe8>, '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.z5iMhPPorD/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.xlaQvid0nb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 16:57:18 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 16:57:18 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 16:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z5iMhPPorD/Render', 16:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:57:18 INFO - 'HOME': '/Users/cltbld', 16:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:57:18 INFO - 'LOGNAME': 'cltbld', 16:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:57:18 INFO - 'MOZ_NO_REMOTE': '1', 16:57:18 INFO - 'NO_EM_RESTART': '1', 16:57:18 INFO - 'PAGER': '/bin/cat', 16:57:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:57:18 INFO - 'PWD': '/builds/slave/test', 16:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:57:18 INFO - 'SHELL': '/bin/bash', 16:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xlaQvid0nb/Listeners', 16:57:18 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 16:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:57:18 INFO - 'USER': 'cltbld', 16:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:57:18 INFO - 'XPC_FLAGS': '0x0', 16:57:18 INFO - 'XPC_SERVICE_NAME': '0', 16:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:57:18 INFO - Cleaning up... 16:57:18 INFO - Return code: 0 16:57:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:57:18 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')]} 16:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:18 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')]} 16:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:18 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 0x109a59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e0594fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109837d30>, '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 0x10975edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10982cbe8>, '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.z5iMhPPorD/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.xlaQvid0nb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 16:57:18 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 16:57:18 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 16:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z5iMhPPorD/Render', 16:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:57:18 INFO - 'HOME': '/Users/cltbld', 16:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:57:18 INFO - 'LOGNAME': 'cltbld', 16:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:57:18 INFO - 'MOZ_NO_REMOTE': '1', 16:57:18 INFO - 'NO_EM_RESTART': '1', 16:57:18 INFO - 'PAGER': '/bin/cat', 16:57:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:57:18 INFO - 'PWD': '/builds/slave/test', 16:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:57:18 INFO - 'SHELL': '/bin/bash', 16:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xlaQvid0nb/Listeners', 16:57:18 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 16:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:57:18 INFO - 'USER': 'cltbld', 16:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:57:18 INFO - 'XPC_FLAGS': '0x0', 16:57:18 INFO - 'XPC_SERVICE_NAME': '0', 16:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:57:18 INFO - Downloading/unpacking blobuploader==1.2.4 16:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:57:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:57:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:57:21 INFO - Downloading blobuploader-1.2.4.tar.gz 16:57:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:57:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:57:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:57:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:57:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:57:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:57:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:57:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:57:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:57:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:57:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:57:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:57:22 INFO - Downloading docopt-0.6.1.tar.gz 16:57:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:57:22 INFO - Installing collected packages: blobuploader, requests, docopt 16:57:22 INFO - Running setup.py install for blobuploader 16:57:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:57:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:57:23 INFO - Running setup.py install for requests 16:57:23 INFO - Running setup.py install for docopt 16:57:23 INFO - Successfully installed blobuploader requests docopt 16:57:23 INFO - Cleaning up... 16:57:23 INFO - Return code: 0 16:57:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:57: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')]} 16:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:57:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57: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')]} 16:57:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:57:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:23 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 0x109a59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e0594fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109837d30>, '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 0x10975edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10982cbe8>, '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.z5iMhPPorD/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.xlaQvid0nb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 16:57:23 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 16:57:23 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 16:57:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z5iMhPPorD/Render', 16:57:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:57:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:57:23 INFO - 'HOME': '/Users/cltbld', 16:57:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:57:23 INFO - 'LOGNAME': 'cltbld', 16:57:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:57:23 INFO - 'MOZ_NO_REMOTE': '1', 16:57:23 INFO - 'NO_EM_RESTART': '1', 16:57:23 INFO - 'PAGER': '/bin/cat', 16:57:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:57:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:57:23 INFO - 'PWD': '/builds/slave/test', 16:57:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:57:23 INFO - 'SHELL': '/bin/bash', 16:57:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xlaQvid0nb/Listeners', 16:57:23 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 16:57:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:57:23 INFO - 'USER': 'cltbld', 16:57:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:57:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:57:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:57:23 INFO - 'XPC_FLAGS': '0x0', 16:57:23 INFO - 'XPC_SERVICE_NAME': '0', 16:57:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:57:24 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-gWUvY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:57:24 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-HdQt5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:57:24 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-J6Whhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:57:24 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-h1qhgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:57:24 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-uHGKx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:57:24 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-pfyAbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:57:24 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-R9qZLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:57:24 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-9x9c8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:57:24 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-ojwaLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:57:24 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-MM0Qce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:57:25 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-wDfSqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:57:25 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-0_vou1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:57:25 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-5aaqlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:57:25 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-xO6QSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:57:25 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-lNIOKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:57:25 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-ew_9LA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:57:25 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-v_pizW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:57:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:57:25 INFO - Running setup.py install for manifestparser 16:57:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:57:25 INFO - Running setup.py install for mozcrash 16:57:26 INFO - Running setup.py install for mozdebug 16:57:26 INFO - Running setup.py install for mozdevice 16:57:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:57:26 INFO - Running setup.py install for mozfile 16:57:26 INFO - Running setup.py install for mozhttpd 16:57:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:57:26 INFO - Running setup.py install for mozinfo 16:57:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:57:26 INFO - Running setup.py install for mozInstall 16:57:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:57:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:57:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:57:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:57:26 INFO - Running setup.py install for mozleak 16:57:27 INFO - Running setup.py install for mozlog 16:57:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:57:27 INFO - Running setup.py install for moznetwork 16:57:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:57:27 INFO - Running setup.py install for mozprocess 16:57:27 INFO - Running setup.py install for mozprofile 16:57:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:57:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:57:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:57:27 INFO - Running setup.py install for mozrunner 16:57:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:57:27 INFO - Running setup.py install for mozscreenshot 16:57:28 INFO - Running setup.py install for moztest 16:57:28 INFO - Running setup.py install for mozversion 16:57:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:57:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:57:28 INFO - Cleaning up... 16:57:28 INFO - Return code: 0 16:57:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:57:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:57:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:57:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:57:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:28 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 0x109a59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e0594fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109837d30>, '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 0x10975edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10982cbe8>, '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.z5iMhPPorD/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.xlaQvid0nb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 16:57:28 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 16:57:28 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 16:57:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z5iMhPPorD/Render', 16:57:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:57:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:57:28 INFO - 'HOME': '/Users/cltbld', 16:57:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:57:28 INFO - 'LOGNAME': 'cltbld', 16:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:57:28 INFO - 'MOZ_NO_REMOTE': '1', 16:57:28 INFO - 'NO_EM_RESTART': '1', 16:57:28 INFO - 'PAGER': '/bin/cat', 16:57:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:57:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:57:28 INFO - 'PWD': '/builds/slave/test', 16:57:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:57:28 INFO - 'SHELL': '/bin/bash', 16:57:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xlaQvid0nb/Listeners', 16:57:28 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 16:57:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:57:28 INFO - 'USER': 'cltbld', 16:57:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:57:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:57:28 INFO - 'XPC_FLAGS': '0x0', 16:57:28 INFO - 'XPC_SERVICE_NAME': '0', 16:57:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:57:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:57:28 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-FulzjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:57:28 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)) 16:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:57:28 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-r6jfPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:57:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 16:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:57:28 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-nrG62n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:57:29 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)) 16:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:57:29 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-2TL52W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:57:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 16:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:57:29 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-M_9mwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:57:29 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)) 16:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:57:29 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-9U7Xci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:57:29 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)) 16:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:57:29 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-Qlojld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:57:29 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)) 16:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:57:29 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-Q6szzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:57:29 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)) 16:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:57:29 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-D1lrEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:57:29 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)) 16:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:57:29 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-ZPUcUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:57:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 16:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:57:29 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-54KxdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:57:29 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)) 16:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:57:29 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-A6EmIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:57:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 16:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:57:29 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-6Br0ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:57:30 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)) 16:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:57:30 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-WgVWuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 16:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:57:30 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-olzhWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:57:30 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)) 16:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:57:30 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-P_9Sve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 16:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:57:30 INFO - Running setup.py (path:/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/pip-ifq6OT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:57:30 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)) 16:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:57:30 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)) 16:57:30 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:57:30 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:57:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:57:30 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 16:57:30 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 16:57:30 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 16:57:30 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 16:57:30 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 16:57:30 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 16:57:33 INFO - Downloading blessings-1.6.tar.gz 16:57:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:57:33 INFO - Installing collected packages: blessings 16:57:33 INFO - Running setup.py install for blessings 16:57:33 INFO - Successfully installed blessings 16:57:33 INFO - Cleaning up... 16:57:33 INFO - Return code: 0 16:57:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:57:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:57:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:57:33 INFO - Reading from file tmpfile_stdout 16:57:33 INFO - Current package versions: 16:57:33 INFO - blessings == 1.6 16:57:33 INFO - blobuploader == 1.2.4 16:57:33 INFO - docopt == 0.6.1 16:57:33 INFO - functools32 == 3.2.3-2 16:57:33 INFO - jsonschema == 2.5.1 16:57:33 INFO - manifestparser == 1.1 16:57:33 INFO - mozInstall == 1.12 16:57:33 INFO - mozcrash == 1.0 16:57:33 INFO - mozdebug == 0.1 16:57:33 INFO - mozdevice == 0.50 16:57:33 INFO - mozfile == 1.2 16:57:33 INFO - mozhttpd == 0.7 16:57:33 INFO - mozinfo == 0.9 16:57:33 INFO - mozleak == 0.1 16:57:33 INFO - mozlog == 3.4 16:57:33 INFO - moznetwork == 0.27 16:57:33 INFO - mozprocess == 0.25 16:57:33 INFO - mozprofile == 0.28 16:57:33 INFO - mozrunner == 6.13 16:57:33 INFO - mozscreenshot == 0.1 16:57:33 INFO - mozsystemmonitor == 0.3 16:57:33 INFO - moztest == 0.8 16:57:33 INFO - mozversion == 1.4 16:57:33 INFO - psutil == 3.1.1 16:57:33 INFO - requests == 1.2.3 16:57:33 INFO - wsgiref == 0.1.2 16:57:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:57:33 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')]} 16:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:57:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:33 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')]} 16:57:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:57:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:33 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 0x109a59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e0594fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109837d30>, '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 0x10975edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10982cbe8>, '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.z5iMhPPorD/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.xlaQvid0nb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 16:57:33 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 16:57:33 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 16:57:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z5iMhPPorD/Render', 16:57:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:57:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:57:33 INFO - 'HOME': '/Users/cltbld', 16:57:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:57:33 INFO - 'LOGNAME': 'cltbld', 16:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:57:33 INFO - 'MOZ_NO_REMOTE': '1', 16:57:33 INFO - 'NO_EM_RESTART': '1', 16:57:33 INFO - 'PAGER': '/bin/cat', 16:57:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:57:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:57:33 INFO - 'PWD': '/builds/slave/test', 16:57:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:57:33 INFO - 'SHELL': '/bin/bash', 16:57:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xlaQvid0nb/Listeners', 16:57:33 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 16:57:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:57:33 INFO - 'USER': 'cltbld', 16:57:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:57:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:57:33 INFO - 'XPC_FLAGS': '0x0', 16:57:33 INFO - 'XPC_SERVICE_NAME': '0', 16:57:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:57:34 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)) 16:57:34 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)) 16:57:34 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)) 16:57:34 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)) 16:57:34 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)) 16:57:34 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)) 16:57:34 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)) 16:57:34 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)) 16:57:34 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)) 16:57:34 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)) 16:57:34 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)) 16:57:34 INFO - Cleaning up... 16:57:34 INFO - Return code: 0 16:57:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109a59b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a5c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e0594fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109837d30>, '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 0x10975edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10982cbe8>, '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.z5iMhPPorD/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.xlaQvid0nb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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 16:57:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:57:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:57:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z5iMhPPorD/Render', 16:57:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:57:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:57:34 INFO - 'HOME': '/Users/cltbld', 16:57:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:57:34 INFO - 'LOGNAME': 'cltbld', 16:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:57:34 INFO - 'MOZ_NO_REMOTE': '1', 16:57:34 INFO - 'NO_EM_RESTART': '1', 16:57:34 INFO - 'PAGER': '/bin/cat', 16:57:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:57:34 INFO - 'PWD': '/builds/slave/test', 16:57:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:57:34 INFO - 'SHELL': '/bin/bash', 16:57:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xlaQvid0nb/Listeners', 16:57:34 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 16:57:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:57:34 INFO - 'USER': 'cltbld', 16:57:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:57:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:57:34 INFO - 'XPC_FLAGS': '0x0', 16:57:34 INFO - 'XPC_SERVICE_NAME': '0', 16:57:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:57:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:57:34 INFO - Cleaning up... 16:57:34 INFO - Return code: 0 16:57:34 INFO - Running post-action listener: _resource_record_post_action 16:57:34 INFO - Running post-action listener: _start_resource_monitoring 16:57:34 INFO - Starting resource monitoring. 16:57:34 INFO - [mozharness: 2017-04-12 23:57:34.359650Z] Finished create-virtualenv step (success) 16:57:34 INFO - [mozharness: 2017-04-12 23:57:34.360601Z] Running install step. 16:57:34 INFO - Running pre-action listener: _resource_record_pre_action 16:57:34 INFO - Running main action method: install 16:57:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:57:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:57:34 INFO - Reading from file tmpfile_stdout 16:57:34 INFO - Detecting whether we're running mozinstall >=1.0... 16:57:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:57:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:57:34 INFO - Reading from file tmpfile_stdout 16:57:34 INFO - Output received: 16:57:34 INFO - Usage: mozinstall [options] installer 16:57:34 INFO - Options: 16:57:34 INFO - -h, --help show this help message and exit 16:57:34 INFO - -d DEST, --destination=DEST 16:57:34 INFO - Directory to install application into. [default: 16:57:34 INFO - "/builds/slave/test"] 16:57:34 INFO - --app=APP Application being installed. [default: firefox] 16:57:34 INFO - mkdir: /builds/slave/test/build/application 16:57:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:57:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:57:54 INFO - Reading from file tmpfile_stdout 16:57:54 INFO - Output received: 16:57:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:57:54 INFO - Running post-action listener: _resource_record_post_action 16:57:54 INFO - [mozharness: 2017-04-12 23:57:54.447547Z] Finished install step (success) 16:57:54 INFO - [mozharness: 2017-04-12 23:57:54.447706Z] Running run-tests step. 16:57:54 INFO - Running pre-action listener: _resource_record_pre_action 16:57:54 INFO - Running main action method: run_tests 16:57:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:57:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:57:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:57:54 INFO - 2017-04-12 16:57:54.462 screenresolution[1621:7412] starting screenresolution argv=screenresolution get 16:57:54 INFO - 2017-04-12 16:57:54.465 screenresolution[1621:7412] Display 0: 1600x1200x32@60 16:57:54 INFO - 2017-04-12 16:57:54.471 screenresolution[1622:7415] starting screenresolution argv=screenresolution list 16:57:54 INFO - Available Modes on Display 0 16:57:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:57:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:57:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:57:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:57:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:57:54 INFO - Intel Iris: 16:57:54 INFO - Chipset Model: Intel Iris 16:57:54 INFO - Type: GPU 16:57:54 INFO - Bus: Built-In 16:57:54 INFO - VRAM (Dynamic, Max): 1536 MB 16:57:54 INFO - Vendor: Intel (0x8086) 16:57:54 INFO - Device ID: 0x0a2e 16:57:54 INFO - Revision ID: 0x0009 16:57:54 INFO - Displays: 16:57:54 INFO - Display: 16:57:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:57:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:57:54 INFO - Display Serial Number: 1600x1200 60 16:57:54 INFO - Main Display: Yes 16:57:54 INFO - Mirror: Off 16:57:54 INFO - Online: Yes 16:57:54 INFO - Rotation: Supported 16:57:54 INFO - Return code: 0 16:57:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:57:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:57:54 INFO - Python 2.7.3 16:57:54 INFO - Return code: 0 16:57:54 INFO - grabbing minidump binary from tooltool 16:57:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:57:54 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 0x109837d30>, '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 0x10975edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10982cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:57:54 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 16:57:54 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 16:57:54 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 16:57:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:57:54 INFO - Return code: 0 16:57:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:57:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:57:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:57:54 INFO - ENV: RUST_BACKTRACE is now 1 16:57:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:57:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:57:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols-full.zip', '--title', 't-yosemite-r7-0113', '--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 16:57:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols-full.zip --title t-yosemite-r7-0113 --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 16:57:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z5iMhPPorD/Render', 16:57:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:57:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:57:54 INFO - 'HOME': '/Users/cltbld', 16:57:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:57:54 INFO - 'LOGNAME': 'cltbld', 16:57:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:57:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:57:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:57:54 INFO - 'MOZ_NO_REMOTE': '1', 16:57:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:57:54 INFO - 'NO_EM_RESTART': '1', 16:57:54 INFO - 'PAGER': '/bin/cat', 16:57:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:57:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:57:54 INFO - 'PWD': '/builds/slave/test', 16:57:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:57:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:57:54 INFO - 'RUST_BACKTRACE': '1', 16:57:54 INFO - 'SHELL': '/bin/bash', 16:57:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xlaQvid0nb/Listeners', 16:57:54 INFO - 'TMPDIR': '/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/', 16:57:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:57:54 INFO - 'USER': 'cltbld', 16:57:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:57:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:57:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:57:54 INFO - 'XPC_FLAGS': '0x0', 16:57:54 INFO - 'XPC_SERVICE_NAME': '0', 16:57:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:57:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols-full.zip', '--title', 't-yosemite-r7-0113', '--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 16:57:55 INFO - mozversion application_buildid: 20170412155407 16:57:55 INFO - mozversion application_changeset: fbde81258254c01b704bf76ee54ef2fe735109a6 16:57:55 INFO - mozversion application_display_name: Nightly 16:57:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:57:55 INFO - mozversion application_name: Firefox 16:57:55 INFO - mozversion application_remotingname: firefox 16:57:55 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 16:57:55 INFO - mozversion application_vendor: Mozilla 16:57:55 INFO - mozversion application_version: 55.0a1 16:57:55 INFO - mozversion platform_buildid: 20170412155407 16:57:55 INFO - mozversion platform_changeset: fbde81258254c01b704bf76ee54ef2fe735109a6 16:57:55 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 16:57:55 INFO - mozversion platform_version: 55.0a1 16:57:55 INFO - using testdate: 1492041475 16:57:55 INFO - actual date: 1492041475 16:57:55 INFO - starting webserver on 'localhost:49244' 16:57:55 INFO - SUITE-START | Running 2 tests 16:57:55 INFO - TEST-START | dromaeo_css 16:57:55 INFO - operating with platform_type : mac_ 16:57:55 INFO - Initialising browser for dromaeo_css test... 16:57:55 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/tmp9g1_vz/profile http://localhost:49244/getInfo.html) 16:57:58 INFO - PID 1632 | 2017-04-12 16:57:58.294 plugin-container[1635:7597] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 16:57:58 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:57:58 INFO - PID 1632 | 2017-04-12 16:57:58.300 plugin-container[1635:7597] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c1b, name = 'com.apple.CFPasteboardClient' 16:57:58 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:57:58 INFO - PID 1632 | 2017-04-12 16:57:58.300 plugin-container[1635:7597] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:57:58 INFO - PID 1632 | __metrics Screen width/height:1600/1200 16:57:58 INFO - PID 1632 | colorDepth:24 16:57:58 INFO - PID 1632 | Browser inner width/height: 1024/689 16:57:58 INFO - PID 1632 | __metrics 16:57:58 ERROR - PID 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 16:57:59 INFO - TEST-INFO | 1632: exit 0 16:57:59 INFO - Browser initialized. 16:57:59 INFO - Running cycle 1/1 for dromaeo_css test... 16:57:59 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/tmp9g1_vz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:58:02 INFO - PID 1636 | 2017-04-12 16:58:02.190 plugin-container[1640:7793] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 16:58:02 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:58:02 INFO - PID 1636 | 2017-04-12 16:58:02.191 plugin-container[1640:7793] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 16:58:02 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:58:02 INFO - PID 1636 | 2017-04-12 16:58:02.191 plugin-container[1640:7793] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:58:11 INFO - PID 1636 | RSS: Main: 235343872 16:58:11 INFO - PID 1636 | 17:01:16 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 17:01:17 INFO - PID 1636 | RSS: Main: 256839680 17:01:17 INFO - PID 1636 | 17:02:59 INFO - PID 1636 | ************************* 17:02:59 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 17:02:59 INFO - PID 1636 | 17:02:59 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:02:59 INFO - PID 1636 | Full stack: 17:02:59 INFO - PID 1636 | ************************* 17:02:59 INFO - PID 1636 | ************************* 17:02:59 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 17:02:59 INFO - PID 1636 | 17:02:59 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:02:59 INFO - PID 1636 | Full stack: 17:02:59 INFO - PID 1636 | ************************* 17:02:59 INFO - PID 1636 | ************************* 17:02:59 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 17:02:59 INFO - PID 1636 | 17:02:59 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:02:59 INFO - PID 1636 | Full stack: 17:02:59 INFO - PID 1636 | ************************* 17:02:59 INFO - PID 1636 | ************************* 17:02:59 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 17:02:59 INFO - PID 1636 | 17:02:59 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:02:59 INFO - PID 1636 | Full stack: 17:02:59 INFO - PID 1636 | ************************* 17:04:19 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 17:04:20 INFO - PID 1636 | RSS: Main: 264384512 17:04:20 INFO - PID 1636 | 17:07:22 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 17:07:22 INFO - PID 1636 | RSS: Main: 277004288 17:07:22 INFO - PID 1636 | 17:10:24 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 17:10:25 INFO - PID 1636 | RSS: Main: 269295616 17:10:25 INFO - PID 1636 | 17:13:28 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 17:13:28 INFO - PID 1636 | RSS: Main: 278974464 17:13:28 INFO - PID 1636 | 17:16:30 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 17:16:30 INFO - PID 1636 | RSS: Main: 272773120 17:16:30 INFO - PID 1636 | 17:16:30 INFO - PID 1636 | __start_tp_report 17:16:30 INFO - PID 1636 | _x_x_mozilla_page_load 17:16:30 INFO - PID 1636 | _x_x_mozilla_page_load_details 17:16:30 INFO - PID 1636 | |i|pagename|runs| 17:16:30 INFO - PID 1636 | |0;dojo.html;3104.922376940576;3534.805585692786;3537.345591065653;3582.856685732571;3590.9461358079634;6172;6194.845128871775;6198.690065496724;6212.751489940402;6278.1838361013115;4978.925316120252;5122.8207012754465;6300.7794727184555;6326.051092336152;6336.46140078094;4872.65891387603;5413.675179489223;5459.535939445142;5478.726063696819;5570.554355651539;5400.918986215191;5511.448855114497;5566.192902029204;5585.916211256835;5626.156076588503;5383.353997520284;5426.131818908955;5447.428020057916;5502.972485137589;5528.253685752412;5415.160650099201;5438.456154384569;5504.614676972614;5534.916976245359;5566.359868615121;5381.596380271495;5387.515123638891;5438.755256013449;5456.508914197741;5478.287822583078;6024.879502409971;6304.432601065881;6328.398802113792;6337.873242535124;6340.3659634036685;6483.157189565373;6576.375244351779;6647.202335719696;6718.966405167943;6726.461883049345;6819.5908245505425;6837.555558888656;6837.931620683829;6848.486363522706;6854.2117656469645;6245.812625621239;6379.553431259767;6383.68081595923;6408.455281301095;6409.327020662857;6654.367835055662;6778.830529236809;6833.829154271084;6895.931040689629;6985.231624521299;6355.904661430082;6414.61512309263;6444.258910225384;6584.604923704593;6630.071789949411;6613.801585952429;6653.667316634149;6775.119234499483;6779.711854747582;6802.897956530656;6604.042413849965;6686.297938716462;6705.2624211336715;6711.697973591103;6720.563163394364;6443.806685799434;6536.705848236746;6543.1166792483355;6689.832754181087;6694.665266736643;5355.357357117093;5365.651232669864;5399.298091248191;5407.756650950717;5425.213344065089;4781.6891902026255;4786.8085276589245;4813.3983252093485;4816.8554943351755;4828.806847726051;6183.072539119168;6381;6398.520110991694;6403.679816009274;6406.679666016774;6650.833729156713;6717.496187785936;6767.898481522682;6784.389404953479;6850.3149685032095;6378.330275321117;6379.617222966637;6382.266039405435;6389.7763578274535;6391.904121438182;5988.251468566429;6012.248468941382;6013.428724271187;6036.879262414727;6048.334683184846;6699.698513566998;6790.117284753148;6857.074294970115;6930.792076237721;6951.895721564079;6761.087253220752;6811.148606424197;6811.761588344384;6850.45196384298;6853.862922741617;5584.078627026495;6757.695903684445;6922.134733158356;6960.408365289007;6979.511434199558;6415.133956915944;6493.188351456068;6546.443552298108;6600.2409722882485;6641.701123449553;5874.089516125007;6844.794656160323;6866.485013623898;6866.896996544956;6881.759138430131;6377.585456945284;6385.648789316632;6614.768483103068;6638.17022872141;6668.533202675766;6512.088307637029;6578.868422631424;6601.933980660132;6620.073189753534;6621.966890165519;5702.258706368146;6240.687965601793;6482.675866206577;6489.837754056186;6500.642464664489;6369.554131210771;6593.736250549924;6632.933670663231;6633.535652504278;6636.004599310142;6244.562880598314;6333.746650133943;6400;6428.582142160744;6434.613923164625 17:16:30 INFO - PID 1636 | |1;ext.html;735.9632018399079;797.6410615223149;827.9006519217695;1295.0351987769113;1295.5918885551057;38597;39240.018999525026;39325.80337098311;39370.60629393705;39600.039996000385;39488.60511394892;39727.801360993224;39879.40180897282;39915.60084399155;39931.80034099833;8037.718679846206;8202.384821138408;8463.703770368038;8808.823823523526;8819.470831750115;8660.263877570414;8709.172628600272;8762.824743505125;8773.210411063003;8785.824283514326;8731.869021964676;8773.385862989593;8778.517181555011;8786.560671966408;8797.252233560155;8717.041125476193;8744.519051452167;8752.299816014704;8752.956235218815;8760.299176065932;8558.144185581454;8581.39930204886;8597.398182127254;8650;8657.740267791976;37461;37674.81162594197;37754.811225943966;37774.43338349927;37785.81107094474;3515.033365824393;3527.770694904823;3529.770564913272;3553.7690050146657;3558.5551806024246;31041;31103.688963110304;31210.843945780354;31271.218219544466;31325.843370783;3154.716075553188;3171.809691418522;3197.4564324064963;3213.3573285343023;3214.9196270093203;190315;210111;235894;286469;287098.12901871133;40398.79800601037;47249.58251252399;49119;49339.506604934206;49419.75290123598;50388.49611503838;50695.493045069816;50922;50998.49001510011;51049.979000419786;13755;13930.024898257227;13959.86040139606;13960.022798404218;13960.51138210178;11342.206045576895;11369.602063927723;11374.943125284486;11387.316760994367;11391.316521008766;10992.505337259843;13647.317634118255;13651.453941842414;13664.745317022778;13779.655508612364;5482.259894914136;5488.396276409591;5933.169356290123;5936.13925980739;5971.552133589998;843.9662413503391;998.2912132385942;1010.2120346130031;1017.6692574913183;1037.2946396450486;24803.751962480506;24872.626910596355;24934.37664058413;24950.37624059413;24973.001079956597;1068.6847380022912;1117.1621284036971;1149.350616901448;1156.1271240213584;1159.5825502818977;12248.38758062111;12729.917956973575;12749.681257968625;12800.10399272042;12836.80744788829;339912;340438;342149;342537;344022;55655;55659.443405566235;55940.72029639907;56086;56181;1488.2112480385413;1504.45087543046;1506.0436622744417;1531.9157446340334;1535.9692806143814;23158.1894633687;24348.878255608608;24542.772861357218;24708.011679533338;24799.256022319358;38603.80698096492;38639.42040869445;38650.03374915649;38677.6132238674;38716;298290;299137;299341;299369.5031524916;299980;34490.31019379648;34762.82618586891;35225;35240.82379588085;35319.29361412809;45334.319985200906;45507.31739023851;46157;46437.3034404491;46478.76760616175;25736.841842117523;30902.381952361276;31115.84442077775;31620.209494762814;31705.841470792497;23121.537569248856;30596.541051884684;31456.842715786275;31928.042158735276;31977.520337194495;34945.12637184091;37455.25089498249;37527.061823454635;37643.62356376401;37781.81109094437;29931.55102673418;30184.5472317911;31655.23334888636;31784.523232151074;32103.518447222843;24375.26874193777;24455.877720611996;24606.892689829358;24938.750612493644;25008.37479063038 17:16:30 INFO - PID 1636 | |2;jquery.html;377.9678727308179;423.47912068156165;428.2398742232539;450.8602333276684;452.726100709071;20472.38582842515;21971.560568788616;22731.659025114637;22878;22906.312810615666;21184.788152118475;21423.464413389673;23351.532969340602;23959.161429349977;24235.75764242357;5523.337199536061;6219.253689557248;6381.712822922957;6423.678816059201;6459;6062.514998800086;6264.718087686042;6320.84197895054;6331.366863313678;6335;6053.667048312341;6181.07283907413;6251.999680051193;6254.874902501947;6287.622742635456;5726.31284245891;6049.606775559597;6320.62076275425;6378.77674281398;6383.904241436382;5490.533304669108;5658.8019419320535;5673.716314184274;5699.857503562403;5728.656280623134;22680.886595566917;28024.719752802415;28045.859770701016;28193.43613127726;28516.572251416244;2252.605793986062;2307.9884600577057;2319.478117423583;2336.4158960259933;2337.439014636492;18205.817941820544;18328.450146495627;18592.163352649164;18600.34898778536;18765.34321298748;2167.111484291433;2189.4964158243693;2231.5871563760757;2232.017912118679;2240.0591751464262;122337;150446;151821;152418.23790881084;164611.0080643159;10599.576016959389;12476.251424914533;13038.152520086163;13081.738365232643;13164.840438294932;16900.239489223015;17024;17048.14759262032;17071.146442677815;17090;7297.3432391084;9029.691152334955;9182.586783594754;9227.492487913096;9382.85925711115;8622.82754344922;8731.60707768152;8756.211940925346;8892;8908.331875109392;8101.392395570356;8125.959370203112;8235.464694794819;8270.172982701803;8273.917260827435;1975.3086419752972;2245.7641947595594;2249.5388445368662;2257.31151998642;2410.1684918703018;914.9359544831798;954.6324665003884;990.8117457683018;1016.8525563793356;1028.8662473878348;20021.199152034045;22395.85676649825;25852.741472585032;26081.347966300993;27338.72661273362;999.2855108597242;1009.2430676992255;1028.0285130551556;1032.736652153711;1041.1930753665818;10057.69826905194;10435.895641043644;11361.715957101138;11448.771024579462;11455.541778328774;642217;655011;656015;656594;670286;113480;120122;140187.59812401948;153666;158658;1164.953401863916;1223.174357308813;1251.7934540800848;1255.3911352993791;1263.0463999680182;18779.718304225175;21628.783712162676;23150.958206880394;23173.304800856;23565;63056.36943630505;63350;63511.0473342891;63649.68175159094;63950.680246598466;80611.59694201726;81870.77193841978;82063.84521160818;82086.58956705178;82397;8811.251043661361;8949.821003580022;8968.05835387301;9294.628214871329;9380.061993800566;14117.788233176716;14758.409663613334;14852.777208341668;14960.700785984001;15285.923570382078;11905.94047029794;12212.38938053076;12241.20432171906;12425.254484730945;12541.247525148521;11672.766544669228;11833.467493962964;11891.940540297242;11924;12180.634580962585;12356.135070544975;12428.192167509083;12462.127651064338;12637.557685480964;12742.936285318514;9094.999550049357;9201.723948281562;9264.53677316145;9365.578548965177;9429.057094290516;11324.6036388726;12062.45718942632;12250.877491224974;12284.570040048555;12288.385580721108 17:16:30 INFO - PID 1636 | |3;mootools.html;1150.5397840863654;1384.4254634326749;1391.2417732335875;1405.7610206264933;1421.6872288096617;11687.941560292198;11739.589114380999;12013.939930300357;12027.398630068494;12052.397380130991;11528.884711152887;11555.017823485014;11666.883331166708;11693.532258709642;11745.47145378458;2484.354067942339;2534.125726624321;2536.7970562354967;2537.8350407223566;2540.695116586003;2462.729099799021;2504.0860086068546;2549.0441084593276;2553.846769193845;2563.4104156044027;2453.6074228123503;2504.3113143885485;2524.7222805491383;2530.633058206559;2536.822422430431;2445.144199530168;2505.686789151356;2517.55942710025;2518.64738936549;2524.823262371635;2412.2522018174245;2505.6116301973225;2514.2834292226626;2523.7728604425506;2526.835755675875;11730.296182229093;11809.88190118105;11810.40947952599;11820.704482387922;11842.28946263227;6589.406953374171;6826.044353790473;6844.691988860463;6847.520673552854;6849.349311815369;3362.5796775403073;3682.521272234619;4368.49762277339;4380.759058251795;4450.1767173073085;978.3249557805162;1040.9583616655325;1081.9459027048615;1273.499682601684;1306.2162702378591;13507.662308442368;15842.76235856463;16332.18339083041;16508.6698266034;16561.92347497421;5599.768051028767;5770;5913.586048976616;5934.406559344117;5946.256717910262;12295.692607684701;12302.630921072383;12360.381980900918;12361.258324500559;12363.62909112728;6654.534182607268;6681.532292739559;6697.028930805006;6697.3637504437;6713.765018224339;6081.878362432815;6173.197484327009;6242.28213755424;6256.937430625636;6269.059641053787;4390.231709450897;4518.209313370147;4519.07358991406;4548.431446069241;4567.292069729131;1075.4353964168874;1316.7695653260641;1320.2606540337442;1330.693940393715;1332.5535945458187;707.748749194039;778.933790627803;787.8345547434989;810.8580998325269;818.402566126719;6203.441690247809;6230.813075607739;6264.780732674334;6408.647524386203;6424.550281480251;519.5609709795217;589.1545632018068;594.7056207177388;616.5221952986382;622.6668732228236;4192.224438478891;4413.602775750198;4452.755098469615;4502.932456013163;4575.405197324327;14725.631859203391;14791;15373.615659608598;15384.153871537173;15385.307661155275;12189.817152742717;14789.778153327708;15081.622959426102;15119.470818521298;15374.154421506924;9714.805703885882;10813.459327033617;11062.05972492331;11195.720106997227;11201.775964480665;6625.765645140478;7157.677904494206;7217.6391180441815;7237.312455316736;7251.347378735728;11907.880921190677;12656.493740250655;12712.745745084861;12849.357532123544;12898.613041608767;10979.560817567208;13301.534446294334;13310.068295219242;13334.86665133375;13464.865351346753;9515.762105947406;9834.21326293909;9844.3601165924;9855.556499957376;9865;10594.682159535227;10864.293820901616;10890.019898209199;11033.779324413626;11069.833952490559;9315.906840931504;9325.953370233377;9337.97282298933;9348.392354496937;9528.571214295234;9146.314026448175;9187.448753074837;9287.746154269087;9300.627974880928;9406.1064198901;8351.45715528513;8356.247937685637;8361.080281169188;8449.155084491502;8471;6106.3588323227195;6182.350853160354;6214.5028397728975;6225.8132256032395;6238.469730072995;5686.800961966312;6049.818505444844;6164.321924588202;6340.334264902303;6344.873102537831 17:16:30 INFO - PID 1636 | |4;prototype.html;386.106164229808;401.66461005060773;402.96977726670497;411.9134981653851;412.66161747367164;6000.069989151689;6143.877122457559;6253.030780229061;6297.464715499183;6303.275123360815;5377.542908852753;5601.411851755558;5807.128930660392;5948.31594366648;6021.2473440819895;4575.656825738066;4598.977005114986;4671.439427268732;4697.647676424264;4727.905441891177;4580.931286030695;4684.4847066822795;4701.858944231679;4706.247000479924;4712.505186955372;3505.8772942947003;4299.5485474025245;4609.631229501649;4625.375574297461;4647.234051060592;4025.9999999999854;4658.208104622222;4664.930026049612;4667.533246675339;4692.131955588227;4007.2185923744883;4109.239790638741;4124.711270211086;4127.525334586501;4135.77253251071;5963.731632076524;5975.25309336333;5977.671228082454;5979.701014949235;6054.182685337467;4588;5506.64206826559;5529.921665275273;5538.307711536058;5538.75075621598;5317.388500322474;6047.848803779941;6099.725512351911;6503.544751867371;6606.621616218157;3893.6301051400073;4135.77253251071;4376.431063961696;4378.846740364072;4384.714993525411;6575.93424065758;6838.384545390889;6922.200227940772;6923.584584924921;6949.200841903195;4375.671824613167;4406.735595864195;4406.942333839919;4415.448068991376;4433.689641725113;3602.9911624745114;4157.773456830242;4357.716748411343;4371.759553224603;4498.955010449854;4325.480942286946;5054.1407960646775;5077.23841423782;5150.197430899156;5222.3472065991755;4771.73755443447;6194.783182588588;6224.190855188796;6229.857144998526;6484.476148105192;1291.8578956314798;1465.567657541028;1493.6937927724791;1540.2914659256867;1540.8998415102983;967.5210770668564;996.1881066930429;1010.1716592394166;1012.5595366015807;1013.366645846346;6111.205543279434;6120;6121.204243448323;6151.907721384183;6196.752129914753;5768.019436695772;6589;6705.295943925915;6709.899351509731;6720.327967203339;5699.344575373802;5780.826575202751;6078.908816367758;6223.937760622336;6226.875462490725;3214.5821043264227;3533.650122668448;3539.592946811098;3542.6634469725336;3556.7332450065833;5418.322709661293;6541.378569035934;6842.863142737217;6867.094298903039;6930.688119034656;5659.971700141556;6157.784477543264;6875.3812156906115;6899.9650052491525;6964.895526567106;5718.1136923776885;5732.713364331767;6611.305812889674;6922.7923162305215;6956.65216739161;6561.9500879859;6637;6696.229933557508;6756.223034351113;6780.220274668279;6101.7559297627595;6538.215414150332;6628.204615446178;6670.733170673118;6677.933220667731;6804;6866.965665171642;6873.666271858045;6918.55029423106;6921.2696825793555;3888.4361767542982;4394.736315821061;4409.889752756207;4429.68992170545;4432.977835110803;4940.677881395738;5839.474447299764;5968;6498.707558159799;6765.594064356154;6413.807585772434;6423.743050277936;6566.80299591013;6571.3757193066585;6604.339566043357;5400.83797486076;6535;6546.705398256994;6555.934440655532;6584.209894812653;6182.289036760651;6350.206224221973;6386.265579458422;6464.41820236181;6500.3174666659315;6529;6537.679123910943;6548.705308261045;6558.638889999692;6565.752507023651;5535.972320138373;6157.6613286269685;6303.747850085945;6323.5654273104265;6345.841353966188 17:16:30 INFO - PID 1636 | |5;yui.html;777.8755399136139;798.8282519258365;839.1231163434211;881.5239770523917;882.9690960816373;14205.289735513235;14944.028638138514;16133.75799363009;16316.265768040437;16544.172791360415;16242.18789060548;16865.83134168658;17222.827771722277;17720.113994300267;17736.645267094682;988.8269552828244;1016.1413605503375;1017.0287375556326;1017.4607458047246;1018.0633816888443;925.8843094071627;975.8633791269228;1024.1294899335542;1044.4412239451894;1045.3414348960182;1063.9255252132355;1070.8714954205507;1078.8489611454402;1079.5843600213896;1083.9024487796096;1070.8554345163382;1076.364944682637;1076.5209481780612;1077.2082519348273;1079.5465904320165;770.8265640230959;785.4501848705929;792.2077922077922;793.3811626931004;796.3867821777256;13832.377543010589;15384.692306153815;17244.137793110418;17248.655026899392;17273.568160795956;16051.438199663073;16318.673626527403;16502.917485412618;16661.33354665812;16722.581935451588;13426.664333391645;13860.792088118586;13967.231802250872;13974.091684040504;14019.50931717385;3277.131560136566;3287.2439338951935;3288.7533434992233;3321.93356132876;3333.033420308108;117731;119249;120326.39836800848;120476;120792;11954.641360759191;12036.338001410006;12053.698657533456;12079.637610871689;12108.21296615717;20219.29232476856;20261.59476810485;20326.186952522028;20472.69290960637;20564.177432902816;8395.24442800151;8611.095834937367;8893.688720894737;8910.064443233367;8918.10818918116;4450.9985253317745;4542.136993971135;4740.146773580788;4801.159797035505;4856.73287969158;4330.437043184366;4405.427294451701;4435.733855968652;4436.800343984529;4459.866204013885;828.353883970504;933.8225737109927;972.7373609125638;973.1484950668165;975.5463709375033;638.5306799502362;659.2550418027694;660.4584240922397;662.3343539742624;693.2478261086677;9353;10714.624988125379;11147.554097835995;11628.302301861915;11732.472038758106;641.7497176101324;658.5850913924197;659.1496968910052;663.1644128398253;665.7869481765788;5308.752106254367;5447.992121457543;5489.58828087895;5526.06056970316;5587.022271102541;105048;107619.92380076255;107776.92223077826;107918;109353.45323273492;95374;96367;96788.0321196779;97708.51145744226;99411.50294248482;66443.66778166078;70556;83243.75134372989;83305.58347208225;83854.99999999878;10145.898541014496;10655.987681170276;10694.125181834868;11132.888671113185;11169.609063682732;34441.138971525914;34809.30381392409;40670.79664601776;40792.3881141789;41049.384259235536;69474;69819;70193;70221.946670801;71028.28971710216;16212.837871621134;16396.67206655884;16704.498865034067;16888.83111168922;17056.232469538652;18041;18116.365927192484;18479.260829567203;18579.16393762311;18649.440516784518;16747;17050.48848534546;17129.314827406764;17165.31338746436;17475.56311092233;13406.530771422953;16707.749383759496;17067.40264090751;17090.060046697552;17581.912090439466;13636.590902272948;13679.384427700976;15736.977096488692;15936.760948585548;16130.516084517483;11827.881721183023;11889;11940.462679179283;12075.879241207476;12151.270923744603;12540.623781286577;12634.620961371174;13240;13262.867371326549;13618.673179642961 17:16:30 INFO - PID 1636 | __end_tp_report 17:16:30 INFO - PID 1636 | __start_cc_report 17:16:30 INFO - PID 1636 | _x_x_mozilla_cycle_collect,431 17:16:30 INFO - PID 1636 | __end_cc_report 17:16:30 INFO - PID 1636 | __startTimestamp1492042590816__endTimestamp 17:16:30 INFO - PID 1636 | 17:16:30 INFO - PID 1636 | ------- Summary: start ------- 17:16:30 INFO - PID 1636 | Number of tests: 6 17:16:30 INFO - PID 1636 | 17:16:30 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6174.19 Median:6403.68 stddev:742.07 (11.6%) stddev-sans-first:704.20 17:16:30 INFO - PID 1636 | Values: 3104.9 3534.8 3537.3 3582.9 3590.9 6172.0 6194.8 6198.7 6212.8 6278.2 4978.9 5122.8 6300.8 6326.1 6336.5 4872.7 5413.7 5459.5 5478.7 5570.6 5400.9 5511.4 5566.2 5585.9 5626.2 5383.4 5426.1 5447.4 5503.0 5528.3 5415.2 5438.5 5504.6 5534.9 5566.4 5381.6 5387.5 5438.8 5456.5 5478.3 6024.9 6304.4 6328.4 6337.9 6340.4 6483.2 6576.4 6647.2 6719.0 6726.5 6819.6 6837.6 6837.9 6848.5 6854.2 6245.8 6379.6 6383.7 6408.5 6409.3 6654.4 6778.8 6833.8 6895.9 6985.2 6355.9 6414.6 6444.3 6584.6 6630.1 6613.8 6653.7 6775.1 6779.7 6802.9 6604.0 6686.3 6705.3 6711.7 6720.6 6443.8 6536.7 6543.1 6689.8 6694.7 5355.4 5365.7 5399.3 5407.8 5425.2 4781.7 4786.8 4813.4 4816.9 4828.8 6183.1 6381.0 6398.5 6403.7 6406.7 6650.8 6717.5 6767.9 6784.4 6850.3 6378.3 6379.6 6382.3 6389.8 6391.9 5988.3 6012.2 6013.4 6036.9 6048.3 6699.7 6790.1 6857.1 6930.8 6951.9 6761.1 6811.1 6811.8 6850.5 6853.9 5584.1 6757.7 6922.1 6960.4 6979.5 6415.1 6493.2 6546.4 6600.2 6641.7 5874.1 6844.8 6866.5 6866.9 6881.8 6377.6 6385.6 6614.8 6638.2 6668.5 6512.1 6578.9 6601.9 6620.1 6622.0 5702.3 6240.7 6482.7 6489.8 6500.6 6369.6 6593.7 6632.9 6633.5 6636.0 6244.6 6333.7 6400.0 6428.6 6434.6 17:16:30 INFO - PID 1636 | 17:16:30 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:44945.87 Median:34626.57 stddev:78233.95 (225.9%) stddev-sans-first:78383.00 17:16:30 INFO - PID 1636 | Values: 736.0 797.6 827.9 1295.0 1295.6 38597.0 39240.0 39325.8 39370.6 39600.0 39488.6 39727.8 39879.4 39915.6 39931.8 8037.7 8202.4 8463.7 8808.8 8819.5 8660.3 8709.2 8762.8 8773.2 8785.8 8731.9 8773.4 8778.5 8786.6 8797.3 8717.0 8744.5 8752.3 8753.0 8760.3 8558.1 8581.4 8597.4 8650.0 8657.7 37461.0 37674.8 37754.8 37774.4 37785.8 3515.0 3527.8 3529.8 3553.8 3558.6 31041.0 31103.7 31210.8 31271.2 31325.8 3154.7 3171.8 3197.5 3213.4 3214.9 190315.0 210111.0 235894.0 286469.0 287098.1 40398.8 47249.6 49119.0 49339.5 49419.8 50388.5 50695.5 50922.0 50998.5 51050.0 13755.0 13930.0 13959.9 13960.0 13960.5 11342.2 11369.6 11374.9 11387.3 11391.3 10992.5 13647.3 13651.5 13664.7 13779.7 5482.3 5488.4 5933.2 5936.1 5971.6 844.0 998.3 1010.2 1017.7 1037.3 24803.8 24872.6 24934.4 24950.4 24973.0 1068.7 1117.2 1149.4 1156.1 1159.6 12248.4 12729.9 12749.7 12800.1 12836.8 339912.0 340438.0 342149.0 342537.0 344022.0 55655.0 55659.4 55940.7 56086.0 56181.0 1488.2 1504.5 1506.0 1531.9 1536.0 23158.2 24348.9 24542.8 24708.0 24799.3 38603.8 38639.4 38650.0 38677.6 38716.0 298290.0 299137.0 299341.0 299369.5 299980.0 34490.3 34762.8 35225.0 35240.8 35319.3 45334.3 45507.3 46157.0 46437.3 46478.8 25736.8 30902.4 31115.8 31620.2 31705.8 23121.5 30596.5 31456.8 31928.0 31977.5 34945.1 37455.3 37527.1 37643.6 37781.8 29931.6 30184.5 31655.2 31784.5 32103.5 24375.3 24455.9 24606.9 24938.8 25008.4 17:16:30 INFO - PID 1636 | 17:16:30 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:39248.27 Median:12746.39 stddev:109878.73 (862.0%) stddev-sans-first:110148.21 17:16:30 INFO - PID 1636 | Values: 378.0 423.5 428.2 450.9 452.7 20472.4 21971.6 22731.7 22878.0 22906.3 21184.8 21423.5 23351.5 23959.2 24235.8 5523.3 6219.3 6381.7 6423.7 6459.0 6062.5 6264.7 6320.8 6331.4 6335.0 6053.7 6181.1 6252.0 6254.9 6287.6 5726.3 6049.6 6320.6 6378.8 6383.9 5490.5 5658.8 5673.7 5699.9 5728.7 22680.9 28024.7 28045.9 28193.4 28516.6 2252.6 2308.0 2319.5 2336.4 2337.4 18205.8 18328.5 18592.2 18600.3 18765.3 2167.1 2189.5 2231.6 2232.0 2240.1 122337.0 150446.0 151821.0 152418.2 164611.0 10599.6 12476.3 13038.2 13081.7 13164.8 16900.2 17024.0 17048.1 17071.1 17090.0 7297.3 9029.7 9182.6 9227.5 9382.9 8622.8 8731.6 8756.2 8892.0 8908.3 8101.4 8126.0 8235.5 8270.2 8273.9 1975.3 2245.8 2249.5 2257.3 2410.2 914.9 954.6 990.8 1016.9 1028.9 20021.2 22395.9 25852.7 26081.3 27338.7 999.3 1009.2 1028.0 1032.7 1041.2 10057.7 10435.9 11361.7 11448.8 11455.5 642217.0 655011.0 656015.0 656594.0 670286.0 113480.0 120122.0 140187.6 153666.0 158658.0 1165.0 1223.2 1251.8 1255.4 1263.0 18779.7 21628.8 23151.0 23173.3 23565.0 63056.4 63350.0 63511.0 63649.7 63950.7 80611.6 81870.8 82063.8 82086.6 82397.0 8811.3 8949.8 8968.1 9294.6 9380.1 14117.8 14758.4 14852.8 14960.7 15285.9 11905.9 12212.4 12241.2 12425.3 12541.2 11672.8 11833.5 11891.9 11924.0 12180.6 12356.1 12428.2 12462.1 12637.6 12742.9 9095.0 9201.7 9264.5 9365.6 9429.1 11324.6 12062.5 12250.9 12284.6 12288.4 17:16:30 INFO - PID 1636 | 17:16:30 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:7193.77 Median:2551.45 stddev:4486.66 (175.8%) stddev-sans-first:4476.26 17:16:30 INFO - PID 1636 | Values: 1150.5 1384.4 1391.2 1405.8 1421.7 11687.9 11739.6 12013.9 12027.4 12052.4 11528.9 11555.0 11666.9 11693.5 11745.5 2484.4 2534.1 2536.8 2537.8 2540.7 2462.7 2504.1 2549.0 2553.8 2563.4 2453.6 2504.3 2524.7 2530.6 2536.8 2445.1 2505.7 2517.6 2518.6 2524.8 2412.3 2505.6 2514.3 2523.8 2526.8 11730.3 11809.9 11810.4 11820.7 11842.3 6589.4 6826.0 6844.7 6847.5 6849.3 3362.6 3682.5 4368.5 4380.8 4450.2 978.3 1041.0 1081.9 1273.5 1306.2 13507.7 15842.8 16332.2 16508.7 16561.9 5599.8 5770.0 5913.6 5934.4 5946.3 12295.7 12302.6 12360.4 12361.3 12363.6 6654.5 6681.5 6697.0 6697.4 6713.8 6081.9 6173.2 6242.3 6256.9 6269.1 4390.2 4518.2 4519.1 4548.4 4567.3 1075.4 1316.8 1320.3 1330.7 1332.6 707.7 778.9 787.8 810.9 818.4 6203.4 6230.8 6264.8 6408.6 6424.6 519.6 589.2 594.7 616.5 622.7 4192.2 4413.6 4452.8 4502.9 4575.4 14725.6 14791.0 15373.6 15384.2 15385.3 12189.8 14789.8 15081.6 15119.5 15374.2 9714.8 10813.5 11062.1 11195.7 11201.8 6625.8 7157.7 7217.6 7237.3 7251.3 11907.9 12656.5 12712.7 12849.4 12898.6 10979.6 13301.5 13310.1 13334.9 13464.9 9515.8 9834.2 9844.4 9855.6 9865.0 10594.7 10864.3 10890.0 11033.8 11069.8 9315.9 9326.0 9338.0 9348.4 9528.6 9146.3 9187.4 9287.7 9300.6 9406.1 8351.5 8356.2 8361.1 8449.2 8471.0 6106.4 6182.4 6214.5 6225.8 6238.5 5686.8 6049.8 6164.3 6340.3 6344.9 17:16:30 INFO - PID 1636 | 17:16:30 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5251.18 Median:5965.87 stddev:1635.39 (27.4%) stddev-sans-first:1598.69 17:16:30 INFO - PID 1636 | Values: 386.1 401.7 403.0 411.9 412.7 6000.1 6143.9 6253.0 6297.5 6303.3 5377.5 5601.4 5807.1 5948.3 6021.2 4575.7 4599.0 4671.4 4697.6 4727.9 4580.9 4684.5 4701.9 4706.2 4712.5 3505.9 4299.5 4609.6 4625.4 4647.2 4026.0 4658.2 4664.9 4667.5 4692.1 4007.2 4109.2 4124.7 4127.5 4135.8 5963.7 5975.3 5977.7 5979.7 6054.2 4588.0 5506.6 5529.9 5538.3 5538.8 5317.4 6047.8 6099.7 6503.5 6606.6 3893.6 4135.8 4376.4 4378.8 4384.7 6575.9 6838.4 6922.2 6923.6 6949.2 4375.7 4406.7 4406.9 4415.4 4433.7 3603.0 4157.8 4357.7 4371.8 4499.0 4325.5 5054.1 5077.2 5150.2 5222.3 4771.7 6194.8 6224.2 6229.9 6484.5 1291.9 1465.6 1493.7 1540.3 1540.9 967.5 996.2 1010.2 1012.6 1013.4 6111.2 6120.0 6121.2 6151.9 6196.8 5768.0 6589.0 6705.3 6709.9 6720.3 5699.3 5780.8 6078.9 6223.9 6226.9 3214.6 3533.7 3539.6 3542.7 3556.7 5418.3 6541.4 6842.9 6867.1 6930.7 5660.0 6157.8 6875.4 6900.0 6964.9 5718.1 5732.7 6611.3 6922.8 6956.7 6562.0 6637.0 6696.2 6756.2 6780.2 6101.8 6538.2 6628.2 6670.7 6677.9 6804.0 6867.0 6873.7 6918.6 6921.3 3888.4 4394.7 4409.9 4429.7 4433.0 4940.7 5839.5 5968.0 6498.7 6765.6 6413.8 6423.7 6566.8 6571.4 6604.3 5400.8 6535.0 6546.7 6555.9 6584.2 6182.3 6350.2 6386.3 6464.4 6500.3 6529.0 6537.7 6548.7 6558.6 6565.8 5536.0 6157.7 6303.7 6323.6 6345.8 17:16:30 INFO - PID 1636 | 17:16:30 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:21860.28 Median:17261.11 stddev:31061.72 (180.0%) stddev-sans-first:31108.52 17:16:30 INFO - PID 1636 | Values: 777.9 798.8 839.1 881.5 883.0 14205.3 14944.0 16133.8 16316.3 16544.2 16242.2 16865.8 17222.8 17720.1 17736.6 988.8 1016.1 1017.0 1017.5 1018.1 925.9 975.9 1024.1 1044.4 1045.3 1063.9 1070.9 1078.8 1079.6 1083.9 1070.9 1076.4 1076.5 1077.2 1079.5 770.8 785.5 792.2 793.4 796.4 13832.4 15384.7 17244.1 17248.7 17273.6 16051.4 16318.7 16502.9 16661.3 16722.6 13426.7 13860.8 13967.2 13974.1 14019.5 3277.1 3287.2 3288.8 3321.9 3333.0 117731.0 119249.0 120326.4 120476.0 120792.0 11954.6 12036.3 12053.7 12079.6 12108.2 20219.3 20261.6 20326.2 20472.7 20564.2 8395.2 8611.1 8893.7 8910.1 8918.1 4451.0 4542.1 4740.1 4801.2 4856.7 4330.4 4405.4 4435.7 4436.8 4459.9 828.4 933.8 972.7 973.1 975.5 638.5 659.3 660.5 662.3 693.2 9353.0 10714.6 11147.6 11628.3 11732.5 641.7 658.6 659.1 663.2 665.8 5308.8 5448.0 5489.6 5526.1 5587.0 105048.0 107619.9 107776.9 107918.0 109353.5 95374.0 96367.0 96788.0 97708.5 99411.5 66443.7 70556.0 83243.8 83305.6 83855.0 10145.9 10656.0 10694.1 11132.9 11169.6 34441.1 34809.3 40670.8 40792.4 41049.4 69474.0 69819.0 70193.0 70221.9 71028.3 16212.8 16396.7 16704.5 16888.8 17056.2 18041.0 18116.4 18479.3 18579.2 18649.4 16747.0 17050.5 17129.3 17165.3 17475.6 13406.5 16707.7 17067.4 17090.1 17581.9 13636.6 13679.4 15737.0 15936.8 16130.5 11827.9 11889.0 11940.5 12075.9 12151.3 12540.6 12634.6 13240.0 13262.9 13618.7 17:16:30 INFO - PID 1636 | -------- Summary: end -------- 17:16:30 INFO - PID 1636 | 17:16:31 INFO - TEST-INFO | 1636: exit 0 17:16:31 INFO - TEST-OK | dromaeo_css | took 1116433ms 17:16:31 INFO - TEST-START | kraken 17:16:31 INFO - operating with platform_type : mac_ 17:16:31 INFO - Initialising browser for kraken test... 17:16:31 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/tmp1Y0m82/profile http://localhost:49244/getInfo.html) 17:16:34 INFO - PID 1647 | 2017-04-12 17:16:34.466 plugin-container[1648:10130] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 17:16:34 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:16:34 INFO - PID 1647 | 2017-04-12 17:16:34.469 plugin-container[1648:10130] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 17:16:34 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:16:34 INFO - PID 1647 | 2017-04-12 17:16:34.469 plugin-container[1648:10130] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:16:34 INFO - PID 1647 | __metrics Screen width/height:1600/1200 17:16:34 INFO - PID 1647 | colorDepth:24 17:16:34 INFO - PID 1647 | Browser inner width/height: 1024/689 17:16:34 INFO - PID 1647 | __metrics 17:16:34 ERROR - PID 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 17:16:35 INFO - TEST-INFO | 1647: exit 0 17:16:35 INFO - Browser initialized. 17:16:35 INFO - Running cycle 1/1 for kraken test... 17:16:35 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/T/tmp1Y0m82/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:16:38 INFO - PID 1649 | 2017-04-12 17:16:38.136 plugin-container[1650:10259] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 17:16:38 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:16:38 INFO - PID 1649 | 2017-04-12 17:16:38.137 plugin-container[1650:10259] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 17:16:38 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:16:38 INFO - PID 1649 | 2017-04-12 17:16:38.137 plugin-container[1650:10259] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:16:48 INFO - PID 1649 | RSS: Main: 236265472 17:16:48 INFO - PID 1649 | 17:16:50 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 17:16:51 INFO - PID 1649 | RSS: Main: 250695680 17:16:51 INFO - PID 1649 | 17:16:54 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 17:16:55 INFO - PID 1649 | RSS: Main: 259342336 17:16:55 INFO - PID 1649 | 17:16:59 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 17:16:59 INFO - PID 1649 | RSS: Main: 261705728 17:16:59 INFO - PID 1649 | 17:17:01 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 17:17:02 INFO - PID 1649 | RSS: Main: 263741440 17:17:02 INFO - PID 1649 | 17:17:04 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:17:05 INFO - PID 1649 | RSS: Main: 264380416 17:17:05 INFO - PID 1649 | 17:17:08 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 17:17:09 INFO - PID 1649 | RSS: Main: 270151680 17:17:09 INFO - PID 1649 | 17:17:12 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 17:17:13 INFO - PID 1649 | RSS: Main: 270880768 17:17:13 INFO - PID 1649 | 17:17:16 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 17:17:17 INFO - PID 1649 | RSS: Main: 270585856 17:17:17 INFO - PID 1649 | 17:17:18 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:17:19 INFO - PID 1649 | RSS: Main: 270499840 17:17:19 INFO - PID 1649 | 17:17:21 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:17:22 INFO - PID 1649 | RSS: Main: 270573568 17:17:22 INFO - PID 1649 | 17:17:24 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:17:24 INFO - PID 1649 | RSS: Main: 270594048 17:17:24 INFO - PID 1649 | 17:17:27 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:17:28 INFO - PID 1649 | RSS: Main: 271106048 17:17:28 INFO - PID 1649 | 17:17:32 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:17:32 INFO - PID 1649 | RSS: Main: 267960320 17:17:32 INFO - PID 1649 | 17:17:34 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 17:17:35 INFO - PID 1649 | RSS: Main: 271216640 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | __start_tp_report 17:17:35 INFO - PID 1649 | _x_x_mozilla_page_load 17:17:35 INFO - PID 1649 | _x_x_mozilla_page_load_details 17:17:35 INFO - PID 1649 | |i|pagename|runs| 17:17:35 INFO - PID 1649 | |0;ai-astar;109;121;121;123;149;122;124;122;123;122 17:17:35 INFO - PID 1649 | |1;audio-beat-detection;159;161;117;157;132;166;129;116;138;122 17:17:35 INFO - PID 1649 | |2;audio-dft;312;267;293;281;262;312;229;213;225;264 17:17:35 INFO - PID 1649 | |3;audio-fft;81;60;63;89;60;56;58;66;107;93 17:17:35 INFO - PID 1649 | |4;audio-oscillator;85;105;88;73;72;75;132;113;108;84 17:17:35 INFO - PID 1649 | |5;imaging-gaussian-blur;124;143;94;92;106;102;143;124;98;86 17:17:35 INFO - PID 1649 | |6;imaging-darkroom;100;148;101;102;141;109;162;134;101;102 17:17:35 INFO - PID 1649 | |7;imaging-desaturate;101;103;103;102;103;149;152;164;109;120 17:17:35 INFO - PID 1649 | |8;json-parse-financial;52;51;52;52;51;53;52;53;52;53 17:17:35 INFO - PID 1649 | |9;json-stringify-tinderbox;66;63;74;70;68;62;63;63;62;63 17:17:35 INFO - PID 1649 | |10;stanford-crypto-aes;90;88;87;89;114;118;79;86;76;80 17:17:35 INFO - PID 1649 | |11;stanford-crypto-ccm;168;162;147;146;154;144;163;160;188;146 17:17:35 INFO - PID 1649 | |12;stanford-crypto-pbkdf2;259;197;204;190;259;258;214;203;181;190 17:17:35 INFO - PID 1649 | |13;stanford-crypto-sha256-iterative;70;72;72;70;73;76;91;99;82;59 17:17:35 INFO - PID 1649 | __end_tp_report 17:17:35 INFO - PID 1649 | __start_cc_report 17:17:35 INFO - PID 1649 | _x_x_mozilla_cycle_collect,1027 17:17:35 INFO - PID 1649 | __end_cc_report 17:17:35 INFO - PID 1649 | __startTimestamp1492042655038__endTimestamp 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | ------- Summary: start ------- 17:17:35 INFO - PID 1649 | Number of tests: 14 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | [#0] ai-astar Cycles:10 Average:123.60 Median:122.50 stddev:9.89 (8.1%) stddev-sans-first:8.97 17:17:35 INFO - PID 1649 | Values: 109.0 121.0 121.0 123.0 149.0 122.0 124.0 122.0 123.0 122.0 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:147.50 stddev:19.39 (13.1%) stddev-sans-first:19.27 17:17:35 INFO - PID 1649 | Values: 159.0 161.0 117.0 157.0 132.0 166.0 129.0 116.0 138.0 122.0 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | [#2] audio-dft Cycles:10 Average:265.80 Median:274.00 stddev:35.06 (12.8%) stddev-sans-first:32.97 17:17:35 INFO - PID 1649 | Values: 312.0 267.0 293.0 281.0 262.0 312.0 229.0 213.0 225.0 264.0 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | [#3] audio-fft Cycles:10 Average:73.30 Median:64.50 stddev:17.88 (27.7%) stddev-sans-first:18.74 17:17:35 INFO - PID 1649 | Values: 81.0 60.0 63.0 89.0 60.0 56.0 58.0 66.0 107.0 93.0 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:74.00 stddev:20.06 (27.1%) stddev-sans-first:21.04 17:17:35 INFO - PID 1649 | Values: 85.0 105.0 88.0 73.0 72.0 75.0 132.0 113.0 108.0 84.0 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:111.20 Median:114.50 stddev:20.91 (18.3%) stddev-sans-first:21.66 17:17:35 INFO - PID 1649 | Values: 124.0 143.0 94.0 92.0 106.0 102.0 143.0 124.0 98.0 86.0 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | [#6] imaging-darkroom Cycles:10 Average:120.00 Median:121.50 stddev:23.75 (19.5%) stddev-sans-first:24.06 17:17:35 INFO - PID 1649 | Values: 100.0 148.0 101.0 102.0 141.0 109.0 162.0 134.0 101.0 102.0 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | [#7] imaging-desaturate Cycles:10 Average:120.60 Median:114.50 stddev:24.65 (21.5%) stddev-sans-first:25.11 17:17:35 INFO - PID 1649 | Values: 101.0 103.0 103.0 102.0 103.0 149.0 152.0 164.0 109.0 120.0 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.00 stddev:0.74 (1.4%) stddev-sans-first:0.78 17:17:35 INFO - PID 1649 | Values: 52.0 51.0 52.0 52.0 51.0 53.0 52.0 53.0 52.0 53.0 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:64.50 stddev:4.06 (6.3%) stddev-sans-first:4.30 17:17:35 INFO - PID 1649 | Values: 66.0 63.0 74.0 70.0 68.0 62.0 63.0 63.0 62.0 63.0 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | [#10] stanford-crypto-aes Cycles:10 Average:90.70 Median:86.50 stddev:14.15 (16.4%) stddev-sans-first:15.01 17:17:35 INFO - PID 1649 | Values: 90.0 88.0 87.0 89.0 114.0 118.0 79.0 86.0 76.0 80.0 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:157.80 Median:161.00 stddev:13.60 (8.4%) stddev-sans-first:13.92 17:17:35 INFO - PID 1649 | Values: 168.0 162.0 147.0 146.0 154.0 144.0 163.0 160.0 188.0 146.0 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:215.50 Median:209.00 stddev:31.12 (14.9%) stddev-sans-first:28.75 17:17:35 INFO - PID 1649 | Values: 259.0 197.0 204.0 190.0 259.0 258.0 214.0 203.0 181.0 190.0 17:17:35 INFO - PID 1649 | 17:17:35 INFO - PID 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.40 Median:74.50 stddev:11.50 (15.4%) stddev-sans-first:11.96 17:17:35 INFO - PID 1649 | Values: 70.0 72.0 72.0 70.0 73.0 76.0 91.0 99.0 82.0 59.0 17:17:35 INFO - PID 1649 | -------- Summary: end -------- 17:17:35 INFO - PID 1649 | 17:17:36 INFO - TEST-INFO | 1649: exit 0 17:17:36 INFO - TEST-OK | kraken | took 64849ms 17:17:36 INFO - SUITE-END | took 1181s 17:17:36 INFO - Completed test suite (00:19:41) 17:17:36 INFO - javascript benchmark 17:17:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7938.781340115109, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3104.922376940576, 3534.805585692786, 3537.345591065653, 3582.856685732571, 3590.9461358079634, 6172.0, 6194.845128871775, 6198.690065496724, 6212.751489940402, 6278.1838361013115, 4978.925316120252, 5122.8207012754465, 6300.7794727184555, 6326.051092336152, 6336.46140078094, 4872.65891387603, 5413.675179489223, 5459.535939445142, 5478.726063696819, 5570.554355651539, 5400.918986215191, 5511.448855114497, 5566.192902029204, 5585.916211256835, 5626.156076588503, 5383.353997520284, 5426.131818908955, 5447.428020057916, 5502.972485137589, 5528.253685752412, 5415.160650099201, 5438.456154384569, 5504.614676972614, 5534.916976245359, 5566.359868615121, 5381.596380271495, 5387.515123638891, 5438.755256013449, 5456.508914197741, 5478.287822583078, 6024.879502409971, 6304.432601065881, 6328.398802113792, 6337.873242535124, 6340.3659634036685, 6483.157189565373, 6576.375244351779, 6647.202335719696, 6718.966405167943, 6726.461883049345, 6819.5908245505425, 6837.555558888656, 6837.931620683829, 6848.486363522706, 6854.2117656469645, 6245.812625621239, 6379.553431259767, 6383.68081595923, 6408.455281301095, 6409.327020662857, 6654.367835055662, 6778.830529236809, 6833.829154271084, 6895.931040689629, 6985.231624521299, 6355.904661430082, 6414.61512309263, 6444.258910225384, 6584.604923704593, 6630.071789949411, 6613.801585952429, 6653.667316634149, 6775.119234499483, 6779.711854747582, 6802.897956530656, 6604.042413849965, 6686.297938716462, 6705.2624211336715, 6711.697973591103, 6720.563163394364, 6443.806685799434, 6536.705848236746, 6543.1166792483355, 6689.832754181087, 6694.665266736643, 5355.357357117093, 5365.651232669864, 5399.298091248191, 5407.756650950717, 5425.213344065089, 4781.6891902026255, 4786.8085276589245, 4813.3983252093485, 4816.8554943351755, 4828.806847726051, 6183.072539119168, 6381.0, 6398.520110991694, 6403.679816009274, 6406.679666016774, 6650.833729156713, 6717.496187785936, 6767.898481522682, 6784.389404953479, 6850.3149685032095, 6378.330275321117, 6379.617222966637, 6382.266039405435, 6389.7763578274535, 6391.904121438182, 5988.251468566429, 6012.248468941382, 6013.428724271187, 6036.879262414727, 6048.334683184846, 6699.698513566998, 6790.117284753148, 6857.074294970115, 6930.792076237721, 6951.895721564079, 6761.087253220752, 6811.148606424197, 6811.761588344384, 6850.45196384298, 6853.862922741617, 5584.078627026495, 6757.695903684445, 6922.134733158356, 6960.408365289007, 6979.511434199558, 6415.133956915944, 6493.188351456068, 6546.443552298108, 6600.2409722882485, 6641.701123449553, 5874.089516125007, 6844.794656160323, 6866.485013623898, 6866.896996544956, 6881.759138430131, 6377.585456945284, 6385.648789316632, 6614.768483103068, 6638.17022872141, 6668.533202675766, 6512.088307637029, 6578.868422631424, 6601.933980660132, 6620.073189753534, 6621.966890165519, 5702.258706368146, 6240.687965601793, 6482.675866206577, 6489.837754056186, 6500.642464664489, 6369.554131210771, 6593.736250549924, 6632.933670663231, 6633.535652504278, 6636.004599310142, 6244.562880598314, 6333.746650133943, 6400.0, 6428.582142160744, 6434.613923164625], "value": 6124.07602400117, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.9632018399079, 797.6410615223149, 827.9006519217695, 1295.0351987769113, 1295.5918885551057, 38597.0, 39240.018999525026, 39325.80337098311, 39370.60629393705, 39600.039996000385, 39488.60511394892, 39727.801360993224, 39879.40180897282, 39915.60084399155, 39931.80034099833, 8037.718679846206, 8202.384821138408, 8463.703770368038, 8808.823823523526, 8819.470831750115, 8660.263877570414, 8709.172628600272, 8762.824743505125, 8773.210411063003, 8785.824283514326, 8731.869021964676, 8773.385862989593, 8778.517181555011, 8786.560671966408, 8797.252233560155, 8717.041125476193, 8744.519051452167, 8752.299816014704, 8752.956235218815, 8760.299176065932, 8558.144185581454, 8581.39930204886, 8597.398182127254, 8650.0, 8657.740267791976, 37461.0, 37674.81162594197, 37754.811225943966, 37774.43338349927, 37785.81107094474, 3515.033365824393, 3527.770694904823, 3529.770564913272, 3553.7690050146657, 3558.5551806024246, 31041.0, 31103.688963110304, 31210.843945780354, 31271.218219544466, 31325.843370783, 3154.716075553188, 3171.809691418522, 3197.4564324064963, 3213.3573285343023, 3214.9196270093203, 190315.0, 210111.0, 235894.0, 286469.0, 287098.12901871133, 40398.79800601037, 47249.58251252399, 49119.0, 49339.506604934206, 49419.75290123598, 50388.49611503838, 50695.493045069816, 50922.0, 50998.49001510011, 51049.979000419786, 13755.0, 13930.024898257227, 13959.86040139606, 13960.022798404218, 13960.51138210178, 11342.206045576895, 11369.602063927723, 11374.943125284486, 11387.316760994367, 11391.316521008766, 10992.505337259843, 13647.317634118255, 13651.453941842414, 13664.745317022778, 13779.655508612364, 5482.259894914136, 5488.396276409591, 5933.169356290123, 5936.13925980739, 5971.552133589998, 843.9662413503391, 998.2912132385942, 1010.2120346130031, 1017.6692574913183, 1037.2946396450486, 24803.751962480506, 24872.626910596355, 24934.37664058413, 24950.37624059413, 24973.001079956597, 1068.6847380022912, 1117.1621284036971, 1149.350616901448, 1156.1271240213584, 1159.5825502818977, 12248.38758062111, 12729.917956973575, 12749.681257968625, 12800.10399272042, 12836.80744788829, 339912.0, 340438.0, 342149.0, 342537.0, 344022.0, 55655.0, 55659.443405566235, 55940.72029639907, 56086.0, 56181.0, 1488.2112480385413, 1504.45087543046, 1506.0436622744417, 1531.9157446340334, 1535.9692806143814, 23158.1894633687, 24348.878255608608, 24542.772861357218, 24708.011679533338, 24799.256022319358, 38603.80698096492, 38639.42040869445, 38650.03374915649, 38677.6132238674, 38716.0, 298290.0, 299137.0, 299341.0, 299369.5031524916, 299980.0, 34490.31019379648, 34762.82618586891, 35225.0, 35240.82379588085, 35319.29361412809, 45334.319985200906, 45507.31739023851, 46157.0, 46437.3034404491, 46478.76760616175, 25736.841842117523, 30902.381952361276, 31115.84442077775, 31620.209494762814, 31705.841470792497, 23121.537569248856, 30596.541051884684, 31456.842715786275, 31928.042158735276, 31977.520337194495, 34945.12637184091, 37455.25089498249, 37527.061823454635, 37643.62356376401, 37781.81109094437, 29931.55102673418, 30184.5472317911, 31655.23334888636, 31784.523232151074, 32103.518447222843, 24375.26874193777, 24455.877720611996, 24606.892689829358, 24938.750612493644, 25008.37479063038], "value": 17685.782899430425, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.9678727308179, 423.47912068156165, 428.2398742232539, 450.8602333276684, 452.726100709071, 20472.38582842515, 21971.560568788616, 22731.659025114637, 22878.0, 22906.312810615666, 21184.788152118475, 21423.464413389673, 23351.532969340602, 23959.161429349977, 24235.75764242357, 5523.337199536061, 6219.253689557248, 6381.712822922957, 6423.678816059201, 6459.0, 6062.514998800086, 6264.718087686042, 6320.84197895054, 6331.366863313678, 6335.0, 6053.667048312341, 6181.07283907413, 6251.999680051193, 6254.874902501947, 6287.622742635456, 5726.31284245891, 6049.606775559597, 6320.62076275425, 6378.77674281398, 6383.904241436382, 5490.533304669108, 5658.8019419320535, 5673.716314184274, 5699.857503562403, 5728.656280623134, 22680.886595566917, 28024.719752802415, 28045.859770701016, 28193.43613127726, 28516.572251416244, 2252.605793986062, 2307.9884600577057, 2319.478117423583, 2336.4158960259933, 2337.439014636492, 18205.817941820544, 18328.450146495627, 18592.163352649164, 18600.34898778536, 18765.34321298748, 2167.111484291433, 2189.4964158243693, 2231.5871563760757, 2232.017912118679, 2240.0591751464262, 122337.0, 150446.0, 151821.0, 152418.23790881084, 164611.0080643159, 10599.576016959389, 12476.251424914533, 13038.152520086163, 13081.738365232643, 13164.840438294932, 16900.239489223015, 17024.0, 17048.14759262032, 17071.146442677815, 17090.0, 7297.3432391084, 9029.691152334955, 9182.586783594754, 9227.492487913096, 9382.85925711115, 8622.82754344922, 8731.60707768152, 8756.211940925346, 8892.0, 8908.331875109392, 8101.392395570356, 8125.959370203112, 8235.464694794819, 8270.172982701803, 8273.917260827435, 1975.3086419752972, 2245.7641947595594, 2249.5388445368662, 2257.31151998642, 2410.1684918703018, 914.9359544831798, 954.6324665003884, 990.8117457683018, 1016.8525563793356, 1028.8662473878348, 20021.199152034045, 22395.85676649825, 25852.741472585032, 26081.347966300993, 27338.72661273362, 999.2855108597242, 1009.2430676992255, 1028.0285130551556, 1032.736652153711, 1041.1930753665818, 10057.69826905194, 10435.895641043644, 11361.715957101138, 11448.771024579462, 11455.541778328774, 642217.0, 655011.0, 656015.0, 656594.0, 670286.0, 113480.0, 120122.0, 140187.59812401948, 153666.0, 158658.0, 1164.953401863916, 1223.174357308813, 1251.7934540800848, 1255.3911352993791, 1263.0463999680182, 18779.718304225175, 21628.783712162676, 23150.958206880394, 23173.304800856, 23565.0, 63056.36943630505, 63350.0, 63511.0473342891, 63649.68175159094, 63950.680246598466, 80611.59694201726, 81870.77193841978, 82063.84521160818, 82086.58956705178, 82397.0, 8811.251043661361, 8949.821003580022, 8968.05835387301, 9294.628214871329, 9380.061993800566, 14117.788233176716, 14758.409663613334, 14852.777208341668, 14960.700785984001, 15285.923570382078, 11905.94047029794, 12212.38938053076, 12241.20432171906, 12425.254484730945, 12541.247525148521, 11672.766544669228, 11833.467493962964, 11891.940540297242, 11924.0, 12180.634580962585, 12356.135070544975, 12428.192167509083, 12462.127651064338, 12637.557685480964, 12742.936285318514, 9094.999550049357, 9201.723948281562, 9264.53677316145, 9365.578548965177, 9429.057094290516, 11324.6036388726, 12062.45718942632, 12250.877491224974, 12284.570040048555, 12288.385580721108], "value": 10859.202048240852, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1150.5397840863654, 1384.4254634326749, 1391.2417732335875, 1405.7610206264933, 1421.6872288096617, 11687.941560292198, 11739.589114380999, 12013.939930300357, 12027.398630068494, 12052.397380130991, 11528.884711152887, 11555.017823485014, 11666.883331166708, 11693.532258709642, 11745.47145378458, 2484.354067942339, 2534.125726624321, 2536.7970562354967, 2537.8350407223566, 2540.695116586003, 2462.729099799021, 2504.0860086068546, 2549.0441084593276, 2553.846769193845, 2563.4104156044027, 2453.6074228123503, 2504.3113143885485, 2524.7222805491383, 2530.633058206559, 2536.822422430431, 2445.144199530168, 2505.686789151356, 2517.55942710025, 2518.64738936549, 2524.823262371635, 2412.2522018174245, 2505.6116301973225, 2514.2834292226626, 2523.7728604425506, 2526.835755675875, 11730.296182229093, 11809.88190118105, 11810.40947952599, 11820.704482387922, 11842.28946263227, 6589.406953374171, 6826.044353790473, 6844.691988860463, 6847.520673552854, 6849.349311815369, 3362.5796775403073, 3682.521272234619, 4368.49762277339, 4380.759058251795, 4450.1767173073085, 978.3249557805162, 1040.9583616655325, 1081.9459027048615, 1273.499682601684, 1306.2162702378591, 13507.662308442368, 15842.76235856463, 16332.18339083041, 16508.6698266034, 16561.92347497421, 5599.768051028767, 5770.0, 5913.586048976616, 5934.406559344117, 5946.256717910262, 12295.692607684701, 12302.630921072383, 12360.381980900918, 12361.258324500559, 12363.62909112728, 6654.534182607268, 6681.532292739559, 6697.028930805006, 6697.3637504437, 6713.765018224339, 6081.878362432815, 6173.197484327009, 6242.28213755424, 6256.937430625636, 6269.059641053787, 4390.231709450897, 4518.209313370147, 4519.07358991406, 4548.431446069241, 4567.292069729131, 1075.4353964168874, 1316.7695653260641, 1320.2606540337442, 1330.693940393715, 1332.5535945458187, 707.748749194039, 778.933790627803, 787.8345547434989, 810.8580998325269, 818.402566126719, 6203.441690247809, 6230.813075607739, 6264.780732674334, 6408.647524386203, 6424.550281480251, 519.5609709795217, 589.1545632018068, 594.7056207177388, 616.5221952986382, 622.6668732228236, 4192.224438478891, 4413.602775750198, 4452.755098469615, 4502.932456013163, 4575.405197324327, 14725.631859203391, 14791.0, 15373.615659608598, 15384.153871537173, 15385.307661155275, 12189.817152742717, 14789.778153327708, 15081.622959426102, 15119.470818521298, 15374.154421506924, 9714.805703885882, 10813.459327033617, 11062.05972492331, 11195.720106997227, 11201.775964480665, 6625.765645140478, 7157.677904494206, 7217.6391180441815, 7237.312455316736, 7251.347378735728, 11907.880921190677, 12656.493740250655, 12712.745745084861, 12849.357532123544, 12898.613041608767, 10979.560817567208, 13301.534446294334, 13310.068295219242, 13334.86665133375, 13464.865351346753, 9515.762105947406, 9834.21326293909, 9844.3601165924, 9855.556499957376, 9865.0, 10594.682159535227, 10864.293820901616, 10890.019898209199, 11033.779324413626, 11069.833952490559, 9315.906840931504, 9325.953370233377, 9337.97282298933, 9348.392354496937, 9528.571214295234, 9146.314026448175, 9187.448753074837, 9287.746154269087, 9300.627974880928, 9406.1064198901, 8351.45715528513, 8356.247937685637, 8361.080281169188, 8449.155084491502, 8471.0, 6106.3588323227195, 6182.350853160354, 6214.5028397728975, 6225.8132256032395, 6238.469730072995, 5686.800961966312, 6049.818505444844, 6164.321924588202, 6340.334264902303, 6344.873102537831], "value": 5372.592510825964, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.106164229808, 401.66461005060773, 402.96977726670497, 411.9134981653851, 412.66161747367164, 6000.069989151689, 6143.877122457559, 6253.030780229061, 6297.464715499183, 6303.275123360815, 5377.542908852753, 5601.411851755558, 5807.128930660392, 5948.31594366648, 6021.2473440819895, 4575.656825738066, 4598.977005114986, 4671.439427268732, 4697.647676424264, 4727.905441891177, 4580.931286030695, 4684.4847066822795, 4701.858944231679, 4706.247000479924, 4712.505186955372, 3505.8772942947003, 4299.5485474025245, 4609.631229501649, 4625.375574297461, 4647.234051060592, 4025.9999999999854, 4658.208104622222, 4664.930026049612, 4667.533246675339, 4692.131955588227, 4007.2185923744883, 4109.239790638741, 4124.711270211086, 4127.525334586501, 4135.77253251071, 5963.731632076524, 5975.25309336333, 5977.671228082454, 5979.701014949235, 6054.182685337467, 4588.0, 5506.64206826559, 5529.921665275273, 5538.307711536058, 5538.75075621598, 5317.388500322474, 6047.848803779941, 6099.725512351911, 6503.544751867371, 6606.621616218157, 3893.6301051400073, 4135.77253251071, 4376.431063961696, 4378.846740364072, 4384.714993525411, 6575.93424065758, 6838.384545390889, 6922.200227940772, 6923.584584924921, 6949.200841903195, 4375.671824613167, 4406.735595864195, 4406.942333839919, 4415.448068991376, 4433.689641725113, 3602.9911624745114, 4157.773456830242, 4357.716748411343, 4371.759553224603, 4498.955010449854, 4325.480942286946, 5054.1407960646775, 5077.23841423782, 5150.197430899156, 5222.3472065991755, 4771.73755443447, 6194.783182588588, 6224.190855188796, 6229.857144998526, 6484.476148105192, 1291.8578956314798, 1465.567657541028, 1493.6937927724791, 1540.2914659256867, 1540.8998415102983, 967.5210770668564, 996.1881066930429, 1010.1716592394166, 1012.5595366015807, 1013.366645846346, 6111.205543279434, 6120.0, 6121.204243448323, 6151.907721384183, 6196.752129914753, 5768.019436695772, 6589.0, 6705.295943925915, 6709.899351509731, 6720.327967203339, 5699.344575373802, 5780.826575202751, 6078.908816367758, 6223.937760622336, 6226.875462490725, 3214.5821043264227, 3533.650122668448, 3539.592946811098, 3542.6634469725336, 3556.7332450065833, 5418.322709661293, 6541.378569035934, 6842.863142737217, 6867.094298903039, 6930.688119034656, 5659.971700141556, 6157.784477543264, 6875.3812156906115, 6899.9650052491525, 6964.895526567106, 5718.1136923776885, 5732.713364331767, 6611.305812889674, 6922.7923162305215, 6956.65216739161, 6561.9500879859, 6637.0, 6696.229933557508, 6756.223034351113, 6780.220274668279, 6101.7559297627595, 6538.215414150332, 6628.204615446178, 6670.733170673118, 6677.933220667731, 6804.0, 6866.965665171642, 6873.666271858045, 6918.55029423106, 6921.2696825793555, 3888.4361767542982, 4394.736315821061, 4409.889752756207, 4429.68992170545, 4432.977835110803, 4940.677881395738, 5839.474447299764, 5968.0, 6498.707558159799, 6765.594064356154, 6413.807585772434, 6423.743050277936, 6566.80299591013, 6571.3757193066585, 6604.339566043357, 5400.83797486076, 6535.0, 6546.705398256994, 6555.934440655532, 6584.209894812653, 6182.289036760651, 6350.206224221973, 6386.265579458422, 6464.41820236181, 6500.3174666659315, 6529.0, 6537.679123910943, 6548.705308261045, 6558.638889999692, 6565.752507023651, 5535.972320138373, 6157.6613286269685, 6303.747850085945, 6323.5654273104265, 6345.841353966188], "value": 4740.798575331744, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [777.8755399136139, 798.8282519258365, 839.1231163434211, 881.5239770523917, 882.9690960816373, 14205.289735513235, 14944.028638138514, 16133.75799363009, 16316.265768040437, 16544.172791360415, 16242.18789060548, 16865.83134168658, 17222.827771722277, 17720.113994300267, 17736.645267094682, 988.8269552828244, 1016.1413605503375, 1017.0287375556326, 1017.4607458047246, 1018.0633816888443, 925.8843094071627, 975.8633791269228, 1024.1294899335542, 1044.4412239451894, 1045.3414348960182, 1063.9255252132355, 1070.8714954205507, 1078.8489611454402, 1079.5843600213896, 1083.9024487796096, 1070.8554345163382, 1076.364944682637, 1076.5209481780612, 1077.2082519348273, 1079.5465904320165, 770.8265640230959, 785.4501848705929, 792.2077922077922, 793.3811626931004, 796.3867821777256, 13832.377543010589, 15384.692306153815, 17244.137793110418, 17248.655026899392, 17273.568160795956, 16051.438199663073, 16318.673626527403, 16502.917485412618, 16661.33354665812, 16722.581935451588, 13426.664333391645, 13860.792088118586, 13967.231802250872, 13974.091684040504, 14019.50931717385, 3277.131560136566, 3287.2439338951935, 3288.7533434992233, 3321.93356132876, 3333.033420308108, 117731.0, 119249.0, 120326.39836800848, 120476.0, 120792.0, 11954.641360759191, 12036.338001410006, 12053.698657533456, 12079.637610871689, 12108.21296615717, 20219.29232476856, 20261.59476810485, 20326.186952522028, 20472.69290960637, 20564.177432902816, 8395.24442800151, 8611.095834937367, 8893.688720894737, 8910.064443233367, 8918.10818918116, 4450.9985253317745, 4542.136993971135, 4740.146773580788, 4801.159797035505, 4856.73287969158, 4330.437043184366, 4405.427294451701, 4435.733855968652, 4436.800343984529, 4459.866204013885, 828.353883970504, 933.8225737109927, 972.7373609125638, 973.1484950668165, 975.5463709375033, 638.5306799502362, 659.2550418027694, 660.4584240922397, 662.3343539742624, 693.2478261086677, 9353.0, 10714.624988125379, 11147.554097835995, 11628.302301861915, 11732.472038758106, 641.7497176101324, 658.5850913924197, 659.1496968910052, 663.1644128398253, 665.7869481765788, 5308.752106254367, 5447.992121457543, 5489.58828087895, 5526.06056970316, 5587.022271102541, 105048.0, 107619.92380076255, 107776.92223077826, 107918.0, 109353.45323273492, 95374.0, 96367.0, 96788.0321196779, 97708.51145744226, 99411.50294248482, 66443.66778166078, 70556.0, 83243.75134372989, 83305.58347208225, 83854.99999999878, 10145.898541014496, 10655.987681170276, 10694.125181834868, 11132.888671113185, 11169.609063682732, 34441.138971525914, 34809.30381392409, 40670.79664601776, 40792.3881141789, 41049.384259235536, 69474.0, 69819.0, 70193.0, 70221.946670801, 71028.28971710216, 16212.837871621134, 16396.67206655884, 16704.498865034067, 16888.83111168922, 17056.232469538652, 18041.0, 18116.365927192484, 18479.260829567203, 18579.16393762311, 18649.440516784518, 16747.0, 17050.48848534546, 17129.314827406764, 17165.31338746436, 17475.56311092233, 13406.530771422953, 16707.749383759496, 17067.40264090751, 17090.060046697552, 17581.912090439466, 13636.590902272948, 13679.384427700976, 15736.977096488692, 15936.760948585548, 16130.516084517483, 11827.881721183023, 11889.0, 11940.462679179283, 12075.879241207476, 12151.270923744603, 12540.623781286577, 12634.620961371174, 13240.0, 13262.867371326549, 13618.673179642961], "value": 8355.89966176448, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1705.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 121.0, 121.0, 123.0, 149.0, 122.0, 124.0, 122.0, 123.0, 122.0], "value": 123.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 117.0, 157.0, 132.0, 166.0, 129.0, 116.0, 138.0, 122.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [312.0, 267.0, 293.0, 281.0, 262.0, 312.0, 229.0, 213.0, 225.0, 264.0], "value": 265.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 63.0, 89.0, 60.0, 56.0, 58.0, 66.0, 107.0, 93.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 105.0, 88.0, 73.0, 72.0, 75.0, 132.0, 113.0, 108.0, 84.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 143.0, 94.0, 92.0, 106.0, 102.0, 143.0, 124.0, 98.0, 86.0], "value": 111.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 148.0, 101.0, 102.0, 141.0, 109.0, 162.0, 134.0, 101.0, 102.0], "value": 120.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 103.0, 103.0, 102.0, 103.0, 149.0, 152.0, 164.0, 109.0, 120.0], "value": 120.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 52.0, 51.0, 53.0, 52.0, 53.0, 52.0, 53.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 74.0, 70.0, 68.0, 62.0, 63.0, 63.0, 62.0, 63.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 87.0, 89.0, 114.0, 118.0, 79.0, 86.0, 76.0, 80.0], "value": 90.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 162.0, 147.0, 146.0, 154.0, 144.0, 163.0, 160.0, 188.0, 146.0], "value": 157.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [259.0, 197.0, 204.0, 190.0, 259.0, 258.0, 214.0, 203.0, 181.0, 190.0], "value": 215.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 72.0, 70.0, 73.0, 76.0, 91.0, 99.0, 82.0, 59.0], "value": 76.4, "unit": "score"}]}]} 17:17:36 INFO - Return code: 0 17:17:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:17:36 ERROR - # TBPL SUCCESS # 17:17:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:17:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:17:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:17:36 INFO - 2017-04-12 17:17:36.875 screenresolution[1654:10659] starting screenresolution argv=screenresolution get 17:17:36 INFO - 2017-04-12 17:17:36.878 screenresolution[1654:10659] Display 0: 1600x1200x32@60 17:17:36 INFO - 2017-04-12 17:17:36.884 screenresolution[1655:10662] starting screenresolution argv=screenresolution list 17:17:36 INFO - Available Modes on Display 0 17:17:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:17:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:17:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:17:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:17:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:17:37 INFO - Intel Iris: 17:17:37 INFO - Chipset Model: Intel Iris 17:17:37 INFO - Type: GPU 17:17:37 INFO - Bus: Built-In 17:17:37 INFO - VRAM (Dynamic, Max): 1536 MB 17:17:37 INFO - Vendor: Intel (0x8086) 17:17:37 INFO - Device ID: 0x0a2e 17:17:37 INFO - Revision ID: 0x0009 17:17:37 INFO - Displays: 17:17:37 INFO - Display: 17:17:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:17:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:17:37 INFO - Display Serial Number: 1600x1200 60 17:17:37 INFO - Main Display: Yes 17:17:37 INFO - Mirror: Off 17:17:37 INFO - Online: Yes 17:17:37 INFO - Rotation: Supported 17:17:37 INFO - Return code: 0 17:17:37 INFO - Running post-action listener: _resource_record_post_action 17:17:37 INFO - [mozharness: 2017-04-13 00:17:37.121749Z] Finished run-tests step (success) 17:17:37 INFO - Running post-run listener: _resource_record_post_run 17:17:37 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 44166144; Write bytes: 523305984; Read time: 451; Write time: 1814 17:17:37 INFO - TinderboxPrint: CPU usage
54.2% 17:17:37 INFO - TinderboxPrint: I/O read bytes / time
44,166,144 / 451 17:17:37 INFO - TinderboxPrint: I/O write bytes / time
523,305,984 / 1,814 17:17:37 INFO - TinderboxPrint: CPU idle
2,204.2 (45.8%) 17:17:37 INFO - TinderboxPrint: CPU system
969.1 (20.1%) 17:17:37 INFO - TinderboxPrint: CPU user
1,636.4 (34.0%) 17:17:37 INFO - TinderboxPrint: Swap in / out
259,784,704 / 0 17:17:37 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209039872; Write bytes: 209852416; Read time: 16045; Write time: 403 17:17:37 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 41745408; Write bytes: 312114176; Read time: 422; Write time: 1406 17:17:37 INFO - Running post-run listener: _upload_blobber_files 17:17:37 INFO - Blob upload gear active. 17:17:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:17:37 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 17:17:37 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:17:37 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:17:38 INFO - (blobuploader) - INFO - Open directory for files ... 17:17:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:17:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:17:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:17:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:17:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:17:39 INFO - (blobuploader) - INFO - Done attempting. 17:17:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:17:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:17:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:17:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:17:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:17:40 INFO - (blobuploader) - INFO - Done attempting. 17:17:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:17:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:17:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:17:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:17:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:17:41 INFO - (blobuploader) - INFO - Done attempting. 17:17:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:17:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:17:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:17:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:17:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:17:43 INFO - (blobuploader) - INFO - Done attempting. 17:17:43 INFO - (blobuploader) - INFO - Iteration through files over. 17:17:43 INFO - Return code: 0 17:17:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:17:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:17:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0255bf49709ee1e29edc697638f975e1f8b9d84330bdd0fbe4833e62d4746b778c876b9f6ec57d6cd3877c045681c9a62ab425f48464bc668a665215329b8a9b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5628bee5568d3397356fbbcdc5b6e5c4857b1d7197f86ec48d5737e083e4fd3f6d4ace621919f528e0ba78795b91f703b66dee7aa6296ca2c1845220a340ca9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/779dc8a5fd6960a6637735998088826cd037737e1e1158d11e7d2119afd61f56f077ecb133040750d7fb841b841f749a83cf37e78e5b4c537d646af1321c964e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/61cfecedcd5c963c1b4f6d25cd0e34ffefdcbf0f80896a99f31bf1e38d97b7594f42eea74854b529ab93ad24c548d128a806b92080d19d061dc69b3be835f4df"} 17:17:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:17:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:17:43 INFO - Contents: 17:17:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0255bf49709ee1e29edc697638f975e1f8b9d84330bdd0fbe4833e62d4746b778c876b9f6ec57d6cd3877c045681c9a62ab425f48464bc668a665215329b8a9b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5628bee5568d3397356fbbcdc5b6e5c4857b1d7197f86ec48d5737e083e4fd3f6d4ace621919f528e0ba78795b91f703b66dee7aa6296ca2c1845220a340ca9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/779dc8a5fd6960a6637735998088826cd037737e1e1158d11e7d2119afd61f56f077ecb133040750d7fb841b841f749a83cf37e78e5b4c537d646af1321c964e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/61cfecedcd5c963c1b4f6d25cd0e34ffefdcbf0f80896a99f31bf1e38d97b7594f42eea74854b529ab93ad24c548d128a806b92080d19d061dc69b3be835f4df"} 17:17:43 INFO - Running post-run listener: copy_logs_to_upload_dir 17:17:43 INFO - Copying logs to upload dir... 17:17:43 INFO - mkdir: /builds/slave/test/build/upload/logs 17:17:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.231123 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-12 17:17:43.255776) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 17:17:43.261271) ========= 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.z5iMhPPorD/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.xlaQvid0nb/Listeners TMPDIR=/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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/Pine/sha512/0255bf49709ee1e29edc697638f975e1f8b9d84330bdd0fbe4833e62d4746b778c876b9f6ec57d6cd3877c045681c9a62ab425f48464bc668a665215329b8a9b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5628bee5568d3397356fbbcdc5b6e5c4857b1d7197f86ec48d5737e083e4fd3f6d4ace621919f528e0ba78795b91f703b66dee7aa6296ca2c1845220a340ca9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/779dc8a5fd6960a6637735998088826cd037737e1e1158d11e7d2119afd61f56f077ecb133040750d7fb841b841f749a83cf37e78e5b4c537d646af1321c964e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/61cfecedcd5c963c1b4f6d25cd0e34ffefdcbf0f80896a99f31bf1e38d97b7594f42eea74854b529ab93ad24c548d128a806b92080d19d061dc69b3be835f4df"} build_url:https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011176 build_url: 'https://queue.taskcluster.net/v1/task/CRoPsfIuQZGYeKeksZAj3Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0255bf49709ee1e29edc697638f975e1f8b9d84330bdd0fbe4833e62d4746b778c876b9f6ec57d6cd3877c045681c9a62ab425f48464bc668a665215329b8a9b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5628bee5568d3397356fbbcdc5b6e5c4857b1d7197f86ec48d5737e083e4fd3f6d4ace621919f528e0ba78795b91f703b66dee7aa6296ca2c1845220a340ca9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/779dc8a5fd6960a6637735998088826cd037737e1e1158d11e7d2119afd61f56f077ecb133040750d7fb841b841f749a83cf37e78e5b4c537d646af1321c964e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/61cfecedcd5c963c1b4f6d25cd0e34ffefdcbf0f80896a99f31bf1e38d97b7594f42eea74854b529ab93ad24c548d128a806b92080d19d061dc69b3be835f4df"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 17:17:43.297233) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 17:17:43.297638) ========= 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.z5iMhPPorD/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.xlaQvid0nb/Listeners TMPDIR=/var/folders/fg/qwsnv5p90m5_c4fzvl8fjr6w00000w/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.005113 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 17:17:43.347251) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 17:17:43.347604) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 17:17:43.348078) ========= ========= Total master_lag: 0.42 =========