builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0134 starttime: 1490915707.2 results: success (0) buildid: 20170330113306 builduid: f40ac9fd8edb49bc88526c0889a60f4f revision: d20d2cc8ee0e5312032c706b39baeb5cc42cf3b3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 16:15:07.205323) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 16:15:07.205887) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 16:15:07.206362) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 16:15:07.233841) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 16:15:07.234286) ========= 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.b1smtYEvv4/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.3XtMxSUFOw/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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-03-30 16:15:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2017-03-30 16:15:07 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.094705 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 16:15:07.354797) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 16:15:07.355365) ========= 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.b1smtYEvv4/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.3XtMxSUFOw/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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.029312 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 16:15:07.407918) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-30 16:15:07.408290) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev d20d2cc8ee0e5312032c706b39baeb5cc42cf3b3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev d20d2cc8ee0e5312032c706b39baeb5cc42cf3b3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b1smtYEvv4/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.3XtMxSUFOw/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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-03-30 16:15:07,516 truncating revision to first 12 chars 2017-03-30 16:15:07,517 Setting DEBUG logging. 2017-03-30 16:15:07,517 attempt 1/10 2017-03-30 16:15:07,517 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/d20d2cc8ee0e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-30 16:15:09,540 unpacking tar archive at: pine-d20d2cc8ee0e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.283959 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-30 16:15:09.715571) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 16:15:09.715892) ========= script_repo_revision: d20d2cc8ee0e5312032c706b39baeb5cc42cf3b3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 16:15:09.716276) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 16:15:09.716554) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 16:15:09.737134) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-30 16:15:09.737639) ========= /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.b1smtYEvv4/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.3XtMxSUFOw/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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:15:09 INFO - MultiFileLogger online at 20170330 16:15:09 in /builds/slave/test 16:15:09 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:15:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:15:09 INFO - {'append_to_log': False, 16:15:09 INFO - 'base_work_dir': '/builds/slave/test', 16:15:09 INFO - 'blob_upload_branch': 'Pine', 16:15:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:15:09 INFO - 'branch': 'Pine', 16:15:09 INFO - 'buildbot_json_path': 'buildprops.json', 16:15:09 INFO - 'config_files': ('talos/mac_config.py',), 16:15:09 INFO - 'default_actions': ('clobber', 16:15:09 INFO - 'read-buildbot-config', 16:15:09 INFO - 'download-and-extract', 16:15:09 INFO - 'populate-webroot', 16:15:09 INFO - 'create-virtualenv', 16:15:09 INFO - 'install', 16:15:09 INFO - 'run-tests'), 16:15:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:15:09 INFO - 'download_minidump_stackwalk': True, 16:15:09 INFO - 'download_symbols': 'ondemand', 16:15:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:15:09 INFO - 'tooltool.py': '/tools/tooltool.py', 16:15:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:15:09 INFO - '/tools/misc-python/virtualenv.py')}, 16:15:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:15:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:15:09 INFO - 'gecko_profile': False, 16:15:09 INFO - 'gecko_profile_interval': 0, 16:15:09 INFO - 'installer_path': 'installer.exe', 16:15:09 INFO - 'log_level': 'info', 16:15:09 INFO - 'log_name': 'talos', 16:15:09 INFO - 'log_to_console': True, 16:15:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:15:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:15:09 INFO - 'opt_config_files': (), 16:15:09 INFO - 'pip_index': False, 16:15:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:15:09 INFO - 'cmd': ('bash', 16:15:09 INFO - '-c', 16:15:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:15:09 INFO - 'enabled': True, 16:15:09 INFO - 'halt_on_failure': False, 16:15:09 INFO - 'name': 'check_screen_resolution'},), 16:15:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:15:09 INFO - 'cmd': ('bash', 16:15:09 INFO - '-c', 16:15:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:15:09 INFO - 'enabled': True, 16:15:09 INFO - 'halt_on_failure': False, 16:15:09 INFO - 'name': 'check_screen_resolution'},), 16:15:09 INFO - 'run_cmd_checks_enabled': True, 16:15:09 INFO - 'suite': 'dromaeojs-e10s', 16:15:09 INFO - 'system_bits': '32', 16:15:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:15:09 INFO - 'title': 't-yosemite-r7-0134', 16:15:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:15:09 INFO - 'use_talos_json': True, 16:15:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:15:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:15:09 INFO - 'work_dir': 'build'} 16:15:09 INFO - [mozharness: 2017-03-30 23:15:09.960536Z] Running clobber step. 16:15:09 INFO - Running pre-action listener: _resource_record_pre_action 16:15:09 INFO - Running main action method: clobber 16:15:09 INFO - rmtree: /builds/slave/test/build 16:15:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:15:12 INFO - Running post-action listener: _resource_record_post_action 16:15:12 INFO - [mozharness: 2017-03-30 23:15:12.543779Z] Finished clobber step (success) 16:15:12 INFO - [mozharness: 2017-03-30 23:15:12.543911Z] Running read-buildbot-config step. 16:15:12 INFO - Running pre-action listener: _resource_record_pre_action 16:15:12 INFO - Running main action method: read_buildbot_config 16:15:12 INFO - Using buildbot properties: 16:15:12 INFO - { 16:15:12 INFO - "project": "", 16:15:12 INFO - "product": "firefox", 16:15:12 INFO - "script_repo_revision": "default", 16:15:12 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 16:15:12 INFO - "repository": "", 16:15:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 16:15:12 INFO - "buildid": "20170330113306", 16:15:12 INFO - "pgo_build": "False", 16:15:12 INFO - "basedir": "/builds/slave/test", 16:15:12 INFO - "buildnumber": 9, 16:15:12 INFO - "slavename": "t-yosemite-r7-0134", 16:15:12 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 16:15:12 INFO - "platform": "yosemite_r7", 16:15:12 INFO - "scheduler": "tests-pine-macosx64-talos", 16:15:12 INFO - "branch": "pine", 16:15:12 INFO - "repo_path": "projects/pine", 16:15:12 INFO - "revision": "d20d2cc8ee0e5312032c706b39baeb5cc42cf3b3", 16:15:12 INFO - "stage_platform": "macosx64", 16:15:12 INFO - "builduid": "f40ac9fd8edb49bc88526c0889a60f4f", 16:15:12 INFO - "slavebuilddir": "test" 16:15:12 INFO - } 16:15:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:15:12 INFO - Running post-action listener: _resource_record_post_action 16:15:12 INFO - [mozharness: 2017-03-30 23:15:12.547264Z] Finished read-buildbot-config step (success) 16:15:12 INFO - [mozharness: 2017-03-30 23:15:12.547366Z] Running download-and-extract step. 16:15:12 INFO - Running pre-action listener: _resource_record_pre_action 16:15:12 INFO - Running main action method: download_and_extract 16:15:12 INFO - mkdir: /builds/slave/test/build/tests 16:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:12 INFO - trying https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:15:12 INFO - Downloading https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/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:15:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/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:15:13 INFO - Downloaded 1325 bytes. 16:15:13 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:15:13 INFO - Using the following test package requirements: 16:15:13 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:15:13 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:15:13 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:15:13 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:15:13 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:15:13 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:15:13 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:15:13 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:15:13 INFO - u'jsshell-mac.zip'], 16:15:13 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:15:13 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:15:13 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:15:13 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:15:13 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:15:13 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:15:13 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:15:13 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:15:13 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:15:13 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:15:13 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:15:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:15:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:15:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:15:13 INFO - Fetch https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:15:15 INFO - Expected file size: 52508482 16:15:15 INFO - Obtained file size: 52508482 16:15:31 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:15:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:15:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:15:31 INFO - Fetch https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:15:33 INFO - Expected file size: 52508482 16:15:33 INFO - Obtained file size: 52508482 16:15:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:15:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:15:48 INFO - Fetch https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:15:50 INFO - Expected file size: 14404152 16:15:50 INFO - Obtained file size: 14404152 16:15:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:50 INFO - trying https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:15:50 INFO - Downloading https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:15:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/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:15:52 INFO - Downloaded 75605671 bytes. 16:15:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:15:52 INFO - mkdir: /builds/slave/test/properties 16:15:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:15:52 INFO - Writing to file /builds/slave/test/properties/build_url 16:15:52 INFO - Contents: 16:15:52 INFO - build_url:https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:15:53 INFO - Running post-action listener: _resource_record_post_action 16:15:53 INFO - Running post-action listener: set_extra_try_arguments 16:15:53 INFO - [mozharness: 2017-03-30 23:15:53.378475Z] Finished download-and-extract step (success) 16:15:53 INFO - [mozharness: 2017-03-30 23:15:53.378603Z] Running populate-webroot step. 16:15:53 INFO - Running pre-action listener: _resource_record_pre_action 16:15:53 INFO - Running main action method: populate_webroot 16:15:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:15:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:15:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:15:53 INFO - '--tppagecycles', 16:15:53 INFO - '7'], 16:15:53 INFO - 'tests': ['tsvgm']}}, 16:15:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:15:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:15:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:15:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:15:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:15:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:15:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:15:53 INFO - 'talos_options': ['--disable-e10s'], 16:15:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:15:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:15:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:15:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:15:53 INFO - 'talos_options': ['--disable-e10s'], 16:15:53 INFO - 'tests': ['damp', 'tps']}, 16:15:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:15:53 INFO - 'tests': ['damp', 'tps']}, 16:15:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:15:53 INFO - 'tests': ['dromaeo_dom']}, 16:15:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:15:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:15:53 INFO - 'tests': ['basic_compositor_video']}, 16:15:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:15:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:15:53 INFO - 'tests': ['a11yr', 16:15:53 INFO - 'ts_paint', 16:15:53 INFO - 'tpaint', 16:15:53 INFO - 'sessionrestore', 16:15:53 INFO - 'sessionrestore_no_auto_restore', 16:15:53 INFO - 'tabpaint']}, 16:15:53 INFO - 'other-e10s': {'tests': ['a11yr', 16:15:53 INFO - 'ts_paint', 16:15:53 INFO - 'tpaint', 16:15:53 INFO - 'sessionrestore', 16:15:53 INFO - 'sessionrestore_no_auto_restore', 16:15:53 INFO - 'tabpaint']}, 16:15:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:15:53 INFO - 'ts_paint', 16:15:53 INFO - 'tpaint', 16:15:53 INFO - 'sessionrestore', 16:15:53 INFO - 'sessionrestore_no_auto_restore', 16:15:53 INFO - 'tabpaint']}, 16:15:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:15:53 INFO - 'ts_paint', 16:15:53 INFO - 'tpaint', 16:15:53 INFO - 'sessionrestore', 16:15:53 INFO - 'sessionrestore_no_auto_restore', 16:15:53 INFO - 'tabpaint']}, 16:15:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:15:53 INFO - 'tests': ['a11yr', 16:15:53 INFO - 'ts_paint', 16:15:53 INFO - 'tpaint', 16:15:53 INFO - 'sessionrestore', 16:15:53 INFO - 'sessionrestore_no_auto_restore', 16:15:53 INFO - 'tabpaint']}, 16:15:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:15:53 INFO - 'tests': ['a11yr', 16:15:53 INFO - 'ts_paint', 16:15:53 INFO - 'tpaint', 16:15:53 INFO - 'sessionrestore', 16:15:53 INFO - 'sessionrestore_no_auto_restore', 16:15:53 INFO - 'tabpaint']}, 16:15:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:15:53 INFO - 'tests': ['tsvgx', 16:15:53 INFO - 'tsvgr_opacity', 16:15:53 INFO - 'tart', 16:15:53 INFO - 'tscrollx', 16:15:53 INFO - 'cart', 16:15:53 INFO - 'tsvg_static']}, 16:15:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:15:53 INFO - 'tsvgr_opacity', 16:15:53 INFO - 'tart', 16:15:53 INFO - 'tscrollx', 16:15:53 INFO - 'cart', 16:15:53 INFO - 'tsvg_static']}, 16:15:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:15:53 INFO - 'talos_options': ['--disable-e10s'], 16:15:53 INFO - 'tests': ['tp5o']}, 16:15:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:15:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:15:53 INFO - 'talos_options': ['--disable-e10s', 16:15:53 INFO - '--xperf_path', 16:15:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:15:53 INFO - 'tests': ['tp5n']}, 16:15:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:15:53 INFO - 'talos_options': ['--xperf_path', 16:15:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:15:53 INFO - 'tests': ['tp5n']}}, 16:15:53 INFO - 'talos.zip': {'path': '', 16:15:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:15:53 INFO - Running post-action listener: _resource_record_post_action 16:15:53 INFO - [mozharness: 2017-03-30 23:15:53.396611Z] Finished populate-webroot step (success) 16:15:53 INFO - [mozharness: 2017-03-30 23:15:53.396740Z] Running create-virtualenv step. 16:15:53 INFO - Running pre-action listener: _resource_record_pre_action 16:15:53 INFO - Running main action method: create_virtualenv 16:15:53 INFO - Creating virtualenv /builds/slave/test/build/venv 16:15:53 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:15:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:15:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:15:53 INFO - Using real prefix '/tools/python27' 16:15:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:15:54 INFO - Installing distribute.............................................................................................................................................................................................done. 16:15:57 INFO - Installing pip.................done. 16:15:57 INFO - Return code: 0 16:15:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:15:57 INFO - Reading from file tmpfile_stdout 16:15:57 INFO - Output received: 16:15:57 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:15:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:57 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 0x102096b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102099768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9a8ce19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e74d30>, '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 0x101d9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e69be8>, '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.b1smtYEvv4/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.3XtMxSUFOw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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:15:57 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:15:57 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:15:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b1smtYEvv4/Render', 16:15:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:57 INFO - 'HOME': '/Users/cltbld', 16:15:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:57 INFO - 'LOGNAME': 'cltbld', 16:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:57 INFO - 'MOZ_NO_REMOTE': '1', 16:15:57 INFO - 'NO_EM_RESTART': '1', 16:15:57 INFO - 'PAGER': '/bin/cat', 16:15:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:57 INFO - 'PWD': '/builds/slave/test', 16:15:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:57 INFO - 'SHELL': '/bin/bash', 16:15:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3XtMxSUFOw/Listeners', 16:15:57 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 16:15:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:57 INFO - 'USER': 'cltbld', 16:15:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:57 INFO - 'XPC_FLAGS': '0x0', 16:15:57 INFO - 'XPC_SERVICE_NAME': '0', 16:15:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:57 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:15:57 INFO - Cleaning up... 16:15:57 INFO - Return code: 0 16:15:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:57 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 0x102096b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102099768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9a8ce19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e74d30>, '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 0x101d9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e69be8>, '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.b1smtYEvv4/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.3XtMxSUFOw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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:15:57 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:15:57 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:15:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b1smtYEvv4/Render', 16:15:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:57 INFO - 'HOME': '/Users/cltbld', 16:15:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:57 INFO - 'LOGNAME': 'cltbld', 16:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:57 INFO - 'MOZ_NO_REMOTE': '1', 16:15:57 INFO - 'NO_EM_RESTART': '1', 16:15:57 INFO - 'PAGER': '/bin/cat', 16:15:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:57 INFO - 'PWD': '/builds/slave/test', 16:15:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:57 INFO - 'SHELL': '/bin/bash', 16:15:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3XtMxSUFOw/Listeners', 16:15:57 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 16:15:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:57 INFO - 'USER': 'cltbld', 16:15:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:57 INFO - 'XPC_FLAGS': '0x0', 16:15:57 INFO - 'XPC_SERVICE_NAME': '0', 16:15:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:57 INFO - Downloading/unpacking psutil>=3.1.1 16:15:57 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:15:57 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:15:57 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:15:57 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:15:57 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:15:57 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:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:16:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:01 INFO - Installing collected packages: psutil 16:16:01 INFO - Running setup.py install for psutil 16:16:01 INFO - building 'psutil._psutil_osx' extension 16:16:01 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:16:02 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:16:02 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:16:02 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:16:02 INFO - building 'psutil._psutil_posix' extension 16:16:02 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:16:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:16:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:16:02 INFO - ^ 16:16:02 INFO - 1 warning generated. 16:16:02 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:16:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:02 INFO - Successfully installed psutil 16:16:02 INFO - Cleaning up... 16:16:02 INFO - Return code: 0 16:16:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102096b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102099768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9a8ce19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e74d30>, '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 0x101d9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e69be8>, '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.b1smtYEvv4/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.3XtMxSUFOw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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:16:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:16:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:16:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b1smtYEvv4/Render', 16:16:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:02 INFO - 'HOME': '/Users/cltbld', 16:16:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:02 INFO - 'LOGNAME': 'cltbld', 16:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:02 INFO - 'MOZ_NO_REMOTE': '1', 16:16:02 INFO - 'NO_EM_RESTART': '1', 16:16:02 INFO - 'PAGER': '/bin/cat', 16:16:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:02 INFO - 'PWD': '/builds/slave/test', 16:16:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:02 INFO - 'SHELL': '/bin/bash', 16:16:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3XtMxSUFOw/Listeners', 16:16:02 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 16:16:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:02 INFO - 'USER': 'cltbld', 16:16:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:02 INFO - 'XPC_FLAGS': '0x0', 16:16:02 INFO - 'XPC_SERVICE_NAME': '0', 16:16:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:16:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:16:05 INFO - Installing collected packages: mozsystemmonitor 16:16:05 INFO - Running setup.py install for mozsystemmonitor 16:16:05 INFO - Successfully installed mozsystemmonitor 16:16:05 INFO - Cleaning up... 16:16:05 INFO - Return code: 0 16:16:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:16: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:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:06 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:16:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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', '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 0x102096b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102099768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9a8ce19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e74d30>, '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 0x101d9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e69be8>, '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.b1smtYEvv4/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.3XtMxSUFOw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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:16: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:16: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 jsonschema==2.5.1 16:16:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b1smtYEvv4/Render', 16:16:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:06 INFO - 'HOME': '/Users/cltbld', 16:16:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:06 INFO - 'LOGNAME': 'cltbld', 16:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:06 INFO - 'MOZ_NO_REMOTE': '1', 16:16:06 INFO - 'NO_EM_RESTART': '1', 16:16:06 INFO - 'PAGER': '/bin/cat', 16:16:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:06 INFO - 'PWD': '/builds/slave/test', 16:16:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:06 INFO - 'SHELL': '/bin/bash', 16:16:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3XtMxSUFOw/Listeners', 16:16:06 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 16:16:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:06 INFO - 'USER': 'cltbld', 16:16:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:06 INFO - 'XPC_FLAGS': '0x0', 16:16:06 INFO - 'XPC_SERVICE_NAME': '0', 16:16:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:06 INFO - Downloading/unpacking jsonschema==2.5.1 16:16: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:16: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:16: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:16: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:16: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:16: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:16:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:16:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:10 INFO - Downloading functools32-3.2.3-2.tar.gz 16:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:16:10 INFO - warning: no files found matching '*.txt' 16:16:10 INFO - no previously-included directories found matching 'build' 16:16:10 INFO - no previously-included directories found matching 'dist' 16:16:10 INFO - no previously-included directories found matching '.git*' 16:16:10 INFO - Installing collected packages: jsonschema, functools32 16:16:10 INFO - Running setup.py install for functools32 16:16:10 INFO - warning: no files found matching '*.txt' 16:16:10 INFO - no previously-included directories found matching 'build' 16:16:10 INFO - no previously-included directories found matching 'dist' 16:16:10 INFO - no previously-included directories found matching '.git*' 16:16:10 INFO - Successfully installed jsonschema functools32 16:16:10 INFO - Cleaning up... 16:16:10 INFO - Return code: 0 16:16:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:10 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 0x102096b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102099768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9a8ce19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e74d30>, '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 0x101d9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e69be8>, '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.b1smtYEvv4/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.3XtMxSUFOw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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:16:10 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:16:10 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:16:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b1smtYEvv4/Render', 16:16:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:10 INFO - 'HOME': '/Users/cltbld', 16:16:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:10 INFO - 'LOGNAME': 'cltbld', 16:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:10 INFO - 'MOZ_NO_REMOTE': '1', 16:16:10 INFO - 'NO_EM_RESTART': '1', 16:16:10 INFO - 'PAGER': '/bin/cat', 16:16:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:10 INFO - 'PWD': '/builds/slave/test', 16:16:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:10 INFO - 'SHELL': '/bin/bash', 16:16:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3XtMxSUFOw/Listeners', 16:16:10 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 16:16:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:10 INFO - 'USER': 'cltbld', 16:16:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:10 INFO - 'XPC_FLAGS': '0x0', 16:16:10 INFO - 'XPC_SERVICE_NAME': '0', 16:16:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:16:10 INFO - Cleaning up... 16:16:11 INFO - Return code: 0 16:16:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:16: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:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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', '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 0x102096b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102099768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9a8ce19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e74d30>, '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 0x101d9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e69be8>, '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.b1smtYEvv4/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.3XtMxSUFOw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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:16: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:16: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 blobuploader==1.2.4 16:16:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b1smtYEvv4/Render', 16:16:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:11 INFO - 'HOME': '/Users/cltbld', 16:16:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:11 INFO - 'LOGNAME': 'cltbld', 16:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:11 INFO - 'MOZ_NO_REMOTE': '1', 16:16:11 INFO - 'NO_EM_RESTART': '1', 16:16:11 INFO - 'PAGER': '/bin/cat', 16:16:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:11 INFO - 'PWD': '/builds/slave/test', 16:16:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:11 INFO - 'SHELL': '/bin/bash', 16:16:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3XtMxSUFOw/Listeners', 16:16:11 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 16:16:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:11 INFO - 'USER': 'cltbld', 16:16:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:11 INFO - 'XPC_FLAGS': '0x0', 16:16:11 INFO - 'XPC_SERVICE_NAME': '0', 16:16:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:11 INFO - Downloading/unpacking blobuploader==1.2.4 16:16: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:16: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:16: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:16: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:16: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:16: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:16:13 INFO - Downloading blobuploader-1.2.4.tar.gz 16:16:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:16:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:16: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:16: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:16: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:16: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:16: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:16: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:16:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:16:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:16: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:16: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:16: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:16: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:16: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:16: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:16:15 INFO - Downloading docopt-0.6.1.tar.gz 16:16:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:16:15 INFO - Installing collected packages: blobuploader, requests, docopt 16:16:15 INFO - Running setup.py install for blobuploader 16:16:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:16:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:16:15 INFO - Running setup.py install for requests 16:16:15 INFO - Running setup.py install for docopt 16:16:15 INFO - Successfully installed blobuploader requests docopt 16:16:15 INFO - Cleaning up... 16:16:16 INFO - Return code: 0 16:16:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:16 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 0x102096b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102099768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9a8ce19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e74d30>, '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 0x101d9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e69be8>, '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.b1smtYEvv4/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.3XtMxSUFOw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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:16:16 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:16:16 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:16:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b1smtYEvv4/Render', 16:16:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:16 INFO - 'HOME': '/Users/cltbld', 16:16:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:16 INFO - 'LOGNAME': 'cltbld', 16:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:16 INFO - 'MOZ_NO_REMOTE': '1', 16:16:16 INFO - 'NO_EM_RESTART': '1', 16:16:16 INFO - 'PAGER': '/bin/cat', 16:16:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:16 INFO - 'PWD': '/builds/slave/test', 16:16:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:16 INFO - 'SHELL': '/bin/bash', 16:16:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3XtMxSUFOw/Listeners', 16:16:16 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 16:16:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:16 INFO - 'USER': 'cltbld', 16:16:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:16 INFO - 'XPC_FLAGS': '0x0', 16:16:16 INFO - 'XPC_SERVICE_NAME': '0', 16:16:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:16:16 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-xxA1Ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:16:16 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-QAmo1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:16:16 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-hYFUrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:16:16 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-qn_8Ap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:16:16 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Soiz7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:16:16 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-II0648-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:16:16 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-iSIujP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:16:17 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-_eNWMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:16:17 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-hHctKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:16:17 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-VooUU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:16:17 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-SbSYOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:16:17 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-bCY8hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:16:17 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-StZ75d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:16:17 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-NMKWCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:17 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-97PHvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:16:17 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-g_led4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:16:17 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Sn_89N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:16:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:16:18 INFO - Running setup.py install for manifestparser 16:16:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:16:18 INFO - Running setup.py install for mozcrash 16:16:18 INFO - Running setup.py install for mozdebug 16:16:18 INFO - Running setup.py install for mozdevice 16:16:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:16:18 INFO - Running setup.py install for mozfile 16:16:18 INFO - Running setup.py install for mozhttpd 16:16:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:16:18 INFO - Running setup.py install for mozinfo 16:16:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:16:19 INFO - Running setup.py install for mozInstall 16:16:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:16:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:16:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:16:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:16:19 INFO - Running setup.py install for mozleak 16:16:19 INFO - Running setup.py install for mozlog 16:16:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:16:19 INFO - Running setup.py install for moznetwork 16:16:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:16:19 INFO - Running setup.py install for mozprocess 16:16:19 INFO - Running setup.py install for mozprofile 16:16:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:16:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:16:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:16:20 INFO - Running setup.py install for mozrunner 16:16:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:16:20 INFO - Running setup.py install for mozscreenshot 16:16:20 INFO - Running setup.py install for moztest 16:16:20 INFO - Running setup.py install for mozversion 16:16:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:16:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:16:20 INFO - Cleaning up... 16:16:20 INFO - Return code: 0 16:16:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:20 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 0x102096b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102099768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9a8ce19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e74d30>, '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 0x101d9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e69be8>, '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.b1smtYEvv4/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.3XtMxSUFOw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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:16:20 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:16:20 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:16:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b1smtYEvv4/Render', 16:16:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:20 INFO - 'HOME': '/Users/cltbld', 16:16:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:20 INFO - 'LOGNAME': 'cltbld', 16:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:20 INFO - 'MOZ_NO_REMOTE': '1', 16:16:20 INFO - 'NO_EM_RESTART': '1', 16:16:20 INFO - 'PAGER': '/bin/cat', 16:16:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:20 INFO - 'PWD': '/builds/slave/test', 16:16:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:20 INFO - 'SHELL': '/bin/bash', 16:16:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3XtMxSUFOw/Listeners', 16:16:20 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 16:16:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:20 INFO - 'USER': 'cltbld', 16:16:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:20 INFO - 'XPC_FLAGS': '0x0', 16:16:20 INFO - 'XPC_SERVICE_NAME': '0', 16:16:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:16:20 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-mklSe6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:16:21 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:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:16:21 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-cd8MUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:16:21 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:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:16:21 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-ucwkx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:16:21 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:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:16:21 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-9zRe4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:16:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:16:21 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-4H6BzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:16:21 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:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:16:21 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Fd6P14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:16:21 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:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:16:21 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-HKWP3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:16:21 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:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:16:21 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-T7UvK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:16:21 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:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:16:21 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-7dG91M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:16:21 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:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:16:21 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-WcL7Vu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:16:21 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:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:16:21 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-oyFLdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:16:22 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:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:16:22 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-ZkRP6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:16:22 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:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:16:22 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Rko0vb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:16:22 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:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:16:22 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-KOf0J0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:16:22 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:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:22 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-bWTQOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:22 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:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:16:22 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-vjBCS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:16:22 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:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:16:22 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-U4l94A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:16:22 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:16:22 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:16:22 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:16:22 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:16:22 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:16:22 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:16:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:16: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:16: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:16: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:16: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:16: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:16: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:16:25 INFO - Downloading blessings-1.6.tar.gz 16:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:16:25 INFO - Installing collected packages: blessings 16:16:25 INFO - Running setup.py install for blessings 16:16:25 INFO - Successfully installed blessings 16:16:25 INFO - Cleaning up... 16:16:25 INFO - Return code: 0 16:16:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:16:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:16:25 INFO - Reading from file tmpfile_stdout 16:16:26 INFO - Current package versions: 16:16:26 INFO - blessings == 1.6 16:16:26 INFO - blobuploader == 1.2.4 16:16:26 INFO - docopt == 0.6.1 16:16:26 INFO - functools32 == 3.2.3-2 16:16:26 INFO - jsonschema == 2.5.1 16:16:26 INFO - manifestparser == 1.1 16:16:26 INFO - mozInstall == 1.12 16:16:26 INFO - mozcrash == 1.0 16:16:26 INFO - mozdebug == 0.1 16:16:26 INFO - mozdevice == 0.49 16:16:26 INFO - mozfile == 1.2 16:16:26 INFO - mozhttpd == 0.7 16:16:26 INFO - mozinfo == 0.9 16:16:26 INFO - mozleak == 0.1 16:16:26 INFO - mozlog == 3.4 16:16:26 INFO - moznetwork == 0.27 16:16:26 INFO - mozprocess == 0.25 16:16:26 INFO - mozprofile == 0.28 16:16:26 INFO - mozrunner == 6.13 16:16:26 INFO - mozscreenshot == 0.1 16:16:26 INFO - mozsystemmonitor == 0.3 16:16:26 INFO - moztest == 0.8 16:16:26 INFO - mozversion == 1.4 16:16:26 INFO - psutil == 3.1.1 16:16:26 INFO - requests == 1.2.3 16:16:26 INFO - wsgiref == 0.1.2 16:16:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:26 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 0x102096b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102099768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9a8ce19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e74d30>, '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 0x101d9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e69be8>, '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.b1smtYEvv4/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.3XtMxSUFOw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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:16:26 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:16:26 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:16:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b1smtYEvv4/Render', 16:16:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:26 INFO - 'HOME': '/Users/cltbld', 16:16:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:26 INFO - 'LOGNAME': 'cltbld', 16:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:26 INFO - 'MOZ_NO_REMOTE': '1', 16:16:26 INFO - 'NO_EM_RESTART': '1', 16:16:26 INFO - 'PAGER': '/bin/cat', 16:16:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:26 INFO - 'PWD': '/builds/slave/test', 16:16:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:26 INFO - 'SHELL': '/bin/bash', 16:16:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3XtMxSUFOw/Listeners', 16:16:26 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 16:16:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:26 INFO - 'USER': 'cltbld', 16:16:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:26 INFO - 'XPC_FLAGS': '0x0', 16:16:26 INFO - 'XPC_SERVICE_NAME': '0', 16:16:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:26 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:16:26 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:16:26 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:16:26 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:16:26 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:16:26 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:16:26 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:16:26 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:16:26 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:16:26 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:16:26 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:16:26 INFO - Cleaning up... 16:16:26 INFO - Return code: 0 16:16:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:26 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 0x102096b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102099768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9a8ce19a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e74d30>, '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 0x101d9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e69be8>, '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.b1smtYEvv4/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.3XtMxSUFOw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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:16:26 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:16:26 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:16:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b1smtYEvv4/Render', 16:16:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:26 INFO - 'HOME': '/Users/cltbld', 16:16:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:26 INFO - 'LOGNAME': 'cltbld', 16:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:26 INFO - 'MOZ_NO_REMOTE': '1', 16:16:26 INFO - 'NO_EM_RESTART': '1', 16:16:26 INFO - 'PAGER': '/bin/cat', 16:16:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:26 INFO - 'PWD': '/builds/slave/test', 16:16:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:26 INFO - 'SHELL': '/bin/bash', 16:16:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3XtMxSUFOw/Listeners', 16:16:26 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 16:16:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:26 INFO - 'USER': 'cltbld', 16:16:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:26 INFO - 'XPC_FLAGS': '0x0', 16:16:26 INFO - 'XPC_SERVICE_NAME': '0', 16:16:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:16:26 INFO - Cleaning up... 16:16:26 INFO - Return code: 0 16:16:26 INFO - Running post-action listener: _resource_record_post_action 16:16:26 INFO - Running post-action listener: _start_resource_monitoring 16:16:26 INFO - Starting resource monitoring. 16:16:26 INFO - [mozharness: 2017-03-30 23:16:26.575396Z] Finished create-virtualenv step (success) 16:16:26 INFO - [mozharness: 2017-03-30 23:16:26.576298Z] Running install step. 16:16:26 INFO - Running pre-action listener: _resource_record_pre_action 16:16:26 INFO - Running main action method: install 16:16:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:16:26 INFO - Reading from file tmpfile_stdout 16:16:26 INFO - Detecting whether we're running mozinstall >=1.0... 16:16:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:16:26 INFO - Reading from file tmpfile_stdout 16:16:26 INFO - Output received: 16:16:26 INFO - Usage: mozinstall [options] installer 16:16:26 INFO - Options: 16:16:26 INFO - -h, --help show this help message and exit 16:16:26 INFO - -d DEST, --destination=DEST 16:16:26 INFO - Directory to install application into. [default: 16:16:26 INFO - "/builds/slave/test"] 16:16:26 INFO - --app=APP Application being installed. [default: firefox] 16:16:26 INFO - mkdir: /builds/slave/test/build/application 16:16:26 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:16:26 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:16:47 INFO - Reading from file tmpfile_stdout 16:16:47 INFO - Output received: 16:16:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:16:47 INFO - Running post-action listener: _resource_record_post_action 16:16:47 INFO - [mozharness: 2017-03-30 23:16:47.302870Z] Finished install step (success) 16:16:47 INFO - [mozharness: 2017-03-30 23:16:47.303020Z] Running run-tests step. 16:16:47 INFO - Running pre-action listener: _resource_record_pre_action 16:16:47 INFO - Running main action method: run_tests 16:16:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:16:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:16:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:16:47 INFO - 2017-03-30 16:16:47.318 screenresolution[1626:7384] starting screenresolution argv=screenresolution get 16:16:47 INFO - 2017-03-30 16:16:47.320 screenresolution[1626:7384] Display 0: 1600x1200x32@60 16:16:47 INFO - 2017-03-30 16:16:47.326 screenresolution[1627:7386] starting screenresolution argv=screenresolution list 16:16:47 INFO - Available Modes on Display 0 16:16:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:16:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:16:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:16:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:16:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:16:47 INFO - Intel Iris: 16:16:47 INFO - Chipset Model: Intel Iris 16:16:47 INFO - Type: GPU 16:16:47 INFO - Bus: Built-In 16:16:47 INFO - VRAM (Dynamic, Max): 1536 MB 16:16:47 INFO - Vendor: Intel (0x8086) 16:16:47 INFO - Device ID: 0x0a2e 16:16:47 INFO - Revision ID: 0x0009 16:16:47 INFO - Displays: 16:16:47 INFO - Display: 16:16:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:16:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:16:47 INFO - Display Serial Number: 1600x1200 60 16:16:47 INFO - Main Display: Yes 16:16:47 INFO - Mirror: Off 16:16:47 INFO - Online: Yes 16:16:47 INFO - Rotation: Supported 16:16:47 INFO - Return code: 0 16:16:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:16:47 INFO - Python 2.7.3 16:16:47 INFO - Return code: 0 16:16:47 INFO - grabbing minidump binary from tooltool 16:16:47 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:16:47 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 0x101e74d30>, '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 0x101d9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e69be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:16:47 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:16:47 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:16:47 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:16:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:16:47 INFO - Return code: 0 16:16:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:16:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:16:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:16:47 INFO - ENV: RUST_BACKTRACE is now 1 16:16:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:16:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:16:47 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/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--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:16:47 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/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0134 --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:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b1smtYEvv4/Render', 16:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:47 INFO - 'HOME': '/Users/cltbld', 16:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:47 INFO - 'LOGNAME': 'cltbld', 16:16:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:16:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:47 INFO - 'MOZ_NO_REMOTE': '1', 16:16:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:16:47 INFO - 'NO_EM_RESTART': '1', 16:16:47 INFO - 'PAGER': '/bin/cat', 16:16:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:47 INFO - 'PWD': '/builds/slave/test', 16:16:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:47 INFO - 'RUST_BACKTRACE': '1', 16:16:47 INFO - 'SHELL': '/bin/bash', 16:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3XtMxSUFOw/Listeners', 16:16:47 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 16:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:47 INFO - 'USER': 'cltbld', 16:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:47 INFO - 'XPC_FLAGS': '0x0', 16:16:47 INFO - 'XPC_SERVICE_NAME': '0', 16:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:47 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/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--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:16:47 INFO - mozversion application_buildid: 20170330113306 16:16:47 INFO - mozversion application_changeset: d20d2cc8ee0e5312032c706b39baeb5cc42cf3b3 16:16:47 INFO - mozversion application_display_name: Nightly 16:16:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:16:47 INFO - mozversion application_name: Firefox 16:16:47 INFO - mozversion application_remotingname: firefox 16:16:47 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 16:16:47 INFO - mozversion application_vendor: Mozilla 16:16:47 INFO - mozversion application_version: 55.0a1 16:16:47 INFO - mozversion platform_buildid: 20170330113306 16:16:47 INFO - mozversion platform_changeset: d20d2cc8ee0e5312032c706b39baeb5cc42cf3b3 16:16:47 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 16:16:47 INFO - mozversion platform_version: 55.0a1 16:16:47 INFO - using testdate: 1490915807 16:16:47 INFO - actual date: 1490915807 16:16:47 INFO - starting webserver on 'localhost:49237' 16:16:47 INFO - SUITE-START | Running 2 tests 16:16:47 INFO - TEST-START | dromaeo_css 16:16:47 INFO - operating with platform_type : mac_ 16:16:47 INFO - Initialising browser for dromaeo_css test... 16:16:47 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpghtAfz/profile http://localhost:49237/getInfo.html) 16:16:50 INFO - PID 1637 | 2017-03-30 16:16:50.955 plugin-container[1639:7559] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f37, name = 'com.apple.tsm.portname' 16:16:50 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:16:50 INFO - PID 1637 | 2017-03-30 16:16:50.958 plugin-container[1639:7559] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b17, name = 'com.apple.CFPasteboardClient' 16:16:50 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:16:50 INFO - PID 1637 | 2017-03-30 16:16:50.958 plugin-container[1639:7559] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:16:51 INFO - PID 1637 | __metrics Screen width/height:1600/1200 16:16:51 INFO - PID 1637 | colorDepth:24 16:16:51 INFO - PID 1637 | Browser inner width/height: 1024/689 16:16:51 INFO - PID 1637 | __metrics 16:16:51 ERROR - PID 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 16:16:51 ERROR - PID 1637 | 1490915811159 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpghtAfz/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpghtAfz/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpghtAfz/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpghtAfz/profile/extensions/talos-powers@TalosPowersService.js:55:9 16:16:51 INFO - TEST-INFO | 1637: exit 0 16:16:51 INFO - Browser initialized. 16:16:51 INFO - Running cycle 1/1 for dromaeo_css test... 16:16:51 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpghtAfz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:16:54 INFO - PID 1640 | 2017-03-30 16:16:54.799 plugin-container[1644:7755] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463b, name = 'com.apple.tsm.portname' 16:16:54 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:16:54 INFO - PID 1640 | 2017-03-30 16:16:54.800 plugin-container[1644:7755] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1b, name = 'com.apple.CFPasteboardClient' 16:16:54 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:16:54 INFO - PID 1640 | 2017-03-30 16:16:54.800 plugin-container[1644:7755] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:17:04 INFO - PID 1640 | RSS: Main: 230187008 16:17:04 INFO - PID 1640 | 16:20:08 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 16:20:10 INFO - PID 1640 | RSS: Main: 253116416 16:20:10 INFO - PID 1640 | 16:21:52 INFO - PID 1640 | ************************* 16:21:52 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 16:21:52 INFO - PID 1640 | 16:21:52 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:21:52 INFO - PID 1640 | Full stack: 16:21:52 INFO - PID 1640 | ************************* 16:21:52 INFO - PID 1640 | ************************* 16:21:52 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 16:21:52 INFO - PID 1640 | 16:21:52 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:21:52 INFO - PID 1640 | Full stack: 16:21:52 INFO - PID 1640 | ************************* 16:21:52 INFO - PID 1640 | ************************* 16:21:52 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 16:21:52 INFO - PID 1640 | 16:21:52 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:21:52 INFO - PID 1640 | Full stack: 16:21:52 INFO - PID 1640 | ************************* 16:21:52 INFO - PID 1640 | ************************* 16:21:52 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 16:21:52 INFO - PID 1640 | 16:21:52 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:21:52 INFO - PID 1640 | Full stack: 16:21:52 INFO - PID 1640 | ************************* 16:23:12 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 16:23:13 INFO - PID 1640 | RSS: Main: 261423104 16:23:13 INFO - PID 1640 | 16:26:14 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 16:26:15 INFO - PID 1640 | RSS: Main: 273125376 16:26:15 INFO - PID 1640 | 16:29:17 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 16:29:18 INFO - PID 1640 | RSS: Main: 265613312 16:29:18 INFO - PID 1640 | 16:32:21 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 16:32:21 INFO - PID 1640 | RSS: Main: 275898368 16:32:21 INFO - PID 1640 | 16:35:25 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 16:35:25 INFO - PID 1640 | RSS: Main: 269955072 16:35:25 INFO - PID 1640 | 16:35:25 INFO - PID 1640 | __start_tp_report 16:35:25 INFO - PID 1640 | _x_x_mozilla_page_load 16:35:25 INFO - PID 1640 | _x_x_mozilla_page_load_details 16:35:25 INFO - PID 1640 | |i|pagename|runs| 16:35:25 INFO - PID 1640 | |0;dojo.html;3580.803055831929;3586.605473397927;3615.8192090395473;3620.2397496525728;3629.364861149298;5750.3962083981205;5787.305523337205;5859.941400585987;6265;6274.717637706302;6121.910283036244;6243.8126856194385;6261.655608941506;6291.842703932392;6304.59020163689;4296.849610263642;4730.455997560291;5063.797448102072;5602;5747.683877386742;4727.409073865767;5736.569757268201;5760.596758226926;5785.842831433709;5831.854203644901;5661.207430959669;5834.6790926499025;5837.182794408786;5865.618734782225;5874.559408044413;5707.62900411476;5733.684647344395;5775.191473193756;5779.162021506858;5865.970670146665;5612.382637909826;5658.91511627326;5671.631343962629;5694.487496125327;5703.857403564903;5213.852952350515;6142.529470849001;6179.474744646711;6243.500519958392;6335.809925702236;6623;6708.496862735315;6710.463162946952;6732.46140308779;6743.123393958803;6822.4200942919915;6835.624040677761;6836.282190370039;6845.075914751495;6846.863062738767;5004.474530174352;6244.875102497925;6358.904616430757;6390.904136437957;6394.776182833579;6726.6636668167375;6892.551984121016;6935.826604334932;6936.4797640177185;6941.514094013472;6386.393292637192;6428.935710642927;6608.86782264352;6653.935370340819;6659.067730517731;6556.8360790980605;6745.12313399255;6766.966165169143;6831.2144103427745;6859.794206173823;6753.628550429676;6781.491388145909;6783.694733736994;6784.7964561063245;6794.830129246809;6475.061116138134;6557.573757705733;6589;6595.8021259362295;6640.203175618956;5340.385855626652;5382.67703937765;5396.028714831368;5416.9729151353995;5438.347398312228;4778.04439112179;4790.808367665254;4805.567498925044;4807.735574543363;4824.589909857631;5886.264216972879;6316;6372.840678983062;6383.521235907326;6384;6667.166604174478;6799.4900382471515;6807.591544507345;6815.522913396114;6874.2094659113845;6321.431071203522;6354.841128971812;6386;6389.904151437732;6396.392342727433;4970.776315065831;5795.652260864362;6024.367441418588;6031.4270144336215;6046.123312119806;6849.691763870538;6877.965610171917;6944.2708515607155;6978.895316570154;7007.6846541904715;6027.2164618601055;6270.905936411049;6893.482988775962;6898.827529311808;6916.170059592881;6964.930350696631;7003.719851206099;7004.089468369283;7060.646967651494;7067.363937245673;6278.497720182283;6341.556091073765;6419.518536109717;6573;6715.56348837324;6819.113515242987;6862.451003919575;6862.965685171542;6870.828229294308;6883.483738719716;6281.685915704105;6443;6542.149520562487;6603.075569420189;6605.867882642224;6509.251436084911;6585.111010013587;6586.604803711793;6589.538732288694;6609.20689517261;5331.5201631853315;5462.481064298885;6262.778758142119;6327.778527751507;6332.366763323631;6527.412532872065;6608.50436217276;6613.140291762233;6640.236372817188;6641.601503909781;6385.744570217139;6399.168108145911;6417;6421.967890160519;6425.45383642377 16:35:25 INFO - PID 1640 | |1;ext.html;673.855121148953;798.3692882622728;1229.5942339028122;1264.968375790605;1290.7353992431547;38202.04494887629;38523.80738096306;38634.80682596587;38676.033099172535;38676.226475470474;32963.50554741681;37635.43546846799;37916.43125353122;38007.23985520288;38012.80993595028;7919.802004949865;8583.699570515033;8815.911840881574;8822.11778822119;8845.115488451167;8678.956605216965;8759.080296568864;8780.034196238408;8788.780280492974;8789.648414063462;8735;8755.993060798026;8807.427517211392;8809.515476648781;8820.955895220515;8732.519711415902;8776.561171941377;8777.95611021944;8782.165694259034;8788.956055219747;8493.83012339756;8569.143085691443;8569.657213711444;8573.056963733985;8590.914090859074;38168.236635267414;38188.80905595482;38200.044998874975;38260.61739382598;38365.808170959244;3055.816651000947;3075.123589776925;3081.0910781319562;3096.1021303821863;3104.161876293411;27362.863185684142;27650.308742281402;27744.861275693693;27958.021469248477;28555.428891422263;2740.177946616027;2780.555111182201;2798.6501687289087;2839.162447078117;2848.401835614503;238992.80503597544;310160.44919775485;313274;314034;314644;38829.41755873664;40064.198716025516;46937.530624694;48365.999999999294;48394.75802621035;50252.24621630678;50326.24510632343;50398;50406.243906341435;50429.49570504321;14341.99606027589;14387.568372948828;14394.42422303117;14434.783478247835;14435.566932992027;11414.942925285486;11593.536258549564;11614.419279036014;11688.94155529217;11702.005329547084;13515.459381624822;13516.918646508257;13548.661283467993;13551.254680992442;13614.863851361557;4700.318453824177;5483.369412517532;5977.163197152402;5986.790462333797;6026.969865150646;974.741693451236;989.4607438945792;997.1374760831828;1008.1884083313004;1031.4481752262504;20194;23834.88082559611;23850.403739906295;24183.516329673308;24265.908034138505;921.949292788903;1056.8361903904906;1100.9009189173014;1131.4964840645835;1147.9196456247982;11688.649340519798;11761.82381761834;12237.816432753516;12257.938710306391;12318.75362492745;458605;458888;459337;459846;459883;53271;53639.73180134074;53830.461695383325;53967.730161349726;53972.92054158895;1464.6704491489543;1515.4620109860841;1522.6117340078208;1529.2048134969752;1535.0712818744498;18706.719399208752;23076.884615576815;23263.41841453977;23337.299881003597;23395;36739.63260367362;36790;36815.63184368122;36819;36864.44703329506;379122;380385;382402;382649;383550;32670.83664581662;32711.34577308393;34155.82922085374;35296.29407411889;35303.823480882435;42681;43183;44218.33672494851;44472.77763611291;44603;29009.419811603228;29629;30674;30675;30737.385252295277;29671.554926675686;30508.237294067825;30636.080917571617;30912.536311955788;30970.69029309678;31683.04950851478;34960.475592866635;35794.463083053255;36093.458598120524;36133.63866361303;29358.82564697388;30204.84897575498;30826.53760193554;30875.536866947463;30957.690423095482;19347.903260483607;24368.390790230387;24427.51144977055;24664;24698.13556525513 16:35:25 INFO - PID 1640 | |2;jquery.html;416.99166016679646;417.6470882104621;424.77274658057945;436.9563043695631;442.08929605013674;19798.109085091168;21368.786312136875;22034.669479957814;22288;22683.773162268415;17870.64258714825;21971.560568788616;21974;22193.77806221937;22227.888860555715;6152.323244443108;6268.902941985157;6343.175387199681;6359.427651511363;6452.516061295421;6084.969575152118;6174.6912654367325;6259.561830671855;6263.812085637438;6273.247210334766;5992.43071908168;6166.38336166382;6180.567360284782;6196.132541444202;6221.0979407985815;5270.209468579725;6241.65670888101;6245.906311405332;6251.187345645082;6251.624902505841;5245.973770131163;5449.337951924661;5516.613837031409;5549.361823390281;5566.916496252559;27956.720432795613;28142.437151257065;28183;28450.00424985136;28558.428831423254;1685.3090233004532;1984.5038740314922;1991.442396129086;2015.4256037029377;2092.3513710749717;16328.428505002268;16427.91786041074;16699.582510437216;17036.659266814593;17088.936770934022;1748.8426041656185;1760.7270873014704;1762.8765986380959;1768.9823101768945;1772.503698964292;156083;168057;168537;170005.09964302502;171775;12664.430100645493;12845.743085138245;12887.226766394046;12902.483900644056;13158.276294803878;16074.598135046483;16354.672906541802;16366.345346186019;16371.83628163727;16441;8604;8739.082396348418;9070.730097786316;9173.495457749754;9209.35534512591;7088.92911070893;7128.893066604005;7588.848223035508;8850.955745221361;8862.42394244372;7854.293113619801;7928.841423171504;7973.641186146638;7982.042154941328;7988.600569971478;2184.322859913432;2186.3440967709626;2188.6716992450943;2188.9890550547148;2359.740428552858;932.8414169591185;942.5193151492788;945.5272363818091;979.6179489998906;980.2011360740973;15966;19541.804581954;21218.78781212199;21311.09081198186;21441.67837482439;976.0873583199731;981.1218959031627;1010.8938561451089;1011.7420057885192;1014.7666036811574;10494.160467162608;10964.616238431616;10992.395418251914;11011.889881101246;11043.83434248487;721075;721780;724187;728950;739321;155731;157197.2140139292;159460;160675.19662401613;164253;1221.969450763738;1247.9188852724544;1254.172246317426;1274.286399616218;1284.3642397013539;17590.648187036444;21656.566868662223;22331.330060098222;22477.21329753451;22610.321690349316;60954.41866045437;63060.68469657622;63785;64190.67904660447;64723;63275;70030;89226;89356.5532172335;91674.9882008082;8352.6241319142;8970.058143895058;9022.729318120468;9060.818783624423;9272.72181834546;14294.070885392417;14508.709825803637;14528.295284702755;14841.109533428029;14863.479778207711;10460.21548383859;11670.88329116698;11814.113941454598;12057.51769929193;12141.878581214074;11369;11425.485853136463;11556.826647600124;11701.824472633087;11860.822087668519;11976.341301228516;11979.221350612183;12255.203411778206;12298.877011229773;12459.626211213677;7568.205338439385;8587.914120858712;8663.523506207219;8946.86579701318;9083.227925626132;11698.532058717556;11850.525978960744;12096.637100886988;12105.69735756613;12129.878701212874 16:35:25 INFO - PID 1640 | |3;mootools.html;1162.9476673549689;1230.907681923856;1373.4437552791123;1389.1526169036892;1400.9229492377922;10705.14358851292;10863.945680271607;11195.048420884224;11213.990740833313;11221.943890280558;11006.679198496171;11096.778064438706;11163.106951443886;11167.83248251277;11182.273152245098;2246.696695946043;2322.8722420266877;2358.870262135582;2362.1614326913955;2363.078399424226;2387.7134743830766;2473.7155227148837;2476.0838489758817;2478.7397323280975;2488.5271798358344;2365.2549446924163;2381;2395.688560487143;2474.418511649761;2486.0677246032737;2376.4890548531976;2431.501542183848;2444.0590372706547;2448.6327050942323;2470.419451428913;1951.5706544560173;2441.2676197140786;2443.3280847766828;2444.1934161726585;2462.2859370782453;11053.502592383362;11187.552497900155;11199.384033878134;11201.551937922473;11251.324920504796;6238.875222495525;6475.643839588868;6484.967575162141;6498.772542960974;6501.414872661436;3685.4656074869263;3702.4631428442726;4005.5794141615293;4092.4884389451317;4143.834246630131;1158.6466127831009;1184.2716729211472;1189.302256088454;1205.4515195585986;1222.7432239229772;13818.723625527533;14285.0000499964;15212.847871521253;15276.541703748906;15343.846561534354;5347.893042139135;5689.630174038674;5772.740226689809;5955.047192449188;5960.314563825129;12776.488940442277;12817.615471535868;12846;12886.80669789954;12905.032122590843;6437.259715132726;6493.545451818327;6502.317256688074;6506.869862602816;6510.44661203803;5832.708364581754;5860.560457965669;5920.674362910081;5934.258217722785;6008.24896887889;3775.414810704345;4430.667699922518;4506.459224893034;4507.6619253555455;4517.638588912879;1041.7656027393775;1376.050525137652;1394.9721005579831;1416.9858301416855;1454.810874586297;734.8456824067006;748.7716246544747;764.9349805266614;767.4704453926885;805.7743831727125;4755.595774359218;5516.696581687966;6197.566170368121;6505.8698826024165;6542.443892269211;569.1661715586686;570.922925405065;586.1939832729996;588.9852753681107;593.9970300148472;3409.9659003410143;3668.174660701321;3706.7590606610484;4154.958450415517;4181.247375472444;11041.83437248442;13143.474261029452;13666.18002919828;13669.906407487577;13696.93151534236;10989;13236.536721214712;13554.254516001518;13585.932070339584;13604.795928061087;10047.899521004843;10133.189344852393;10255.33340332876;10296.40405737127;10317.742056448498;4700.647451441224;5443.4284400138995;6512.5766825156215;6740.258571557027;6788.506528563708;12048.518059277882;12052.276863388224;12123.090768192596;12188.085893558196;12211.02311815035;12300.138990270952;12367.93816030914;12567;12595.118341715992;12768.297743624189;8770.298376129767;8915.37592368528;9011.143941325563;9013.90986090157;9017.819643607223;10280.845787313347;10309.793804124025;10371.792564148524;10379.896201037893;10395.636152734618;8363.163683631588;8567.700130495403;8605.95697021511;8615.870761938451;8763.474191548528;7706.845863082819;7715.691372345268;7796.9220307796195;7812.804679883048;7943.086545047394;7700.730474433368;7705.30652241301;7709.575973321521;7795.41534384912;7812.960935195288;5614.803481878114;5760.94239057604;5770.5095066919785;5783.334916484658;5935.465808077293;5810.883782324246;5905.881882362242;5960.0761881908375;5964.731587078679;5989.730462129128 16:35:25 INFO - PID 1640 | |4;prototype.html;304.0134762695052;366.543653151826;398.6611380326723;409.9733517321374;410.65710132039754;5116.288367165399;5959.6424214547105;6260.968695156529;6382.26603940547;6398.328175541571;5075.720835354064;5370.757101889502;6150.569460137792;6222.1644614838615;6294.213223347082;3856.421536769479;4023.919521609566;4699.882502937437;4908.705477671333;4917.311576379309;3802.030482227028;4581.053052452694;4891.021795640886;4905.901881962359;4908.239222920435;3925.3719404895223;4761.119192949298;4869.342638743778;4882.6093912487095;4897.142999975008;3951;4689.531046895318;4806.591439727627;4883.218685010399;4898.4486247470295;4287.421198138242;4290.656747460227;4295.8066886989845;4303.72025818324;4355.5644435556505;6100.816975490742;6130.17242672238;6132.264128304588;6137.754489820401;6169.7532098716;4649.744264065475;5468;5614.466625670555;5631.690257035862;5645.769920881229;6421.779861826285;6427.614343139427;6653.800385988428;6704.430123439513;6712.295209003033;4063.553009168989;4644.442666879962;4791.305260737174;4793.952060479385;4808.447028591721;6146.692665366714;6295.276043254993;6825.317468253135;6840.589564626139;7064.470164737717;4812.446568644651;4857.732824694675;4861.611071114302;4897.4857639948;4904.386951631046;4548.522405147478;4659.487456379796;4724.645651576146;5005.074061298671;5008.949910500921;5482.91775623366;5543.972280138573;5570;5595.888082238333;5648.265725455664;5423.810166644227;6512.641804700787;6595.373439523238;6718.160229971254;6756.290589488131;1434.8206474190727;1449.913005219669;1719.415398764426;1721.9938771331508;1726.4734256051775;988.663854289545;1004.5680357446369;1190.934498602585;1192.201225179132;1217.6468824040928;5283.6037297202865;6301.968490157612;6323.841903952438;6351.618902865843;6369.681515924185;6709.396154346132;6811.659417029228;6944.270851560514;6973.616451095137;6997.790066298019;5392.91910621341;6237.033259844731;6257.155284036597;6261.436470717657;6313.747450101944;3453.723702103826;3492.4237500812587;3583.390823560001;3609.4224924012556;3622.8913132606062;6648.301928297556;7066.364027237573;7100.822479438056;7145.17830449505;7178.5692858428665;6534.411902928664;7021.859562808715;7127.714891404389;7153.320434558708;7161.854103343544;5688.407245971135;6783.660816959133;7037.155541334971;7146.535475194096;7180.7845764627145;5766.625169363978;6586.37429444202;6860.862782744217;6955.791326260018;6965.5124141309625;6447;6551.344865513411;6797;6865.794026179223;6867.519273650996;6988.161420629557;7045.929540704527;7191.460640451883;7198.820029499305;7252.347288744038;3718.4980027695915;3779.905502362463;4333.891652708708;4392.770024393175;4532.841350552715;5676.460736230051;6643.368879956396;6856.177258728984;6995.825104372431;7018.894716579153;4930.704157750546;6231.629041610839;6440.420362167428;6781.355771201728;6797.966010169917;5609.74756135983;6541.901871471837;6655.101561289164;6675.499337549801;6752.628605426748;6580.440662543546;6607.372299631527;6613.5370524062855;6629.436497897733;6653.600783952978;6459.4832413405875;6666;6784.846576081979;6789.219239787488;6793.354631310018;5403.946230485018;6151.9384806151365;6170.5372097092;6442.549021568445;6551.934480655133 16:35:25 INFO - PID 1640 | |5;yui.html;697.8021706101565;722.1695050691706;725.4232884856539;730.2369024369532;737.498501019307;15486.92256538717;16246.75629865553;16396.83603163968;16470.176491175454;16470.258838352274;14893.10641361519;14913.850861491383;15071.020383675053;15074.17092059939;15122.470713525028;927.2906226736552;957.3585697582602;969.6412327438858;969.8448248280276;987.8320685483449;998.0518507417946;1006.5319626373735;1008.5108722269694;1008.6721815409984;1015.7663737340416;1045.6549338718244;1046.7278507588044;1047.0524175621038;1047.8847326794046;1050.086424810416;1033.41095575522;1035.471909326249;1042.4266653340633;1044.3107549017611;1044.958201671932;774.0401901641924;778.4278555261878;780.0911937592698;780.5355813291139;780.6292011294646;16385.426510072208;16622.002679839126;16630.75053874193;16866.156692165467;17111.572210694707;14536.127832330094;14653.506658652785;14653.92673036628;14708.55874323772;14766.409343626243;11713.297202167812;12010.459529321202;12018.759624807455;12085.335306558136;12105;2991.2073300575366;3042.710942460485;3048.3903219356216;3072.1244445332973;3141.4816555268353;106596;109767;109903.45048274708;110992.78014439746;112046;10735.57057717698;10961.945190273998;11513.30920144794;11621.883781162249;11858.644240672793;24674.753252467606;24702.752972470404;24748.133815316378;24794.5041099177;24813.5037299253;7673.731419400406;8010.439269251213;8036.557989310644;8108.675652973933;8279.54462504556;4202.810873510699;4204;4216.072464057902;4259.403683484315;4344.717593356421;4080.265552200573;4086.468759061303;4155.563665815046;4183.602557757008;4187;703.3985942019602;842.9620667069996;861.440063958432;871.7471933139318;872.9563521823883;544.6160456877892;550.6783719074174;567.8884873515716;591.9378465261171;600.8738164985402;9994.500274986221;10019.616119981158;10224.948875255575;10521.368717876952;10574.101201397814;453.5441880909665;456.07082042957813;458.93574899513425;462.5143599220805;480.9903801923942;4509.932351014738;5023.2214006829;5367.731613419313;5488.91766623501;5523.530499907472;100464.49767751114;103840;104377;105726;106786.46606766917;88042.55978720193;92376.07623923675;93517;93784.53107734417;95463.36293096536;80949.19050809534;82698;83043;83068.58465707753;84166.47500575116;10826.40454774995;10834;11051.613193538187;11140.164487663296;11158.665240042812;36079.27841443209;36345;36869.89390318295;37393;37654.058648534;48843.75578122087;49242.61360795766;49604.75197624133;49851.75074124606;50218.744531387005;11780.82328765052;13239.40422681001;13643.86356136426;14128.43486260538;14215.928920355333;14148;14326.713465730836;14430.927845360706;14531.27343632793;15012.249387530797;13758.098174410065;13785.724285514434;13816.171029738249;13835.308234588432;14468.927655361656;11906.40467976615;13509.392077356346;13524.93237533806;13677.110987785762;13713.862861371259;13278.933605331911;13391.86608133945;13423.731525369632;13464.663383415493;13927.303634818421;10047.949760251153;10049.196064314983;10133.341332813343;10137.391756494633;10782.784344313226;10274.33216840903;10893.773989332347;11277.361185058378;11408.315501069963;11415.429228538706 16:35:25 INFO - PID 1640 | __end_tp_report 16:35:25 INFO - PID 1640 | __start_cc_report 16:35:25 INFO - PID 1640 | _x_x_mozilla_cycle_collect,439 16:35:25 INFO - PID 1640 | __end_cc_report 16:35:25 INFO - PID 1640 | __startTimestamp1490916925305__endTimestamp 16:35:25 INFO - PID 1640 | 16:35:25 INFO - PID 1640 | ------- Summary: start ------- 16:35:25 INFO - PID 1640 | Number of tests: 6 16:35:25 INFO - PID 1640 | 16:35:25 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:6189.11 Median:6386.39 stddev:746.62 (11.7%) stddev-sans-first:720.33 16:35:25 INFO - PID 1640 | Values: 3580.8 3586.6 3615.8 3620.2 3629.4 5750.4 5787.3 5859.9 6265.0 6274.7 6121.9 6243.8 6261.7 6291.8 6304.6 4296.8 4730.5 5063.8 5602.0 5747.7 4727.4 5736.6 5760.6 5785.8 5831.9 5661.2 5834.7 5837.2 5865.6 5874.6 5707.6 5733.7 5775.2 5779.2 5866.0 5612.4 5658.9 5671.6 5694.5 5703.9 5213.9 6142.5 6179.5 6243.5 6335.8 6623.0 6708.5 6710.5 6732.5 6743.1 6822.4 6835.6 6836.3 6845.1 6846.9 5004.5 6244.9 6358.9 6390.9 6394.8 6726.7 6892.6 6935.8 6936.5 6941.5 6386.4 6428.9 6608.9 6653.9 6659.1 6556.8 6745.1 6767.0 6831.2 6859.8 6753.6 6781.5 6783.7 6784.8 6794.8 6475.1 6557.6 6589.0 6595.8 6640.2 5340.4 5382.7 5396.0 5417.0 5438.3 4778.0 4790.8 4805.6 4807.7 4824.6 5886.3 6316.0 6372.8 6383.5 6384.0 6667.2 6799.5 6807.6 6815.5 6874.2 6321.4 6354.8 6386.0 6389.9 6396.4 4970.8 5795.7 6024.4 6031.4 6046.1 6849.7 6878.0 6944.3 6978.9 7007.7 6027.2 6270.9 6893.5 6898.8 6916.2 6964.9 7003.7 7004.1 7060.6 7067.4 6278.5 6341.6 6419.5 6573.0 6715.6 6819.1 6862.5 6863.0 6870.8 6883.5 6281.7 6443.0 6542.1 6603.1 6605.9 6509.3 6585.1 6586.6 6589.5 6609.2 5331.5 5462.5 6262.8 6327.8 6332.4 6527.4 6608.5 6613.1 6640.2 6641.6 6385.7 6399.2 6417.0 6422.0 6425.5 16:35:25 INFO - PID 1640 | 16:35:25 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:51399.88 Median:35300.06 stddev:102341.19 (289.9%) stddev-sans-first:102557.42 16:35:25 INFO - PID 1640 | Values: 673.9 798.4 1229.6 1265.0 1290.7 38202.0 38523.8 38634.8 38676.0 38676.2 32963.5 37635.4 37916.4 38007.2 38012.8 7919.8 8583.7 8815.9 8822.1 8845.1 8679.0 8759.1 8780.0 8788.8 8789.6 8735.0 8756.0 8807.4 8809.5 8821.0 8732.5 8776.6 8778.0 8782.2 8789.0 8493.8 8569.1 8569.7 8573.1 8590.9 38168.2 38188.8 38200.0 38260.6 38365.8 3055.8 3075.1 3081.1 3096.1 3104.2 27362.9 27650.3 27744.9 27958.0 28555.4 2740.2 2780.6 2798.7 2839.2 2848.4 238992.8 310160.4 313274.0 314034.0 314644.0 38829.4 40064.2 46937.5 48366.0 48394.8 50252.2 50326.2 50398.0 50406.2 50429.5 14342.0 14387.6 14394.4 14434.8 14435.6 11414.9 11593.5 11614.4 11688.9 11702.0 13515.5 13516.9 13548.7 13551.3 13614.9 4700.3 5483.4 5977.2 5986.8 6027.0 974.7 989.5 997.1 1008.2 1031.4 20194.0 23834.9 23850.4 24183.5 24265.9 921.9 1056.8 1100.9 1131.5 1147.9 11688.6 11761.8 12237.8 12257.9 12318.8 458605.0 458888.0 459337.0 459846.0 459883.0 53271.0 53639.7 53830.5 53967.7 53972.9 1464.7 1515.5 1522.6 1529.2 1535.1 18706.7 23076.9 23263.4 23337.3 23395.0 36739.6 36790.0 36815.6 36819.0 36864.4 379122.0 380385.0 382402.0 382649.0 383550.0 32670.8 32711.3 34155.8 35296.3 35303.8 42681.0 43183.0 44218.3 44472.8 44603.0 29009.4 29629.0 30674.0 30675.0 30737.4 29671.6 30508.2 30636.1 30912.5 30970.7 31683.0 34960.5 35794.5 36093.5 36133.6 29358.8 30204.8 30826.5 30875.5 30957.7 19347.9 24368.4 24427.5 24664.0 24698.1 16:35:25 INFO - PID 1640 | 16:35:25 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:41965.27 Median:22210.83 stddev:122131.76 (549.9%) stddev-sans-first:122434.53 16:35:25 INFO - PID 1640 | Values: 417.0 417.6 424.8 437.0 442.1 19798.1 21368.8 22034.7 22288.0 22683.8 17870.6 21971.6 21974.0 22193.8 22227.9 6152.3 6268.9 6343.2 6359.4 6452.5 6085.0 6174.7 6259.6 6263.8 6273.2 5992.4 6166.4 6180.6 6196.1 6221.1 5270.2 6241.7 6245.9 6251.2 6251.6 5246.0 5449.3 5516.6 5549.4 5566.9 27956.7 28142.4 28183.0 28450.0 28558.4 1685.3 1984.5 1991.4 2015.4 2092.4 16328.4 16427.9 16699.6 17036.7 17088.9 1748.8 1760.7 1762.9 1769.0 1772.5 156083.0 168057.0 168537.0 170005.1 171775.0 12664.4 12845.7 12887.2 12902.5 13158.3 16074.6 16354.7 16366.3 16371.8 16441.0 8604.0 8739.1 9070.7 9173.5 9209.4 7088.9 7128.9 7588.8 8851.0 8862.4 7854.3 7928.8 7973.6 7982.0 7988.6 2184.3 2186.3 2188.7 2189.0 2359.7 932.8 942.5 945.5 979.6 980.2 15966.0 19541.8 21218.8 21311.1 21441.7 976.1 981.1 1010.9 1011.7 1014.8 10494.2 10964.6 10992.4 11011.9 11043.8 721075.0 721780.0 724187.0 728950.0 739321.0 155731.0 157197.2 159460.0 160675.2 164253.0 1222.0 1247.9 1254.2 1274.3 1284.4 17590.6 21656.6 22331.3 22477.2 22610.3 60954.4 63060.7 63785.0 64190.7 64723.0 63275.0 70030.0 89226.0 89356.6 91675.0 8352.6 8970.1 9022.7 9060.8 9272.7 14294.1 14508.7 14528.3 14841.1 14863.5 10460.2 11670.9 11814.1 12057.5 12141.9 11369.0 11425.5 11556.8 11701.8 11860.8 11976.3 11979.2 12255.2 12298.9 12459.6 7568.2 8587.9 8663.5 8946.9 9083.2 11698.5 11850.5 12096.6 12105.7 12129.9 16:35:25 INFO - PID 1640 | 16:35:25 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:6744.17 Median:2475.25 stddev:4176.03 (168.7%) stddev-sans-first:4166.68 16:35:25 INFO - PID 1640 | Values: 1162.9 1230.9 1373.4 1389.2 1400.9 10705.1 10863.9 11195.0 11214.0 11221.9 11006.7 11096.8 11163.1 11167.8 11182.3 2246.7 2322.9 2358.9 2362.2 2363.1 2387.7 2473.7 2476.1 2478.7 2488.5 2365.3 2381.0 2395.7 2474.4 2486.1 2376.5 2431.5 2444.1 2448.6 2470.4 1951.6 2441.3 2443.3 2444.2 2462.3 11053.5 11187.6 11199.4 11201.6 11251.3 6238.9 6475.6 6485.0 6498.8 6501.4 3685.5 3702.5 4005.6 4092.5 4143.8 1158.6 1184.3 1189.3 1205.5 1222.7 13818.7 14285.0 15212.8 15276.5 15343.8 5347.9 5689.6 5772.7 5955.0 5960.3 12776.5 12817.6 12846.0 12886.8 12905.0 6437.3 6493.5 6502.3 6506.9 6510.4 5832.7 5860.6 5920.7 5934.3 6008.2 3775.4 4430.7 4506.5 4507.7 4517.6 1041.8 1376.1 1395.0 1417.0 1454.8 734.8 748.8 764.9 767.5 805.8 4755.6 5516.7 6197.6 6505.9 6542.4 569.2 570.9 586.2 589.0 594.0 3410.0 3668.2 3706.8 4155.0 4181.2 11041.8 13143.5 13666.2 13669.9 13696.9 10989.0 13236.5 13554.3 13585.9 13604.8 10047.9 10133.2 10255.3 10296.4 10317.7 4700.6 5443.4 6512.6 6740.3 6788.5 12048.5 12052.3 12123.1 12188.1 12211.0 12300.1 12367.9 12567.0 12595.1 12768.3 8770.3 8915.4 9011.1 9013.9 9017.8 10280.8 10309.8 10371.8 10379.9 10395.6 8363.2 8567.7 8606.0 8615.9 8763.5 7706.8 7715.7 7796.9 7812.8 7943.1 7700.7 7705.3 7709.6 7795.4 7813.0 5614.8 5760.9 5770.5 5783.3 5935.5 5810.9 5905.9 5960.1 5964.7 5989.7 16:35:25 INFO - PID 1640 | 16:35:25 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5431.29 Median:6142.22 stddev:1675.29 (27.3%) stddev-sans-first:1635.19 16:35:25 INFO - PID 1640 | Values: 304.0 366.5 398.7 410.0 410.7 5116.3 5959.6 6261.0 6382.3 6398.3 5075.7 5370.8 6150.6 6222.2 6294.2 3856.4 4023.9 4699.9 4908.7 4917.3 3802.0 4581.1 4891.0 4905.9 4908.2 3925.4 4761.1 4869.3 4882.6 4897.1 3951.0 4689.5 4806.6 4883.2 4898.4 4287.4 4290.7 4295.8 4303.7 4355.6 6100.8 6130.2 6132.3 6137.8 6169.8 4649.7 5468.0 5614.5 5631.7 5645.8 6421.8 6427.6 6653.8 6704.4 6712.3 4063.6 4644.4 4791.3 4794.0 4808.4 6146.7 6295.3 6825.3 6840.6 7064.5 4812.4 4857.7 4861.6 4897.5 4904.4 4548.5 4659.5 4724.6 5005.1 5008.9 5482.9 5544.0 5570.0 5595.9 5648.3 5423.8 6512.6 6595.4 6718.2 6756.3 1434.8 1449.9 1719.4 1722.0 1726.5 988.7 1004.6 1190.9 1192.2 1217.6 5283.6 6302.0 6323.8 6351.6 6369.7 6709.4 6811.7 6944.3 6973.6 6997.8 5392.9 6237.0 6257.2 6261.4 6313.7 3453.7 3492.4 3583.4 3609.4 3622.9 6648.3 7066.4 7100.8 7145.2 7178.6 6534.4 7021.9 7127.7 7153.3 7161.9 5688.4 6783.7 7037.2 7146.5 7180.8 5766.6 6586.4 6860.9 6955.8 6965.5 6447.0 6551.3 6797.0 6865.8 6867.5 6988.2 7045.9 7191.5 7198.8 7252.3 3718.5 3779.9 4333.9 4392.8 4532.8 5676.5 6643.4 6856.2 6995.8 7018.9 4930.7 6231.6 6440.4 6781.4 6798.0 5609.7 6541.9 6655.1 6675.5 6752.6 6580.4 6607.4 6613.5 6629.4 6653.6 6459.5 6666.0 6784.8 6789.2 6793.4 5403.9 6151.9 6170.5 6442.5 6551.9 16:35:25 INFO - PID 1640 | 16:35:25 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:20273.37 Median:16391.13 stddev:29337.08 (179.0%) stddev-sans-first:29382.56 16:35:25 INFO - PID 1640 | Values: 697.8 722.2 725.4 730.2 737.5 15486.9 16246.8 16396.8 16470.2 16470.3 14893.1 14913.9 15071.0 15074.2 15122.5 927.3 957.4 969.6 969.8 987.8 998.1 1006.5 1008.5 1008.7 1015.8 1045.7 1046.7 1047.1 1047.9 1050.1 1033.4 1035.5 1042.4 1044.3 1045.0 774.0 778.4 780.1 780.5 780.6 16385.4 16622.0 16630.8 16866.2 17111.6 14536.1 14653.5 14653.9 14708.6 14766.4 11713.3 12010.5 12018.8 12085.3 12105.0 2991.2 3042.7 3048.4 3072.1 3141.5 106596.0 109767.0 109903.5 110992.8 112046.0 10735.6 10961.9 11513.3 11621.9 11858.6 24674.8 24702.8 24748.1 24794.5 24813.5 7673.7 8010.4 8036.6 8108.7 8279.5 4202.8 4204.0 4216.1 4259.4 4344.7 4080.3 4086.5 4155.6 4183.6 4187.0 703.4 843.0 861.4 871.7 873.0 544.6 550.7 567.9 591.9 600.9 9994.5 10019.6 10224.9 10521.4 10574.1 453.5 456.1 458.9 462.5 481.0 4509.9 5023.2 5367.7 5488.9 5523.5 100464.5 103840.0 104377.0 105726.0 106786.5 88042.6 92376.1 93517.0 93784.5 95463.4 80949.2 82698.0 83043.0 83068.6 84166.5 10826.4 10834.0 11051.6 11140.2 11158.7 36079.3 36345.0 36869.9 37393.0 37654.1 48843.8 49242.6 49604.8 49851.8 50218.7 11780.8 13239.4 13643.9 14128.4 14215.9 14148.0 14326.7 14430.9 14531.3 15012.2 13758.1 13785.7 13816.2 13835.3 14468.9 11906.4 13509.4 13524.9 13677.1 13713.9 13278.9 13391.9 13423.7 13464.7 13927.3 10047.9 10049.2 10133.3 10137.4 10782.8 10274.3 10893.8 11277.4 11408.3 11415.4 16:35:25 INFO - PID 1640 | -------- Summary: end -------- 16:35:25 INFO - PID 1640 | 16:35:25 INFO - TEST-INFO | 1640: exit 0 16:35:25 INFO - TEST-OK | dromaeo_css | took 1118069ms 16:35:25 INFO - TEST-START | kraken 16:35:25 INFO - operating with platform_type : mac_ 16:35:25 INFO - Initialising browser for kraken test... 16:35:26 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpaK5FQL/profile http://localhost:49237/getInfo.html) 16:35:28 INFO - PID 1652 | 2017-03-30 16:35:28.737 plugin-container[1653:10100] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 16:35:28 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:28 INFO - PID 1652 | 2017-03-30 16:35:28.740 plugin-container[1653:10100] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470f, name = 'com.apple.CFPasteboardClient' 16:35:28 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:28 INFO - PID 1652 | 2017-03-30 16:35:28.740 plugin-container[1653:10100] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:35:28 INFO - PID 1652 | __metrics Screen width/height:1600/1200 16:35:28 INFO - PID 1652 | colorDepth:24 16:35:28 INFO - PID 1652 | Browser inner width/height: 1024/689 16:35:28 INFO - PID 1652 | __metrics 16:35:28 ERROR - PID 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 16:35:28 ERROR - PID 1652 | 1490916928952 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpaK5FQL/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpaK5FQL/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpaK5FQL/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpaK5FQL/profile/extensions/talos-powers@TalosPowersService.js:55:9 16:35:29 INFO - TEST-INFO | 1652: exit 0 16:35:29 INFO - Browser initialized. 16:35:29 INFO - Running cycle 1/1 for kraken test... 16:35:29 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpaK5FQL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:35:32 INFO - PID 1654 | 2017-03-30 16:35:32.245 plugin-container[1655:10219] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3b, name = 'com.apple.tsm.portname' 16:35:32 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:32 INFO - PID 1654 | 2017-03-30 16:35:32.246 plugin-container[1655:10219] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.CFPasteboardClient' 16:35:32 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:32 INFO - PID 1654 | 2017-03-30 16:35:32.246 plugin-container[1655:10219] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:35:41 INFO - PID 1654 | RSS: Main: 230166528 16:35:41 INFO - PID 1654 | 16:35:44 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 16:35:45 INFO - PID 1654 | RSS: Main: 244994048 16:35:45 INFO - PID 1654 | 16:35:48 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 16:35:49 INFO - PID 1654 | RSS: Main: 253399040 16:35:49 INFO - PID 1654 | 16:35:52 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 16:35:53 INFO - PID 1654 | RSS: Main: 255889408 16:35:53 INFO - PID 1654 | 16:35:55 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 16:35:55 INFO - PID 1654 | RSS: Main: 257794048 16:35:55 INFO - PID 1654 | 16:35:57 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:35:58 INFO - PID 1654 | RSS: Main: 258105344 16:35:58 INFO - PID 1654 | 16:36:01 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 16:36:02 INFO - PID 1654 | RSS: Main: 263524352 16:36:02 INFO - PID 1654 | 16:36:05 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 16:36:06 INFO - PID 1654 | RSS: Main: 264859648 16:36:06 INFO - PID 1654 | 16:36:09 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 16:36:10 INFO - PID 1654 | RSS: Main: 264589312 16:36:10 INFO - PID 1654 | 16:36:11 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:36:12 INFO - PID 1654 | RSS: Main: 264318976 16:36:12 INFO - PID 1654 | 16:36:14 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:36:15 INFO - PID 1654 | RSS: Main: 264232960 16:36:15 INFO - PID 1654 | 16:36:17 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:36:18 INFO - PID 1654 | RSS: Main: 264273920 16:36:18 INFO - PID 1654 | 16:36:21 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:36:21 INFO - PID 1654 | RSS: Main: 264679424 16:36:21 INFO - PID 1654 | 16:36:25 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:36:25 INFO - PID 1654 | RSS: Main: 264437760 16:36:25 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 16:36:27 INFO - PID 1654 | RSS: Main: 267612160 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | __start_tp_report 16:36:27 INFO - PID 1654 | _x_x_mozilla_page_load 16:36:27 INFO - PID 1654 | _x_x_mozilla_page_load_details 16:36:27 INFO - PID 1654 | |i|pagename|runs| 16:36:27 INFO - PID 1654 | |0;ai-astar;111;123;129;124;122;123;124;124;109;178 16:36:27 INFO - PID 1654 | |1;audio-beat-detection;172;104;98;191;149;156;122;114;155;156 16:36:27 INFO - PID 1654 | |2;audio-dft;222;224;262;234;224;211;217;215;208;213 16:36:27 INFO - PID 1654 | |3;audio-fft;87;60;59;88;65;86;88;63;74;62 16:36:27 INFO - PID 1654 | |4;audio-oscillator;87;71;88;74;70;75;87;73;73;86 16:36:27 INFO - PID 1654 | |5;imaging-gaussian-blur;92;94;93;99;117;94;92;92;93;92 16:36:27 INFO - PID 1654 | |6;imaging-darkroom;102;101;102;111;101;101;103;115;163;160 16:36:27 INFO - PID 1654 | |7;imaging-desaturate;103;141;103;105;98;103;102;107;103;102 16:36:27 INFO - PID 1654 | |8;json-parse-financial;68;67;67;66;69;66;68;68;67;66 16:36:27 INFO - PID 1654 | |9;json-stringify-tinderbox;60;77;93;68;57;69;91;93;89;92 16:36:27 INFO - PID 1654 | |10;stanford-crypto-aes;90;88;89;85;90;95;76;102;125;111 16:36:27 INFO - PID 1654 | |11;stanford-crypto-ccm;147;146;148;150;152;164;153;172;216;179 16:36:27 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;198;194;241;197;195;194;201;198;206;193 16:36:27 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;73;73;73;72;73;70;83;90;105;106 16:36:27 INFO - PID 1654 | __end_tp_report 16:36:27 INFO - PID 1654 | __start_cc_report 16:36:27 INFO - PID 1654 | _x_x_mozilla_cycle_collect,1027 16:36:27 INFO - PID 1654 | __end_cc_report 16:36:27 INFO - PID 1654 | __startTimestamp1490916987856__endTimestamp 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | ------- Summary: start ------- 16:36:27 INFO - PID 1654 | Number of tests: 14 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:126.70 Median:124.00 stddev:19.06 (15.4%) stddev-sans-first:19.35 16:36:27 INFO - PID 1654 | Values: 111.0 123.0 129.0 124.0 122.0 123.0 124.0 124.0 109.0 178.0 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:156.00 stddev:30.68 (19.7%) stddev-sans-first:30.52 16:36:27 INFO - PID 1654 | Values: 172.0 104.0 98.0 191.0 149.0 156.0 122.0 114.0 155.0 156.0 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:223.00 Median:223.00 stddev:15.68 (7.0%) stddev-sans-first:16.63 16:36:27 INFO - PID 1654 | Values: 222.0 224.0 262.0 234.0 224.0 211.0 217.0 215.0 208.0 213.0 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:73.20 Median:80.00 stddev:12.76 (16.0%) stddev-sans-first:12.52 16:36:27 INFO - PID 1654 | Values: 87.0 60.0 59.0 88.0 65.0 86.0 88.0 63.0 74.0 62.0 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:78.40 Median:80.50 stddev:7.55 (9.4%) stddev-sans-first:7.33 16:36:27 INFO - PID 1654 | Values: 87.0 71.0 88.0 74.0 70.0 75.0 87.0 73.0 73.0 86.0 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:95.80 Median:93.00 stddev:7.74 (8.3%) stddev-sans-first:8.09 16:36:27 INFO - PID 1654 | Values: 92.0 94.0 93.0 99.0 117.0 94.0 92.0 92.0 93.0 92.0 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:115.90 Median:107.00 stddev:24.51 (22.9%) stddev-sans-first:25.48 16:36:27 INFO - PID 1654 | Values: 102.0 101.0 102.0 111.0 101.0 101.0 103.0 115.0 163.0 160.0 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:106.70 Median:104.00 stddev:12.27 (11.8%) stddev-sans-first:12.94 16:36:27 INFO - PID 1654 | Values: 103.0 141.0 103.0 105.0 98.0 103.0 102.0 107.0 103.0 102.0 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:67.50 stddev:1.03 (1.5%) stddev-sans-first:1.05 16:36:27 INFO - PID 1654 | Values: 68.0 67.0 67.0 66.0 69.0 66.0 68.0 68.0 67.0 66.0 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:78.90 Median:90.00 stddev:14.43 (16.0%) stddev-sans-first:13.59 16:36:27 INFO - PID 1654 | Values: 60.0 77.0 93.0 68.0 57.0 69.0 91.0 93.0 89.0 92.0 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:95.10 Median:88.50 stddev:14.15 (16.0%) stddev-sans-first:14.88 16:36:27 INFO - PID 1654 | Values: 90.0 88.0 89.0 85.0 90.0 95.0 76.0 102.0 125.0 111.0 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:162.70 Median:158.50 stddev:21.87 (13.8%) stddev-sans-first:22.45 16:36:27 INFO - PID 1654 | Values: 147.0 146.0 148.0 150.0 152.0 164.0 153.0 172.0 216.0 179.0 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.70 Median:198.00 stddev:14.35 (7.2%) stddev-sans-first:15.15 16:36:27 INFO - PID 1654 | Values: 198.0 194.0 241.0 197.0 195.0 194.0 201.0 198.0 206.0 193.0 16:36:27 INFO - PID 1654 | 16:36:27 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.80 Median:73.00 stddev:13.89 (19.0%) stddev-sans-first:14.37 16:36:27 INFO - PID 1654 | Values: 73.0 73.0 73.0 72.0 73.0 70.0 83.0 90.0 105.0 106.0 16:36:27 INFO - PID 1654 | -------- Summary: end -------- 16:36:27 INFO - PID 1654 | 16:36:28 INFO - TEST-INFO | 1654: exit 0 16:36:28 INFO - TEST-OK | kraken | took 62769ms 16:36:28 INFO - SUITE-END | took 1180s 16:36:28 INFO - Completed test suite (00:19:41) 16:36:28 INFO - javascript benchmark 16:36:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7760.892494088956, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3580.803055831929, 3586.605473397927, 3615.8192090395473, 3620.2397496525728, 3629.364861149298, 5750.3962083981205, 5787.305523337205, 5859.941400585987, 6265.0, 6274.717637706302, 6121.910283036244, 6243.8126856194385, 6261.655608941506, 6291.842703932392, 6304.59020163689, 4296.849610263642, 4730.455997560291, 5063.797448102072, 5602.0, 5747.683877386742, 4727.409073865767, 5736.569757268201, 5760.596758226926, 5785.842831433709, 5831.854203644901, 5661.207430959669, 5834.6790926499025, 5837.182794408786, 5865.618734782225, 5874.559408044413, 5707.62900411476, 5733.684647344395, 5775.191473193756, 5779.162021506858, 5865.970670146665, 5612.382637909826, 5658.91511627326, 5671.631343962629, 5694.487496125327, 5703.857403564903, 5213.852952350515, 6142.529470849001, 6179.474744646711, 6243.500519958392, 6335.809925702236, 6623.0, 6708.496862735315, 6710.463162946952, 6732.46140308779, 6743.123393958803, 6822.4200942919915, 6835.624040677761, 6836.282190370039, 6845.075914751495, 6846.863062738767, 5004.474530174352, 6244.875102497925, 6358.904616430757, 6390.904136437957, 6394.776182833579, 6726.6636668167375, 6892.551984121016, 6935.826604334932, 6936.4797640177185, 6941.514094013472, 6386.393292637192, 6428.935710642927, 6608.86782264352, 6653.935370340819, 6659.067730517731, 6556.8360790980605, 6745.12313399255, 6766.966165169143, 6831.2144103427745, 6859.794206173823, 6753.628550429676, 6781.491388145909, 6783.694733736994, 6784.7964561063245, 6794.830129246809, 6475.061116138134, 6557.573757705733, 6589.0, 6595.8021259362295, 6640.203175618956, 5340.385855626652, 5382.67703937765, 5396.028714831368, 5416.9729151353995, 5438.347398312228, 4778.04439112179, 4790.808367665254, 4805.567498925044, 4807.735574543363, 4824.589909857631, 5886.264216972879, 6316.0, 6372.840678983062, 6383.521235907326, 6384.0, 6667.166604174478, 6799.4900382471515, 6807.591544507345, 6815.522913396114, 6874.2094659113845, 6321.431071203522, 6354.841128971812, 6386.0, 6389.904151437732, 6396.392342727433, 4970.776315065831, 5795.652260864362, 6024.367441418588, 6031.4270144336215, 6046.123312119806, 6849.691763870538, 6877.965610171917, 6944.2708515607155, 6978.895316570154, 7007.6846541904715, 6027.2164618601055, 6270.905936411049, 6893.482988775962, 6898.827529311808, 6916.170059592881, 6964.930350696631, 7003.719851206099, 7004.089468369283, 7060.646967651494, 7067.363937245673, 6278.497720182283, 6341.556091073765, 6419.518536109717, 6573.0, 6715.56348837324, 6819.113515242987, 6862.451003919575, 6862.965685171542, 6870.828229294308, 6883.483738719716, 6281.685915704105, 6443.0, 6542.149520562487, 6603.075569420189, 6605.867882642224, 6509.251436084911, 6585.111010013587, 6586.604803711793, 6589.538732288694, 6609.20689517261, 5331.5201631853315, 5462.481064298885, 6262.778758142119, 6327.778527751507, 6332.366763323631, 6527.412532872065, 6608.50436217276, 6613.140291762233, 6640.236372817188, 6641.601503909781, 6385.744570217139, 6399.168108145911, 6417.0, 6421.967890160519, 6425.45383642377], "value": 6141.023137739991, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.855121148953, 798.3692882622728, 1229.5942339028122, 1264.968375790605, 1290.7353992431547, 38202.04494887629, 38523.80738096306, 38634.80682596587, 38676.033099172535, 38676.226475470474, 32963.50554741681, 37635.43546846799, 37916.43125353122, 38007.23985520288, 38012.80993595028, 7919.802004949865, 8583.699570515033, 8815.911840881574, 8822.11778822119, 8845.115488451167, 8678.956605216965, 8759.080296568864, 8780.034196238408, 8788.780280492974, 8789.648414063462, 8735.0, 8755.993060798026, 8807.427517211392, 8809.515476648781, 8820.955895220515, 8732.519711415902, 8776.561171941377, 8777.95611021944, 8782.165694259034, 8788.956055219747, 8493.83012339756, 8569.143085691443, 8569.657213711444, 8573.056963733985, 8590.914090859074, 38168.236635267414, 38188.80905595482, 38200.044998874975, 38260.61739382598, 38365.808170959244, 3055.816651000947, 3075.123589776925, 3081.0910781319562, 3096.1021303821863, 3104.161876293411, 27362.863185684142, 27650.308742281402, 27744.861275693693, 27958.021469248477, 28555.428891422263, 2740.177946616027, 2780.555111182201, 2798.6501687289087, 2839.162447078117, 2848.401835614503, 238992.80503597544, 310160.44919775485, 313274.0, 314034.0, 314644.0, 38829.41755873664, 40064.198716025516, 46937.530624694, 48365.999999999294, 48394.75802621035, 50252.24621630678, 50326.24510632343, 50398.0, 50406.243906341435, 50429.49570504321, 14341.99606027589, 14387.568372948828, 14394.42422303117, 14434.783478247835, 14435.566932992027, 11414.942925285486, 11593.536258549564, 11614.419279036014, 11688.94155529217, 11702.005329547084, 13515.459381624822, 13516.918646508257, 13548.661283467993, 13551.254680992442, 13614.863851361557, 4700.318453824177, 5483.369412517532, 5977.163197152402, 5986.790462333797, 6026.969865150646, 974.741693451236, 989.4607438945792, 997.1374760831828, 1008.1884083313004, 1031.4481752262504, 20194.0, 23834.88082559611, 23850.403739906295, 24183.516329673308, 24265.908034138505, 921.949292788903, 1056.8361903904906, 1100.9009189173014, 1131.4964840645835, 1147.9196456247982, 11688.649340519798, 11761.82381761834, 12237.816432753516, 12257.938710306391, 12318.75362492745, 458605.0, 458888.0, 459337.0, 459846.0, 459883.0, 53271.0, 53639.73180134074, 53830.461695383325, 53967.730161349726, 53972.92054158895, 1464.6704491489543, 1515.4620109860841, 1522.6117340078208, 1529.2048134969752, 1535.0712818744498, 18706.719399208752, 23076.884615576815, 23263.41841453977, 23337.299881003597, 23395.0, 36739.63260367362, 36790.0, 36815.63184368122, 36819.0, 36864.44703329506, 379122.0, 380385.0, 382402.0, 382649.0, 383550.0, 32670.83664581662, 32711.34577308393, 34155.82922085374, 35296.29407411889, 35303.823480882435, 42681.0, 43183.0, 44218.33672494851, 44472.77763611291, 44603.0, 29009.419811603228, 29629.0, 30674.0, 30675.0, 30737.385252295277, 29671.554926675686, 30508.237294067825, 30636.080917571617, 30912.536311955788, 30970.69029309678, 31683.04950851478, 34960.475592866635, 35794.463083053255, 36093.458598120524, 36133.63866361303, 29358.82564697388, 30204.84897575498, 30826.53760193554, 30875.536866947463, 30957.690423095482, 19347.903260483607, 24368.390790230387, 24427.51144977055, 24664.0, 24698.13556525513], "value": 17612.982705311693, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.99166016679646, 417.6470882104621, 424.77274658057945, 436.9563043695631, 442.08929605013674, 19798.109085091168, 21368.786312136875, 22034.669479957814, 22288.0, 22683.773162268415, 17870.64258714825, 21971.560568788616, 21974.0, 22193.77806221937, 22227.888860555715, 6152.323244443108, 6268.902941985157, 6343.175387199681, 6359.427651511363, 6452.516061295421, 6084.969575152118, 6174.6912654367325, 6259.561830671855, 6263.812085637438, 6273.247210334766, 5992.43071908168, 6166.38336166382, 6180.567360284782, 6196.132541444202, 6221.0979407985815, 5270.209468579725, 6241.65670888101, 6245.906311405332, 6251.187345645082, 6251.624902505841, 5245.973770131163, 5449.337951924661, 5516.613837031409, 5549.361823390281, 5566.916496252559, 27956.720432795613, 28142.437151257065, 28183.0, 28450.00424985136, 28558.428831423254, 1685.3090233004532, 1984.5038740314922, 1991.442396129086, 2015.4256037029377, 2092.3513710749717, 16328.428505002268, 16427.91786041074, 16699.582510437216, 17036.659266814593, 17088.936770934022, 1748.8426041656185, 1760.7270873014704, 1762.8765986380959, 1768.9823101768945, 1772.503698964292, 156083.0, 168057.0, 168537.0, 170005.09964302502, 171775.0, 12664.430100645493, 12845.743085138245, 12887.226766394046, 12902.483900644056, 13158.276294803878, 16074.598135046483, 16354.672906541802, 16366.345346186019, 16371.83628163727, 16441.0, 8604.0, 8739.082396348418, 9070.730097786316, 9173.495457749754, 9209.35534512591, 7088.92911070893, 7128.893066604005, 7588.848223035508, 8850.955745221361, 8862.42394244372, 7854.293113619801, 7928.841423171504, 7973.641186146638, 7982.042154941328, 7988.600569971478, 2184.322859913432, 2186.3440967709626, 2188.6716992450943, 2188.9890550547148, 2359.740428552858, 932.8414169591185, 942.5193151492788, 945.5272363818091, 979.6179489998906, 980.2011360740973, 15966.0, 19541.804581954, 21218.78781212199, 21311.09081198186, 21441.67837482439, 976.0873583199731, 981.1218959031627, 1010.8938561451089, 1011.7420057885192, 1014.7666036811574, 10494.160467162608, 10964.616238431616, 10992.395418251914, 11011.889881101246, 11043.83434248487, 721075.0, 721780.0, 724187.0, 728950.0, 739321.0, 155731.0, 157197.2140139292, 159460.0, 160675.19662401613, 164253.0, 1221.969450763738, 1247.9188852724544, 1254.172246317426, 1274.286399616218, 1284.3642397013539, 17590.648187036444, 21656.566868662223, 22331.330060098222, 22477.21329753451, 22610.321690349316, 60954.41866045437, 63060.68469657622, 63785.0, 64190.67904660447, 64723.0, 63275.0, 70030.0, 89226.0, 89356.5532172335, 91674.9882008082, 8352.6241319142, 8970.058143895058, 9022.729318120468, 9060.818783624423, 9272.72181834546, 14294.070885392417, 14508.709825803637, 14528.295284702755, 14841.109533428029, 14863.479778207711, 10460.21548383859, 11670.88329116698, 11814.113941454598, 12057.51769929193, 12141.878581214074, 11369.0, 11425.485853136463, 11556.826647600124, 11701.824472633087, 11860.822087668519, 11976.341301228516, 11979.221350612183, 12255.203411778206, 12298.877011229773, 12459.626211213677, 7568.205338439385, 8587.914120858712, 8663.523506207219, 8946.86579701318, 9083.227925626132, 11698.532058717556, 11850.525978960744, 12096.637100886988, 12105.69735756613, 12129.878701212874], "value": 10600.554500016988, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1162.9476673549689, 1230.907681923856, 1373.4437552791123, 1389.1526169036892, 1400.9229492377922, 10705.14358851292, 10863.945680271607, 11195.048420884224, 11213.990740833313, 11221.943890280558, 11006.679198496171, 11096.778064438706, 11163.106951443886, 11167.83248251277, 11182.273152245098, 2246.696695946043, 2322.8722420266877, 2358.870262135582, 2362.1614326913955, 2363.078399424226, 2387.7134743830766, 2473.7155227148837, 2476.0838489758817, 2478.7397323280975, 2488.5271798358344, 2365.2549446924163, 2381.0, 2395.688560487143, 2474.418511649761, 2486.0677246032737, 2376.4890548531976, 2431.501542183848, 2444.0590372706547, 2448.6327050942323, 2470.419451428913, 1951.5706544560173, 2441.2676197140786, 2443.3280847766828, 2444.1934161726585, 2462.2859370782453, 11053.502592383362, 11187.552497900155, 11199.384033878134, 11201.551937922473, 11251.324920504796, 6238.875222495525, 6475.643839588868, 6484.967575162141, 6498.772542960974, 6501.414872661436, 3685.4656074869263, 3702.4631428442726, 4005.5794141615293, 4092.4884389451317, 4143.834246630131, 1158.6466127831009, 1184.2716729211472, 1189.302256088454, 1205.4515195585986, 1222.7432239229772, 13818.723625527533, 14285.0000499964, 15212.847871521253, 15276.541703748906, 15343.846561534354, 5347.893042139135, 5689.630174038674, 5772.740226689809, 5955.047192449188, 5960.314563825129, 12776.488940442277, 12817.615471535868, 12846.0, 12886.80669789954, 12905.032122590843, 6437.259715132726, 6493.545451818327, 6502.317256688074, 6506.869862602816, 6510.44661203803, 5832.708364581754, 5860.560457965669, 5920.674362910081, 5934.258217722785, 6008.24896887889, 3775.414810704345, 4430.667699922518, 4506.459224893034, 4507.6619253555455, 4517.638588912879, 1041.7656027393775, 1376.050525137652, 1394.9721005579831, 1416.9858301416855, 1454.810874586297, 734.8456824067006, 748.7716246544747, 764.9349805266614, 767.4704453926885, 805.7743831727125, 4755.595774359218, 5516.696581687966, 6197.566170368121, 6505.8698826024165, 6542.443892269211, 569.1661715586686, 570.922925405065, 586.1939832729996, 588.9852753681107, 593.9970300148472, 3409.9659003410143, 3668.174660701321, 3706.7590606610484, 4154.958450415517, 4181.247375472444, 11041.83437248442, 13143.474261029452, 13666.18002919828, 13669.906407487577, 13696.93151534236, 10989.0, 13236.536721214712, 13554.254516001518, 13585.932070339584, 13604.795928061087, 10047.899521004843, 10133.189344852393, 10255.33340332876, 10296.40405737127, 10317.742056448498, 4700.647451441224, 5443.4284400138995, 6512.5766825156215, 6740.258571557027, 6788.506528563708, 12048.518059277882, 12052.276863388224, 12123.090768192596, 12188.085893558196, 12211.02311815035, 12300.138990270952, 12367.93816030914, 12567.0, 12595.118341715992, 12768.297743624189, 8770.298376129767, 8915.37592368528, 9011.143941325563, 9013.90986090157, 9017.819643607223, 10280.845787313347, 10309.793804124025, 10371.792564148524, 10379.896201037893, 10395.636152734618, 8363.163683631588, 8567.700130495403, 8605.95697021511, 8615.870761938451, 8763.474191548528, 7706.845863082819, 7715.691372345268, 7796.9220307796195, 7812.804679883048, 7943.086545047394, 7700.730474433368, 7705.30652241301, 7709.575973321521, 7795.41534384912, 7812.960935195288, 5614.803481878114, 5760.94239057604, 5770.5095066919785, 5783.334916484658, 5935.465808077293, 5810.883782324246, 5905.881882362242, 5960.0761881908375, 5964.731587078679, 5989.730462129128], "value": 5089.153028592043, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.0134762695052, 366.543653151826, 398.6611380326723, 409.9733517321374, 410.65710132039754, 5116.288367165399, 5959.6424214547105, 6260.968695156529, 6382.26603940547, 6398.328175541571, 5075.720835354064, 5370.757101889502, 6150.569460137792, 6222.1644614838615, 6294.213223347082, 3856.421536769479, 4023.919521609566, 4699.882502937437, 4908.705477671333, 4917.311576379309, 3802.030482227028, 4581.053052452694, 4891.021795640886, 4905.901881962359, 4908.239222920435, 3925.3719404895223, 4761.119192949298, 4869.342638743778, 4882.6093912487095, 4897.142999975008, 3951.0, 4689.531046895318, 4806.591439727627, 4883.218685010399, 4898.4486247470295, 4287.421198138242, 4290.656747460227, 4295.8066886989845, 4303.72025818324, 4355.5644435556505, 6100.816975490742, 6130.17242672238, 6132.264128304588, 6137.754489820401, 6169.7532098716, 4649.744264065475, 5468.0, 5614.466625670555, 5631.690257035862, 5645.769920881229, 6421.779861826285, 6427.614343139427, 6653.800385988428, 6704.430123439513, 6712.295209003033, 4063.553009168989, 4644.442666879962, 4791.305260737174, 4793.952060479385, 4808.447028591721, 6146.692665366714, 6295.276043254993, 6825.317468253135, 6840.589564626139, 7064.470164737717, 4812.446568644651, 4857.732824694675, 4861.611071114302, 4897.4857639948, 4904.386951631046, 4548.522405147478, 4659.487456379796, 4724.645651576146, 5005.074061298671, 5008.949910500921, 5482.91775623366, 5543.972280138573, 5570.0, 5595.888082238333, 5648.265725455664, 5423.810166644227, 6512.641804700787, 6595.373439523238, 6718.160229971254, 6756.290589488131, 1434.8206474190727, 1449.913005219669, 1719.415398764426, 1721.9938771331508, 1726.4734256051775, 988.663854289545, 1004.5680357446369, 1190.934498602585, 1192.201225179132, 1217.6468824040928, 5283.6037297202865, 6301.968490157612, 6323.841903952438, 6351.618902865843, 6369.681515924185, 6709.396154346132, 6811.659417029228, 6944.270851560514, 6973.616451095137, 6997.790066298019, 5392.91910621341, 6237.033259844731, 6257.155284036597, 6261.436470717657, 6313.747450101944, 3453.723702103826, 3492.4237500812587, 3583.390823560001, 3609.4224924012556, 3622.8913132606062, 6648.301928297556, 7066.364027237573, 7100.822479438056, 7145.17830449505, 7178.5692858428665, 6534.411902928664, 7021.859562808715, 7127.714891404389, 7153.320434558708, 7161.854103343544, 5688.407245971135, 6783.660816959133, 7037.155541334971, 7146.535475194096, 7180.7845764627145, 5766.625169363978, 6586.37429444202, 6860.862782744217, 6955.791326260018, 6965.5124141309625, 6447.0, 6551.344865513411, 6797.0, 6865.794026179223, 6867.519273650996, 6988.161420629557, 7045.929540704527, 7191.460640451883, 7198.820029499305, 7252.347288744038, 3718.4980027695915, 3779.905502362463, 4333.891652708708, 4392.770024393175, 4532.841350552715, 5676.460736230051, 6643.368879956396, 6856.177258728984, 6995.825104372431, 7018.894716579153, 4930.704157750546, 6231.629041610839, 6440.420362167428, 6781.355771201728, 6797.966010169917, 5609.74756135983, 6541.901871471837, 6655.101561289164, 6675.499337549801, 6752.628605426748, 6580.440662543546, 6607.372299631527, 6613.5370524062855, 6629.436497897733, 6653.600783952978, 6459.4832413405875, 6666.0, 6784.846576081979, 6789.219239787488, 6793.354631310018, 5403.946230485018, 6151.9384806151365, 6170.5372097092, 6442.549021568445, 6551.934480655133], "value": 4908.403267031113, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.8021706101565, 722.1695050691706, 725.4232884856539, 730.2369024369532, 737.498501019307, 15486.92256538717, 16246.75629865553, 16396.83603163968, 16470.176491175454, 16470.258838352274, 14893.10641361519, 14913.850861491383, 15071.020383675053, 15074.17092059939, 15122.470713525028, 927.2906226736552, 957.3585697582602, 969.6412327438858, 969.8448248280276, 987.8320685483449, 998.0518507417946, 1006.5319626373735, 1008.5108722269694, 1008.6721815409984, 1015.7663737340416, 1045.6549338718244, 1046.7278507588044, 1047.0524175621038, 1047.8847326794046, 1050.086424810416, 1033.41095575522, 1035.471909326249, 1042.4266653340633, 1044.3107549017611, 1044.958201671932, 774.0401901641924, 778.4278555261878, 780.0911937592698, 780.5355813291139, 780.6292011294646, 16385.426510072208, 16622.002679839126, 16630.75053874193, 16866.156692165467, 17111.572210694707, 14536.127832330094, 14653.506658652785, 14653.92673036628, 14708.55874323772, 14766.409343626243, 11713.297202167812, 12010.459529321202, 12018.759624807455, 12085.335306558136, 12105.0, 2991.2073300575366, 3042.710942460485, 3048.3903219356216, 3072.1244445332973, 3141.4816555268353, 106596.0, 109767.0, 109903.45048274708, 110992.78014439746, 112046.0, 10735.57057717698, 10961.945190273998, 11513.30920144794, 11621.883781162249, 11858.644240672793, 24674.753252467606, 24702.752972470404, 24748.133815316378, 24794.5041099177, 24813.5037299253, 7673.731419400406, 8010.439269251213, 8036.557989310644, 8108.675652973933, 8279.54462504556, 4202.810873510699, 4204.0, 4216.072464057902, 4259.403683484315, 4344.717593356421, 4080.265552200573, 4086.468759061303, 4155.563665815046, 4183.602557757008, 4187.0, 703.3985942019602, 842.9620667069996, 861.440063958432, 871.7471933139318, 872.9563521823883, 544.6160456877892, 550.6783719074174, 567.8884873515716, 591.9378465261171, 600.8738164985402, 9994.500274986221, 10019.616119981158, 10224.948875255575, 10521.368717876952, 10574.101201397814, 453.5441880909665, 456.07082042957813, 458.93574899513425, 462.5143599220805, 480.9903801923942, 4509.932351014738, 5023.2214006829, 5367.731613419313, 5488.91766623501, 5523.530499907472, 100464.49767751114, 103840.0, 104377.0, 105726.0, 106786.46606766917, 88042.55978720193, 92376.07623923675, 93517.0, 93784.53107734417, 95463.36293096536, 80949.19050809534, 82698.0, 83043.0, 83068.58465707753, 84166.47500575116, 10826.40454774995, 10834.0, 11051.613193538187, 11140.164487663296, 11158.665240042812, 36079.27841443209, 36345.0, 36869.89390318295, 37393.0, 37654.058648534, 48843.75578122087, 49242.61360795766, 49604.75197624133, 49851.75074124606, 50218.744531387005, 11780.82328765052, 13239.40422681001, 13643.86356136426, 14128.43486260538, 14215.928920355333, 14148.0, 14326.713465730836, 14430.927845360706, 14531.27343632793, 15012.249387530797, 13758.098174410065, 13785.724285514434, 13816.171029738249, 13835.308234588432, 14468.927655361656, 11906.40467976615, 13509.392077356346, 13524.93237533806, 13677.110987785762, 13713.862861371259, 13278.933605331911, 13391.86608133945, 13423.731525369632, 13464.663383415493, 13927.303634818421, 10047.949760251153, 10049.196064314983, 10133.341332813343, 10137.391756494633, 10782.784344313226, 10274.33216840903, 10893.773989332347, 11277.361185058378, 11408.315501069963, 11415.429228538706], "value": 7628.702941296941, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1648.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 123.0, 129.0, 124.0, 122.0, 123.0, 124.0, 124.0, 109.0, 178.0], "value": 126.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 104.0, 98.0, 191.0, 149.0, 156.0, 122.0, 114.0, 155.0, 156.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [222.0, 224.0, 262.0, 234.0, 224.0, 211.0, 217.0, 215.0, 208.0, 213.0], "value": 223.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 60.0, 59.0, 88.0, 65.0, 86.0, 88.0, 63.0, 74.0, 62.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 88.0, 74.0, 70.0, 75.0, 87.0, 73.0, 73.0, 86.0], "value": 78.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 93.0, 99.0, 117.0, 94.0, 92.0, 92.0, 93.0, 92.0], "value": 95.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 101.0, 102.0, 111.0, 101.0, 101.0, 103.0, 115.0, 163.0, 160.0], "value": 115.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 141.0, 103.0, 105.0, 98.0, 103.0, 102.0, 107.0, 103.0, 102.0], "value": 106.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 66.0, 69.0, 66.0, 68.0, 68.0, 67.0, 66.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 77.0, 93.0, 68.0, 57.0, 69.0, 91.0, 93.0, 89.0, 92.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 89.0, 85.0, 90.0, 95.0, 76.0, 102.0, 125.0, 111.0], "value": 95.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 148.0, 150.0, 152.0, 164.0, 153.0, 172.0, 216.0, 179.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 194.0, 241.0, 197.0, 195.0, 194.0, 201.0, 198.0, 206.0, 193.0], "value": 201.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 72.0, 73.0, 70.0, 83.0, 90.0, 105.0, 106.0], "value": 81.8, "unit": "score"}]}]} 16:36:29 INFO - Return code: 0 16:36:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:36:29 ERROR - # TBPL SUCCESS # 16:36:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:36:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:36:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:36:29 INFO - 2017-03-30 16:36:29.159 screenresolution[1659:10610] starting screenresolution argv=screenresolution get 16:36:29 INFO - 2017-03-30 16:36:29.164 screenresolution[1659:10610] Display 0: 1600x1200x32@60 16:36:29 INFO - 2017-03-30 16:36:29.170 screenresolution[1660:10613] starting screenresolution argv=screenresolution list 16:36:29 INFO - Available Modes on Display 0 16:36:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:36:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:36:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:36:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:36:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:36:29 INFO - Intel Iris: 16:36:29 INFO - Chipset Model: Intel Iris 16:36:29 INFO - Type: GPU 16:36:29 INFO - Bus: Built-In 16:36:29 INFO - VRAM (Dynamic, Max): 1536 MB 16:36:29 INFO - Vendor: Intel (0x8086) 16:36:29 INFO - Device ID: 0x0a2e 16:36:29 INFO - Revision ID: 0x0009 16:36:29 INFO - Displays: 16:36:29 INFO - Display: 16:36:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:36:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:36:29 INFO - Display Serial Number: 1600x1200 60 16:36:29 INFO - Main Display: Yes 16:36:29 INFO - Mirror: Off 16:36:29 INFO - Online: Yes 16:36:29 INFO - Rotation: Supported 16:36:29 INFO - Return code: 0 16:36:29 INFO - Running post-action listener: _resource_record_post_action 16:36:29 INFO - [mozharness: 2017-03-30 23:36:29.407963Z] Finished run-tests step (success) 16:36:29 INFO - Running post-run listener: _resource_record_post_run 16:36:29 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 12883456; Write bytes: 519045120; Read time: 134; Write time: 1971 16:36:29 INFO - TinderboxPrint: CPU usage
54.3% 16:36:29 INFO - TinderboxPrint: I/O read bytes / time
12,883,456 / 134 16:36:29 INFO - TinderboxPrint: I/O write bytes / time
519,045,120 / 1,971 16:36:29 INFO - TinderboxPrint: CPU idle
2,196.6 (45.7%) 16:36:29 INFO - TinderboxPrint: CPU system
969.2 (20.2%) 16:36:29 INFO - TinderboxPrint: CPU user
1,643.9 (34.2%) 16:36:29 INFO - TinderboxPrint: Swap in / out
251,604,992 / 0 16:36:29 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205158912; Write bytes: 202682368; Read time: 15714; Write time: 378 16:36:29 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 11912704; Write bytes: 304662528; Read time: 119; Write time: 1484 16:36:29 INFO - Running post-run listener: _upload_blobber_files 16:36:29 INFO - Blob upload gear active. 16:36:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:36:29 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:36:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:36:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:36:30 INFO - (blobuploader) - INFO - Open directory for files ... 16:36:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:36:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:36:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:31 INFO - (blobuploader) - INFO - Done attempting. 16:36:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:36:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:36:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:32 INFO - (blobuploader) - INFO - Done attempting. 16:36:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:36:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:36:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:33 INFO - (blobuploader) - INFO - Done attempting. 16:36:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:36:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:36:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:35 INFO - (blobuploader) - INFO - Done attempting. 16:36:35 INFO - (blobuploader) - INFO - Iteration through files over. 16:36:35 INFO - Return code: 0 16:36:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:36:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:36:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e5e6b891c81d42070646bc3428ddd5aa6ca5a3209441e3a742091640a40a00a70af568cc937ec5f1351f2dd0e786c60bc724501cb9ddd6fdfe415321748a4705", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6693dc5e4a7b833fe10e0cfded9d895eee10cfe3e21e1d54b43d9848db5d5995537dbfbbdb8170abfc8ed9c8ef6f15fe59b7f40852cbd49238fb9e6736f18911", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fa0a885d3c08afce969c44e6906ee35c5ece8fe77f30bef1b51a795cda7db487d28853d1e1649ffba558ef7c3c9fbe5062210a5bf8790917db45636b8e882004", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7bb8f74e42e06a6f67e0c36bf1dc1f13ff6f3ed36b0ce6a1085e6806376e7611a09f00bec644b4d2034b687dc3e34ebb67115aa8024c73c7fe184f46a236c496"} 16:36:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:36:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:36:35 INFO - Contents: 16:36:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e5e6b891c81d42070646bc3428ddd5aa6ca5a3209441e3a742091640a40a00a70af568cc937ec5f1351f2dd0e786c60bc724501cb9ddd6fdfe415321748a4705", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6693dc5e4a7b833fe10e0cfded9d895eee10cfe3e21e1d54b43d9848db5d5995537dbfbbdb8170abfc8ed9c8ef6f15fe59b7f40852cbd49238fb9e6736f18911", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fa0a885d3c08afce969c44e6906ee35c5ece8fe77f30bef1b51a795cda7db487d28853d1e1649ffba558ef7c3c9fbe5062210a5bf8790917db45636b8e882004", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7bb8f74e42e06a6f67e0c36bf1dc1f13ff6f3ed36b0ce6a1085e6806376e7611a09f00bec644b4d2034b687dc3e34ebb67115aa8024c73c7fe184f46a236c496"} 16:36:35 INFO - Running post-run listener: copy_logs_to_upload_dir 16:36:35 INFO - Copying logs to upload dir... 16:36:35 INFO - mkdir: /builds/slave/test/build/upload/logs 16:36:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.005855 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-30 16:36:35.788386) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 16:36:35.791617) ========= 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.b1smtYEvv4/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.3XtMxSUFOw/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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/e5e6b891c81d42070646bc3428ddd5aa6ca5a3209441e3a742091640a40a00a70af568cc937ec5f1351f2dd0e786c60bc724501cb9ddd6fdfe415321748a4705", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6693dc5e4a7b833fe10e0cfded9d895eee10cfe3e21e1d54b43d9848db5d5995537dbfbbdb8170abfc8ed9c8ef6f15fe59b7f40852cbd49238fb9e6736f18911", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fa0a885d3c08afce969c44e6906ee35c5ece8fe77f30bef1b51a795cda7db487d28853d1e1649ffba558ef7c3c9fbe5062210a5bf8790917db45636b8e882004", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7bb8f74e42e06a6f67e0c36bf1dc1f13ff6f3ed36b0ce6a1085e6806376e7611a09f00bec644b4d2034b687dc3e34ebb67115aa8024c73c7fe184f46a236c496"} build_url:https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011166 build_url: 'https://queue.taskcluster.net/v1/task/aW2h0iYFRSaeRP1edqFXzg/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/e5e6b891c81d42070646bc3428ddd5aa6ca5a3209441e3a742091640a40a00a70af568cc937ec5f1351f2dd0e786c60bc724501cb9ddd6fdfe415321748a4705", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6693dc5e4a7b833fe10e0cfded9d895eee10cfe3e21e1d54b43d9848db5d5995537dbfbbdb8170abfc8ed9c8ef6f15fe59b7f40852cbd49238fb9e6736f18911", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fa0a885d3c08afce969c44e6906ee35c5ece8fe77f30bef1b51a795cda7db487d28853d1e1649ffba558ef7c3c9fbe5062210a5bf8790917db45636b8e882004", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7bb8f74e42e06a6f67e0c36bf1dc1f13ff6f3ed36b0ce6a1085e6806376e7611a09f00bec644b4d2034b687dc3e34ebb67115aa8024c73c7fe184f46a236c496"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 16:36:35.825823) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 16:36:35.826199) ========= 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.b1smtYEvv4/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.3XtMxSUFOw/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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.005365 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 16:36:35.876296) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 16:36:35.876637) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 16:36:35.876981) ========= ========= Total master_lag: 0.19 =========