builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0153 starttime: 1480958811.11 results: success (0) buildid: 20161205070638 builduid: ca6df37595034678b897c7b624369dfe revision: 46049b21980d67b13d6915c052a3025c273a1788 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.108840) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.109306) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.109612) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.135193) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.135623) ========= 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.ggrMNIFle4/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.vfgEMsPqYX/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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-05 09:26:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.4M=0.001s 2016-12-05 09:26:51 (13.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120493 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.279983) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.280350) ========= 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.ggrMNIFle4/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.vfgEMsPqYX/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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.020105 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:51.330721) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-05 09:26:51.331234) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 46049b21980d67b13d6915c052a3025c273a1788 --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 46049b21980d67b13d6915c052a3025c273a1788 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ggrMNIFle4/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.vfgEMsPqYX/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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-05 09:26:51,407 truncating revision to first 12 chars 2016-12-05 09:26:51,407 Setting DEBUG logging. 2016-12-05 09:26:51,407 attempt 1/10 2016-12-05 09:26:51,407 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/46049b21980d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-05 09:26:52,643 unpacking tar archive at: mozilla-aurora-46049b21980d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.492098 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-05 09:26:52.847263) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:52.847626) ========= script_repo_revision: 46049b21980d67b13d6915c052a3025c273a1788 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:52.848054) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:52.848387) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-05 09:26:52.869162) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-12-05 09:26:52.869615) ========= /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.ggrMNIFle4/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.vfgEMsPqYX/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 09:26:53 INFO - MultiFileLogger online at 20161205 09:26:53 in /builds/slave/test 09:26:53 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 09:26:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:26:53 INFO - {'append_to_log': False, 09:26:53 INFO - 'base_work_dir': '/builds/slave/test', 09:26:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:26:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:26:53 INFO - 'branch': 'Mozilla-Aurora', 09:26:53 INFO - 'buildbot_json_path': 'buildprops.json', 09:26:53 INFO - 'config_files': ('talos/mac_config.py',), 09:26:53 INFO - 'default_actions': ('clobber', 09:26:53 INFO - 'read-buildbot-config', 09:26:53 INFO - 'download-and-extract', 09:26:53 INFO - 'populate-webroot', 09:26:53 INFO - 'create-virtualenv', 09:26:53 INFO - 'install', 09:26:53 INFO - 'run-tests'), 09:26:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:26:53 INFO - 'download_minidump_stackwalk': True, 09:26:53 INFO - 'download_symbols': 'ondemand', 09:26:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:26:53 INFO - 'tooltool.py': '/tools/tooltool.py', 09:26:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:26:53 INFO - '/tools/misc-python/virtualenv.py')}, 09:26:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:26:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:26:53 INFO - 'installer_path': 'installer.exe', 09:26:53 INFO - 'log_level': 'info', 09:26:53 INFO - 'log_name': 'talos', 09:26:53 INFO - 'log_to_console': True, 09:26:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:26:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:26:53 INFO - 'opt_config_files': (), 09:26:53 INFO - 'pip_index': False, 09:26:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:53 INFO - 'cmd': ('bash', 09:26:53 INFO - '-c', 09:26:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:53 INFO - 'enabled': True, 09:26:53 INFO - 'halt_on_failure': False, 09:26:53 INFO - 'name': 'check_screen_resolution'},), 09:26:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:53 INFO - 'cmd': ('bash', 09:26:53 INFO - '-c', 09:26:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:53 INFO - 'enabled': True, 09:26:53 INFO - 'halt_on_failure': False, 09:26:53 INFO - 'name': 'check_screen_resolution'},), 09:26:53 INFO - 'run_cmd_checks_enabled': True, 09:26:53 INFO - 'sps_profile': False, 09:26:53 INFO - 'sps_profile_interval': 0, 09:26:53 INFO - 'suite': 'dromaeojs-e10s', 09:26:53 INFO - 'system_bits': '32', 09:26:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:26:53 INFO - 'title': 't-yosemite-r7-0153', 09:26:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:26:53 INFO - 'use_talos_json': True, 09:26:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:26:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:26:53 INFO - 'work_dir': 'build'} 09:26:53 INFO - [mozharness: 2016-12-05 17:26:53.070157Z] Running clobber step. 09:26:53 INFO - Running pre-action listener: _resource_record_pre_action 09:26:53 INFO - Running main action method: clobber 09:26:53 INFO - rmtree: /builds/slave/test/build 09:26:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:26:53 INFO - Running post-action listener: _resource_record_post_action 09:26:53 INFO - [mozharness: 2016-12-05 17:26:53.452005Z] Finished clobber step (success) 09:26:53 INFO - [mozharness: 2016-12-05 17:26:53.452160Z] Running read-buildbot-config step. 09:26:53 INFO - Running pre-action listener: _resource_record_pre_action 09:26:53 INFO - Running main action method: read_buildbot_config 09:26:53 INFO - Using buildbot properties: 09:26:53 INFO - { 09:26:53 INFO - "project": "", 09:26:53 INFO - "product": "firefox", 09:26:53 INFO - "script_repo_revision": "production", 09:26:53 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:26:53 INFO - "repository": "", 09:26:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:26:53 INFO - "buildid": "20161205070638", 09:26:53 INFO - "pgo_build": "False", 09:26:53 INFO - "basedir": "/builds/slave/test", 09:26:53 INFO - "buildnumber": 1, 09:26:53 INFO - "slavename": "t-yosemite-r7-0153", 09:26:53 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:26:53 INFO - "platform": "yosemite_r7", 09:26:53 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:26:53 INFO - "branch": "mozilla-aurora", 09:26:53 INFO - "repo_path": "releases/mozilla-aurora", 09:26:53 INFO - "revision": "46049b21980d67b13d6915c052a3025c273a1788", 09:26:53 INFO - "stage_platform": "macosx64", 09:26:53 INFO - "builduid": "ca6df37595034678b897c7b624369dfe", 09:26:53 INFO - "slavebuilddir": "test" 09:26:53 INFO - } 09:26:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:26:53 INFO - Running post-action listener: _resource_record_post_action 09:26:53 INFO - [mozharness: 2016-12-05 17:26:53.456539Z] Finished read-buildbot-config step (success) 09:26:53 INFO - [mozharness: 2016-12-05 17:26:53.456793Z] Running download-and-extract step. 09:26:53 INFO - Running pre-action listener: _resource_record_pre_action 09:26:53 INFO - Running main action method: download_and_extract 09:26:53 INFO - mkdir: /builds/slave/test/build/tests 09:26: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')]} 09:26:53 INFO - trying https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:26:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/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 09:26:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/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 09:26:54 INFO - Downloaded 1199 bytes. 09:26:54 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:26:54 INFO - Using the following test package requirements: 09:26:54 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:26:54 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:54 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:26:54 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:54 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:26:54 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:54 INFO - u'jsshell-mac.zip'], 09:26:54 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:54 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:26:54 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:26:54 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:54 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:26:54 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:54 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:26:54 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:54 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:26:54 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:26:54 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:26:54 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:26:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:26:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:26:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:26:54 INFO - Http code: 200 09:26:54 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 09:26:54 INFO - x-amz-cf-id: 8NiuuTM_VQqlrO9vzhT55z1byTu9T5b24lVlgDzclCc02hXQudvxbQ== 09:26:54 INFO - x-amz-version-id: t9xxaFr6hPXSmRJLA66VGMjwUVK_akvk 09:26:55 INFO - Expected file size: 16282010 09:26:55 INFO - Obtained file size: 16282010 09:27:02 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 09:27:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:27:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:27:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:27:02 INFO - Http code: 200 09:27:02 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 09:27:02 INFO - x-amz-cf-id: RkcaP2iylbil94ZTLQwMW29KiXXV7506oBlhBDZRtuy1iUjaCtX9zw== 09:27:02 INFO - x-amz-version-id: t9xxaFr6hPXSmRJLA66VGMjwUVK_akvk 09:27:03 INFO - Expected file size: 16282010 09:27:03 INFO - Obtained file size: 16282010 09:27:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:27:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:27:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:27:09 INFO - Http code: 200 09:27:09 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 09:27:09 INFO - x-amz-cf-id: mmzoYihSE_ekpfZ_Dj_V0N0TrndUUGNHL8O-QM1ZHcGnNLrbvJXeDA== 09:27:09 INFO - x-amz-version-id: BePxp_qK1o2iig7g1R8kQ1LEMUwGwUAH 09:27:10 INFO - Expected file size: 14409038 09:27:10 INFO - Obtained file size: 14409038 09:27: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')]} 09:27:10 INFO - trying https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:27:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:27:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/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 09:27:19 INFO - Downloaded 109762604 bytes. 09:27:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:27:19 INFO - mkdir: /builds/slave/test/properties 09:27:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:27:19 INFO - Writing to file /builds/slave/test/properties/build_url 09:27:19 INFO - Contents: 09:27:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:27:19 INFO - Running post-action listener: _resource_record_post_action 09:27:19 INFO - Running post-action listener: set_extra_try_arguments 09:27:19 INFO - [mozharness: 2016-12-05 17:27:19.790417Z] Finished download-and-extract step (success) 09:27:19 INFO - [mozharness: 2016-12-05 17:27:19.790565Z] Running populate-webroot step. 09:27:19 INFO - Running pre-action listener: _resource_record_pre_action 09:27:19 INFO - Running main action method: populate_webroot 09:27:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:27:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:27:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:27:19 INFO - '--tppagecycles', 09:27:19 INFO - '7'], 09:27:19 INFO - 'tests': ['tsvgm']}}, 09:27:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:27:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:27:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:27:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:27:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:27:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:27:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:19 INFO - 'talos_options': ['--disable-e10s'], 09:27:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:19 INFO - 'talos_options': ['--disable-e10s'], 09:27:19 INFO - 'tests': ['damp', 'tps']}, 09:27:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:19 INFO - 'tests': ['damp', 'tps']}, 09:27:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:27:19 INFO - 'tests': ['dromaeo_dom']}, 09:27:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:27:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:27:19 INFO - 'tests': ['basic_compositor_video']}, 09:27:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:27:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:27:19 INFO - 'tests': ['a11yr', 09:27:19 INFO - 'ts_paint', 09:27:19 INFO - 'tpaint', 09:27:19 INFO - 'sessionrestore', 09:27:19 INFO - 'sessionrestore_no_auto_restore', 09:27:19 INFO - 'tabpaint']}, 09:27:19 INFO - 'other-e10s': {'tests': ['a11yr', 09:27:19 INFO - 'ts_paint', 09:27:19 INFO - 'tpaint', 09:27:19 INFO - 'sessionrestore', 09:27:19 INFO - 'sessionrestore_no_auto_restore', 09:27:19 INFO - 'tabpaint']}, 09:27:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:27:19 INFO - 'ts_paint', 09:27:19 INFO - 'tpaint', 09:27:19 INFO - 'sessionrestore', 09:27:19 INFO - 'sessionrestore_no_auto_restore', 09:27:19 INFO - 'tabpaint']}, 09:27:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:27:19 INFO - 'ts_paint', 09:27:19 INFO - 'tpaint', 09:27:19 INFO - 'sessionrestore', 09:27:19 INFO - 'sessionrestore_no_auto_restore', 09:27:19 INFO - 'tabpaint']}, 09:27:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:27:19 INFO - 'tests': ['a11yr', 09:27:19 INFO - 'ts_paint', 09:27:19 INFO - 'tpaint', 09:27:19 INFO - 'sessionrestore', 09:27:19 INFO - 'sessionrestore_no_auto_restore', 09:27:19 INFO - 'tabpaint']}, 09:27:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:27:19 INFO - 'tests': ['a11yr', 09:27:19 INFO - 'ts_paint', 09:27:19 INFO - 'tpaint', 09:27:19 INFO - 'sessionrestore', 09:27:19 INFO - 'sessionrestore_no_auto_restore', 09:27:19 INFO - 'tabpaint']}, 09:27:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:27:19 INFO - 'tests': ['tsvgx', 09:27:19 INFO - 'tsvgr_opacity', 09:27:19 INFO - 'tart', 09:27:19 INFO - 'tscrollx', 09:27:19 INFO - 'cart']}, 09:27:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:27:19 INFO - 'tsvgr_opacity', 09:27:19 INFO - 'tart', 09:27:19 INFO - 'tscrollx', 09:27:19 INFO - 'cart']}, 09:27:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:19 INFO - 'talos_options': ['--disable-e10s'], 09:27:19 INFO - 'tests': ['tp5o']}, 09:27:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:19 INFO - 'tests': ['tp5o']}, 09:27:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:19 INFO - 'talos_options': ['--disable-e10s', 09:27:19 INFO - '--xperf_path', 09:27:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:19 INFO - 'tests': ['tp5n']}, 09:27:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:27:19 INFO - 'talos_options': ['--xperf_path', 09:27:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:19 INFO - 'tests': ['tp5n']}}, 09:27:19 INFO - 'talos.zip': {'path': '', 09:27:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:27:19 INFO - Running post-action listener: _resource_record_post_action 09:27:19 INFO - [mozharness: 2016-12-05 17:27:19.809510Z] Finished populate-webroot step (success) 09:27:19 INFO - [mozharness: 2016-12-05 17:27:19.809638Z] Running create-virtualenv step. 09:27:19 INFO - Running pre-action listener: _resource_record_pre_action 09:27:19 INFO - Running main action method: create_virtualenv 09:27:19 INFO - Creating virtualenv /builds/slave/test/build/venv 09:27:19 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 09:27:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:27:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:27:19 INFO - Using real prefix '/tools/python27' 09:27:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:27:20 INFO - Installing distribute.............................................................................................................................................................................................done. 09:27:23 INFO - Installing pip.................done. 09:27:23 INFO - Return code: 0 09:27:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:27:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:27:23 INFO - Reading from file tmpfile_stdout 09:27:23 INFO - Output received: 09:27:23 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) 09:27:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:27:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:23 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 0x10f023490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce2ac95e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37d30>, '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 0x10ec9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2fb20>, '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.ggrMNIFle4/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.vfgEMsPqYX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 09:27:23 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 09:27:23 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 09:27:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggrMNIFle4/Render', 09:27:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:23 INFO - 'HOME': '/Users/cltbld', 09:27:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:23 INFO - 'LOGNAME': 'cltbld', 09:27:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:23 INFO - 'MOZ_NO_REMOTE': '1', 09:27:23 INFO - 'NO_EM_RESTART': '1', 09:27:23 INFO - 'PAGER': '/bin/cat', 09:27:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:23 INFO - 'PWD': '/builds/slave/test', 09:27:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:23 INFO - 'SHELL': '/bin/bash', 09:27:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vfgEMsPqYX/Listeners', 09:27:23 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 09:27:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:23 INFO - 'USER': 'cltbld', 09:27:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:23 INFO - 'XPC_FLAGS': '0x0', 09:27:23 INFO - 'XPC_SERVICE_NAME': '0', 09:27:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:24 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 09:27:24 INFO - Cleaning up... 09:27:24 INFO - Return code: 0 09:27:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:27:24 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')]} 09:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:24 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')]} 09:27:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:24 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 0x10f023490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce2ac95e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37d30>, '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 0x10ec9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2fb20>, '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.ggrMNIFle4/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.vfgEMsPqYX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 09:27:24 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 09:27:24 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 09:27:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggrMNIFle4/Render', 09:27:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:24 INFO - 'HOME': '/Users/cltbld', 09:27:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:24 INFO - 'LOGNAME': 'cltbld', 09:27:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:24 INFO - 'MOZ_NO_REMOTE': '1', 09:27:24 INFO - 'NO_EM_RESTART': '1', 09:27:24 INFO - 'PAGER': '/bin/cat', 09:27:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:24 INFO - 'PWD': '/builds/slave/test', 09:27:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:24 INFO - 'SHELL': '/bin/bash', 09:27:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vfgEMsPqYX/Listeners', 09:27:24 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 09:27:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:24 INFO - 'USER': 'cltbld', 09:27:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:24 INFO - 'XPC_FLAGS': '0x0', 09:27:24 INFO - 'XPC_SERVICE_NAME': '0', 09:27:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:24 INFO - Downloading/unpacking psutil>=3.1.1 09:27:24 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 09:27:24 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 09:27:24 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 09:27:24 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 09:27:24 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 09:27:24 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 09:27:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:27:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:28 INFO - Installing collected packages: psutil 09:27:28 INFO - Running setup.py install for psutil 09:27:28 INFO - building 'psutil._psutil_osx' extension 09:27:28 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 09:27:28 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 09:27:28 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 09:27:29 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 09:27:29 INFO - building 'psutil._psutil_posix' extension 09:27:29 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 09:27:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:27:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:27:29 INFO - ^ 09:27:29 INFO - 1 warning generated. 09:27:29 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 09:27:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:29 INFO - Successfully installed psutil 09:27:29 INFO - Cleaning up... 09:27:29 INFO - Return code: 0 09:27:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:27:29 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')]} 09:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:29 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')]} 09:27:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:29 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 0x10f023490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce2ac95e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37d30>, '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 0x10ec9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2fb20>, '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.ggrMNIFle4/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.vfgEMsPqYX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 09:27:29 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 09:27:29 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 09:27:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggrMNIFle4/Render', 09:27:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:29 INFO - 'HOME': '/Users/cltbld', 09:27:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:29 INFO - 'LOGNAME': 'cltbld', 09:27:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:29 INFO - 'MOZ_NO_REMOTE': '1', 09:27:29 INFO - 'NO_EM_RESTART': '1', 09:27:29 INFO - 'PAGER': '/bin/cat', 09:27:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:29 INFO - 'PWD': '/builds/slave/test', 09:27:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:29 INFO - 'SHELL': '/bin/bash', 09:27:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vfgEMsPqYX/Listeners', 09:27:29 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 09:27:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:29 INFO - 'USER': 'cltbld', 09:27:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:29 INFO - 'XPC_FLAGS': '0x0', 09:27:29 INFO - 'XPC_SERVICE_NAME': '0', 09:27:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:27:29 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 09:27:29 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 09:27:29 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 09:27:29 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 09:27:29 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 09:27:29 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 09:27:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:27:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:27:32 INFO - Installing collected packages: mozsystemmonitor 09:27:32 INFO - Running setup.py install for mozsystemmonitor 09:27:32 INFO - Successfully installed mozsystemmonitor 09:27:32 INFO - Cleaning up... 09:27:32 INFO - Return code: 0 09:27:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:27:32 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')]} 09:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:32 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')]} 09:27:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:32 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 0x10f023490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce2ac95e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37d30>, '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 0x10ec9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2fb20>, '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.ggrMNIFle4/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.vfgEMsPqYX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 09:27:32 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 09:27:32 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 09:27:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggrMNIFle4/Render', 09:27:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:32 INFO - 'HOME': '/Users/cltbld', 09:27:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:32 INFO - 'LOGNAME': 'cltbld', 09:27:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:32 INFO - 'MOZ_NO_REMOTE': '1', 09:27:32 INFO - 'NO_EM_RESTART': '1', 09:27:32 INFO - 'PAGER': '/bin/cat', 09:27:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:32 INFO - 'PWD': '/builds/slave/test', 09:27:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:32 INFO - 'SHELL': '/bin/bash', 09:27:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vfgEMsPqYX/Listeners', 09:27:32 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 09:27:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:32 INFO - 'USER': 'cltbld', 09:27:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:32 INFO - 'XPC_FLAGS': '0x0', 09:27:32 INFO - 'XPC_SERVICE_NAME': '0', 09:27:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:32 INFO - Downloading/unpacking jsonschema==2.5.1 09:27:32 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 09:27:32 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 09:27:32 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 09:27:32 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 09:27:32 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 09:27:32 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 09:27:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:27:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:27:35 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 09:27:35 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 09:27:35 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 09:27:35 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 09:27:35 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 09:27:35 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 09:27:35 INFO - Downloading functools32-3.2.3-2.tar.gz 09:27:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:27:35 INFO - warning: no files found matching '*.txt' 09:27:35 INFO - no previously-included directories found matching 'build' 09:27:35 INFO - no previously-included directories found matching 'dist' 09:27:35 INFO - no previously-included directories found matching '.git*' 09:27:35 INFO - Installing collected packages: jsonschema, functools32 09:27:35 INFO - Running setup.py install for functools32 09:27:35 INFO - warning: no files found matching '*.txt' 09:27:35 INFO - no previously-included directories found matching 'build' 09:27:35 INFO - no previously-included directories found matching 'dist' 09:27:35 INFO - no previously-included directories found matching '.git*' 09:27:35 INFO - Successfully installed jsonschema functools32 09:27:35 INFO - Cleaning up... 09:27:36 INFO - Return code: 0 09:27:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:27:36 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')]} 09:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:36 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')]} 09:27:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:36 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 0x10f023490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce2ac95e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37d30>, '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 0x10ec9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2fb20>, '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.ggrMNIFle4/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.vfgEMsPqYX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 09:27:36 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 09:27:36 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 09:27:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggrMNIFle4/Render', 09:27:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:36 INFO - 'HOME': '/Users/cltbld', 09:27:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:36 INFO - 'LOGNAME': 'cltbld', 09:27:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:36 INFO - 'MOZ_NO_REMOTE': '1', 09:27:36 INFO - 'NO_EM_RESTART': '1', 09:27:36 INFO - 'PAGER': '/bin/cat', 09:27:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:36 INFO - 'PWD': '/builds/slave/test', 09:27:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:36 INFO - 'SHELL': '/bin/bash', 09:27:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vfgEMsPqYX/Listeners', 09:27:36 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 09:27:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:36 INFO - 'USER': 'cltbld', 09:27:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:36 INFO - 'XPC_FLAGS': '0x0', 09:27:36 INFO - 'XPC_SERVICE_NAME': '0', 09:27:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:27:36 INFO - Cleaning up... 09:27:36 INFO - Return code: 0 09:27:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:27:36 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')]} 09:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:36 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')]} 09:27:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:36 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 0x10f023490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce2ac95e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37d30>, '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 0x10ec9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2fb20>, '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.ggrMNIFle4/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.vfgEMsPqYX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 09:27:36 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 09:27:36 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 09:27:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggrMNIFle4/Render', 09:27:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:36 INFO - 'HOME': '/Users/cltbld', 09:27:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:36 INFO - 'LOGNAME': 'cltbld', 09:27:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:36 INFO - 'MOZ_NO_REMOTE': '1', 09:27:36 INFO - 'NO_EM_RESTART': '1', 09:27:36 INFO - 'PAGER': '/bin/cat', 09:27:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:36 INFO - 'PWD': '/builds/slave/test', 09:27:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:36 INFO - 'SHELL': '/bin/bash', 09:27:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vfgEMsPqYX/Listeners', 09:27:36 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 09:27:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:36 INFO - 'USER': 'cltbld', 09:27:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:36 INFO - 'XPC_FLAGS': '0x0', 09:27:36 INFO - 'XPC_SERVICE_NAME': '0', 09:27:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:36 INFO - Downloading/unpacking blobuploader==1.2.4 09:27:36 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 09:27:36 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 09:27:36 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 09:27:36 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 09:27:36 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 09:27:36 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 09:27:39 INFO - Downloading blobuploader-1.2.4.tar.gz 09:27:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:27:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:27:39 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 09:27:39 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 09:27:39 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 09:27:39 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 09:27:39 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 09:27:39 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 09:27:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:27:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:27:39 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 09:27:39 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 09:27:39 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 09:27:39 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 09:27:39 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 09:27:39 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 09:27:40 INFO - Downloading docopt-0.6.1.tar.gz 09:27:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:27:40 INFO - Installing collected packages: blobuploader, requests, docopt 09:27:40 INFO - Running setup.py install for blobuploader 09:27:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:27:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:27:40 INFO - Running setup.py install for requests 09:27:41 INFO - Running setup.py install for docopt 09:27:41 INFO - Successfully installed blobuploader requests docopt 09:27:41 INFO - Cleaning up... 09:27:41 INFO - Return code: 0 09:27:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:41 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')]} 09:27:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:41 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')]} 09:27:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:41 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 0x10f023490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce2ac95e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37d30>, '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 0x10ec9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2fb20>, '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.ggrMNIFle4/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.vfgEMsPqYX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 09:27:41 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 09:27:41 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 09:27:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggrMNIFle4/Render', 09:27:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:41 INFO - 'HOME': '/Users/cltbld', 09:27:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:41 INFO - 'LOGNAME': 'cltbld', 09:27:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:41 INFO - 'MOZ_NO_REMOTE': '1', 09:27:41 INFO - 'NO_EM_RESTART': '1', 09:27:41 INFO - 'PAGER': '/bin/cat', 09:27:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:41 INFO - 'PWD': '/builds/slave/test', 09:27:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:41 INFO - 'SHELL': '/bin/bash', 09:27:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vfgEMsPqYX/Listeners', 09:27:41 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 09:27:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:41 INFO - 'USER': 'cltbld', 09:27:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:41 INFO - 'XPC_FLAGS': '0x0', 09:27:41 INFO - 'XPC_SERVICE_NAME': '0', 09:27:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:41 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-S_h49A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:41 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-YIDpqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:41 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Gp1RYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:41 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-ccmnUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:42 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-C7MbxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:42 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-DbQbSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:42 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-xoezyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:42 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-3geGN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:42 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-lhqI4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:42 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-wYoYi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:42 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-E5JKxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:42 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-VNnmNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:42 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-0hGZoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:42 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-vsESEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:43 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-VIawe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:43 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-JRi2my-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:43 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-0ksNJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:27:43 INFO - Running setup.py install for manifestparser 09:27:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:27:43 INFO - Running setup.py install for mozcrash 09:27:43 INFO - Running setup.py install for mozdebug 09:27:43 INFO - Running setup.py install for mozdevice 09:27:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:27:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:27:43 INFO - Running setup.py install for mozfile 09:27:44 INFO - Running setup.py install for mozhttpd 09:27:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:27:44 INFO - Running setup.py install for mozinfo 09:27:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:27:44 INFO - Running setup.py install for mozInstall 09:27:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:27:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:27:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:27:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:27:44 INFO - Running setup.py install for mozleak 09:27:44 INFO - Running setup.py install for mozlog 09:27:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:27:44 INFO - Running setup.py install for moznetwork 09:27:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:27:45 INFO - Running setup.py install for mozprocess 09:27:45 INFO - Running setup.py install for mozprofile 09:27:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:27:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:27:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:27:45 INFO - Running setup.py install for mozrunner 09:27:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:27:45 INFO - Running setup.py install for mozscreenshot 09:27:45 INFO - Running setup.py install for moztest 09:27:45 INFO - Running setup.py install for mozversion 09:27:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:27:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:27:45 INFO - Cleaning up... 09:27:45 INFO - Return code: 0 09:27:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:45 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 0x10f023490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce2ac95e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37d30>, '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 0x10ec9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2fb20>, '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.ggrMNIFle4/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.vfgEMsPqYX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 09:27:45 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 09:27:45 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 09:27:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggrMNIFle4/Render', 09:27:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:45 INFO - 'HOME': '/Users/cltbld', 09:27:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:45 INFO - 'LOGNAME': 'cltbld', 09:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:45 INFO - 'MOZ_NO_REMOTE': '1', 09:27:45 INFO - 'NO_EM_RESTART': '1', 09:27:45 INFO - 'PAGER': '/bin/cat', 09:27:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:45 INFO - 'PWD': '/builds/slave/test', 09:27:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:45 INFO - 'SHELL': '/bin/bash', 09:27:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vfgEMsPqYX/Listeners', 09:27:45 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 09:27:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:45 INFO - 'USER': 'cltbld', 09:27:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:45 INFO - 'XPC_FLAGS': '0x0', 09:27:45 INFO - 'XPC_SERVICE_NAME': '0', 09:27:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-1_V6Ck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:46 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)) 09:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Ttpvfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:46 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)) 09:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-2LwbKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:46 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)) 09:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-gcIRx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:46 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)) 09:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-OUHfTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:46 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)) 09:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-VShu4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:46 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)) 09:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-MXzGx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:46 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)) 09:27:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:46 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-vPujSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:47 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)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:47 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Dsiwj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:47 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)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:47 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-gRaIwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:47 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)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:47 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-KDJvXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:47 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)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:47 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-vCQmJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:47 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)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:47 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-xyw_hA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:47 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)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:47 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-93g5Tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:47 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)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:47 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Xv5ltB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:47 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)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:47 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-gKXr6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:47 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)) 09:27:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:47 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-us22Z2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:48 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)) 09:27:48 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)) 09:27:48 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)) 09:27:48 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)) 09:27:48 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)) 09:27:48 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)) 09:27:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27:50 INFO - Downloading blessings-1.6.tar.gz 09:27:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:27:50 INFO - Installing collected packages: blessings 09:27:50 INFO - Running setup.py install for blessings 09:27:50 INFO - Successfully installed blessings 09:27:50 INFO - Cleaning up... 09:27:51 INFO - Return code: 0 09:27:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:27:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:51 INFO - Reading from file tmpfile_stdout 09:27:51 INFO - Current package versions: 09:27:51 INFO - blessings == 1.6 09:27:51 INFO - blobuploader == 1.2.4 09:27:51 INFO - docopt == 0.6.1 09:27:51 INFO - functools32 == 3.2.3-2 09:27:51 INFO - jsonschema == 2.5.1 09:27:51 INFO - manifestparser == 1.1 09:27:51 INFO - mozInstall == 1.12 09:27:51 INFO - mozcrash == 1.0 09:27:51 INFO - mozdebug == 0.1 09:27:51 INFO - mozdevice == 0.48 09:27:51 INFO - mozfile == 1.2 09:27:51 INFO - mozhttpd == 0.7 09:27:51 INFO - mozinfo == 0.9 09:27:51 INFO - mozleak == 0.1 09:27:51 INFO - mozlog == 3.4 09:27:51 INFO - moznetwork == 0.27 09:27:51 INFO - mozprocess == 0.23 09:27:51 INFO - mozprofile == 0.28 09:27:51 INFO - mozrunner == 6.13 09:27:51 INFO - mozscreenshot == 0.1 09:27:51 INFO - mozsystemmonitor == 0.3 09:27:51 INFO - moztest == 0.7 09:27:51 INFO - mozversion == 1.4 09:27:51 INFO - psutil == 3.1.1 09:27:51 INFO - requests == 1.2.3 09:27:51 INFO - wsgiref == 0.1.2 09:27:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:51 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 0x10f023490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce2ac95e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37d30>, '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 0x10ec9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2fb20>, '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.ggrMNIFle4/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.vfgEMsPqYX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 09:27:51 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 09:27:51 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 09:27:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggrMNIFle4/Render', 09:27:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:51 INFO - 'HOME': '/Users/cltbld', 09:27:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:51 INFO - 'LOGNAME': 'cltbld', 09:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:51 INFO - 'MOZ_NO_REMOTE': '1', 09:27:51 INFO - 'NO_EM_RESTART': '1', 09:27:51 INFO - 'PAGER': '/bin/cat', 09:27:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:51 INFO - 'PWD': '/builds/slave/test', 09:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:51 INFO - 'SHELL': '/bin/bash', 09:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vfgEMsPqYX/Listeners', 09:27:51 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 09:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:51 INFO - 'USER': 'cltbld', 09:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:51 INFO - 'XPC_FLAGS': '0x0', 09:27:51 INFO - 'XPC_SERVICE_NAME': '0', 09:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:51 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)) 09:27:51 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)) 09:27:51 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)) 09:27:51 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)) 09:27:51 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)) 09:27:51 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)) 09:27:51 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)) 09:27:51 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)) 09:27:51 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)) 09:27:51 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)) 09:27:51 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)) 09:27:51 INFO - Cleaning up... 09:27:51 INFO - Return code: 0 09:27:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:51 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 0x10f023490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce2ac95e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee37d30>, '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 0x10ec9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2fb20>, '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.ggrMNIFle4/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.vfgEMsPqYX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 09:27:51 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 09:27:51 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 09:27:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggrMNIFle4/Render', 09:27:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:51 INFO - 'HOME': '/Users/cltbld', 09:27:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:51 INFO - 'LOGNAME': 'cltbld', 09:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:51 INFO - 'MOZ_NO_REMOTE': '1', 09:27:51 INFO - 'NO_EM_RESTART': '1', 09:27:51 INFO - 'PAGER': '/bin/cat', 09:27:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:51 INFO - 'PWD': '/builds/slave/test', 09:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:51 INFO - 'SHELL': '/bin/bash', 09:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vfgEMsPqYX/Listeners', 09:27:51 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 09:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:51 INFO - 'USER': 'cltbld', 09:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:51 INFO - 'XPC_FLAGS': '0x0', 09:27:51 INFO - 'XPC_SERVICE_NAME': '0', 09:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:27:51 INFO - Cleaning up... 09:27:51 INFO - Return code: 0 09:27:51 INFO - Running post-action listener: _resource_record_post_action 09:27:51 INFO - Running post-action listener: _start_resource_monitoring 09:27:51 INFO - Starting resource monitoring. 09:27:51 INFO - [mozharness: 2016-12-05 17:27:51.861400Z] Finished create-virtualenv step (success) 09:27:51 INFO - [mozharness: 2016-12-05 17:27:51.862281Z] Running install step. 09:27:51 INFO - Running pre-action listener: _resource_record_pre_action 09:27:51 INFO - Running main action method: install 09:27:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:52 INFO - Reading from file tmpfile_stdout 09:27:52 INFO - Detecting whether we're running mozinstall >=1.0... 09:27:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:27:52 INFO - Reading from file tmpfile_stdout 09:27:52 INFO - Output received: 09:27:52 INFO - Usage: mozinstall [options] installer 09:27:52 INFO - Options: 09:27:52 INFO - -h, --help show this help message and exit 09:27:52 INFO - -d DEST, --destination=DEST 09:27:52 INFO - Directory to install application into. [default: 09:27:52 INFO - "/builds/slave/test"] 09:27:52 INFO - --app=APP Application being installed. [default: firefox] 09:27:52 INFO - mkdir: /builds/slave/test/build/application 09:27:52 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'] 09:27:52 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 09:28:23 INFO - Reading from file tmpfile_stdout 09:28:23 INFO - Output received: 09:28:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:28:23 INFO - Running post-action listener: _resource_record_post_action 09:28:23 INFO - [mozharness: 2016-12-05 17:28:23.744907Z] Finished install step (success) 09:28:23 INFO - [mozharness: 2016-12-05 17:28:23.745047Z] Running run-tests step. 09:28:23 INFO - Running pre-action listener: _resource_record_pre_action 09:28:23 INFO - Running main action method: run_tests 09:28:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:28:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:28:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:28:23 INFO - 2016-12-05 09:28:23.759 screenresolution[1998:283716] starting screenresolution argv=screenresolution get 09:28:23 INFO - 2016-12-05 09:28:23.762 screenresolution[1998:283716] Display 0: 1600x1200x32@60 09:28:23 INFO - 2016-12-05 09:28:23.768 screenresolution[1999:283719] starting screenresolution argv=screenresolution list 09:28:23 INFO - Available Modes on Display 0 09:28:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:28:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:28:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:28:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:28:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:28:24 INFO - Intel Iris: 09:28:24 INFO - Chipset Model: Intel Iris 09:28:24 INFO - Type: GPU 09:28:24 INFO - Bus: Built-In 09:28:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:28:24 INFO - Vendor: Intel (0x8086) 09:28:24 INFO - Device ID: 0x0a2e 09:28:24 INFO - Revision ID: 0x0009 09:28:24 INFO - Displays: 09:28:24 INFO - Display: 09:28:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:28:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:28:24 INFO - Display Serial Number: 1600x1200 60 09:28:24 INFO - Main Display: Yes 09:28:24 INFO - Mirror: Off 09:28:24 INFO - Online: Yes 09:28:24 INFO - Rotation: Supported 09:28:24 INFO - Return code: 0 09:28:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:28:24 INFO - Python 2.7.3 09:28:24 INFO - Return code: 0 09:28:24 INFO - grabbing minidump binary from tooltool 09:28:24 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')]} 09:28:24 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 0x10ee37d30>, '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 0x10ec9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee2fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:28:24 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 09:28:24 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 09:28:24 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 09:28:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:28:24 INFO - Return code: 0 09:28:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:28:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:28:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:28:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:28:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:28:24 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/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0153', '--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 09:28:24 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/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0153 --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 09:28:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggrMNIFle4/Render', 09:28:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:24 INFO - 'HOME': '/Users/cltbld', 09:28:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:24 INFO - 'LOGNAME': 'cltbld', 09:28:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:28:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:24 INFO - 'MOZ_NO_REMOTE': '1', 09:28:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:28:24 INFO - 'NO_EM_RESTART': '1', 09:28:24 INFO - 'PAGER': '/bin/cat', 09:28:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:24 INFO - 'PWD': '/builds/slave/test', 09:28:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:28:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:24 INFO - 'SHELL': '/bin/bash', 09:28:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vfgEMsPqYX/Listeners', 09:28:24 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 09:28:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:24 INFO - 'USER': 'cltbld', 09:28:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:24 INFO - 'XPC_FLAGS': '0x0', 09:28:24 INFO - 'XPC_SERVICE_NAME': '0', 09:28:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:24 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/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0153', '--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 09:28:24 INFO - mozversion application_buildid: 20161205070638 09:28:24 INFO - mozversion application_changeset: 46049b21980d67b13d6915c052a3025c273a1788 09:28:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:28:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:28:24 INFO - mozversion application_name: Firefox 09:28:24 INFO - mozversion application_remotingname: firefox-dev 09:28:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:28:24 INFO - mozversion application_vendor: Mozilla 09:28:24 INFO - mozversion application_version: 52.0a2 09:28:24 INFO - mozversion platform_buildid: 20161205070638 09:28:24 INFO - mozversion platform_changeset: 46049b21980d67b13d6915c052a3025c273a1788 09:28:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:28:24 INFO - mozversion platform_version: 52.0a2 09:28:24 INFO - using testdate: 1480958904 09:28:24 INFO - actual date: 1480958904 09:28:24 INFO - starting webserver on 'localhost:49315' 09:28:24 INFO - SUITE-START | Running 2 tests 09:28:24 INFO - TEST-START | dromaeo_css 09:28:24 INFO - operating with platform_type : mac_ 09:28:24 INFO - Initialising browser for dromaeo_css test... 09:28:24 INFO - TEST-INFO | started process 2009 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpHvHaji/profile http://localhost:49315/getInfo.html) 09:28:27 INFO - PROCESS | 2009 | 2016-12-05 09:28:27.603 plugin-container[2013:283900] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 09:28:27 INFO - PROCESS | 2009 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:28:27 INFO - PROCESS | 2009 | 2016-12-05 09:28:27.607 plugin-container[2013:283900] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 09:28:27 INFO - PROCESS | 2009 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:28:27 INFO - PROCESS | 2009 | 2016-12-05 09:28:27.607 plugin-container[2013:283900] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:28:27 INFO - PROCESS | 2009 | __metrics Screen width/height:1600/1200 09:28:27 INFO - PROCESS | 2009 | colorDepth:24 09:28:27 INFO - PROCESS | 2009 | Browser inner width/height: 1024/689 09:28:27 INFO - PROCESS | 2009 | __metrics 09:28:27 ERROR - PROCESS | 2009 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 09:28:28 INFO - TEST-INFO | 2009: exit 0 09:28:28 INFO - Browser initialized. 09:28:28 INFO - Running cycle 1/1 for dromaeo_css test... 09:28:28 INFO - TEST-INFO | started process 2014 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpHvHaji/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:31 INFO - PROCESS | 2014 | 2016-12-05 09:28:31.249 plugin-container[2015:284033] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 09:28:31 INFO - PROCESS | 2014 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:28:31 INFO - PROCESS | 2014 | 2016-12-05 09:28:31.249 plugin-container[2015:284033] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:28:31 INFO - PROCESS | 2014 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:28:31 INFO - PROCESS | 2014 | 2016-12-05 09:28:31.249 plugin-container[2015:284033] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:28:40 INFO - PROCESS | 2014 | RSS: Main: 229388288 09:28:40 INFO - PROCESS | 2014 | 09:29:30 INFO - PROCESS | 2014 | 1480958970575 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] 09:31:44 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49315/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49315/tests/dromaeo/cssquery-ext.html) 09:31:46 INFO - PROCESS | 2014 | RSS: Main: 233623552 09:31:46 INFO - PROCESS | 2014 | 09:34:49 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49315/tests/dromaeo/cssquery-ext.html (next: http://localhost:49315/tests/dromaeo/cssquery-jquery.html) 09:34:49 INFO - PROCESS | 2014 | RSS: Main: 243109888 09:34:49 INFO - PROCESS | 2014 | 09:37:51 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49315/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49315/tests/dromaeo/cssquery-mootools.html) 09:37:52 INFO - PROCESS | 2014 | RSS: Main: 246005760 09:37:52 INFO - PROCESS | 2014 | 09:40:54 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49315/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49315/tests/dromaeo/cssquery-prototype.html) 09:40:54 INFO - PROCESS | 2014 | RSS: Main: 245796864 09:40:54 INFO - PROCESS | 2014 | 09:43:57 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49315/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49315/tests/dromaeo/cssquery-yui.html) 09:43:58 INFO - PROCESS | 2014 | RSS: Main: 248795136 09:43:58 INFO - PROCESS | 2014 | 09:47:00 INFO - PROCESS | 2014 | Cycle 1(1): loaded http://localhost:49315/tests/dromaeo/cssquery-yui.html (next: http://localhost:49315/tests/dromaeo/cssquery-dojo.html) 09:47:00 INFO - PROCESS | 2014 | RSS: Main: 250261504 09:47:00 INFO - PROCESS | 2014 | 09:47:00 INFO - PROCESS | 2014 | __start_tp_report 09:47:00 INFO - PROCESS | 2014 | _x_x_mozilla_page_load 09:47:00 INFO - PROCESS | 2014 | _x_x_mozilla_page_load_details 09:47:00 INFO - PROCESS | 2014 | |i|pagename|runs| 09:47:00 INFO - PROCESS | 2014 | |0;dojo.html;3406.0973841931886;3414.6756058174465;3431.1593659553414;3445.4142795724724;3460.031191266446;4765.64257680674;5909.320428150759;5989.88020239595;6001.5798894077325;6006.57953943223;4514.713306707589;5279.049771041211;5674;5926.5258779297665;6014.849628759283;4524.981879077214;5007.574356179711;5364.195370694388;5364.892702145955;5398.649087809299;5040.269160971657;5310.973445132769;5330.946690533084;5405.297311349519;5441.347038355403;5222.608304377149;5265.921011184835;5286.656367336111;5314.70769107697;5369.838904832861;5233.790648374061;5267.683938963674;5294.941011797656;5312.017276803765;5369.785208591652;5205;5231.24146998683;5268.473152684739;5321.813736519203;5354.036273470773;5769.798057067983;5895.587308888379;6002.309734380515;6035.215421995113;6057.273127224717;6206.627602343874;6235.812925612239;6260.812175634738;6302.338254483259;6303.810885673437;6355.713992870332;6410.134631824737;6418.133551970471;6446.033095035782;6477.514186436036;5887.499562537147;5907.320658124285;6120.785772497941;6122.326544080148;6148.323684394713;6381.8085457436355;6394.040893865865;6415.711292991827;6432.035194720829;6568.441682456949;6241.063840423973;6248.562600617913;6257.7184026718905;6258.655773932477;6275.905861412082;6261.906071408933;6345.460635846005;6362.681865906686;6380.234371875405;6382.712777925005;6111.591738512148;6133.610107582747;6153.446189842935;6222.066689996446;6228.159198508234;5526.944730552724;6259.164314284866;6303.905441418382;6304.338044505353;6318.399752023551;4767.118083154627;4983.352164218629;5029.421616514074;5037;5056.241563765465;4095.9385609215888;4424.535423780521;4470.463544374696;4474.910501789946;4477.9924516984065;5964.7912323068485;6122.428649698713;6124.081387791778;6151.323354431009;6152.78465253714;6237.875242495215;6282.811515654537;6333.113364129025;6371.458426033746;6404.135441715402;5938.198343223696;5968.791092311835;5997.100434934708;6044;6087.847803804941;5467.371252305956;5506.889862202734;5511.338639363302;5518.310211223597;5530.253415788898;6296.590721603081;6472.35276472349;6475.935240647534;6490.091387205889;6494.6752662367635;6168.814935551941;6278.780242691484;6301.117843502005;6333;6398.1362516059735;4829.73436460998;6458.095866578777;6548.1814773153355;6549.181352330958;6566.277709451861;4697.953020469751;6057.727402266821;6264.404881536247;6288.025356069817;6335.208098987627;4968.3292755479;5161.406438259648;6166;6208.099825525183;6215.129881816459;6154.969225154025;6185.134081228542;6185.907211391923;6197.5351848610635;6219.160413344141;6108.992016317258;6169.81490555284;6183.84540386494;6185.597936134137;6220.440160385587;5931.466168044896;5977.880442391215;6113.327533971348;6134.417230363287;6156.630602163885;6131.621347939075;6182.319944806158;6210.4100110489435;6231.2834024086;6283.088952101828;6144.692765361803;6178.382161783786;6178.969105154625;6186.876262474815;6199.039148931923 09:47:00 INFO - PROCESS | 2014 | |1;ext.html;469.7392946914462;482.3464206000869;492.1952607486758;494.44375078037217;507.42407367637765;35825.2834943301;36664.450033249486;39300.60699393005;39370.80314598423;39516.80241598791;39994.80002599983;40334.39498407519;40357.394639080434;40429.797851010706;40479.392809107885;7882.290593846553;8903.287736981058;9009.95495022524;9102.453852768822;9204.033576474474;8041.7185398511065;9392.248620110375;9412.62349506019;9417.670381536647;9425;7949.284564389204;9386.014468480813;9424.81150376992;9435.292353073513;9477.38397004196;9349.579268932881;9413.635022921639;9414.340996130239;9416.435013899189;9420.95289523548;9286.95356523213;9313.627454901796;9344.67293644726;9349;9351.906480935171;38731.41902871431;38743.612563874565;39034.219315613525;39187.8040609798;39204.411933821015;3132.6554079051516;3315.469524876032;3559.7096549666744;3685.649863262986;3856.84572617095;28081.157565273075;28583.428331433464;29909.252268693457;30176.54735178974;30323.090307290815;3178.4596618574665;3230.0632816483194;3237.87048518059;3241.286916878307;3315.96684033159;360642.1967890144;395285.0235748832;396954;528998;529855;58923.41076589265;58937;59246.11130833041;59617;59643.70178149082;54696;58360.95736649282;62314.75370492565;62323;62375.68812156001;15477.690446191238;15837.128957907194;16019.199040047952;16032.438864639915;16074.276657550437;12368.381580920917;12386.56647017368;12451.501939922302;12477.75044499123;12516.561920332744;15780.210989450481;16066.036037837766;16188.838111618734;16253.756193657104;16297.348106075624;3188.9202769930475;5373.973130134403;5760.251167348218;5843.860447212753;6392.936070639327;767.7773445700684;831.7421599304236;878.9912100879037;893.7799903132308;900.7567956651667;28152.85923570369;29021.419571608873;30028.54957175644;30034.24914377158;30284.394312113633;997.7705127820497;1034.7206254311293;1090.51472094917;1100.2023532938556;1107.0368779162181;12059;15210.315535800915;15656.608584785243;15753.527394178192;15803.20983950798;579369;686990;694626;696126;697237;56898.57753556111;57353.27940161802;57472.137917931264;57600;57656.71171644199;1337.9063465557317;1358.3615700620692;1376.9173849569058;1379.675776192594;1386.1128877518597;28129.87480500757;29173.562396563644;29861.850690746407;29943.85028074846;30484.390312194075;49521.50478495169;49933.00133997372;49943.25085123653;50061.99876002532;50071.499285006685;634791;635732;635871.8206408939;636452;636659.8167009135;25837.96648134129;26659.7334026665;26984.46031079328;27040.32399190036;27096.593551096354;46364.30453543132;46897.53102468931;47171.528284718086;47281.527184729086;47549.049019018734;38212.808935955145;38419;38646.806765965994;39699.80150099231;39797;38513.614863852126;38603.80698096492;38872.6112738869;39362.60637393704;39605.60394396019;36387.81806090953;37999.810000949816;38033.23933521369;38242.235155296185;38522.22955540929;26953.86523067372;32653.34693306073;33707.15732106619;34271.82864085763;34488.482672759426;25452;25595.488090238465;25612.487750245262;26014;26271.343216419744 09:47:00 INFO - PROCESS | 2014 | |2;jquery.html;358.2226568346687;363.492927366324;369.24489419137853;374.6422166830677;389.8149625139576;19151.946642934632;19974;20169.19323227071;20193.19227230909;20457.283995060156;19819.702704459443;19866.503337416572;20121.29575464859;20237.898810505925;20609.587808243825;4563.7489938053395;5659.122835960412;5855;5883;5965.284165900098;5766;5815.302163740336;5891.086881533369;5953.58324917256;5956.374580669032;5205.37535495741;5613.747381367848;5777.884442311151;5794.913076303858;5844.590878638497;5770.480656740892;5816.243888294537;5837.708114594253;5845.736941837585;5852.063669812831;4601.3558101865765;4729.196036673739;5018.64869459138;5036.84889453317;5099.413567439755;24966.750332496624;24997.750022499724;25141.37146571332;25341.61987570188;25706.614400784005;2222.0556263588073;2321.558903808288;2360.173939121311;2417.4560723836994;2454.239185852374;12326.75346493065;14829.220493540844;14979.400823967027;15415.768763468557;15741;1808.7739032620923;1912.254220853868;1916.2430839818333;1918.7121931710356;1925.0855843474378;111188.99999999919;113772;116941;117384.41307793492;118573.814261858;10558.947205263925;11061.115110791117;11092.279001864854;11168.162387820783;11314;13553.932230338785;13661.180329180082;13682.179069255877;13718.519851805138;13748.93125534386;6709.563878347892;6956.756513522002;8323.875141872877;8490.363222758318;8588.141185881486;6565.343465653396;8494.853194818621;8500.787480313042;8507.404481686222;8648.308135349156;5704.172894930211;6484.513661475408;6523.7064332105165;6683.331666833278;6709.597424154566;1549.8372670869396;1826.5707558723689;1826.881252718569;1832.4777438429983;1836.1828986101325;852.3266619370752;878.3500209844669;932.8041111366689;960.9279304052224;997.7455748784014;19694.409167724993;21143;21726.34820955374;22209.44476388071;22236.666450003264;945.2154711589365;945.2957546627669;963.1572374172599;975.0639386189196;981.4062492192121;11259.155563332783;11582.88417115835;11588.072954163647;11623.07015438763;11640.650780476599;557359.8528029417;568458;575545;608904;611274;110491.44754276337;113260.86739132668;127967.36016320046;138298;148475;839.9118092600311;943.3820847345;971.883373995111;980.6567701304486;1018.1396719771781;18985.33551325697;19432.4170274892;19940.800591993895;19954.70067949011;19968.50078748043;35988.64011359853;42101.947451313965;44181.77909110434;53973;54887.45112548823;76902.61548692221;78948;79947.20052799398;80457;80716.59641701754;8991.325650576102;9239.39943903644;9911.752206194902;10722.89277107219;12394.566190183301;10221.28451008452;13018.218906865619;13736.65658358549;13777.517786877412;13882.305884705926;11310.868913108952;11450.370229637118;11575.247608905394;11591;12106.51573937068;11034.889651103385;11469;11543.595974140864;11599.362035088152;11671.88328116708;11486.712832179262;11743.706407339543;11762.882371176178;11905.285682859056;11923.82114268304;12331.75336493283;12379.257244565355;12389.690257743629;12405.441755121221;12487.625371238877;8992.86510702353;9755.317127800985;11699.2980421175;11944.044476442037;12150.453229604866 09:47:00 INFO - PROCESS | 2014 | |3;mootools.html;1364.5087768403375;1460.379338781018;1614.6044219166306;1733.7226043832989;1781.109445277362;6527.967360163205;7701;7883.329916957058;8305.501669899802;8350.874736878945;7816.491928024674;8092;8116.5941702914915;8174.877376839352;8187;2215.7341119065654;2282.7146606674164;2298.5402919416183;2307.2501437032947;2307.284741730061;2305;2313.745487996319;2329.801966832821;2364.7044119485063;2373.288013595923;2267.8639281643073;2275.6245219538837;2280.0765689895534;2305.0779688124717;2321.419645088728;1570.364002578946;2293.1629955066446;2306.96539551906;2317.79139877411;2390.3307074019303;2062.9994452690435;2204.889755512218;2302.2747834432103;2323.4888324568738;2332.0788288626068;8017;8046.235607617267;8151.75544733653;8164.6325915333355;8256.463329883538;4963.553280204799;5050.974745126287;5106.08090543702;5118.795248190068;5124.846254612367;3182.347618738153;3277.5903012123485;3488.8604455821737;3493.6681015303507;3517.0503963929596;1012.8683271174774;1047.7904419116207;1049.9735510464875;1063.2716589136467;1078.95684172633;8720.389572729911;8873.378863479558;10695;10816.729581760519;10944.781104377867;4399.758013309299;4505.504394516601;4576.565226303496;4636.327732478771;4638.397008388888;6792.72829086831;8084.514929104273;9100.908990910139;9179.724608261762;9214.401063930956;4885.438174609965;5104.106781313255;5110.1568241239775;5120.539151476385;5123.205903085028;4190.266703326905;4488.977555112204;4661.883452913704;4697.177993851063;4703.341532185496;3746.38184699527;3765.359888818907;3825.483559719458;3853.1523064925673;3864.8454061837774;1254.1221145198397;1255.4601521345726;1257.226805514602;1264.2540900868532;1265.3040827544999;888.1739981816972;888.8622263549161;893.7363477774072;897.3673560140086;897.546738896853;5359.946400536022;5597.3003374578175;5617.662940223599;5689.146628005749;5733.8566535836935;422.06091446531224;423.9173361194599;424.4376201532981;425.5149129991812;430.29646527927065;3770.434434834797;3770.9434358484646;3782.451544526083;3863.9567316824864;3930.4890364252465;9573.18627916635;9808;9858.950705246429;9930.404175749478;9998.350107243006;9648.890377606525;9686.75783105428;9889.307748457682;9926.851097233548;9927.15619172364;7562.621868906533;7676.961615191888;7771.222877712184;7771.572563508948;7793.454458187985;5269.2348063399195;5346.385165705994;5415.4313797050745;5485.61600687948;5485.6982865942755;9038.954805225932;9137.680181193626;9181.954090229774;9184.448933064037;9222.677206297747;8804.60379282921;9056;9077.818443631222;9123;9252.583633736633;6493.285738568659;6915.550489218184;6999.230084690578;7055.4708396871465;7179.353858152791;6421.967890160519;7895.68417263303;7898.723544675909;7903.328217101381;7916.564588947663;7021;7116.110486189226;7151.7854464366155;7156.677949492389;7184.497085203986;6836.555623884432;6905.462708904964;6991.2659170788365;7003.439724822104;7160.57036577807;6599.406053455212;6615.569987950767;6623.370779775913;6708.362705542965;6721.697523611351;4796.760161991956;4806.903861922812;4855.902881942271;4870.853874383774;4881.438634556929;4779;4812.326274321527;4905.043516514245;4950.554450099508;4964.925526117184 09:47:00 INFO - PROCESS | 2014 | |4;prototype.html;258.14553826833185;260.1959943773739;263.55459273827233;290.96944820793817;294.93216560191166;5255.316808814849;5381.4349493303125;5470.538257783998;5502.2296878437;5503.587230957679;4557.293619488976;5055.292259083732;5103.795848166078;5217.61733140717;5255.369355677324;3447.379991206676;4039.4546736190678;4081.8979525511877;4116.176764647083;4120.155368149537;3993.8801835944823;4013.3177359848896;4128.834846606147;4130.979345103286;4146.087860670663;3701.9264413320107;4038.5961403859674;4056.492938382702;4058.208649313385;4059.106996460774;4051.9594804051876;4069.3285607874664;4074.653654439376;4080.9387859182134;4093.9385909211387;3121.859516321771;3326.467765157563;3559;3580.7135429165605;3581.982090089559;3961.7821019843896;5019.262848518535;5198.298229738974;5221.71280579568;5229.2156176573435;4675.901163226639;4677.4854765975715;4681.250999840009;4691.554302341272;4709.2229782085915;5145.6398052136365;5206.505381988705;5707.885842283173;5725.799597014085;5747.281589801275;3576.8390422430793;3813.599572044951;3830.885073447801;3855.9588910994157;3943.6647884929816;5051.343325367716;5216.58267338612;5948.7620495180145;5979.461848433619;6078.179445774852;3238.9028329150165;3649.543807024122;3651.507046548735;3907.199024200032;3912.5304963404574;3647.854085836537;3656.2687462507606;3720.311742327678;3869.690424766012;3927.116398810245;3992.4210989406374;4559.817607295738;4569.040501494724;4577.855536115971;4604.470485894098;5718.942810571841;5737.426257374229;5759.452851979056;5789.392113828072;5799.883042689388;1215.7325388414536;1237.294741997053;1262.5517941130765;1272.4083301264957;1280.7630588341187;841.2428814067266;862.4825104937113;865.4331412924545;865.8701194820701;871.8648609465542;4367.77243605757;4526.642007397768;5111.309973153651;5116.923246151311;5154.329937108152;5239.633225674243;5547.084731019337;5575.33096028471;5585.553155747531;5613.578981576398;4272.269730759048;4607.562281583244;4827.975860120677;4919.188333924933;5160.40655324635;2899.5505696617056;2917.8249305041763;2921.6932222116798;2928.9267768305963;2935.280856190247;4976.452590215146;5201.491567445474;5370.3287089113865;5636.351819540723;5820.854478638068;5120.974395128001;5443.755031023614;5653.745581448845;5678.6655136043;5763.538916886639;5742.109972954198;5747.252857128547;5747.626404283708;5754.03667111438;5844.590878638454;4235.597618226264;5060.949390506048;5415.945840541544;5498.752556135064;5554.888902221852;4539.614132798617;5088.720120393414;5450.574494349976;5456.116775485997;5501.614886957875;4816.182533218107;5002.824901128443;5208.687478751287;5237.869053273698;5835.095560188178;3164.74992378014;3240.254741409442;3413.9829300853335;3719.20037192005;3724.6647801697977;4487.259602165606;4928.802847886188;4969.801207951786;5468.753906074316;5519.531557022734;5400.594955378284;5473.945260547343;5533.142362933751;5630.296212973379;5743.224664670216;5087.262346959744;5729.54163666914;5739.684317362585;5767.423257674367;5773.740181691918;4737.573618374363;5105.795768169231;5330.600204984564;5404.514088412873;5672.5461963042035;5704.459795855013;5724.914126288026;5725.8568535786935;5768.278965129359;5772.56705747079;4514.88712782183;4662.603678687349;5604.299462567179;5608.663480191201;5643.1535269709875 09:47:00 INFO - PROCESS | 2014 | |5;yui.html;637.9776707815228;687.3538873458951;691.2914262880547;761.9847603047939;774.6862520679124;12524.686882827933;13091.214527128379;14069;14614.830813534918;14753.262336883166;10454.849966503678;12417.130800843945;13270.469181232738;13379.331033448338;13388.464461421532;627.1533429869685;651.4397618048474;768.7194174126431;790.8774140008308;795.0459448661601;792.0772300270207;792.3819420851717;793.8491686579531;794.2454668065332;794.519315813933;711.7189059692558;820.4912953968548;835.3275613131427;837.0917554453425;839.1985653700732;679.8130514108635;818.1654712193559;830.2444775254489;830.464350493928;832.6045128563944;613.6011592464934;614.112607282477;614.5483069943588;617.3764497857152;619.2074145094241;13477.797833032513;13625.795613065713;13660.453581856715;13750;13781.862181378157;12657.493700251978;13607;13619.727605447835;13674.521391751341;13805.309734513234;11015.669529914116;11046.392448415334;11220.49507772144;11470.942645286803;11487.885121148765;3142.3244002539564;3643.872464463731;3731.1045349116016;3749.250149970017;3831.3869780834934;90252;91826.5408672959;94258.05741942562;95199.52400237955;97716.51141744317;10194.745131371776;10547.894521054845;10548.419836909045;10552.525136368882;10561.260711750258;14560;20500.589988200452;20561.869097199797;20679.379618611467;20781.58436831285;7671.002769639911;7678;7686.884696729554;7727.729529466442;7826.256505631955;4200.726952748061;4310.3103503439925;4325.351197320427;4366.214081465303;4394.406755088022;3381.6956473917467;3470.16715988161;3485.320362529333;3507.7018453431233;3518.0852978225844;567.0303780535331;577.924869766936;604.8729766748944;633.4140919649305;649.2825427902195;647.7085311609794;656.1830521001384;663.4758540752752;666.74330382802;674.3155696967582;9002;9113.36206465541;9184.908150918405;9364.016778238323;9554.04459554039;591.7958304385066;595.4402861310355;630.7729217481703;646.0857886091222;652.846581053452;4325.6106950374615;4497.977510112429;4528.705634133837;4569.725816451024;4581.404417425713;109726.45136774424;110866;114186;119243.80756192327;122604.77395226112;93306.68633627135;97416.02583974211;103078;105304.47347763213;105763;73835.63082184555;89295.55352223197;90691.09308906959;93881;94797;8816.559172041501;8918.86621700662;8929.151730585583;9003.90996090057;9013.704205567954;45911;49401.76495587639;59905.70047149737;59929.40070599238;59931.31936495158;40367.59632403756;40532.79733601313;81613;81859;82989;11223.88776112261;11498.310101393943;11510.36692981894;12347.69130771738;12413.503459861866;14004.929975350058;14108.082974606617;14254.216018119103;14369.281535923372;14472;12841.743165136832;13265.734685306434;13309.401076951754;13337.799933001206;13719.451221951409;12755.426005829946;12975.09174357786;13141.34293285351;13334.533291334757;13472.326383680973;11451.942740286246;12572.182808117443;12682.302473364054;12978.026648001249;12984.805227921399;8867.556622168995;11098.389588572705;11310.264832785842;11608.593699220486;11649.76700465969;10958.506867190836;11342;11678.474468648761;11692.298462092302;11699.473523691624 09:47:00 INFO - PROCESS | 2014 | __end_tp_report 09:47:00 INFO - PROCESS | 2014 | __start_cc_report 09:47:00 INFO - PROCESS | 2014 | _x_x_mozilla_cycle_collect,365 09:47:00 INFO - PROCESS | 2014 | __end_cc_report 09:47:00 INFO - PROCESS | 2014 | __startTimestamp1480960020971__endTimestamp 09:47:00 INFO - PROCESS | 2014 | 09:47:00 INFO - PROCESS | 2014 | ------- Summary: start ------- 09:47:00 INFO - PROCESS | 2014 | Number of tests: 6 09:47:00 INFO - PROCESS | 2014 | 09:47:00 INFO - PROCESS | 2014 | [#0] dojo.html Cycles:165 Average:5809.01 Median:6124.08 stddev:692.98 (11.3%) stddev-sans-first:668.97 09:47:00 INFO - PROCESS | 2014 | Values: 3406.1 3414.7 3431.2 3445.4 3460.0 4765.6 5909.3 5989.9 6001.6 6006.6 4514.7 5279.0 5674.0 5926.5 6014.8 4525.0 5007.6 5364.2 5364.9 5398.6 5040.3 5311.0 5330.9 5405.3 5441.3 5222.6 5265.9 5286.7 5314.7 5369.8 5233.8 5267.7 5294.9 5312.0 5369.8 5205.0 5231.2 5268.5 5321.8 5354.0 5769.8 5895.6 6002.3 6035.2 6057.3 6206.6 6235.8 6260.8 6302.3 6303.8 6355.7 6410.1 6418.1 6446.0 6477.5 5887.5 5907.3 6120.8 6122.3 6148.3 6381.8 6394.0 6415.7 6432.0 6568.4 6241.1 6248.6 6257.7 6258.7 6275.9 6261.9 6345.5 6362.7 6380.2 6382.7 6111.6 6133.6 6153.4 6222.1 6228.2 5526.9 6259.2 6303.9 6304.3 6318.4 4767.1 4983.4 5029.4 5037.0 5056.2 4095.9 4424.5 4470.5 4474.9 4478.0 5964.8 6122.4 6124.1 6151.3 6152.8 6237.9 6282.8 6333.1 6371.5 6404.1 5938.2 5968.8 5997.1 6044.0 6087.8 5467.4 5506.9 5511.3 5518.3 5530.3 6296.6 6472.4 6475.9 6490.1 6494.7 6168.8 6278.8 6301.1 6333.0 6398.1 4829.7 6458.1 6548.2 6549.2 6566.3 4698.0 6057.7 6264.4 6288.0 6335.2 4968.3 5161.4 6166.0 6208.1 6215.1 6155.0 6185.1 6185.9 6197.5 6219.2 6109.0 6169.8 6183.8 6185.6 6220.4 5931.5 5977.9 6113.3 6134.4 6156.6 6131.6 6182.3 6210.4 6231.3 6283.1 6144.7 6178.4 6179.0 6186.9 6199.0 09:47:00 INFO - PROCESS | 2014 | 09:47:00 INFO - PROCESS | 2014 | [#1] ext.html Cycles:180 Average:70843.12 Median:39366.70 stddev:159203.17 (404.4%) stddev-sans-first:159562.10 09:47:00 INFO - PROCESS | 2014 | Values: 469.7 482.3 492.2 494.4 507.4 35825.3 36664.5 39300.6 39370.8 39516.8 39994.8 40334.4 40357.4 40429.8 40479.4 7882.3 8903.3 9010.0 9102.5 9204.0 8041.7 9392.2 9412.6 9417.7 9425.0 7949.3 9386.0 9424.8 9435.3 9477.4 9349.6 9413.6 9414.3 9416.4 9421.0 9287.0 9313.6 9344.7 9349.0 9351.9 38731.4 38743.6 39034.2 39187.8 39204.4 3132.7 3315.5 3559.7 3685.6 3856.8 28081.2 28583.4 29909.3 30176.5 30323.1 3178.5 3230.1 3237.9 3241.3 3316.0 360642.2 395285.0 396954.0 528998.0 529855.0 58923.4 58937.0 59246.1 59617.0 59643.7 54696.0 58361.0 62314.8 62323.0 62375.7 15477.7 15837.1 16019.2 16032.4 16074.3 12368.4 12386.6 12451.5 12477.8 12516.6 15780.2 16066.0 16188.8 16253.8 16297.3 3188.9 5374.0 5760.3 5843.9 6392.9 767.8 831.7 879.0 893.8 900.8 28152.9 29021.4 30028.5 30034.2 30284.4 997.8 1034.7 1090.5 1100.2 1107.0 12059.0 15210.3 15656.6 15753.5 15803.2 579369.0 686990.0 694626.0 696126.0 697237.0 56898.6 57353.3 57472.1 57600.0 57656.7 1337.9 1358.4 1376.9 1379.7 1386.1 28129.9 29173.6 29861.9 29943.9 30484.4 49521.5 49933.0 49943.3 50062.0 50071.5 634791.0 635732.0 635871.8 636452.0 636659.8 25838.0 26659.7 26984.5 27040.3 27096.6 46364.3 46897.5 47171.5 47281.5 47549.0 38212.8 38419.0 38646.8 39699.8 39797.0 38513.6 38603.8 38872.6 39362.6 39605.6 36387.8 37999.8 38033.2 38242.2 38522.2 26953.9 32653.3 33707.2 34271.8 34488.5 25452.0 25595.5 25612.5 26014.0 26271.3 09:47:00 INFO - PROCESS | 2014 | 09:47:00 INFO - PROCESS | 2014 | [#2] jquery.html Cycles:180 Average:34757.83 Median:12229.36 stddev:97599.42 (798.1%) stddev-sans-first:97839.03 09:47:00 INFO - PROCESS | 2014 | Values: 358.2 363.5 369.2 374.6 389.8 19151.9 19974.0 20169.2 20193.2 20457.3 19819.7 19866.5 20121.3 20237.9 20609.6 4563.7 5659.1 5855.0 5883.0 5965.3 5766.0 5815.3 5891.1 5953.6 5956.4 5205.4 5613.7 5777.9 5794.9 5844.6 5770.5 5816.2 5837.7 5845.7 5852.1 4601.4 4729.2 5018.6 5036.8 5099.4 24966.8 24997.8 25141.4 25341.6 25706.6 2222.1 2321.6 2360.2 2417.5 2454.2 12326.8 14829.2 14979.4 15415.8 15741.0 1808.8 1912.3 1916.2 1918.7 1925.1 111189.0 113772.0 116941.0 117384.4 118573.8 10558.9 11061.1 11092.3 11168.2 11314.0 13553.9 13661.2 13682.2 13718.5 13748.9 6709.6 6956.8 8323.9 8490.4 8588.1 6565.3 8494.9 8500.8 8507.4 8648.3 5704.2 6484.5 6523.7 6683.3 6709.6 1549.8 1826.6 1826.9 1832.5 1836.2 852.3 878.4 932.8 960.9 997.7 19694.4 21143.0 21726.3 22209.4 22236.7 945.2 945.3 963.2 975.1 981.4 11259.2 11582.9 11588.1 11623.1 11640.7 557359.9 568458.0 575545.0 608904.0 611274.0 110491.4 113260.9 127967.4 138298.0 148475.0 839.9 943.4 971.9 980.7 1018.1 18985.3 19432.4 19940.8 19954.7 19968.5 35988.6 42101.9 44181.8 53973.0 54887.5 76902.6 78948.0 79947.2 80457.0 80716.6 8991.3 9239.4 9911.8 10722.9 12394.6 10221.3 13018.2 13736.7 13777.5 13882.3 11310.9 11450.4 11575.2 11591.0 12106.5 11034.9 11469.0 11543.6 11599.4 11671.9 11486.7 11743.7 11762.9 11905.3 11923.8 12331.8 12379.3 12389.7 12405.4 12487.6 8992.9 9755.3 11699.3 11944.0 12150.5 09:47:00 INFO - PROCESS | 2014 | 09:47:00 INFO - PROCESS | 2014 | [#3] mootools.html Cycles:180 Average:5301.69 Median:5121.87 stddev:2875.08 (56.1%) stddev-sans-first:2867.92 09:47:00 INFO - PROCESS | 2014 | Values: 1364.5 1460.4 1614.6 1733.7 1781.1 6528.0 7701.0 7883.3 8305.5 8350.9 7816.5 8092.0 8116.6 8174.9 8187.0 2215.7 2282.7 2298.5 2307.3 2307.3 2305.0 2313.7 2329.8 2364.7 2373.3 2267.9 2275.6 2280.1 2305.1 2321.4 1570.4 2293.2 2307.0 2317.8 2390.3 2063.0 2204.9 2302.3 2323.5 2332.1 8017.0 8046.2 8151.8 8164.6 8256.5 4963.6 5051.0 5106.1 5118.8 5124.8 3182.3 3277.6 3488.9 3493.7 3517.1 1012.9 1047.8 1050.0 1063.3 1079.0 8720.4 8873.4 10695.0 10816.7 10944.8 4399.8 4505.5 4576.6 4636.3 4638.4 6792.7 8084.5 9100.9 9179.7 9214.4 4885.4 5104.1 5110.2 5120.5 5123.2 4190.3 4489.0 4661.9 4697.2 4703.3 3746.4 3765.4 3825.5 3853.2 3864.8 1254.1 1255.5 1257.2 1264.3 1265.3 888.2 888.9 893.7 897.4 897.5 5359.9 5597.3 5617.7 5689.1 5733.9 422.1 423.9 424.4 425.5 430.3 3770.4 3770.9 3782.5 3864.0 3930.5 9573.2 9808.0 9859.0 9930.4 9998.4 9648.9 9686.8 9889.3 9926.9 9927.2 7562.6 7677.0 7771.2 7771.6 7793.5 5269.2 5346.4 5415.4 5485.6 5485.7 9039.0 9137.7 9182.0 9184.4 9222.7 8804.6 9056.0 9077.8 9123.0 9252.6 6493.3 6915.6 6999.2 7055.5 7179.4 6422.0 7895.7 7898.7 7903.3 7916.6 7021.0 7116.1 7151.8 7156.7 7184.5 6836.6 6905.5 6991.3 7003.4 7160.6 6599.4 6615.6 6623.4 6708.4 6721.7 4796.8 4806.9 4855.9 4870.9 4881.4 4779.0 4812.3 4905.0 4950.6 4964.9 09:47:00 INFO - PROCESS | 2014 | 09:47:00 INFO - PROCESS | 2014 | [#4] prototype.html Cycles:180 Average:4467.96 Median:5053.32 stddev:1378.57 (27.3%) stddev-sans-first:1345.74 09:47:00 INFO - PROCESS | 2014 | Values: 258.1 260.2 263.6 291.0 294.9 5255.3 5381.4 5470.5 5502.2 5503.6 4557.3 5055.3 5103.8 5217.6 5255.4 3447.4 4039.5 4081.9 4116.2 4120.2 3993.9 4013.3 4128.8 4131.0 4146.1 3701.9 4038.6 4056.5 4058.2 4059.1 4052.0 4069.3 4074.7 4080.9 4093.9 3121.9 3326.5 3559.0 3580.7 3582.0 3961.8 5019.3 5198.3 5221.7 5229.2 4675.9 4677.5 4681.3 4691.6 4709.2 5145.6 5206.5 5707.9 5725.8 5747.3 3576.8 3813.6 3830.9 3856.0 3943.7 5051.3 5216.6 5948.8 5979.5 6078.2 3238.9 3649.5 3651.5 3907.2 3912.5 3647.9 3656.3 3720.3 3869.7 3927.1 3992.4 4559.8 4569.0 4577.9 4604.5 5718.9 5737.4 5759.5 5789.4 5799.9 1215.7 1237.3 1262.6 1272.4 1280.8 841.2 862.5 865.4 865.9 871.9 4367.8 4526.6 5111.3 5116.9 5154.3 5239.6 5547.1 5575.3 5585.6 5613.6 4272.3 4607.6 4828.0 4919.2 5160.4 2899.6 2917.8 2921.7 2928.9 2935.3 4976.5 5201.5 5370.3 5636.4 5820.9 5121.0 5443.8 5653.7 5678.7 5763.5 5742.1 5747.3 5747.6 5754.0 5844.6 4235.6 5060.9 5415.9 5498.8 5554.9 4539.6 5088.7 5450.6 5456.1 5501.6 4816.2 5002.8 5208.7 5237.9 5835.1 3164.7 3240.3 3414.0 3719.2 3724.7 4487.3 4928.8 4969.8 5468.8 5519.5 5400.6 5473.9 5533.1 5630.3 5743.2 5087.3 5729.5 5739.7 5767.4 5773.7 4737.6 5105.8 5330.6 5404.5 5672.5 5704.5 5724.9 5725.9 5768.3 5772.6 4514.9 4662.6 5604.3 5608.7 5643.2 09:47:00 INFO - PROCESS | 2014 | 09:47:00 INFO - PROCESS | 2014 | [#5] yui.html Cycles:180 Average:20815.27 Median:4317.83 stddev:31335.16 (725.7%) stddev-sans-first:31386.43 09:47:00 INFO - PROCESS | 2014 | Values: 638.0 687.4 691.3 762.0 774.7 12524.7 13091.2 14069.0 14614.8 14753.3 10454.8 12417.1 13270.5 13379.3 13388.5 627.2 651.4 768.7 790.9 795.0 792.1 792.4 793.8 794.2 794.5 711.7 820.5 835.3 837.1 839.2 679.8 818.2 830.2 830.5 832.6 613.6 614.1 614.5 617.4 619.2 13477.8 13625.8 13660.5 13750.0 13781.9 12657.5 13607.0 13619.7 13674.5 13805.3 11015.7 11046.4 11220.5 11470.9 11487.9 3142.3 3643.9 3731.1 3749.3 3831.4 90252.0 91826.5 94258.1 95199.5 97716.5 10194.7 10547.9 10548.4 10552.5 10561.3 14560.0 20500.6 20561.9 20679.4 20781.6 7671.0 7678.0 7686.9 7727.7 7826.3 4200.7 4310.3 4325.4 4366.2 4394.4 3381.7 3470.2 3485.3 3507.7 3518.1 567.0 577.9 604.9 633.4 649.3 647.7 656.2 663.5 666.7 674.3 9002.0 9113.4 9184.9 9364.0 9554.0 591.8 595.4 630.8 646.1 652.8 4325.6 4498.0 4528.7 4569.7 4581.4 109726.5 110866.0 114186.0 119243.8 122604.8 93306.7 97416.0 103078.0 105304.5 105763.0 73835.6 89295.6 90691.1 93881.0 94797.0 8816.6 8918.9 8929.2 9003.9 9013.7 45911.0 49401.8 59905.7 59929.4 59931.3 40367.6 40532.8 81613.0 81859.0 82989.0 11223.9 11498.3 11510.4 12347.7 12413.5 14004.9 14108.1 14254.2 14369.3 14472.0 12841.7 13265.7 13309.4 13337.8 13719.5 12755.4 12975.1 13141.3 13334.5 13472.3 11451.9 12572.2 12682.3 12978.0 12984.8 8867.6 11098.4 11310.3 11608.6 11649.8 10958.5 11342.0 11678.5 11692.3 11699.5 09:47:00 INFO - PROCESS | 2014 | -------- Summary: end -------- 09:47:00 INFO - PROCESS | 2014 | 09:47:01 INFO - TEST-INFO | 2014: exit 0 09:47:01 INFO - TEST-OK | dromaeo_css | took 1117271ms 09:47:01 INFO - TEST-START | kraken 09:47:01 INFO - operating with platform_type : mac_ 09:47:01 INFO - Initialising browser for kraken test... 09:47:01 INFO - TEST-INFO | started process 2020 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpQ6FiF9/profile http://localhost:49315/getInfo.html) 09:47:04 INFO - PROCESS | 2020 | 2016-12-05 09:47:04.634 plugin-container[2021:286121] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 09:47:04 INFO - PROCESS | 2020 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:04 INFO - PROCESS | 2020 | 2016-12-05 09:47:04.668 plugin-container[2021:286121] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 09:47:04 INFO - PROCESS | 2020 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:04 INFO - PROCESS | 2020 | 2016-12-05 09:47:04.668 plugin-container[2021:286121] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:47:04 INFO - PROCESS | 2020 | __metrics Screen width/height:1600/1200 09:47:04 INFO - PROCESS | 2020 | colorDepth:24 09:47:04 INFO - PROCESS | 2020 | Browser inner width/height: 1024/689 09:47:04 INFO - PROCESS | 2020 | __metrics 09:47:04 ERROR - PROCESS | 2020 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 09:47:05 INFO - TEST-INFO | 2020: exit 0 09:47:05 INFO - Browser initialized. 09:47:05 INFO - Running cycle 1/1 for kraken test... 09:47:05 INFO - TEST-INFO | started process 2022 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpQ6FiF9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:47:08 INFO - PROCESS | 2022 | 2016-12-05 09:47:08.177 plugin-container[2023:286244] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x882f, name = 'com.apple.tsm.portname' 09:47:08 INFO - PROCESS | 2022 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:08 INFO - PROCESS | 2022 | 2016-12-05 09:47:08.178 plugin-container[2023:286244] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8917, name = 'com.apple.CFPasteboardClient' 09:47:08 INFO - PROCESS | 2022 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:08 INFO - PROCESS | 2022 | 2016-12-05 09:47:08.178 plugin-container[2023:286244] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:47:17 INFO - PROCESS | 2022 | RSS: Main: 229523456 09:47:17 INFO - PROCESS | 2022 | 09:47:20 INFO - PROCESS | 2022 | Cycle 1(1): loaded http://localhost:49315/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49315/tests/kraken/driver.html?testName=audio-beat-detection) 09:47:21 INFO - PROCESS | 2022 | RSS: Main: 241262592 09:47:21 INFO - PROCESS | 2022 | 09:47:24 INFO - PROCESS | 2022 | Cycle 1(1): loaded http://localhost:49315/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49315/tests/kraken/driver.html?testName=audio-dft) 09:47:25 INFO - PROCESS | 2022 | RSS: Main: 249036800 09:47:25 INFO - PROCESS | 2022 | 09:47:28 INFO - PROCESS | 2022 | Cycle 1(1): loaded http://localhost:49315/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49315/tests/kraken/driver.html?testName=audio-fft) 09:47:29 INFO - PROCESS | 2022 | RSS: Main: 252784640 09:47:29 INFO - PROCESS | 2022 | 09:47:31 INFO - PROCESS | 2022 | Cycle 1(1): loaded http://localhost:49315/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49315/tests/kraken/driver.html?testName=audio-oscillator) 09:47:31 INFO - PROCESS | 2022 | RSS: Main: 253120512 09:47:31 INFO - PROCESS | 2022 | 09:47:33 INFO - PROCESS | 2022 | Cycle 1(1): loaded http://localhost:49315/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49315/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:47:34 INFO - PROCESS | 2022 | RSS: Main: 253243392 09:47:34 INFO - PROCESS | 2022 | 09:47:37 INFO - PROCESS | 2022 | Cycle 1(1): loaded http://localhost:49315/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49315/tests/kraken/driver.html?testName=imaging-darkroom) 09:47:37 INFO - PROCESS | 2022 | RSS: Main: 258830336 09:47:37 INFO - PROCESS | 2022 | 09:47:40 INFO - PROCESS | 2022 | Cycle 1(1): loaded http://localhost:49315/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49315/tests/kraken/driver.html?testName=imaging-desaturate) 09:47:41 INFO - PROCESS | 2022 | RSS: Main: 257937408 09:47:41 INFO - PROCESS | 2022 | 09:47:44 INFO - PROCESS | 2022 | Cycle 1(1): loaded http://localhost:49315/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49315/tests/kraken/driver.html?testName=json-parse-financial) 09:47:44 INFO - PROCESS | 2022 | RSS: Main: 258076672 09:47:44 INFO - PROCESS | 2022 | 09:47:46 INFO - PROCESS | 2022 | Cycle 1(1): loaded http://localhost:49315/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49315/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:47:47 INFO - PROCESS | 2022 | RSS: Main: 257114112 09:47:47 INFO - PROCESS | 2022 | 09:47:49 INFO - PROCESS | 2022 | Cycle 1(1): loaded http://localhost:49315/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49315/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:47:49 INFO - PROCESS | 2022 | RSS: Main: 257417216 09:47:49 INFO - PROCESS | 2022 | 09:47:51 INFO - PROCESS | 2022 | Cycle 1(1): loaded http://localhost:49315/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49315/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:47:52 INFO - PROCESS | 2022 | RSS: Main: 257134592 09:47:52 INFO - PROCESS | 2022 | 09:47:55 INFO - PROCESS | 2022 | Cycle 1(1): loaded http://localhost:49315/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49315/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:47:56 INFO - PROCESS | 2022 | RSS: Main: 257601536 09:47:56 INFO - PROCESS | 2022 | 09:47:59 INFO - PROCESS | 2022 | Cycle 1(1): loaded http://localhost:49315/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49315/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:48:00 INFO - PROCESS | 2022 | RSS: Main: 257490944 09:48:00 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | Cycle 1(1): loaded http://localhost:49315/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49315/tests/kraken/driver.html?testName=ai-astar) 09:48:02 INFO - PROCESS | 2022 | RSS: Main: 258969600 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | __start_tp_report 09:48:02 INFO - PROCESS | 2022 | _x_x_mozilla_page_load 09:48:02 INFO - PROCESS | 2022 | _x_x_mozilla_page_load_details 09:48:02 INFO - PROCESS | 2022 | |i|pagename|runs| 09:48:02 INFO - PROCESS | 2022 | |0;ai-astar;114;119;118;118;168;118;118;118;120;117 09:48:02 INFO - PROCESS | 2022 | |1;audio-beat-detection;154;151;147;194;105;149;100;101;166;145 09:48:02 INFO - PROCESS | 2022 | |2;audio-dft;197;199;201;202;195;197;199;217;277;210 09:48:02 INFO - PROCESS | 2022 | |3;audio-fft;86;65;84;110;94;59;59;62;77;51 09:48:02 INFO - PROCESS | 2022 | |4;audio-oscillator;91;72;89;72;71;71;88;83;103;90 09:48:02 INFO - PROCESS | 2022 | |5;imaging-gaussian-blur;90;88;90;91;93;99;90;89;133;88 09:48:02 INFO - PROCESS | 2022 | |6;imaging-darkroom;106;97;97;97;98;108;153;153;96;99 09:48:02 INFO - PROCESS | 2022 | |7;imaging-desaturate;88;89;85;87;89;89;91;91;88;87 09:48:02 INFO - PROCESS | 2022 | |8;json-parse-financial;57;57;56;59;61;60;60;57;58;90 09:48:02 INFO - PROCESS | 2022 | |9;json-stringify-tinderbox;71;78;73;85;79;78;71;73;73;74 09:48:02 INFO - PROCESS | 2022 | |10;stanford-crypto-aes;83;87;86;88;88;100;109;89;88;91 09:48:02 INFO - PROCESS | 2022 | |11;stanford-crypto-ccm;146;154;158;165;155;169;151;174;221;176 09:48:02 INFO - PROCESS | 2022 | |12;stanford-crypto-pbkdf2;168;225;159;161;230;177;190;160;219;234 09:48:02 INFO - PROCESS | 2022 | |13;stanford-crypto-sha256-iterative;60;93;93;68;68;70;69;70;70;67 09:48:02 INFO - PROCESS | 2022 | __end_tp_report 09:48:02 INFO - PROCESS | 2022 | __start_cc_report 09:48:02 INFO - PROCESS | 2022 | _x_x_mozilla_cycle_collect,1014 09:48:02 INFO - PROCESS | 2022 | __end_cc_report 09:48:02 INFO - PROCESS | 2022 | __startTimestamp1480960082263__endTimestamp 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | ------- Summary: start ------- 09:48:02 INFO - PROCESS | 2022 | Number of tests: 14 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | [#0] ai-astar Cycles:10 Average:122.80 Median:118.00 stddev:15.96 (13.5%) stddev-sans-first:16.60 09:48:02 INFO - PROCESS | 2022 | Values: 114.0 119.0 118.0 118.0 168.0 118.0 118.0 118.0 120.0 117.0 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:150.00 stddev:30.54 (20.4%) stddev-sans-first:32.04 09:48:02 INFO - PROCESS | 2022 | Values: 154.0 151.0 147.0 194.0 105.0 149.0 100.0 101.0 166.0 145.0 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | [#2] audio-dft Cycles:10 Average:209.40 Median:201.50 stddev:24.69 (12.3%) stddev-sans-first:25.77 09:48:02 INFO - PROCESS | 2022 | Values: 197.0 199.0 201.0 202.0 195.0 197.0 199.0 217.0 277.0 210.0 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | [#3] audio-fft Cycles:10 Average:74.70 Median:71.00 stddev:18.70 (26.3%) stddev-sans-first:19.38 09:48:02 INFO - PROCESS | 2022 | Values: 86.0 65.0 84.0 110.0 94.0 59.0 59.0 62.0 77.0 51.0 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | [#4] audio-oscillator Cycles:10 Average:83.00 Median:85.50 stddev:11.08 (13.0%) stddev-sans-first:11.36 09:48:02 INFO - PROCESS | 2022 | Values: 91.0 72.0 89.0 72.0 71.0 71.0 88.0 83.0 103.0 90.0 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | [#5] imaging-gaussian-blur Cycles:10 Average:95.10 Median:90.00 stddev:13.70 (15.2%) stddev-sans-first:14.40 09:48:02 INFO - PROCESS | 2022 | Values: 90.0 88.0 90.0 91.0 93.0 99.0 90.0 89.0 133.0 88.0 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | [#6] imaging-darkroom Cycles:10 Average:110.40 Median:97.00 stddev:22.81 (23.5%) stddev-sans-first:24.14 09:48:02 INFO - PROCESS | 2022 | Values: 106.0 97.0 97.0 97.0 98.0 108.0 153.0 153.0 96.0 99.0 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | [#7] imaging-desaturate Cycles:10 Average:88.40 Median:89.00 stddev:1.84 (2.1%) stddev-sans-first:1.94 09:48:02 INFO - PROCESS | 2022 | Values: 88.0 89.0 85.0 87.0 89.0 89.0 91.0 91.0 88.0 87.0 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:59.50 stddev:10.15 (17.1%) stddev-sans-first:10.63 09:48:02 INFO - PROCESS | 2022 | Values: 57.0 57.0 56.0 59.0 61.0 60.0 60.0 57.0 58.0 90.0 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | [#9] json-stringify-tinderbox Cycles:10 Average:75.50 Median:76.00 stddev:4.43 (5.8%) stddev-sans-first:4.39 09:48:02 INFO - PROCESS | 2022 | Values: 71.0 78.0 73.0 85.0 79.0 78.0 71.0 73.0 73.0 74.0 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:88.00 stddev:7.75 (8.8%) stddev-sans-first:7.68 09:48:02 INFO - PROCESS | 2022 | Values: 83.0 87.0 86.0 88.0 88.0 100.0 109.0 89.0 88.0 91.0 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | [#11] stanford-crypto-ccm Cycles:10 Average:166.90 Median:167.00 stddev:21.46 (12.9%) stddev-sans-first:21.39 09:48:02 INFO - PROCESS | 2022 | Values: 146.0 154.0 158.0 165.0 155.0 169.0 151.0 174.0 221.0 176.0 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.30 Median:204.50 stddev:31.45 (15.4%) stddev-sans-first:32.11 09:48:02 INFO - PROCESS | 2022 | Values: 168.0 225.0 159.0 161.0 230.0 177.0 190.0 160.0 219.0 234.0 09:48:02 INFO - PROCESS | 2022 | 09:48:02 INFO - PROCESS | 2022 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:70.00 stddev:11.04 (15.8%) stddev-sans-first:10.70 09:48:02 INFO - PROCESS | 2022 | Values: 60.0 93.0 93.0 68.0 68.0 70.0 69.0 70.0 70.0 67.0 09:48:02 INFO - PROCESS | 2022 | -------- Summary: end -------- 09:48:02 INFO - PROCESS | 2022 | 09:48:03 INFO - TEST-INFO | 2022: exit 0 09:48:03 INFO - TEST-OK | kraken | took 61678ms 09:48:03 INFO - SUITE-END | took 1178s 09:48:03 INFO - Completed test suite (00:19:39) 09:48:03 INFO - javascript benchmark 09:48:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7159.606361260204, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3406.0973841931886, 3414.6756058174465, 3431.1593659553414, 3445.4142795724724, 3460.031191266446, 4765.64257680674, 5909.320428150759, 5989.88020239595, 6001.5798894077325, 6006.57953943223, 4514.713306707589, 5279.049771041211, 5674.0, 5926.5258779297665, 6014.849628759283, 4524.981879077214, 5007.574356179711, 5364.195370694388, 5364.892702145955, 5398.649087809299, 5040.269160971657, 5310.973445132769, 5330.946690533084, 5405.297311349519, 5441.347038355403, 5222.608304377149, 5265.921011184835, 5286.656367336111, 5314.70769107697, 5369.838904832861, 5233.790648374061, 5267.683938963674, 5294.941011797656, 5312.017276803765, 5369.785208591652, 5205.0, 5231.24146998683, 5268.473152684739, 5321.813736519203, 5354.036273470773, 5769.798057067983, 5895.587308888379, 6002.309734380515, 6035.215421995113, 6057.273127224717, 6206.627602343874, 6235.812925612239, 6260.812175634738, 6302.338254483259, 6303.810885673437, 6355.713992870332, 6410.134631824737, 6418.133551970471, 6446.033095035782, 6477.514186436036, 5887.499562537147, 5907.320658124285, 6120.785772497941, 6122.326544080148, 6148.323684394713, 6381.8085457436355, 6394.040893865865, 6415.711292991827, 6432.035194720829, 6568.441682456949, 6241.063840423973, 6248.562600617913, 6257.7184026718905, 6258.655773932477, 6275.905861412082, 6261.906071408933, 6345.460635846005, 6362.681865906686, 6380.234371875405, 6382.712777925005, 6111.591738512148, 6133.610107582747, 6153.446189842935, 6222.066689996446, 6228.159198508234, 5526.944730552724, 6259.164314284866, 6303.905441418382, 6304.338044505353, 6318.399752023551, 4767.118083154627, 4983.352164218629, 5029.421616514074, 5037.0, 5056.241563765465, 4095.9385609215888, 4424.535423780521, 4470.463544374696, 4474.910501789946, 4477.9924516984065, 5964.7912323068485, 6122.428649698713, 6124.081387791778, 6151.323354431009, 6152.78465253714, 6237.875242495215, 6282.811515654537, 6333.113364129025, 6371.458426033746, 6404.135441715402, 5938.198343223696, 5968.791092311835, 5997.100434934708, 6044.0, 6087.847803804941, 5467.371252305956, 5506.889862202734, 5511.338639363302, 5518.310211223597, 5530.253415788898, 6296.590721603081, 6472.35276472349, 6475.935240647534, 6490.091387205889, 6494.6752662367635, 6168.814935551941, 6278.780242691484, 6301.117843502005, 6333.0, 6398.1362516059735, 4829.73436460998, 6458.095866578777, 6548.1814773153355, 6549.181352330958, 6566.277709451861, 4697.953020469751, 6057.727402266821, 6264.404881536247, 6288.025356069817, 6335.208098987627, 4968.3292755479, 5161.406438259648, 6166.0, 6208.099825525183, 6215.129881816459, 6154.969225154025, 6185.134081228542, 6185.907211391923, 6197.5351848610635, 6219.160413344141, 6108.992016317258, 6169.81490555284, 6183.84540386494, 6185.597936134137, 6220.440160385587, 5931.466168044896, 5977.880442391215, 6113.327533971348, 6134.417230363287, 6156.630602163885, 6131.621347939075, 6182.319944806158, 6210.4100110489435, 6231.2834024086, 6283.088952101828, 6144.692765361803, 6178.382161783786, 6178.969105154625, 6186.876262474815, 6199.039148931923], "value": 5766.4764507681375, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.7392946914462, 482.3464206000869, 492.1952607486758, 494.44375078037217, 507.42407367637765, 35825.2834943301, 36664.450033249486, 39300.60699393005, 39370.80314598423, 39516.80241598791, 39994.80002599983, 40334.39498407519, 40357.394639080434, 40429.797851010706, 40479.392809107885, 7882.290593846553, 8903.287736981058, 9009.95495022524, 9102.453852768822, 9204.033576474474, 8041.7185398511065, 9392.248620110375, 9412.62349506019, 9417.670381536647, 9425.0, 7949.284564389204, 9386.014468480813, 9424.81150376992, 9435.292353073513, 9477.38397004196, 9349.579268932881, 9413.635022921639, 9414.340996130239, 9416.435013899189, 9420.95289523548, 9286.95356523213, 9313.627454901796, 9344.67293644726, 9349.0, 9351.906480935171, 38731.41902871431, 38743.612563874565, 39034.219315613525, 39187.8040609798, 39204.411933821015, 3132.6554079051516, 3315.469524876032, 3559.7096549666744, 3685.649863262986, 3856.84572617095, 28081.157565273075, 28583.428331433464, 29909.252268693457, 30176.54735178974, 30323.090307290815, 3178.4596618574665, 3230.0632816483194, 3237.87048518059, 3241.286916878307, 3315.96684033159, 360642.1967890144, 395285.0235748832, 396954.0, 528998.0, 529855.0, 58923.41076589265, 58937.0, 59246.11130833041, 59617.0, 59643.70178149082, 54696.0, 58360.95736649282, 62314.75370492565, 62323.0, 62375.68812156001, 15477.690446191238, 15837.128957907194, 16019.199040047952, 16032.438864639915, 16074.276657550437, 12368.381580920917, 12386.56647017368, 12451.501939922302, 12477.75044499123, 12516.561920332744, 15780.210989450481, 16066.036037837766, 16188.838111618734, 16253.756193657104, 16297.348106075624, 3188.9202769930475, 5373.973130134403, 5760.251167348218, 5843.860447212753, 6392.936070639327, 767.7773445700684, 831.7421599304236, 878.9912100879037, 893.7799903132308, 900.7567956651667, 28152.85923570369, 29021.419571608873, 30028.54957175644, 30034.24914377158, 30284.394312113633, 997.7705127820497, 1034.7206254311293, 1090.51472094917, 1100.2023532938556, 1107.0368779162181, 12059.0, 15210.315535800915, 15656.608584785243, 15753.527394178192, 15803.20983950798, 579369.0, 686990.0, 694626.0, 696126.0, 697237.0, 56898.57753556111, 57353.27940161802, 57472.137917931264, 57600.0, 57656.71171644199, 1337.9063465557317, 1358.3615700620692, 1376.9173849569058, 1379.675776192594, 1386.1128877518597, 28129.87480500757, 29173.562396563644, 29861.850690746407, 29943.85028074846, 30484.390312194075, 49521.50478495169, 49933.00133997372, 49943.25085123653, 50061.99876002532, 50071.499285006685, 634791.0, 635732.0, 635871.8206408939, 636452.0, 636659.8167009135, 25837.96648134129, 26659.7334026665, 26984.46031079328, 27040.32399190036, 27096.593551096354, 46364.30453543132, 46897.53102468931, 47171.528284718086, 47281.527184729086, 47549.049019018734, 38212.808935955145, 38419.0, 38646.806765965994, 39699.80150099231, 39797.0, 38513.614863852126, 38603.80698096492, 38872.6112738869, 39362.60637393704, 39605.60394396019, 36387.81806090953, 37999.810000949816, 38033.23933521369, 38242.235155296185, 38522.22955540929, 26953.86523067372, 32653.34693306073, 33707.15732106619, 34271.82864085763, 34488.482672759426, 25452.0, 25595.488090238465, 25612.487750245262, 26014.0, 26271.343216419744], "value": 19332.290769230287, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.2226568346687, 363.492927366324, 369.24489419137853, 374.6422166830677, 389.8149625139576, 19151.946642934632, 19974.0, 20169.19323227071, 20193.19227230909, 20457.283995060156, 19819.702704459443, 19866.503337416572, 20121.29575464859, 20237.898810505925, 20609.587808243825, 4563.7489938053395, 5659.122835960412, 5855.0, 5883.0, 5965.284165900098, 5766.0, 5815.302163740336, 5891.086881533369, 5953.58324917256, 5956.374580669032, 5205.37535495741, 5613.747381367848, 5777.884442311151, 5794.913076303858, 5844.590878638497, 5770.480656740892, 5816.243888294537, 5837.708114594253, 5845.736941837585, 5852.063669812831, 4601.3558101865765, 4729.196036673739, 5018.64869459138, 5036.84889453317, 5099.413567439755, 24966.750332496624, 24997.750022499724, 25141.37146571332, 25341.61987570188, 25706.614400784005, 2222.0556263588073, 2321.558903808288, 2360.173939121311, 2417.4560723836994, 2454.239185852374, 12326.75346493065, 14829.220493540844, 14979.400823967027, 15415.768763468557, 15741.0, 1808.7739032620923, 1912.254220853868, 1916.2430839818333, 1918.7121931710356, 1925.0855843474378, 111188.99999999919, 113772.0, 116941.0, 117384.41307793492, 118573.814261858, 10558.947205263925, 11061.115110791117, 11092.279001864854, 11168.162387820783, 11314.0, 13553.932230338785, 13661.180329180082, 13682.179069255877, 13718.519851805138, 13748.93125534386, 6709.563878347892, 6956.756513522002, 8323.875141872877, 8490.363222758318, 8588.141185881486, 6565.343465653396, 8494.853194818621, 8500.787480313042, 8507.404481686222, 8648.308135349156, 5704.172894930211, 6484.513661475408, 6523.7064332105165, 6683.331666833278, 6709.597424154566, 1549.8372670869396, 1826.5707558723689, 1826.881252718569, 1832.4777438429983, 1836.1828986101325, 852.3266619370752, 878.3500209844669, 932.8041111366689, 960.9279304052224, 997.7455748784014, 19694.409167724993, 21143.0, 21726.34820955374, 22209.44476388071, 22236.666450003264, 945.2154711589365, 945.2957546627669, 963.1572374172599, 975.0639386189196, 981.4062492192121, 11259.155563332783, 11582.88417115835, 11588.072954163647, 11623.07015438763, 11640.650780476599, 557359.8528029417, 568458.0, 575545.0, 608904.0, 611274.0, 110491.44754276337, 113260.86739132668, 127967.36016320046, 138298.0, 148475.0, 839.9118092600311, 943.3820847345, 971.883373995111, 980.6567701304486, 1018.1396719771781, 18985.33551325697, 19432.4170274892, 19940.800591993895, 19954.70067949011, 19968.50078748043, 35988.64011359853, 42101.947451313965, 44181.77909110434, 53973.0, 54887.45112548823, 76902.61548692221, 78948.0, 79947.20052799398, 80457.0, 80716.59641701754, 8991.325650576102, 9239.39943903644, 9911.752206194902, 10722.89277107219, 12394.566190183301, 10221.28451008452, 13018.218906865619, 13736.65658358549, 13777.517786877412, 13882.305884705926, 11310.868913108952, 11450.370229637118, 11575.247608905394, 11591.0, 12106.51573937068, 11034.889651103385, 11469.0, 11543.595974140864, 11599.362035088152, 11671.88328116708, 11486.712832179262, 11743.706407339543, 11762.882371176178, 11905.285682859056, 11923.82114268304, 12331.75336493283, 12379.257244565355, 12389.690257743629, 12405.441755121221, 12487.625371238877, 8992.86510702353, 9755.317127800985, 11699.2980421175, 11944.044476442037, 12150.453229604866], "value": 9821.712686410068, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.5087768403375, 1460.379338781018, 1614.6044219166306, 1733.7226043832989, 1781.109445277362, 6527.967360163205, 7701.0, 7883.329916957058, 8305.501669899802, 8350.874736878945, 7816.491928024674, 8092.0, 8116.5941702914915, 8174.877376839352, 8187.0, 2215.7341119065654, 2282.7146606674164, 2298.5402919416183, 2307.2501437032947, 2307.284741730061, 2305.0, 2313.745487996319, 2329.801966832821, 2364.7044119485063, 2373.288013595923, 2267.8639281643073, 2275.6245219538837, 2280.0765689895534, 2305.0779688124717, 2321.419645088728, 1570.364002578946, 2293.1629955066446, 2306.96539551906, 2317.79139877411, 2390.3307074019303, 2062.9994452690435, 2204.889755512218, 2302.2747834432103, 2323.4888324568738, 2332.0788288626068, 8017.0, 8046.235607617267, 8151.75544733653, 8164.6325915333355, 8256.463329883538, 4963.553280204799, 5050.974745126287, 5106.08090543702, 5118.795248190068, 5124.846254612367, 3182.347618738153, 3277.5903012123485, 3488.8604455821737, 3493.6681015303507, 3517.0503963929596, 1012.8683271174774, 1047.7904419116207, 1049.9735510464875, 1063.2716589136467, 1078.95684172633, 8720.389572729911, 8873.378863479558, 10695.0, 10816.729581760519, 10944.781104377867, 4399.758013309299, 4505.504394516601, 4576.565226303496, 4636.327732478771, 4638.397008388888, 6792.72829086831, 8084.514929104273, 9100.908990910139, 9179.724608261762, 9214.401063930956, 4885.438174609965, 5104.106781313255, 5110.1568241239775, 5120.539151476385, 5123.205903085028, 4190.266703326905, 4488.977555112204, 4661.883452913704, 4697.177993851063, 4703.341532185496, 3746.38184699527, 3765.359888818907, 3825.483559719458, 3853.1523064925673, 3864.8454061837774, 1254.1221145198397, 1255.4601521345726, 1257.226805514602, 1264.2540900868532, 1265.3040827544999, 888.1739981816972, 888.8622263549161, 893.7363477774072, 897.3673560140086, 897.546738896853, 5359.946400536022, 5597.3003374578175, 5617.662940223599, 5689.146628005749, 5733.8566535836935, 422.06091446531224, 423.9173361194599, 424.4376201532981, 425.5149129991812, 430.29646527927065, 3770.434434834797, 3770.9434358484646, 3782.451544526083, 3863.9567316824864, 3930.4890364252465, 9573.18627916635, 9808.0, 9858.950705246429, 9930.404175749478, 9998.350107243006, 9648.890377606525, 9686.75783105428, 9889.307748457682, 9926.851097233548, 9927.15619172364, 7562.621868906533, 7676.961615191888, 7771.222877712184, 7771.572563508948, 7793.454458187985, 5269.2348063399195, 5346.385165705994, 5415.4313797050745, 5485.61600687948, 5485.6982865942755, 9038.954805225932, 9137.680181193626, 9181.954090229774, 9184.448933064037, 9222.677206297747, 8804.60379282921, 9056.0, 9077.818443631222, 9123.0, 9252.583633736633, 6493.285738568659, 6915.550489218184, 6999.230084690578, 7055.4708396871465, 7179.353858152791, 6421.967890160519, 7895.68417263303, 7898.723544675909, 7903.328217101381, 7916.564588947663, 7021.0, 7116.110486189226, 7151.7854464366155, 7156.677949492389, 7184.497085203986, 6836.555623884432, 6905.462708904964, 6991.2659170788365, 7003.439724822104, 7160.57036577807, 6599.406053455212, 6615.569987950767, 6623.370779775913, 6708.362705542965, 6721.697523611351, 4796.760161991956, 4806.903861922812, 4855.902881942271, 4870.853874383774, 4881.438634556929, 4779.0, 4812.326274321527, 4905.043516514245, 4950.554450099508, 4964.925526117184], "value": 4250.072337507534, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [258.14553826833185, 260.1959943773739, 263.55459273827233, 290.96944820793817, 294.93216560191166, 5255.316808814849, 5381.4349493303125, 5470.538257783998, 5502.2296878437, 5503.587230957679, 4557.293619488976, 5055.292259083732, 5103.795848166078, 5217.61733140717, 5255.369355677324, 3447.379991206676, 4039.4546736190678, 4081.8979525511877, 4116.176764647083, 4120.155368149537, 3993.8801835944823, 4013.3177359848896, 4128.834846606147, 4130.979345103286, 4146.087860670663, 3701.9264413320107, 4038.5961403859674, 4056.492938382702, 4058.208649313385, 4059.106996460774, 4051.9594804051876, 4069.3285607874664, 4074.653654439376, 4080.9387859182134, 4093.9385909211387, 3121.859516321771, 3326.467765157563, 3559.0, 3580.7135429165605, 3581.982090089559, 3961.7821019843896, 5019.262848518535, 5198.298229738974, 5221.71280579568, 5229.2156176573435, 4675.901163226639, 4677.4854765975715, 4681.250999840009, 4691.554302341272, 4709.2229782085915, 5145.6398052136365, 5206.505381988705, 5707.885842283173, 5725.799597014085, 5747.281589801275, 3576.8390422430793, 3813.599572044951, 3830.885073447801, 3855.9588910994157, 3943.6647884929816, 5051.343325367716, 5216.58267338612, 5948.7620495180145, 5979.461848433619, 6078.179445774852, 3238.9028329150165, 3649.543807024122, 3651.507046548735, 3907.199024200032, 3912.5304963404574, 3647.854085836537, 3656.2687462507606, 3720.311742327678, 3869.690424766012, 3927.116398810245, 3992.4210989406374, 4559.817607295738, 4569.040501494724, 4577.855536115971, 4604.470485894098, 5718.942810571841, 5737.426257374229, 5759.452851979056, 5789.392113828072, 5799.883042689388, 1215.7325388414536, 1237.294741997053, 1262.5517941130765, 1272.4083301264957, 1280.7630588341187, 841.2428814067266, 862.4825104937113, 865.4331412924545, 865.8701194820701, 871.8648609465542, 4367.77243605757, 4526.642007397768, 5111.309973153651, 5116.923246151311, 5154.329937108152, 5239.633225674243, 5547.084731019337, 5575.33096028471, 5585.553155747531, 5613.578981576398, 4272.269730759048, 4607.562281583244, 4827.975860120677, 4919.188333924933, 5160.40655324635, 2899.5505696617056, 2917.8249305041763, 2921.6932222116798, 2928.9267768305963, 2935.280856190247, 4976.452590215146, 5201.491567445474, 5370.3287089113865, 5636.351819540723, 5820.854478638068, 5120.974395128001, 5443.755031023614, 5653.745581448845, 5678.6655136043, 5763.538916886639, 5742.109972954198, 5747.252857128547, 5747.626404283708, 5754.03667111438, 5844.590878638454, 4235.597618226264, 5060.949390506048, 5415.945840541544, 5498.752556135064, 5554.888902221852, 4539.614132798617, 5088.720120393414, 5450.574494349976, 5456.116775485997, 5501.614886957875, 4816.182533218107, 5002.824901128443, 5208.687478751287, 5237.869053273698, 5835.095560188178, 3164.74992378014, 3240.254741409442, 3413.9829300853335, 3719.20037192005, 3724.6647801697977, 4487.259602165606, 4928.802847886188, 4969.801207951786, 5468.753906074316, 5519.531557022734, 5400.594955378284, 5473.945260547343, 5533.142362933751, 5630.296212973379, 5743.224664670216, 5087.262346959744, 5729.54163666914, 5739.684317362585, 5767.423257674367, 5773.740181691918, 4737.573618374363, 5105.795768169231, 5330.600204984564, 5404.514088412873, 5672.5461963042035, 5704.459795855013, 5724.914126288026, 5725.8568535786935, 5768.278965129359, 5772.56705747079, 4514.88712782183, 4662.603678687349, 5604.299462567179, 5608.663480191201, 5643.1535269709875], "value": 4016.2448408373075, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.9776707815228, 687.3538873458951, 691.2914262880547, 761.9847603047939, 774.6862520679124, 12524.686882827933, 13091.214527128379, 14069.0, 14614.830813534918, 14753.262336883166, 10454.849966503678, 12417.130800843945, 13270.469181232738, 13379.331033448338, 13388.464461421532, 627.1533429869685, 651.4397618048474, 768.7194174126431, 790.8774140008308, 795.0459448661601, 792.0772300270207, 792.3819420851717, 793.8491686579531, 794.2454668065332, 794.519315813933, 711.7189059692558, 820.4912953968548, 835.3275613131427, 837.0917554453425, 839.1985653700732, 679.8130514108635, 818.1654712193559, 830.2444775254489, 830.464350493928, 832.6045128563944, 613.6011592464934, 614.112607282477, 614.5483069943588, 617.3764497857152, 619.2074145094241, 13477.797833032513, 13625.795613065713, 13660.453581856715, 13750.0, 13781.862181378157, 12657.493700251978, 13607.0, 13619.727605447835, 13674.521391751341, 13805.309734513234, 11015.669529914116, 11046.392448415334, 11220.49507772144, 11470.942645286803, 11487.885121148765, 3142.3244002539564, 3643.872464463731, 3731.1045349116016, 3749.250149970017, 3831.3869780834934, 90252.0, 91826.5408672959, 94258.05741942562, 95199.52400237955, 97716.51141744317, 10194.745131371776, 10547.894521054845, 10548.419836909045, 10552.525136368882, 10561.260711750258, 14560.0, 20500.589988200452, 20561.869097199797, 20679.379618611467, 20781.58436831285, 7671.002769639911, 7678.0, 7686.884696729554, 7727.729529466442, 7826.256505631955, 4200.726952748061, 4310.3103503439925, 4325.351197320427, 4366.214081465303, 4394.406755088022, 3381.6956473917467, 3470.16715988161, 3485.320362529333, 3507.7018453431233, 3518.0852978225844, 567.0303780535331, 577.924869766936, 604.8729766748944, 633.4140919649305, 649.2825427902195, 647.7085311609794, 656.1830521001384, 663.4758540752752, 666.74330382802, 674.3155696967582, 9002.0, 9113.36206465541, 9184.908150918405, 9364.016778238323, 9554.04459554039, 591.7958304385066, 595.4402861310355, 630.7729217481703, 646.0857886091222, 652.846581053452, 4325.6106950374615, 4497.977510112429, 4528.705634133837, 4569.725816451024, 4581.404417425713, 109726.45136774424, 110866.0, 114186.0, 119243.80756192327, 122604.77395226112, 93306.68633627135, 97416.02583974211, 103078.0, 105304.47347763213, 105763.0, 73835.63082184555, 89295.55352223197, 90691.09308906959, 93881.0, 94797.0, 8816.559172041501, 8918.86621700662, 8929.151730585583, 9003.90996090057, 9013.704205567954, 45911.0, 49401.76495587639, 59905.70047149737, 59929.40070599238, 59931.31936495158, 40367.59632403756, 40532.79733601313, 81613.0, 81859.0, 82989.0, 11223.88776112261, 11498.310101393943, 11510.36692981894, 12347.69130771738, 12413.503459861866, 14004.929975350058, 14108.082974606617, 14254.216018119103, 14369.281535923372, 14472.0, 12841.743165136832, 13265.734685306434, 13309.401076951754, 13337.799933001206, 13719.451221951409, 12755.426005829946, 12975.09174357786, 13141.34293285351, 13334.533291334757, 13472.326383680973, 11451.942740286246, 12572.182808117443, 12682.302473364054, 12978.026648001249, 12984.805227921399, 8867.556622168995, 11098.389588572705, 11310.264832785842, 11608.593699220486, 11649.76700465969, 10958.506867190836, 11342.0, 11678.474468648761, 11692.298462092302, 11699.473523691624], "value": 7205.90752316167, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1584.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 119.0, 118.0, 118.0, 168.0, 118.0, 118.0, 118.0, 120.0, 117.0], "value": 122.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 151.0, 147.0, 194.0, 105.0, 149.0, 100.0, 101.0, 166.0, 145.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 199.0, 201.0, 202.0, 195.0, 197.0, 199.0, 217.0, 277.0, 210.0], "value": 209.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 65.0, 84.0, 110.0, 94.0, 59.0, 59.0, 62.0, 77.0, 51.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 72.0, 89.0, 72.0, 71.0, 71.0, 88.0, 83.0, 103.0, 90.0], "value": 83.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 90.0, 91.0, 93.0, 99.0, 90.0, 89.0, 133.0, 88.0], "value": 95.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 97.0, 97.0, 97.0, 98.0, 108.0, 153.0, 153.0, 96.0, 99.0], "value": 110.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 85.0, 87.0, 89.0, 89.0, 91.0, 91.0, 88.0, 87.0], "value": 88.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 56.0, 59.0, 61.0, 60.0, 60.0, 57.0, 58.0, 90.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 78.0, 73.0, 85.0, 79.0, 78.0, 71.0, 73.0, 73.0, 74.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 86.0, 88.0, 88.0, 100.0, 109.0, 89.0, 88.0, 91.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 154.0, 158.0, 165.0, 155.0, 169.0, 151.0, 174.0, 221.0, 176.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 225.0, 159.0, 161.0, 230.0, 177.0, 190.0, 160.0, 219.0, 234.0], "value": 192.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 93.0, 93.0, 68.0, 68.0, 70.0, 69.0, 70.0, 70.0, 67.0], "value": 72.8, "unit": "score"}]}]} 09:48:03 INFO - Return code: 0 09:48:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:48:03 ERROR - # TBPL SUCCESS # 09:48:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:48:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:48:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:48:03 INFO - 2016-12-05 09:48:03.599 screenresolution[2027:286633] starting screenresolution argv=screenresolution get 09:48:03 INFO - 2016-12-05 09:48:03.603 screenresolution[2027:286633] Display 0: 1600x1200x32@60 09:48:03 INFO - 2016-12-05 09:48:03.609 screenresolution[2028:286636] starting screenresolution argv=screenresolution list 09:48:03 INFO - Available Modes on Display 0 09:48:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:48:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:48:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:48:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:48:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:48:03 INFO - Intel Iris: 09:48:03 INFO - Chipset Model: Intel Iris 09:48:03 INFO - Type: GPU 09:48:03 INFO - Bus: Built-In 09:48:03 INFO - VRAM (Dynamic, Max): 1536 MB 09:48:03 INFO - Vendor: Intel (0x8086) 09:48:03 INFO - Device ID: 0x0a2e 09:48:03 INFO - Revision ID: 0x0009 09:48:03 INFO - Displays: 09:48:03 INFO - Display: 09:48:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:48:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:48:03 INFO - Display Serial Number: 1600x1200 60 09:48:03 INFO - Main Display: Yes 09:48:03 INFO - Mirror: Off 09:48:03 INFO - Online: Yes 09:48:03 INFO - Rotation: Supported 09:48:03 INFO - Return code: 0 09:48:03 INFO - Running post-action listener: _resource_record_post_action 09:48:03 INFO - [mozharness: 2016-12-05 17:48:03.853381Z] Finished run-tests step (success) 09:48:03 INFO - Running post-run listener: _resource_record_post_run 09:48:03 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 8623104; Write bytes: 663776768; Read time: 79; Write time: 2018 09:48:03 INFO - TinderboxPrint: CPU usage
54.4% 09:48:03 INFO - TinderboxPrint: I/O read bytes / time
8,623,104 / 79 09:48:03 INFO - TinderboxPrint: I/O write bytes / time
663,776,768 / 2,018 09:48:03 INFO - TinderboxPrint: CPU idle
2,212.0 (45.6%) 09:48:03 INFO - TinderboxPrint: CPU system
979.4 (20.2%) 09:48:03 INFO - TinderboxPrint: CPU user
1,654.5 (34.1%) 09:48:03 INFO - TinderboxPrint: Swap in / out
366,010,368 / 0 09:48:03 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 336013824; Write bytes: 333916160; Read time: 26223; Write time: 662 09:48:03 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 8619008; Write bytes: 312124928; Read time: 78; Write time: 1293 09:48:04 INFO - Running post-run listener: _upload_blobber_files 09:48:04 INFO - Blob upload gear active. 09:48:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:48:04 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 09:48:04 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'] 09:48:04 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 09:48:04 INFO - (blobuploader) - INFO - Open directory for files ... 09:48:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:48:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:48:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:06 INFO - (blobuploader) - INFO - Done attempting. 09:48:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:48:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:48:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:09 INFO - (blobuploader) - INFO - Done attempting. 09:48:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:48:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:48:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:48:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:48:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:48:12 INFO - (blobuploader) - INFO - Done attempting. 09:48:12 INFO - (blobuploader) - INFO - Iteration through files over. 09:48:12 INFO - Return code: 0 09:48:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:48:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:48:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4184cd5519d0432b0d68fb0c98f72804bba0d75393337d984129bcc052a1f268d69c014b8cad92c9415a5c0bdcd0f834c135acd539e82a32341e64ad059a83c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8271beb6f32f3135a76e05dc7ff1af3ee97427f3d4c7c6e0c1c1ffd8c4a6d619575aacaa435bde71ca3801c476598ac5aa9531d42dfdce6ffc981698f88af23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42c874be5c1cb307d2d77fb320f3f73b36f056fce6544f0ed8b77105e86bc1ed593b9e6522fa7c7a0304b176e175a703aa038837dc58225481230a929801e53f"} 09:48:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:48:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:48:12 INFO - Contents: 09:48:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4184cd5519d0432b0d68fb0c98f72804bba0d75393337d984129bcc052a1f268d69c014b8cad92c9415a5c0bdcd0f834c135acd539e82a32341e64ad059a83c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8271beb6f32f3135a76e05dc7ff1af3ee97427f3d4c7c6e0c1c1ffd8c4a6d619575aacaa435bde71ca3801c476598ac5aa9531d42dfdce6ffc981698f88af23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42c874be5c1cb307d2d77fb320f3f73b36f056fce6544f0ed8b77105e86bc1ed593b9e6522fa7c7a0304b176e175a703aa038837dc58225481230a929801e53f"} 09:48:12 INFO - Running post-run listener: copy_logs_to_upload_dir 09:48:12 INFO - Copying logs to upload dir... 09:48:12 INFO - mkdir: /builds/slave/test/build/upload/logs 09:48:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.426556 ========= master_lag: 1.38 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-12-05 09:48:13.677445) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-05 09:48:13.680570) ========= 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.ggrMNIFle4/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.vfgEMsPqYX/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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/4184cd5519d0432b0d68fb0c98f72804bba0d75393337d984129bcc052a1f268d69c014b8cad92c9415a5c0bdcd0f834c135acd539e82a32341e64ad059a83c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8271beb6f32f3135a76e05dc7ff1af3ee97427f3d4c7c6e0c1c1ffd8c4a6d619575aacaa435bde71ca3801c476598ac5aa9531d42dfdce6ffc981698f88af23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42c874be5c1cb307d2d77fb320f3f73b36f056fce6544f0ed8b77105e86bc1ed593b9e6522fa7c7a0304b176e175a703aa038837dc58225481230a929801e53f"} build_url:https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011138 build_url: 'https://queue.taskcluster.net/v1/task/Mq8hX-SxQAOuAfDUg5a-rw/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/4184cd5519d0432b0d68fb0c98f72804bba0d75393337d984129bcc052a1f268d69c014b8cad92c9415a5c0bdcd0f834c135acd539e82a32341e64ad059a83c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8271beb6f32f3135a76e05dc7ff1af3ee97427f3d4c7c6e0c1c1ffd8c4a6d619575aacaa435bde71ca3801c476598ac5aa9531d42dfdce6ffc981698f88af23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/42c874be5c1cb307d2d77fb320f3f73b36f056fce6544f0ed8b77105e86bc1ed593b9e6522fa7c7a0304b176e175a703aa038837dc58225481230a929801e53f"}' ========= master_lag: 0.13 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-05 09:48:13.824578) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:48:13.824902) ========= 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.ggrMNIFle4/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.vfgEMsPqYX/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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.005298 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:48:13.851595) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-05 09:48:13.851922) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-05 09:48:13.852241) ========= ========= Total master_lag: 1.61 =========