builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0029 starttime: 1494522786.47 results: success (0) revision: 0259418f19ab804b126730ef1d5d03006d6aad52 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 10:13:06.468018) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 10:13:06.468677) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 10:13:06.469122) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 10:13:06.496432) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 10:13:06.496995) ========= 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.3tJkwNBtBN/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.bBklUxqRKv/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-05-11 10:13:06-- 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% 15.3M=0.001s 2017-05-11 10:13:06 (15.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153191 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 10:13:06.674402) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 10:13:06.674753) ========= 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.3tJkwNBtBN/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.bBklUxqRKv/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.029614 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 10:13:06.725897) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 10:13:06.726253) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0259418f19ab804b126730ef1d5d03006d6aad52 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 0259418f19ab804b126730ef1d5d03006d6aad52 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3tJkwNBtBN/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.bBklUxqRKv/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-05-11 10:13:06,823 truncating revision to first 12 chars 2017-05-11 10:13:06,823 Setting DEBUG logging. 2017-05-11 10:13:06,823 attempt 1/10 2017-05-11 10:13:06,823 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0259418f19ab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 10:13:07,625 unpacking tar archive at: mozilla-central-0259418f19ab/testing/mozharness/ program finished with exit code 0 elapsedTime=1.082179 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 10:13:07.830205) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 10:13:07.830540) ========= script_repo_revision: 0259418f19ab804b126730ef1d5d03006d6aad52 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 10:13:07.831004) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 10:13:07.831347) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 10:13:07.851078) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 23 secs) (at 2017-05-11 10:13:07.851544) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3tJkwNBtBN/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.bBklUxqRKv/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:13:08 INFO - MultiFileLogger online at 20170511 10:13:08 in /builds/slave/test 10:13:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:13:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:13:08 INFO - {'append_to_log': False, 10:13:08 INFO - 'base_work_dir': '/builds/slave/test', 10:13:08 INFO - 'blob_upload_branch': 'Firefox', 10:13:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:13:08 INFO - 'branch': 'Firefox', 10:13:08 INFO - 'buildbot_json_path': 'buildprops.json', 10:13:08 INFO - 'config_files': ('talos/mac_config.py',), 10:13:08 INFO - 'default_actions': ('clobber', 10:13:08 INFO - 'read-buildbot-config', 10:13:08 INFO - 'download-and-extract', 10:13:08 INFO - 'populate-webroot', 10:13:08 INFO - 'create-virtualenv', 10:13:08 INFO - 'install', 10:13:08 INFO - 'run-tests'), 10:13:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:13:08 INFO - 'download_minidump_stackwalk': True, 10:13:08 INFO - 'download_symbols': 'ondemand', 10:13:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:13:08 INFO - 'tooltool.py': '/tools/tooltool.py', 10:13:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:13:08 INFO - '/tools/misc-python/virtualenv.py')}, 10:13:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:13:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:13:08 INFO - 'gecko_profile': False, 10:13:08 INFO - 'gecko_profile_interval': 0, 10:13:08 INFO - 'installer_path': 'installer.exe', 10:13:08 INFO - 'log_level': 'info', 10:13:08 INFO - 'log_name': 'talos', 10:13:08 INFO - 'log_to_console': True, 10:13:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:13:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:13:08 INFO - 'opt_config_files': (), 10:13:08 INFO - 'pip_index': False, 10:13:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:13:08 INFO - 'cmd': ('bash', 10:13:08 INFO - '-c', 10:13:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:13:08 INFO - 'enabled': True, 10:13:08 INFO - 'halt_on_failure': False, 10:13:08 INFO - 'name': 'check_screen_resolution'},), 10:13:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:13:08 INFO - 'cmd': ('bash', 10:13:08 INFO - '-c', 10:13:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:13:08 INFO - 'enabled': True, 10:13:08 INFO - 'halt_on_failure': False, 10:13:08 INFO - 'name': 'check_screen_resolution'},), 10:13:08 INFO - 'run_cmd_checks_enabled': True, 10:13:08 INFO - 'suite': 'dromaeojs-e10s', 10:13:08 INFO - 'system_bits': '32', 10:13:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:13:08 INFO - 'title': 't-yosemite-r7-0029', 10:13:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:13:08 INFO - 'use_talos_json': True, 10:13:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:13:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:13:08 INFO - 'work_dir': 'build'} 10:13:08 INFO - [mozharness: 2017-05-11 17:13:08.062851Z] Running clobber step. 10:13:08 INFO - Running pre-action listener: _resource_record_pre_action 10:13:08 INFO - Running main action method: clobber 10:13:08 INFO - rmtree: /builds/slave/test/build 10:13:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:13:10 INFO - Running post-action listener: _resource_record_post_action 10:13:10 INFO - [mozharness: 2017-05-11 17:13:10.570833Z] Finished clobber step (success) 10:13:10 INFO - [mozharness: 2017-05-11 17:13:10.570978Z] Running read-buildbot-config step. 10:13:10 INFO - Running pre-action listener: _resource_record_pre_action 10:13:10 INFO - Running main action method: read_buildbot_config 10:13:10 INFO - Using buildbot properties: 10:13:10 INFO - { 10:13:10 INFO - "project": "", 10:13:10 INFO - "product": "firefox", 10:13:10 INFO - "who": "archaeopteryx@coole-files.de", 10:13:10 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:13:10 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:13:10 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:13:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:13:10 INFO - "stage_platform": "macosx64", 10:13:10 INFO - "basedir": "/builds/slave/test", 10:13:10 INFO - "buildnumber": 57, 10:13:10 INFO - "platform": "yosemite_r7", 10:13:10 INFO - "parent_task_id": "HdCbCH7aTPCUq8Uq-alcLw", 10:13:10 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 10:13:10 INFO - "slavebuilddir": "test", 10:13:10 INFO - "taskId": "BjUTgeD0SVO_bBRjd7uB1A", 10:13:10 INFO - "branch": "mozilla-central", 10:13:10 INFO - "script_repo_revision": "production", 10:13:10 INFO - "revision": "0259418f19ab804b126730ef1d5d03006d6aad52", 10:13:10 INFO - "slavename": "t-yosemite-r7-0029", 10:13:10 INFO - "repo_path": "mozilla-central" 10:13:10 INFO - } 10:13:10 INFO - Finding installer, test and symbols from parent task. 10:13:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BjUTgeD0SVO_bBRjd7uB1A'}, attempt #1 10:13:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BjUTgeD0SVO_bBRjd7uB1A'}, attempt #1 10:13:11 INFO - Task dependencies: GduZFzSIQBSFamYg1lxMnQ 10:13:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw'}, attempt #1 10:13:12 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:13:12 INFO - Set test_url: https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/test_packages.json 10:13:12 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/target.crashreporter-symbols.zip 10:13:12 INFO - Running post-action listener: _resource_record_post_action 10:13:12 INFO - [mozharness: 2017-05-11 17:13:12.287592Z] Finished read-buildbot-config step (success) 10:13:12 INFO - [mozharness: 2017-05-11 17:13:12.287746Z] Running download-and-extract step. 10:13:12 INFO - Running pre-action listener: _resource_record_pre_action 10:13:12 INFO - Running main action method: download_and_extract 10:13:12 INFO - mkdir: /builds/slave/test/build/tests 10:13: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')]} 10:13:12 INFO - trying https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:13:12 INFO - Downloading https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/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 10:13:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/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 10:13:13 INFO - Downloaded 1325 bytes. 10:13:13 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:13:13 INFO - Using the following test package requirements: 10:13:13 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:13:13 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:13:13 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:13:13 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:13:13 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:13:13 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:13:13 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:13:13 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:13:13 INFO - u'jsshell-mac.zip'], 10:13:13 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:13:13 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:13:13 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:13:13 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:13:13 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:13:13 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:13:13 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:13:13 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:13:13 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:13:13 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:13:13 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:13:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:13:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:13:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:13:13 INFO - Fetch https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:13:14 INFO - Content-Length response header: 52707584 10:13:14 INFO - Bytes received: 52707584 10:13: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 10:13:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:13:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:13:31 INFO - Fetch https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:13:33 INFO - Content-Length response header: 52707584 10:13:33 INFO - Bytes received: 52707584 10:13:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:13:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:13:48 INFO - Fetch https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:13:49 INFO - Content-Length response header: 14409411 10:13:49 INFO - Bytes received: 14409411 10:13: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')]} 10:13:50 INFO - trying https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:13:50 INFO - Downloading https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:13:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/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 10:13:52 INFO - Downloaded 75540053 bytes. 10:13:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:13:52 INFO - mkdir: /builds/slave/test/properties 10:13:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:13:52 INFO - Writing to file /builds/slave/test/properties/build_url 10:13:52 INFO - Contents: 10:13:52 INFO - build_url:https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:13:52 INFO - Running post-action listener: _resource_record_post_action 10:13:52 INFO - Running post-action listener: set_extra_try_arguments 10:13:52 INFO - [mozharness: 2017-05-11 17:13:52.305630Z] Finished download-and-extract step (success) 10:13:52 INFO - [mozharness: 2017-05-11 17:13:52.305769Z] Running populate-webroot step. 10:13:52 INFO - Running pre-action listener: _resource_record_pre_action 10:13:52 INFO - Running main action method: populate_webroot 10:13:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:13:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:13:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:13:52 INFO - '--tppagecycles', 10:13:52 INFO - '7'], 10:13:52 INFO - 'tests': ['tsvgm']}}, 10:13:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:13:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:13:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:13:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:13:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:13:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:13:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:13:52 INFO - 'talos_options': ['--disable-e10s'], 10:13:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:13:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:13:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:13:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:13:52 INFO - 'talos_options': ['--disable-e10s'], 10:13:52 INFO - 'tests': ['damp', 'tps']}, 10:13:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:13:52 INFO - 'tests': ['damp', 'tps']}, 10:13:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:13:52 INFO - 'tests': ['dromaeo_dom']}, 10:13:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:13:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:13:52 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:13:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:13:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:13:52 INFO - 'tests': ['a11yr', 10:13:52 INFO - 'ts_paint', 10:13:52 INFO - 'tpaint', 10:13:52 INFO - 'sessionrestore', 10:13:52 INFO - 'sessionrestore_no_auto_restore', 10:13:52 INFO - 'tabpaint']}, 10:13:52 INFO - 'other-e10s': {'tests': ['a11yr', 10:13:52 INFO - 'ts_paint', 10:13:52 INFO - 'tpaint', 10:13:52 INFO - 'sessionrestore', 10:13:52 INFO - 'sessionrestore_no_auto_restore', 10:13:52 INFO - 'tabpaint']}, 10:13:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:13:52 INFO - 'ts_paint', 10:13:52 INFO - 'tpaint', 10:13:52 INFO - 'sessionrestore', 10:13:52 INFO - 'sessionrestore_no_auto_restore', 10:13:52 INFO - 'tabpaint']}, 10:13:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:13:52 INFO - 'ts_paint', 10:13:52 INFO - 'tpaint', 10:13:52 INFO - 'sessionrestore', 10:13:52 INFO - 'sessionrestore_no_auto_restore', 10:13:52 INFO - 'tabpaint']}, 10:13:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:13:52 INFO - 'tests': ['a11yr', 10:13:52 INFO - 'ts_paint', 10:13:52 INFO - 'tpaint', 10:13:52 INFO - 'sessionrestore', 10:13:52 INFO - 'sessionrestore_no_auto_restore', 10:13:52 INFO - 'tabpaint']}, 10:13:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:13:52 INFO - 'tests': ['a11yr', 10:13:52 INFO - 'ts_paint', 10:13:52 INFO - 'tpaint', 10:13:52 INFO - 'sessionrestore', 10:13:52 INFO - 'sessionrestore_no_auto_restore', 10:13:52 INFO - 'tabpaint']}, 10:13:52 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:13:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:13:52 INFO - 'bloom_basic_ref']}, 10:13:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:13:52 INFO - 'tests': ['tsvgx', 10:13:52 INFO - 'tsvgr_opacity', 10:13:52 INFO - 'tart', 10:13:52 INFO - 'tscrollx', 10:13:52 INFO - 'cart', 10:13:52 INFO - 'tsvg_static']}, 10:13:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:13:52 INFO - 'tsvgr_opacity', 10:13:52 INFO - 'tart', 10:13:52 INFO - 'tscrollx', 10:13:52 INFO - 'cart', 10:13:52 INFO - 'tsvg_static']}, 10:13:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:13:52 INFO - 'talos_options': ['--disable-e10s'], 10:13:52 INFO - 'tests': ['tp5o']}, 10:13:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:13:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:13:52 INFO - 'talos_options': ['--disable-e10s', 10:13:52 INFO - '--xperf_path', 10:13:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:13:52 INFO - 'tests': ['tp5n']}, 10:13:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:13:52 INFO - 'talos_options': ['--xperf_path', 10:13:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:13:52 INFO - 'tests': ['tp5n']}}, 10:13:52 INFO - 'talos.zip': {'path': '', 10:13:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:13:52 INFO - Running post-action listener: _resource_record_post_action 10:13:52 INFO - [mozharness: 2017-05-11 17:13:52.326855Z] Finished populate-webroot step (success) 10:13:52 INFO - [mozharness: 2017-05-11 17:13:52.326989Z] Running create-virtualenv step. 10:13:52 INFO - Running pre-action listener: _resource_record_pre_action 10:13:52 INFO - Running main action method: create_virtualenv 10:13:52 INFO - Creating virtualenv /builds/slave/test/build/venv 10:13:52 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 10:13:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:13:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:13:52 INFO - Using real prefix '/tools/python27' 10:13:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:13:53 INFO - Installing distribute.............................................................................................................................................................................................done. 10:13:56 INFO - Installing pip.................done. 10:13:56 INFO - Return code: 0 10:13:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:13:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:13:56 INFO - Reading from file tmpfile_stdout 10:13:56 INFO - Output received: 10:13:56 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) 10:13:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:56 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 0x10ce79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f1d88360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb8ad30>, '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 0x10cb3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7ebe8>, '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.3tJkwNBtBN/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.bBklUxqRKv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:13:56 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 10:13:56 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 10:13:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3tJkwNBtBN/Render', 10:13:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:56 INFO - 'HOME': '/Users/cltbld', 10:13:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:56 INFO - 'LOGNAME': 'cltbld', 10:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:56 INFO - 'MOZ_NO_REMOTE': '1', 10:13:56 INFO - 'NO_EM_RESTART': '1', 10:13:56 INFO - 'PAGER': '/bin/cat', 10:13:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:56 INFO - 'PWD': '/builds/slave/test', 10:13:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:56 INFO - 'SHELL': '/bin/bash', 10:13:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bBklUxqRKv/Listeners', 10:13:56 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:13:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:56 INFO - 'USER': 'cltbld', 10:13:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:56 INFO - 'XPC_FLAGS': '0x0', 10:13:56 INFO - 'XPC_SERVICE_NAME': '0', 10:13:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:56 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 10:13:56 INFO - Cleaning up... 10:13:56 INFO - Return code: 0 10:13:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:56 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 0x10ce79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f1d88360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb8ad30>, '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 0x10cb3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7ebe8>, '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.3tJkwNBtBN/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.bBklUxqRKv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:13:56 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 10:13:56 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 10:13:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3tJkwNBtBN/Render', 10:13:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:56 INFO - 'HOME': '/Users/cltbld', 10:13:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:56 INFO - 'LOGNAME': 'cltbld', 10:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:56 INFO - 'MOZ_NO_REMOTE': '1', 10:13:56 INFO - 'NO_EM_RESTART': '1', 10:13:56 INFO - 'PAGER': '/bin/cat', 10:13:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:56 INFO - 'PWD': '/builds/slave/test', 10:13:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:56 INFO - 'SHELL': '/bin/bash', 10:13:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bBklUxqRKv/Listeners', 10:13:56 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:13:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:56 INFO - 'USER': 'cltbld', 10:13:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:56 INFO - 'XPC_FLAGS': '0x0', 10:13:56 INFO - 'XPC_SERVICE_NAME': '0', 10:13:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:56 INFO - Downloading/unpacking psutil>=3.1.1 10:13:56 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 10:13:56 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 10:13:56 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 10:13:56 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 10:13:56 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 10:13:56 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 10:16:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:16:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:16:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:16:29 INFO - Installing collected packages: psutil 10:16:29 INFO - Running setup.py install for psutil 10:16:29 INFO - building 'psutil._psutil_osx' extension 10:16:29 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 10:16:30 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 10:16:30 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 10:16:30 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 10:16:30 INFO - building 'psutil._psutil_posix' extension 10:16:30 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 10:16:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:16:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:16:30 INFO - ^ 10:16:30 INFO - 1 warning generated. 10:16:30 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 10:16:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:16:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:16:30 INFO - Successfully installed psutil 10:16:30 INFO - Cleaning up... 10:16:30 INFO - Return code: 0 10:16:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:16:30 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')]} 10:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:16:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:30 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')]} 10:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:16:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:16:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ce79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f1d88360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb8ad30>, '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 0x10cb3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7ebe8>, '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.3tJkwNBtBN/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.bBklUxqRKv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:16:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3tJkwNBtBN/Render', 10:16:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:31 INFO - 'HOME': '/Users/cltbld', 10:16:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:31 INFO - 'LOGNAME': 'cltbld', 10:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:31 INFO - 'MOZ_NO_REMOTE': '1', 10:16:31 INFO - 'NO_EM_RESTART': '1', 10:16:31 INFO - 'PAGER': '/bin/cat', 10:16:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:31 INFO - 'PWD': '/builds/slave/test', 10:16:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:31 INFO - 'SHELL': '/bin/bash', 10:16:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bBklUxqRKv/Listeners', 10:16:31 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:16:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:31 INFO - 'USER': 'cltbld', 10:16:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:31 INFO - 'XPC_FLAGS': '0x0', 10:16:31 INFO - 'XPC_SERVICE_NAME': '0', 10:16:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:16:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:16:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:16:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:19:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:19:03 INFO - Installing collected packages: mozsystemmonitor 10:19:03 INFO - Running setup.py install for mozsystemmonitor 10:19:03 INFO - Successfully installed mozsystemmonitor 10:19:03 INFO - Cleaning up... 10:19:03 INFO - Return code: 0 10:19:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:19:03 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')]} 10:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:03 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')]} 10:19:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ce79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f1d88360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb8ad30>, '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 0x10cb3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7ebe8>, '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.3tJkwNBtBN/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.bBklUxqRKv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:19:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:19:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3tJkwNBtBN/Render', 10:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:05 INFO - 'HOME': '/Users/cltbld', 10:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:05 INFO - 'LOGNAME': 'cltbld', 10:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:05 INFO - 'MOZ_NO_REMOTE': '1', 10:19:05 INFO - 'NO_EM_RESTART': '1', 10:19:05 INFO - 'PAGER': '/bin/cat', 10:19:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:05 INFO - 'PWD': '/builds/slave/test', 10:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:05 INFO - 'SHELL': '/bin/bash', 10:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bBklUxqRKv/Listeners', 10:19:05 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:05 INFO - 'USER': 'cltbld', 10:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:05 INFO - 'XPC_FLAGS': '0x0', 10:19:05 INFO - 'XPC_SERVICE_NAME': '0', 10:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:05 INFO - Downloading/unpacking jsonschema==2.5.1 10:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:21:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:08 INFO - Downloading functools32-3.2.3-2.tar.gz 10:24:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:24:08 INFO - warning: no files found matching '*.txt' 10:24:08 INFO - no previously-included directories found matching 'build' 10:24:08 INFO - no previously-included directories found matching 'dist' 10:24:08 INFO - no previously-included directories found matching '.git*' 10:24:08 INFO - Installing collected packages: jsonschema, functools32 10:24:08 INFO - Running setup.py install for functools32 10:24:08 INFO - warning: no files found matching '*.txt' 10:24:08 INFO - no previously-included directories found matching 'build' 10:24:08 INFO - no previously-included directories found matching 'dist' 10:24:08 INFO - no previously-included directories found matching '.git*' 10:24:08 INFO - Successfully installed jsonschema functools32 10:24:08 INFO - Cleaning up... 10:24:09 INFO - Return code: 0 10:24:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:24:09 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')]} 10:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:09 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')]} 10:24:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24: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 0x10ce79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f1d88360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb8ad30>, '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 0x10cb3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7ebe8>, '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.3tJkwNBtBN/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.bBklUxqRKv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:24: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 10:24: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 10:24:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3tJkwNBtBN/Render', 10:24:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:24:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:24:10 INFO - 'HOME': '/Users/cltbld', 10:24:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:24:10 INFO - 'LOGNAME': 'cltbld', 10:24:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:10 INFO - 'MOZ_NO_REMOTE': '1', 10:24:10 INFO - 'NO_EM_RESTART': '1', 10:24:10 INFO - 'PAGER': '/bin/cat', 10:24:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:24:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:24:10 INFO - 'PWD': '/builds/slave/test', 10:24:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:24:10 INFO - 'SHELL': '/bin/bash', 10:24:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bBklUxqRKv/Listeners', 10:24:10 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:24:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:24:10 INFO - 'USER': 'cltbld', 10:24:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:24:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:24:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:10 INFO - 'XPC_FLAGS': '0x0', 10:24:10 INFO - 'XPC_SERVICE_NAME': '0', 10:24:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:24:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:24:10 INFO - Cleaning up... 10:24:10 INFO - Return code: 0 10:24:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:24: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')]} 10:24:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24: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')]} 10:24:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24: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', '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 0x10ce79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f1d88360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb8ad30>, '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 0x10cb3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7ebe8>, '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.3tJkwNBtBN/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.bBklUxqRKv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:24: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:24: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 blobuploader==1.2.4 10:24:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3tJkwNBtBN/Render', 10:24:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:24:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:24:10 INFO - 'HOME': '/Users/cltbld', 10:24:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:24:10 INFO - 'LOGNAME': 'cltbld', 10:24:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:10 INFO - 'MOZ_NO_REMOTE': '1', 10:24:10 INFO - 'NO_EM_RESTART': '1', 10:24:10 INFO - 'PAGER': '/bin/cat', 10:24:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:24:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:24:10 INFO - 'PWD': '/builds/slave/test', 10:24:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:24:10 INFO - 'SHELL': '/bin/bash', 10:24:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bBklUxqRKv/Listeners', 10:24:10 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:24:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:24:10 INFO - 'USER': 'cltbld', 10:24:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:24:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:24:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:10 INFO - 'XPC_FLAGS': '0x0', 10:24:10 INFO - 'XPC_SERVICE_NAME': '0', 10:24:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:24:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:10 INFO - Downloading/unpacking blobuploader==1.2.4 10:24:10 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 10:24:10 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 10:24:10 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 10:24:10 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 10:24:10 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 10:24:10 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 10:26:43 INFO - Downloading blobuploader-1.2.4.tar.gz 10:26:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:26:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:26:43 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 10:26:43 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 10:26:43 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 10:26:43 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 10:26:43 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 10:26:43 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 10:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:29:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:29: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 10:31:44 INFO - Downloading docopt-0.6.1.tar.gz 10:31:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:31:44 INFO - Installing collected packages: blobuploader, requests, docopt 10:31:44 INFO - Running setup.py install for blobuploader 10:31:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:31:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:31:45 INFO - Running setup.py install for requests 10:31:45 INFO - Running setup.py install for docopt 10:31:45 INFO - Successfully installed blobuploader requests docopt 10:31:45 INFO - Cleaning up... 10:31:45 INFO - Return code: 0 10:31:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:31:45 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')]} 10:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:45 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')]} 10:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:46 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 0x10ce79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f1d88360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb8ad30>, '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 0x10cb3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7ebe8>, '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.3tJkwNBtBN/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.bBklUxqRKv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:31:46 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 10:31:46 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 10:31:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3tJkwNBtBN/Render', 10:31:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:46 INFO - 'HOME': '/Users/cltbld', 10:31:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:46 INFO - 'LOGNAME': 'cltbld', 10:31:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:46 INFO - 'MOZ_NO_REMOTE': '1', 10:31:46 INFO - 'NO_EM_RESTART': '1', 10:31:46 INFO - 'PAGER': '/bin/cat', 10:31:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:46 INFO - 'PWD': '/builds/slave/test', 10:31:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:46 INFO - 'SHELL': '/bin/bash', 10:31:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bBklUxqRKv/Listeners', 10:31:46 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:31:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:46 INFO - 'USER': 'cltbld', 10:31:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:46 INFO - 'XPC_FLAGS': '0x0', 10:31:46 INFO - 'XPC_SERVICE_NAME': '0', 10:31:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:31:47 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Yorpru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:31:47 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-wPfiL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:31:47 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-NNsmMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:31:47 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-0jOrBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:31:47 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-PBk24v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:31:47 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-dVrBmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:31:47 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-4egBy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:31:47 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-sGi6Es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:31:47 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-s5kiu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:31:48 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-qIjXpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:31:48 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zBr3VP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:31:48 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-w2UXVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:31:48 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-FTfdL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:31:48 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ndHzns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:31:48 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-_Aszas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:31:48 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-1O5UdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:31:48 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-7gUcgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:31:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:31:48 INFO - Running setup.py install for manifestparser 10:31:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:31:49 INFO - Running setup.py install for mozcrash 10:31:49 INFO - Running setup.py install for mozdebug 10:31:49 INFO - Running setup.py install for mozdevice 10:31:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:31:49 INFO - Running setup.py install for mozfile 10:31:49 INFO - Running setup.py install for mozhttpd 10:31:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:31:49 INFO - Running setup.py install for mozinfo 10:31:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:31:49 INFO - Running setup.py install for mozInstall 10:31:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:31:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:31:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:31:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:31:50 INFO - Running setup.py install for mozleak 10:31:50 INFO - Running setup.py install for mozlog 10:31:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:31:50 INFO - Running setup.py install for moznetwork 10:31:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:31:50 INFO - Running setup.py install for mozprocess 10:31:50 INFO - Running setup.py install for mozprofile 10:31:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:31:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:31:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:31:50 INFO - Running setup.py install for mozrunner 10:31:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:31:51 INFO - Running setup.py install for mozscreenshot 10:31:51 INFO - Running setup.py install for moztest 10:31:51 INFO - Running setup.py install for mozversion 10:31:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:31:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:31:51 INFO - Cleaning up... 10:31:51 INFO - Return code: 0 10:31:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:31:51 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')]} 10:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:51 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')]} 10:31:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:51 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 0x10ce79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f1d88360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb8ad30>, '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 0x10cb3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7ebe8>, '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.3tJkwNBtBN/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.bBklUxqRKv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:31:51 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 10:31:51 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 10:31:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3tJkwNBtBN/Render', 10:31:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:51 INFO - 'HOME': '/Users/cltbld', 10:31:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:51 INFO - 'LOGNAME': 'cltbld', 10:31:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:51 INFO - 'MOZ_NO_REMOTE': '1', 10:31:51 INFO - 'NO_EM_RESTART': '1', 10:31:51 INFO - 'PAGER': '/bin/cat', 10:31:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:51 INFO - 'PWD': '/builds/slave/test', 10:31:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:51 INFO - 'SHELL': '/bin/bash', 10:31:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bBklUxqRKv/Listeners', 10:31:51 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:31:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:51 INFO - 'USER': 'cltbld', 10:31:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:51 INFO - 'XPC_FLAGS': '0x0', 10:31:51 INFO - 'XPC_SERVICE_NAME': '0', 10:31:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:31:51 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-3QpcJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:31:51 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)) 10:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:31:51 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-OBZ4Xe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:31:51 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)) 10:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:31:51 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-b4o9Mz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:31:52 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)) 10:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:31:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-_siEfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:31:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-nc3Dhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:31:52 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)) 10:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:31:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-kuj2Eh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:31:52 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)) 10:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:31:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-2xj0_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:31:52 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)) 10:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:31:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-MwRXCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:31:52 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)) 10:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:31:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-NHgDXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:31:52 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)) 10:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:31:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zVp612-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:31:52 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)) 10:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:31:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Gw_rts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:31:52 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)) 10:31:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:31:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-1RPuBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:31:53 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)) 10:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:31:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-dDnoVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:31:53 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)) 10:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:31:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-UBql2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:31:53 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)) 10:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:31:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-z3Wb50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:31:53 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)) 10:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:31:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-7A78wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:31:53 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)) 10:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:31:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-cKIXrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:31:53 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)) 10:31:53 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)) 10:31:53 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)) 10:31:53 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)) 10:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:31:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:31:53 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 10:31:53 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 10:31:53 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 10:31:53 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 10:31:53 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 10:31:53 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 10:34:26 INFO - Downloading blessings-1.6.tar.gz 10:34:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:34:26 INFO - Installing collected packages: blessings 10:34:26 INFO - Running setup.py install for blessings 10:34:26 INFO - Successfully installed blessings 10:34:26 INFO - Cleaning up... 10:34:26 INFO - Return code: 0 10:34:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:34:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:34:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:34:26 INFO - Reading from file tmpfile_stdout 10:34:26 INFO - Current package versions: 10:34:26 INFO - blessings == 1.6 10:34:26 INFO - blobuploader == 1.2.4 10:34:26 INFO - docopt == 0.6.1 10:34:26 INFO - functools32 == 3.2.3-2 10:34:26 INFO - jsonschema == 2.5.1 10:34:26 INFO - manifestparser == 1.1 10:34:26 INFO - mozInstall == 1.12 10:34:26 INFO - mozcrash == 1.0 10:34:26 INFO - mozdebug == 0.1 10:34:26 INFO - mozdevice == 0.50 10:34:26 INFO - mozfile == 1.2 10:34:26 INFO - mozhttpd == 0.7 10:34:26 INFO - mozinfo == 0.9 10:34:26 INFO - mozleak == 0.1 10:34:26 INFO - mozlog == 3.4 10:34:26 INFO - moznetwork == 0.27 10:34:26 INFO - mozprocess == 0.25 10:34:26 INFO - mozprofile == 0.28 10:34:26 INFO - mozrunner == 6.13 10:34:26 INFO - mozscreenshot == 0.1 10:34:26 INFO - mozsystemmonitor == 0.3 10:34:26 INFO - moztest == 0.8 10:34:26 INFO - mozversion == 1.4 10:34:26 INFO - psutil == 3.1.1 10:34:26 INFO - requests == 1.2.3 10:34:26 INFO - wsgiref == 0.1.2 10:34:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:34: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')]} 10:34:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34: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')]} 10:34:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:27 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 0x10ce79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f1d88360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb8ad30>, '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 0x10cb3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7ebe8>, '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.3tJkwNBtBN/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.bBklUxqRKv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:34:27 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 10:34:27 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 10:34:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3tJkwNBtBN/Render', 10:34:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:34:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:34:27 INFO - 'HOME': '/Users/cltbld', 10:34:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:34:27 INFO - 'LOGNAME': 'cltbld', 10:34:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:27 INFO - 'MOZ_NO_REMOTE': '1', 10:34:27 INFO - 'NO_EM_RESTART': '1', 10:34:27 INFO - 'PAGER': '/bin/cat', 10:34:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:34:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:34:27 INFO - 'PWD': '/builds/slave/test', 10:34:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:34:27 INFO - 'SHELL': '/bin/bash', 10:34:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bBklUxqRKv/Listeners', 10:34:27 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:34:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:34:27 INFO - 'USER': 'cltbld', 10:34:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:34:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:34:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:27 INFO - 'XPC_FLAGS': '0x0', 10:34:27 INFO - 'XPC_SERVICE_NAME': '0', 10:34:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:34:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:27 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)) 10:34:27 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)) 10:34:27 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)) 10:34:27 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)) 10:34:27 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)) 10:34:27 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)) 10:34:27 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)) 10:34:27 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)) 10:34:27 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)) 10:34:27 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)) 10:34:27 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)) 10:34:27 INFO - Cleaning up... 10:34:27 INFO - Return code: 0 10:34:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:34:27 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')]} 10:34:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:34:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:27 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')]} 10:34:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:34:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:34:27 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 0x10ce79b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce7c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2f1d88360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb8ad30>, '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 0x10cb3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7ebe8>, '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.3tJkwNBtBN/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.bBklUxqRKv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 10:34:27 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 10:34:27 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 10:34:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3tJkwNBtBN/Render', 10:34:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:34:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:34:27 INFO - 'HOME': '/Users/cltbld', 10:34:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:34:27 INFO - 'LOGNAME': 'cltbld', 10:34:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:27 INFO - 'MOZ_NO_REMOTE': '1', 10:34:27 INFO - 'NO_EM_RESTART': '1', 10:34:27 INFO - 'PAGER': '/bin/cat', 10:34:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:34:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:34:27 INFO - 'PWD': '/builds/slave/test', 10:34:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:34:27 INFO - 'SHELL': '/bin/bash', 10:34:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bBklUxqRKv/Listeners', 10:34:27 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:34:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:34:27 INFO - 'USER': 'cltbld', 10:34:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:34:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:34:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:27 INFO - 'XPC_FLAGS': '0x0', 10:34:27 INFO - 'XPC_SERVICE_NAME': '0', 10:34:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:34:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:34:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:34:28 INFO - Cleaning up... 10:34:28 INFO - Return code: 0 10:34:28 INFO - Running post-action listener: _resource_record_post_action 10:34:28 INFO - Running post-action listener: _start_resource_monitoring 10:34:28 INFO - Starting resource monitoring. 10:34:28 INFO - [mozharness: 2017-05-11 17:34:28.240135Z] Finished create-virtualenv step (success) 10:34:28 INFO - [mozharness: 2017-05-11 17:34:28.240914Z] Running install step. 10:34:28 INFO - Running pre-action listener: _resource_record_pre_action 10:34:28 INFO - Running main action method: install 10:34:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:34:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:34:28 INFO - Reading from file tmpfile_stdout 10:34:28 INFO - Detecting whether we're running mozinstall >=1.0... 10:34:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:34:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:34:28 INFO - Reading from file tmpfile_stdout 10:34:28 INFO - Output received: 10:34:28 INFO - Usage: mozinstall [options] installer 10:34:28 INFO - Options: 10:34:28 INFO - -h, --help show this help message and exit 10:34:28 INFO - -d DEST, --destination=DEST 10:34:28 INFO - Directory to install application into. [default: 10:34:28 INFO - "/builds/slave/test"] 10:34:28 INFO - --app=APP Application being installed. [default: firefox] 10:34:28 INFO - mkdir: /builds/slave/test/build/application 10:34:28 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'] 10:34:28 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 10:34:49 INFO - Reading from file tmpfile_stdout 10:34:49 INFO - Output received: 10:34:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:34:49 INFO - Running post-action listener: _resource_record_post_action 10:34:49 INFO - [mozharness: 2017-05-11 17:34:49.075712Z] Finished install step (success) 10:34:49 INFO - [mozharness: 2017-05-11 17:34:49.075851Z] Running run-tests step. 10:34:49 INFO - Running pre-action listener: _resource_record_pre_action 10:34:49 INFO - Running main action method: run_tests 10:34:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:34:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:34:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:34:49 INFO - 2017-05-11 10:34:49.089 screenresolution[1636:9117] starting screenresolution argv=screenresolution get 10:34:49 INFO - 2017-05-11 10:34:49.092 screenresolution[1636:9117] Display 0: 1600x1200x32@60 10:34:49 INFO - 2017-05-11 10:34:49.098 screenresolution[1637:9119] starting screenresolution argv=screenresolution list 10:34:49 INFO - Available Modes on Display 0 10:34:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:34:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:34:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:34:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:34:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:34:49 INFO - Intel Iris: 10:34:49 INFO - Chipset Model: Intel Iris 10:34:49 INFO - Type: GPU 10:34:49 INFO - Bus: Built-In 10:34:49 INFO - VRAM (Dynamic, Max): 1536 MB 10:34:49 INFO - Vendor: Intel (0x8086) 10:34:49 INFO - Device ID: 0x0a2e 10:34:49 INFO - Revision ID: 0x0009 10:34:49 INFO - Displays: 10:34:49 INFO - Display: 10:34:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:34:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:34:49 INFO - Display Serial Number: 1600x1200 60 10:34:49 INFO - Main Display: Yes 10:34:49 INFO - Mirror: Off 10:34:49 INFO - Online: Yes 10:34:49 INFO - Rotation: Supported 10:34:49 INFO - Return code: 0 10:34:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:34:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:34:49 INFO - Python 2.7.3 10:34:49 INFO - Return code: 0 10:34:49 INFO - grabbing minidump binary from tooltool 10:34:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:34:49 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 0x10cb8ad30>, '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 0x10cb3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb7ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:34:49 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 10:34:49 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 10:34:49 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 10:34:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:34:49 INFO - Return code: 0 10:34:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:34:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:34:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:34:49 INFO - ENV: RUST_BACKTRACE is now 1 10:34:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:34:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:34:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 10:34:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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 10:34:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3tJkwNBtBN/Render', 10:34:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:34:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:34:49 INFO - 'HOME': '/Users/cltbld', 10:34:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:34:49 INFO - 'LOGNAME': 'cltbld', 10:34:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:34:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:34:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:34:49 INFO - 'MOZ_NO_REMOTE': '1', 10:34:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:34:49 INFO - 'NO_EM_RESTART': '1', 10:34:49 INFO - 'PAGER': '/bin/cat', 10:34:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:34:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:34:49 INFO - 'PWD': '/builds/slave/test', 10:34:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:34:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:34:49 INFO - 'RUST_BACKTRACE': '1', 10:34:49 INFO - 'SHELL': '/bin/bash', 10:34:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bBklUxqRKv/Listeners', 10:34:49 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 10:34:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:34:49 INFO - 'USER': 'cltbld', 10:34:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:34:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:34:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:34:49 INFO - 'XPC_FLAGS': '0x0', 10:34:49 INFO - 'XPC_SERVICE_NAME': '0', 10:34:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:34:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 10:34:49 INFO - mozversion application_buildid: 20170511075519 10:34:49 INFO - mozversion application_changeset: 0259418f19ab804b126730ef1d5d03006d6aad52 10:34:49 INFO - mozversion application_display_name: Nightly 10:34:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:34:49 INFO - mozversion application_name: Firefox 10:34:49 INFO - mozversion application_remotingname: firefox 10:34:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:34:49 INFO - mozversion application_vendor: Mozilla 10:34:49 INFO - mozversion application_version: 55.0a1 10:34:49 INFO - mozversion platform_buildid: 20170511075519 10:34:49 INFO - mozversion platform_changeset: 0259418f19ab804b126730ef1d5d03006d6aad52 10:34:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:34:49 INFO - mozversion platform_version: 55.0a1 10:34:49 INFO - using testdate: 1494524089 10:34:49 INFO - actual date: 1494524089 10:34:49 INFO - starting webserver on 'localhost:49249' 10:34:49 INFO - SUITE-START | Running 2 tests 10:34:49 INFO - TEST-START | dromaeo_css 10:34:49 INFO - operating with platform_type : mac_ 10:34:49 INFO - Initialising browser for dromaeo_css test... 10:34:49 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpH4j9x7/profile http://localhost:49249/getInfo.html) 10:34:52 INFO - PID 1647 | 2017-05-11 10:34:52.294 plugin-container[1649:9310] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9843, name = 'com.apple.tsm.portname' 10:34:52 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:52 INFO - PID 1647 | 2017-05-11 10:34:52.299 plugin-container[1649:9310] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b07, name = 'com.apple.CFPasteboardClient' 10:34:52 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:52 INFO - PID 1647 | 2017-05-11 10:34:52.299 plugin-container[1649:9310] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:34:52 INFO - PID 1647 | __metrics Screen width/height:1600/1200 10:34:52 INFO - PID 1647 | colorDepth:24 10:34:52 INFO - PID 1647 | Browser inner width/height: 1024/691 10:34:52 INFO - PID 1647 | __metrics 10:34:52 INFO - PID 1647 | 10:34:52 INFO - PID 1647 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 10:34:52 INFO - PID 1647 | 10:34:53 INFO - TEST-INFO | 1647: exit 0 10:34:53 INFO - Browser initialized. 10:34:53 INFO - Running cycle 1/1 for dromaeo_css test... 10:34:53 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpH4j9x7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:34:55 INFO - PID 1652 | 2017-05-11 10:34:55.337 plugin-container[1653:9478] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943b, name = 'com.apple.tsm.portname' 10:34:55 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:55 INFO - PID 1652 | 2017-05-11 10:34:55.338 plugin-container[1653:9478] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x965b, name = 'com.apple.CFPasteboardClient' 10:34:55 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:55 INFO - PID 1652 | 2017-05-11 10:34:55.338 plugin-container[1653:9478] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:34:56 INFO - PID 1652 | 2017-05-11 10:34:56.074 plugin-container[1654:9523] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434f, name = 'com.apple.tsm.portname' 10:34:56 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:56 INFO - PID 1652 | 2017-05-11 10:34:56.074 plugin-container[1654:9523] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 10:34:56 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:56 INFO - PID 1652 | 2017-05-11 10:34:56.074 plugin-container[1654:9523] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:35:05 INFO - PID 1652 | RSS: Main: 229191680 10:35:05 INFO - PID 1652 | 10:38:08 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 10:38:10 INFO - PID 1652 | RSS: Main: 253779968 10:38:10 INFO - PID 1652 | 10:41:12 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 10:41:13 INFO - PID 1652 | RSS: Main: 259813376 10:41:13 INFO - PID 1652 | 10:44:14 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 10:44:15 INFO - PID 1652 | RSS: Main: 271880192 10:44:15 INFO - PID 1652 | 10:47:17 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 10:47:18 INFO - PID 1652 | RSS: Main: 264265728 10:47:18 INFO - PID 1652 | 10:50:20 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 10:50:21 INFO - PID 1652 | RSS: Main: 268165120 10:50:21 INFO - PID 1652 | 10:53:23 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 10:53:23 INFO - PID 1652 | RSS: Main: 266907648 10:53:23 INFO - PID 1652 | 10:53:23 INFO - PID 1652 | __start_tp_report 10:53:23 INFO - PID 1652 | _x_x_mozilla_page_load 10:53:23 INFO - PID 1652 | _x_x_mozilla_page_load_details 10:53:23 INFO - PID 1652 | |i|pagename|runs| 10:53:23 INFO - PID 1652 | |0;dojo.html;2850.315924178197;3596.982015089924;3631.782093074414;3657.817109144542;3662.8901132966002;5931.466168044875;6127.220070483963;6189.133521307015;6211.068339749028;6294.181756371671;6198.628082315065;6217.751289948408;6227.221597300338;6275.874482510347;6336.42972132508;5077.873053173664;5359.844820486862;5461.15352120422;5503.091989821674;5527.364353099395;5634.4365563443735;5638.718064096779;5652.406497317784;5669.376368599451;5710.314762228539;5424.105022671254;5501.119820828688;5517.36550296713;5536.584756143306;5570.944290557123;5579.9163012554845;5603.8879222415735;5614.550835933115;5617.634853734494;5628.690422026787;5514.172874068881;5523.392426833086;5532.059549876491;5557.194206839986;5589.273394458735;5068.594512438996;6146.84632884177;6207.937920620781;6298;6310.211223597051;6732.394084532416;6804.08144900437;6828.52200345976;6838.760643377458;6846.554973926729;6894.13823272091;6905.274946130685;6910.861782764367;6927.376536111774;6940.826479338007;6210.254769427697;6333.081703153109;6411.455026322721;6427.196600424947;6429.871402571923;6894.172699276019;6905.205901321312;6996.440284777205;6996.440284777205;7056.717731290691;6461.160049193575;6577.177852768404;6590.835229119214;6685.0640910272605;6713;6765.255821859591;6877.724891004404;6908.965455172692;6914.170299564084;6914.654267286615;6799.150106236721;6809.693563789641;6828.6927088281145;6830.8633827324165;6855.622940738208;6565.80302590923;6650.534462587572;6710.0941372915;6776.42400395962;6843.657817109224;5429.864253393696;5435.021696094741;5438.945610543923;5440.646357986719;5470.234167216593;4741.881452963704;4764.49972752856;4816.036792641486;4844.636652251095;4872.293517439952;5245.344331958505;6115.027386288523;6307;6368.29948705642;6430.003349480838;6865.588064716133;6883.346082122191;6910.136232970879;6936.965315173493;6950.895736563955;4764.8332308369045;6102.603330783573;6286.277078136073;6465.224173099165;6469.51478639104;5890.852728681732;5969.164316995537;6055.455009049207;6083.726232319556;6128.724207410677;6995.545289556163;7038.718451261892;7075.115610548682;7089.361957423856;7093.645317733989;6877.518573699793;6953.4089602384365;6960;6963.40811031068;6969.954506824017;6479.676016199265;6677.964915438115;6790.049393085071;7063.71745130189;7120.181179164462;6658.334166583303;6696.866062678817;6753.662316884234;6805.319468053353;6813.3527314905;6865.107536020485;6879.96800479912;6910.20532638753;6915.481338899502;6927.930720692729;5480.753366098455;6423.550351475352;6579.374959378852;6610.603363797995;6673.1992160941;5863.032599621015;6463.7737679181;6636.170478690164;6727.159105111861;6748.223954245128;6373.235211774431;6560.081588577507;6607.339266073354;6614.140161778938;6628.635425051669;6530.575512591667;6625.072489851328;6637.469002479694;6685;6702.865942681022;5896.9115463268945;6187.535934804818;6518.185226846645;6543.443807276435;6562.606243625207 10:53:23 INFO - PID 1652 | |1;ext.html;741.2698491985396;795.824918517926;821.6343727041465;1214.3503225774207;1272.929988850614;38321;39455.40816887749;39457.80271098648;39470.40794388086;39688.40467392988;39601.207975840465;40037.39943900844;40053.198936021334;40087.59912400875;40119.79940100295;7931.167227441122;8061.838763224732;8434.915650843473;8644.222020018196;8727.432716873383;7467.925320746805;8665.610047547843;8691.913510811148;8760.824783504358;8764.1674040966;8712.172343627377;8762.518061506615;8786.209241168293;8788.780280492974;8791.20879120879;8761.824763504757;8769.868451973225;8780.429272097357;8785.516796576187;8790.989036260848;8518;8534.829303413959;8569.314454843598;8578.785530361729;8612.095729948327;38389.808050959844;38603.61396386056;38718.80640596779;38750.612493874985;38940;3646.033801042726;3654.214343916044;3678.6321367863266;3686.189038411545;3693.409054551286;31330.373392532023;31508.684913150806;31622.209444763834;31647.050588482383;31761.84119079413;3174.603174603167;3213.084270982782;3213.791103578283;3215.6623554526673;3234.87060517579;239745;243240;277381.99999999796;289114;291531.62702559476;37158.8142059288;49180.50819491831;49661.51015469542;50144.247836282484;50219.49780502221;41495;42259.7887010563;43087.56912430836;50508.49491505112;52407;11878.40607969598;14417;14537.418503259963;14556.417743290362;14605.56183314481;11785.351805650771;11969.521219151136;11971;11999.400029998465;12003.979661728676;13960.301984900714;14035.157890526601;14074.225917574631;14152.929235353757;14585.124892506483;6159.19930409044;6207.689615519206;6209.6895155242055;6212.565120441616;6226.968865155645;987.5013118375318;989.5052473763119;1028.7582418131733;1031.5409642708923;1039.5374058544026;23205.883970580377;25311.746882531308;25339.493210135694;25372.365690857507;25455.36361590975;998.9550470228858;1084.7342401111778;1113.565709373345;1154.3939431798206;1166.859976802789;12337.581178164446;12532.561360352527;13124.671883202805;13125.60623181306;13449.663758406117;234923.65076349358;316410;327861;328430;329168;56840.43159568434;56996;57349.13976290359;57529.56176095631;57533;1527.2821773766311;1542.8891198337178;1575.6139745762132;1588.0471716969726;1593.8963967342086;24646.923473638304;24844.130455433973;25027.594783711807;25052.123175688765;25072.498550028533;40941.79529102335;41043.589564103975;41111.58888411077;41152.38271425871;41181;262797.6860115687;264654;264913;265222;265370;22435.551288974457;30361.544576830922;33453.16367090842;34103.82948085244;36258.27483450369;42740;43107.353389699805;45301.3373328334;45411.77294113508;45566.54433455623;30757.23106922345;31002.534961975136;31179;31229.53155702621;31272.843635781675;30161.245968850955;30212.09363719092;30934;31213.219669508442;31297.530537042418;37123;37156.62843371532;37254.25491490209;37316;37396.43905341368;21907.890460547595;29437;30438.391232175676;31046.534301985037;31852.725890964102;23397.181098661466;24292.75707242905;24310.75689243085;24478.755212447646;24568 10:53:23 INFO - PID 1652 | |2;jquery.html;429.54468263640524;436.95567593451653;437.3658195616356;452.33055078483835;461.53615616305626;17124;20604.690929636068;21288.787112128874;21945.01247443865;22076.668849967264;21752.456188595293;21929.890350548263;21976.011079501423;22178.889105554448;22341.99461024254;5916.615419997708;6163.414475624831;6214.81918435498;6217.875642487147;6219.751209951596;5835.18307436957;6172.4136207060255;6204.224471941007;6210.65841378724;6215.471684906788;6096.4817990470865;6180.258368995726;6180.474659653934;6191.690415479231;6196.318404975449;5065.468125846806;5964.314103878066;6200.689965501707;6202.9379706203035;6204.6587437690905;5424.267723857308;5431.755570999315;5451.182322651594;5493.093639549469;5512.614117011811;26299;26450;26503.73496265032;26512;26838.46323073547;2151.5911976724374;2203.768604296558;2212.4026512841606;2212.4247695599097;2215.3575463115676;16252.918735406365;16984.57538561546;17045.7443138353;17214.225359858836;17722.822771772248;1890.2250077468173;2119.7456305243313;2159.805617494417;2164.2425151197112;2164.6536554151417;138534;147782.7832582512;156259;159868;161398;11800.291982481078;12432.637798818376;12478.18891772032;12480.188787728768;12496.750064998649;13198.746119118669;14334.283285835667;14894.776578351333;15209.771853422208;15987.040777553384;7975.042994840656;8698.652053917773;8886.24466920306;8893.955530222307;9009.279257659371;6944.4097251734165;7143.107111611049;7957.164497727771;8515.57422128891;8550.187732165434;7665.271799179069;7744.961275193701;7795.883061754078;7815;7841.1181316483635;2190.5206584476596;2204.3386983904766;2206.7491159017713;2210.160139147114;2414.058517178267;845.8308338332358;950.4439902657023;962.6090299517131;964.9324547281764;967.4678926590348;19627;20322.796772032387;20464.38606841797;20553.177872885215;20719.171233150806;978.6525783346951;985.610683779903;1008.6822650864954;1020.9336393134422;1021.1983592879554;10238.948805255926;10519.84220236697;10525.894741052645;10592;10608.893911060944;482199;483991;488743;495441;503112;116217.41891290489;117723;118339;121140.39429802794;122415;1160.5531870230022;1164.6040346282305;1188.1267268557604;1190.101473387604;1240.553400775738;21322.786772132702;21968.89015554912;21999.560008800054;22031.889840550695;22237.999290031665;50224.748876255384;51522.71193220199;56704;57785.42214577969;57949;74663;75042.62478687572;75757.86363204446;77430;78625.60687196527;7729.574873381793;8042.83914321722;8548.74353769388;8619.784505387164;8852.159044890725;11418.885811142114;12674.873251267738;12960.345955447205;13299;13352.399142038437;9976.501174941079;10669.57321707123;11245.437728113726;11387.943060284977;11462.770744585228;9256.768580785534;9888.802223955337;10330.535125919201;10440.947795260976;10603.310784798963;9533.618655253713;11439.110702361237;11831.940840295743;11867.940660296643;11975.401229938294;8566.742997710078;8601.311895048506;8635.222829945335;8753.824923501621;8756.868646970173;11177.552897883994;11284.830727539258;11528.59649912249;11726.94136529312;11823.231489953125 10:53:23 INFO - PID 1652 | |3;mootools.html;1396.588006538071;1423.3452611798573;1439.820022497188;1449.9855001449982;1470.5073800276907;11337.546498140064;11372;11872.168948173632;11880.465379057941;12025.639230823068;11103.278286911365;11452.656420307383;11593.768124637501;11745.47145378458;11767.117466190028;2474.268012391324;2536.441982763789;2538.4923015396903;2541.0217066429373;2543.504016716741;2453.288546321565;2514.170323793153;2516.245126462063;2520.785733212679;2521.2814347910758;2500.8999640014376;2504.3488692939786;2526.494701059786;2526.5578523758268;2529.6079107738237;2450.277168235375;2470.851748895063;2498.762617551348;2511.485145545159;2513.0576033987254;2419.9274021779197;2476.839005464657;2506.1854897158496;2506.987465062681;2533.214703441939;11634.94182529082;11666.183367164302;11729.178957472981;11734.178607497479;11755.412229388496;6543.149390579194;6750.763723269662;6802.183737951428;6822.727090916357;6843.520953533254;4027.959720402817;4355.085432059272;4406.361077643724;4425.734455932655;4439.511653718088;1238.5107882386408;1240.4727990603958;1262.7600755856358;1296.8119622654756;1310.488909325364;16083.195840207944;16462.83537164625;16616.584585385343;16621.6675666486;16728.24722887473;4583.9083218335445;5601.243832082698;5762.0121058234845;5788.5369170466265;5791.478766910914;11599.88400116005;11707.414629268502;11767.52929882795;11779.352135632622;11796.29222246668;6536.281009089092;6602.7689030883685;6634.170728658918;6644.8671026579195;6649.368310010541;5048.62135339851;6026.246719160105;6237.345078766755;6242.719077641517;6258.874822503524;4252.617264446215;4407.537208593116;4440.6447484201835;4443.355713421536;4494.325851122292;1265.949362025527;1282.3139620303093;1286.8906142977764;1297.4810075969688;1303.3548393545257;661.751843058853;733.625850816077;751.7406494759299;781.8553567590014;822.576373167819;5086.415062267886;6118.877622447526;6276.278228003838;6346.079818426395;6409.807705768834;561.5479538971167;585.6749504025307;596.2517041113462;614.8901233273895;622.4024936061342;3985.5416627087675;4275.593818587229;4342;4360.9127817444105;4480.193565158238;14355.497557585435;14520.98353115293;14754.96715229945;14853.628659283604;14875.181864997312;14886.478973235884;15043.623909402353;15100;15124.470643527424;15179.544613661608;7542.094948606203;8519;8735.34484913634;8757.430767000125;8759.430637008574;6365.974007922652;6423.775167869102;6948.756793512202;7001.687755285357;7016.017757514054;12882.35588220604;13245.139065960682;13401.463941442233;13497.122687025312;13605;12281.385930703607;12684.429200685807;13654.317284135952;13754.487006429084;13975.720485590027;9289.16397524226;9365.719028429157;9438;9453.149216570542;9487.810243795224;10553.577856885639;10782.622608208674;10794.676159715222;10799.244052916221;10808.513616887101;8958.417702849294;9001.504917229353;9160.6793762218;9188.724338269863;9337.206337461392;8295.502269863828;8887.244584210415;8917.910820891708;8986.68546600892;9100.726978190665;8127.1466496017065;8321.00147982246;8342.207490288412;8359.791005224917;8361.498310101413;5240.899411123707;5857.8535536610225;5990.760369585168;6001.279846418458;6294.653794041372;6078.149059131637;6121.204243448501;6224.284207316216;6226.097215903578;6307.24313082433 10:53:23 INFO - PID 1652 | |4;prototype.html;358.7829363235241;401.6365189503499;405.53161098930735;405.8721502726641;416.4731614507646;5328.307320048393;6310.400511951369;6386.2975072741965;6400.967995160017;6418.871622567552;5699.917015767011;5944.821655350335;6023.548233882465;6130.233720784902;6246.843828904268;4257.957420425787;4677.74272415017;4687.273472611733;4714.90570188596;4721.220998535237;4727.692699974491;4737.265723812815;4747.667663263573;4750.857474275777;4757.571818536331;3309.470484722445;3537.8325152699676;4449.710768800018;4481.708688935209;4494.988627558806;3784.9621503784883;3952.6837852971835;4507.346434766941;4514.954850451502;4548.56788605082;3509.8245087745768;3850.6726928211137;3870.593587673282;3900.980495097535;3906.4726261954556;4901.852944411673;5888.29340479141;6026.2165918430765;6048.909266361008;6077.301110372319;4621.399218101659;5314.813981510667;5348.539671750999;5368.35579730431;5373.785048598052;6269.655168965705;6320.209973753281;6518.500744828669;6533.41199292066;6587.670616469205;4335.56644335567;4381.233284175257;4406.383106365111;4407.382966384676;4414.690971631987;6832.555883867532;7021.332973367522;7022.367986881205;7120.572765634027;7137.785866424016;4307.267764480046;4395.824167033346;4421.425214722066;4445;4463.486699029653;3824.8470061197795;3959.366501359826;4519.480259770103;4548.566516913502;4567.771611419415;4641.907161856811;5286.312779338738;5350.30446042012;5361.584799352682;5372;5461.508464238238;6656.334366563402;6671.499637527108;6691.5315927884585;6731.697073631698;1369.6849724563406;1413.724323756878;1534.3939144038043;1544.2819089123611;1544.4131230132477;844.2612713875359;998.610541888664;1000.8390267289909;1003.1322905686675;1018.981018981019;6099.451049405575;6396.5522413431545;6406.6476343800605;6423.389777971085;6429.7749578764515;6761.830954226085;6925.930740692629;6983.615901125487;7008.719651213996;7048.568243955827;5584.392875877417;6288.276848162428;6314.8001879642725;6342.809715708536;6407.327230640715;3526.541549598587;3605.729570282187;3633.9818300908855;3652.3243200008083;3657.396529572644;6300.684965751786;7044.8023835948015;7101.964490177619;7163.2478589747325;7169.354758071799;6152.938470615326;6465.870682586322;7055.506114571931;7170.820729481805;7190.64046797658;6887.896681549781;6997.545159564612;7231.385332246713;7250.129984401906;7280.890786638099;6967.686454109678;7017.7192912282935;7106.751263705745;7116.181639111568;7152.892706609301;6300.810975670737;6791.796246112624;6969.30306969299;6976.790696279119;7015.754448594274;6579.697106012857;7025.367716905503;7266.309700578437;7283.162436319891;7290.307420795016;4850.6362022847725;4952.851414457572;5115.002574498086;5152.098382783073;5181.92227116586;6300.739852029521;6639.203295604559;6935;7096.387348223696;7114.430845532448;5328.600354973315;5848.707564621837;6734.225564060196;6818.488613354118;6826.93173068263;5254.763535640982;5534.7786088556;6681.861804222602;6723.2940541244425;6820.624865632438;6621.635810030494;6626.370494802986;6642.83392915181;6650.83372915681;6662.734080524685;6570.375814297634;6694.665266736741;6730.697118629576;6766.7969960901255;6826.249112597709;5955.523558115254;5960.612560183574;6446.967765161145;6537.5096867734155;6637.767678131242 10:53:23 INFO - PID 1652 | |5;yui.html;814.7718638781143;827.2637352756052;837.526797359492;852.7228650688528;861.6854847980485;14262.144271343726;14906.329215185315;16638.50084497464;17020.978741275518;17029.57426064349;14390.640233994156;16873.662526749456;17282.222299996498;17657.91171044143;17762.64474710508;971.7764914069786;998.1465846701075;1003.9598416063385;1005.3867141043958;1006.4565134827185;1008.2236677758159;1009.581023875091;1009.6516701737888;1012.3015119567511;1012.9088382045614;1048.36573872807;1063.5852017713098;1068.5031460370926;1071.0842229893408;1072.126217133038;1049.3389164826149;1065.8028264771042;1066.7333166708322;1066.8239740442818;1068.839674048891;771.3751860992614;783.5337973905517;784.4116912315764;786.9803254918615;788.8304014636881;15656.452024179212;17308.307667693276;17566.7364989524;17567.648647027116;17589.384371546934;16516.009039457673;16928.830711692848;17092.145392730316;17330.13349332541;17381.65236695259;14055.929720351332;14188.21964791936;14298.142111473346;14308.356123974447;14333.928330358385;3371.4605663094035;3387.779794313362;3391.355642427931;3441.827908604585;3445.9827700861333;92149;126227;129356;130629.34685326608;131060;12046.976007039324;12317.260964342167;12368.628941131781;12374.443150058269;12379.566715164927;20921.26775562848;21084.84033378179;21309.46726331854;21318.893405532872;21583.02876370567;5590.413006634326;6947.3747362737595;7578.962105189549;7850.764477065697;7933.1670174630835;3613.78938055749;3823.063349479395;3889.96110038896;3985.20295940813;3987.342088555356;4127.731697439657;4167.416561681367;4234.280172370704;4235.428217190702;4239.894002649959;843.409613270713;862.7153039497076;867.8828358171693;867.9392442529089;867.9522626255616;559.9692016939107;630.5901164243279;642.2678146912524;647.7765171015992;695.2803848017379;10350.53422595985;10494.845566987626;11581.33402584958;12132.63602091921;12302.569410070604;591.7810410148187;638.7445021991241;643.6170478565247;644.8549076457759;659.8911179655399;5544.750486228129;5626.549876009991;5639.2668953035845;5723.227364305765;5733.455321744427;81757.78027746531;105753.41369879458;106900.46549767358;107054.46472767586;110636;95603.56594651086;96178.0382196183;96431;96556.51721741346;97309.51345243228;75833;83874;85246;86867;86875;10848.636962302193;11312.9434352831;11355;11358.54565817391;11559.248648837798;33803.66196338005;41323.1735365297;41326;41989.950251243965;43575.78212108919;52810;54391.45608543864;63741.36258637354;67154.32845671481;68605;15787.842121578637;16767.91616041912;17026.574335641708;17080.402185923434;17158.39945601898;17802.287908483515;18619.81380186181;18661.626767464848;18704;18811.341603043827;16155.27301271422;16306.67386652284;17005.57486062858;17066.91466542709;17171.742423863903;16785;17032.318707251572;17139.828601713823;17174.570635734206;17329.48011559655;13395.598132056053;16693.165341732623;16855.24151413165;17089.230984605474;17242;11651.242669226473;11731.648050558497;12156.75686486283;12213.450394732436;12363.75272494563;12350.320732359807;12847.486100555872;13064.150830196008;13511.256880871646;13852 10:53:23 INFO - PID 1652 | __end_tp_report 10:53:23 INFO - PID 1652 | __start_cc_report 10:53:23 INFO - PID 1652 | _x_x_mozilla_cycle_collect,415 10:53:23 INFO - PID 1652 | __end_cc_report 10:53:23 INFO - PID 1652 | __startTimestamp1494525203399__endTimestamp 10:53:23 INFO - PID 1652 | 10:53:23 INFO - PID 1652 | ------- Summary: start ------- 10:53:23 INFO - PID 1652 | Number of tests: 6 10:53:23 INFO - PID 1652 | 10:53:23 INFO - PID 1652 | [#0] dojo.html Cycles:165 Average:6236.91 Median:6469.51 stddev:777.99 (12.0%) stddev-sans-first:733.62 10:53:23 INFO - PID 1652 | Values: 2850.3 3597.0 3631.8 3657.8 3662.9 5931.5 6127.2 6189.1 6211.1 6294.2 6198.6 6217.8 6227.2 6275.9 6336.4 5077.9 5359.8 5461.2 5503.1 5527.4 5634.4 5638.7 5652.4 5669.4 5710.3 5424.1 5501.1 5517.4 5536.6 5570.9 5579.9 5603.9 5614.6 5617.6 5628.7 5514.2 5523.4 5532.1 5557.2 5589.3 5068.6 6146.8 6207.9 6298.0 6310.2 6732.4 6804.1 6828.5 6838.8 6846.6 6894.1 6905.3 6910.9 6927.4 6940.8 6210.3 6333.1 6411.5 6427.2 6429.9 6894.2 6905.2 6996.4 6996.4 7056.7 6461.2 6577.2 6590.8 6685.1 6713.0 6765.3 6877.7 6909.0 6914.2 6914.7 6799.2 6809.7 6828.7 6830.9 6855.6 6565.8 6650.5 6710.1 6776.4 6843.7 5429.9 5435.0 5438.9 5440.6 5470.2 4741.9 4764.5 4816.0 4844.6 4872.3 5245.3 6115.0 6307.0 6368.3 6430.0 6865.6 6883.3 6910.1 6937.0 6950.9 4764.8 6102.6 6286.3 6465.2 6469.5 5890.9 5969.2 6055.5 6083.7 6128.7 6995.5 7038.7 7075.1 7089.4 7093.6 6877.5 6953.4 6960.0 6963.4 6970.0 6479.7 6678.0 6790.0 7063.7 7120.2 6658.3 6696.9 6753.7 6805.3 6813.4 6865.1 6880.0 6910.2 6915.5 6927.9 5480.8 6423.6 6579.4 6610.6 6673.2 5863.0 6463.8 6636.2 6727.2 6748.2 6373.2 6560.1 6607.3 6614.1 6628.6 6530.6 6625.1 6637.5 6685.0 6702.9 5896.9 6187.5 6518.2 6543.4 6562.6 10:53:23 INFO - PID 1652 | 10:53:23 INFO - PID 1652 | [#1] ext.html Cycles:180 Average:43688.45 Median:181310.58 stddev:73733.40 (40.7%) stddev-sans-first:73869.73 10:53:23 INFO - PID 1652 | Values: 741.3 795.8 821.6 1214.4 1272.9 38321.0 39455.4 39457.8 39470.4 39688.4 39601.2 40037.4 40053.2 40087.6 40119.8 7931.2 8061.8 8434.9 8644.2 8727.4 7467.9 8665.6 8691.9 8760.8 8764.2 8712.2 8762.5 8786.2 8788.8 8791.2 8761.8 8769.9 8780.4 8785.5 8791.0 8518.0 8534.8 8569.3 8578.8 8612.1 38389.8 38603.6 38718.8 38750.6 38940.0 3646.0 3654.2 3678.6 3686.2 3693.4 31330.4 31508.7 31622.2 31647.1 31761.8 3174.6 3213.1 3213.8 3215.7 3234.9 239745.0 243240.0 277382.0 289114.0 291531.6 37158.8 49180.5 49661.5 50144.2 50219.5 41495.0 42259.8 43087.6 50508.5 52407.0 11878.4 14417.0 14537.4 14556.4 14605.6 11785.4 11969.5 11971.0 11999.4 12004.0 13960.3 14035.2 14074.2 14152.9 14585.1 6159.2 6207.7 6209.7 6212.6 6227.0 987.5 989.5 1028.8 1031.5 1039.5 23205.9 25311.7 25339.5 25372.4 25455.4 999.0 1084.7 1113.6 1154.4 1166.9 12337.6 12532.6 13124.7 13125.6 13449.7 234923.7 316410.0 327861.0 328430.0 329168.0 56840.4 56996.0 57349.1 57529.6 57533.0 1527.3 1542.9 1575.6 1588.0 1593.9 24646.9 24844.1 25027.6 25052.1 25072.5 40941.8 41043.6 41111.6 41152.4 41181.0 262797.7 264654.0 264913.0 265222.0 265370.0 22435.6 30361.5 33453.2 34103.8 36258.3 42740.0 43107.4 45301.3 45411.8 45566.5 30757.2 31002.5 31179.0 31229.5 31272.8 30161.2 30212.1 30934.0 31213.2 31297.5 37123.0 37156.6 37254.3 37316.0 37396.4 21907.9 29437.0 30438.4 31046.5 31852.7 23397.2 24292.8 24310.8 24478.8 24568.0 10:53:23 INFO - PID 1652 | 10:53:23 INFO - PID 1652 | [#2] jquery.html Cycles:180 Average:33268.95 Median:22208.44 stddev:83886.95 (377.7%) stddev-sans-first:84086.03 10:53:23 INFO - PID 1652 | Values: 429.5 437.0 437.4 452.3 461.5 17124.0 20604.7 21288.8 21945.0 22076.7 21752.5 21929.9 21976.0 22178.9 22342.0 5916.6 6163.4 6214.8 6217.9 6219.8 5835.2 6172.4 6204.2 6210.7 6215.5 6096.5 6180.3 6180.5 6191.7 6196.3 5065.5 5964.3 6200.7 6202.9 6204.7 5424.3 5431.8 5451.2 5493.1 5512.6 26299.0 26450.0 26503.7 26512.0 26838.5 2151.6 2203.8 2212.4 2212.4 2215.4 16252.9 16984.6 17045.7 17214.2 17722.8 1890.2 2119.7 2159.8 2164.2 2164.7 138534.0 147782.8 156259.0 159868.0 161398.0 11800.3 12432.6 12478.2 12480.2 12496.8 13198.7 14334.3 14894.8 15209.8 15987.0 7975.0 8698.7 8886.2 8894.0 9009.3 6944.4 7143.1 7957.2 8515.6 8550.2 7665.3 7745.0 7795.9 7815.0 7841.1 2190.5 2204.3 2206.7 2210.2 2414.1 845.8 950.4 962.6 964.9 967.5 19627.0 20322.8 20464.4 20553.2 20719.2 978.7 985.6 1008.7 1020.9 1021.2 10238.9 10519.8 10525.9 10592.0 10608.9 482199.0 483991.0 488743.0 495441.0 503112.0 116217.4 117723.0 118339.0 121140.4 122415.0 1160.6 1164.6 1188.1 1190.1 1240.6 21322.8 21968.9 21999.6 22031.9 22238.0 50224.7 51522.7 56704.0 57785.4 57949.0 74663.0 75042.6 75757.9 77430.0 78625.6 7729.6 8042.8 8548.7 8619.8 8852.2 11418.9 12674.9 12960.3 13299.0 13352.4 9976.5 10669.6 11245.4 11387.9 11462.8 9256.8 9888.8 10330.5 10440.9 10603.3 9533.6 11439.1 11831.9 11867.9 11975.4 8566.7 8601.3 8635.2 8753.8 8756.9 11177.6 11284.8 11528.6 11726.9 11823.2 10:53:23 INFO - PID 1652 | 10:53:23 INFO - PID 1652 | [#3] mootools.html Cycles:180 Average:7082.88 Median:4006.75 stddev:4482.05 (111.9%) stddev-sans-first:4474.26 10:53:23 INFO - PID 1652 | Values: 1396.6 1423.3 1439.8 1450.0 1470.5 11337.5 11372.0 11872.2 11880.5 12025.6 11103.3 11452.7 11593.8 11745.5 11767.1 2474.3 2536.4 2538.5 2541.0 2543.5 2453.3 2514.2 2516.2 2520.8 2521.3 2500.9 2504.3 2526.5 2526.6 2529.6 2450.3 2470.9 2498.8 2511.5 2513.1 2419.9 2476.8 2506.2 2507.0 2533.2 11634.9 11666.2 11729.2 11734.2 11755.4 6543.1 6750.8 6802.2 6822.7 6843.5 4028.0 4355.1 4406.4 4425.7 4439.5 1238.5 1240.5 1262.8 1296.8 1310.5 16083.2 16462.8 16616.6 16621.7 16728.2 4583.9 5601.2 5762.0 5788.5 5791.5 11599.9 11707.4 11767.5 11779.4 11796.3 6536.3 6602.8 6634.2 6644.9 6649.4 5048.6 6026.2 6237.3 6242.7 6258.9 4252.6 4407.5 4440.6 4443.4 4494.3 1265.9 1282.3 1286.9 1297.5 1303.4 661.8 733.6 751.7 781.9 822.6 5086.4 6118.9 6276.3 6346.1 6409.8 561.5 585.7 596.3 614.9 622.4 3985.5 4275.6 4342.0 4360.9 4480.2 14355.5 14521.0 14755.0 14853.6 14875.2 14886.5 15043.6 15100.0 15124.5 15179.5 7542.1 8519.0 8735.3 8757.4 8759.4 6366.0 6423.8 6948.8 7001.7 7016.0 12882.4 13245.1 13401.5 13497.1 13605.0 12281.4 12684.4 13654.3 13754.5 13975.7 9289.2 9365.7 9438.0 9453.1 9487.8 10553.6 10782.6 10794.7 10799.2 10808.5 8958.4 9001.5 9160.7 9188.7 9337.2 8295.5 8887.2 8917.9 8986.7 9100.7 8127.1 8321.0 8342.2 8359.8 8361.5 5240.9 5857.9 5990.8 6001.3 6294.7 6078.1 6121.2 6224.3 6226.1 6307.2 10:53:23 INFO - PID 1652 | 10:53:23 INFO - PID 1652 | [#4] prototype.html Cycles:180 Average:5408.74 Median:6114.84 stddev:1743.16 (28.5%) stddev-sans-first:1706.34 10:53:23 INFO - PID 1652 | Values: 358.8 401.6 405.5 405.9 416.5 5328.3 6310.4 6386.3 6401.0 6418.9 5699.9 5944.8 6023.5 6130.2 6246.8 4258.0 4677.7 4687.3 4714.9 4721.2 4727.7 4737.3 4747.7 4750.9 4757.6 3309.5 3537.8 4449.7 4481.7 4495.0 3785.0 3952.7 4507.3 4515.0 4548.6 3509.8 3850.7 3870.6 3901.0 3906.5 4901.9 5888.3 6026.2 6048.9 6077.3 4621.4 5314.8 5348.5 5368.4 5373.8 6269.7 6320.2 6518.5 6533.4 6587.7 4335.6 4381.2 4406.4 4407.4 4414.7 6832.6 7021.3 7022.4 7120.6 7137.8 4307.3 4395.8 4421.4 4445.0 4463.5 3824.8 3959.4 4519.5 4548.6 4567.8 4641.9 5286.3 5350.3 5361.6 5372.0 5461.5 6656.3 6671.5 6691.5 6731.7 1369.7 1413.7 1534.4 1544.3 1544.4 844.3 998.6 1000.8 1003.1 1019.0 6099.5 6396.6 6406.6 6423.4 6429.8 6761.8 6925.9 6983.6 7008.7 7048.6 5584.4 6288.3 6314.8 6342.8 6407.3 3526.5 3605.7 3634.0 3652.3 3657.4 6300.7 7044.8 7102.0 7163.2 7169.4 6152.9 6465.9 7055.5 7170.8 7190.6 6887.9 6997.5 7231.4 7250.1 7280.9 6967.7 7017.7 7106.8 7116.2 7152.9 6300.8 6791.8 6969.3 6976.8 7015.8 6579.7 7025.4 7266.3 7283.2 7290.3 4850.6 4952.9 5115.0 5152.1 5181.9 6300.7 6639.2 6935.0 7096.4 7114.4 5328.6 5848.7 6734.2 6818.5 6826.9 5254.8 5534.8 6681.9 6723.3 6820.6 6621.6 6626.4 6642.8 6650.8 6662.7 6570.4 6694.7 6730.7 6766.8 6826.2 5955.5 5960.6 6447.0 6537.5 6637.8 10:53:23 INFO - PID 1652 | 10:53:23 INFO - PID 1652 | [#5] yui.html Cycles:180 Average:21841.85 Median:17474.19 stddev:30990.76 (177.4%) stddev-sans-first:31037.48 10:53:23 INFO - PID 1652 | Values: 814.8 827.3 837.5 852.7 861.7 14262.1 14906.3 16638.5 17021.0 17029.6 14390.6 16873.7 17282.2 17657.9 17762.6 971.8 998.1 1004.0 1005.4 1006.5 1008.2 1009.6 1009.7 1012.3 1012.9 1048.4 1063.6 1068.5 1071.1 1072.1 1049.3 1065.8 1066.7 1066.8 1068.8 771.4 783.5 784.4 787.0 788.8 15656.5 17308.3 17566.7 17567.6 17589.4 16516.0 16928.8 17092.1 17330.1 17381.7 14055.9 14188.2 14298.1 14308.4 14333.9 3371.5 3387.8 3391.4 3441.8 3446.0 92149.0 126227.0 129356.0 130629.3 131060.0 12047.0 12317.3 12368.6 12374.4 12379.6 20921.3 21084.8 21309.5 21318.9 21583.0 5590.4 6947.4 7579.0 7850.8 7933.2 3613.8 3823.1 3890.0 3985.2 3987.3 4127.7 4167.4 4234.3 4235.4 4239.9 843.4 862.7 867.9 867.9 868.0 560.0 630.6 642.3 647.8 695.3 10350.5 10494.8 11581.3 12132.6 12302.6 591.8 638.7 643.6 644.9 659.9 5544.8 5626.5 5639.3 5723.2 5733.5 81757.8 105753.4 106900.5 107054.5 110636.0 95603.6 96178.0 96431.0 96556.5 97309.5 75833.0 83874.0 85246.0 86867.0 86875.0 10848.6 11312.9 11355.0 11358.5 11559.2 33803.7 41323.2 41326.0 41990.0 43575.8 52810.0 54391.5 63741.4 67154.3 68605.0 15787.8 16767.9 17026.6 17080.4 17158.4 17802.3 18619.8 18661.6 18704.0 18811.3 16155.3 16306.7 17005.6 17066.9 17171.7 16785.0 17032.3 17139.8 17174.6 17329.5 13395.6 16693.2 16855.2 17089.2 17242.0 11651.2 11731.6 12156.8 12213.5 12363.8 12350.3 12847.5 13064.2 13511.3 13852.0 10:53:23 INFO - PID 1652 | -------- Summary: end -------- 10:53:23 INFO - PID 1652 | 10:53:24 INFO - TEST-INFO | 1652: exit 0 10:53:24 INFO - TEST-OK | dromaeo_css | took 1114356ms 10:53:24 INFO - TEST-START | kraken 10:53:24 INFO - operating with platform_type : mac_ 10:53:24 INFO - Initialising browser for kraken test... 10:53:24 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp1e65el/profile http://localhost:49249/getInfo.html) 10:53:26 INFO - PID 1663 | 2017-05-11 10:53:26.350 plugin-container[1664:11724] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 10:53:26 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:53:26 INFO - PID 1663 | 2017-05-11 10:53:26.352 plugin-container[1664:11724] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 10:53:26 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:53:26 INFO - PID 1663 | 2017-05-11 10:53:26.352 plugin-container[1664:11724] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:53:26 INFO - PID 1663 | __metrics Screen width/height:1600/1200 10:53:26 INFO - PID 1663 | colorDepth:24 10:53:26 INFO - PID 1663 | Browser inner width/height: 1024/691 10:53:26 INFO - PID 1663 | __metrics 10:53:26 INFO - PID 1663 | 10:53:26 INFO - PID 1663 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 10:53:26 INFO - PID 1663 | 10:53:27 INFO - TEST-INFO | 1663: exit 0 10:53:27 INFO - Browser initialized. 10:53:27 INFO - Running cycle 1/1 for kraken test... 10:53:27 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp1e65el/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:53:29 INFO - PID 1666 | 2017-05-11 10:53:29.689 plugin-container[1667:11878] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463b, name = 'com.apple.tsm.portname' 10:53:29 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:53:29 INFO - PID 1666 | 2017-05-11 10:53:29.690 plugin-container[1667:11878] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463f, name = 'com.apple.CFPasteboardClient' 10:53:29 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:53:29 INFO - PID 1666 | 2017-05-11 10:53:29.690 plugin-container[1667:11878] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:53:30 INFO - PID 1666 | 2017-05-11 10:53:30.282 plugin-container[1668:11920] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 10:53:30 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:53:30 INFO - PID 1666 | 2017-05-11 10:53:30.282 plugin-container[1668:11920] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 10:53:30 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:53:30 INFO - PID 1666 | 2017-05-11 10:53:30.282 plugin-container[1668:11920] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:53:39 INFO - PID 1666 | RSS: Main: 228880384 10:53:39 INFO - PID 1666 | 10:53:42 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 10:53:43 INFO - PID 1666 | RSS: Main: 242397184 10:53:43 INFO - PID 1666 | 10:53:45 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 10:53:46 INFO - PID 1666 | RSS: Main: 250535936 10:53:46 INFO - PID 1666 | 10:53:49 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 10:53:50 INFO - PID 1666 | RSS: Main: 252821504 10:53:50 INFO - PID 1666 | 10:53:52 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 10:53:52 INFO - PID 1666 | RSS: Main: 255201280 10:53:52 INFO - PID 1666 | 10:53:55 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:53:55 INFO - PID 1666 | RSS: Main: 255741952 10:53:55 INFO - PID 1666 | 10:53:58 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 10:53:59 INFO - PID 1666 | RSS: Main: 261758976 10:53:59 INFO - PID 1666 | 10:54:02 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 10:54:03 INFO - PID 1666 | RSS: Main: 263745536 10:54:03 INFO - PID 1666 | 10:54:06 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 10:54:06 INFO - PID 1666 | RSS: Main: 263057408 10:54:06 INFO - PID 1666 | 10:54:08 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:54:08 INFO - PID 1666 | RSS: Main: 262246400 10:54:08 INFO - PID 1666 | 10:54:10 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:54:11 INFO - PID 1666 | RSS: Main: 262377472 10:54:11 INFO - PID 1666 | 10:54:13 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:54:14 INFO - PID 1666 | RSS: Main: 261943296 10:54:14 INFO - PID 1666 | 10:54:17 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:54:18 INFO - PID 1666 | RSS: Main: 262189056 10:54:18 INFO - PID 1666 | 10:54:21 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:54:21 INFO - PID 1666 | RSS: Main: 262160384 10:54:21 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 10:54:23 INFO - PID 1666 | RSS: Main: 265084928 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | __start_tp_report 10:54:23 INFO - PID 1666 | _x_x_mozilla_page_load 10:54:23 INFO - PID 1666 | _x_x_mozilla_page_load_details 10:54:23 INFO - PID 1666 | |i|pagename|runs| 10:54:23 INFO - PID 1666 | |0;ai-astar;102;116;115;114;114;113;115;115;115;114 10:54:23 INFO - PID 1666 | |1;audio-beat-detection;135;112;111;151;114;130;146;96;163;145 10:54:23 INFO - PID 1666 | |2;audio-dft;193;196;199;212;209;222;193;200;199;196 10:54:23 INFO - PID 1666 | |3;audio-fft;72;58;57;87;74;49;65;82;90;87 10:54:23 INFO - PID 1666 | |4;audio-oscillator;77;73;92;90;101;108;85;74;94;112 10:54:23 INFO - PID 1666 | |5;imaging-gaussian-blur;96;91;90;90;91;90;91;116;139;134 10:54:23 INFO - PID 1666 | |6;imaging-darkroom;98;99;98;98;99;97;104;91;98;92 10:54:23 INFO - PID 1666 | |7;imaging-desaturate;107;127;115;104;90;89;103;84;148;92 10:54:23 INFO - PID 1666 | |8;json-parse-financial;51;50;51;50;51;56;70;55;43;67 10:54:23 INFO - PID 1666 | |9;json-stringify-tinderbox;56;56;71;68;80;85;78;68;49;77 10:54:23 INFO - PID 1666 | |10;stanford-crypto-aes;89;104;82;76;73;100;106;106;84;79 10:54:23 INFO - PID 1666 | |11;stanford-crypto-ccm;135;140;135;138;140;157;140;137;226;147 10:54:23 INFO - PID 1666 | |12;stanford-crypto-pbkdf2;181;183;185;216;185;165;204;241;187;182 10:54:23 INFO - PID 1666 | |13;stanford-crypto-sha256-iterative;66;68;66;70;69;82;90;68;66;67 10:54:23 INFO - PID 1666 | __end_tp_report 10:54:23 INFO - PID 1666 | __start_cc_report 10:54:23 INFO - PID 1666 | _x_x_mozilla_cycle_collect,972 10:54:23 INFO - PID 1666 | __end_cc_report 10:54:23 INFO - PID 1666 | __startTimestamp1494525263773__endTimestamp 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | ------- Summary: start ------- 10:54:23 INFO - PID 1666 | Number of tests: 14 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | [#0] ai-astar Cycles:10 Average:113.30 Median:115.00 stddev:4.06 (3.5%) stddev-sans-first:0.88 10:54:23 INFO - PID 1666 | Values: 102.0 116.0 115.0 114.0 114.0 113.0 115.0 115.0 115.0 114.0 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | [#1] audio-beat-detection Cycles:10 Average:130.30 Median:145.50 stddev:21.43 (14.7%) stddev-sans-first:22.66 10:54:23 INFO - PID 1666 | Values: 135.0 112.0 111.0 151.0 114.0 130.0 146.0 96.0 163.0 145.0 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | [#2] audio-dft Cycles:10 Average:201.90 Median:199.50 stddev:9.46 (4.7%) stddev-sans-first:9.47 10:54:23 INFO - PID 1666 | Values: 193.0 196.0 199.0 212.0 209.0 222.0 193.0 200.0 199.0 196.0 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | [#3] audio-fft Cycles:10 Average:72.10 Median:78.00 stddev:14.44 (18.5%) stddev-sans-first:15.32 10:54:23 INFO - PID 1666 | Values: 72.0 58.0 57.0 87.0 74.0 49.0 65.0 82.0 90.0 87.0 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:81.00 stddev:13.68 (16.9%) stddev-sans-first:13.60 10:54:23 INFO - PID 1666 | Values: 77.0 73.0 92.0 90.0 101.0 108.0 85.0 74.0 94.0 112.0 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:102.80 Median:90.50 stddev:19.48 (21.5%) stddev-sans-first:20.50 10:54:23 INFO - PID 1666 | Values: 96.0 91.0 90.0 90.0 91.0 90.0 91.0 116.0 139.0 134.0 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | [#6] imaging-darkroom Cycles:10 Average:97.40 Median:98.00 stddev:3.66 (3.7%) stddev-sans-first:3.87 10:54:23 INFO - PID 1666 | Values: 98.0 99.0 98.0 98.0 99.0 97.0 104.0 91.0 98.0 92.0 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | [#7] imaging-desaturate Cycles:10 Average:105.90 Median:116.00 stddev:19.79 (17.1%) stddev-sans-first:20.99 10:54:23 INFO - PID 1666 | Values: 107.0 127.0 115.0 104.0 90.0 89.0 103.0 84.0 148.0 92.0 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:53.00 stddev:8.22 (15.5%) stddev-sans-first:8.63 10:54:23 INFO - PID 1666 | Values: 51.0 50.0 51.0 50.0 51.0 56.0 70.0 55.0 43.0 67.0 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:74.00 stddev:11.86 (16.0%) stddev-sans-first:11.64 10:54:23 INFO - PID 1666 | Values: 56.0 56.0 71.0 68.0 80.0 85.0 78.0 68.0 49.0 77.0 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:77.50 stddev:12.97 (16.7%) stddev-sans-first:13.76 10:54:23 INFO - PID 1666 | Values: 89.0 104.0 82.0 76.0 73.0 100.0 106.0 106.0 84.0 79.0 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:140.00 stddev:27.68 (19.8%) stddev-sans-first:28.86 10:54:23 INFO - PID 1666 | Values: 135.0 140.0 135.0 138.0 140.0 157.0 140.0 137.0 226.0 147.0 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.90 Median:186.00 stddev:21.77 (11.7%) stddev-sans-first:22.66 10:54:23 INFO - PID 1666 | Values: 181.0 183.0 185.0 216.0 185.0 165.0 204.0 241.0 187.0 182.0 10:54:23 INFO - PID 1666 | 10:54:23 INFO - PID 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:68.50 stddev:8.13 (11.9%) stddev-sans-first:8.41 10:54:23 INFO - PID 1666 | Values: 66.0 68.0 66.0 70.0 69.0 82.0 90.0 68.0 66.0 67.0 10:54:23 INFO - PID 1666 | -------- Summary: end -------- 10:54:23 INFO - PID 1666 | 10:54:24 INFO - TEST-INFO | 1666: exit 0 10:54:24 INFO - TEST-OK | kraken | took 60772ms 10:54:24 INFO - SUITE-END | took 1175s 10:54:24 INFO - Completed test suite (00:19:35) 10:54:24 INFO - javascript benchmark 10:54:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7872.075048507114, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2850.315924178197, 3596.982015089924, 3631.782093074414, 3657.817109144542, 3662.8901132966002, 5931.466168044875, 6127.220070483963, 6189.133521307015, 6211.068339749028, 6294.181756371671, 6198.628082315065, 6217.751289948408, 6227.221597300338, 6275.874482510347, 6336.42972132508, 5077.873053173664, 5359.844820486862, 5461.15352120422, 5503.091989821674, 5527.364353099395, 5634.4365563443735, 5638.718064096779, 5652.406497317784, 5669.376368599451, 5710.314762228539, 5424.105022671254, 5501.119820828688, 5517.36550296713, 5536.584756143306, 5570.944290557123, 5579.9163012554845, 5603.8879222415735, 5614.550835933115, 5617.634853734494, 5628.690422026787, 5514.172874068881, 5523.392426833086, 5532.059549876491, 5557.194206839986, 5589.273394458735, 5068.594512438996, 6146.84632884177, 6207.937920620781, 6298.0, 6310.211223597051, 6732.394084532416, 6804.08144900437, 6828.52200345976, 6838.760643377458, 6846.554973926729, 6894.13823272091, 6905.274946130685, 6910.861782764367, 6927.376536111774, 6940.826479338007, 6210.254769427697, 6333.081703153109, 6411.455026322721, 6427.196600424947, 6429.871402571923, 6894.172699276019, 6905.205901321312, 6996.440284777205, 6996.440284777205, 7056.717731290691, 6461.160049193575, 6577.177852768404, 6590.835229119214, 6685.0640910272605, 6713.0, 6765.255821859591, 6877.724891004404, 6908.965455172692, 6914.170299564084, 6914.654267286615, 6799.150106236721, 6809.693563789641, 6828.6927088281145, 6830.8633827324165, 6855.622940738208, 6565.80302590923, 6650.534462587572, 6710.0941372915, 6776.42400395962, 6843.657817109224, 5429.864253393696, 5435.021696094741, 5438.945610543923, 5440.646357986719, 5470.234167216593, 4741.881452963704, 4764.49972752856, 4816.036792641486, 4844.636652251095, 4872.293517439952, 5245.344331958505, 6115.027386288523, 6307.0, 6368.29948705642, 6430.003349480838, 6865.588064716133, 6883.346082122191, 6910.136232970879, 6936.965315173493, 6950.895736563955, 4764.8332308369045, 6102.603330783573, 6286.277078136073, 6465.224173099165, 6469.51478639104, 5890.852728681732, 5969.164316995537, 6055.455009049207, 6083.726232319556, 6128.724207410677, 6995.545289556163, 7038.718451261892, 7075.115610548682, 7089.361957423856, 7093.645317733989, 6877.518573699793, 6953.4089602384365, 6960.0, 6963.40811031068, 6969.954506824017, 6479.676016199265, 6677.964915438115, 6790.049393085071, 7063.71745130189, 7120.181179164462, 6658.334166583303, 6696.866062678817, 6753.662316884234, 6805.319468053353, 6813.3527314905, 6865.107536020485, 6879.96800479912, 6910.20532638753, 6915.481338899502, 6927.930720692729, 5480.753366098455, 6423.550351475352, 6579.374959378852, 6610.603363797995, 6673.1992160941, 5863.032599621015, 6463.7737679181, 6636.170478690164, 6727.159105111861, 6748.223954245128, 6373.235211774431, 6560.081588577507, 6607.339266073354, 6614.140161778938, 6628.635425051669, 6530.575512591667, 6625.072489851328, 6637.469002479694, 6685.0, 6702.865942681022, 5896.9115463268945, 6187.535934804818, 6518.185226846645, 6543.443807276435, 6562.606243625207], "value": 6183.48312144061, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.2698491985396, 795.824918517926, 821.6343727041465, 1214.3503225774207, 1272.929988850614, 38321.0, 39455.40816887749, 39457.80271098648, 39470.40794388086, 39688.40467392988, 39601.207975840465, 40037.39943900844, 40053.198936021334, 40087.59912400875, 40119.79940100295, 7931.167227441122, 8061.838763224732, 8434.915650843473, 8644.222020018196, 8727.432716873383, 7467.925320746805, 8665.610047547843, 8691.913510811148, 8760.824783504358, 8764.1674040966, 8712.172343627377, 8762.518061506615, 8786.209241168293, 8788.780280492974, 8791.20879120879, 8761.824763504757, 8769.868451973225, 8780.429272097357, 8785.516796576187, 8790.989036260848, 8518.0, 8534.829303413959, 8569.314454843598, 8578.785530361729, 8612.095729948327, 38389.808050959844, 38603.61396386056, 38718.80640596779, 38750.612493874985, 38940.0, 3646.033801042726, 3654.214343916044, 3678.6321367863266, 3686.189038411545, 3693.409054551286, 31330.373392532023, 31508.684913150806, 31622.209444763834, 31647.050588482383, 31761.84119079413, 3174.603174603167, 3213.084270982782, 3213.791103578283, 3215.6623554526673, 3234.87060517579, 239745.0, 243240.0, 277381.99999999796, 289114.0, 291531.62702559476, 37158.8142059288, 49180.50819491831, 49661.51015469542, 50144.247836282484, 50219.49780502221, 41495.0, 42259.7887010563, 43087.56912430836, 50508.49491505112, 52407.0, 11878.40607969598, 14417.0, 14537.418503259963, 14556.417743290362, 14605.56183314481, 11785.351805650771, 11969.521219151136, 11971.0, 11999.400029998465, 12003.979661728676, 13960.301984900714, 14035.157890526601, 14074.225917574631, 14152.929235353757, 14585.124892506483, 6159.19930409044, 6207.689615519206, 6209.6895155242055, 6212.565120441616, 6226.968865155645, 987.5013118375318, 989.5052473763119, 1028.7582418131733, 1031.5409642708923, 1039.5374058544026, 23205.883970580377, 25311.746882531308, 25339.493210135694, 25372.365690857507, 25455.36361590975, 998.9550470228858, 1084.7342401111778, 1113.565709373345, 1154.3939431798206, 1166.859976802789, 12337.581178164446, 12532.561360352527, 13124.671883202805, 13125.60623181306, 13449.663758406117, 234923.65076349358, 316410.0, 327861.0, 328430.0, 329168.0, 56840.43159568434, 56996.0, 57349.13976290359, 57529.56176095631, 57533.0, 1527.2821773766311, 1542.8891198337178, 1575.6139745762132, 1588.0471716969726, 1593.8963967342086, 24646.923473638304, 24844.130455433973, 25027.594783711807, 25052.123175688765, 25072.498550028533, 40941.79529102335, 41043.589564103975, 41111.58888411077, 41152.38271425871, 41181.0, 262797.6860115687, 264654.0, 264913.0, 265222.0, 265370.0, 22435.551288974457, 30361.544576830922, 33453.16367090842, 34103.82948085244, 36258.27483450369, 42740.0, 43107.353389699805, 45301.3373328334, 45411.77294113508, 45566.54433455623, 30757.23106922345, 31002.534961975136, 31179.0, 31229.53155702621, 31272.843635781675, 30161.245968850955, 30212.09363719092, 30934.0, 31213.219669508442, 31297.530537042418, 37123.0, 37156.62843371532, 37254.25491490209, 37316.0, 37396.43905341368, 21907.890460547595, 29437.0, 30438.391232175676, 31046.534301985037, 31852.725890964102, 23397.181098661466, 24292.75707242905, 24310.75689243085, 24478.755212447646, 24568.0], "value": 17686.10286769159, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.54468263640524, 436.95567593451653, 437.3658195616356, 452.33055078483835, 461.53615616305626, 17124.0, 20604.690929636068, 21288.787112128874, 21945.01247443865, 22076.668849967264, 21752.456188595293, 21929.890350548263, 21976.011079501423, 22178.889105554448, 22341.99461024254, 5916.615419997708, 6163.414475624831, 6214.81918435498, 6217.875642487147, 6219.751209951596, 5835.18307436957, 6172.4136207060255, 6204.224471941007, 6210.65841378724, 6215.471684906788, 6096.4817990470865, 6180.258368995726, 6180.474659653934, 6191.690415479231, 6196.318404975449, 5065.468125846806, 5964.314103878066, 6200.689965501707, 6202.9379706203035, 6204.6587437690905, 5424.267723857308, 5431.755570999315, 5451.182322651594, 5493.093639549469, 5512.614117011811, 26299.0, 26450.0, 26503.73496265032, 26512.0, 26838.46323073547, 2151.5911976724374, 2203.768604296558, 2212.4026512841606, 2212.4247695599097, 2215.3575463115676, 16252.918735406365, 16984.57538561546, 17045.7443138353, 17214.225359858836, 17722.822771772248, 1890.2250077468173, 2119.7456305243313, 2159.805617494417, 2164.2425151197112, 2164.6536554151417, 138534.0, 147782.7832582512, 156259.0, 159868.0, 161398.0, 11800.291982481078, 12432.637798818376, 12478.18891772032, 12480.188787728768, 12496.750064998649, 13198.746119118669, 14334.283285835667, 14894.776578351333, 15209.771853422208, 15987.040777553384, 7975.042994840656, 8698.652053917773, 8886.24466920306, 8893.955530222307, 9009.279257659371, 6944.4097251734165, 7143.107111611049, 7957.164497727771, 8515.57422128891, 8550.187732165434, 7665.271799179069, 7744.961275193701, 7795.883061754078, 7815.0, 7841.1181316483635, 2190.5206584476596, 2204.3386983904766, 2206.7491159017713, 2210.160139147114, 2414.058517178267, 845.8308338332358, 950.4439902657023, 962.6090299517131, 964.9324547281764, 967.4678926590348, 19627.0, 20322.796772032387, 20464.38606841797, 20553.177872885215, 20719.171233150806, 978.6525783346951, 985.610683779903, 1008.6822650864954, 1020.9336393134422, 1021.1983592879554, 10238.948805255926, 10519.84220236697, 10525.894741052645, 10592.0, 10608.893911060944, 482199.0, 483991.0, 488743.0, 495441.0, 503112.0, 116217.41891290489, 117723.0, 118339.0, 121140.39429802794, 122415.0, 1160.5531870230022, 1164.6040346282305, 1188.1267268557604, 1190.101473387604, 1240.553400775738, 21322.786772132702, 21968.89015554912, 21999.560008800054, 22031.889840550695, 22237.999290031665, 50224.748876255384, 51522.71193220199, 56704.0, 57785.42214577969, 57949.0, 74663.0, 75042.62478687572, 75757.86363204446, 77430.0, 78625.60687196527, 7729.574873381793, 8042.83914321722, 8548.74353769388, 8619.784505387164, 8852.159044890725, 11418.885811142114, 12674.873251267738, 12960.345955447205, 13299.0, 13352.399142038437, 9976.501174941079, 10669.57321707123, 11245.437728113726, 11387.943060284977, 11462.770744585228, 9256.768580785534, 9888.802223955337, 10330.535125919201, 10440.947795260976, 10603.310784798963, 9533.618655253713, 11439.110702361237, 11831.940840295743, 11867.940660296643, 11975.401229938294, 8566.742997710078, 8601.311895048506, 8635.222829945335, 8753.824923501621, 8756.868646970173, 11177.552897883994, 11284.830727539258, 11528.59649912249, 11726.94136529312, 11823.231489953125], "value": 10162.159350558479, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1396.588006538071, 1423.3452611798573, 1439.820022497188, 1449.9855001449982, 1470.5073800276907, 11337.546498140064, 11372.0, 11872.168948173632, 11880.465379057941, 12025.639230823068, 11103.278286911365, 11452.656420307383, 11593.768124637501, 11745.47145378458, 11767.117466190028, 2474.268012391324, 2536.441982763789, 2538.4923015396903, 2541.0217066429373, 2543.504016716741, 2453.288546321565, 2514.170323793153, 2516.245126462063, 2520.785733212679, 2521.2814347910758, 2500.8999640014376, 2504.3488692939786, 2526.494701059786, 2526.5578523758268, 2529.6079107738237, 2450.277168235375, 2470.851748895063, 2498.762617551348, 2511.485145545159, 2513.0576033987254, 2419.9274021779197, 2476.839005464657, 2506.1854897158496, 2506.987465062681, 2533.214703441939, 11634.94182529082, 11666.183367164302, 11729.178957472981, 11734.178607497479, 11755.412229388496, 6543.149390579194, 6750.763723269662, 6802.183737951428, 6822.727090916357, 6843.520953533254, 4027.959720402817, 4355.085432059272, 4406.361077643724, 4425.734455932655, 4439.511653718088, 1238.5107882386408, 1240.4727990603958, 1262.7600755856358, 1296.8119622654756, 1310.488909325364, 16083.195840207944, 16462.83537164625, 16616.584585385343, 16621.6675666486, 16728.24722887473, 4583.9083218335445, 5601.243832082698, 5762.0121058234845, 5788.5369170466265, 5791.478766910914, 11599.88400116005, 11707.414629268502, 11767.52929882795, 11779.352135632622, 11796.29222246668, 6536.281009089092, 6602.7689030883685, 6634.170728658918, 6644.8671026579195, 6649.368310010541, 5048.62135339851, 6026.246719160105, 6237.345078766755, 6242.719077641517, 6258.874822503524, 4252.617264446215, 4407.537208593116, 4440.6447484201835, 4443.355713421536, 4494.325851122292, 1265.949362025527, 1282.3139620303093, 1286.8906142977764, 1297.4810075969688, 1303.3548393545257, 661.751843058853, 733.625850816077, 751.7406494759299, 781.8553567590014, 822.576373167819, 5086.415062267886, 6118.877622447526, 6276.278228003838, 6346.079818426395, 6409.807705768834, 561.5479538971167, 585.6749504025307, 596.2517041113462, 614.8901233273895, 622.4024936061342, 3985.5416627087675, 4275.593818587229, 4342.0, 4360.9127817444105, 4480.193565158238, 14355.497557585435, 14520.98353115293, 14754.96715229945, 14853.628659283604, 14875.181864997312, 14886.478973235884, 15043.623909402353, 15100.0, 15124.470643527424, 15179.544613661608, 7542.094948606203, 8519.0, 8735.34484913634, 8757.430767000125, 8759.430637008574, 6365.974007922652, 6423.775167869102, 6948.756793512202, 7001.687755285357, 7016.017757514054, 12882.35588220604, 13245.139065960682, 13401.463941442233, 13497.122687025312, 13605.0, 12281.385930703607, 12684.429200685807, 13654.317284135952, 13754.487006429084, 13975.720485590027, 9289.16397524226, 9365.719028429157, 9438.0, 9453.149216570542, 9487.810243795224, 10553.577856885639, 10782.622608208674, 10794.676159715222, 10799.244052916221, 10808.513616887101, 8958.417702849294, 9001.504917229353, 9160.6793762218, 9188.724338269863, 9337.206337461392, 8295.502269863828, 8887.244584210415, 8917.910820891708, 8986.68546600892, 9100.726978190665, 8127.1466496017065, 8321.00147982246, 8342.207490288412, 8359.791005224917, 8361.498310101413, 5240.899411123707, 5857.8535536610225, 5990.760369585168, 6001.279846418458, 6294.653794041372, 6078.149059131637, 6121.204243448501, 6224.284207316216, 6226.097215903578, 6307.24313082433], "value": 5310.156188118721, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.7829363235241, 401.6365189503499, 405.53161098930735, 405.8721502726641, 416.4731614507646, 5328.307320048393, 6310.400511951369, 6386.2975072741965, 6400.967995160017, 6418.871622567552, 5699.917015767011, 5944.821655350335, 6023.548233882465, 6130.233720784902, 6246.843828904268, 4257.957420425787, 4677.74272415017, 4687.273472611733, 4714.90570188596, 4721.220998535237, 4727.692699974491, 4737.265723812815, 4747.667663263573, 4750.857474275777, 4757.571818536331, 3309.470484722445, 3537.8325152699676, 4449.710768800018, 4481.708688935209, 4494.988627558806, 3784.9621503784883, 3952.6837852971835, 4507.346434766941, 4514.954850451502, 4548.56788605082, 3509.8245087745768, 3850.6726928211137, 3870.593587673282, 3900.980495097535, 3906.4726261954556, 4901.852944411673, 5888.29340479141, 6026.2165918430765, 6048.909266361008, 6077.301110372319, 4621.399218101659, 5314.813981510667, 5348.539671750999, 5368.35579730431, 5373.785048598052, 6269.655168965705, 6320.209973753281, 6518.500744828669, 6533.41199292066, 6587.670616469205, 4335.56644335567, 4381.233284175257, 4406.383106365111, 4407.382966384676, 4414.690971631987, 6832.555883867532, 7021.332973367522, 7022.367986881205, 7120.572765634027, 7137.785866424016, 4307.267764480046, 4395.824167033346, 4421.425214722066, 4445.0, 4463.486699029653, 3824.8470061197795, 3959.366501359826, 4519.480259770103, 4548.566516913502, 4567.771611419415, 4641.907161856811, 5286.312779338738, 5350.30446042012, 5361.584799352682, 5372.0, 5461.508464238238, 6656.334366563402, 6671.499637527108, 6691.5315927884585, 6731.697073631698, 1369.6849724563406, 1413.724323756878, 1534.3939144038043, 1544.2819089123611, 1544.4131230132477, 844.2612713875359, 998.610541888664, 1000.8390267289909, 1003.1322905686675, 1018.981018981019, 6099.451049405575, 6396.5522413431545, 6406.6476343800605, 6423.389777971085, 6429.7749578764515, 6761.830954226085, 6925.930740692629, 6983.615901125487, 7008.719651213996, 7048.568243955827, 5584.392875877417, 6288.276848162428, 6314.8001879642725, 6342.809715708536, 6407.327230640715, 3526.541549598587, 3605.729570282187, 3633.9818300908855, 3652.3243200008083, 3657.396529572644, 6300.684965751786, 7044.8023835948015, 7101.964490177619, 7163.2478589747325, 7169.354758071799, 6152.938470615326, 6465.870682586322, 7055.506114571931, 7170.820729481805, 7190.64046797658, 6887.896681549781, 6997.545159564612, 7231.385332246713, 7250.129984401906, 7280.890786638099, 6967.686454109678, 7017.7192912282935, 7106.751263705745, 7116.181639111568, 7152.892706609301, 6300.810975670737, 6791.796246112624, 6969.30306969299, 6976.790696279119, 7015.754448594274, 6579.697106012857, 7025.367716905503, 7266.309700578437, 7283.162436319891, 7290.307420795016, 4850.6362022847725, 4952.851414457572, 5115.002574498086, 5152.098382783073, 5181.92227116586, 6300.739852029521, 6639.203295604559, 6935.0, 7096.387348223696, 7114.430845532448, 5328.600354973315, 5848.707564621837, 6734.225564060196, 6818.488613354118, 6826.93173068263, 5254.763535640982, 5534.7786088556, 6681.861804222602, 6723.2940541244425, 6820.624865632438, 6621.635810030494, 6626.370494802986, 6642.83392915181, 6650.83372915681, 6662.734080524685, 6570.375814297634, 6694.665266736741, 6730.697118629576, 6766.7969960901255, 6826.249112597709, 5955.523558115254, 5960.612560183574, 6446.967765161145, 6537.5096867734155, 6637.767678131242], "value": 4854.245325763457, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [814.7718638781143, 827.2637352756052, 837.526797359492, 852.7228650688528, 861.6854847980485, 14262.144271343726, 14906.329215185315, 16638.50084497464, 17020.978741275518, 17029.57426064349, 14390.640233994156, 16873.662526749456, 17282.222299996498, 17657.91171044143, 17762.64474710508, 971.7764914069786, 998.1465846701075, 1003.9598416063385, 1005.3867141043958, 1006.4565134827185, 1008.2236677758159, 1009.581023875091, 1009.6516701737888, 1012.3015119567511, 1012.9088382045614, 1048.36573872807, 1063.5852017713098, 1068.5031460370926, 1071.0842229893408, 1072.126217133038, 1049.3389164826149, 1065.8028264771042, 1066.7333166708322, 1066.8239740442818, 1068.839674048891, 771.3751860992614, 783.5337973905517, 784.4116912315764, 786.9803254918615, 788.8304014636881, 15656.452024179212, 17308.307667693276, 17566.7364989524, 17567.648647027116, 17589.384371546934, 16516.009039457673, 16928.830711692848, 17092.145392730316, 17330.13349332541, 17381.65236695259, 14055.929720351332, 14188.21964791936, 14298.142111473346, 14308.356123974447, 14333.928330358385, 3371.4605663094035, 3387.779794313362, 3391.355642427931, 3441.827908604585, 3445.9827700861333, 92149.0, 126227.0, 129356.0, 130629.34685326608, 131060.0, 12046.976007039324, 12317.260964342167, 12368.628941131781, 12374.443150058269, 12379.566715164927, 20921.26775562848, 21084.84033378179, 21309.46726331854, 21318.893405532872, 21583.02876370567, 5590.413006634326, 6947.3747362737595, 7578.962105189549, 7850.764477065697, 7933.1670174630835, 3613.78938055749, 3823.063349479395, 3889.96110038896, 3985.20295940813, 3987.342088555356, 4127.731697439657, 4167.416561681367, 4234.280172370704, 4235.428217190702, 4239.894002649959, 843.409613270713, 862.7153039497076, 867.8828358171693, 867.9392442529089, 867.9522626255616, 559.9692016939107, 630.5901164243279, 642.2678146912524, 647.7765171015992, 695.2803848017379, 10350.53422595985, 10494.845566987626, 11581.33402584958, 12132.63602091921, 12302.569410070604, 591.7810410148187, 638.7445021991241, 643.6170478565247, 644.8549076457759, 659.8911179655399, 5544.750486228129, 5626.549876009991, 5639.2668953035845, 5723.227364305765, 5733.455321744427, 81757.78027746531, 105753.41369879458, 106900.46549767358, 107054.46472767586, 110636.0, 95603.56594651086, 96178.0382196183, 96431.0, 96556.51721741346, 97309.51345243228, 75833.0, 83874.0, 85246.0, 86867.0, 86875.0, 10848.636962302193, 11312.9434352831, 11355.0, 11358.54565817391, 11559.248648837798, 33803.66196338005, 41323.1735365297, 41326.0, 41989.950251243965, 43575.78212108919, 52810.0, 54391.45608543864, 63741.36258637354, 67154.32845671481, 68605.0, 15787.842121578637, 16767.91616041912, 17026.574335641708, 17080.402185923434, 17158.39945601898, 17802.287908483515, 18619.81380186181, 18661.626767464848, 18704.0, 18811.341603043827, 16155.27301271422, 16306.67386652284, 17005.57486062858, 17066.91466542709, 17171.742423863903, 16785.0, 17032.318707251572, 17139.828601713823, 17174.570635734206, 17329.48011559655, 13395.598132056053, 16693.165341732623, 16855.24151413165, 17089.230984605474, 17242.0, 11651.242669226473, 11731.648050558497, 12156.75686486283, 12213.450394732436, 12363.75272494563, 12350.320732359807, 12847.486100555872, 13064.150830196008, 13511.256880871646, 13852.0], "value": 8306.631998833449, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 116.0, 115.0, 114.0, 114.0, 113.0, 115.0, 115.0, 115.0, 114.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 112.0, 111.0, 151.0, 114.0, 130.0, 146.0, 96.0, 163.0, 145.0], "value": 130.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 196.0, 199.0, 212.0, 209.0, 222.0, 193.0, 200.0, 199.0, 196.0], "value": 201.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 58.0, 57.0, 87.0, 74.0, 49.0, 65.0, 82.0, 90.0, 87.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 92.0, 90.0, 101.0, 108.0, 85.0, 74.0, 94.0, 112.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 91.0, 90.0, 90.0, 91.0, 90.0, 91.0, 116.0, 139.0, 134.0], "value": 102.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 98.0, 98.0, 99.0, 97.0, 104.0, 91.0, 98.0, 92.0], "value": 97.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 127.0, 115.0, 104.0, 90.0, 89.0, 103.0, 84.0, 148.0, 92.0], "value": 105.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 51.0, 50.0, 51.0, 56.0, 70.0, 55.0, 43.0, 67.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 71.0, 68.0, 80.0, 85.0, 78.0, 68.0, 49.0, 77.0], "value": 68.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 104.0, 82.0, 76.0, 73.0, 100.0, 106.0, 106.0, 84.0, 79.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 140.0, 135.0, 138.0, 140.0, 157.0, 140.0, 137.0, 226.0, 147.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 183.0, 185.0, 216.0, 185.0, 165.0, 204.0, 241.0, 187.0, 182.0], "value": 192.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 70.0, 69.0, 82.0, 90.0, 68.0, 66.0, 67.0], "value": 71.2, "unit": "score"}]}]} 10:54:25 INFO - Return code: 0 10:54:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:54:25 INFO - # TBPL SUCCESS # 10:54:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:54:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:54:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:54:25 INFO - 2017-05-11 10:54:25.143 screenresolution[1673:12309] starting screenresolution argv=screenresolution get 10:54:25 INFO - 2017-05-11 10:54:25.146 screenresolution[1673:12309] Display 0: 1600x1200x32@60 10:54:25 INFO - 2017-05-11 10:54:25.152 screenresolution[1674:12312] starting screenresolution argv=screenresolution list 10:54:25 INFO - Available Modes on Display 0 10:54:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:54:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:54:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:54:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:54:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:54:25 INFO - Intel Iris: 10:54:25 INFO - Chipset Model: Intel Iris 10:54:25 INFO - Type: GPU 10:54:25 INFO - Bus: Built-In 10:54:25 INFO - VRAM (Dynamic, Max): 1536 MB 10:54:25 INFO - Vendor: Intel (0x8086) 10:54:25 INFO - Device ID: 0x0a2e 10:54:25 INFO - Revision ID: 0x0009 10:54:25 INFO - Displays: 10:54:25 INFO - Display: 10:54:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:54:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:54:25 INFO - Display Serial Number: 1600x1200 60 10:54:25 INFO - Main Display: Yes 10:54:25 INFO - Mirror: Off 10:54:25 INFO - Online: Yes 10:54:25 INFO - Rotation: Supported 10:54:25 INFO - Return code: 0 10:54:25 INFO - Running post-action listener: _resource_record_post_action 10:54:25 INFO - [mozharness: 2017-05-11 17:54:25.394830Z] Finished run-tests step (success) 10:54:25 INFO - Running post-run listener: _resource_record_post_run 10:54:25 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 35128320; Write bytes: 526963712; Read time: 324; Write time: 1652 10:54:25 INFO - TinderboxPrint: CPU usage
53.9% 10:54:25 INFO - TinderboxPrint: I/O read bytes / time
35,128,320 / 324 10:54:25 INFO - TinderboxPrint: I/O write bytes / time
526,963,712 / 1,652 10:54:25 INFO - TinderboxPrint: CPU idle
2,205.8 (46.1%) 10:54:25 INFO - TinderboxPrint: CPU system
955.9 (20.0%) 10:54:25 INFO - TinderboxPrint: CPU user
1,623.9 (33.9%) 10:54:25 INFO - TinderboxPrint: Swap in / out
244,916,224 / 0 10:54:25 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205177344; Write bytes: 206391296; Read time: 15877; Write time: 442 10:54:25 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 34169856; Write bytes: 310631424; Read time: 309; Write time: 1175 10:54:25 INFO - Running post-run listener: _upload_blobber_files 10:54:25 INFO - Blob upload gear active. 10:54:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:54:25 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 10:54:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:54:26 INFO - (blobuploader) - INFO - Open directory for files ... 10:54:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:54:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:54:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:54:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:54:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:54:27 INFO - (blobuploader) - INFO - Done attempting. 10:54:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:54:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:54:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:54:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:54:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:54:28 INFO - (blobuploader) - INFO - Done attempting. 10:54:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:54:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:54:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:54:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:54:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:54:29 INFO - (blobuploader) - INFO - Done attempting. 10:54:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:54:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:54:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:54:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:54:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:54:31 INFO - (blobuploader) - INFO - Done attempting. 10:54:31 INFO - (blobuploader) - INFO - Iteration through files over. 10:54:31 INFO - Return code: 0 10:54:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:54:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:54:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f6fc0eac80ca1cdbb0a2863ca13e5bece614b66a5e903eeb2fcc13f90959ac23fd49f509757308038823f3c714afdeef8f2a5c201f5327a543a1e1b012730cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/177606b47295a2c3e594fbd4f475034b8cb44797843bdb314555d545a3aec439a2c99a30db282bc0a030d47721df1e7d09c4013b3ed5fc7f26220c1bdaf9e8d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d30fed3195e37ecd15b48eb0404be9572b74a4cf05336ba12277c3eb9f43ae04fc3395de28eba19842b1cd611033a7ab70dd1fad00a5dce664dbff07fe388ce5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/961d14fce39c44763777a503c871ace4e1e878715c062ea703127c0a3dbadf155e734e7914497f8fd82c1a03151b59a5d8598c26434140d47c99ff5d96c1d2e7"} 10:54:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:54:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:54:31 INFO - Contents: 10:54:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f6fc0eac80ca1cdbb0a2863ca13e5bece614b66a5e903eeb2fcc13f90959ac23fd49f509757308038823f3c714afdeef8f2a5c201f5327a543a1e1b012730cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/177606b47295a2c3e594fbd4f475034b8cb44797843bdb314555d545a3aec439a2c99a30db282bc0a030d47721df1e7d09c4013b3ed5fc7f26220c1bdaf9e8d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d30fed3195e37ecd15b48eb0404be9572b74a4cf05336ba12277c3eb9f43ae04fc3395de28eba19842b1cd611033a7ab70dd1fad00a5dce664dbff07fe388ce5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/961d14fce39c44763777a503c871ace4e1e878715c062ea703127c0a3dbadf155e734e7914497f8fd82c1a03151b59a5d8598c26434140d47c99ff5d96c1d2e7"} 10:54:31 INFO - Running post-run listener: copy_logs_to_upload_dir 10:54:31 INFO - Copying logs to upload dir... 10:54:31 INFO - mkdir: /builds/slave/test/build/upload/logs 10:54:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=2483.661188 ========= master_lag: -0.15 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 23 secs) (at 2017-05-11 10:54:31.366035) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 10:54:31.369366) ========= 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.3tJkwNBtBN/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.bBklUxqRKv/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f6fc0eac80ca1cdbb0a2863ca13e5bece614b66a5e903eeb2fcc13f90959ac23fd49f509757308038823f3c714afdeef8f2a5c201f5327a543a1e1b012730cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/177606b47295a2c3e594fbd4f475034b8cb44797843bdb314555d545a3aec439a2c99a30db282bc0a030d47721df1e7d09c4013b3ed5fc7f26220c1bdaf9e8d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d30fed3195e37ecd15b48eb0404be9572b74a4cf05336ba12277c3eb9f43ae04fc3395de28eba19842b1cd611033a7ab70dd1fad00a5dce664dbff07fe388ce5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/961d14fce39c44763777a503c871ace4e1e878715c062ea703127c0a3dbadf155e734e7914497f8fd82c1a03151b59a5d8598c26434140d47c99ff5d96c1d2e7"} build_url:https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011334 build_url: 'https://queue.taskcluster.net/v1/task/HdCbCH7aTPCUq8Uq-alcLw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f6fc0eac80ca1cdbb0a2863ca13e5bece614b66a5e903eeb2fcc13f90959ac23fd49f509757308038823f3c714afdeef8f2a5c201f5327a543a1e1b012730cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/177606b47295a2c3e594fbd4f475034b8cb44797843bdb314555d545a3aec439a2c99a30db282bc0a030d47721df1e7d09c4013b3ed5fc7f26220c1bdaf9e8d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d30fed3195e37ecd15b48eb0404be9572b74a4cf05336ba12277c3eb9f43ae04fc3395de28eba19842b1cd611033a7ab70dd1fad00a5dce664dbff07fe388ce5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/961d14fce39c44763777a503c871ace4e1e878715c062ea703127c0a3dbadf155e734e7914497f8fd82c1a03151b59a5d8598c26434140d47c99ff5d96c1d2e7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 10:54:31.403334) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 10:54:31.403660) ========= 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.3tJkwNBtBN/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.bBklUxqRKv/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005075 ========= master_lag: 0.58 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 10:54:31.993131) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 10:54:31.993424) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 10:54:31.993839) ========= ========= Total master_lag: 0.53 =========