builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0075 starttime: 1481301198.75 results: success (0) buildid: 20161209060650 builduid: 2c3fc1794d494affa82dcfdfc9689e0a revision: f6f2e2b9fa87a1cf454dec44bcf52cd4f96288a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-09 08:33:18.748694) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-09 08:33:18.749153) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-09 08:33:18.749428) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-09 08:33:18.775885) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:33:18.776250) ========= 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.3ASQqmwJBn/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.85tR4SAT0z/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-09 08:33:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 39.5M=0s 2016-12-09 08:33:18 (39.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126832 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:33:18.927284) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:33:18.927599) ========= 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.3ASQqmwJBn/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.85tR4SAT0z/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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.026400 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:33:18.975546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-09 08:33:18.975946) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f6f2e2b9fa87a1cf454dec44bcf52cd4f96288a0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f6f2e2b9fa87a1cf454dec44bcf52cd4f96288a0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3ASQqmwJBn/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.85tR4SAT0z/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-09 08:33:19,067 truncating revision to first 12 chars 2016-12-09 08:33:19,067 Setting DEBUG logging. 2016-12-09 08:33:19,067 attempt 1/10 2016-12-09 08:33:19,067 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f6f2e2b9fa87?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-09 08:33:20,147 unpacking tar archive at: mozilla-aurora-f6f2e2b9fa87/testing/mozharness/ program finished with exit code 0 elapsedTime=1.343687 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-09 08:33:20.343330) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-09 08:33:20.343649) ========= script_repo_revision: f6f2e2b9fa87a1cf454dec44bcf52cd4f96288a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-09 08:33:20.344091) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-09 08:33:20.344391) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-09 08:33:20.364917) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-09 08:33:20.365563) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3ASQqmwJBn/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.85tR4SAT0z/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 08:33:20 INFO - MultiFileLogger online at 20161209 08:33:20 in /builds/slave/test 08:33:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:33:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:33:20 INFO - {'append_to_log': False, 08:33:20 INFO - 'base_work_dir': '/builds/slave/test', 08:33:20 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:33:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:33:20 INFO - 'branch': 'Mozilla-Aurora', 08:33:20 INFO - 'buildbot_json_path': 'buildprops.json', 08:33:20 INFO - 'config_files': ('talos/mac_config.py',), 08:33:20 INFO - 'default_actions': ('clobber', 08:33:20 INFO - 'read-buildbot-config', 08:33:20 INFO - 'download-and-extract', 08:33:20 INFO - 'populate-webroot', 08:33:20 INFO - 'create-virtualenv', 08:33:20 INFO - 'install', 08:33:20 INFO - 'run-tests'), 08:33:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:33:20 INFO - 'download_minidump_stackwalk': True, 08:33:20 INFO - 'download_symbols': 'ondemand', 08:33:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:33:20 INFO - 'tooltool.py': '/tools/tooltool.py', 08:33:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:33:20 INFO - '/tools/misc-python/virtualenv.py')}, 08:33:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:33:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:33:20 INFO - 'installer_path': 'installer.exe', 08:33:20 INFO - 'log_level': 'info', 08:33:20 INFO - 'log_name': 'talos', 08:33:20 INFO - 'log_to_console': True, 08:33:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:33:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:33:20 INFO - 'opt_config_files': (), 08:33:20 INFO - 'pip_index': False, 08:33:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:33:20 INFO - 'cmd': ('bash', 08:33:20 INFO - '-c', 08:33:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:33:20 INFO - 'enabled': True, 08:33:20 INFO - 'halt_on_failure': False, 08:33:20 INFO - 'name': 'check_screen_resolution'},), 08:33:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:33:20 INFO - 'cmd': ('bash', 08:33:20 INFO - '-c', 08:33:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:33:20 INFO - 'enabled': True, 08:33:20 INFO - 'halt_on_failure': False, 08:33:20 INFO - 'name': 'check_screen_resolution'},), 08:33:20 INFO - 'run_cmd_checks_enabled': True, 08:33:20 INFO - 'sps_profile': False, 08:33:20 INFO - 'sps_profile_interval': 0, 08:33:20 INFO - 'suite': 'dromaeojs-e10s', 08:33:20 INFO - 'system_bits': '32', 08:33:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:33:20 INFO - 'title': 't-yosemite-r7-0075', 08:33:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:33:20 INFO - 'use_talos_json': True, 08:33:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:33:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:33:20 INFO - 'work_dir': 'build'} 08:33:20 INFO - [mozharness: 2016-12-09 16:33:20.567368Z] Running clobber step. 08:33:20 INFO - Running pre-action listener: _resource_record_pre_action 08:33:20 INFO - Running main action method: clobber 08:33:20 INFO - rmtree: /builds/slave/test/build 08:33:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:33:23 INFO - Running post-action listener: _resource_record_post_action 08:33:23 INFO - [mozharness: 2016-12-09 16:33:23.122577Z] Finished clobber step (success) 08:33:23 INFO - [mozharness: 2016-12-09 16:33:23.122721Z] Running read-buildbot-config step. 08:33:23 INFO - Running pre-action listener: _resource_record_pre_action 08:33:23 INFO - Running main action method: read_buildbot_config 08:33:23 INFO - Using buildbot properties: 08:33:23 INFO - { 08:33:23 INFO - "project": "", 08:33:23 INFO - "product": "firefox", 08:33:23 INFO - "script_repo_revision": "production", 08:33:23 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:33:23 INFO - "repository": "", 08:33:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:33:23 INFO - "buildid": "20161209060650", 08:33:23 INFO - "pgo_build": "False", 08:33:23 INFO - "basedir": "/builds/slave/test", 08:33:23 INFO - "buildnumber": 477, 08:33:23 INFO - "slavename": "t-yosemite-r7-0075", 08:33:23 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:33:23 INFO - "platform": "yosemite_r7", 08:33:23 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:33:23 INFO - "branch": "mozilla-aurora", 08:33:23 INFO - "repo_path": "releases/mozilla-aurora", 08:33:23 INFO - "revision": "f6f2e2b9fa87a1cf454dec44bcf52cd4f96288a0", 08:33:23 INFO - "stage_platform": "macosx64", 08:33:23 INFO - "builduid": "2c3fc1794d494affa82dcfdfc9689e0a", 08:33:23 INFO - "slavebuilddir": "test" 08:33:23 INFO - } 08:33:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:33:23 INFO - Running post-action listener: _resource_record_post_action 08:33:23 INFO - [mozharness: 2016-12-09 16:33:23.126241Z] Finished read-buildbot-config step (success) 08:33:23 INFO - [mozharness: 2016-12-09 16:33:23.126343Z] Running download-and-extract step. 08:33:23 INFO - Running pre-action listener: _resource_record_pre_action 08:33:23 INFO - Running main action method: download_and_extract 08:33:23 INFO - mkdir: /builds/slave/test/build/tests 08:33:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:23 INFO - trying https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:33:23 INFO - Downloading https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:33:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:33:24 INFO - Downloaded 1199 bytes. 08:33:24 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:33:24 INFO - Using the following test package requirements: 08:33:24 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:33:24 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:33:24 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:33:24 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:33:24 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:33:24 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:33:24 INFO - u'jsshell-mac.zip'], 08:33:24 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:33:24 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:33:24 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:33:24 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:33:24 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:33:24 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:33:24 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:33:24 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:33:24 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:33:24 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:33:24 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:33:24 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:33:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:33:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:33:24 INFO - Fetch https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:33:25 INFO - Http code: 200 08:33:25 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 08:33:25 INFO - x-amz-cf-id: vXAflQ1Lgq0XaXCvN_R_QrNHdjrIIjyOox3lmpF59THTrwZwOJ276Q== 08:33:25 INFO - x-amz-version-id: szeVb_5gNyunXaWM7dQI.Wy2QSJNu7_G 08:33:25 INFO - Expected file size: 16281040 08:33:25 INFO - Obtained file size: 16281040 08:33:31 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:33:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:33:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:33:31 INFO - Fetch https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:33:32 INFO - Http code: 200 08:33:32 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 08:33:32 INFO - x-amz-cf-id: K8d2ypEHrZ6rAO-b0qRBYYnNYT1kbOHy42mdmL2XoPl21I8c8Bt4pw== 08:33:32 INFO - x-amz-version-id: szeVb_5gNyunXaWM7dQI.Wy2QSJNu7_G 08:33:32 INFO - Expected file size: 16281040 08:33:32 INFO - Obtained file size: 16281040 08:33:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:33:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:33:39 INFO - Fetch https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:33:40 INFO - Http code: 200 08:33:40 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 08:33:40 INFO - x-amz-cf-id: VAikKrj79RTb_RW4p5Tr9_WVdYOZNsuoYuFNvHoFiYHZKMHBPiASMQ== 08:33:40 INFO - x-amz-version-id: Ck_QnPAs5f_UCgxlH.SU7wRumzivXr1_ 08:33:40 INFO - Expected file size: 14409038 08:33:40 INFO - Obtained file size: 14409038 08:33:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:40 INFO - trying https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:33:40 INFO - Downloading https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:33:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:33:43 INFO - Downloaded 109785285 bytes. 08:33:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:33:43 INFO - mkdir: /builds/slave/test/properties 08:33:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:33:43 INFO - Writing to file /builds/slave/test/properties/build_url 08:33:43 INFO - Contents: 08:33:43 INFO - build_url:https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:33:44 INFO - Running post-action listener: _resource_record_post_action 08:33:44 INFO - Running post-action listener: set_extra_try_arguments 08:33:44 INFO - [mozharness: 2016-12-09 16:33:44.121762Z] Finished download-and-extract step (success) 08:33:44 INFO - [mozharness: 2016-12-09 16:33:44.121908Z] Running populate-webroot step. 08:33:44 INFO - Running pre-action listener: _resource_record_pre_action 08:33:44 INFO - Running main action method: populate_webroot 08:33:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:33:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:33:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:33:44 INFO - '--tppagecycles', 08:33:44 INFO - '7'], 08:33:44 INFO - 'tests': ['tsvgm']}}, 08:33:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:33:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:33:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:33:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:33:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:33:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:33:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:44 INFO - 'talos_options': ['--disable-e10s'], 08:33:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:33:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:33:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:44 INFO - 'talos_options': ['--disable-e10s'], 08:33:44 INFO - 'tests': ['damp', 'tps']}, 08:33:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:44 INFO - 'tests': ['damp', 'tps']}, 08:33:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:33:44 INFO - 'tests': ['dromaeo_dom']}, 08:33:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:33:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:33:44 INFO - 'tests': ['basic_compositor_video']}, 08:33:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:33:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:33:44 INFO - 'tests': ['a11yr', 08:33:44 INFO - 'ts_paint', 08:33:44 INFO - 'tpaint', 08:33:44 INFO - 'sessionrestore', 08:33:44 INFO - 'sessionrestore_no_auto_restore', 08:33:44 INFO - 'tabpaint']}, 08:33:44 INFO - 'other-e10s': {'tests': ['a11yr', 08:33:44 INFO - 'ts_paint', 08:33:44 INFO - 'tpaint', 08:33:44 INFO - 'sessionrestore', 08:33:44 INFO - 'sessionrestore_no_auto_restore', 08:33:44 INFO - 'tabpaint']}, 08:33:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:33:44 INFO - 'ts_paint', 08:33:44 INFO - 'tpaint', 08:33:44 INFO - 'sessionrestore', 08:33:44 INFO - 'sessionrestore_no_auto_restore', 08:33:44 INFO - 'tabpaint']}, 08:33:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:33:44 INFO - 'ts_paint', 08:33:44 INFO - 'tpaint', 08:33:44 INFO - 'sessionrestore', 08:33:44 INFO - 'sessionrestore_no_auto_restore', 08:33:44 INFO - 'tabpaint']}, 08:33:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:33:44 INFO - 'tests': ['a11yr', 08:33:44 INFO - 'ts_paint', 08:33:44 INFO - 'tpaint', 08:33:44 INFO - 'sessionrestore', 08:33:44 INFO - 'sessionrestore_no_auto_restore', 08:33:44 INFO - 'tabpaint']}, 08:33:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:33:44 INFO - 'tests': ['a11yr', 08:33:44 INFO - 'ts_paint', 08:33:44 INFO - 'tpaint', 08:33:44 INFO - 'sessionrestore', 08:33:44 INFO - 'sessionrestore_no_auto_restore', 08:33:44 INFO - 'tabpaint']}, 08:33:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:33:44 INFO - 'tests': ['tsvgx', 08:33:44 INFO - 'tsvgr_opacity', 08:33:44 INFO - 'tart', 08:33:44 INFO - 'tscrollx', 08:33:44 INFO - 'cart']}, 08:33:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:33:44 INFO - 'tsvgr_opacity', 08:33:44 INFO - 'tart', 08:33:44 INFO - 'tscrollx', 08:33:44 INFO - 'cart']}, 08:33:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:44 INFO - 'talos_options': ['--disable-e10s'], 08:33:44 INFO - 'tests': ['tp5o']}, 08:33:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:44 INFO - 'tests': ['tp5o']}, 08:33:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:44 INFO - 'talos_options': ['--disable-e10s', 08:33:44 INFO - '--xperf_path', 08:33:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:33:44 INFO - 'tests': ['tp5n']}, 08:33:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:33:44 INFO - 'talos_options': ['--xperf_path', 08:33:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:33:44 INFO - 'tests': ['tp5n']}}, 08:33:44 INFO - 'talos.zip': {'path': '', 08:33:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:33:44 INFO - Running post-action listener: _resource_record_post_action 08:33:44 INFO - [mozharness: 2016-12-09 16:33:44.140326Z] Finished populate-webroot step (success) 08:33:44 INFO - [mozharness: 2016-12-09 16:33:44.140436Z] Running create-virtualenv step. 08:33:44 INFO - Running pre-action listener: _resource_record_pre_action 08:33:44 INFO - Running main action method: create_virtualenv 08:33:44 INFO - Creating virtualenv /builds/slave/test/build/venv 08:33:44 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 08:33:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:33:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:33:44 INFO - Using real prefix '/tools/python27' 08:33:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:33:44 INFO - Installing distribute.............................................................................................................................................................................................done. 08:33:47 INFO - Installing pip.................done. 08:33:47 INFO - Return code: 0 08:33:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:33:48 INFO - Reading from file tmpfile_stdout 08:33:48 INFO - Output received: 08:33:48 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) 08:33:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:33:48 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')]} 08:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:48 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')]} 08:33:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:48 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 0x10ae91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39b4a2f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca5d30>, '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 0x10ab0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac9db20>, '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.3ASQqmwJBn/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.85tR4SAT0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 08:33:48 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 08:33:48 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 08:33:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ASQqmwJBn/Render', 08:33:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:48 INFO - 'HOME': '/Users/cltbld', 08:33:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:48 INFO - 'LOGNAME': 'cltbld', 08:33:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:48 INFO - 'MOZ_NO_REMOTE': '1', 08:33:48 INFO - 'NO_EM_RESTART': '1', 08:33:48 INFO - 'PAGER': '/bin/cat', 08:33:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:48 INFO - 'PWD': '/builds/slave/test', 08:33:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:48 INFO - 'SHELL': '/bin/bash', 08:33:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85tR4SAT0z/Listeners', 08:33:48 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 08:33:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:48 INFO - 'USER': 'cltbld', 08:33:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:48 INFO - 'XPC_FLAGS': '0x0', 08:33:48 INFO - 'XPC_SERVICE_NAME': '0', 08:33:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:48 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 08:33:48 INFO - Cleaning up... 08:33:48 INFO - Return code: 0 08:33:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:33:48 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')]} 08:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:48 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')]} 08:33:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:48 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 0x10ae91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39b4a2f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca5d30>, '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 0x10ab0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac9db20>, '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.3ASQqmwJBn/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.85tR4SAT0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 08:33:48 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 08:33:48 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 08:33:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ASQqmwJBn/Render', 08:33:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:48 INFO - 'HOME': '/Users/cltbld', 08:33:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:48 INFO - 'LOGNAME': 'cltbld', 08:33:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:48 INFO - 'MOZ_NO_REMOTE': '1', 08:33:48 INFO - 'NO_EM_RESTART': '1', 08:33:48 INFO - 'PAGER': '/bin/cat', 08:33:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:48 INFO - 'PWD': '/builds/slave/test', 08:33:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:48 INFO - 'SHELL': '/bin/bash', 08:33:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85tR4SAT0z/Listeners', 08:33:48 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 08:33:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:48 INFO - 'USER': 'cltbld', 08:33:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:48 INFO - 'XPC_FLAGS': '0x0', 08:33:48 INFO - 'XPC_SERVICE_NAME': '0', 08:33:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:48 INFO - Downloading/unpacking psutil>=3.1.1 08:33:48 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 08:33:48 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 08:33:48 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 08:33:48 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 08:33:48 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 08:33:48 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 08:33:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:33:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:33:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:33:52 INFO - Installing collected packages: psutil 08:33:52 INFO - Running setup.py install for psutil 08:33:52 INFO - building 'psutil._psutil_osx' extension 08:33:52 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 08:33:52 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 08:33:52 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 08:33:52 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 08:33:52 INFO - building 'psutil._psutil_posix' extension 08:33:52 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 08:33:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:33:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:33:53 INFO - ^ 08:33:53 INFO - 1 warning generated. 08:33:53 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 08:33:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:33:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:33:53 INFO - Successfully installed psutil 08:33:53 INFO - Cleaning up... 08:33:53 INFO - Return code: 0 08:33:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:33:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ae91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39b4a2f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca5d30>, '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 0x10ab0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac9db20>, '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.3ASQqmwJBn/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.85tR4SAT0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 08:33:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:33:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:33:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ASQqmwJBn/Render', 08:33:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:53 INFO - 'HOME': '/Users/cltbld', 08:33:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:53 INFO - 'LOGNAME': 'cltbld', 08:33:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:53 INFO - 'MOZ_NO_REMOTE': '1', 08:33:53 INFO - 'NO_EM_RESTART': '1', 08:33:53 INFO - 'PAGER': '/bin/cat', 08:33:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:53 INFO - 'PWD': '/builds/slave/test', 08:33:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:53 INFO - 'SHELL': '/bin/bash', 08:33:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85tR4SAT0z/Listeners', 08:33:53 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 08:33:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:53 INFO - 'USER': 'cltbld', 08:33:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:53 INFO - 'XPC_FLAGS': '0x0', 08:33:53 INFO - 'XPC_SERVICE_NAME': '0', 08:33:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:33:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:33:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:33:56 INFO - Installing collected packages: mozsystemmonitor 08:33:56 INFO - Running setup.py install for mozsystemmonitor 08:33:56 INFO - Successfully installed mozsystemmonitor 08:33:56 INFO - Cleaning up... 08:33:56 INFO - Return code: 0 08:33:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:33: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')]} 08:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33: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')]} 08:33:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33: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', '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 0x10ae91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39b4a2f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca5d30>, '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 0x10ab0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac9db20>, '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.3ASQqmwJBn/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.85tR4SAT0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 08:33: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:33: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 jsonschema==2.5.1 08:33:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ASQqmwJBn/Render', 08:33:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:56 INFO - 'HOME': '/Users/cltbld', 08:33:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:56 INFO - 'LOGNAME': 'cltbld', 08:33:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:56 INFO - 'MOZ_NO_REMOTE': '1', 08:33:56 INFO - 'NO_EM_RESTART': '1', 08:33:56 INFO - 'PAGER': '/bin/cat', 08:33:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:56 INFO - 'PWD': '/builds/slave/test', 08:33:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:56 INFO - 'SHELL': '/bin/bash', 08:33:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85tR4SAT0z/Listeners', 08:33:56 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 08:33:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:56 INFO - 'USER': 'cltbld', 08:33:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:56 INFO - 'XPC_FLAGS': '0x0', 08:33:56 INFO - 'XPC_SERVICE_NAME': '0', 08:33:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:56 INFO - Downloading/unpacking jsonschema==2.5.1 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:33:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:33:59 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 08:33:59 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 08:33:59 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 08:33:59 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 08:33:59 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 08:33:59 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 08:33:59 INFO - Downloading functools32-3.2.3-2.tar.gz 08:33:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:33:59 INFO - warning: no files found matching '*.txt' 08:33:59 INFO - no previously-included directories found matching 'build' 08:33:59 INFO - no previously-included directories found matching 'dist' 08:33:59 INFO - no previously-included directories found matching '.git*' 08:33:59 INFO - Installing collected packages: jsonschema, functools32 08:33:59 INFO - Running setup.py install for functools32 08:33:59 INFO - warning: no files found matching '*.txt' 08:33:59 INFO - no previously-included directories found matching 'build' 08:33:59 INFO - no previously-included directories found matching 'dist' 08:33:59 INFO - no previously-included directories found matching '.git*' 08:33:59 INFO - Successfully installed jsonschema functools32 08:33:59 INFO - Cleaning up... 08:34:00 INFO - Return code: 0 08:34:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:34:00 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')]} 08:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:00 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')]} 08:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:00 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 0x10ae91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39b4a2f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca5d30>, '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 0x10ab0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac9db20>, '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.3ASQqmwJBn/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.85tR4SAT0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 08:34:00 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 08:34:00 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 08:34:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ASQqmwJBn/Render', 08:34:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:00 INFO - 'HOME': '/Users/cltbld', 08:34:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:00 INFO - 'LOGNAME': 'cltbld', 08:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:00 INFO - 'MOZ_NO_REMOTE': '1', 08:34:00 INFO - 'NO_EM_RESTART': '1', 08:34:00 INFO - 'PAGER': '/bin/cat', 08:34:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:00 INFO - 'PWD': '/builds/slave/test', 08:34:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:00 INFO - 'SHELL': '/bin/bash', 08:34:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85tR4SAT0z/Listeners', 08:34:00 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 08:34:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:00 INFO - 'USER': 'cltbld', 08:34:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:00 INFO - 'XPC_FLAGS': '0x0', 08:34:00 INFO - 'XPC_SERVICE_NAME': '0', 08:34:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:34:00 INFO - Cleaning up... 08:34:00 INFO - Return code: 0 08:34:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:34:00 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')]} 08:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:00 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')]} 08:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:00 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 0x10ae91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39b4a2f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca5d30>, '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 0x10ab0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac9db20>, '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.3ASQqmwJBn/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.85tR4SAT0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 08:34:00 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 08:34:00 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 08:34:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ASQqmwJBn/Render', 08:34:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:00 INFO - 'HOME': '/Users/cltbld', 08:34:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:00 INFO - 'LOGNAME': 'cltbld', 08:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:00 INFO - 'MOZ_NO_REMOTE': '1', 08:34:00 INFO - 'NO_EM_RESTART': '1', 08:34:00 INFO - 'PAGER': '/bin/cat', 08:34:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:00 INFO - 'PWD': '/builds/slave/test', 08:34:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:00 INFO - 'SHELL': '/bin/bash', 08:34:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85tR4SAT0z/Listeners', 08:34:00 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 08:34:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:00 INFO - 'USER': 'cltbld', 08:34:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:00 INFO - 'XPC_FLAGS': '0x0', 08:34:00 INFO - 'XPC_SERVICE_NAME': '0', 08:34:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:00 INFO - Downloading/unpacking blobuploader==1.2.4 08:34:00 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 08:34:00 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 08:34:00 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 08:34:00 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 08:34:00 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 08:34:00 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 08:34:03 INFO - Downloading blobuploader-1.2.4.tar.gz 08:34:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:34:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:34:03 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 08:34:03 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 08:34:03 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 08:34:03 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 08:34:03 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 08:34:03 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 08:34:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:34:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:34:03 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 08:34:03 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 08:34:03 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 08:34:03 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 08:34:03 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 08:34:03 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 08:34:04 INFO - Downloading docopt-0.6.1.tar.gz 08:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:34:04 INFO - Installing collected packages: blobuploader, requests, docopt 08:34:04 INFO - Running setup.py install for blobuploader 08:34:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:34:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:34:04 INFO - Running setup.py install for requests 08:34:05 INFO - Running setup.py install for docopt 08:34:05 INFO - Successfully installed blobuploader requests docopt 08:34:05 INFO - Cleaning up... 08:34:05 INFO - Return code: 0 08:34:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:34:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:05 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 0x10ae91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39b4a2f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca5d30>, '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 0x10ab0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac9db20>, '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.3ASQqmwJBn/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.85tR4SAT0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 08:34:05 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 08:34:05 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 08:34:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ASQqmwJBn/Render', 08:34:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:05 INFO - 'HOME': '/Users/cltbld', 08:34:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:05 INFO - 'LOGNAME': 'cltbld', 08:34:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:05 INFO - 'MOZ_NO_REMOTE': '1', 08:34:05 INFO - 'NO_EM_RESTART': '1', 08:34:05 INFO - 'PAGER': '/bin/cat', 08:34:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:05 INFO - 'PWD': '/builds/slave/test', 08:34:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:05 INFO - 'SHELL': '/bin/bash', 08:34:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85tR4SAT0z/Listeners', 08:34:05 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 08:34:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:05 INFO - 'USER': 'cltbld', 08:34:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:05 INFO - 'XPC_FLAGS': '0x0', 08:34:05 INFO - 'XPC_SERVICE_NAME': '0', 08:34:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:34:05 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-MRweBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:34:05 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-rqJbOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:34:05 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-GgrGCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:34:05 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-8PrZmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:34:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:34:05 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-lp5cpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:34:06 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-WHAnvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:34:06 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-dhawYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:34:06 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-6dq60j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:34:06 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-6P1oxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:34:06 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-gnnzw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:34:06 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-ekaTLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:34:06 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-G9N3JA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:34:06 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-OXLa2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:34:06 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-6FzwYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:34:06 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-JMXeTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:34:07 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-tdbmw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:34:07 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-aFN5YA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:34:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:34:07 INFO - Running setup.py install for manifestparser 08:34:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:34:07 INFO - Running setup.py install for mozcrash 08:34:07 INFO - Running setup.py install for mozdebug 08:34:07 INFO - Running setup.py install for mozdevice 08:34:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:34:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:34:07 INFO - Running setup.py install for mozfile 08:34:08 INFO - Running setup.py install for mozhttpd 08:34:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:34:08 INFO - Running setup.py install for mozinfo 08:34:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:34:08 INFO - Running setup.py install for mozInstall 08:34:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:34:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:34:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:34:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:34:08 INFO - Running setup.py install for mozleak 08:34:08 INFO - Running setup.py install for mozlog 08:34:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:34:08 INFO - Running setup.py install for moznetwork 08:34:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:34:08 INFO - Running setup.py install for mozprocess 08:34:09 INFO - Running setup.py install for mozprofile 08:34:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:34:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:34:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:34:09 INFO - Running setup.py install for mozrunner 08:34:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:34:09 INFO - Running setup.py install for mozscreenshot 08:34:09 INFO - Running setup.py install for moztest 08:34:09 INFO - Running setup.py install for mozversion 08:34:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:34:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:34:09 INFO - Cleaning up... 08:34:09 INFO - Return code: 0 08:34:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:34: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')]} 08:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34: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')]} 08:34:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:10 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 0x10ae91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39b4a2f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca5d30>, '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 0x10ab0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac9db20>, '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.3ASQqmwJBn/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.85tR4SAT0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 08:34:10 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 08:34:10 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 08:34:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ASQqmwJBn/Render', 08:34:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:10 INFO - 'HOME': '/Users/cltbld', 08:34:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:10 INFO - 'LOGNAME': 'cltbld', 08:34:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:10 INFO - 'MOZ_NO_REMOTE': '1', 08:34:10 INFO - 'NO_EM_RESTART': '1', 08:34:10 INFO - 'PAGER': '/bin/cat', 08:34:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:10 INFO - 'PWD': '/builds/slave/test', 08:34:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:10 INFO - 'SHELL': '/bin/bash', 08:34:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85tR4SAT0z/Listeners', 08:34:10 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 08:34:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:10 INFO - 'USER': 'cltbld', 08:34:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:10 INFO - 'XPC_FLAGS': '0x0', 08:34:10 INFO - 'XPC_SERVICE_NAME': '0', 08:34:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:34:10 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-eJmKPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:34:11 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)) 08:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:34:11 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-6jjNsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:34:11 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)) 08:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:34:11 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-eJ3fev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:34:11 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)) 08:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:34:11 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-0TwvbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:34:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:34:11 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-X2ZZFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:34:11 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)) 08:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:34:11 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-VyQLlx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:34:11 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)) 08:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:34:11 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-K2iWpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:34:11 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)) 08:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:34:11 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-K4bMeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:34:11 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)) 08:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:34:11 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-B578Yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:34:11 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)) 08:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:34:11 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-CCA78F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:34:12 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)) 08:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:34:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-b0Xbpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:34:12 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)) 08:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:34:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-DTilAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:34:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-zIj4FJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:34:12 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)) 08:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:34:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-MaKGBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:34:12 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)) 08:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:34:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-LRp8oV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:34:12 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)) 08:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:34:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-dLBFZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:34:12 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-s04Dxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:34:12 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)) 08:34:12 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)) 08:34:12 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)) 08:34:12 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)) 08:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:34:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:34:12 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 08:34:12 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 08:34:12 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 08:34:12 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 08:34:12 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 08:34:12 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 08:34:15 INFO - Downloading blessings-1.6.tar.gz 08:34:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:34:15 INFO - Installing collected packages: blessings 08:34:15 INFO - Running setup.py install for blessings 08:34:15 INFO - Successfully installed blessings 08:34:15 INFO - Cleaning up... 08:34:16 INFO - Return code: 0 08:34:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:34:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:34:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:34:16 INFO - Reading from file tmpfile_stdout 08:34:16 INFO - Current package versions: 08:34:16 INFO - blessings == 1.6 08:34:16 INFO - blobuploader == 1.2.4 08:34:16 INFO - docopt == 0.6.1 08:34:16 INFO - functools32 == 3.2.3-2 08:34:16 INFO - jsonschema == 2.5.1 08:34:16 INFO - manifestparser == 1.1 08:34:16 INFO - mozInstall == 1.12 08:34:16 INFO - mozcrash == 1.0 08:34:16 INFO - mozdebug == 0.1 08:34:16 INFO - mozdevice == 0.48 08:34:16 INFO - mozfile == 1.2 08:34:16 INFO - mozhttpd == 0.7 08:34:16 INFO - mozinfo == 0.9 08:34:16 INFO - mozleak == 0.1 08:34:16 INFO - mozlog == 3.4 08:34:16 INFO - moznetwork == 0.27 08:34:16 INFO - mozprocess == 0.23 08:34:16 INFO - mozprofile == 0.28 08:34:16 INFO - mozrunner == 6.13 08:34:16 INFO - mozscreenshot == 0.1 08:34:16 INFO - mozsystemmonitor == 0.3 08:34:16 INFO - moztest == 0.7 08:34:16 INFO - mozversion == 1.4 08:34:16 INFO - psutil == 3.1.1 08:34:16 INFO - requests == 1.2.3 08:34:16 INFO - wsgiref == 0.1.2 08:34:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ae91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39b4a2f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca5d30>, '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 0x10ab0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac9db20>, '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.3ASQqmwJBn/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.85tR4SAT0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 08:34:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:34:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:34:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ASQqmwJBn/Render', 08:34:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:16 INFO - 'HOME': '/Users/cltbld', 08:34:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:16 INFO - 'LOGNAME': 'cltbld', 08:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:16 INFO - 'MOZ_NO_REMOTE': '1', 08:34:16 INFO - 'NO_EM_RESTART': '1', 08:34:16 INFO - 'PAGER': '/bin/cat', 08:34:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:16 INFO - 'PWD': '/builds/slave/test', 08:34:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:16 INFO - 'SHELL': '/bin/bash', 08:34:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85tR4SAT0z/Listeners', 08:34:16 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 08:34:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:16 INFO - 'USER': 'cltbld', 08:34:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:16 INFO - 'XPC_FLAGS': '0x0', 08:34:16 INFO - 'XPC_SERVICE_NAME': '0', 08:34:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:16 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)) 08:34:16 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)) 08:34:16 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)) 08:34:16 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)) 08:34:16 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)) 08:34:16 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)) 08:34:16 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)) 08:34:16 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)) 08:34:16 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)) 08:34:16 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)) 08:34:16 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)) 08:34:16 INFO - Cleaning up... 08:34:16 INFO - Return code: 0 08:34:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:16 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 0x10ae91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39b4a2f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aca5d30>, '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 0x10ab0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac9db20>, '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.3ASQqmwJBn/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.85tR4SAT0z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 08:34:16 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 08:34:16 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 08:34:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ASQqmwJBn/Render', 08:34:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:16 INFO - 'HOME': '/Users/cltbld', 08:34:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:16 INFO - 'LOGNAME': 'cltbld', 08:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:16 INFO - 'MOZ_NO_REMOTE': '1', 08:34:16 INFO - 'NO_EM_RESTART': '1', 08:34:16 INFO - 'PAGER': '/bin/cat', 08:34:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:16 INFO - 'PWD': '/builds/slave/test', 08:34:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:16 INFO - 'SHELL': '/bin/bash', 08:34:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85tR4SAT0z/Listeners', 08:34:16 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 08:34:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:16 INFO - 'USER': 'cltbld', 08:34:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:16 INFO - 'XPC_FLAGS': '0x0', 08:34:16 INFO - 'XPC_SERVICE_NAME': '0', 08:34:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:34:16 INFO - Cleaning up... 08:34:16 INFO - Return code: 0 08:34:16 INFO - Running post-action listener: _resource_record_post_action 08:34:16 INFO - Running post-action listener: _start_resource_monitoring 08:34:16 INFO - Starting resource monitoring. 08:34:16 INFO - [mozharness: 2016-12-09 16:34:16.914255Z] Finished create-virtualenv step (success) 08:34:16 INFO - [mozharness: 2016-12-09 16:34:16.915237Z] Running install step. 08:34:16 INFO - Running pre-action listener: _resource_record_pre_action 08:34:16 INFO - Running main action method: install 08:34:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:34:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:34:17 INFO - Reading from file tmpfile_stdout 08:34:17 INFO - Detecting whether we're running mozinstall >=1.0... 08:34:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:34:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:34:17 INFO - Reading from file tmpfile_stdout 08:34:17 INFO - Output received: 08:34:17 INFO - Usage: mozinstall [options] installer 08:34:17 INFO - Options: 08:34:17 INFO - -h, --help show this help message and exit 08:34:17 INFO - -d DEST, --destination=DEST 08:34:17 INFO - Directory to install application into. [default: 08:34:17 INFO - "/builds/slave/test"] 08:34:17 INFO - --app=APP Application being installed. [default: firefox] 08:34:17 INFO - mkdir: /builds/slave/test/build/application 08:34:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:34:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:34:48 INFO - Reading from file tmpfile_stdout 08:34:48 INFO - Output received: 08:34:48 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:34:48 INFO - Running post-action listener: _resource_record_post_action 08:34:48 INFO - [mozharness: 2016-12-09 16:34:48.334633Z] Finished install step (success) 08:34:48 INFO - [mozharness: 2016-12-09 16:34:48.334770Z] Running run-tests step. 08:34:48 INFO - Running pre-action listener: _resource_record_pre_action 08:34:48 INFO - Running main action method: run_tests 08:34:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:34:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:34:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:34:48 INFO - 2016-12-09 08:34:48.349 screenresolution[1627:7511] starting screenresolution argv=screenresolution get 08:34:48 INFO - 2016-12-09 08:34:48.351 screenresolution[1627:7511] Display 0: 1600x1200x32@60 08:34:48 INFO - 2016-12-09 08:34:48.357 screenresolution[1628:7513] starting screenresolution argv=screenresolution list 08:34:48 INFO - Available Modes on Display 0 08:34:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:34:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:34:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:34:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:34:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:34:48 INFO - Intel Iris: 08:34:48 INFO - Chipset Model: Intel Iris 08:34:48 INFO - Type: GPU 08:34:48 INFO - Bus: Built-In 08:34:48 INFO - VRAM (Dynamic, Max): 1536 MB 08:34:48 INFO - Vendor: Intel (0x8086) 08:34:48 INFO - Device ID: 0x0a2e 08:34:48 INFO - Revision ID: 0x0009 08:34:48 INFO - Displays: 08:34:48 INFO - Display: 08:34:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:34:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:34:48 INFO - Display Serial Number: 1600x1200 60 08:34:48 INFO - Main Display: Yes 08:34:48 INFO - Mirror: Off 08:34:48 INFO - Online: Yes 08:34:48 INFO - Rotation: Supported 08:34:48 INFO - Return code: 0 08:34:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:34:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:34:48 INFO - Python 2.7.3 08:34:48 INFO - Return code: 0 08:34:48 INFO - grabbing minidump binary from tooltool 08:34:48 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')]} 08:34:48 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 0x10aca5d30>, '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 0x10ab0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac9db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:34:48 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 08:34:48 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 08:34:48 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 08:34:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:34:48 INFO - Return code: 0 08:34:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:34:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:34:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:34:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:34:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:34:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0075', '--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 08:34:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0075 --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 08:34:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3ASQqmwJBn/Render', 08:34:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:48 INFO - 'HOME': '/Users/cltbld', 08:34:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:48 INFO - 'LOGNAME': 'cltbld', 08:34:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:34:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:34:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:48 INFO - 'MOZ_NO_REMOTE': '1', 08:34:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:34:48 INFO - 'NO_EM_RESTART': '1', 08:34:48 INFO - 'PAGER': '/bin/cat', 08:34:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:48 INFO - 'PWD': '/builds/slave/test', 08:34:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:34:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:48 INFO - 'SHELL': '/bin/bash', 08:34:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85tR4SAT0z/Listeners', 08:34:48 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 08:34:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:48 INFO - 'USER': 'cltbld', 08:34:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:48 INFO - 'XPC_FLAGS': '0x0', 08:34:48 INFO - 'XPC_SERVICE_NAME': '0', 08:34:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0075', '--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 08:34:48 INFO - mozversion application_buildid: 20161209060650 08:34:48 INFO - mozversion application_changeset: f6f2e2b9fa87a1cf454dec44bcf52cd4f96288a0 08:34:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:34:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:34:48 INFO - mozversion application_name: Firefox 08:34:48 INFO - mozversion application_remotingname: firefox-dev 08:34:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:34:48 INFO - mozversion application_vendor: Mozilla 08:34:48 INFO - mozversion application_version: 52.0a2 08:34:48 INFO - mozversion platform_buildid: 20161209060650 08:34:48 INFO - mozversion platform_changeset: f6f2e2b9fa87a1cf454dec44bcf52cd4f96288a0 08:34:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:34:48 INFO - mozversion platform_version: 52.0a2 08:34:48 INFO - using testdate: 1481301288 08:34:48 INFO - actual date: 1481301288 08:34:48 INFO - starting webserver on 'localhost:49240' 08:34:48 INFO - SUITE-START | Running 2 tests 08:34:48 INFO - TEST-START | dromaeo_css 08:34:48 INFO - operating with platform_type : mac_ 08:34:48 INFO - Initialising browser for dromaeo_css test... 08:34:48 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpLgkDlm/profile http://localhost:49240/getInfo.html) 08:34:51 INFO - PROCESS | 1638 | 2016-12-09 08:34:51.905 plugin-container[1640:7694] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b43, name = 'com.apple.tsm.portname' 08:34:51 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:34:51 INFO - PROCESS | 1638 | 2016-12-09 08:34:51.908 plugin-container[1640:7694] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 08:34:51 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:34:51 INFO - PROCESS | 1638 | 2016-12-09 08:34:51.908 plugin-container[1640:7694] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:34:51 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 08:34:51 INFO - PROCESS | 1638 | colorDepth:24 08:34:51 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 08:34:51 INFO - PROCESS | 1638 | __metrics 08:34:52 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:34:53 INFO - TEST-INFO | 1638: exit 0 08:34:53 INFO - Browser initialized. 08:34:53 INFO - Running cycle 1/1 for dromaeo_css test... 08:34:53 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpLgkDlm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:34:55 INFO - PROCESS | 1641 | 2016-12-09 08:34:55.606 plugin-container[1642:7833] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 08:34:55 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:34:55 INFO - PROCESS | 1641 | 2016-12-09 08:34:55.608 plugin-container[1642:7833] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 08:34:55 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:34:55 INFO - PROCESS | 1641 | 2016-12-09 08:34:55.609 plugin-container[1642:7833] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:35:05 INFO - PROCESS | 1641 | RSS: Main: 229396480 08:35:05 INFO - PROCESS | 1641 | 08:35:54 INFO - PROCESS | 1641 | 1481301354883 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:38:09 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 08:38:11 INFO - PROCESS | 1641 | RSS: Main: 232394752 08:38:11 INFO - PROCESS | 1641 | 08:41:13 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 08:41:14 INFO - PROCESS | 1641 | RSS: Main: 243585024 08:41:14 INFO - PROCESS | 1641 | 08:44:16 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 08:44:16 INFO - PROCESS | 1641 | RSS: Main: 246308864 08:44:16 INFO - PROCESS | 1641 | 08:47:18 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 08:47:19 INFO - PROCESS | 1641 | RSS: Main: 245563392 08:47:19 INFO - PROCESS | 1641 | 08:50:22 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 08:50:22 INFO - PROCESS | 1641 | RSS: Main: 248946688 08:50:22 INFO - PROCESS | 1641 | 08:53:25 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 08:53:25 INFO - PROCESS | 1641 | RSS: Main: 253620224 08:53:25 INFO - PROCESS | 1641 | 08:53:25 INFO - PROCESS | 1641 | __start_tp_report 08:53:25 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 08:53:25 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 08:53:25 INFO - PROCESS | 1641 | |i|pagename|runs| 08:53:25 INFO - PROCESS | 1641 | |0;dojo.html;3372.0895358253274;3407.046027112408;3407.505911642811;3408.4205685033544;3410;5550.16747487877;5601.6358936669085;5613.270274864272;5619.213310136579;5619.662820230784;5723.313202415716;5832.212651292074;5856.853578660535;5860.619059761112;5870.11948207768;4413.558644135577;5261;5269.2886460327745;5313.920291195635;5317.73411329432;5325.094733895227;5363.141897296433;5375.7849686012705;5383.757730902099;5407.161889907051;5208.5572726318305;5280.656757310781;5342.038433082043;5343.225232341309;5354.089804733185;5249.2126181073145;5273.604479664022;5318.175682769177;5318.30861987941;5342.679439233659;5184.066867963765;5249.947500525022;5325.707086110262;5333.173358129496;5346.91979620306;5869;5881.85295367615;5909.084091965709;5919.112133180014;5941.079132734433;6124.540659450515;6159.384061593805;6243.937560624381;6265.968670156665;6286.4342209201395;6223.7821676241565;6236.970899801528;6253.062040693887;6259.561830671855;6263.1544741459775;5884.411558844125;5899.941000589939;5904.527637788967;5964.522838172936;5965.224520812267;5330.440303768126;6224.595401298901;6335.303116657164;6335.954567496404;6347.365263473708;6089.63462192261;6129.267100814801;6143.32423433422;6146.846328841814;6160.568760186744;5908.645481271137;6274.247090349188;6274.341194174637;6294.118823364733;6302.558820882586;6237.282712488031;6251.749930002749;6279.340669229665;6282.371762823681;6294.779682711083;5528.530074943595;5731.455511726379;6184;6185.659788711574;6248.125262463259;4949.752512374367;4955.776990035385;4995.375578052744;5000.1749711297225;5038.269450929594;4437.134758722018;4457.531959144308;4483.775811209426;4491.281394976788;4518.209313370147;5285.920711189336;5821.359650438449;5826.202434324201;5950.4049595041015;6052.03167493199;5146.433892271847;5301.575873930076;6222.62664240147;6240.31356550779;6240.7503699851495;5101.285819985205;5799.217105690762;5980.611260268069;6002.939970600238;6049.243844519435;5111.437741848394;5202.76587553561;5347.812826551052;5401.5948803839865;5441.727913604304;6222.408871157233;6263.248410190807;6320.5891617044745;6512.772052978123;6530.8040758777315;5073.34046573943;5279.577633789365;6248.781292654735;6257.68711564429;6273.62358258506;6399.488040956619;6410.487161027013;6465.159529261354;6470.708818103102;6473.676316184078;4990.47600002009;6096.359882212481;6253.7185826637;6257.9687101564205;6289.779857704958;5041.773120209673;6159.784407545714;6247.718852651551;6250.312465628686;6260.968695156495;6176.938230617637;6184.783532576338;6212;6216.378362163748;6220.657863817533;6005.279366476051;6181.629102253879;6206.503479721701;6216.378362163748;6237.376262373727;5729.140628905698;5979.940200597938;6015.48868346178;6015.699215039318;6118.449339559283;6153.815385538441;6183.536234782319;6194.535409844189;6215.254169499689;6238.937610624017;5988.835050570925;5997.610155340063;6047.576669633259;6052.697365131814;6088.117223002729 08:53:25 INFO - PROCESS | 1641 | |1;ext.html;497.03327028928726;513.9614528910332;514.9588032957364;527.9730923354078;564.5004171308392;37498.43752343713;39802.00494987627;39916.002099947444;39945.400818987735;41723.165536689245;40820;41130;41155;41167.58832411675;41242;9239.815203695938;9295.907040929606;9387.95306023469;9403.529823508798;9409.62361505539;8060.355171586289;8394.24451799338;9112.772180695469;9129.908700913005;9185.40294880834;9258.814823703522;9295.860562091573;9299.860502092473;9304.767380815467;9312.767180820467;9249.53752312385;9304.813903721888;9305.302102342284;9319.534023298807;9321.207697345733;8135.511869287802;8139.674413023472;8165.183481651847;8170;8176.959115204445;38790.22419551593;38982;39077.60922390768;39101.80449097765;39176.21647567033;3106.5650808886776;3159.9730087721355;3215;3389.9322013559836;3584.3368976739125;28318.716812831815;28521.85739071312;29387.41225175506;30376.392472150656;30595.08214753561;3098.8450577471262;3214.148250713563;3266.869325226988;3292.407366673998;3327.234736010707;508024;514582;515681;518786.406067971;518934;60611;60721.08918366228;61113.08330375048;61168.38831611716;61171.77656446935;61145.38854611486;61509.692451537456;61523.76952461015;61744.382556174765;61846.38153618496;12580.748385032432;15331.386744530093;15479.613009674847;15482.845171548366;15516;11790.23363481371;11880.643580692593;11938.820917686242;11964.58123965657;11987.460564274628;15746.68506629861;16401.42595009169;16521;16550.255238514295;16636.58408539796;2711.4034912319257;5898.876404494378;6125.418085281891;6137.601055931439;6185.567010309235;860.4622111180512;946.4747065378643;975.3172779054548;1019.8934156440197;1028.7633844215873;25117.497650047262;26347;29583.704162958526;30021.549676754865;30222.94219702344;996.6163027234422;1006.9647562335283;1019.90820826126;1075.2795626929976;1202.3567391445536;12702.491900324068;14026.789598156036;15654.373825047098;15785.605359865865;15942.681146377008;470116;576678.2332176624;587111;624023;625898;45817.541824582;55634.44365556293;56176.71911640416;56677;56903.715481422325;1152.256794367645;1350.2843492948753;1374.7731624281882;1377.7175678985784;1377.7657798174134;23207.3037808866;28860.27849303784;29585.964491242703;29604.851975739984;29884;49568.75215623899;49618;49930.500694994036;49961;50057;511440;513995;514037;516327;516853;26348.736512634627;26517.734822651528;26796;26852.328691782863;26928;44796.32805507855;45146;46029;46577;46692.832679183295;37732;38221.853344399715;38493.80753096216;40078.797636070965;40171.79914100411;37956.24087518289;38132.42801358037;38977.2204555913;39201;39571.01072473096;37490.062748431505;37667.81166094152;37761.811190944965;37965.43051854169;38014.619853802214;32540.83729581337;33926.32147357088;34020.829895850366;34062.48906266457;34286.31427371489;25067.24798256055;25203.74796252014;25244.737763111403;25700.742992569834;25856.61215081738 08:53:25 INFO - PROCESS | 1641 | |2;jquery.html;352.8835484290186;355.4384073164399;368.45284752143044;373.46967306424875;375.2232877942659;16827.747583786237;19171.424857254286;19182.52043698908;19724.211031558738;19899.403017909448;15830.287637056332;19291.710624340612;19685.50786230345;19840.702389464168;19876.20495180195;5630.436956304367;5749.885002299972;5954.910676339859;5955.910661340084;5991.730372133243;5326.733663316838;5402.9464254470395;5599.188117722928;5637.746301416426;5828.766849326022;4731.148393289207;5062.6456148069465;5154.226865970116;5559.721264109257;5792.015357389254;5603.579731520153;5706.885862282752;5721.370649228582;5770.451807078362;5787.797427090031;5060.2156665716875;5080.415752188509;5115.2071428928575;5120.5903527718065;5130.615203859688;19698.50753731154;20203.89898050515;24890.875545622337;24941.376465588324;25175.748242517522;2104.9263275785274;2487.4900645367657;2503.224000559814;2839.6592408910856;2902.477554040272;13827.478977312487;14409.991300608961;14796.90039991624;15129.773053404208;15676;1681.1174133579846;1792.793828709702;1816.3824299738153;1822.9361972331037;1824.9361272355536;117558;118100;122343.16485252729;123600;123771;9286.44281343108;9707.46608936501;10043.296969212231;10079.748006299755;10112.241581881388;10163.390196588229;12110.697232569257;12346.320952347527;12419.254844709347;12459.563915262923;7744.57404842739;7989.960050199712;8027.799305017421;8069.273765361146;8101.351891848638;8173.918260817316;8304.294134998592;8307.709230177035;8445.831083378298;8465.40742148056;3440.810755408477;3766.7928263945196;3933.783641899666;3938.310795610814;4152.43942067817;1844.8893066416058;1918.2902326139417;1926.0273561851175;1928.1227041695995;1961.0381849690302;382.5237579213841;392.07861525415046;875.470340444035;896.1307531694246;1008.7831116310028;11221.66335009951;11728.179027467995;11763.705907352385;11828.76342473163;12066.034717222778;427.5916499742738;429.9935500967488;445.3653543700214;850.8213275212152;893.5264309915625;10387.962472648844;10782.815166580007;10787.671232876794;10793.896002297397;10803.362544113465;642062;652293.7385313043;660322;667200;667664;96560.99596675328;125439;125752;142697.5730242705;150173;957.6504575829912;982.2044144243185;989.4656885281867;993.7913038261902;994.1351024608781;18302.176402062207;18331.54171145732;18907;19067.809321906603;19362.128704208062;55498.72250638721;56047;56348.71825640846;58152;59168.81662366815;71751;77610;84288;85989.7306926531;86016;5920;6730.730770769114;6893.896591551231;7370.189279179179;7735.149133595188;11519.020883224684;12558.43487043067;12632.494700212255;12942.223466592035;12994.415251313525;10607.41659208751;10824.458777061272;10957;10973.067289280181;11240.32558046487;10251.538680759533;10528.210384221367;10752.08607268391;10815.134789217002;10947.945260273647;8463.915360846313;8784.95607521984;9431.481268530297;9746.805063898824;11480;5499.862503437286;7303.269673032654;7391.371733402721;7463.402927765657;7568.508046976929;10187.08316251541;10961.45192740344;10988.890111098786;11029.779404411707;11197.38414387184 08:53:25 INFO - PROCESS | 1641 | |3;mootools.html;1455.8980871339004;1465;1627.3978627907677;1771.6102457459353;1777.5911540345712;7136.965140054695;7512.060992375953;7744.303012728853;8026.759197224085;8514.829703405929;7953.602319883998;8219.219174178459;8240.093589705128;8345.374096942738;8368.497890126597;2309.8036666883295;2355.8233132515043;2355.9057637694473;2359.563480756057;2362.196853069956;2340.754220806816;2374.6675465434855;2377.1679912030822;2390.6414037894283;2395.700537432821;2325.5813953488364;2329.2080692564523;2350.17743789674;2358.705161854768;2365.4322962488964;2186.081845624841;2235.9776402235934;2241.3948233976816;2258.7289525256992;2272.420532764151;2332.7667233276707;2342.636891281854;2363.5509253242003;2369.7867191952637;2371.9644205336936;8248.298894593967;8312.25189732927;8372.58137093143;8389.412741108124;8397.790055248606;5073.441921388644;5190.143626301656;5203.245529398254;5207.062728708831;5212.1921102228835;2964.777641676883;3263.298390845956;3397.010630645041;3557.5553055868018;3572.07126147201;990.1583653894132;1035.2649618770681;1036.4921188617598;1096.6161843354841;1099.7305660113243;8682.479051256945;10747.247692661516;10845.728856778644;10861.619843305525;10916.181286403473;4524.23088075028;4652.418447694038;4711.963368059021;4743.098811225925;4827.686200396963;9265;9358.344915855825;9360.15758581731;9379.390339628038;9383.436993780395;5176.870578235575;5178.042062218502;5178.741062946838;5199.168133098686;5201.869953251124;4236.449261596034;4237.978810105929;4695.460022097503;4732.384789977281;4753.120672675566;3218.5011323244935;3800.771953682788;3886.2033283177448;3913.8649791560697;3939.4484772131923;1237.6596435979998;1253.4296894912793;1267.8288431061874;1268.5940499040403;1277.73806369694;760.444875241066;869.2437579306044;897.3945809266415;918.7794929217074;924.177482040984;5606.971965140148;5652.293463317085;5682.857928551736;5687.573431992617;5880.206172166702;451.41541703494056;454.3525476196407;457.8100088463317;459.82986295070367;461.9792109355087;3797.35444974355;3801.6198380161763;3821.5987321331418;3880.825362858678;3939.0940083780893;9610.51947402627;9968.551415186334;9970.950145249228;9987.250956178315;10035.14701250402;9820.754481137887;9941.005899410002;9941.950290248502;9954.45250511229;9957.751056223653;7676.961615192;7715.228477152352;7725.575093369806;7762.340201082971;7817.843643127106;5457.372402173801;5535.501804837584;5596.776128954959;5726.026575482261;5737.110747834093;8151.877721834058;9194.402363846328;9205.125513076247;9240.260779137787;9349.345545811728;9307.44155350654;9367.906320936976;9459.763505912408;9512.476813775309;9531;6513.120728701565;6740.427063699641;6897.931020689729;7094.645267736696;7096.325849044332;7832.490888092256;7998.880016799869;8000.079990800899;8017.679292828455;8038.919610804051;6204.565680402328;6210.5652604317265;7028.402585780266;7048.295170482911;7274.745383911538;6054.606450580698;6839.897401538881;7008.929910700827;7028.824279393056;7516.549007059594;5521.944780552143;6461.256955450183;6598.340165983555;6671.866562668816;6763.458923286224;4580.816767329269;4741.739204343657;4770.236762118045;4807.759612019455;4844.491328410607;4822.927656085091;4833.806647734052;4838.201696720002;4905.877353066202;4944.53026962438 08:53:25 INFO - PROCESS | 1641 | |4;prototype.html;305.88835075197545;307.7445720052356;310.2832457024273;316.4793914011451;320.95827542419494;4599.448066232053;5362.892742145154;5412.83761487156;5600.523955463786;5638.746256418461;4525.343825145352;5291.894162116755;5439.782408703657;5460.672359658423;5531.861703457415;3895.590962948892;4166.83332666693;4213.808819413903;4228.625696648586;4240.427542281791;3889.766614003169;3967.7024223183234;4200.852970146046;4246.68149888758;4253.510846252674;4155.065110350162;4170.52039015514;4170.768638778254;4178.268802959486;4205.558416366283;3705.3330400527893;4169.457970463851;4177.227212965611;4190.97904510477;4219.367094935754;2916.489614317486;3115.7040081192476;3541.610422853484;3578.427451607756;3616.150204701893;4212.241796476633;4903.289023091667;5009.849704508871;5313.388960269541;5348.171033489815;4331.242032644274;4889.559939605452;4909.484504127086;4931.284963680247;4931.876703082452;4967.40391153064;5659.122835960433;5843.590948633598;5853.473187413153;5881.41233847357;3455.6198818129988;3678.3562876496508;3780.810959452044;3862.324093283692;4095.4266402703647;5368.342670378149;5567.638103523299;6060.3939606039485;6084;6114.694265286762;3350.798952062884;3386.441237195835;3931.6264954829244;3958.8812335629977;4171.97914010428;3527.2945410917405;3920.6471417572557;4216.7680777557525;4218.110594242953;4236.2374772540625;4907.9263811042865;4913.707815763447;4918.360613120271;4948.505149485023;4956.900861982812;4987.476314986947;5745.913811292834;5922.526197904157;5958.851028724316;5998.49012833914;1153.5212886651946;1290.3548225887057;1297.202220634303;1361.5779108476404;1375.9748987004546;771.413725568572;902.3683421604903;905.9310014183195;944.4994153098869;947.8672985782063;4389.31965545341;4682.528185582761;5217.947820521822;5254.65844720092;5266.683998960075;4755.667103302805;4883.755812209375;5413.864653383697;5597.468240517144;5614.550835933115;4333.090051089243;4705.811797514172;5176.430592634807;5247.632665713363;5275.868103297448;2957.5711521829644;2999.9550006749914;3023.289526961162;3066.175625502822;3102.7672924530752;5057.6459647824295;6033.616721395025;6089.543284253698;6121.296050954108;6135.877282454415;5039.44566097729;5161.870953226125;5927.288725352899;5950.285965684146;6010.909836352371;6063.363346848606;6073.0283154695035;6086.208792856989;6119.755209791559;6153.661548614779;5315.734213289243;5606.775728970795;5675.77296908119;5838.591298609226;5885.646861188342;4724.763761811964;4885.364902562644;5547.278853748987;5605.067540564277;5753.482186603226;5239.349604874453;5497.340319161726;5712.314522257356;6023.831420688636;6093.593030752916;3194.8881789137267;3447.6552344765323;3668.3763760160305;3705.333040052816;3978.0253837809923;5392.460753924576;5583.385827559046;5914.0671453482955;6073.514118870568;6117.663528505975;5705.885882282414;5868.677222752619;5946.762129514944;5958.433948774859;5969.432903874125;5719.884622498739;5724.856878578069;5939.2575928009;5947.9702601486715;5983;4837.346958160603;5575;5664.886702266014;5721.799737009184;5793.94206057934;4515.774211289488;4949.812045109076;5129.512696293846;5886.499647530008;5900.380460051804;5639.7180140993605;5782.971085144547;5793.594448388572;5803.8549036274435;5806.332271788799 08:53:25 INFO - PROCESS | 1641 | |5;yui.html;686.1183379357525;701.400302741156;709.5025510204079;713.1121753416996;724.786188074518;12361.938190309036;13893.235872027035;14632.561023169295;14757.040792348516;14813.777793333094;10764.569417223322;13110.082294239382;13204.933975330134;13748.518801841938;13782.862171378283;697.4873468001015;807.9335277433789;809.5992483720566;834.4409245805294;842.3513894301384;574.6867956963465;680.9148856392951;805.3396215103618;820.7578764264556;825.7027470110755;841.474078700812;871.3116637856086;873.4977388001925;879.6657270237301;880.8898887639045;853.8121613245077;868.374770165483;869.0136694851326;871.333429926107;871.3551971541077;624.4536030972898;636.2778246689994;646.8382904273932;647.9416852483299;650.0931200974613;13919.58241252754;14153.433862645481;14333;14342.569722908329;14350.425982960669;12781.233126012376;13109.213447193199;13289.601311960656;13296.468141274338;13430.462781488728;11015.173861960386;11161.497732602053;11304;11420.086393088533;11655.88344116565;3474.3051389722154;3660.5973342932257;3703.870364537255;3751.606081361472;3759.4360845873066;84726;88447.55776221078;88652;89310.553447233;90227.54886225592;10211.438370889673;10353.119984801226;10492.842607360895;10500.05499505033;10615.0446459819;15750.684986300439;19402.902985484983;19486.805131948782;19662.410127696192;19689.606207876048;7340.15588207363;7395;7440.4419668524;7512.661930213154;7590.924090759132;3938.369860822312;3994.6005399460405;3998.520177578651;4030.5969403059457;4064.634182923551;3318.5354050432957;3438.2607739335663;3473.583170019563;3478.4086705260165;3490.6334834842482;562.1679913727706;575.7362589116932;622.3589702606323;645.8223988403226;652.3411354531956;605.8879107365152;630.7792272704517;634.9806669287508;640.4972096903908;681.9181698196248;8413.579321033923;8533.701320453754;8643.78390540229;8801.51591662452;8850.291976641853;479.94000749906263;576.2019602849999;607.4381197392482;648.3322178156507;666.4435196610788;3473.1838018065732;4057.1885622875543;4178.540360560336;4224.413248301178;4358.607725304738;73644.63177684184;92137;108456;108624.37063444055;110189;97566;97674.5116274414;98824.50587747159;98871.50564247133;100532;86328.13671863326;87368;88028.11971880327;88232;90304.5484772585;7847.176046515262;8188.426810123234;8250.587470626324;8310.168983101641;8317.875231871405;57127.42872571387;57590.7120464395;57672.134917975425;57898.7105064472;59224;78534;79100;81010.78483822865;82249;83100.75348869893;9550.426974381558;10770.330598757206;10932.890671093506;11012.669619911416;11095.611653592085;11594.768104637691;11714.824277636013;12056.457459414523;12205.511779529075;12215.144939854481;12498.937505312415;12832.679183020498;12995.220286782445;13137.671558211121;13309.86690133125;11183.552657893919;12915.354232288535;12976.545820896223;13348.666283342995;13511.932440337736;11143.609973650884;11760.647525534672;12414.068944828994;12424.440900159334;12928.288944108164;9752.122308992224;9860.852087218555;10387.792244155225;10653.414062226342;10802.67591972242;10427.061564459234;10463.058324750504;10501.632442864464;10535.894641053797;10668.83996740034 08:53:25 INFO - PROCESS | 1641 | __end_tp_report 08:53:25 INFO - PROCESS | 1641 | __start_cc_report 08:53:25 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,366 08:53:25 INFO - PROCESS | 1641 | __end_cc_report 08:53:25 INFO - PROCESS | 1641 | __startTimestamp1481302405566__endTimestamp 08:53:25 INFO - PROCESS | 1641 | 08:53:25 INFO - PROCESS | 1641 | ------- Summary: start ------- 08:53:25 INFO - PROCESS | 1641 | Number of tests: 6 08:53:25 INFO - PROCESS | 1641 | 08:53:25 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5744.31 Median:5979.94 stddev:648.90 (10.9%) stddev-sans-first:623.63 08:53:25 INFO - PROCESS | 1641 | Values: 3372.1 3407.0 3407.5 3408.4 3410.0 5550.2 5601.6 5613.3 5619.2 5619.7 5723.3 5832.2 5856.9 5860.6 5870.1 4413.6 5261.0 5269.3 5313.9 5317.7 5325.1 5363.1 5375.8 5383.8 5407.2 5208.6 5280.7 5342.0 5343.2 5354.1 5249.2 5273.6 5318.2 5318.3 5342.7 5184.1 5249.9 5325.7 5333.2 5346.9 5869.0 5881.9 5909.1 5919.1 5941.1 6124.5 6159.4 6243.9 6266.0 6286.4 6223.8 6237.0 6253.1 6259.6 6263.2 5884.4 5899.9 5904.5 5964.5 5965.2 5330.4 6224.6 6335.3 6336.0 6347.4 6089.6 6129.3 6143.3 6146.8 6160.6 5908.6 6274.2 6274.3 6294.1 6302.6 6237.3 6251.7 6279.3 6282.4 6294.8 5528.5 5731.5 6184.0 6185.7 6248.1 4949.8 4955.8 4995.4 5000.2 5038.3 4437.1 4457.5 4483.8 4491.3 4518.2 5285.9 5821.4 5826.2 5950.4 6052.0 5146.4 5301.6 6222.6 6240.3 6240.8 5101.3 5799.2 5980.6 6002.9 6049.2 5111.4 5202.8 5347.8 5401.6 5441.7 6222.4 6263.2 6320.6 6512.8 6530.8 5073.3 5279.6 6248.8 6257.7 6273.6 6399.5 6410.5 6465.2 6470.7 6473.7 4990.5 6096.4 6253.7 6258.0 6289.8 5041.8 6159.8 6247.7 6250.3 6261.0 6176.9 6184.8 6212.0 6216.4 6220.7 6005.3 6181.6 6206.5 6216.4 6237.4 5729.1 5979.9 6015.5 6015.7 6118.4 6153.8 6183.5 6194.5 6215.3 6238.9 5988.8 5997.6 6047.6 6052.7 6088.1 08:53:25 INFO - PROCESS | 1641 | 08:53:25 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:66799.86 Median:39686.51 stddev:143384.83 (361.3%) stddev-sans-first:143700.65 08:53:25 INFO - PROCESS | 1641 | Values: 497.0 514.0 515.0 528.0 564.5 37498.4 39802.0 39916.0 39945.4 41723.2 40820.0 41130.0 41155.0 41167.6 41242.0 9239.8 9295.9 9388.0 9403.5 9409.6 8060.4 8394.2 9112.8 9129.9 9185.4 9258.8 9295.9 9299.9 9304.8 9312.8 9249.5 9304.8 9305.3 9319.5 9321.2 8135.5 8139.7 8165.2 8170.0 8177.0 38790.2 38982.0 39077.6 39101.8 39176.2 3106.6 3160.0 3215.0 3389.9 3584.3 28318.7 28521.9 29387.4 30376.4 30595.1 3098.8 3214.1 3266.9 3292.4 3327.2 508024.0 514582.0 515681.0 518786.4 518934.0 60611.0 60721.1 61113.1 61168.4 61171.8 61145.4 61509.7 61523.8 61744.4 61846.4 12580.7 15331.4 15479.6 15482.8 15516.0 11790.2 11880.6 11938.8 11964.6 11987.5 15746.7 16401.4 16521.0 16550.3 16636.6 2711.4 5898.9 6125.4 6137.6 6185.6 860.5 946.5 975.3 1019.9 1028.8 25117.5 26347.0 29583.7 30021.5 30222.9 996.6 1007.0 1019.9 1075.3 1202.4 12702.5 14026.8 15654.4 15785.6 15942.7 470116.0 576678.2 587111.0 624023.0 625898.0 45817.5 55634.4 56176.7 56677.0 56903.7 1152.3 1350.3 1374.8 1377.7 1377.8 23207.3 28860.3 29586.0 29604.9 29884.0 49568.8 49618.0 49930.5 49961.0 50057.0 511440.0 513995.0 514037.0 516327.0 516853.0 26348.7 26517.7 26796.0 26852.3 26928.0 44796.3 45146.0 46029.0 46577.0 46692.8 37732.0 38221.9 38493.8 40078.8 40171.8 37956.2 38132.4 38977.2 39201.0 39571.0 37490.1 37667.8 37761.8 37965.4 38014.6 32540.8 33926.3 34020.8 34062.5 34286.3 25067.2 25203.7 25244.7 25700.7 25856.6 08:53:25 INFO - PROCESS | 1641 | 08:53:25 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:36361.55 Median:354.16 stddev:109591.30 (30943.9%) stddev-sans-first:109865.38 08:53:25 INFO - PROCESS | 1641 | Values: 352.9 355.4 368.5 373.5 375.2 16827.7 19171.4 19182.5 19724.2 19899.4 15830.3 19291.7 19685.5 19840.7 19876.2 5630.4 5749.9 5954.9 5955.9 5991.7 5326.7 5402.9 5599.2 5637.7 5828.8 4731.1 5062.6 5154.2 5559.7 5792.0 5603.6 5706.9 5721.4 5770.5 5787.8 5060.2 5080.4 5115.2 5120.6 5130.6 19698.5 20203.9 24890.9 24941.4 25175.7 2104.9 2487.5 2503.2 2839.7 2902.5 13827.5 14410.0 14796.9 15129.8 15676.0 1681.1 1792.8 1816.4 1822.9 1824.9 117558.0 118100.0 122343.2 123600.0 123771.0 9286.4 9707.5 10043.3 10079.7 10112.2 10163.4 12110.7 12346.3 12419.3 12459.6 7744.6 7990.0 8027.8 8069.3 8101.4 8173.9 8304.3 8307.7 8445.8 8465.4 3440.8 3766.8 3933.8 3938.3 4152.4 1844.9 1918.3 1926.0 1928.1 1961.0 382.5 392.1 875.5 896.1 1008.8 11221.7 11728.2 11763.7 11828.8 12066.0 427.6 430.0 445.4 850.8 893.5 10388.0 10782.8 10787.7 10793.9 10803.4 642062.0 652293.7 660322.0 667200.0 667664.0 96561.0 125439.0 125752.0 142697.6 150173.0 957.7 982.2 989.5 993.8 994.1 18302.2 18331.5 18907.0 19067.8 19362.1 55498.7 56047.0 56348.7 58152.0 59168.8 71751.0 77610.0 84288.0 85989.7 86016.0 5920.0 6730.7 6893.9 7370.2 7735.1 11519.0 12558.4 12632.5 12942.2 12994.4 10607.4 10824.5 10957.0 10973.1 11240.3 10251.5 10528.2 10752.1 10815.1 10947.9 8463.9 8785.0 9431.5 9746.8 11480.0 5499.9 7303.3 7391.4 7463.4 7568.5 10187.1 10961.5 10988.9 11029.8 11197.4 08:53:25 INFO - PROCESS | 1641 | 08:53:25 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5373.53 Median:5202.56 stddev:2936.13 (56.4%) stddev-sans-first:2929.60 08:53:25 INFO - PROCESS | 1641 | Values: 1455.9 1465.0 1627.4 1771.6 1777.6 7137.0 7512.1 7744.3 8026.8 8514.8 7953.6 8219.2 8240.1 8345.4 8368.5 2309.8 2355.8 2355.9 2359.6 2362.2 2340.8 2374.7 2377.2 2390.6 2395.7 2325.6 2329.2 2350.2 2358.7 2365.4 2186.1 2236.0 2241.4 2258.7 2272.4 2332.8 2342.6 2363.6 2369.8 2372.0 8248.3 8312.3 8372.6 8389.4 8397.8 5073.4 5190.1 5203.2 5207.1 5212.2 2964.8 3263.3 3397.0 3557.6 3572.1 990.2 1035.3 1036.5 1096.6 1099.7 8682.5 10747.2 10845.7 10861.6 10916.2 4524.2 4652.4 4712.0 4743.1 4827.7 9265.0 9358.3 9360.2 9379.4 9383.4 5176.9 5178.0 5178.7 5199.2 5201.9 4236.4 4238.0 4695.5 4732.4 4753.1 3218.5 3800.8 3886.2 3913.9 3939.4 1237.7 1253.4 1267.8 1268.6 1277.7 760.4 869.2 897.4 918.8 924.2 5607.0 5652.3 5682.9 5687.6 5880.2 451.4 454.4 457.8 459.8 462.0 3797.4 3801.6 3821.6 3880.8 3939.1 9610.5 9968.6 9971.0 9987.3 10035.1 9820.8 9941.0 9942.0 9954.5 9957.8 7677.0 7715.2 7725.6 7762.3 7817.8 5457.4 5535.5 5596.8 5726.0 5737.1 8151.9 9194.4 9205.1 9240.3 9349.3 9307.4 9367.9 9459.8 9512.5 9531.0 6513.1 6740.4 6897.9 7094.6 7096.3 7832.5 7998.9 8000.1 8017.7 8038.9 6204.6 6210.6 7028.4 7048.3 7274.7 6054.6 6839.9 7008.9 7028.8 7516.5 5521.9 6461.3 6598.3 6671.9 6763.5 4580.8 4741.7 4770.2 4807.8 4844.5 4822.9 4833.8 4838.2 4905.9 4944.5 08:53:25 INFO - PROCESS | 1641 | 08:53:25 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4628.09 Median:5145.69 stddev:1442.87 (28.0%) stddev-sans-first:1409.98 08:53:25 INFO - PROCESS | 1641 | Values: 305.9 307.7 310.3 316.5 321.0 4599.4 5362.9 5412.8 5600.5 5638.7 4525.3 5291.9 5439.8 5460.7 5531.9 3895.6 4166.8 4213.8 4228.6 4240.4 3889.8 3967.7 4200.9 4246.7 4253.5 4155.1 4170.5 4170.8 4178.3 4205.6 3705.3 4169.5 4177.2 4191.0 4219.4 2916.5 3115.7 3541.6 3578.4 3616.2 4212.2 4903.3 5009.8 5313.4 5348.2 4331.2 4889.6 4909.5 4931.3 4931.9 4967.4 5659.1 5843.6 5853.5 5881.4 3455.6 3678.4 3780.8 3862.3 4095.4 5368.3 5567.6 6060.4 6084.0 6114.7 3350.8 3386.4 3931.6 3958.9 4172.0 3527.3 3920.6 4216.8 4218.1 4236.2 4907.9 4913.7 4918.4 4948.5 4956.9 4987.5 5745.9 5922.5 5958.9 5998.5 1153.5 1290.4 1297.2 1361.6 1376.0 771.4 902.4 905.9 944.5 947.9 4389.3 4682.5 5217.9 5254.7 5266.7 4755.7 4883.8 5413.9 5597.5 5614.6 4333.1 4705.8 5176.4 5247.6 5275.9 2957.6 3000.0 3023.3 3066.2 3102.8 5057.6 6033.6 6089.5 6121.3 6135.9 5039.4 5161.9 5927.3 5950.3 6010.9 6063.4 6073.0 6086.2 6119.8 6153.7 5315.7 5606.8 5675.8 5838.6 5885.6 4724.8 4885.4 5547.3 5605.1 5753.5 5239.3 5497.3 5712.3 6023.8 6093.6 3194.9 3447.7 3668.4 3705.3 3978.0 5392.5 5583.4 5914.1 6073.5 6117.7 5705.9 5868.7 5946.8 5958.4 5969.4 5719.9 5724.9 5939.3 5948.0 5983.0 4837.3 5575.0 5664.9 5721.8 5793.9 4515.8 4949.8 5129.5 5886.5 5900.4 5639.7 5783.0 5793.6 5803.9 5806.3 08:53:25 INFO - PROCESS | 1641 | 08:53:25 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20403.82 Median:2419.27 stddev:30205.49 (1248.5%) stddev-sans-first:30253.94 08:53:25 INFO - PROCESS | 1641 | Values: 686.1 701.4 709.5 713.1 724.8 12361.9 13893.2 14632.6 14757.0 14813.8 10764.6 13110.1 13204.9 13748.5 13782.9 697.5 807.9 809.6 834.4 842.4 574.7 680.9 805.3 820.8 825.7 841.5 871.3 873.5 879.7 880.9 853.8 868.4 869.0 871.3 871.4 624.5 636.3 646.8 647.9 650.1 13919.6 14153.4 14333.0 14342.6 14350.4 12781.2 13109.2 13289.6 13296.5 13430.5 11015.2 11161.5 11304.0 11420.1 11655.9 3474.3 3660.6 3703.9 3751.6 3759.4 84726.0 88447.6 88652.0 89310.6 90227.5 10211.4 10353.1 10492.8 10500.1 10615.0 15750.7 19402.9 19486.8 19662.4 19689.6 7340.2 7395.0 7440.4 7512.7 7590.9 3938.4 3994.6 3998.5 4030.6 4064.6 3318.5 3438.3 3473.6 3478.4 3490.6 562.2 575.7 622.4 645.8 652.3 605.9 630.8 635.0 640.5 681.9 8413.6 8533.7 8643.8 8801.5 8850.3 479.9 576.2 607.4 648.3 666.4 3473.2 4057.2 4178.5 4224.4 4358.6 73644.6 92137.0 108456.0 108624.4 110189.0 97566.0 97674.5 98824.5 98871.5 100532.0 86328.1 87368.0 88028.1 88232.0 90304.5 7847.2 8188.4 8250.6 8310.2 8317.9 57127.4 57590.7 57672.1 57898.7 59224.0 78534.0 79100.0 81010.8 82249.0 83100.8 9550.4 10770.3 10932.9 11012.7 11095.6 11594.8 11714.8 12056.5 12205.5 12215.1 12498.9 12832.7 12995.2 13137.7 13309.9 11183.6 12915.4 12976.5 13348.7 13511.9 11143.6 11760.6 12414.1 12424.4 12928.3 9752.1 9860.9 10387.8 10653.4 10802.7 10427.1 10463.1 10501.6 10535.9 10668.8 08:53:25 INFO - PROCESS | 1641 | -------- Summary: end -------- 08:53:25 INFO - PROCESS | 1641 | 08:53:26 INFO - TEST-INFO | 1641: exit 0 08:53:26 INFO - TEST-OK | dromaeo_css | took 1117265ms 08:53:26 INFO - TEST-START | kraken 08:53:26 INFO - operating with platform_type : mac_ 08:53:26 INFO - Initialising browser for kraken test... 08:53:26 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpepsxjv/profile http://localhost:49240/getInfo.html) 08:53:29 INFO - PROCESS | 1652 | 2016-12-09 08:53:29.280 plugin-container[1653:10361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 08:53:29 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:29 INFO - PROCESS | 1652 | 2016-12-09 08:53:29.283 plugin-container[1653:10361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 08:53:29 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:29 INFO - PROCESS | 1652 | 2016-12-09 08:53:29.283 plugin-container[1653:10361] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:53:29 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 08:53:29 INFO - PROCESS | 1652 | colorDepth:24 08:53:29 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 08:53:29 INFO - PROCESS | 1652 | __metrics 08:53:29 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:53:30 INFO - TEST-INFO | 1652: exit 0 08:53:30 INFO - Browser initialized. 08:53:30 INFO - Running cycle 1/1 for kraken test... 08:53:30 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpepsxjv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:53:32 INFO - PROCESS | 1654 | 2016-12-09 08:53:32.840 plugin-container[1655:10485] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 08:53:32 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:32 INFO - PROCESS | 1654 | 2016-12-09 08:53:32.841 plugin-container[1655:10485] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 08:53:32 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:32 INFO - PROCESS | 1654 | 2016-12-09 08:53:32.841 plugin-container[1655:10485] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:53:42 INFO - PROCESS | 1654 | RSS: Main: 229507072 08:53:42 INFO - PROCESS | 1654 | 08:53:45 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 08:53:46 INFO - PROCESS | 1654 | RSS: Main: 241598464 08:53:46 INFO - PROCESS | 1654 | 08:53:48 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 08:53:49 INFO - PROCESS | 1654 | RSS: Main: 249270272 08:53:49 INFO - PROCESS | 1654 | 08:53:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 08:53:53 INFO - PROCESS | 1654 | RSS: Main: 252932096 08:53:53 INFO - PROCESS | 1654 | 08:53:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 08:53:55 INFO - PROCESS | 1654 | RSS: Main: 254484480 08:53:55 INFO - PROCESS | 1654 | 08:53:57 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:53:58 INFO - PROCESS | 1654 | RSS: Main: 254410752 08:53:58 INFO - PROCESS | 1654 | 08:54:01 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 08:54:01 INFO - PROCESS | 1654 | RSS: Main: 258457600 08:54:01 INFO - PROCESS | 1654 | 08:54:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 08:54:05 INFO - PROCESS | 1654 | RSS: Main: 258699264 08:54:05 INFO - PROCESS | 1654 | 08:54:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 08:54:09 INFO - PROCESS | 1654 | RSS: Main: 258322432 08:54:09 INFO - PROCESS | 1654 | 08:54:10 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:54:11 INFO - PROCESS | 1654 | RSS: Main: 257318912 08:54:11 INFO - PROCESS | 1654 | 08:54:13 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:54:14 INFO - PROCESS | 1654 | RSS: Main: 257372160 08:54:14 INFO - PROCESS | 1654 | 08:54:16 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:54:17 INFO - PROCESS | 1654 | RSS: Main: 257343488 08:54:17 INFO - PROCESS | 1654 | 08:54:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:54:20 INFO - PROCESS | 1654 | RSS: Main: 257617920 08:54:20 INFO - PROCESS | 1654 | 08:54:23 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:54:24 INFO - PROCESS | 1654 | RSS: Main: 257630208 08:54:24 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 08:54:26 INFO - PROCESS | 1654 | RSS: Main: 259379200 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | __start_tp_report 08:54:26 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 08:54:26 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 08:54:26 INFO - PROCESS | 1654 | |i|pagename|runs| 08:54:26 INFO - PROCESS | 1654 | |0;ai-astar;107;117;117;119;118;119;118;119;118;118 08:54:26 INFO - PROCESS | 1654 | |1;audio-beat-detection;149;118;112;151;115;119;133;121;142;114 08:54:26 INFO - PROCESS | 1654 | |2;audio-dft;193;203;196;193;194;194;193;196;200;197 08:54:26 INFO - PROCESS | 1654 | |3;audio-fft;75;57;65;98;81;58;59;60;78;57 08:54:26 INFO - PROCESS | 1654 | |4;audio-oscillator;85;71;88;74;69;70;84;68;72;87 08:54:26 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;90;88;90;88;91;87;91;88;121;88 08:54:26 INFO - PROCESS | 1654 | |6;imaging-darkroom;106;173;119;101;100;137;148;174;99;100 08:54:26 INFO - PROCESS | 1654 | |7;imaging-desaturate;92;91;71;137;140;90;90;87;132;91 08:54:26 INFO - PROCESS | 1654 | |8;json-parse-financial;58;57;56;60;61;60;70;75;51;63 08:54:26 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;85;107;57;73;91;109;101;104;103;76 08:54:26 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;85;87;90;86;86;85;84;89;83;83 08:54:26 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;160;159;144;153;159;158;149;133;209;181 08:54:26 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;170;171;170;168;170;163;170;169;169;170 08:54:26 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;67;70;68;68;72;67;69;69;79;89 08:54:26 INFO - PROCESS | 1654 | __end_tp_report 08:54:26 INFO - PROCESS | 1654 | __start_cc_report 08:54:26 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,966 08:54:26 INFO - PROCESS | 1654 | __end_cc_report 08:54:26 INFO - PROCESS | 1654 | __startTimestamp1481302466328__endTimestamp 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | ------- Summary: start ------- 08:54:26 INFO - PROCESS | 1654 | Number of tests: 14 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:117.00 Median:118.00 stddev:3.59 (3.0%) stddev-sans-first:0.78 08:54:26 INFO - PROCESS | 1654 | Values: 107.0 117.0 117.0 119.0 118.0 119.0 118.0 119.0 118.0 118.0 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:127.40 Median:127.00 stddev:15.05 (11.9%) stddev-sans-first:13.78 08:54:26 INFO - PROCESS | 1654 | Values: 149.0 118.0 112.0 151.0 115.0 119.0 133.0 121.0 142.0 114.0 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:195.90 Median:196.00 stddev:3.35 (1.7%) stddev-sans-first:3.38 08:54:26 INFO - PROCESS | 1654 | Values: 193.0 203.0 196.0 193.0 194.0 194.0 193.0 196.0 200.0 197.0 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:68.80 Median:70.00 stddev:13.77 (19.7%) stddev-sans-first:14.43 08:54:26 INFO - PROCESS | 1654 | Values: 75.0 57.0 65.0 98.0 81.0 58.0 59.0 60.0 78.0 57.0 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:79.00 stddev:8.15 (10.3%) stddev-sans-first:8.08 08:54:26 INFO - PROCESS | 1654 | Values: 85.0 71.0 88.0 74.0 69.0 70.0 84.0 68.0 72.0 87.0 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:92.20 Median:89.00 stddev:10.22 (11.5%) stddev-sans-first:10.81 08:54:26 INFO - PROCESS | 1654 | Values: 90.0 88.0 90.0 88.0 91.0 87.0 91.0 88.0 121.0 88.0 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:125.70 Median:142.50 stddev:30.32 (21.3%) stddev-sans-first:31.31 08:54:26 INFO - PROCESS | 1654 | Values: 106.0 173.0 119.0 101.0 100.0 137.0 148.0 174.0 99.0 100.0 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:102.10 Median:90.00 stddev:24.46 (27.2%) stddev-sans-first:25.67 08:54:26 INFO - PROCESS | 1654 | Values: 92.0 91.0 71.0 137.0 140.0 90.0 90.0 87.0 132.0 91.0 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:61.10 Median:60.50 stddev:6.94 (11.5%) stddev-sans-first:7.26 08:54:26 INFO - PROCESS | 1654 | Values: 58.0 57.0 56.0 60.0 61.0 60.0 70.0 75.0 51.0 63.0 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:90.60 Median:65.00 stddev:17.42 (26.8%) stddev-sans-first:18.36 08:54:26 INFO - PROCESS | 1654 | Values: 85.0 107.0 57.0 73.0 91.0 109.0 101.0 104.0 103.0 76.0 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:86.00 stddev:2.35 (2.7%) stddev-sans-first:2.47 08:54:26 INFO - PROCESS | 1654 | Values: 85.0 87.0 90.0 86.0 86.0 85.0 84.0 89.0 83.0 83.0 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:160.50 Median:159.00 stddev:21.08 (13.3%) stddev-sans-first:22.36 08:54:26 INFO - PROCESS | 1654 | Values: 160.0 159.0 144.0 153.0 159.0 158.0 149.0 133.0 209.0 181.0 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:170.00 stddev:2.26 (1.3%) stddev-sans-first:2.37 08:54:26 INFO - PROCESS | 1654 | Values: 170.0 171.0 170.0 168.0 170.0 163.0 170.0 169.0 169.0 170.0 08:54:26 INFO - PROCESS | 1654 | 08:54:26 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:69.50 stddev:7.00 (10.1%) stddev-sans-first:7.21 08:54:26 INFO - PROCESS | 1654 | Values: 67.0 70.0 68.0 68.0 72.0 67.0 69.0 69.0 79.0 89.0 08:54:26 INFO - PROCESS | 1654 | -------- Summary: end -------- 08:54:26 INFO - PROCESS | 1654 | 08:54:27 INFO - TEST-INFO | 1654: exit 0 08:54:27 INFO - TEST-OK | kraken | took 61151ms 08:54:27 INFO - SUITE-END | took 1178s 08:54:27 INFO - Completed test suite (00:19:38) 08:54:27 INFO - javascript benchmark 08:54:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7066.192237393513, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3372.0895358253274, 3407.046027112408, 3407.505911642811, 3408.4205685033544, 3410.0, 5550.16747487877, 5601.6358936669085, 5613.270274864272, 5619.213310136579, 5619.662820230784, 5723.313202415716, 5832.212651292074, 5856.853578660535, 5860.619059761112, 5870.11948207768, 4413.558644135577, 5261.0, 5269.2886460327745, 5313.920291195635, 5317.73411329432, 5325.094733895227, 5363.141897296433, 5375.7849686012705, 5383.757730902099, 5407.161889907051, 5208.5572726318305, 5280.656757310781, 5342.038433082043, 5343.225232341309, 5354.089804733185, 5249.2126181073145, 5273.604479664022, 5318.175682769177, 5318.30861987941, 5342.679439233659, 5184.066867963765, 5249.947500525022, 5325.707086110262, 5333.173358129496, 5346.91979620306, 5869.0, 5881.85295367615, 5909.084091965709, 5919.112133180014, 5941.079132734433, 6124.540659450515, 6159.384061593805, 6243.937560624381, 6265.968670156665, 6286.4342209201395, 6223.7821676241565, 6236.970899801528, 6253.062040693887, 6259.561830671855, 6263.1544741459775, 5884.411558844125, 5899.941000589939, 5904.527637788967, 5964.522838172936, 5965.224520812267, 5330.440303768126, 6224.595401298901, 6335.303116657164, 6335.954567496404, 6347.365263473708, 6089.63462192261, 6129.267100814801, 6143.32423433422, 6146.846328841814, 6160.568760186744, 5908.645481271137, 6274.247090349188, 6274.341194174637, 6294.118823364733, 6302.558820882586, 6237.282712488031, 6251.749930002749, 6279.340669229665, 6282.371762823681, 6294.779682711083, 5528.530074943595, 5731.455511726379, 6184.0, 6185.659788711574, 6248.125262463259, 4949.752512374367, 4955.776990035385, 4995.375578052744, 5000.1749711297225, 5038.269450929594, 4437.134758722018, 4457.531959144308, 4483.775811209426, 4491.281394976788, 4518.209313370147, 5285.920711189336, 5821.359650438449, 5826.202434324201, 5950.4049595041015, 6052.03167493199, 5146.433892271847, 5301.575873930076, 6222.62664240147, 6240.31356550779, 6240.7503699851495, 5101.285819985205, 5799.217105690762, 5980.611260268069, 6002.939970600238, 6049.243844519435, 5111.437741848394, 5202.76587553561, 5347.812826551052, 5401.5948803839865, 5441.727913604304, 6222.408871157233, 6263.248410190807, 6320.5891617044745, 6512.772052978123, 6530.8040758777315, 5073.34046573943, 5279.577633789365, 6248.781292654735, 6257.68711564429, 6273.62358258506, 6399.488040956619, 6410.487161027013, 6465.159529261354, 6470.708818103102, 6473.676316184078, 4990.47600002009, 6096.359882212481, 6253.7185826637, 6257.9687101564205, 6289.779857704958, 5041.773120209673, 6159.784407545714, 6247.718852651551, 6250.312465628686, 6260.968695156495, 6176.938230617637, 6184.783532576338, 6212.0, 6216.378362163748, 6220.657863817533, 6005.279366476051, 6181.629102253879, 6206.503479721701, 6216.378362163748, 6237.376262373727, 5729.140628905698, 5979.940200597938, 6015.48868346178, 6015.699215039318, 6118.449339559283, 6153.815385538441, 6183.536234782319, 6194.535409844189, 6215.254169499689, 6238.937610624017, 5988.835050570925, 5997.610155340063, 6047.576669633259, 6052.697365131814, 6088.117223002729], "value": 5706.010378928972, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.03327028928726, 513.9614528910332, 514.9588032957364, 527.9730923354078, 564.5004171308392, 37498.43752343713, 39802.00494987627, 39916.002099947444, 39945.400818987735, 41723.165536689245, 40820.0, 41130.0, 41155.0, 41167.58832411675, 41242.0, 9239.815203695938, 9295.907040929606, 9387.95306023469, 9403.529823508798, 9409.62361505539, 8060.355171586289, 8394.24451799338, 9112.772180695469, 9129.908700913005, 9185.40294880834, 9258.814823703522, 9295.860562091573, 9299.860502092473, 9304.767380815467, 9312.767180820467, 9249.53752312385, 9304.813903721888, 9305.302102342284, 9319.534023298807, 9321.207697345733, 8135.511869287802, 8139.674413023472, 8165.183481651847, 8170.0, 8176.959115204445, 38790.22419551593, 38982.0, 39077.60922390768, 39101.80449097765, 39176.21647567033, 3106.5650808886776, 3159.9730087721355, 3215.0, 3389.9322013559836, 3584.3368976739125, 28318.716812831815, 28521.85739071312, 29387.41225175506, 30376.392472150656, 30595.08214753561, 3098.8450577471262, 3214.148250713563, 3266.869325226988, 3292.407366673998, 3327.234736010707, 508024.0, 514582.0, 515681.0, 518786.406067971, 518934.0, 60611.0, 60721.08918366228, 61113.08330375048, 61168.38831611716, 61171.77656446935, 61145.38854611486, 61509.692451537456, 61523.76952461015, 61744.382556174765, 61846.38153618496, 12580.748385032432, 15331.386744530093, 15479.613009674847, 15482.845171548366, 15516.0, 11790.23363481371, 11880.643580692593, 11938.820917686242, 11964.58123965657, 11987.460564274628, 15746.68506629861, 16401.42595009169, 16521.0, 16550.255238514295, 16636.58408539796, 2711.4034912319257, 5898.876404494378, 6125.418085281891, 6137.601055931439, 6185.567010309235, 860.4622111180512, 946.4747065378643, 975.3172779054548, 1019.8934156440197, 1028.7633844215873, 25117.497650047262, 26347.0, 29583.704162958526, 30021.549676754865, 30222.94219702344, 996.6163027234422, 1006.9647562335283, 1019.90820826126, 1075.2795626929976, 1202.3567391445536, 12702.491900324068, 14026.789598156036, 15654.373825047098, 15785.605359865865, 15942.681146377008, 470116.0, 576678.2332176624, 587111.0, 624023.0, 625898.0, 45817.541824582, 55634.44365556293, 56176.71911640416, 56677.0, 56903.715481422325, 1152.256794367645, 1350.2843492948753, 1374.7731624281882, 1377.7175678985784, 1377.7657798174134, 23207.3037808866, 28860.27849303784, 29585.964491242703, 29604.851975739984, 29884.0, 49568.75215623899, 49618.0, 49930.500694994036, 49961.0, 50057.0, 511440.0, 513995.0, 514037.0, 516327.0, 516853.0, 26348.736512634627, 26517.734822651528, 26796.0, 26852.328691782863, 26928.0, 44796.32805507855, 45146.0, 46029.0, 46577.0, 46692.832679183295, 37732.0, 38221.853344399715, 38493.80753096216, 40078.797636070965, 40171.79914100411, 37956.24087518289, 38132.42801358037, 38977.2204555913, 39201.0, 39571.01072473096, 37490.062748431505, 37667.81166094152, 37761.811190944965, 37965.43051854169, 38014.619853802214, 32540.83729581337, 33926.32147357088, 34020.829895850366, 34062.48906266457, 34286.31427371489, 25067.24798256055, 25203.74796252014, 25244.737763111403, 25700.742992569834, 25856.61215081738], "value": 19146.047824416753, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.8835484290186, 355.4384073164399, 368.45284752143044, 373.46967306424875, 375.2232877942659, 16827.747583786237, 19171.424857254286, 19182.52043698908, 19724.211031558738, 19899.403017909448, 15830.287637056332, 19291.710624340612, 19685.50786230345, 19840.702389464168, 19876.20495180195, 5630.436956304367, 5749.885002299972, 5954.910676339859, 5955.910661340084, 5991.730372133243, 5326.733663316838, 5402.9464254470395, 5599.188117722928, 5637.746301416426, 5828.766849326022, 4731.148393289207, 5062.6456148069465, 5154.226865970116, 5559.721264109257, 5792.015357389254, 5603.579731520153, 5706.885862282752, 5721.370649228582, 5770.451807078362, 5787.797427090031, 5060.2156665716875, 5080.415752188509, 5115.2071428928575, 5120.5903527718065, 5130.615203859688, 19698.50753731154, 20203.89898050515, 24890.875545622337, 24941.376465588324, 25175.748242517522, 2104.9263275785274, 2487.4900645367657, 2503.224000559814, 2839.6592408910856, 2902.477554040272, 13827.478977312487, 14409.991300608961, 14796.90039991624, 15129.773053404208, 15676.0, 1681.1174133579846, 1792.793828709702, 1816.3824299738153, 1822.9361972331037, 1824.9361272355536, 117558.0, 118100.0, 122343.16485252729, 123600.0, 123771.0, 9286.44281343108, 9707.46608936501, 10043.296969212231, 10079.748006299755, 10112.241581881388, 10163.390196588229, 12110.697232569257, 12346.320952347527, 12419.254844709347, 12459.563915262923, 7744.57404842739, 7989.960050199712, 8027.799305017421, 8069.273765361146, 8101.351891848638, 8173.918260817316, 8304.294134998592, 8307.709230177035, 8445.831083378298, 8465.40742148056, 3440.810755408477, 3766.7928263945196, 3933.783641899666, 3938.310795610814, 4152.43942067817, 1844.8893066416058, 1918.2902326139417, 1926.0273561851175, 1928.1227041695995, 1961.0381849690302, 382.5237579213841, 392.07861525415046, 875.470340444035, 896.1307531694246, 1008.7831116310028, 11221.66335009951, 11728.179027467995, 11763.705907352385, 11828.76342473163, 12066.034717222778, 427.5916499742738, 429.9935500967488, 445.3653543700214, 850.8213275212152, 893.5264309915625, 10387.962472648844, 10782.815166580007, 10787.671232876794, 10793.896002297397, 10803.362544113465, 642062.0, 652293.7385313043, 660322.0, 667200.0, 667664.0, 96560.99596675328, 125439.0, 125752.0, 142697.5730242705, 150173.0, 957.6504575829912, 982.2044144243185, 989.4656885281867, 993.7913038261902, 994.1351024608781, 18302.176402062207, 18331.54171145732, 18907.0, 19067.809321906603, 19362.128704208062, 55498.72250638721, 56047.0, 56348.71825640846, 58152.0, 59168.81662366815, 71751.0, 77610.0, 84288.0, 85989.7306926531, 86016.0, 5920.0, 6730.730770769114, 6893.896591551231, 7370.189279179179, 7735.149133595188, 11519.020883224684, 12558.43487043067, 12632.494700212255, 12942.223466592035, 12994.415251313525, 10607.41659208751, 10824.458777061272, 10957.0, 10973.067289280181, 11240.32558046487, 10251.538680759533, 10528.210384221367, 10752.08607268391, 10815.134789217002, 10947.945260273647, 8463.915360846313, 8784.95607521984, 9431.481268530297, 9746.805063898824, 11480.0, 5499.862503437286, 7303.269673032654, 7391.371733402721, 7463.402927765657, 7568.508046976929, 10187.08316251541, 10961.45192740344, 10988.890111098786, 11029.779404411707, 11197.38414387184], "value": 8979.808351496173, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1455.8980871339004, 1465.0, 1627.3978627907677, 1771.6102457459353, 1777.5911540345712, 7136.965140054695, 7512.060992375953, 7744.303012728853, 8026.759197224085, 8514.829703405929, 7953.602319883998, 8219.219174178459, 8240.093589705128, 8345.374096942738, 8368.497890126597, 2309.8036666883295, 2355.8233132515043, 2355.9057637694473, 2359.563480756057, 2362.196853069956, 2340.754220806816, 2374.6675465434855, 2377.1679912030822, 2390.6414037894283, 2395.700537432821, 2325.5813953488364, 2329.2080692564523, 2350.17743789674, 2358.705161854768, 2365.4322962488964, 2186.081845624841, 2235.9776402235934, 2241.3948233976816, 2258.7289525256992, 2272.420532764151, 2332.7667233276707, 2342.636891281854, 2363.5509253242003, 2369.7867191952637, 2371.9644205336936, 8248.298894593967, 8312.25189732927, 8372.58137093143, 8389.412741108124, 8397.790055248606, 5073.441921388644, 5190.143626301656, 5203.245529398254, 5207.062728708831, 5212.1921102228835, 2964.777641676883, 3263.298390845956, 3397.010630645041, 3557.5553055868018, 3572.07126147201, 990.1583653894132, 1035.2649618770681, 1036.4921188617598, 1096.6161843354841, 1099.7305660113243, 8682.479051256945, 10747.247692661516, 10845.728856778644, 10861.619843305525, 10916.181286403473, 4524.23088075028, 4652.418447694038, 4711.963368059021, 4743.098811225925, 4827.686200396963, 9265.0, 9358.344915855825, 9360.15758581731, 9379.390339628038, 9383.436993780395, 5176.870578235575, 5178.042062218502, 5178.741062946838, 5199.168133098686, 5201.869953251124, 4236.449261596034, 4237.978810105929, 4695.460022097503, 4732.384789977281, 4753.120672675566, 3218.5011323244935, 3800.771953682788, 3886.2033283177448, 3913.8649791560697, 3939.4484772131923, 1237.6596435979998, 1253.4296894912793, 1267.8288431061874, 1268.5940499040403, 1277.73806369694, 760.444875241066, 869.2437579306044, 897.3945809266415, 918.7794929217074, 924.177482040984, 5606.971965140148, 5652.293463317085, 5682.857928551736, 5687.573431992617, 5880.206172166702, 451.41541703494056, 454.3525476196407, 457.8100088463317, 459.82986295070367, 461.9792109355087, 3797.35444974355, 3801.6198380161763, 3821.5987321331418, 3880.825362858678, 3939.0940083780893, 9610.51947402627, 9968.551415186334, 9970.950145249228, 9987.250956178315, 10035.14701250402, 9820.754481137887, 9941.005899410002, 9941.950290248502, 9954.45250511229, 9957.751056223653, 7676.961615192, 7715.228477152352, 7725.575093369806, 7762.340201082971, 7817.843643127106, 5457.372402173801, 5535.501804837584, 5596.776128954959, 5726.026575482261, 5737.110747834093, 8151.877721834058, 9194.402363846328, 9205.125513076247, 9240.260779137787, 9349.345545811728, 9307.44155350654, 9367.906320936976, 9459.763505912408, 9512.476813775309, 9531.0, 6513.120728701565, 6740.427063699641, 6897.931020689729, 7094.645267736696, 7096.325849044332, 7832.490888092256, 7998.880016799869, 8000.079990800899, 8017.679292828455, 8038.919610804051, 6204.565680402328, 6210.5652604317265, 7028.402585780266, 7048.295170482911, 7274.745383911538, 6054.606450580698, 6839.897401538881, 7008.929910700827, 7028.824279393056, 7516.549007059594, 5521.944780552143, 6461.256955450183, 6598.340165983555, 6671.866562668816, 6763.458923286224, 4580.816767329269, 4741.739204343657, 4770.236762118045, 4807.759612019455, 4844.491328410607, 4822.927656085091, 4833.806647734052, 4838.201696720002, 4905.877353066202, 4944.53026962438], "value": 4305.704687189542, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.88835075197545, 307.7445720052356, 310.2832457024273, 316.4793914011451, 320.95827542419494, 4599.448066232053, 5362.892742145154, 5412.83761487156, 5600.523955463786, 5638.746256418461, 4525.343825145352, 5291.894162116755, 5439.782408703657, 5460.672359658423, 5531.861703457415, 3895.590962948892, 4166.83332666693, 4213.808819413903, 4228.625696648586, 4240.427542281791, 3889.766614003169, 3967.7024223183234, 4200.852970146046, 4246.68149888758, 4253.510846252674, 4155.065110350162, 4170.52039015514, 4170.768638778254, 4178.268802959486, 4205.558416366283, 3705.3330400527893, 4169.457970463851, 4177.227212965611, 4190.97904510477, 4219.367094935754, 2916.489614317486, 3115.7040081192476, 3541.610422853484, 3578.427451607756, 3616.150204701893, 4212.241796476633, 4903.289023091667, 5009.849704508871, 5313.388960269541, 5348.171033489815, 4331.242032644274, 4889.559939605452, 4909.484504127086, 4931.284963680247, 4931.876703082452, 4967.40391153064, 5659.122835960433, 5843.590948633598, 5853.473187413153, 5881.41233847357, 3455.6198818129988, 3678.3562876496508, 3780.810959452044, 3862.324093283692, 4095.4266402703647, 5368.342670378149, 5567.638103523299, 6060.3939606039485, 6084.0, 6114.694265286762, 3350.798952062884, 3386.441237195835, 3931.6264954829244, 3958.8812335629977, 4171.97914010428, 3527.2945410917405, 3920.6471417572557, 4216.7680777557525, 4218.110594242953, 4236.2374772540625, 4907.9263811042865, 4913.707815763447, 4918.360613120271, 4948.505149485023, 4956.900861982812, 4987.476314986947, 5745.913811292834, 5922.526197904157, 5958.851028724316, 5998.49012833914, 1153.5212886651946, 1290.3548225887057, 1297.202220634303, 1361.5779108476404, 1375.9748987004546, 771.413725568572, 902.3683421604903, 905.9310014183195, 944.4994153098869, 947.8672985782063, 4389.31965545341, 4682.528185582761, 5217.947820521822, 5254.65844720092, 5266.683998960075, 4755.667103302805, 4883.755812209375, 5413.864653383697, 5597.468240517144, 5614.550835933115, 4333.090051089243, 4705.811797514172, 5176.430592634807, 5247.632665713363, 5275.868103297448, 2957.5711521829644, 2999.9550006749914, 3023.289526961162, 3066.175625502822, 3102.7672924530752, 5057.6459647824295, 6033.616721395025, 6089.543284253698, 6121.296050954108, 6135.877282454415, 5039.44566097729, 5161.870953226125, 5927.288725352899, 5950.285965684146, 6010.909836352371, 6063.363346848606, 6073.0283154695035, 6086.208792856989, 6119.755209791559, 6153.661548614779, 5315.734213289243, 5606.775728970795, 5675.77296908119, 5838.591298609226, 5885.646861188342, 4724.763761811964, 4885.364902562644, 5547.278853748987, 5605.067540564277, 5753.482186603226, 5239.349604874453, 5497.340319161726, 5712.314522257356, 6023.831420688636, 6093.593030752916, 3194.8881789137267, 3447.6552344765323, 3668.3763760160305, 3705.333040052816, 3978.0253837809923, 5392.460753924576, 5583.385827559046, 5914.0671453482955, 6073.514118870568, 6117.663528505975, 5705.885882282414, 5868.677222752619, 5946.762129514944, 5958.433948774859, 5969.432903874125, 5719.884622498739, 5724.856878578069, 5939.2575928009, 5947.9702601486715, 5983.0, 4837.346958160603, 5575.0, 5664.886702266014, 5721.799737009184, 5793.94206057934, 4515.774211289488, 4949.812045109076, 5129.512696293846, 5886.499647530008, 5900.380460051804, 5639.7180140993605, 5782.971085144547, 5793.594448388572, 5803.8549036274435, 5806.332271788799], "value": 4166.829097660507, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.1183379357525, 701.400302741156, 709.5025510204079, 713.1121753416996, 724.786188074518, 12361.938190309036, 13893.235872027035, 14632.561023169295, 14757.040792348516, 14813.777793333094, 10764.569417223322, 13110.082294239382, 13204.933975330134, 13748.518801841938, 13782.862171378283, 697.4873468001015, 807.9335277433789, 809.5992483720566, 834.4409245805294, 842.3513894301384, 574.6867956963465, 680.9148856392951, 805.3396215103618, 820.7578764264556, 825.7027470110755, 841.474078700812, 871.3116637856086, 873.4977388001925, 879.6657270237301, 880.8898887639045, 853.8121613245077, 868.374770165483, 869.0136694851326, 871.333429926107, 871.3551971541077, 624.4536030972898, 636.2778246689994, 646.8382904273932, 647.9416852483299, 650.0931200974613, 13919.58241252754, 14153.433862645481, 14333.0, 14342.569722908329, 14350.425982960669, 12781.233126012376, 13109.213447193199, 13289.601311960656, 13296.468141274338, 13430.462781488728, 11015.173861960386, 11161.497732602053, 11304.0, 11420.086393088533, 11655.88344116565, 3474.3051389722154, 3660.5973342932257, 3703.870364537255, 3751.606081361472, 3759.4360845873066, 84726.0, 88447.55776221078, 88652.0, 89310.553447233, 90227.54886225592, 10211.438370889673, 10353.119984801226, 10492.842607360895, 10500.05499505033, 10615.0446459819, 15750.684986300439, 19402.902985484983, 19486.805131948782, 19662.410127696192, 19689.606207876048, 7340.15588207363, 7395.0, 7440.4419668524, 7512.661930213154, 7590.924090759132, 3938.369860822312, 3994.6005399460405, 3998.520177578651, 4030.5969403059457, 4064.634182923551, 3318.5354050432957, 3438.2607739335663, 3473.583170019563, 3478.4086705260165, 3490.6334834842482, 562.1679913727706, 575.7362589116932, 622.3589702606323, 645.8223988403226, 652.3411354531956, 605.8879107365152, 630.7792272704517, 634.9806669287508, 640.4972096903908, 681.9181698196248, 8413.579321033923, 8533.701320453754, 8643.78390540229, 8801.51591662452, 8850.291976641853, 479.94000749906263, 576.2019602849999, 607.4381197392482, 648.3322178156507, 666.4435196610788, 3473.1838018065732, 4057.1885622875543, 4178.540360560336, 4224.413248301178, 4358.607725304738, 73644.63177684184, 92137.0, 108456.0, 108624.37063444055, 110189.0, 97566.0, 97674.5116274414, 98824.50587747159, 98871.50564247133, 100532.0, 86328.13671863326, 87368.0, 88028.11971880327, 88232.0, 90304.5484772585, 7847.176046515262, 8188.426810123234, 8250.587470626324, 8310.168983101641, 8317.875231871405, 57127.42872571387, 57590.7120464395, 57672.134917975425, 57898.7105064472, 59224.0, 78534.0, 79100.0, 81010.78483822865, 82249.0, 83100.75348869893, 9550.426974381558, 10770.330598757206, 10932.890671093506, 11012.669619911416, 11095.611653592085, 11594.768104637691, 11714.824277636013, 12056.457459414523, 12205.511779529075, 12215.144939854481, 12498.937505312415, 12832.679183020498, 12995.220286782445, 13137.671558211121, 13309.86690133125, 11183.552657893919, 12915.354232288535, 12976.545820896223, 13348.666283342995, 13511.932440337736, 11143.609973650884, 11760.647525534672, 12414.068944828994, 12424.440900159334, 12928.288944108164, 9752.122308992224, 9860.852087218555, 10387.792244155225, 10653.414062226342, 10802.67591972242, 10427.061564459234, 10463.058324750504, 10501.632442864464, 10535.894641053797, 10668.83996740034], "value": 7071.948905829018, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1544.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 117.0, 119.0, 118.0, 119.0, 118.0, 119.0, 118.0, 118.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 118.0, 112.0, 151.0, 115.0, 119.0, 133.0, 121.0, 142.0, 114.0], "value": 127.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 203.0, 196.0, 193.0, 194.0, 194.0, 193.0, 196.0, 200.0, 197.0], "value": 195.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 57.0, 65.0, 98.0, 81.0, 58.0, 59.0, 60.0, 78.0, 57.0], "value": 68.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 88.0, 74.0, 69.0, 70.0, 84.0, 68.0, 72.0, 87.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 90.0, 88.0, 91.0, 87.0, 91.0, 88.0, 121.0, 88.0], "value": 92.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 173.0, 119.0, 101.0, 100.0, 137.0, 148.0, 174.0, 99.0, 100.0], "value": 125.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 71.0, 137.0, 140.0, 90.0, 90.0, 87.0, 132.0, 91.0], "value": 102.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 56.0, 60.0, 61.0, 60.0, 70.0, 75.0, 51.0, 63.0], "value": 61.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 107.0, 57.0, 73.0, 91.0, 109.0, 101.0, 104.0, 103.0, 76.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 90.0, 86.0, 86.0, 85.0, 84.0, 89.0, 83.0, 83.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 144.0, 153.0, 159.0, 158.0, 149.0, 133.0, 209.0, 181.0], "value": 160.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 170.0, 168.0, 170.0, 163.0, 170.0, 169.0, 169.0, 170.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 68.0, 68.0, 72.0, 67.0, 69.0, 69.0, 79.0, 89.0], "value": 71.8, "unit": "score"}]}]} 08:54:27 INFO - Return code: 0 08:54:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:54:27 ERROR - # TBPL SUCCESS # 08:54:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:54:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:54:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:54:27 INFO - 2016-12-09 08:54:27.629 screenresolution[1659:10876] starting screenresolution argv=screenresolution get 08:54:27 INFO - 2016-12-09 08:54:27.632 screenresolution[1659:10876] Display 0: 1600x1200x32@60 08:54:27 INFO - 2016-12-09 08:54:27.638 screenresolution[1660:10879] starting screenresolution argv=screenresolution list 08:54:27 INFO - Available Modes on Display 0 08:54:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:54:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:54:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:54:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:54:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:54:27 INFO - Intel Iris: 08:54:27 INFO - Chipset Model: Intel Iris 08:54:27 INFO - Type: GPU 08:54:27 INFO - Bus: Built-In 08:54:27 INFO - VRAM (Dynamic, Max): 1536 MB 08:54:27 INFO - Vendor: Intel (0x8086) 08:54:27 INFO - Device ID: 0x0a2e 08:54:27 INFO - Revision ID: 0x0009 08:54:27 INFO - Displays: 08:54:27 INFO - Display: 08:54:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:54:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:54:27 INFO - Display Serial Number: 1600x1200 60 08:54:27 INFO - Main Display: Yes 08:54:27 INFO - Mirror: Off 08:54:27 INFO - Online: Yes 08:54:27 INFO - Rotation: Supported 08:54:27 INFO - Return code: 0 08:54:27 INFO - Running post-action listener: _resource_record_post_action 08:54:27 INFO - [mozharness: 2016-12-09 16:54:27.879622Z] Finished run-tests step (success) 08:54:27 INFO - Running post-run listener: _resource_record_post_run 08:54:27 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 39627264; Write bytes: 661142528; Read time: 399; Write time: 2189 08:54:27 INFO - TinderboxPrint: CPU usage
54.5% 08:54:27 INFO - TinderboxPrint: I/O read bytes / time
39,627,264 / 399 08:54:27 INFO - TinderboxPrint: I/O write bytes / time
661,142,528 / 2,189 08:54:27 INFO - TinderboxPrint: CPU idle
2,204.2 (45.5%) 08:54:27 INFO - TinderboxPrint: CPU system
979.5 (20.2%) 08:54:27 INFO - TinderboxPrint: CPU user
1,658.0 (34.2%) 08:54:27 INFO - TinderboxPrint: Swap in / out
388,460,544 / 0 08:54:27 INFO - install - Wall time: 31s; CPU: 55.0%; Read bytes: 345903616; Write bytes: 346183680; Read time: 26762; Write time: 648 08:54:27 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 37554688; Write bytes: 307864576; Read time: 374; Write time: 1512 08:54:28 INFO - Running post-run listener: _upload_blobber_files 08:54:28 INFO - Blob upload gear active. 08:54:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:54:28 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 08:54:28 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:54:28 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:54:28 INFO - (blobuploader) - INFO - Open directory for files ... 08:54:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:54:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:54:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:54:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:54:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:54:29 INFO - (blobuploader) - INFO - Done attempting. 08:54:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:54:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:54:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:54:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:54:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:54:30 INFO - (blobuploader) - INFO - Done attempting. 08:54:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:54:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:54:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:54:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:54:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:54:33 INFO - (blobuploader) - INFO - Done attempting. 08:54:33 INFO - (blobuploader) - INFO - Iteration through files over. 08:54:33 INFO - Return code: 0 08:54:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:54:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:54:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5f068b565b6e07a156e4701fc8fb603048ada1d611d18425d2c57022406c9fe330aea5d2b510fa7f2ac7753c0e92e14a0a683d05f1d994e7278845014c9ee990", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8880ecde4c1390de427d231068467318e10c57393ea04913cfe3cd3707a61b16f872f458ce1061613f07cfd85a56699144e3d409f042c5626312b50e10ea892", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/742433c952fc73803360015f185e945ca007ab5c11d8fe9c451574b7a62dce5ecefc082b8bafb7c1c6132d90f264d8fb6712d814d79844ddc44c551d6d5ed60f"} 08:54:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:54:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:54:33 INFO - Contents: 08:54:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5f068b565b6e07a156e4701fc8fb603048ada1d611d18425d2c57022406c9fe330aea5d2b510fa7f2ac7753c0e92e14a0a683d05f1d994e7278845014c9ee990", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8880ecde4c1390de427d231068467318e10c57393ea04913cfe3cd3707a61b16f872f458ce1061613f07cfd85a56699144e3d409f042c5626312b50e10ea892", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/742433c952fc73803360015f185e945ca007ab5c11d8fe9c451574b7a62dce5ecefc082b8bafb7c1c6132d90f264d8fb6712d814d79844ddc44c551d6d5ed60f"} 08:54:33 INFO - Running post-run listener: copy_logs_to_upload_dir 08:54:33 INFO - Copying logs to upload dir... 08:54:33 INFO - mkdir: /builds/slave/test/build/upload/logs 08:54:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.923044 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-09 08:54:33.331531) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-09 08:54:33.335248) ========= 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.3ASQqmwJBn/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.85tR4SAT0z/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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/Mozilla-Aurora/sha512/5f068b565b6e07a156e4701fc8fb603048ada1d611d18425d2c57022406c9fe330aea5d2b510fa7f2ac7753c0e92e14a0a683d05f1d994e7278845014c9ee990", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8880ecde4c1390de427d231068467318e10c57393ea04913cfe3cd3707a61b16f872f458ce1061613f07cfd85a56699144e3d409f042c5626312b50e10ea892", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/742433c952fc73803360015f185e945ca007ab5c11d8fe9c451574b7a62dce5ecefc082b8bafb7c1c6132d90f264d8fb6712d814d79844ddc44c551d6d5ed60f"} build_url:https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010305 build_url: 'https://queue.taskcluster.net/v1/task/O4KogXScQKKjCSm7hnNFPQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5f068b565b6e07a156e4701fc8fb603048ada1d611d18425d2c57022406c9fe330aea5d2b510fa7f2ac7753c0e92e14a0a683d05f1d994e7278845014c9ee990", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8880ecde4c1390de427d231068467318e10c57393ea04913cfe3cd3707a61b16f872f458ce1061613f07cfd85a56699144e3d409f042c5626312b50e10ea892", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/742433c952fc73803360015f185e945ca007ab5c11d8fe9c451574b7a62dce5ecefc082b8bafb7c1c6132d90f264d8fb6712d814d79844ddc44c551d6d5ed60f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-09 08:54:33.369061) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:54:33.369445) ========= 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.3ASQqmwJBn/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.85tR4SAT0z/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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.004944 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:54:33.420472) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-09 08:54:33.421017) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-09 08:54:33.421583) ========= ========= Total master_lag: 0.18 =========