builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0121 starttime: 1490632586.28 results: success (0) buildid: 20170327075316 builduid: a027e70c3f4840a6910f05d66abdd467 revision: 6f684ac21eaeb4269bd650783deef431b2f114bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:26.285259) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:26.285786) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:26.286115) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:26.312818) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:26.313292) ========= 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.CvS0BccVY9/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.8JsgSBBHlE/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-27 09:36:26-- 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% 8.86M=0.001s 2017-03-27 09:36:26 (8.86 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124863 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:26.462471) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:26.462817) ========= 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.CvS0BccVY9/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.8JsgSBBHlE/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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.029036 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:26.513552) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-27 09:36:26.513941) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6f684ac21eaeb4269bd650783deef431b2f114bc --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 6f684ac21eaeb4269bd650783deef431b2f114bc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CvS0BccVY9/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.8JsgSBBHlE/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-27 09:36:26,616 truncating revision to first 12 chars 2017-03-27 09:36:26,616 Setting DEBUG logging. 2017-03-27 09:36:26,616 attempt 1/10 2017-03-27 09:36:26,616 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6f684ac21eae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 09:36:28,896 unpacking tar archive at: mozilla-aurora-6f684ac21eae/testing/mozharness/ program finished with exit code 0 elapsedTime=2.543319 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-27 09:36:29.080992) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:29.081327) ========= script_repo_revision: 6f684ac21eaeb4269bd650783deef431b2f114bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:29.081768) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:29.082047) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:29.101992) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-27 09:36:29.102300) ========= /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.CvS0BccVY9/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.8JsgSBBHlE/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:36:29 INFO - MultiFileLogger online at 20170327 09:36:29 in /builds/slave/test 09:36:29 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:36:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:36:29 INFO - {'append_to_log': False, 09:36:29 INFO - 'base_work_dir': '/builds/slave/test', 09:36:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:36:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:36:29 INFO - 'branch': 'Mozilla-Aurora', 09:36:29 INFO - 'buildbot_json_path': 'buildprops.json', 09:36:29 INFO - 'config_files': ('talos/mac_config.py',), 09:36:29 INFO - 'default_actions': ('clobber', 09:36:29 INFO - 'read-buildbot-config', 09:36:29 INFO - 'download-and-extract', 09:36:29 INFO - 'populate-webroot', 09:36:29 INFO - 'create-virtualenv', 09:36:29 INFO - 'install', 09:36:29 INFO - 'run-tests'), 09:36:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:36:29 INFO - 'download_minidump_stackwalk': True, 09:36:29 INFO - 'download_symbols': 'ondemand', 09:36:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:36:29 INFO - 'tooltool.py': '/tools/tooltool.py', 09:36:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:36:29 INFO - '/tools/misc-python/virtualenv.py')}, 09:36:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:36:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:36:29 INFO - 'gecko_profile': False, 09:36:29 INFO - 'gecko_profile_interval': 0, 09:36:29 INFO - 'installer_path': 'installer.exe', 09:36:29 INFO - 'log_level': 'info', 09:36:29 INFO - 'log_name': 'talos', 09:36:29 INFO - 'log_to_console': True, 09:36:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:36:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:36:29 INFO - 'opt_config_files': (), 09:36:29 INFO - 'pip_index': False, 09:36:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:29 INFO - 'cmd': ('bash', 09:36:29 INFO - '-c', 09:36:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:29 INFO - 'enabled': True, 09:36:29 INFO - 'halt_on_failure': False, 09:36:29 INFO - 'name': 'check_screen_resolution'},), 09:36:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:29 INFO - 'cmd': ('bash', 09:36:29 INFO - '-c', 09:36:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:29 INFO - 'enabled': True, 09:36:29 INFO - 'halt_on_failure': False, 09:36:29 INFO - 'name': 'check_screen_resolution'},), 09:36:29 INFO - 'run_cmd_checks_enabled': True, 09:36:29 INFO - 'suite': 'dromaeojs-e10s', 09:36:29 INFO - 'system_bits': '32', 09:36:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:36:29 INFO - 'title': 't-yosemite-r7-0121', 09:36:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:36:29 INFO - 'use_talos_json': True, 09:36:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:36:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:36:29 INFO - 'work_dir': 'build'} 09:36:29 INFO - [mozharness: 2017-03-27 16:36:29.318853Z] Running clobber step. 09:36:29 INFO - Running pre-action listener: _resource_record_pre_action 09:36:29 INFO - Running main action method: clobber 09:36:29 INFO - rmtree: /builds/slave/test/build 09:36:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:36:31 INFO - Running post-action listener: _resource_record_post_action 09:36:31 INFO - [mozharness: 2017-03-27 16:36:31.886146Z] Finished clobber step (success) 09:36:31 INFO - [mozharness: 2017-03-27 16:36:31.886348Z] Running read-buildbot-config step. 09:36:31 INFO - Running pre-action listener: _resource_record_pre_action 09:36:31 INFO - Running main action method: read_buildbot_config 09:36:31 INFO - Using buildbot properties: 09:36:31 INFO - { 09:36:31 INFO - "project": "", 09:36:31 INFO - "product": "firefox", 09:36:31 INFO - "script_repo_revision": "production", 09:36:31 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:36:31 INFO - "repository": "", 09:36:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:36:31 INFO - "buildid": "20170327075316", 09:36:31 INFO - "pgo_build": "False", 09:36:31 INFO - "basedir": "/builds/slave/test", 09:36:31 INFO - "buildnumber": 32, 09:36:31 INFO - "slavename": "t-yosemite-r7-0121", 09:36:31 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:36:31 INFO - "platform": "yosemite_r7", 09:36:31 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:36:31 INFO - "branch": "mozilla-aurora", 09:36:31 INFO - "repo_path": "releases/mozilla-aurora", 09:36:31 INFO - "revision": "6f684ac21eaeb4269bd650783deef431b2f114bc", 09:36:31 INFO - "stage_platform": "macosx64", 09:36:31 INFO - "builduid": "a027e70c3f4840a6910f05d66abdd467", 09:36:31 INFO - "slavebuilddir": "test" 09:36:31 INFO - } 09:36:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 09:36:31 INFO - Running post-action listener: _resource_record_post_action 09:36:31 INFO - [mozharness: 2017-03-27 16:36:31.890418Z] Finished read-buildbot-config step (success) 09:36:31 INFO - [mozharness: 2017-03-27 16:36:31.890530Z] Running download-and-extract step. 09:36:31 INFO - Running pre-action listener: _resource_record_pre_action 09:36:31 INFO - Running main action method: download_and_extract 09:36:31 INFO - mkdir: /builds/slave/test/build/tests 09:36:31 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:36:31 INFO - trying https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 09:36:31 INFO - Downloading https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:36:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 09:36:32 INFO - Downloaded 1199 bytes. 09:36:32 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:36:32 INFO - Using the following test package requirements: 09:36:32 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:36:32 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:32 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 09:36:32 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:32 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 09:36:32 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:32 INFO - u'jsshell-mac.zip'], 09:36:32 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:32 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 09:36:32 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:36:32 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:32 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 09:36:32 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:32 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 09:36:32 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:32 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 09:36:32 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:32 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 09:36:32 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:36:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:36:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:36:32 INFO - Fetch https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:36:34 INFO - Expected file size: 51787403 09:36:34 INFO - Obtained file size: 51787403 09:36:50 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:36:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:36:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:36:50 INFO - Fetch https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:36:51 INFO - Expected file size: 51787403 09:36:51 INFO - Obtained file size: 51787403 09:37:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 09:37:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:37:06 INFO - Fetch https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 09:37:07 INFO - Expected file size: 14404438 09:37:07 INFO - Obtained file size: 14404438 09:37:07 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:37:07 INFO - trying https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:37:07 INFO - Downloading https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 09:37:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 09:37:09 INFO - Downloaded 74670028 bytes. 09:37:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:37:09 INFO - mkdir: /builds/slave/test/properties 09:37:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:37:09 INFO - Writing to file /builds/slave/test/properties/build_url 09:37:09 INFO - Contents: 09:37:09 INFO - build_url:https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:37:10 INFO - Running post-action listener: _resource_record_post_action 09:37:10 INFO - Running post-action listener: set_extra_try_arguments 09:37:10 INFO - [mozharness: 2017-03-27 16:37:10.513707Z] Finished download-and-extract step (success) 09:37:10 INFO - [mozharness: 2017-03-27 16:37:10.513836Z] Running populate-webroot step. 09:37:10 INFO - Running pre-action listener: _resource_record_pre_action 09:37:10 INFO - Running main action method: populate_webroot 09:37:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:37:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:37:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:37:10 INFO - '--tppagecycles', 09:37:10 INFO - '7'], 09:37:10 INFO - 'tests': ['tsvgm']}}, 09:37:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:37:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:37:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:37:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:37:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:37:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:37:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:37:10 INFO - 'talos_options': ['--disable-e10s'], 09:37:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:37:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:37:10 INFO - 'talos_options': ['--disable-e10s'], 09:37:10 INFO - 'tests': ['damp', 'tps']}, 09:37:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:37:10 INFO - 'tests': ['damp', 'tps']}, 09:37:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:37:10 INFO - 'tests': ['dromaeo_dom']}, 09:37:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:37:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:37:10 INFO - 'tests': ['basic_compositor_video']}, 09:37:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:37:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:37:10 INFO - 'tests': ['a11yr', 09:37:10 INFO - 'ts_paint', 09:37:10 INFO - 'tpaint', 09:37:10 INFO - 'sessionrestore', 09:37:10 INFO - 'sessionrestore_no_auto_restore', 09:37:10 INFO - 'tabpaint']}, 09:37:10 INFO - 'other-e10s': {'tests': ['a11yr', 09:37:10 INFO - 'ts_paint', 09:37:10 INFO - 'tpaint', 09:37:10 INFO - 'sessionrestore', 09:37:10 INFO - 'sessionrestore_no_auto_restore', 09:37:10 INFO - 'tabpaint']}, 09:37:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:37:10 INFO - 'ts_paint', 09:37:10 INFO - 'tpaint', 09:37:10 INFO - 'sessionrestore', 09:37:10 INFO - 'sessionrestore_no_auto_restore', 09:37:10 INFO - 'tabpaint']}, 09:37:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:37:10 INFO - 'ts_paint', 09:37:10 INFO - 'tpaint', 09:37:10 INFO - 'sessionrestore', 09:37:10 INFO - 'sessionrestore_no_auto_restore', 09:37:10 INFO - 'tabpaint']}, 09:37:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:37:10 INFO - 'tests': ['a11yr', 09:37:10 INFO - 'ts_paint', 09:37:10 INFO - 'tpaint', 09:37:10 INFO - 'sessionrestore', 09:37:10 INFO - 'sessionrestore_no_auto_restore', 09:37:10 INFO - 'tabpaint']}, 09:37:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:37:10 INFO - 'tests': ['a11yr', 09:37:10 INFO - 'ts_paint', 09:37:10 INFO - 'tpaint', 09:37:10 INFO - 'sessionrestore', 09:37:10 INFO - 'sessionrestore_no_auto_restore', 09:37:10 INFO - 'tabpaint']}, 09:37:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:37:10 INFO - 'tests': ['tsvgx', 09:37:10 INFO - 'tsvgr_opacity', 09:37:10 INFO - 'tart', 09:37:10 INFO - 'tscrollx', 09:37:10 INFO - 'cart', 09:37:10 INFO - 'tsvg_static']}, 09:37:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:37:10 INFO - 'tsvgr_opacity', 09:37:10 INFO - 'tart', 09:37:10 INFO - 'tscrollx', 09:37:10 INFO - 'cart', 09:37:10 INFO - 'tsvg_static']}, 09:37:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:37:10 INFO - 'talos_options': ['--disable-e10s'], 09:37:10 INFO - 'tests': ['tp5o']}, 09:37:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:37:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:37:10 INFO - 'talos_options': ['--disable-e10s', 09:37:10 INFO - '--xperf_path', 09:37:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:10 INFO - 'tests': ['tp5n']}, 09:37:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:37:10 INFO - 'talos_options': ['--xperf_path', 09:37:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:10 INFO - 'tests': ['tp5n']}}, 09:37:10 INFO - 'talos.zip': {'path': '', 09:37:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:37:10 INFO - Running post-action listener: _resource_record_post_action 09:37:10 INFO - [mozharness: 2017-03-27 16:37:10.532318Z] Finished populate-webroot step (success) 09:37:10 INFO - [mozharness: 2017-03-27 16:37:10.532452Z] Running create-virtualenv step. 09:37:10 INFO - Running pre-action listener: _resource_record_pre_action 09:37:10 INFO - Running main action method: create_virtualenv 09:37:10 INFO - Creating virtualenv /builds/slave/test/build/venv 09:37:10 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:37:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:37:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:37:10 INFO - Using real prefix '/tools/python27' 09:37:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:37:11 INFO - Installing distribute.............................................................................................................................................................................................done. 09:37:14 INFO - Installing pip.................done. 09:37:14 INFO - Return code: 0 09:37:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:37:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:37:14 INFO - Reading from file tmpfile_stdout 09:37:14 INFO - Output received: 09:37:14 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:37:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10419db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395a25b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bd30>, '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 0x103ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f70be8>, '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.CvS0BccVY9/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.8JsgSBBHlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:37:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:37:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:37:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CvS0BccVY9/Render', 09:37:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:14 INFO - 'HOME': '/Users/cltbld', 09:37:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:14 INFO - 'LOGNAME': 'cltbld', 09:37:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:14 INFO - 'MOZ_NO_REMOTE': '1', 09:37:14 INFO - 'NO_EM_RESTART': '1', 09:37:14 INFO - 'PAGER': '/bin/cat', 09:37:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:14 INFO - 'PWD': '/builds/slave/test', 09:37:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:14 INFO - 'SHELL': '/bin/bash', 09:37:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JsgSBBHlE/Listeners', 09:37:14 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:37:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:14 INFO - 'USER': 'cltbld', 09:37:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:14 INFO - 'XPC_FLAGS': '0x0', 09:37:14 INFO - 'XPC_SERVICE_NAME': '0', 09:37:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:14 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:37:14 INFO - Cleaning up... 09:37:14 INFO - Return code: 0 09:37:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10419db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395a25b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bd30>, '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 0x103ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f70be8>, '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.CvS0BccVY9/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.8JsgSBBHlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:37:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:37:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:37:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CvS0BccVY9/Render', 09:37:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:14 INFO - 'HOME': '/Users/cltbld', 09:37:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:14 INFO - 'LOGNAME': 'cltbld', 09:37:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:14 INFO - 'MOZ_NO_REMOTE': '1', 09:37:14 INFO - 'NO_EM_RESTART': '1', 09:37:14 INFO - 'PAGER': '/bin/cat', 09:37:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:14 INFO - 'PWD': '/builds/slave/test', 09:37:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:14 INFO - 'SHELL': '/bin/bash', 09:37:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JsgSBBHlE/Listeners', 09:37:14 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:37:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:14 INFO - 'USER': 'cltbld', 09:37:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:14 INFO - 'XPC_FLAGS': '0x0', 09:37:14 INFO - 'XPC_SERVICE_NAME': '0', 09:37:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:15 INFO - Downloading/unpacking psutil>=3.1.1 09:37:15 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:37:15 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:37:15 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:37:15 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:37:15 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:37:15 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:37:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:37:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:18 INFO - Installing collected packages: psutil 09:37:18 INFO - Running setup.py install for psutil 09:37:18 INFO - building 'psutil._psutil_osx' extension 09:37:18 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:37:19 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:37:19 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:37:19 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:37:19 INFO - building 'psutil._psutil_posix' extension 09:37:19 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:37:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:37:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:37:19 INFO - ^ 09:37:19 INFO - 1 warning generated. 09:37:19 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:37:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:19 INFO - Successfully installed psutil 09:37:19 INFO - Cleaning up... 09:37:19 INFO - Return code: 0 09:37:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:37:19 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:37:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:19 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:37:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:19 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 0x10419db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395a25b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bd30>, '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 0x103ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f70be8>, '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.CvS0BccVY9/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.8JsgSBBHlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:37:19 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:37:19 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:37:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CvS0BccVY9/Render', 09:37:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:19 INFO - 'HOME': '/Users/cltbld', 09:37:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:19 INFO - 'LOGNAME': 'cltbld', 09:37:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:19 INFO - 'MOZ_NO_REMOTE': '1', 09:37:19 INFO - 'NO_EM_RESTART': '1', 09:37:19 INFO - 'PAGER': '/bin/cat', 09:37:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:19 INFO - 'PWD': '/builds/slave/test', 09:37:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:19 INFO - 'SHELL': '/bin/bash', 09:37:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JsgSBBHlE/Listeners', 09:37:19 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:37:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:19 INFO - 'USER': 'cltbld', 09:37:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:19 INFO - 'XPC_FLAGS': '0x0', 09:37:19 INFO - 'XPC_SERVICE_NAME': '0', 09:37:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:37:20 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:37:20 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:37:20 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:37:20 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:37:20 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:37:20 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:37:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:37:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:37:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:37:22 INFO - Installing collected packages: mozsystemmonitor 09:37:22 INFO - Running setup.py install for mozsystemmonitor 09:37:22 INFO - Successfully installed mozsystemmonitor 09:37:22 INFO - Cleaning up... 09:37:23 INFO - Return code: 0 09:37:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:37: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:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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', '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 0x10419db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395a25b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bd30>, '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 0x103ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f70be8>, '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.CvS0BccVY9/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.8JsgSBBHlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:37: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:37: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 jsonschema==2.5.1 09:37:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CvS0BccVY9/Render', 09:37:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:23 INFO - 'HOME': '/Users/cltbld', 09:37:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:23 INFO - 'LOGNAME': 'cltbld', 09:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:23 INFO - 'MOZ_NO_REMOTE': '1', 09:37:23 INFO - 'NO_EM_RESTART': '1', 09:37:23 INFO - 'PAGER': '/bin/cat', 09:37:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:23 INFO - 'PWD': '/builds/slave/test', 09:37:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:23 INFO - 'SHELL': '/bin/bash', 09:37:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JsgSBBHlE/Listeners', 09:37:23 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:37:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:23 INFO - 'USER': 'cltbld', 09:37:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:23 INFO - 'XPC_FLAGS': '0x0', 09:37:23 INFO - 'XPC_SERVICE_NAME': '0', 09:37:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:24 INFO - Downloading/unpacking jsonschema==2.5.1 09:37: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:37: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:37: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:37: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:37: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:37: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:37:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:37:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:37:27 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:37:27 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:37:27 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:37:27 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:37:27 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:37:27 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:37:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:37:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:37:27 INFO - warning: no files found matching '*.txt' 09:37:27 INFO - no previously-included directories found matching 'build' 09:37:27 INFO - no previously-included directories found matching 'dist' 09:37:27 INFO - no previously-included directories found matching '.git*' 09:37:27 INFO - Installing collected packages: jsonschema, functools32 09:37:27 INFO - Running setup.py install for functools32 09:37:27 INFO - warning: no files found matching '*.txt' 09:37:27 INFO - no previously-included directories found matching 'build' 09:37:27 INFO - no previously-included directories found matching 'dist' 09:37:27 INFO - no previously-included directories found matching '.git*' 09:37:27 INFO - Successfully installed jsonschema functools32 09:37:27 INFO - Cleaning up... 09:37:28 INFO - Return code: 0 09:37:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:28 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 0x10419db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395a25b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bd30>, '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 0x103ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f70be8>, '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.CvS0BccVY9/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.8JsgSBBHlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:37:28 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:37:28 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:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CvS0BccVY9/Render', 09:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:28 INFO - 'HOME': '/Users/cltbld', 09:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:28 INFO - 'LOGNAME': 'cltbld', 09:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:28 INFO - 'MOZ_NO_REMOTE': '1', 09:37:28 INFO - 'NO_EM_RESTART': '1', 09:37:28 INFO - 'PAGER': '/bin/cat', 09:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:28 INFO - 'PWD': '/builds/slave/test', 09:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:28 INFO - 'SHELL': '/bin/bash', 09:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JsgSBBHlE/Listeners', 09:37:28 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:28 INFO - 'USER': 'cltbld', 09:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:28 INFO - 'XPC_FLAGS': '0x0', 09:37:28 INFO - 'XPC_SERVICE_NAME': '0', 09:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:37:28 INFO - Cleaning up... 09:37:28 INFO - Return code: 0 09:37:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:28 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 0x10419db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395a25b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bd30>, '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 0x103ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f70be8>, '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.CvS0BccVY9/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.8JsgSBBHlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:37:28 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:37:28 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:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CvS0BccVY9/Render', 09:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:28 INFO - 'HOME': '/Users/cltbld', 09:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:28 INFO - 'LOGNAME': 'cltbld', 09:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:28 INFO - 'MOZ_NO_REMOTE': '1', 09:37:28 INFO - 'NO_EM_RESTART': '1', 09:37:28 INFO - 'PAGER': '/bin/cat', 09:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:28 INFO - 'PWD': '/builds/slave/test', 09:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:28 INFO - 'SHELL': '/bin/bash', 09:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JsgSBBHlE/Listeners', 09:37:28 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:28 INFO - 'USER': 'cltbld', 09:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:28 INFO - 'XPC_FLAGS': '0x0', 09:37:28 INFO - 'XPC_SERVICE_NAME': '0', 09:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:37:28 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:37:28 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:37:28 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:37:28 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:37:28 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:37:28 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:37:31 INFO - Downloading blobuploader-1.2.4.tar.gz 09:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:37:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:37:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:32 INFO - Downloading docopt-0.6.1.tar.gz 09:37:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:37:32 INFO - Installing collected packages: blobuploader, requests, docopt 09:37:32 INFO - Running setup.py install for blobuploader 09:37:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:37:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:37:32 INFO - Running setup.py install for requests 09:37:33 INFO - Running setup.py install for docopt 09:37:33 INFO - Successfully installed blobuploader requests docopt 09:37:33 INFO - Cleaning up... 09:37:33 INFO - Return code: 0 09:37:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:33 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 0x10419db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395a25b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bd30>, '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 0x103ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f70be8>, '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.CvS0BccVY9/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.8JsgSBBHlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:37:33 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:37:33 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:37:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CvS0BccVY9/Render', 09:37:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:33 INFO - 'HOME': '/Users/cltbld', 09:37:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:33 INFO - 'LOGNAME': 'cltbld', 09:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:33 INFO - 'MOZ_NO_REMOTE': '1', 09:37:33 INFO - 'NO_EM_RESTART': '1', 09:37:33 INFO - 'PAGER': '/bin/cat', 09:37:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:33 INFO - 'PWD': '/builds/slave/test', 09:37:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:33 INFO - 'SHELL': '/bin/bash', 09:37:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JsgSBBHlE/Listeners', 09:37:33 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:37:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:33 INFO - 'USER': 'cltbld', 09:37:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:33 INFO - 'XPC_FLAGS': '0x0', 09:37:33 INFO - 'XPC_SERVICE_NAME': '0', 09:37:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:33 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-KXs3Ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:33 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-3yfmX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:33 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-pSizij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-mUU9_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-YwcfY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-ueu33X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-fbrzNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-3vg2Yz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-3qfnH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-gpVsFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-h9lW64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-G3D_Ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-1LRS_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:35 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-3agkJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:35 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-05aJgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:35 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-hSUx4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:35 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-KAOihr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:37:35 INFO - Running setup.py install for manifestparser 09:37:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:37:35 INFO - Running setup.py install for mozcrash 09:37:35 INFO - Running setup.py install for mozdebug 09:37:35 INFO - Running setup.py install for mozdevice 09:37:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:37:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:37:36 INFO - Running setup.py install for mozfile 09:37:36 INFO - Running setup.py install for mozhttpd 09:37:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:37:36 INFO - Running setup.py install for mozinfo 09:37:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:37:36 INFO - Running setup.py install for mozInstall 09:37:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:37:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:37:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:37:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:37:36 INFO - Running setup.py install for mozleak 09:37:36 INFO - Running setup.py install for mozlog 09:37:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:37:36 INFO - Running setup.py install for moznetwork 09:37:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:37:37 INFO - Running setup.py install for mozprocess 09:37:37 INFO - Running setup.py install for mozprofile 09:37:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:37:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:37:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:37:37 INFO - Running setup.py install for mozrunner 09:37:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:37:37 INFO - Running setup.py install for mozscreenshot 09:37:37 INFO - Running setup.py install for moztest 09:37:37 INFO - Running setup.py install for mozversion 09:37:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:37:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:37:38 INFO - Cleaning up... 09:37:38 INFO - Return code: 0 09:37:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:38 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:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:38 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:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:38 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 0x10419db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395a25b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bd30>, '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 0x103ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f70be8>, '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.CvS0BccVY9/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.8JsgSBBHlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:37:38 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:37:38 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:37:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CvS0BccVY9/Render', 09:37:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:38 INFO - 'HOME': '/Users/cltbld', 09:37:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:38 INFO - 'LOGNAME': 'cltbld', 09:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:38 INFO - 'MOZ_NO_REMOTE': '1', 09:37:38 INFO - 'NO_EM_RESTART': '1', 09:37:38 INFO - 'PAGER': '/bin/cat', 09:37:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:38 INFO - 'PWD': '/builds/slave/test', 09:37:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:38 INFO - 'SHELL': '/bin/bash', 09:37:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JsgSBBHlE/Listeners', 09:37:38 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:37:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:38 INFO - 'USER': 'cltbld', 09:37:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:38 INFO - 'XPC_FLAGS': '0x0', 09:37:38 INFO - 'XPC_SERVICE_NAME': '0', 09:37:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-9L79ul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:38 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:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-gZs2Av-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:38 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:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-KaG6Ts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:38 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:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-pssal3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-DR1Rp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:38 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:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Rkw7nO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:38 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:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Mr_uwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:39 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:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:39 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-GmuKex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:39 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:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:39 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Vx7vRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:39 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:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:39 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-vVkRJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:39 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:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:39 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-AXkRuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:39 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:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:39 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-6xcWGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:39 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-9zCwJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:39 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:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:39 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-8H8OZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:39 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:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:39 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-LSdb88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:39 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:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:39 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-3hgI3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:40 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-O86Zqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:40 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:37:40 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:37:40 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:37:40 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:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:37:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:37:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:37:40 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:37:40 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:37:40 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:37:40 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:37:40 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:37:40 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:37:42 INFO - Downloading blessings-1.6.tar.gz 09:37:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:37:42 INFO - Installing collected packages: blessings 09:37:42 INFO - Running setup.py install for blessings 09:37:43 INFO - Successfully installed blessings 09:37:43 INFO - Cleaning up... 09:37:43 INFO - Return code: 0 09:37:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:37:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:43 INFO - Reading from file tmpfile_stdout 09:37:43 INFO - Current package versions: 09:37:43 INFO - blessings == 1.6 09:37:43 INFO - blobuploader == 1.2.4 09:37:43 INFO - docopt == 0.6.1 09:37:43 INFO - functools32 == 3.2.3-2 09:37:43 INFO - jsonschema == 2.5.1 09:37:43 INFO - manifestparser == 1.1 09:37:43 INFO - mozInstall == 1.12 09:37:43 INFO - mozcrash == 1.0 09:37:43 INFO - mozdebug == 0.1 09:37:43 INFO - mozdevice == 0.49 09:37:43 INFO - mozfile == 1.2 09:37:43 INFO - mozhttpd == 0.7 09:37:43 INFO - mozinfo == 0.9 09:37:43 INFO - mozleak == 0.1 09:37:43 INFO - mozlog == 3.4 09:37:43 INFO - moznetwork == 0.27 09:37:43 INFO - mozprocess == 0.25 09:37:43 INFO - mozprofile == 0.28 09:37:43 INFO - mozrunner == 6.13 09:37:43 INFO - mozscreenshot == 0.1 09:37:43 INFO - mozsystemmonitor == 0.3 09:37:43 INFO - moztest == 0.8 09:37:43 INFO - mozversion == 1.4 09:37:43 INFO - psutil == 3.1.1 09:37:43 INFO - requests == 1.2.3 09:37:43 INFO - wsgiref == 0.1.2 09:37:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:43 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:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:43 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:37:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:43 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 0x10419db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395a25b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bd30>, '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 0x103ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f70be8>, '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.CvS0BccVY9/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.8JsgSBBHlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:37:43 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:37:43 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:37:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CvS0BccVY9/Render', 09:37:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:43 INFO - 'HOME': '/Users/cltbld', 09:37:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:43 INFO - 'LOGNAME': 'cltbld', 09:37:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:43 INFO - 'MOZ_NO_REMOTE': '1', 09:37:43 INFO - 'NO_EM_RESTART': '1', 09:37:43 INFO - 'PAGER': '/bin/cat', 09:37:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:43 INFO - 'PWD': '/builds/slave/test', 09:37:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:43 INFO - 'SHELL': '/bin/bash', 09:37:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JsgSBBHlE/Listeners', 09:37:43 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:37:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:43 INFO - 'USER': 'cltbld', 09:37:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:43 INFO - 'XPC_FLAGS': '0x0', 09:37:43 INFO - 'XPC_SERVICE_NAME': '0', 09:37:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:43 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:37:43 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:37:43 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:37:43 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:37:43 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:37:43 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:37:43 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:37:43 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:37:43 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:37:43 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:37:43 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:37:43 INFO - Cleaning up... 09:37:43 INFO - Return code: 0 09:37:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:37:43 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:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:43 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:37:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:43 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 0x10419db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395a25b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f7bd30>, '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 0x103ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f70be8>, '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.CvS0BccVY9/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.8JsgSBBHlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:37:43 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:37:43 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:37:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CvS0BccVY9/Render', 09:37:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:43 INFO - 'HOME': '/Users/cltbld', 09:37:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:43 INFO - 'LOGNAME': 'cltbld', 09:37:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:43 INFO - 'MOZ_NO_REMOTE': '1', 09:37:43 INFO - 'NO_EM_RESTART': '1', 09:37:43 INFO - 'PAGER': '/bin/cat', 09:37:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:43 INFO - 'PWD': '/builds/slave/test', 09:37:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:43 INFO - 'SHELL': '/bin/bash', 09:37:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JsgSBBHlE/Listeners', 09:37:43 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:37:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:43 INFO - 'USER': 'cltbld', 09:37:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:43 INFO - 'XPC_FLAGS': '0x0', 09:37:43 INFO - 'XPC_SERVICE_NAME': '0', 09:37:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:37:43 INFO - Cleaning up... 09:37:43 INFO - Return code: 0 09:37:43 INFO - Running post-action listener: _resource_record_post_action 09:37:43 INFO - Running post-action listener: _start_resource_monitoring 09:37:44 INFO - Starting resource monitoring. 09:37:44 INFO - [mozharness: 2017-03-27 16:37:44.020614Z] Finished create-virtualenv step (success) 09:37:44 INFO - [mozharness: 2017-03-27 16:37:44.021570Z] Running install step. 09:37:44 INFO - Running pre-action listener: _resource_record_pre_action 09:37:44 INFO - Running main action method: install 09:37:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:44 INFO - Reading from file tmpfile_stdout 09:37:44 INFO - Detecting whether we're running mozinstall >=1.0... 09:37:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:37:44 INFO - Reading from file tmpfile_stdout 09:37:44 INFO - Output received: 09:37:44 INFO - Usage: mozinstall [options] installer 09:37:44 INFO - Options: 09:37:44 INFO - -h, --help show this help message and exit 09:37:44 INFO - -d DEST, --destination=DEST 09:37:44 INFO - Directory to install application into. [default: 09:37:44 INFO - "/builds/slave/test"] 09:37:44 INFO - --app=APP Application being installed. [default: firefox] 09:37:44 INFO - mkdir: /builds/slave/test/build/application 09:37:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:38:04 INFO - Reading from file tmpfile_stdout 09:38:04 INFO - Output received: 09:38:04 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:38:04 INFO - Running post-action listener: _resource_record_post_action 09:38:04 INFO - [mozharness: 2017-03-27 16:38:04.684722Z] Finished install step (success) 09:38:04 INFO - [mozharness: 2017-03-27 16:38:04.684874Z] Running run-tests step. 09:38:04 INFO - Running pre-action listener: _resource_record_pre_action 09:38:04 INFO - Running main action method: run_tests 09:38:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:04 INFO - 2017-03-27 09:38:04.699 screenresolution[1628:7380] starting screenresolution argv=screenresolution get 09:38:04 INFO - 2017-03-27 09:38:04.702 screenresolution[1628:7380] Display 0: 1600x1200x32@60 09:38:04 INFO - 2017-03-27 09:38:04.707 screenresolution[1629:7383] starting screenresolution argv=screenresolution list 09:38:04 INFO - Available Modes on Display 0 09:38:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:04 INFO - Intel Iris: 09:38:04 INFO - Chipset Model: Intel Iris 09:38:04 INFO - Type: GPU 09:38:04 INFO - Bus: Built-In 09:38:04 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:04 INFO - Vendor: Intel (0x8086) 09:38:04 INFO - Device ID: 0x0a2e 09:38:04 INFO - Revision ID: 0x0009 09:38:04 INFO - Displays: 09:38:04 INFO - Display: 09:38:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:04 INFO - Display Serial Number: 1600x1200 60 09:38:04 INFO - Main Display: Yes 09:38:04 INFO - Mirror: Off 09:38:04 INFO - Online: Yes 09:38:04 INFO - Rotation: Supported 09:38:04 INFO - Return code: 0 09:38:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:38:04 INFO - Python 2.7.3 09:38:04 INFO - Return code: 0 09:38:04 INFO - grabbing minidump binary from tooltool 09:38:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:04 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 0x103f7bd30>, '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 0x103ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f70be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:38:04 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:38:04 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:38:04 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:38:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:38:05 INFO - Return code: 0 09:38:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:38:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:38:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:38:05 INFO - ENV: RUST_BACKTRACE is now 1 09:38:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:38:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:38:05 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/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0121', '--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:38:05 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/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0121 --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:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CvS0BccVY9/Render', 09:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:05 INFO - 'HOME': '/Users/cltbld', 09:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:05 INFO - 'LOGNAME': 'cltbld', 09:38:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:38:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:05 INFO - 'MOZ_NO_REMOTE': '1', 09:38:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:38:05 INFO - 'NO_EM_RESTART': '1', 09:38:05 INFO - 'PAGER': '/bin/cat', 09:38:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:05 INFO - 'PWD': '/builds/slave/test', 09:38:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:05 INFO - 'RUST_BACKTRACE': '1', 09:38:05 INFO - 'SHELL': '/bin/bash', 09:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JsgSBBHlE/Listeners', 09:38:05 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:05 INFO - 'USER': 'cltbld', 09:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:05 INFO - 'XPC_FLAGS': '0x0', 09:38:05 INFO - 'XPC_SERVICE_NAME': '0', 09:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:05 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/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0121', '--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:38:05 INFO - mozversion application_buildid: 20170327075316 09:38:05 INFO - mozversion application_changeset: 6f684ac21eaeb4269bd650783deef431b2f114bc 09:38:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:38:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:38:05 INFO - mozversion application_name: Firefox 09:38:05 INFO - mozversion application_remotingname: firefox-dev 09:38:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:38:05 INFO - mozversion application_vendor: Mozilla 09:38:05 INFO - mozversion application_version: 54.0a2 09:38:05 INFO - mozversion platform_buildid: 20170327075316 09:38:05 INFO - mozversion platform_changeset: 6f684ac21eaeb4269bd650783deef431b2f114bc 09:38:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:38:05 INFO - mozversion platform_version: 54.0a2 09:38:05 INFO - using testdate: 1490632685 09:38:05 INFO - actual date: 1490632685 09:38:05 INFO - starting webserver on 'localhost:49242' 09:38:05 INFO - SUITE-START | Running 2 tests 09:38:05 INFO - TEST-START | dromaeo_css 09:38:05 INFO - operating with platform_type : mac_ 09:38:05 INFO - Initialising browser for dromaeo_css test... 09:38:05 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpJjBRW_/profile http://localhost:49242/getInfo.html) 09:38:08 INFO - PROCESS | 1639 | 2017-03-27 09:38:08.332 plugin-container[1641:7566] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 09:38:08 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:08 INFO - PROCESS | 1639 | 2017-03-27 09:38:08.336 plugin-container[1641:7566] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 09:38:08 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:08 INFO - PROCESS | 1639 | 2017-03-27 09:38:08.336 plugin-container[1641:7566] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:08 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 09:38:08 INFO - PROCESS | 1639 | colorDepth:24 09:38:08 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 09:38:08 INFO - PROCESS | 1639 | __metrics 09:38:08 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:38:09 INFO - TEST-INFO | 1639: exit 0 09:38:09 INFO - Browser initialized. 09:38:09 INFO - Running cycle 1/1 for dromaeo_css test... 09:38:09 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpJjBRW_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:11 INFO - PROCESS | 1642 | 2017-03-27 09:38:11.953 plugin-container[1643:7691] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4f, name = 'com.apple.tsm.portname' 09:38:11 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:11 INFO - PROCESS | 1642 | 2017-03-27 09:38:11.954 plugin-container[1643:7691] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:38:11 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:11 INFO - PROCESS | 1642 | 2017-03-27 09:38:11.954 plugin-container[1643:7691] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:21 INFO - PROCESS | 1642 | RSS: Main: 220487680 09:38:21 INFO - PROCESS | 1642 | 09:41:25 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 09:41:27 INFO - PROCESS | 1642 | RSS: Main: 240087040 09:41:27 INFO - PROCESS | 1642 | 09:43:09 INFO - PROCESS | 1642 | ************************* 09:43:09 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 09:43:09 INFO - PROCESS | 1642 | 09:43:09 ERROR - PROCESS | 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:43:09 INFO - PROCESS | 1642 | Full stack: 09:43:09 INFO - PROCESS | 1642 | ************************* 09:44:29 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 09:44:30 INFO - PROCESS | 1642 | RSS: Main: 250077184 09:44:30 INFO - PROCESS | 1642 | 09:47:32 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 09:47:32 INFO - PROCESS | 1642 | RSS: Main: 256598016 09:47:32 INFO - PROCESS | 1642 | 09:50:34 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 09:50:35 INFO - PROCESS | 1642 | RSS: Main: 256004096 09:50:35 INFO - PROCESS | 1642 | 09:53:38 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 09:53:38 INFO - PROCESS | 1642 | RSS: Main: 261197824 09:53:38 INFO - PROCESS | 1642 | 09:56:41 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 09:56:42 INFO - PROCESS | 1642 | RSS: Main: 257253376 09:56:42 INFO - PROCESS | 1642 | 09:56:42 INFO - PROCESS | 1642 | __start_tp_report 09:56:42 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 09:56:42 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 09:56:42 INFO - PROCESS | 1642 | |i|pagename|runs| 09:56:42 INFO - PROCESS | 1642 | |0;dojo.html;3208.8074715517073;3487.128217945514;3487.9476807847877;3498;3571.2857428514303;4922.753862306884;5616.52259557937;6163.969180154093;6193.907091393627;6215.19202503675;5030.924536131952;6134.0798880167995;6179;6194.938050619492;6195.946689062859;4683.601893839028;5485.259489968863;5498.285222921025;5588.469095435927;5639;5621.662700237978;5636.0982242841155;5637.013522633543;5642.097264437691;5648.915266271009;4628.629709623239;5299.6820190788485;5336.466353364672;5621.156826476021;5675.5459563235045;4601.12578610063;5321.441248668912;5442.074847275952;5445.972770136124;5501.422350653163;4705.411823522059;4747.83382581608;5238.690327418146;5504.559635229172;5549.694766787825;6198.442140207358;6249.312575616678;6250.624962502219;6270.059491076375;6273.686315684243;5148.5623721983675;6334.968325158345;6423.357664233586;6581.407673309425;6586.407223349922;6325.87348253037;6341.2707538633185;6479.611223326616;6553.377429144223;6556.704948277339;6309.022101574264;6313.96843015782;6314.273858506239;6332.176817013805;6342.936570634327;5975.282966044103;6224.84840304536;6794.660266986631;6804.591724496546;6815.454763618898;6228.28374736902;6289.308176100625;6314.494840412756;6316.30520642729;6370.840728981812;5528.861778455571;6530.8040758777315;6560.343965603402;6593.175853018372;6646.102776125162;6370.713317900706;6505.902411463831;6511.706973186123;6515.51133664977;6520.315366886409;6218.81343559694;6274.247090349096;6277.246730392382;6524.836879077966;6537.117489139;5038.773255203525;5128.692278463304;5243;5264.368275806928;5315.176147697113;4581.977090114595;4596.931046034312;4623.468301145344;4637.188492013884;4678.602318802872;6128.969355153285;6164.537659675542;6239.937600623935;6245.781397651152;6256.906146407808;6602.372774586406;6604.570702904296;6619.139511863427;6624.735010599522;6640.302768209365;5651.4348565143155;5725.312962444534;5782.797602083991;6137.447629713258;6221.9377806222265;5756.683382413924;5800.1299805029585;5820.708964551755;5827.825165245049;5845.444682755132;6753.831154221184;6760.121184246115;6793.320667933167;6800.421964133104;6825.863482730417;6231.376862313732;6314;6556.114924485133;6618.966905165636;6643.667816609247;6659.134312539339;6675.532712710064;6791.762288319885;6803.795886123424;6821.215560210441;5267.431492007059;5415.539679127318;6400.103985441949;6452.22573291208;6462.612243265419;6264.68676566179;6478.805635830932;6481.967590162019;6528.575642583407;6567.44176744982;6062.969295219911;6244.344343843831;6267.1539342188225;6293.96853015732;6318.399752023551;6346.4605508532295;6351.428371446592;6355.205599300088;6371.362863713592;6372.617642941438;5534.695591742332;6206.255249370104;6278.4035516625845;6306.117143599808;6343.238811342669;6156.907646385218;6228;6253.96873015632;6266.404691554295;6389.9680501597195;6297.338779428094;6302.369763023661;6318.020706790455;6328.936710632835;6334.176557047738 09:56:42 INFO - PROCESS | 1642 | |1;ext.html;691.3984833195119;761.4061032394734;828.5898480252276;866.6836604639309;970.2432102959694;37650.62349376505;38570.80714596427;39804.2039159217;40013.19973600526;40015;38755.22489550207;38817.61182388175;38978.61021389785;39091;39192.804035979854;7898.921010789891;8017.438779285452;8653.524056176908;10754.784904301909;10907;10634.78730425391;10680.359178449318;10739.194560407961;10750.838737418906;10798.190135739811;10788.46057697112;10788.46057697116;10794.892051079467;10801.297915635498;10829.512671929782;10764.192685548536;10772.192085593611;10807.891921080767;10838.295510791851;10848.18638602108;10470.057694807429;10485.737856553571;10488.528016239288;10533.157347412189;10540;33310.666893331;33340.333193335995;33420.8302709404;33481.497777533354;33519.82680606167;3687.760295580779;3695.7782533047834;3726.6087060858763;3733.9439908401396;3786.0345611868797;23619;23622;24148.913298901592;24155.154569590155;24891.25326240216;3036.1195253376495;3070.9846450767827;3159.984200078985;3217.0831313075896;3259.5925509311337;370056;372090;374462;376167;376351;43698;43812.3428148578;43851;44001.899952500804;44107;45008.77495612501;45342.77328613402;45466.86332841706;45554.08891822145;45599;13650.044496885122;13796.517121900686;13845.961552883373;14007.509737159355;14020.789688154686;12029.398530073462;12037.97677197448;12048.87951120495;12076.396180190955;12110.636680899586;11328.376939268255;11858.407079645984;11879.465424055938;11895.226810257305;11898.76202475963;4835.008823191238;5696.772129114789;5743.9425605744245;5744.684042377709;5795;1106.2145876427664;1119.6417146513038;1128.1820680006929;1150.827375893606;1162.136458919425;22171.889140554194;26579.734202658114;27179.86410067937;27180.45639087207;27207.86396068007;967.8741763570831;1013.8492810659955;1224.9877501225053;1269.3942113640585;1286.9420876060599;12165.20926139798;14622.926885365505;14929.954903156675;15002.549923502313;15297.46458873934;494395.0560494421;503311;574784.1260793669;575266;618446;39721.20557588832;40349.394759078634;45872.54127458682;46246.768766155954;46492.302615460794;1382.1845111384332;1423.9145651260958;1430.8569143085608;1436.1598464897943;1437.9209143497208;26234.819433125173;26369.60445593356;26720.465590688465;27084.72915270822;27239.59140612853;39853.80438586847;40036;40088.1982360357;40160.59839401685;40184.1963160741;487609;488423;492036.53981729865;492631;493964;28774.712252878042;29231.123066308046;29269.268268293465;29550;29553;36539;36947.07632309106;37091.44362834613;37227.441588376736;37258.62741372552;26238.868805656613;26476;26801.81969883562;26901.923923042857;27200.319992000357;22224.88887555552;26073.478530429664;26278.34304142335;26368;26801.597976029985;30047.948321809505;30973.53539696861;31154;31265.218369540944;31392.901248456194;26264.868675657264;26331.473370532865;26589.734102658727;26691.86654066717;27386.863065685342;21582.784172158077;21691.023903924677;21953.341399758032;22225.555488889808;22355 09:56:42 INFO - PROCESS | 1642 | |2;jquery.html;397.68980195447546;407.8878308465174;409.4288467587717;423.56450410141696;448.22680875489783;19591.020448977564;19940.90029549852;20837.47906302343;21047.842368669717;21266.36200913973;17860.28558857644;19744.111514981825;21086;21356.679649805228;21441.67837482439;5640.802571909984;6298.968505157468;6611.702473388685;6663.867142585772;6683.398494135527;3816.4466152407886;6341.397567231105;6450.7742229022;6453.548251622365;6549.508786840982;6545.869082618345;6554.672266386686;6577.375149360827;6583.9012414813815;6585.143931288926;6550;7112.217656057829;7460.403167746567;7491.887621685679;7518.8120296992465;7017.89473157903;7165.534240274365;7276.599787011713;7306.050213472215;7316.122065352191;25521;25688.8715556421;26273.474530509473;26312.342191445176;26579.601305980425;2850.102217801386;2853.9001134960386;2911.490489164388;2949.837758923258;2974.8363839988792;12382.442790074378;12908.290044047573;16063.91968040164;16273.430429934895;16827.242774075195;1849.4636555398918;1864.4872660018602;1908.0841196225751;1916.7795703493998;1917.8273955343946;129053;140414;144308.27845860703;152181;162248.18875905662;20753;20936.057877395247;20955.790442095687;21036.47408814792;21581.02885370162;31737.523937140963;31815;32321.89907655995;32356.83821580877;32423.35153296921;10206.897931020743;10333.483325833678;10339.586416543254;10455.790884182274;10637.202209834293;8702.956485217534;8743.431676940978;8791.604377802887;8943.597538110671;8985.281177505783;8656.48061116335;9042.231410330063;9565.473898935625;9635.277354198462;9674.274429417681;3750.362438385481;4040.1920665137445;4135.242521928169;4206.831726730897;4244.236037513216;888.7778055486128;897.2059727141527;898.4609234459272;926.2358554192817;933.7432206143229;20720.896395518226;21155;21236.362909112442;21274.893625531775;21308.680369794467;770.6493545436813;909.2044461096541;925.5557332480447;942.137943781446;959.1032384720309;6774.525783195228;6799.966000170067;6882.311768823078;7095.82260443493;7112.253213412517;668707;780143;799392;815216;818402;115563.42218289022;131359;161646;164826;165476.17261913614;1317.2820812657126;1332.80007998799;1395.2535393564597;1401.2643362234705;1402.3128666953323;17491.737623935398;19049.523761906064;19092;19260.422187334403;19387.071954595165;62527.06209406954;62815;64698.35301647112;65873.67063164653;66814;91230.54384728034;92566;93791;94639.58040629259;95269;11280;11335.263207891461;11402.372869492257;11637.53449861996;11703.76592468163;12395.442205100611;12477.688057798629;12502.062345323955;12600.936995314965;12814.615561533168;10711.678649640524;10994;11102.666919992413;11178.217524773188;11295.943520282675;10137.290389672948;10200.795984080425;10250.538725757508;10358.792824143324;10507.42209178472;11162.832557511481;11241.156913231376;11365;11497.310161390344;11585.768284634429;10089.596416143271;11404.030657394214;11405.600803971822;11419.31484110956;11703.23928944616;10525.57904682858;10743.623973161215;10774.353538787698;10819.891801081889;10978.341299522055 09:56:42 INFO - PROCESS | 1642 | |3;mootools.html;1466.2009204983283;1547.489328521588;1560.6566555357817;1589.6741168060546;1591.5384538483838;9416.905830941689;9579.952100239489;10602.992715692006;11256.930591593806;11278.15413843961;9256.72229833106;9263.351565390425;9287.210587100104;10671.306365086264;11250.324980501176;2306.8961896714645;2399.1123284384716;2402.7356990731005;2404.038384646138;2404.843685160459;2369.668246445499;2394.5091256292417;2403.375122468153;2406.759324067597;2410.4937963027787;2355.316958082154;2365.4322962488964;2381.654660074288;2389.844660097097;2401.3036219496325;2352.9058837646476;2357.4695693469002;2360.6459031145296;2366.1954935321987;2373.979188948743;2324.1981516376823;2351.271105957159;2373.2405630198346;2381.785639292472;2398.9760102398927;11105.611303604335;11198.608048718337;11220.214584979054;11252.26860254081;11280.153988450898;5647.124695672177;5779.8555036124435;5827.708614569255;5861.912071318934;5866.237389139427;3521.753477256593;3631.8365673544754;3664.926701465982;3690.5940346561856;3700.0749812546865;1168.842206302147;1172.94135293235;1191.2316555821533;1211.9394030298538;1224.577520755338;12335.568255111028;13991.440342386293;15007.174605396698;15010.774838377323;15047.398104075824;4516.187086324493;4636.095961287584;4763.809447622126;4831.541003604652;4847.95152048475;12854.614361569167;12865.807012894815;12877.484900604059;12949.935250323688;12967.027472939379;5681.715914204356;5695.715214239354;5703.8859222816145;5717.17101020358;5723.370429252779;4379.1022840317655;4809.567138957511;5115.693058416433;5169.095408303532;5239.345081864767;3583.2475180211927;4021.7184797063924;4052.2908491013955;4127.649149822299;4156.210320039183;1141.6289705845634;1141.7773534160924;1143.0626885953438;1158.8030034894089;1159.2580748321082;760.9733659321897;881.4975463985595;912.3248795890993;921.488573841511;930.6789157740569;6878.518503704692;6916.412104971033;6940.861182776316;6970.372666460043;7202.1357437106835;979.2215188924822;1010.8635334229784;1029.248648486594;1038.2887721910515;1061.1086687182803;4153.979230103831;4173.08192197716;4274;4279.251131052053;4465.464144302705;13067.542636007884;13670.453181872614;13674.384652690653;13681.179129252278;13691.726165476635;10872.402017888933;12803.16779409354;13604.727905441836;13611.863881361258;13681.726365472634;8674.349423793241;9973.650922217543;9985.400875947467;10264.281500294908;10289.845652315222;5187.2478490619405;6577.868442631217;6591.5056370773345;6769.086173366532;6818.931810682028;9603.663871764455;12272.140950133406;12374.75250494967;12375.504979801068;12392;10980.231383803057;12450;12544.623661290176;12723.300218488072;12775.488980440678;8103.594820258845;8253.33973282124;9095.272378209595;9418.340716149803;9931.503424828874;9506.619735210514;9818.410895346302;10305.896941030494;10475.790484190426;10582;8748.65005399777;8763.167499087576;8802.911970880465;8829.028806831371;8945.016048234818;8599.785005374915;8640.870386944327;8686.652533898827;8745.781355466164;8812.559372031501;7878.527288362717;7939.6030198491;7976.7208147714555;8017.278444939983;8025.9598702006115;5147.176451767699;5225.216217567396;5296.993571221456;5301.257823904733;5342.866428339167;5834.18321434991;5847.766089356548;5900.468957793819;5963.2844058713235;6025.78909738157 09:56:42 INFO - PROCESS | 1642 | |4;prototype.html;327.7410845432109;328.81586311665467;375.33165081213997;383.4095492940871;388.94360317753933;5405;5484.61607687462;5494.890102197954;5520.503154716075;5525.861853453665;4476.820927162909;4494.820207191717;5055.739048313844;5219.060569097571;5228.686278823264;3477.6000759912554;3842.5773164951834;4341.957930096785;4400.7579583122915;4414.80133393998;4226.224985506079;4242.97878510607;4420.845270415536;4438.844640437585;4441.71128876622;3900.9804950975204;4200.852970146046;4396.56034396561;4420.602145806877;4505.234110201273;3535.381308271042;3898.532176138853;3948.822302996372;4387.473503179607;4403.405540252057;3421.6578342165835;4006.659433948118;4037.899052523681;4040.838366465338;4067.2678917794783;4050.177420160939;4883.970724152588;4960.189530821261;5265.078611243017;5274.654962984415;4509.278515437514;4588.954110458886;5004.79788892886;5094.974525127351;5141.640085194038;5096.050560815361;5298.549623282026;5537.307836520435;5655.236543066716;5729.885402291972;4079.755214687128;4550.953280745414;4561.407017087758;4579.67942244043;4593.471750748673;5305.8938821223355;5732.939406209823;5737.340205876336;5745.856353591152;6094.659174981497;3986.6412022918075;4144.171165766858;4546.113507866001;4652.232151782301;4659.9767001165455;4438.378626992224;4677.64917631179;4744.102359056336;4808.759562021885;4809.615230781529;4834.516548345137;5023.924641130386;5145.202493613496;5152.201408781645;5193.532582067632;4743.762811859393;5388.084025715638;5430.51125398716;5665.745041473144;5684.181062060101;1337.4716986790163;1447.7321695486369;1568.9331254746653;1602.8317026712261;1625.617979774752;1128.26810845352;1172.3317708906013;1173.3546549397797;1205.7709035283267;1221.4609591914075;4672.57946784791;4816.638752093537;5248.422673505911;5258.316418865523;5391.56867450603;5386.461353864583;5944.970275148683;6007.489363404072;6008.279006519246;6023.759049638053;4785.688930219525;4813.122882075993;4978.178600530944;4984.177610694195;5316.963192177488;2732.931676708098;2829.5614179802164;2957.6746557878614;3127.124405166557;3131.4029844779325;4924.88048060914;4991.975040124777;5617.8595535111135;5751.175468313609;6121.846953826101;5200.76596553156;5420.620556561082;5696.1266338889955;6087.239095113111;6128.9693551531955;6105.877882442415;6159.692015399212;6168;6178.320384757673;6189.812246692957;5147.459516750687;5188.325517682677;5457.918131227955;5576.5817563683695;6031.849203769941;5143.794248230029;5297.940411917555;5445.891082178414;5849.151872978309;5913.970430147821;5214.895702085862;5698.373178950229;5982.850428739316;6105.5124483503705;6120.265568131675;4254.446921900133;4406.515283318897;4546.954200533962;4585.6102231310715;4587.334836448763;5018.924716129188;5483.055834400529;5831.620944638585;5866.266716660418;5928.9407105928385;4846.781894814671;4923.876903077452;5625.353084395347;5634.605577609528;5737.770489180386;4953.529414705597;5214;5432.674039557639;5508.641938274;5690.3456102548735;4515.9774201128785;4572.565606267399;4761.381130415675;5152.355955505562;5555.8888822224135;5054.671446355975;5233.659812112201;5592.728253971472;5603.439656034364;5704.885902282014;4617.953820461752;5166.922496162636;5391.649542779707;5511.503964643041;5515.862103447446 09:56:42 INFO - PROCESS | 1642 | |5;yui.html;706.7526365771977;715.3597530210465;792.6433105102706;832.4963397144726;834.4826207751195;14250.501232456865;15426.768598471032;15741.685166296667;16100.503514631824;16414.753778693314;14925.253737313145;15134.621634459116;15206.391744330242;15431.69136617267;15620.531384058468;862.3316929379749;920.9861852072207;952.485657744817;961.4519723757461;961.6297725375711;892.2237653241689;950.9001554836747;957.4494665567291;958.4776296918161;961.9374740641871;1011.7521207304184;1016.7356487313315;1017.3336464615688;1019.5666841592331;1021.0147207944332;997.7206382212992;1004.1013293102632;1011.7217765114578;1011.8886922438526;1012.2205901455837;740.4483659673524;746.302207436049;748.636911098117;750.7935317787652;751.898493703354;15933.283002264925;16424.425145119865;16652.666946661;16857;17093.829061709348;14163.645908852259;14246.857531424657;14300.713985720227;14342.71314573713;14366.497172598909;11580.6525804226;11826.113041521834;11915.968884704933;11975.64073077809;12439.689007774787;3641.6722494975315;3644.516151383013;3649.105969037603;3660.1032746976894;3750.5311836020496;81212;105000.47499762452;111645.44177279061;115750;116598.99999999916;9175.541222938826;10759.677209683721;10828.837567436338;11462.312261264184;11613.3612651305;22741;22751.886240568692;23325.650115248285;23369.7663023371;23402;7589.316961473383;7612.3910087194;7621.771346859603;7623.237676232332;7770.184130666199;4290;4304.569543045733;4319.632831209383;4405.889852753707;4412.161689279027;3452.8273586320583;3480.077779388464;3542.75200735946;3563.8574457021427;3580.5524309461316;544.4827413956757;617.9752809887644;626.6835248199721;634.2072409488139;636.1189752193245;669.2404121322335;678.4131726057024;685.1572565744063;699.6746512871539;730.2478447199393;10076.69769906904;10243.897561024294;10564.947175264075;10575.153987680967;10575.947120264349;660.2374257732326;683.6718375179842;701.9192792828788;707.6002058836741;709.3261401668435;4848.066919513643;5032.471590482946;5032.949670503248;5043.268726034705;5046;88844;98041.01958980462;108939;109090;111566.88433115566;80712;80915.7862632061;83318.16681833104;87592;88186.55906720426;70223.6488817563;80752.19247807447;81296;81359.96600085046;82666.17333826705;8636.870446943427;10458.790824183627;11197.776044478902;11364.602238921598;11446.885531144582;50670.74664626801;51046;51431.71420714512;51524.742376287875;52857;64181.358186419406;65383;65992.68014639584;66341.33658663352;67115.66442167757;12840.935795320964;12987;13047.739045219232;13072.477100915858;13291.20252784836;14691.191984440959;14754.557363279118;14979.625509362353;15021.849781502482;15042.774358384415;13452.192868427926;13583.52457663977;13938.442462301395;14149.929250353682;14608;13425.462981480632;13505.86494135046;14030.298485075502;14091.929540352232;14514.129152250898;13380.531681391105;13611;13923.373448194652;13986.720265594835;14215.857841421453;9918.504074796087;10144.847827282745;10317.638882639072;10464.843027354444;10648.574057037942;10127.362264394449;11110.61112861046;11467.942660286646;11638.301701897575;11719.589814356457 09:56:42 INFO - PROCESS | 1642 | __end_tp_report 09:56:42 INFO - PROCESS | 1642 | __start_cc_report 09:56:42 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,417 09:56:42 INFO - PROCESS | 1642 | __end_cc_report 09:56:42 INFO - PROCESS | 1642 | __startTimestamp1490633802094__endTimestamp 09:56:42 INFO - PROCESS | 1642 | 09:56:42 INFO - PROCESS | 1642 | ------- Summary: start ------- 09:56:42 INFO - PROCESS | 1642 | Number of tests: 6 09:56:42 INFO - PROCESS | 1642 | 09:56:42 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5981.45 Median:6253.97 stddev:724.08 (11.6%) stddev-sans-first:692.86 09:56:42 INFO - PROCESS | 1642 | Values: 3208.8 3487.1 3487.9 3498.0 3571.3 4922.8 5616.5 6164.0 6193.9 6215.2 5030.9 6134.1 6179.0 6194.9 6195.9 4683.6 5485.3 5498.3 5588.5 5639.0 5621.7 5636.1 5637.0 5642.1 5648.9 4628.6 5299.7 5336.5 5621.2 5675.5 4601.1 5321.4 5442.1 5446.0 5501.4 4705.4 4747.8 5238.7 5504.6 5549.7 6198.4 6249.3 6250.6 6270.1 6273.7 5148.6 6335.0 6423.4 6581.4 6586.4 6325.9 6341.3 6479.6 6553.4 6556.7 6309.0 6314.0 6314.3 6332.2 6342.9 5975.3 6224.8 6794.7 6804.6 6815.5 6228.3 6289.3 6314.5 6316.3 6370.8 5528.9 6530.8 6560.3 6593.2 6646.1 6370.7 6505.9 6511.7 6515.5 6520.3 6218.8 6274.2 6277.2 6524.8 6537.1 5038.8 5128.7 5243.0 5264.4 5315.2 4582.0 4596.9 4623.5 4637.2 4678.6 6129.0 6164.5 6239.9 6245.8 6256.9 6602.4 6604.6 6619.1 6624.7 6640.3 5651.4 5725.3 5782.8 6137.4 6221.9 5756.7 5800.1 5820.7 5827.8 5845.4 6753.8 6760.1 6793.3 6800.4 6825.9 6231.4 6314.0 6556.1 6619.0 6643.7 6659.1 6675.5 6791.8 6803.8 6821.2 5267.4 5415.5 6400.1 6452.2 6462.6 6264.7 6478.8 6482.0 6528.6 6567.4 6063.0 6244.3 6267.2 6294.0 6318.4 6346.5 6351.4 6355.2 6371.4 6372.6 5534.7 6206.3 6278.4 6306.1 6343.2 6156.9 6228.0 6254.0 6266.4 6390.0 6297.3 6302.4 6318.0 6328.9 6334.2 09:56:42 INFO - PROCESS | 1642 | 09:56:42 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:58301.42 Median:28080.79 stddev:128095.11 (456.2%) stddev-sans-first:128381.42 09:56:42 INFO - PROCESS | 1642 | Values: 691.4 761.4 828.6 866.7 970.2 37650.6 38570.8 39804.2 40013.2 40015.0 38755.2 38817.6 38978.6 39091.0 39192.8 7898.9 8017.4 8653.5 10754.8 10907.0 10634.8 10680.4 10739.2 10750.8 10798.2 10788.5 10788.5 10794.9 10801.3 10829.5 10764.2 10772.2 10807.9 10838.3 10848.2 10470.1 10485.7 10488.5 10533.2 10540.0 33310.7 33340.3 33420.8 33481.5 33519.8 3687.8 3695.8 3726.6 3733.9 3786.0 23619.0 23622.0 24148.9 24155.2 24891.3 3036.1 3071.0 3160.0 3217.1 3259.6 370056.0 372090.0 374462.0 376167.0 376351.0 43698.0 43812.3 43851.0 44001.9 44107.0 45008.8 45342.8 45466.9 45554.1 45599.0 13650.0 13796.5 13846.0 14007.5 14020.8 12029.4 12038.0 12048.9 12076.4 12110.6 11328.4 11858.4 11879.5 11895.2 11898.8 4835.0 5696.8 5743.9 5744.7 5795.0 1106.2 1119.6 1128.2 1150.8 1162.1 22171.9 26579.7 27179.9 27180.5 27207.9 967.9 1013.8 1225.0 1269.4 1286.9 12165.2 14622.9 14930.0 15002.5 15297.5 494395.1 503311.0 574784.1 575266.0 618446.0 39721.2 40349.4 45872.5 46246.8 46492.3 1382.2 1423.9 1430.9 1436.2 1437.9 26234.8 26369.6 26720.5 27084.7 27239.6 39853.8 40036.0 40088.2 40160.6 40184.2 487609.0 488423.0 492036.5 492631.0 493964.0 28774.7 29231.1 29269.3 29550.0 29553.0 36539.0 36947.1 37091.4 37227.4 37258.6 26238.9 26476.0 26801.8 26901.9 27200.3 22224.9 26073.5 26278.3 26368.0 26801.6 30047.9 30973.5 31154.0 31265.2 31392.9 26264.9 26331.5 26589.7 26691.9 27386.9 21582.8 21691.0 21953.3 22225.6 22355.0 09:56:42 INFO - PROCESS | 1642 | 09:56:42 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:43410.95 Median:25604.94 stddev:129458.17 (505.6%) stddev-sans-first:129781.04 09:56:42 INFO - PROCESS | 1642 | Values: 397.7 407.9 409.4 423.6 448.2 19591.0 19940.9 20837.5 21047.8 21266.4 17860.3 19744.1 21086.0 21356.7 21441.7 5640.8 6299.0 6611.7 6663.9 6683.4 3816.4 6341.4 6450.8 6453.5 6549.5 6545.9 6554.7 6577.4 6583.9 6585.1 6550.0 7112.2 7460.4 7491.9 7518.8 7017.9 7165.5 7276.6 7306.1 7316.1 25521.0 25688.9 26273.5 26312.3 26579.6 2850.1 2853.9 2911.5 2949.8 2974.8 12382.4 12908.3 16063.9 16273.4 16827.2 1849.5 1864.5 1908.1 1916.8 1917.8 129053.0 140414.0 144308.3 152181.0 162248.2 20753.0 20936.1 20955.8 21036.5 21581.0 31737.5 31815.0 32321.9 32356.8 32423.4 10206.9 10333.5 10339.6 10455.8 10637.2 8703.0 8743.4 8791.6 8943.6 8985.3 8656.5 9042.2 9565.5 9635.3 9674.3 3750.4 4040.2 4135.2 4206.8 4244.2 888.8 897.2 898.5 926.2 933.7 20720.9 21155.0 21236.4 21274.9 21308.7 770.6 909.2 925.6 942.1 959.1 6774.5 6800.0 6882.3 7095.8 7112.3 668707.0 780143.0 799392.0 815216.0 818402.0 115563.4 131359.0 161646.0 164826.0 165476.2 1317.3 1332.8 1395.3 1401.3 1402.3 17491.7 19049.5 19092.0 19260.4 19387.1 62527.1 62815.0 64698.4 65873.7 66814.0 91230.5 92566.0 93791.0 94639.6 95269.0 11280.0 11335.3 11402.4 11637.5 11703.8 12395.4 12477.7 12502.1 12600.9 12814.6 10711.7 10994.0 11102.7 11178.2 11295.9 10137.3 10200.8 10250.5 10358.8 10507.4 11162.8 11241.2 11365.0 11497.3 11585.8 10089.6 11404.0 11405.6 11419.3 11703.2 10525.6 10743.6 10774.4 10819.9 10978.3 09:56:42 INFO - PROCESS | 1642 | 09:56:42 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:6640.27 Median:3648.38 stddev:4123.69 (113.0%) stddev-sans-first:4116.93 09:56:42 INFO - PROCESS | 1642 | Values: 1466.2 1547.5 1560.7 1589.7 1591.5 9416.9 9580.0 10603.0 11256.9 11278.2 9256.7 9263.4 9287.2 10671.3 11250.3 2306.9 2399.1 2402.7 2404.0 2404.8 2369.7 2394.5 2403.4 2406.8 2410.5 2355.3 2365.4 2381.7 2389.8 2401.3 2352.9 2357.5 2360.6 2366.2 2374.0 2324.2 2351.3 2373.2 2381.8 2399.0 11105.6 11198.6 11220.2 11252.3 11280.2 5647.1 5779.9 5827.7 5861.9 5866.2 3521.8 3631.8 3664.9 3690.6 3700.1 1168.8 1172.9 1191.2 1211.9 1224.6 12335.6 13991.4 15007.2 15010.8 15047.4 4516.2 4636.1 4763.8 4831.5 4848.0 12854.6 12865.8 12877.5 12949.9 12967.0 5681.7 5695.7 5703.9 5717.2 5723.4 4379.1 4809.6 5115.7 5169.1 5239.3 3583.2 4021.7 4052.3 4127.6 4156.2 1141.6 1141.8 1143.1 1158.8 1159.3 761.0 881.5 912.3 921.5 930.7 6878.5 6916.4 6940.9 6970.4 7202.1 979.2 1010.9 1029.2 1038.3 1061.1 4154.0 4173.1 4274.0 4279.3 4465.5 13067.5 13670.5 13674.4 13681.2 13691.7 10872.4 12803.2 13604.7 13611.9 13681.7 8674.3 9973.7 9985.4 10264.3 10289.8 5187.2 6577.9 6591.5 6769.1 6818.9 9603.7 12272.1 12374.8 12375.5 12392.0 10980.2 12450.0 12544.6 12723.3 12775.5 8103.6 8253.3 9095.3 9418.3 9931.5 9506.6 9818.4 10305.9 10475.8 10582.0 8748.7 8763.2 8802.9 8829.0 8945.0 8599.8 8640.9 8686.7 8745.8 8812.6 7878.5 7939.6 7976.7 8017.3 8026.0 5147.2 5225.2 5297.0 5301.3 5342.9 5834.2 5847.8 5900.5 5963.3 6025.8 09:56:42 INFO - PROCESS | 1642 | 09:56:42 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4682.10 Median:5021.42 stddev:1320.67 (26.3%) stddev-sans-first:1283.30 09:56:42 INFO - PROCESS | 1642 | Values: 327.7 328.8 375.3 383.4 388.9 5405.0 5484.6 5494.9 5520.5 5525.9 4476.8 4494.8 5055.7 5219.1 5228.7 3477.6 3842.6 4342.0 4400.8 4414.8 4226.2 4243.0 4420.8 4438.8 4441.7 3901.0 4200.9 4396.6 4420.6 4505.2 3535.4 3898.5 3948.8 4387.5 4403.4 3421.7 4006.7 4037.9 4040.8 4067.3 4050.2 4884.0 4960.2 5265.1 5274.7 4509.3 4589.0 5004.8 5095.0 5141.6 5096.1 5298.5 5537.3 5655.2 5729.9 4079.8 4551.0 4561.4 4579.7 4593.5 5305.9 5732.9 5737.3 5745.9 6094.7 3986.6 4144.2 4546.1 4652.2 4660.0 4438.4 4677.6 4744.1 4808.8 4809.6 4834.5 5023.9 5145.2 5152.2 5193.5 4743.8 5388.1 5430.5 5665.7 5684.2 1337.5 1447.7 1568.9 1602.8 1625.6 1128.3 1172.3 1173.4 1205.8 1221.5 4672.6 4816.6 5248.4 5258.3 5391.6 5386.5 5945.0 6007.5 6008.3 6023.8 4785.7 4813.1 4978.2 4984.2 5317.0 2732.9 2829.6 2957.7 3127.1 3131.4 4924.9 4992.0 5617.9 5751.2 6121.8 5200.8 5420.6 5696.1 6087.2 6129.0 6105.9 6159.7 6168.0 6178.3 6189.8 5147.5 5188.3 5457.9 5576.6 6031.8 5143.8 5297.9 5445.9 5849.2 5914.0 5214.9 5698.4 5982.9 6105.5 6120.3 4254.4 4406.5 4547.0 4585.6 4587.3 5018.9 5483.1 5831.6 5866.3 5928.9 4846.8 4923.9 5625.4 5634.6 5737.8 4953.5 5214.0 5432.7 5508.6 5690.3 4516.0 4572.6 4761.4 5152.4 5555.9 5054.7 5233.7 5592.7 5603.4 5704.9 4618.0 5166.9 5391.6 5511.5 5515.9 09:56:42 INFO - PROCESS | 1642 | 09:56:42 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:20594.06 Median:23347.71 stddev:29226.79 (125.2%) stddev-sans-first:29270.63 09:56:42 INFO - PROCESS | 1642 | Values: 706.8 715.4 792.6 832.5 834.5 14250.5 15426.8 15741.7 16100.5 16414.8 14925.3 15134.6 15206.4 15431.7 15620.5 862.3 921.0 952.5 961.5 961.6 892.2 950.9 957.4 958.5 961.9 1011.8 1016.7 1017.3 1019.6 1021.0 997.7 1004.1 1011.7 1011.9 1012.2 740.4 746.3 748.6 750.8 751.9 15933.3 16424.4 16652.7 16857.0 17093.8 14163.6 14246.9 14300.7 14342.7 14366.5 11580.7 11826.1 11916.0 11975.6 12439.7 3641.7 3644.5 3649.1 3660.1 3750.5 81212.0 105000.5 111645.4 115750.0 116599.0 9175.5 10759.7 10828.8 11462.3 11613.4 22741.0 22751.9 23325.7 23369.8 23402.0 7589.3 7612.4 7621.8 7623.2 7770.2 4290.0 4304.6 4319.6 4405.9 4412.2 3452.8 3480.1 3542.8 3563.9 3580.6 544.5 618.0 626.7 634.2 636.1 669.2 678.4 685.2 699.7 730.2 10076.7 10243.9 10564.9 10575.2 10575.9 660.2 683.7 701.9 707.6 709.3 4848.1 5032.5 5032.9 5043.3 5046.0 88844.0 98041.0 108939.0 109090.0 111566.9 80712.0 80915.8 83318.2 87592.0 88186.6 70223.6 80752.2 81296.0 81360.0 82666.2 8636.9 10458.8 11197.8 11364.6 11446.9 50670.7 51046.0 51431.7 51524.7 52857.0 64181.4 65383.0 65992.7 66341.3 67115.7 12840.9 12987.0 13047.7 13072.5 13291.2 14691.2 14754.6 14979.6 15021.8 15042.8 13452.2 13583.5 13938.4 14149.9 14608.0 13425.5 13505.9 14030.3 14091.9 14514.1 13380.5 13611.0 13923.4 13986.7 14215.9 9918.5 10144.8 10317.6 10464.8 10648.6 10127.4 11110.6 11467.9 11638.3 11719.6 09:56:42 INFO - PROCESS | 1642 | -------- Summary: end -------- 09:56:42 INFO - PROCESS | 1642 | 09:56:42 INFO - TEST-INFO | 1642: exit 0 09:56:42 INFO - TEST-OK | dromaeo_css | took 1117459ms 09:56:42 INFO - TEST-START | kraken 09:56:42 INFO - operating with platform_type : mac_ 09:56:42 INFO - Initialising browser for kraken test... 09:56:42 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpIZMdr3/profile http://localhost:49242/getInfo.html) 09:56:45 INFO - PROCESS | 1654 | 2017-03-27 09:56:45.420 plugin-container[1655:10204] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 09:56:45 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:45 INFO - PROCESS | 1654 | 2017-03-27 09:56:45.423 plugin-container[1655:10204] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4427, name = 'com.apple.CFPasteboardClient' 09:56:45 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:45 INFO - PROCESS | 1654 | 2017-03-27 09:56:45.423 plugin-container[1655:10204] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:56:45 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 09:56:45 INFO - PROCESS | 1654 | colorDepth:24 09:56:45 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 09:56:45 INFO - PROCESS | 1654 | __metrics 09:56:45 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:56:46 INFO - TEST-INFO | 1654: exit 0 09:56:46 INFO - Browser initialized. 09:56:46 INFO - Running cycle 1/1 for kraken test... 09:56:46 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpIZMdr3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:48 INFO - PROCESS | 1656 | 2017-03-27 09:56:48.622 plugin-container[1657:10332] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:56:48 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:48 INFO - PROCESS | 1656 | 2017-03-27 09:56:48.622 plugin-container[1657:10332] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.CFPasteboardClient' 09:56:48 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:48 INFO - PROCESS | 1656 | 2017-03-27 09:56:48.622 plugin-container[1657:10332] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:56:58 INFO - PROCESS | 1656 | RSS: Main: 221069312 09:56:58 INFO - PROCESS | 1656 | 09:57:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:02 INFO - PROCESS | 1656 | RSS: Main: 235487232 09:57:02 INFO - PROCESS | 1656 | 09:57:05 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 09:57:05 INFO - PROCESS | 1656 | RSS: Main: 243425280 09:57:05 INFO - PROCESS | 1656 | 09:57:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 09:57:09 INFO - PROCESS | 1656 | RSS: Main: 247242752 09:57:09 INFO - PROCESS | 1656 | 09:57:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 09:57:12 INFO - PROCESS | 1656 | RSS: Main: 247549952 09:57:12 INFO - PROCESS | 1656 | 09:57:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:57:15 INFO - PROCESS | 1656 | RSS: Main: 248856576 09:57:15 INFO - PROCESS | 1656 | 09:57:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 09:57:18 INFO - PROCESS | 1656 | RSS: Main: 252579840 09:57:18 INFO - PROCESS | 1656 | 09:57:21 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 09:57:22 INFO - PROCESS | 1656 | RSS: Main: 252252160 09:57:22 INFO - PROCESS | 1656 | 09:57:25 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 09:57:26 INFO - PROCESS | 1656 | RSS: Main: 252014592 09:57:26 INFO - PROCESS | 1656 | 09:57:28 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:57:28 INFO - PROCESS | 1656 | RSS: Main: 251961344 09:57:28 INFO - PROCESS | 1656 | 09:57:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:57:31 INFO - PROCESS | 1656 | RSS: Main: 251953152 09:57:31 INFO - PROCESS | 1656 | 09:57:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:57:34 INFO - PROCESS | 1656 | RSS: Main: 252026880 09:57:34 INFO - PROCESS | 1656 | 09:57:36 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:57:37 INFO - PROCESS | 1656 | RSS: Main: 252342272 09:57:37 INFO - PROCESS | 1656 | 09:57:40 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:57:41 INFO - PROCESS | 1656 | RSS: Main: 252096512 09:57:41 INFO - PROCESS | 1656 | 09:57:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 09:57:43 INFO - PROCESS | 1656 | RSS: Main: 254025728 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | __start_tp_report 09:57:43 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 09:57:43 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 09:57:43 INFO - PROCESS | 1656 | |i|pagename|runs| 09:57:43 INFO - PROCESS | 1656 | |0;ai-astar;108;120;122;143;177;154;119;132;180;159 09:57:43 INFO - PROCESS | 1656 | |1;audio-beat-detection;171;146;104;178;140;143;114;112;130;113 09:57:43 INFO - PROCESS | 1656 | |2;audio-dft;265;197;194;195;199;199;198;197;196;195 09:57:43 INFO - PROCESS | 1656 | |3;audio-fft;83;62;64;90;67;64;63;63;78;69 09:57:43 INFO - PROCESS | 1656 | |4;audio-oscillator;88;93;86;73;72;71;82;73;91;113 09:57:43 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;89;95;89;93;95;119;91;90;90;91 09:57:43 INFO - PROCESS | 1656 | |6;imaging-darkroom;140;131;97;98;97;98;99;97;98;140 09:57:43 INFO - PROCESS | 1656 | |7;imaging-desaturate;145;92;91;93;141;93;91;92;99;116 09:57:43 INFO - PROCESS | 1656 | |8;json-parse-financial;65;66;65;68;69;68;69;70;69;70 09:57:43 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;58;65;61;64;62;58;58;60;60;59 09:57:43 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;86;84;83;87;88;87;85;96;104;98 09:57:43 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;151;158;137;138;139;149;139;140;138;137 09:57:43 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;169;166;176;168;209;216;152;175;172;153 09:57:43 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;67;69;69;79;65;57;81;86;84;62 09:57:43 INFO - PROCESS | 1656 | __end_tp_report 09:57:43 INFO - PROCESS | 1656 | __start_cc_report 09:57:43 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1006 09:57:43 INFO - PROCESS | 1656 | __end_cc_report 09:57:43 INFO - PROCESS | 1656 | __startTimestamp1490633863245__endTimestamp 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | ------- Summary: start ------- 09:57:43 INFO - PROCESS | 1656 | Number of tests: 14 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:141.40 Median:148.50 stddev:25.32 (17.0%) stddev-sans-first:23.79 09:57:43 INFO - PROCESS | 1656 | Values: 108.0 120.0 122.0 143.0 177.0 154.0 119.0 132.0 180.0 159.0 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:141.50 stddev:25.37 (17.9%) stddev-sans-first:23.35 09:57:43 INFO - PROCESS | 1656 | Values: 171.0 146.0 104.0 178.0 140.0 143.0 114.0 112.0 130.0 113.0 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:203.50 Median:197.50 stddev:21.68 (11.0%) stddev-sans-first:1.80 09:57:43 INFO - PROCESS | 1656 | Values: 265.0 197.0 194.0 195.0 199.0 199.0 198.0 197.0 196.0 195.0 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:70.30 Median:68.00 stddev:9.87 (14.5%) stddev-sans-first:9.33 09:57:43 INFO - PROCESS | 1656 | Values: 83.0 62.0 64.0 90.0 67.0 64.0 63.0 63.0 78.0 69.0 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:84.20 Median:84.00 stddev:13.12 (15.6%) stddev-sans-first:13.85 09:57:43 INFO - PROCESS | 1656 | Values: 88.0 93.0 86.0 73.0 72.0 71.0 82.0 73.0 91.0 113.0 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:91.00 stddev:8.99 (9.9%) stddev-sans-first:9.34 09:57:43 INFO - PROCESS | 1656 | Values: 89.0 95.0 89.0 93.0 95.0 119.0 91.0 90.0 90.0 91.0 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:109.50 Median:97.50 stddev:19.14 (19.6%) stddev-sans-first:16.83 09:57:43 INFO - PROCESS | 1656 | Values: 140.0 131.0 97.0 98.0 97.0 98.0 99.0 97.0 98.0 140.0 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:105.30 Median:92.00 stddev:21.27 (23.1%) stddev-sans-first:17.03 09:57:43 INFO - PROCESS | 1656 | Values: 145.0 92.0 91.0 93.0 141.0 93.0 91.0 92.0 99.0 116.0 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:67.90 Median:69.00 stddev:1.91 (2.8%) stddev-sans-first:1.72 09:57:43 INFO - PROCESS | 1656 | Values: 65.0 66.0 65.0 68.0 69.0 68.0 69.0 70.0 69.0 70.0 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:60.50 stddev:2.51 (4.1%) stddev-sans-first:2.49 09:57:43 INFO - PROCESS | 1656 | Values: 58.0 65.0 61.0 64.0 62.0 58.0 58.0 60.0 60.0 59.0 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:87.00 stddev:7.02 (8.1%) stddev-sans-first:7.31 09:57:43 INFO - PROCESS | 1656 | Values: 86.0 84.0 83.0 87.0 88.0 87.0 85.0 96.0 104.0 98.0 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:142.60 Median:139.50 stddev:7.35 (5.3%) stddev-sans-first:7.14 09:57:43 INFO - PROCESS | 1656 | Values: 151.0 158.0 137.0 138.0 139.0 149.0 139.0 140.0 138.0 137.0 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:173.50 stddev:21.14 (12.2%) stddev-sans-first:22.29 09:57:43 INFO - PROCESS | 1656 | Values: 169.0 166.0 176.0 168.0 209.0 216.0 152.0 175.0 172.0 153.0 09:57:43 INFO - PROCESS | 1656 | 09:57:43 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:74.00 stddev:9.93 (13.4%) stddev-sans-first:10.37 09:57:43 INFO - PROCESS | 1656 | Values: 67.0 69.0 69.0 79.0 65.0 57.0 81.0 86.0 84.0 62.0 09:57:43 INFO - PROCESS | 1656 | -------- Summary: end -------- 09:57:43 INFO - PROCESS | 1656 | 09:57:44 INFO - TEST-INFO | 1656: exit 0 09:57:44 INFO - TEST-OK | kraken | took 61401ms 09:57:44 INFO - SUITE-END | took 1178s 09:57:44 INFO - Completed test suite (00:19:39) 09:57:44 INFO - javascript benchmark 09:57:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7668.443033018247, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3208.8074715517073, 3487.128217945514, 3487.9476807847877, 3498.0, 3571.2857428514303, 4922.753862306884, 5616.52259557937, 6163.969180154093, 6193.907091393627, 6215.19202503675, 5030.924536131952, 6134.0798880167995, 6179.0, 6194.938050619492, 6195.946689062859, 4683.601893839028, 5485.259489968863, 5498.285222921025, 5588.469095435927, 5639.0, 5621.662700237978, 5636.0982242841155, 5637.013522633543, 5642.097264437691, 5648.915266271009, 4628.629709623239, 5299.6820190788485, 5336.466353364672, 5621.156826476021, 5675.5459563235045, 4601.12578610063, 5321.441248668912, 5442.074847275952, 5445.972770136124, 5501.422350653163, 4705.411823522059, 4747.83382581608, 5238.690327418146, 5504.559635229172, 5549.694766787825, 6198.442140207358, 6249.312575616678, 6250.624962502219, 6270.059491076375, 6273.686315684243, 5148.5623721983675, 6334.968325158345, 6423.357664233586, 6581.407673309425, 6586.407223349922, 6325.87348253037, 6341.2707538633185, 6479.611223326616, 6553.377429144223, 6556.704948277339, 6309.022101574264, 6313.96843015782, 6314.273858506239, 6332.176817013805, 6342.936570634327, 5975.282966044103, 6224.84840304536, 6794.660266986631, 6804.591724496546, 6815.454763618898, 6228.28374736902, 6289.308176100625, 6314.494840412756, 6316.30520642729, 6370.840728981812, 5528.861778455571, 6530.8040758777315, 6560.343965603402, 6593.175853018372, 6646.102776125162, 6370.713317900706, 6505.902411463831, 6511.706973186123, 6515.51133664977, 6520.315366886409, 6218.81343559694, 6274.247090349096, 6277.246730392382, 6524.836879077966, 6537.117489139, 5038.773255203525, 5128.692278463304, 5243.0, 5264.368275806928, 5315.176147697113, 4581.977090114595, 4596.931046034312, 4623.468301145344, 4637.188492013884, 4678.602318802872, 6128.969355153285, 6164.537659675542, 6239.937600623935, 6245.781397651152, 6256.906146407808, 6602.372774586406, 6604.570702904296, 6619.139511863427, 6624.735010599522, 6640.302768209365, 5651.4348565143155, 5725.312962444534, 5782.797602083991, 6137.447629713258, 6221.9377806222265, 5756.683382413924, 5800.1299805029585, 5820.708964551755, 5827.825165245049, 5845.444682755132, 6753.831154221184, 6760.121184246115, 6793.320667933167, 6800.421964133104, 6825.863482730417, 6231.376862313732, 6314.0, 6556.114924485133, 6618.966905165636, 6643.667816609247, 6659.134312539339, 6675.532712710064, 6791.762288319885, 6803.795886123424, 6821.215560210441, 5267.431492007059, 5415.539679127318, 6400.103985441949, 6452.22573291208, 6462.612243265419, 6264.68676566179, 6478.805635830932, 6481.967590162019, 6528.575642583407, 6567.44176744982, 6062.969295219911, 6244.344343843831, 6267.1539342188225, 6293.96853015732, 6318.399752023551, 6346.4605508532295, 6351.428371446592, 6355.205599300088, 6371.362863713592, 6372.617642941438, 5534.695591742332, 6206.255249370104, 6278.4035516625845, 6306.117143599808, 6343.238811342669, 6156.907646385218, 6228.0, 6253.96873015632, 6266.404691554295, 6389.9680501597195, 6297.338779428094, 6302.369763023661, 6318.020706790455, 6328.936710632835, 6334.176557047738], "value": 5935.773419714647, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.3984833195119, 761.4061032394734, 828.5898480252276, 866.6836604639309, 970.2432102959694, 37650.62349376505, 38570.80714596427, 39804.2039159217, 40013.19973600526, 40015.0, 38755.22489550207, 38817.61182388175, 38978.61021389785, 39091.0, 39192.804035979854, 7898.921010789891, 8017.438779285452, 8653.524056176908, 10754.784904301909, 10907.0, 10634.78730425391, 10680.359178449318, 10739.194560407961, 10750.838737418906, 10798.190135739811, 10788.46057697112, 10788.46057697116, 10794.892051079467, 10801.297915635498, 10829.512671929782, 10764.192685548536, 10772.192085593611, 10807.891921080767, 10838.295510791851, 10848.18638602108, 10470.057694807429, 10485.737856553571, 10488.528016239288, 10533.157347412189, 10540.0, 33310.666893331, 33340.333193335995, 33420.8302709404, 33481.497777533354, 33519.82680606167, 3687.760295580779, 3695.7782533047834, 3726.6087060858763, 3733.9439908401396, 3786.0345611868797, 23619.0, 23622.0, 24148.913298901592, 24155.154569590155, 24891.25326240216, 3036.1195253376495, 3070.9846450767827, 3159.984200078985, 3217.0831313075896, 3259.5925509311337, 370056.0, 372090.0, 374462.0, 376167.0, 376351.0, 43698.0, 43812.3428148578, 43851.0, 44001.899952500804, 44107.0, 45008.77495612501, 45342.77328613402, 45466.86332841706, 45554.08891822145, 45599.0, 13650.044496885122, 13796.517121900686, 13845.961552883373, 14007.509737159355, 14020.789688154686, 12029.398530073462, 12037.97677197448, 12048.87951120495, 12076.396180190955, 12110.636680899586, 11328.376939268255, 11858.407079645984, 11879.465424055938, 11895.226810257305, 11898.76202475963, 4835.008823191238, 5696.772129114789, 5743.9425605744245, 5744.684042377709, 5795.0, 1106.2145876427664, 1119.6417146513038, 1128.1820680006929, 1150.827375893606, 1162.136458919425, 22171.889140554194, 26579.734202658114, 27179.86410067937, 27180.45639087207, 27207.86396068007, 967.8741763570831, 1013.8492810659955, 1224.9877501225053, 1269.3942113640585, 1286.9420876060599, 12165.20926139798, 14622.926885365505, 14929.954903156675, 15002.549923502313, 15297.46458873934, 494395.0560494421, 503311.0, 574784.1260793669, 575266.0, 618446.0, 39721.20557588832, 40349.394759078634, 45872.54127458682, 46246.768766155954, 46492.302615460794, 1382.1845111384332, 1423.9145651260958, 1430.8569143085608, 1436.1598464897943, 1437.9209143497208, 26234.819433125173, 26369.60445593356, 26720.465590688465, 27084.72915270822, 27239.59140612853, 39853.80438586847, 40036.0, 40088.1982360357, 40160.59839401685, 40184.1963160741, 487609.0, 488423.0, 492036.53981729865, 492631.0, 493964.0, 28774.712252878042, 29231.123066308046, 29269.268268293465, 29550.0, 29553.0, 36539.0, 36947.07632309106, 37091.44362834613, 37227.441588376736, 37258.62741372552, 26238.868805656613, 26476.0, 26801.81969883562, 26901.923923042857, 27200.319992000357, 22224.88887555552, 26073.478530429664, 26278.34304142335, 26368.0, 26801.597976029985, 30047.948321809505, 30973.53539696861, 31154.0, 31265.218369540944, 31392.901248456194, 26264.868675657264, 26331.473370532865, 26589.734102658727, 26691.86654066717, 27386.863065685342, 21582.784172158077, 21691.023903924677, 21953.341399758032, 22225.555488889808, 22355.0], "value": 17952.227709160787, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.68980195447546, 407.8878308465174, 409.4288467587717, 423.56450410141696, 448.22680875489783, 19591.020448977564, 19940.90029549852, 20837.47906302343, 21047.842368669717, 21266.36200913973, 17860.28558857644, 19744.111514981825, 21086.0, 21356.679649805228, 21441.67837482439, 5640.802571909984, 6298.968505157468, 6611.702473388685, 6663.867142585772, 6683.398494135527, 3816.4466152407886, 6341.397567231105, 6450.7742229022, 6453.548251622365, 6549.508786840982, 6545.869082618345, 6554.672266386686, 6577.375149360827, 6583.9012414813815, 6585.143931288926, 6550.0, 7112.217656057829, 7460.403167746567, 7491.887621685679, 7518.8120296992465, 7017.89473157903, 7165.534240274365, 7276.599787011713, 7306.050213472215, 7316.122065352191, 25521.0, 25688.8715556421, 26273.474530509473, 26312.342191445176, 26579.601305980425, 2850.102217801386, 2853.9001134960386, 2911.490489164388, 2949.837758923258, 2974.8363839988792, 12382.442790074378, 12908.290044047573, 16063.91968040164, 16273.430429934895, 16827.242774075195, 1849.4636555398918, 1864.4872660018602, 1908.0841196225751, 1916.7795703493998, 1917.8273955343946, 129053.0, 140414.0, 144308.27845860703, 152181.0, 162248.18875905662, 20753.0, 20936.057877395247, 20955.790442095687, 21036.47408814792, 21581.02885370162, 31737.523937140963, 31815.0, 32321.89907655995, 32356.83821580877, 32423.35153296921, 10206.897931020743, 10333.483325833678, 10339.586416543254, 10455.790884182274, 10637.202209834293, 8702.956485217534, 8743.431676940978, 8791.604377802887, 8943.597538110671, 8985.281177505783, 8656.48061116335, 9042.231410330063, 9565.473898935625, 9635.277354198462, 9674.274429417681, 3750.362438385481, 4040.1920665137445, 4135.242521928169, 4206.831726730897, 4244.236037513216, 888.7778055486128, 897.2059727141527, 898.4609234459272, 926.2358554192817, 933.7432206143229, 20720.896395518226, 21155.0, 21236.362909112442, 21274.893625531775, 21308.680369794467, 770.6493545436813, 909.2044461096541, 925.5557332480447, 942.137943781446, 959.1032384720309, 6774.525783195228, 6799.966000170067, 6882.311768823078, 7095.82260443493, 7112.253213412517, 668707.0, 780143.0, 799392.0, 815216.0, 818402.0, 115563.42218289022, 131359.0, 161646.0, 164826.0, 165476.17261913614, 1317.2820812657126, 1332.80007998799, 1395.2535393564597, 1401.2643362234705, 1402.3128666953323, 17491.737623935398, 19049.523761906064, 19092.0, 19260.422187334403, 19387.071954595165, 62527.06209406954, 62815.0, 64698.35301647112, 65873.67063164653, 66814.0, 91230.54384728034, 92566.0, 93791.0, 94639.58040629259, 95269.0, 11280.0, 11335.263207891461, 11402.372869492257, 11637.53449861996, 11703.76592468163, 12395.442205100611, 12477.688057798629, 12502.062345323955, 12600.936995314965, 12814.615561533168, 10711.678649640524, 10994.0, 11102.666919992413, 11178.217524773188, 11295.943520282675, 10137.290389672948, 10200.795984080425, 10250.538725757508, 10358.792824143324, 10507.42209178472, 11162.832557511481, 11241.156913231376, 11365.0, 11497.310161390344, 11585.768284634429, 10089.596416143271, 11404.030657394214, 11405.600803971822, 11419.31484110956, 11703.23928944616, 10525.57904682858, 10743.623973161215, 10774.353538787698, 10819.891801081889, 10978.341299522055], "value": 11295.11421787528, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1466.2009204983283, 1547.489328521588, 1560.6566555357817, 1589.6741168060546, 1591.5384538483838, 9416.905830941689, 9579.952100239489, 10602.992715692006, 11256.930591593806, 11278.15413843961, 9256.72229833106, 9263.351565390425, 9287.210587100104, 10671.306365086264, 11250.324980501176, 2306.8961896714645, 2399.1123284384716, 2402.7356990731005, 2404.038384646138, 2404.843685160459, 2369.668246445499, 2394.5091256292417, 2403.375122468153, 2406.759324067597, 2410.4937963027787, 2355.316958082154, 2365.4322962488964, 2381.654660074288, 2389.844660097097, 2401.3036219496325, 2352.9058837646476, 2357.4695693469002, 2360.6459031145296, 2366.1954935321987, 2373.979188948743, 2324.1981516376823, 2351.271105957159, 2373.2405630198346, 2381.785639292472, 2398.9760102398927, 11105.611303604335, 11198.608048718337, 11220.214584979054, 11252.26860254081, 11280.153988450898, 5647.124695672177, 5779.8555036124435, 5827.708614569255, 5861.912071318934, 5866.237389139427, 3521.753477256593, 3631.8365673544754, 3664.926701465982, 3690.5940346561856, 3700.0749812546865, 1168.842206302147, 1172.94135293235, 1191.2316555821533, 1211.9394030298538, 1224.577520755338, 12335.568255111028, 13991.440342386293, 15007.174605396698, 15010.774838377323, 15047.398104075824, 4516.187086324493, 4636.095961287584, 4763.809447622126, 4831.541003604652, 4847.95152048475, 12854.614361569167, 12865.807012894815, 12877.484900604059, 12949.935250323688, 12967.027472939379, 5681.715914204356, 5695.715214239354, 5703.8859222816145, 5717.17101020358, 5723.370429252779, 4379.1022840317655, 4809.567138957511, 5115.693058416433, 5169.095408303532, 5239.345081864767, 3583.2475180211927, 4021.7184797063924, 4052.2908491013955, 4127.649149822299, 4156.210320039183, 1141.6289705845634, 1141.7773534160924, 1143.0626885953438, 1158.8030034894089, 1159.2580748321082, 760.9733659321897, 881.4975463985595, 912.3248795890993, 921.488573841511, 930.6789157740569, 6878.518503704692, 6916.412104971033, 6940.861182776316, 6970.372666460043, 7202.1357437106835, 979.2215188924822, 1010.8635334229784, 1029.248648486594, 1038.2887721910515, 1061.1086687182803, 4153.979230103831, 4173.08192197716, 4274.0, 4279.251131052053, 4465.464144302705, 13067.542636007884, 13670.453181872614, 13674.384652690653, 13681.179129252278, 13691.726165476635, 10872.402017888933, 12803.16779409354, 13604.727905441836, 13611.863881361258, 13681.726365472634, 8674.349423793241, 9973.650922217543, 9985.400875947467, 10264.281500294908, 10289.845652315222, 5187.2478490619405, 6577.868442631217, 6591.5056370773345, 6769.086173366532, 6818.931810682028, 9603.663871764455, 12272.140950133406, 12374.75250494967, 12375.504979801068, 12392.0, 10980.231383803057, 12450.0, 12544.623661290176, 12723.300218488072, 12775.488980440678, 8103.594820258845, 8253.33973282124, 9095.272378209595, 9418.340716149803, 9931.503424828874, 9506.619735210514, 9818.410895346302, 10305.896941030494, 10475.790484190426, 10582.0, 8748.65005399777, 8763.167499087576, 8802.911970880465, 8829.028806831371, 8945.016048234818, 8599.785005374915, 8640.870386944327, 8686.652533898827, 8745.781355466164, 8812.559372031501, 7878.527288362717, 7939.6030198491, 7976.7208147714555, 8017.278444939983, 8025.9598702006115, 5147.176451767699, 5225.216217567396, 5296.993571221456, 5301.257823904733, 5342.866428339167, 5834.18321434991, 5847.766089356548, 5900.468957793819, 5963.2844058713235, 6025.78909738157], "value": 5096.016434429575, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.7410845432109, 328.81586311665467, 375.33165081213997, 383.4095492940871, 388.94360317753933, 5405.0, 5484.61607687462, 5494.890102197954, 5520.503154716075, 5525.861853453665, 4476.820927162909, 4494.820207191717, 5055.739048313844, 5219.060569097571, 5228.686278823264, 3477.6000759912554, 3842.5773164951834, 4341.957930096785, 4400.7579583122915, 4414.80133393998, 4226.224985506079, 4242.97878510607, 4420.845270415536, 4438.844640437585, 4441.71128876622, 3900.9804950975204, 4200.852970146046, 4396.56034396561, 4420.602145806877, 4505.234110201273, 3535.381308271042, 3898.532176138853, 3948.822302996372, 4387.473503179607, 4403.405540252057, 3421.6578342165835, 4006.659433948118, 4037.899052523681, 4040.838366465338, 4067.2678917794783, 4050.177420160939, 4883.970724152588, 4960.189530821261, 5265.078611243017, 5274.654962984415, 4509.278515437514, 4588.954110458886, 5004.79788892886, 5094.974525127351, 5141.640085194038, 5096.050560815361, 5298.549623282026, 5537.307836520435, 5655.236543066716, 5729.885402291972, 4079.755214687128, 4550.953280745414, 4561.407017087758, 4579.67942244043, 4593.471750748673, 5305.8938821223355, 5732.939406209823, 5737.340205876336, 5745.856353591152, 6094.659174981497, 3986.6412022918075, 4144.171165766858, 4546.113507866001, 4652.232151782301, 4659.9767001165455, 4438.378626992224, 4677.64917631179, 4744.102359056336, 4808.759562021885, 4809.615230781529, 4834.516548345137, 5023.924641130386, 5145.202493613496, 5152.201408781645, 5193.532582067632, 4743.762811859393, 5388.084025715638, 5430.51125398716, 5665.745041473144, 5684.181062060101, 1337.4716986790163, 1447.7321695486369, 1568.9331254746653, 1602.8317026712261, 1625.617979774752, 1128.26810845352, 1172.3317708906013, 1173.3546549397797, 1205.7709035283267, 1221.4609591914075, 4672.57946784791, 4816.638752093537, 5248.422673505911, 5258.316418865523, 5391.56867450603, 5386.461353864583, 5944.970275148683, 6007.489363404072, 6008.279006519246, 6023.759049638053, 4785.688930219525, 4813.122882075993, 4978.178600530944, 4984.177610694195, 5316.963192177488, 2732.931676708098, 2829.5614179802164, 2957.6746557878614, 3127.124405166557, 3131.4029844779325, 4924.88048060914, 4991.975040124777, 5617.8595535111135, 5751.175468313609, 6121.846953826101, 5200.76596553156, 5420.620556561082, 5696.1266338889955, 6087.239095113111, 6128.9693551531955, 6105.877882442415, 6159.692015399212, 6168.0, 6178.320384757673, 6189.812246692957, 5147.459516750687, 5188.325517682677, 5457.918131227955, 5576.5817563683695, 6031.849203769941, 5143.794248230029, 5297.940411917555, 5445.891082178414, 5849.151872978309, 5913.970430147821, 5214.895702085862, 5698.373178950229, 5982.850428739316, 6105.5124483503705, 6120.265568131675, 4254.446921900133, 4406.515283318897, 4546.954200533962, 4585.6102231310715, 4587.334836448763, 5018.924716129188, 5483.055834400529, 5831.620944638585, 5866.266716660418, 5928.9407105928385, 4846.781894814671, 4923.876903077452, 5625.353084395347, 5634.605577609528, 5737.770489180386, 4953.529414705597, 5214.0, 5432.674039557639, 5508.641938274, 5690.3456102548735, 4515.9774201128785, 4572.565606267399, 4761.381130415675, 5152.355955505562, 5555.8888822224135, 5054.671446355975, 5233.659812112201, 5592.728253971472, 5603.439656034364, 5704.885902282014, 4617.953820461752, 5166.922496162636, 5391.649542779707, 5511.503964643041, 5515.862103447446], "value": 4291.446525737485, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.7526365771977, 715.3597530210465, 792.6433105102706, 832.4963397144726, 834.4826207751195, 14250.501232456865, 15426.768598471032, 15741.685166296667, 16100.503514631824, 16414.753778693314, 14925.253737313145, 15134.621634459116, 15206.391744330242, 15431.69136617267, 15620.531384058468, 862.3316929379749, 920.9861852072207, 952.485657744817, 961.4519723757461, 961.6297725375711, 892.2237653241689, 950.9001554836747, 957.4494665567291, 958.4776296918161, 961.9374740641871, 1011.7521207304184, 1016.7356487313315, 1017.3336464615688, 1019.5666841592331, 1021.0147207944332, 997.7206382212992, 1004.1013293102632, 1011.7217765114578, 1011.8886922438526, 1012.2205901455837, 740.4483659673524, 746.302207436049, 748.636911098117, 750.7935317787652, 751.898493703354, 15933.283002264925, 16424.425145119865, 16652.666946661, 16857.0, 17093.829061709348, 14163.645908852259, 14246.857531424657, 14300.713985720227, 14342.71314573713, 14366.497172598909, 11580.6525804226, 11826.113041521834, 11915.968884704933, 11975.64073077809, 12439.689007774787, 3641.6722494975315, 3644.516151383013, 3649.105969037603, 3660.1032746976894, 3750.5311836020496, 81212.0, 105000.47499762452, 111645.44177279061, 115750.0, 116598.99999999916, 9175.541222938826, 10759.677209683721, 10828.837567436338, 11462.312261264184, 11613.3612651305, 22741.0, 22751.886240568692, 23325.650115248285, 23369.7663023371, 23402.0, 7589.316961473383, 7612.3910087194, 7621.771346859603, 7623.237676232332, 7770.184130666199, 4290.0, 4304.569543045733, 4319.632831209383, 4405.889852753707, 4412.161689279027, 3452.8273586320583, 3480.077779388464, 3542.75200735946, 3563.8574457021427, 3580.5524309461316, 544.4827413956757, 617.9752809887644, 626.6835248199721, 634.2072409488139, 636.1189752193245, 669.2404121322335, 678.4131726057024, 685.1572565744063, 699.6746512871539, 730.2478447199393, 10076.69769906904, 10243.897561024294, 10564.947175264075, 10575.153987680967, 10575.947120264349, 660.2374257732326, 683.6718375179842, 701.9192792828788, 707.6002058836741, 709.3261401668435, 4848.066919513643, 5032.471590482946, 5032.949670503248, 5043.268726034705, 5046.0, 88844.0, 98041.01958980462, 108939.0, 109090.0, 111566.88433115566, 80712.0, 80915.7862632061, 83318.16681833104, 87592.0, 88186.55906720426, 70223.6488817563, 80752.19247807447, 81296.0, 81359.96600085046, 82666.17333826705, 8636.870446943427, 10458.790824183627, 11197.776044478902, 11364.602238921598, 11446.885531144582, 50670.74664626801, 51046.0, 51431.71420714512, 51524.742376287875, 52857.0, 64181.358186419406, 65383.0, 65992.68014639584, 66341.33658663352, 67115.66442167757, 12840.935795320964, 12987.0, 13047.739045219232, 13072.477100915858, 13291.20252784836, 14691.191984440959, 14754.557363279118, 14979.625509362353, 15021.849781502482, 15042.774358384415, 13452.192868427926, 13583.52457663977, 13938.442462301395, 14149.929250353682, 14608.0, 13425.462981480632, 13505.86494135046, 14030.298485075502, 14091.929540352232, 14514.129152250898, 13380.531681391105, 13611.0, 13923.373448194652, 13986.720265594835, 14215.857841421453, 9918.504074796087, 10144.847827282745, 10317.638882639072, 10464.843027354444, 10648.574057037942, 10127.362264394449, 11110.61112861046, 11467.942660286646, 11638.301701897575, 11719.589814356457], "value": 7724.737356768676, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1551.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 120.0, 122.0, 143.0, 177.0, 154.0, 119.0, 132.0, 180.0, 159.0], "value": 141.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 146.0, 104.0, 178.0, 140.0, 143.0, 114.0, 112.0, 130.0, 113.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [265.0, 197.0, 194.0, 195.0, 199.0, 199.0, 198.0, 197.0, 196.0, 195.0], "value": 203.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 62.0, 64.0, 90.0, 67.0, 64.0, 63.0, 63.0, 78.0, 69.0], "value": 70.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 93.0, 86.0, 73.0, 72.0, 71.0, 82.0, 73.0, 91.0, 113.0], "value": 84.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 89.0, 93.0, 95.0, 119.0, 91.0, 90.0, 90.0, 91.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 131.0, 97.0, 98.0, 97.0, 98.0, 99.0, 97.0, 98.0, 140.0], "value": 109.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 92.0, 91.0, 93.0, 141.0, 93.0, 91.0, 92.0, 99.0, 116.0], "value": 105.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 68.0, 69.0, 68.0, 69.0, 70.0, 69.0, 70.0], "value": 67.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 65.0, 61.0, 64.0, 62.0, 58.0, 58.0, 60.0, 60.0, 59.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 83.0, 87.0, 88.0, 87.0, 85.0, 96.0, 104.0, 98.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 158.0, 137.0, 138.0, 139.0, 149.0, 139.0, 140.0, 138.0, 137.0], "value": 142.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 176.0, 168.0, 209.0, 216.0, 152.0, 175.0, 172.0, 153.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 69.0, 79.0, 65.0, 57.0, 81.0, 86.0, 84.0, 62.0], "value": 71.9, "unit": "score"}]}]} 09:57:44 INFO - Return code: 0 09:57:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:57:44 ERROR - # TBPL SUCCESS # 09:57:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:57:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:57:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:57:44 INFO - 2017-03-27 09:57:44.588 screenresolution[1661:10720] starting screenresolution argv=screenresolution get 09:57:44 INFO - 2017-03-27 09:57:44.591 screenresolution[1661:10720] Display 0: 1600x1200x32@60 09:57:44 INFO - 2017-03-27 09:57:44.597 screenresolution[1662:10724] starting screenresolution argv=screenresolution list 09:57:44 INFO - Available Modes on Display 0 09:57:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:57:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:57:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:57:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:57:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:57:44 INFO - Intel Iris: 09:57:44 INFO - Chipset Model: Intel Iris 09:57:44 INFO - Type: GPU 09:57:44 INFO - Bus: Built-In 09:57:44 INFO - VRAM (Dynamic, Max): 1536 MB 09:57:44 INFO - Vendor: Intel (0x8086) 09:57:44 INFO - Device ID: 0x0a2e 09:57:44 INFO - Revision ID: 0x0009 09:57:44 INFO - Displays: 09:57:44 INFO - Display: 09:57:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:57:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:57:44 INFO - Display Serial Number: 1600x1200 60 09:57:44 INFO - Main Display: Yes 09:57:44 INFO - Mirror: Off 09:57:44 INFO - Online: Yes 09:57:44 INFO - Rotation: Supported 09:57:44 INFO - Return code: 0 09:57:44 INFO - Running post-action listener: _resource_record_post_action 09:57:44 INFO - [mozharness: 2017-03-27 16:57:44.832006Z] Finished run-tests step (success) 09:57:44 INFO - Running post-run listener: _resource_record_post_run 09:57:44 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 39803392; Write bytes: 514750464; Read time: 381; Write time: 1832 09:57:44 INFO - TinderboxPrint: CPU usage
54.6% 09:57:44 INFO - TinderboxPrint: I/O read bytes / time
39,803,392 / 381 09:57:44 INFO - TinderboxPrint: I/O write bytes / time
514,750,464 / 1,832 09:57:44 INFO - TinderboxPrint: CPU idle
2,182.1 (45.4%) 09:57:44 INFO - TinderboxPrint: CPU system
963.9 (20.1%) 09:57:44 INFO - TinderboxPrint: CPU user
1,655.5 (34.5%) 09:57:44 INFO - TinderboxPrint: Swap in / out
248,381,440 / 0 09:57:44 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201609728; Write bytes: 201930752; Read time: 15519; Write time: 503 09:57:44 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 37984768; Write bytes: 302051328; Read time: 362; Write time: 1295 09:57:45 INFO - Running post-run listener: _upload_blobber_files 09:57:45 INFO - Blob upload gear active. 09:57:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:57:45 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:57:45 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:57:45 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:57:45 INFO - (blobuploader) - INFO - Open directory for files ... 09:57:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:57:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:57:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:46 INFO - (blobuploader) - INFO - Done attempting. 09:57:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:57:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:57:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:47 INFO - (blobuploader) - INFO - Done attempting. 09:57:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:57:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:57:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:48 INFO - (blobuploader) - INFO - Done attempting. 09:57:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:57:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:57:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:50 INFO - (blobuploader) - INFO - Done attempting. 09:57:50 INFO - (blobuploader) - INFO - Iteration through files over. 09:57:50 INFO - Return code: 0 09:57:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:57:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:57:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6dc0d7973bd535570f7822fea2c4ade92d96fc0efd9fa478ae27971647c51eb319a7a5591257c741d5f8a519c8c32c441b1f248f202a2618ed1657cb61ffa6f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e79da128dd4dd8023c81fcf7ae193acc8e6d6fafd526cc908fabe6c1e0862511e2b53764651ad1e9197a439c9f26fcf768c0d013e2c23862b13c02917fdb4002", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d336c143ff57f81a242d05a3516113ceeb78493d7ab235a4f0e9989d3e3f4656c3778cf941f82cca4b7140eadc19fa8986723a3a19fe8babcb45a810550945d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ab8392522c7e0e63f4970ba182cf2a31318f1b45e2bed65df1bf62c6896d72dd774f924c6dc651b8f43b11c7841937945ee71c2a10790e610e2e615c01d9943"} 09:57:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:57:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:57:50 INFO - Contents: 09:57:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6dc0d7973bd535570f7822fea2c4ade92d96fc0efd9fa478ae27971647c51eb319a7a5591257c741d5f8a519c8c32c441b1f248f202a2618ed1657cb61ffa6f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e79da128dd4dd8023c81fcf7ae193acc8e6d6fafd526cc908fabe6c1e0862511e2b53764651ad1e9197a439c9f26fcf768c0d013e2c23862b13c02917fdb4002", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d336c143ff57f81a242d05a3516113ceeb78493d7ab235a4f0e9989d3e3f4656c3778cf941f82cca4b7140eadc19fa8986723a3a19fe8babcb45a810550945d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ab8392522c7e0e63f4970ba182cf2a31318f1b45e2bed65df1bf62c6896d72dd774f924c6dc651b8f43b11c7841937945ee71c2a10790e610e2e615c01d9943"} 09:57:50 INFO - Running post-run listener: copy_logs_to_upload_dir 09:57:50 INFO - Copying logs to upload dir... 09:57:50 INFO - mkdir: /builds/slave/test/build/upload/logs 09:57:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.844669 ========= master_lag: 0.97 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-27 09:57:51.918377) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 09:57:51.924292) ========= 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.CvS0BccVY9/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.8JsgSBBHlE/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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/6dc0d7973bd535570f7822fea2c4ade92d96fc0efd9fa478ae27971647c51eb319a7a5591257c741d5f8a519c8c32c441b1f248f202a2618ed1657cb61ffa6f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e79da128dd4dd8023c81fcf7ae193acc8e6d6fafd526cc908fabe6c1e0862511e2b53764651ad1e9197a439c9f26fcf768c0d013e2c23862b13c02917fdb4002", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d336c143ff57f81a242d05a3516113ceeb78493d7ab235a4f0e9989d3e3f4656c3778cf941f82cca4b7140eadc19fa8986723a3a19fe8babcb45a810550945d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ab8392522c7e0e63f4970ba182cf2a31318f1b45e2bed65df1bf62c6896d72dd774f924c6dc651b8f43b11c7841937945ee71c2a10790e610e2e615c01d9943"} build_url:https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010900 build_url: 'https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6dc0d7973bd535570f7822fea2c4ade92d96fc0efd9fa478ae27971647c51eb319a7a5591257c741d5f8a519c8c32c441b1f248f202a2618ed1657cb61ffa6f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e79da128dd4dd8023c81fcf7ae193acc8e6d6fafd526cc908fabe6c1e0862511e2b53764651ad1e9197a439c9f26fcf768c0d013e2c23862b13c02917fdb4002", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d336c143ff57f81a242d05a3516113ceeb78493d7ab235a4f0e9989d3e3f4656c3778cf941f82cca4b7140eadc19fa8986723a3a19fe8babcb45a810550945d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ab8392522c7e0e63f4970ba182cf2a31318f1b45e2bed65df1bf62c6896d72dd774f924c6dc651b8f43b11c7841937945ee71c2a10790e610e2e615c01d9943"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 09:57:51.960266) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 09:57:51.960661) ========= 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.CvS0BccVY9/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.8JsgSBBHlE/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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.005293 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 09:57:52.009681) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 09:57:52.010004) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 09:57:52.010407) ========= ========= Total master_lag: 1.11 =========