builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0369 starttime: 1489078739.42 results: success (0) buildid: 20170309072024 builduid: d2f85c978ad44ff4ad9c25a00c43c038 revision: 3d8f875e7af5260119652056404f47e16760ea42 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-09 08:58:59.421969) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-09 08:58:59.422399) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-09 08:58:59.422670) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-09 08:58:59.452256) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 08:58:59.452849) ========= 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.mQdXI66W5S/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.Zab2gp7TIF/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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-09 08:58:59-- 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% 18.8M=0.001s 2017-03-09 08:58:59 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.156386 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 08:58:59.642019) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 08:58:59.642598) ========= 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.mQdXI66W5S/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.Zab2gp7TIF/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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.029879 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 08:58:59.700191) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-09 08:58:59.700545) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3d8f875e7af5260119652056404f47e16760ea42 --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 3d8f875e7af5260119652056404f47e16760ea42 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mQdXI66W5S/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.Zab2gp7TIF/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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-09 08:58:59,811 truncating revision to first 12 chars 2017-03-09 08:58:59,812 Setting DEBUG logging. 2017-03-09 08:58:59,812 attempt 1/10 2017-03-09 08:58:59,812 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3d8f875e7af5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-09 08:59:00,850 unpacking tar archive at: mozilla-aurora-3d8f875e7af5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.328934 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-09 08:59:01.056363) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-09 08:59:01.057094) ========= script_repo_revision: 3d8f875e7af5260119652056404f47e16760ea42 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-09 08:59:01.057635) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-09 08:59:01.057913) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-09 08:59:01.077922) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-09 08:59:01.078497) ========= /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.mQdXI66W5S/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.Zab2gp7TIF/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:59:01 INFO - MultiFileLogger online at 20170309 08:59:01 in /builds/slave/test 08:59:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:59:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:59:01 INFO - {'append_to_log': False, 08:59:01 INFO - 'base_work_dir': '/builds/slave/test', 08:59:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:59:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:59:01 INFO - 'branch': 'Mozilla-Aurora', 08:59:01 INFO - 'buildbot_json_path': 'buildprops.json', 08:59:01 INFO - 'config_files': ('talos/mac_config.py',), 08:59:01 INFO - 'default_actions': ('clobber', 08:59:01 INFO - 'read-buildbot-config', 08:59:01 INFO - 'download-and-extract', 08:59:01 INFO - 'populate-webroot', 08:59:01 INFO - 'create-virtualenv', 08:59:01 INFO - 'install', 08:59:01 INFO - 'run-tests'), 08:59:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:59:01 INFO - 'download_minidump_stackwalk': True, 08:59:01 INFO - 'download_symbols': 'ondemand', 08:59:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:59:01 INFO - 'tooltool.py': '/tools/tooltool.py', 08:59:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:59:01 INFO - '/tools/misc-python/virtualenv.py')}, 08:59:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:59:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:59:01 INFO - 'gecko_profile': False, 08:59:01 INFO - 'gecko_profile_interval': 0, 08:59:01 INFO - 'installer_path': 'installer.exe', 08:59:01 INFO - 'log_level': 'info', 08:59:01 INFO - 'log_name': 'talos', 08:59:01 INFO - 'log_to_console': True, 08:59:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:59:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:59:01 INFO - 'opt_config_files': (), 08:59:01 INFO - 'pip_index': False, 08:59:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:59:01 INFO - 'cmd': ('bash', 08:59:01 INFO - '-c', 08:59:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:59:01 INFO - 'enabled': True, 08:59:01 INFO - 'halt_on_failure': False, 08:59:01 INFO - 'name': 'check_screen_resolution'},), 08:59:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:59:01 INFO - 'cmd': ('bash', 08:59:01 INFO - '-c', 08:59:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:59:01 INFO - 'enabled': True, 08:59:01 INFO - 'halt_on_failure': False, 08:59:01 INFO - 'name': 'check_screen_resolution'},), 08:59:01 INFO - 'run_cmd_checks_enabled': True, 08:59:01 INFO - 'suite': 'dromaeojs-e10s', 08:59:01 INFO - 'system_bits': '32', 08:59:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:59:01 INFO - 'title': 't-yosemite-r7-0369', 08:59:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:59:01 INFO - 'use_talos_json': True, 08:59:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:59:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:59:01 INFO - 'work_dir': 'build'} 08:59:01 INFO - [mozharness: 2017-03-09 16:59:01.300287Z] Running clobber step. 08:59:01 INFO - Running pre-action listener: _resource_record_pre_action 08:59:01 INFO - Running main action method: clobber 08:59:01 INFO - rmtree: /builds/slave/test/build 08:59:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:59:03 INFO - Running post-action listener: _resource_record_post_action 08:59:03 INFO - [mozharness: 2017-03-09 16:59:03.709221Z] Finished clobber step (success) 08:59:03 INFO - [mozharness: 2017-03-09 16:59:03.709373Z] Running read-buildbot-config step. 08:59:03 INFO - Running pre-action listener: _resource_record_pre_action 08:59:03 INFO - Running main action method: read_buildbot_config 08:59:03 INFO - Using buildbot properties: 08:59:03 INFO - { 08:59:03 INFO - "project": "", 08:59:03 INFO - "product": "firefox", 08:59:03 INFO - "script_repo_revision": "production", 08:59:03 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:59:03 INFO - "repository": "", 08:59:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:59:03 INFO - "buildid": "20170309072024", 08:59:03 INFO - "pgo_build": "False", 08:59:03 INFO - "basedir": "/builds/slave/test", 08:59:03 INFO - "buildnumber": 60, 08:59:03 INFO - "slavename": "t-yosemite-r7-0369", 08:59:03 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:59:03 INFO - "platform": "yosemite_r7", 08:59:03 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:59:03 INFO - "branch": "mozilla-aurora", 08:59:03 INFO - "repo_path": "releases/mozilla-aurora", 08:59:03 INFO - "revision": "3d8f875e7af5260119652056404f47e16760ea42", 08:59:03 INFO - "stage_platform": "macosx64", 08:59:03 INFO - "builduid": "d2f85c978ad44ff4ad9c25a00c43c038", 08:59:03 INFO - "slavebuilddir": "test" 08:59:03 INFO - } 08:59:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 08:59:03 INFO - Running post-action listener: _resource_record_post_action 08:59:03 INFO - [mozharness: 2017-03-09 16:59:03.712861Z] Finished read-buildbot-config step (success) 08:59:03 INFO - [mozharness: 2017-03-09 16:59:03.712984Z] Running download-and-extract step. 08:59:03 INFO - Running pre-action listener: _resource_record_pre_action 08:59:03 INFO - Running main action method: download_and_extract 08:59:03 INFO - mkdir: /builds/slave/test/build/tests 08:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:03 INFO - trying https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 08:59:03 INFO - Downloading https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/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 08:59:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/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 08:59:05 INFO - Downloaded 1199 bytes. 08:59:05 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 08:59:05 INFO - Using the following test package requirements: 08:59:05 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 08:59:05 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:59:05 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 08:59:05 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:59:05 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 08:59:05 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:59:05 INFO - u'jsshell-mac.zip'], 08:59:05 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:59:05 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 08:59:05 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 08:59:05 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:59:05 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 08:59:05 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:59:05 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 08:59:05 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:59:05 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 08:59:05 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:59:05 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 08:59:05 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:59:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 08:59:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:59:05 INFO - Fetch https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 08:59:06 INFO - Expected file size: 51777399 08:59:06 INFO - Obtained file size: 51777399 08:59:22 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 08:59:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 08:59:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:59:22 INFO - Fetch https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 08:59:24 INFO - Expected file size: 51777399 08:59:24 INFO - Obtained file size: 51777399 08:59:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 08:59:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:59:40 INFO - Fetch https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 08:59:40 INFO - Expected file size: 14404424 08:59:40 INFO - Obtained file size: 14404424 08:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:41 INFO - trying https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:59:41 INFO - Downloading https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 08:59:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/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 08:59:43 INFO - Downloaded 74616561 bytes. 08:59:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:59:43 INFO - mkdir: /builds/slave/test/properties 08:59:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:59:43 INFO - Writing to file /builds/slave/test/properties/build_url 08:59:43 INFO - Contents: 08:59:43 INFO - build_url:https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:59:44 INFO - Running post-action listener: _resource_record_post_action 08:59:44 INFO - Running post-action listener: set_extra_try_arguments 08:59:44 INFO - [mozharness: 2017-03-09 16:59:44.099829Z] Finished download-and-extract step (success) 08:59:44 INFO - [mozharness: 2017-03-09 16:59:44.099974Z] Running populate-webroot step. 08:59:44 INFO - Running pre-action listener: _resource_record_pre_action 08:59:44 INFO - Running main action method: populate_webroot 08:59:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:59:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:59:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:59:44 INFO - '--tppagecycles', 08:59:44 INFO - '7'], 08:59:44 INFO - 'tests': ['tsvgm']}}, 08:59:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:59:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:59:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:59:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:59:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:59:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:59:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:59:44 INFO - 'talos_options': ['--disable-e10s'], 08:59:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:59:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:59:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:59:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:59:44 INFO - 'talos_options': ['--disable-e10s'], 08:59:44 INFO - 'tests': ['damp', 'tps']}, 08:59:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:59:44 INFO - 'tests': ['damp', 'tps']}, 08:59:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:59:44 INFO - 'tests': ['dromaeo_dom']}, 08:59:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:59:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:59:44 INFO - 'tests': ['basic_compositor_video']}, 08:59:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:59:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:59:44 INFO - 'tests': ['a11yr', 08:59:44 INFO - 'ts_paint', 08:59:44 INFO - 'tpaint', 08:59:44 INFO - 'sessionrestore', 08:59:44 INFO - 'sessionrestore_no_auto_restore', 08:59:44 INFO - 'tabpaint']}, 08:59:44 INFO - 'other-e10s': {'tests': ['a11yr', 08:59:44 INFO - 'ts_paint', 08:59:44 INFO - 'tpaint', 08:59:44 INFO - 'sessionrestore', 08:59:44 INFO - 'sessionrestore_no_auto_restore', 08:59:44 INFO - 'tabpaint']}, 08:59:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:59:44 INFO - 'ts_paint', 08:59:44 INFO - 'tpaint', 08:59:44 INFO - 'sessionrestore', 08:59:44 INFO - 'sessionrestore_no_auto_restore', 08:59:44 INFO - 'tabpaint']}, 08:59:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:59:44 INFO - 'ts_paint', 08:59:44 INFO - 'tpaint', 08:59:44 INFO - 'sessionrestore', 08:59:44 INFO - 'sessionrestore_no_auto_restore', 08:59:44 INFO - 'tabpaint']}, 08:59:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:59:44 INFO - 'tests': ['a11yr', 08:59:44 INFO - 'ts_paint', 08:59:44 INFO - 'tpaint', 08:59:44 INFO - 'sessionrestore', 08:59:44 INFO - 'sessionrestore_no_auto_restore', 08:59:44 INFO - 'tabpaint']}, 08:59:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:59:44 INFO - 'tests': ['a11yr', 08:59:44 INFO - 'ts_paint', 08:59:44 INFO - 'tpaint', 08:59:44 INFO - 'sessionrestore', 08:59:44 INFO - 'sessionrestore_no_auto_restore', 08:59:44 INFO - 'tabpaint']}, 08:59:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:59:44 INFO - 'tests': ['tsvgx', 08:59:44 INFO - 'tsvgr_opacity', 08:59:44 INFO - 'tart', 08:59:44 INFO - 'tscrollx', 08:59:44 INFO - 'cart', 08:59:44 INFO - 'tsvg_static']}, 08:59:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:59:44 INFO - 'tsvgr_opacity', 08:59:44 INFO - 'tart', 08:59:44 INFO - 'tscrollx', 08:59:44 INFO - 'cart', 08:59:44 INFO - 'tsvg_static']}, 08:59:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:59:44 INFO - 'talos_options': ['--disable-e10s'], 08:59:44 INFO - 'tests': ['tp5o']}, 08:59:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:59:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:59:44 INFO - 'talos_options': ['--disable-e10s', 08:59:44 INFO - '--xperf_path', 08:59:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:59:44 INFO - 'tests': ['tp5n']}, 08:59:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:59:44 INFO - 'talos_options': ['--xperf_path', 08:59:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:59:44 INFO - 'tests': ['tp5n']}}, 08:59:44 INFO - 'talos.zip': {'path': '', 08:59:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:59:44 INFO - Running post-action listener: _resource_record_post_action 08:59:44 INFO - [mozharness: 2017-03-09 16:59:44.119021Z] Finished populate-webroot step (success) 08:59:44 INFO - [mozharness: 2017-03-09 16:59:44.119133Z] Running create-virtualenv step. 08:59:44 INFO - Running pre-action listener: _resource_record_pre_action 08:59:44 INFO - Running main action method: create_virtualenv 08:59:44 INFO - Creating virtualenv /builds/slave/test/build/venv 08:59:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:59:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:59:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:59:44 INFO - Using real prefix '/tools/python27' 08:59:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:59:44 INFO - Installing distribute.............................................................................................................................................................................................done. 08:59:47 INFO - Installing pip.................done. 08:59:47 INFO - Return code: 0 08:59:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:59:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:59:48 INFO - Reading from file tmpfile_stdout 08:59:48 INFO - Output received: 08:59:48 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:59:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110555b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110558768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c0554a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110333d30>, '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 0x11025adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110328be8>, '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.mQdXI66W5S/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.Zab2gp7TIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:59:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mQdXI66W5S/Render', 08:59:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:48 INFO - 'HOME': '/Users/cltbld', 08:59:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:48 INFO - 'LOGNAME': 'cltbld', 08:59:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:48 INFO - 'MOZ_NO_REMOTE': '1', 08:59:48 INFO - 'NO_EM_RESTART': '1', 08:59:48 INFO - 'PAGER': '/bin/cat', 08:59:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:48 INFO - 'PWD': '/builds/slave/test', 08:59:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:48 INFO - 'SHELL': '/bin/bash', 08:59:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zab2gp7TIF/Listeners', 08:59:48 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:59:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:48 INFO - 'USER': 'cltbld', 08:59:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:48 INFO - 'XPC_FLAGS': '0x0', 08:59:48 INFO - 'XPC_SERVICE_NAME': '0', 08:59:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:59:48 INFO - Cleaning up... 08:59:48 INFO - Return code: 0 08:59:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110555b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110558768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c0554a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110333d30>, '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 0x11025adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110328be8>, '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.mQdXI66W5S/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.Zab2gp7TIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:59:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mQdXI66W5S/Render', 08:59:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:48 INFO - 'HOME': '/Users/cltbld', 08:59:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:48 INFO - 'LOGNAME': 'cltbld', 08:59:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:48 INFO - 'MOZ_NO_REMOTE': '1', 08:59:48 INFO - 'NO_EM_RESTART': '1', 08:59:48 INFO - 'PAGER': '/bin/cat', 08:59:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:48 INFO - 'PWD': '/builds/slave/test', 08:59:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:48 INFO - 'SHELL': '/bin/bash', 08:59:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zab2gp7TIF/Listeners', 08:59:48 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:59:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:48 INFO - 'USER': 'cltbld', 08:59:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:48 INFO - 'XPC_FLAGS': '0x0', 08:59:48 INFO - 'XPC_SERVICE_NAME': '0', 08:59:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:48 INFO - Downloading/unpacking psutil>=3.1.1 08:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:59:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:59:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:59:52 INFO - Installing collected packages: psutil 08:59:52 INFO - Running setup.py install for psutil 08:59:52 INFO - building 'psutil._psutil_osx' extension 08:59:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:59:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:59:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:59:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:59:53 INFO - building 'psutil._psutil_posix' extension 08:59:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:59:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:59:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:59:53 INFO - ^ 08:59:53 INFO - 1 warning generated. 08:59:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:59:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:59:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:59:53 INFO - Successfully installed psutil 08:59:53 INFO - Cleaning up... 08:59:53 INFO - Return code: 0 08:59:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110555b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110558768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c0554a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110333d30>, '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 0x11025adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110328be8>, '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.mQdXI66W5S/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.Zab2gp7TIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:59:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mQdXI66W5S/Render', 08:59:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:53 INFO - 'HOME': '/Users/cltbld', 08:59:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:53 INFO - 'LOGNAME': 'cltbld', 08:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:53 INFO - 'MOZ_NO_REMOTE': '1', 08:59:53 INFO - 'NO_EM_RESTART': '1', 08:59:53 INFO - 'PAGER': '/bin/cat', 08:59:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:53 INFO - 'PWD': '/builds/slave/test', 08:59:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:53 INFO - 'SHELL': '/bin/bash', 08:59:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zab2gp7TIF/Listeners', 08:59:53 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:59:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:53 INFO - 'USER': 'cltbld', 08:59:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:53 INFO - 'XPC_FLAGS': '0x0', 08:59:53 INFO - 'XPC_SERVICE_NAME': '0', 08:59:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:59:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:59:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:59:56 INFO - Installing collected packages: mozsystemmonitor 08:59:56 INFO - Running setup.py install for mozsystemmonitor 08:59:56 INFO - Successfully installed mozsystemmonitor 08:59:56 INFO - Cleaning up... 08:59:57 INFO - Return code: 0 08:59:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110555b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110558768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c0554a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110333d30>, '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 0x11025adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110328be8>, '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.mQdXI66W5S/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.Zab2gp7TIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:59:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mQdXI66W5S/Render', 08:59:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:57 INFO - 'HOME': '/Users/cltbld', 08:59:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:57 INFO - 'LOGNAME': 'cltbld', 08:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:57 INFO - 'MOZ_NO_REMOTE': '1', 08:59:57 INFO - 'NO_EM_RESTART': '1', 08:59:57 INFO - 'PAGER': '/bin/cat', 08:59:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:57 INFO - 'PWD': '/builds/slave/test', 08:59:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:57 INFO - 'SHELL': '/bin/bash', 08:59:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zab2gp7TIF/Listeners', 08:59:57 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:59:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:57 INFO - 'USER': 'cltbld', 08:59:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:57 INFO - 'XPC_FLAGS': '0x0', 08:59:57 INFO - 'XPC_SERVICE_NAME': '0', 08:59:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:57 INFO - Downloading/unpacking jsonschema==2.5.1 08:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:00 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:00 INFO - warning: no files found matching '*.txt' 09:00:00 INFO - no previously-included directories found matching 'build' 09:00:00 INFO - no previously-included directories found matching 'dist' 09:00:00 INFO - no previously-included directories found matching '.git*' 09:00:00 INFO - Installing collected packages: jsonschema, functools32 09:00:01 INFO - Running setup.py install for functools32 09:00:01 INFO - warning: no files found matching '*.txt' 09:00:01 INFO - no previously-included directories found matching 'build' 09:00:01 INFO - no previously-included directories found matching 'dist' 09:00:01 INFO - no previously-included directories found matching '.git*' 09:00:01 INFO - Successfully installed jsonschema functools32 09:00:01 INFO - Cleaning up... 09:00:01 INFO - Return code: 0 09:00:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:00:01 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:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:01 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:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:01 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 0x110555b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110558768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c0554a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110333d30>, '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 0x11025adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110328be8>, '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.mQdXI66W5S/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.Zab2gp7TIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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:00:01 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:00:01 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:00:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mQdXI66W5S/Render', 09:00:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:01 INFO - 'HOME': '/Users/cltbld', 09:00:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:01 INFO - 'LOGNAME': 'cltbld', 09:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:01 INFO - 'MOZ_NO_REMOTE': '1', 09:00:01 INFO - 'NO_EM_RESTART': '1', 09:00:01 INFO - 'PAGER': '/bin/cat', 09:00:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:01 INFO - 'PWD': '/builds/slave/test', 09:00:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:01 INFO - 'SHELL': '/bin/bash', 09:00:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zab2gp7TIF/Listeners', 09:00:01 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 09:00:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:01 INFO - 'USER': 'cltbld', 09:00:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:01 INFO - 'XPC_FLAGS': '0x0', 09:00:01 INFO - 'XPC_SERVICE_NAME': '0', 09:00:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:00:01 INFO - Cleaning up... 09:00:01 INFO - Return code: 0 09:00:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00:01 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:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:01 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:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:01 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 0x110555b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110558768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c0554a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110333d30>, '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 0x11025adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110328be8>, '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.mQdXI66W5S/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.Zab2gp7TIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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:00:01 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:00:01 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:00:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mQdXI66W5S/Render', 09:00:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:01 INFO - 'HOME': '/Users/cltbld', 09:00:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:01 INFO - 'LOGNAME': 'cltbld', 09:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:01 INFO - 'MOZ_NO_REMOTE': '1', 09:00:01 INFO - 'NO_EM_RESTART': '1', 09:00:01 INFO - 'PAGER': '/bin/cat', 09:00:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:01 INFO - 'PWD': '/builds/slave/test', 09:00:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:01 INFO - 'SHELL': '/bin/bash', 09:00:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zab2gp7TIF/Listeners', 09:00:01 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 09:00:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:01 INFO - 'USER': 'cltbld', 09:00:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:01 INFO - 'XPC_FLAGS': '0x0', 09:00:01 INFO - 'XPC_SERVICE_NAME': '0', 09:00:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:01 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:01 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:00:01 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:00:01 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:00:01 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:00:01 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:00:01 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:00:04 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00:04 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:00:04 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:00:04 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:00:04 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:00:04 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:00:04 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:00:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:05 INFO - Downloading docopt-0.6.1.tar.gz 09:00:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:05 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:05 INFO - Running setup.py install for blobuploader 09:00:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:05 INFO - Running setup.py install for requests 09:00:06 INFO - Running setup.py install for docopt 09:00:06 INFO - Successfully installed blobuploader requests docopt 09:00:06 INFO - Cleaning up... 09:00:06 INFO - Return code: 0 09:00:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:06 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 0x110555b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110558768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c0554a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110333d30>, '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 0x11025adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110328be8>, '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.mQdXI66W5S/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.Zab2gp7TIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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:00:06 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:00:06 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:00:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mQdXI66W5S/Render', 09:00:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:06 INFO - 'HOME': '/Users/cltbld', 09:00:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:06 INFO - 'LOGNAME': 'cltbld', 09:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:06 INFO - 'MOZ_NO_REMOTE': '1', 09:00:06 INFO - 'NO_EM_RESTART': '1', 09:00:06 INFO - 'PAGER': '/bin/cat', 09:00:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:06 INFO - 'PWD': '/builds/slave/test', 09:00:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:06 INFO - 'SHELL': '/bin/bash', 09:00:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zab2gp7TIF/Listeners', 09:00:06 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 09:00:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:06 INFO - 'USER': 'cltbld', 09:00:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:06 INFO - 'XPC_FLAGS': '0x0', 09:00:06 INFO - 'XPC_SERVICE_NAME': '0', 09:00:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:06 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-K27trN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:07 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-wMFrQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:07 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-YrD7O6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:07 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-RJ6tN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:07 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-LYFKD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:07 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-hDDwJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:07 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-4J_fVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:07 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-6XNmWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:07 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-5RK_W7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:07 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-hnkn1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:08 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-6t4779-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:08 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-KT_OmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:08 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-6z0KNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:08 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-K_gF5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:08 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-i6bBnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:08 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-HDE6iL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:08 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-eJlbui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:08 INFO - Running setup.py install for manifestparser 09:00:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:08 INFO - Running setup.py install for mozcrash 09:00:09 INFO - Running setup.py install for mozdebug 09:00:09 INFO - Running setup.py install for mozdevice 09:00:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:09 INFO - Running setup.py install for mozfile 09:00:09 INFO - Running setup.py install for mozhttpd 09:00:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:09 INFO - Running setup.py install for mozinfo 09:00:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:09 INFO - Running setup.py install for mozInstall 09:00:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:09 INFO - Running setup.py install for mozleak 09:00:10 INFO - Running setup.py install for mozlog 09:00:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:10 INFO - Running setup.py install for moznetwork 09:00:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:10 INFO - Running setup.py install for mozprocess 09:00:10 INFO - Running setup.py install for mozprofile 09:00:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:10 INFO - Running setup.py install for mozrunner 09:00:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:10 INFO - Running setup.py install for mozscreenshot 09:00:11 INFO - Running setup.py install for moztest 09:00:11 INFO - Running setup.py install for mozversion 09:00:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:00:11 INFO - Cleaning up... 09:00:11 INFO - Return code: 0 09:00:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:11 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 0x110555b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110558768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c0554a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110333d30>, '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 0x11025adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110328be8>, '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.mQdXI66W5S/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.Zab2gp7TIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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:00:11 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:00:11 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:00:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mQdXI66W5S/Render', 09:00:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:11 INFO - 'HOME': '/Users/cltbld', 09:00:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:11 INFO - 'LOGNAME': 'cltbld', 09:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:11 INFO - 'MOZ_NO_REMOTE': '1', 09:00:11 INFO - 'NO_EM_RESTART': '1', 09:00:11 INFO - 'PAGER': '/bin/cat', 09:00:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:11 INFO - 'PWD': '/builds/slave/test', 09:00:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:11 INFO - 'SHELL': '/bin/bash', 09:00:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zab2gp7TIF/Listeners', 09:00:11 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 09:00:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:11 INFO - 'USER': 'cltbld', 09:00:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:11 INFO - 'XPC_FLAGS': '0x0', 09:00:11 INFO - 'XPC_SERVICE_NAME': '0', 09:00:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:11 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-2NTT5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:11 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-Ryv0hB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:11 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-lbWEnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:11 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-nlZ0lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:11 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:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:12 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-qeEBpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:12 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:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:12 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-TPzjF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:12 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:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:12 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-hqqT6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:12 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:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:12 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-No0Wyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:12 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:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:12 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-cF0fuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:12 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:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:12 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-GbtWvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:12 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-QqewAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:12 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-Bwe_CM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:12 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:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:12 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-iJgYSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:12 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-TUegYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:13 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:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:13 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-nwu6Gr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:13 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:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:13 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-qjr17O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:13 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:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:13 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-e3QWgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:13 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:00:13 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:00:13 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:00:13 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:00:13 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:00:13 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:00:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:13 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:00:13 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:00:13 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:00:13 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:00:13 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:00:13 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:00:15 INFO - Downloading blessings-1.6.tar.gz 09:00:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:00:16 INFO - Installing collected packages: blessings 09:00:16 INFO - Running setup.py install for blessings 09:00:16 INFO - Successfully installed blessings 09:00:16 INFO - Cleaning up... 09:00:16 INFO - Return code: 0 09:00:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:00:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:16 INFO - Reading from file tmpfile_stdout 09:00:16 INFO - Current package versions: 09:00:16 INFO - blessings == 1.6 09:00:16 INFO - blobuploader == 1.2.4 09:00:16 INFO - docopt == 0.6.1 09:00:16 INFO - functools32 == 3.2.3-2 09:00:16 INFO - jsonschema == 2.5.1 09:00:16 INFO - manifestparser == 1.1 09:00:16 INFO - mozInstall == 1.12 09:00:16 INFO - mozcrash == 1.0 09:00:16 INFO - mozdebug == 0.1 09:00:16 INFO - mozdevice == 0.49 09:00:16 INFO - mozfile == 1.2 09:00:16 INFO - mozhttpd == 0.7 09:00:16 INFO - mozinfo == 0.9 09:00:16 INFO - mozleak == 0.1 09:00:16 INFO - mozlog == 3.4 09:00:16 INFO - moznetwork == 0.27 09:00:16 INFO - mozprocess == 0.25 09:00:16 INFO - mozprofile == 0.28 09:00:16 INFO - mozrunner == 6.13 09:00:16 INFO - mozscreenshot == 0.1 09:00:16 INFO - mozsystemmonitor == 0.3 09:00:16 INFO - moztest == 0.8 09:00:16 INFO - mozversion == 1.4 09:00:16 INFO - psutil == 3.1.1 09:00:16 INFO - requests == 1.2.3 09:00:16 INFO - wsgiref == 0.1.2 09:00:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110555b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110558768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c0554a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110333d30>, '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 0x11025adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110328be8>, '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.mQdXI66W5S/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.Zab2gp7TIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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:00:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:00:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:00:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mQdXI66W5S/Render', 09:00:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:16 INFO - 'HOME': '/Users/cltbld', 09:00:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:16 INFO - 'LOGNAME': 'cltbld', 09:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:16 INFO - 'MOZ_NO_REMOTE': '1', 09:00:16 INFO - 'NO_EM_RESTART': '1', 09:00:16 INFO - 'PAGER': '/bin/cat', 09:00:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:16 INFO - 'PWD': '/builds/slave/test', 09:00:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:16 INFO - 'SHELL': '/bin/bash', 09:00:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zab2gp7TIF/Listeners', 09:00:16 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 09:00:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:16 INFO - 'USER': 'cltbld', 09:00:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:16 INFO - 'XPC_FLAGS': '0x0', 09:00:16 INFO - 'XPC_SERVICE_NAME': '0', 09:00:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:00:16 INFO - Cleaning up... 09:00:16 INFO - Return code: 0 09:00:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110555b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110558768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c0554a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110333d30>, '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 0x11025adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110328be8>, '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.mQdXI66W5S/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.Zab2gp7TIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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:00:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:00:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:00:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mQdXI66W5S/Render', 09:00:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:16 INFO - 'HOME': '/Users/cltbld', 09:00:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:16 INFO - 'LOGNAME': 'cltbld', 09:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:16 INFO - 'MOZ_NO_REMOTE': '1', 09:00:16 INFO - 'NO_EM_RESTART': '1', 09:00:16 INFO - 'PAGER': '/bin/cat', 09:00:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:16 INFO - 'PWD': '/builds/slave/test', 09:00:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:16 INFO - 'SHELL': '/bin/bash', 09:00:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zab2gp7TIF/Listeners', 09:00:16 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 09:00:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:16 INFO - 'USER': 'cltbld', 09:00:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:16 INFO - 'XPC_FLAGS': '0x0', 09:00:16 INFO - 'XPC_SERVICE_NAME': '0', 09:00:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:00:17 INFO - Cleaning up... 09:00:17 INFO - Return code: 0 09:00:17 INFO - Running post-action listener: _resource_record_post_action 09:00:17 INFO - Running post-action listener: _start_resource_monitoring 09:00:17 INFO - Starting resource monitoring. 09:00:17 INFO - [mozharness: 2017-03-09 17:00:17.234173Z] Finished create-virtualenv step (success) 09:00:17 INFO - [mozharness: 2017-03-09 17:00:17.235117Z] Running install step. 09:00:17 INFO - Running pre-action listener: _resource_record_pre_action 09:00:17 INFO - Running main action method: install 09:00:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:17 INFO - Reading from file tmpfile_stdout 09:00:17 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:17 INFO - Reading from file tmpfile_stdout 09:00:17 INFO - Output received: 09:00:17 INFO - Usage: mozinstall [options] installer 09:00:17 INFO - Options: 09:00:17 INFO - -h, --help show this help message and exit 09:00:17 INFO - -d DEST, --destination=DEST 09:00:17 INFO - Directory to install application into. [default: 09:00:17 INFO - "/builds/slave/test"] 09:00:17 INFO - --app=APP Application being installed. [default: firefox] 09:00:17 INFO - mkdir: /builds/slave/test/build/application 09:00:17 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:00:17 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:00:38 INFO - Reading from file tmpfile_stdout 09:00:38 INFO - Output received: 09:00:38 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:00:38 INFO - Running post-action listener: _resource_record_post_action 09:00:38 INFO - [mozharness: 2017-03-09 17:00:38.498750Z] Finished install step (success) 09:00:38 INFO - [mozharness: 2017-03-09 17:00:38.498888Z] Running run-tests step. 09:00:38 INFO - Running pre-action listener: _resource_record_pre_action 09:00:38 INFO - Running main action method: run_tests 09:00:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:00:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:00:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:00:38 INFO - 2017-03-09 09:00:38.513 screenresolution[1624:7494] starting screenresolution argv=screenresolution get 09:00:38 INFO - 2017-03-09 09:00:38.516 screenresolution[1624:7494] Display 0: 1600x1200x32@60 09:00:38 INFO - 2017-03-09 09:00:38.521 screenresolution[1625:7497] starting screenresolution argv=screenresolution list 09:00:38 INFO - Available Modes on Display 0 09:00:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:00:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:00:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:00:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:00:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:00:38 INFO - Intel Iris: 09:00:38 INFO - Chipset Model: Intel Iris 09:00:38 INFO - Type: GPU 09:00:38 INFO - Bus: Built-In 09:00:38 INFO - VRAM (Dynamic, Max): 1536 MB 09:00:38 INFO - Vendor: Intel (0x8086) 09:00:38 INFO - Device ID: 0x0a2e 09:00:38 INFO - Revision ID: 0x0009 09:00:38 INFO - Displays: 09:00:38 INFO - Display: 09:00:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:00:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:00:38 INFO - Display Serial Number: 1600x1200 60 09:00:38 INFO - Main Display: Yes 09:00:38 INFO - Mirror: Off 09:00:38 INFO - Online: Yes 09:00:38 INFO - Rotation: Supported 09:00:38 INFO - Return code: 0 09:00:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:00:38 INFO - Python 2.7.3 09:00:38 INFO - Return code: 0 09:00:38 INFO - grabbing minidump binary from tooltool 09:00: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:00:38 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 0x110333d30>, '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 0x11025adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110328be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:00:38 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:00:38 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:00:38 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:00:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:00:38 INFO - Return code: 0 09:00:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:00:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:00:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:00:38 INFO - ENV: RUST_BACKTRACE is now 1 09:00:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:00:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:00:38 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/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0369', '--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:00:38 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/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0369 --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:00:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mQdXI66W5S/Render', 09:00:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:38 INFO - 'HOME': '/Users/cltbld', 09:00:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:38 INFO - 'LOGNAME': 'cltbld', 09:00:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:00:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:38 INFO - 'MOZ_NO_REMOTE': '1', 09:00:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:00:38 INFO - 'NO_EM_RESTART': '1', 09:00:38 INFO - 'PAGER': '/bin/cat', 09:00:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:38 INFO - 'PWD': '/builds/slave/test', 09:00:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:00:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:38 INFO - 'RUST_BACKTRACE': '1', 09:00:38 INFO - 'SHELL': '/bin/bash', 09:00:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zab2gp7TIF/Listeners', 09:00:38 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 09:00:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:38 INFO - 'USER': 'cltbld', 09:00:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:38 INFO - 'XPC_FLAGS': '0x0', 09:00:38 INFO - 'XPC_SERVICE_NAME': '0', 09:00:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:38 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/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0369', '--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:00:39 INFO - mozversion application_buildid: 20170309072024 09:00:39 INFO - mozversion application_changeset: 3d8f875e7af5260119652056404f47e16760ea42 09:00:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:00:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:00:39 INFO - mozversion application_name: Firefox 09:00:39 INFO - mozversion application_remotingname: firefox-dev 09:00:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:00:39 INFO - mozversion application_vendor: Mozilla 09:00:39 INFO - mozversion application_version: 54.0a2 09:00:39 INFO - mozversion platform_buildid: 20170309072024 09:00:39 INFO - mozversion platform_changeset: 3d8f875e7af5260119652056404f47e16760ea42 09:00:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:00:39 INFO - mozversion platform_version: 54.0a2 09:00:39 INFO - using testdate: 1489078839 09:00:39 INFO - actual date: 1489078839 09:00:39 INFO - starting webserver on 'localhost:49236' 09:00:39 INFO - SUITE-START | Running 2 tests 09:00:39 INFO - TEST-START | dromaeo_css 09:00:39 INFO - operating with platform_type : mac_ 09:00:39 INFO - Initialising browser for dromaeo_css test... 09:00:39 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmpjxteMB/profile http://localhost:49236/getInfo.html) 09:00:42 INFO - PROCESS | 1635 | 2017-03-09 09:00:42.032 plugin-container[1637:7680] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913b, name = 'com.apple.tsm.portname' 09:00:42 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:42 INFO - PROCESS | 1635 | 2017-03-09 09:00:42.037 plugin-container[1637:7680] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970f, name = 'com.apple.CFPasteboardClient' 09:00:42 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:42 INFO - PROCESS | 1635 | 2017-03-09 09:00:42.037 plugin-container[1637:7680] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:00:42 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 09:00:42 INFO - PROCESS | 1635 | colorDepth:24 09:00:42 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 09:00:42 INFO - PROCESS | 1635 | __metrics 09:00:42 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:00:42 INFO - TEST-INFO | 1635: exit 0 09:00:42 INFO - Browser initialized. 09:00:42 INFO - Running cycle 1/1 for dromaeo_css test... 09:00:42 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmpjxteMB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:00:45 INFO - PROCESS | 1638 | 2017-03-09 09:00:45.438 plugin-container[1639:7810] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 09:00:45 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:45 INFO - PROCESS | 1638 | 2017-03-09 09:00:45.439 plugin-container[1639:7810] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8847, name = 'com.apple.CFPasteboardClient' 09:00:45 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:45 INFO - PROCESS | 1638 | 2017-03-09 09:00:45.439 plugin-container[1639:7810] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:00:55 INFO - PROCESS | 1638 | RSS: Main: 220094464 09:00:55 INFO - PROCESS | 1638 | 09:03:59 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 09:04:00 INFO - PROCESS | 1638 | RSS: Main: 237989888 09:04:00 INFO - PROCESS | 1638 | 09:05:43 INFO - PROCESS | 1638 | ************************* 09:05:43 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 09:05:43 INFO - PROCESS | 1638 | 09:05:43 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:05:43 INFO - PROCESS | 1638 | Full stack: 09:05:43 INFO - PROCESS | 1638 | ************************* 09:07:03 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 09:07:03 INFO - PROCESS | 1638 | RSS: Main: 247865344 09:07:03 INFO - PROCESS | 1638 | 09:10:05 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 09:10:06 INFO - PROCESS | 1638 | RSS: Main: 254914560 09:10:06 INFO - PROCESS | 1638 | 09:13:08 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 09:13:08 INFO - PROCESS | 1638 | RSS: Main: 251183104 09:13:08 INFO - PROCESS | 1638 | 09:16:12 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 09:16:12 INFO - PROCESS | 1638 | RSS: Main: 256249856 09:16:12 INFO - PROCESS | 1638 | 09:19:15 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 09:19:15 INFO - PROCESS | 1638 | RSS: Main: 252731392 09:19:15 INFO - PROCESS | 1638 | 09:19:15 INFO - PROCESS | 1638 | __start_tp_report 09:19:15 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 09:19:15 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 09:19:15 INFO - PROCESS | 1638 | |i|pagename|runs| 09:19:15 INFO - PROCESS | 1638 | |0;dojo.html;3253.7722359434842;3480.8085555294465;3516.173699180694;3518.524999125118;3534;5541.6674999499955;5659.547236221103;6084.269887613487;6115.571909966304;6210.627362358262;4729.858104256869;6155.476784473325;6161.845953851145;6232.968835155829;6338.112664227012;5514.641548299358;5564.1931919871795;5572.91328191003;5581.413951535092;5650.434956504358;5638.323401191863;5638.4361563843695;5640.323161220639;5642.0126477866415;5680.233168522238;5412.9458705413035;5416.566674666017;5587.469190426903;5624.91562626561;5646.548276137899;5555.833325000256;5582.078956972095;5621.634593751393;5672.744726487318;5698.715064246771;5448.482394172587;5564.081926482126;5564.27664403629;5565.888682226333;5606.327240731098;5943.851403714899;6202.224721909762;6236.220472440945;6248.50011999039;6312.242530896322;6346.270178929436;6361.268454127789;6375.904361434582;6388.616682999035;6394.168758061469;6516.185476815398;6585.407313341774;6606.240282367493;6607.735690572371;6626.271110177925;6326.272478664966;6334.588251763713;6344.333844946306;6348.936510634927;6359.618422894641;6323.7786677466065;6452.903206451907;6529.379708927644;6636;6655.900161497581;6421.197350331208;6426.614403135827;6440.098386225932;6441.162648855712;6458.644774537445;6492.188476440445;6592.538522303393;6610.537262391586;6613.371729685672;6614.834629134214;5331.253624492574;6324.462420694293;6350.888594495945;6362.872742545123;6485.416312531895;5180.32655754757;5911.669874278339;6018.368071352533;6430.678466076677;6514.315996820265;5211.687298762011;5227.424983251839;5242.606804489678;5274.12976858814;5286.9471305287225;4681.789319480631;4687.929681054787;4695.81216751333;4716.080364328992;4767.4994125617;6041.063635136561;6051.062085376773;6073.726682299219;6080.969595151996;6202.503799696014;6487.870242595121;6516.609003459807;6578.04618330349;6601.70292336846;6607.80176594703;6215.34738852414;6227.097070924781;6230.43926046658;6230.6573138476915;6240.095186197976;5628.212050312959;5766.971165144148;5809.564282678816;5819.53443724501;5852.824415267549;6549.803505894831;6680.365365290289;6728.6972086255255;6736.831579210363;6749.055132281583;6502.902456463063;6608.372204640552;6633.005049242653;6661.900071498834;6696.229933557704;6772.458203343819;6829.863402732017;6846.554973926679;6854.314568543105;6854.417374523221;6291.528135389773;6351.428371446592;6365.204349456318;6379.6810159490915;6392.648404337806;5509.311336082989;6099.725512352044;6549.115869357575;6593.439557637655;6677.332266773478;6337.112804207507;6350.697637228523;6377.553571250153;6383.425491705769;6420.55056146084;6246.781362652286;6298.464630506458;6311.431971122621;6331.5251356147555;6382.489400848014;5416.7562459688625;5633.464820842013;6314.305426403;6358.046293056079;6368.64972426521;6284.811455656338;6395.456386207225;6395.488360931207;6412.647304398116;6423.9036414452885;5745.3105627324985;6281.905771413341;6290.559660823698;6314.810555683337;6357.205349331333 09:19:15 INFO - PROCESS | 1638 | |1;ext.html;613.3651670521011;679.7281087564974;765.5865832450477;911.530561760693;912.0788004115841;38513.614863851355;39151.41272880905;40736.38895416564;40799.79600101995;40883.18233635325;39312.21375572494;39550;39569.208615827665;39596.20807583847;39730;7773.689052437896;10506.842397364007;10905.291156074869;10933.835992460079;10938.617148399808;10660.680179594625;10736.194785391088;10754.300970436936;10759.569617215302;10771.461426928661;9082.818343633156;10759.139268858511;10769.299995500307;10801.67594972152;10802.783944321109;10728.141748660088;10738.194635402337;10755.83866242007;10761.946190269076;10787.78424431507;10604.575816967312;10664.573417063308;10673.146148308195;10682.679519614425;10689.9465502672;33651.6634833651;33710.32579348424;33796.81711140098;33865.830670846735;34067.97796066122;3209.406259841913;3705.314516814398;3771.113788259757;3776.811159442017;3949.0522274653877;24629.876850615634;24670.383240418952;25087.62368564473;25897.611535826978;26032;3062.2344413896526;3069.125299289714;3080.799748016394;3092.567040614316;3105.8757649693985;334395;335991;337496;337900.3104984484;338102;44831.55168448339;44900.65298041064;45090.098198035856;45113.87215319578;45532;46738.766306168254;46902.82742931386;46970.5302946973;47049.5295047052;47097;13724.794128088086;13827.723445531034;14054.227017514135;14086.859131408555;14089.85910140906;10173.542190601293;11900.345480998454;12120.45457954394;12134.939325303494;12179.817302740466;9141.771455713528;9302.995806735145;9656.37233579815;10348.896511034944;11495.42522873853;5073.797048118107;5370.872116855504;5649.435056494318;5682.119271512922;5708.571857110734;1005.3867141043958;1009.9446078847621;1059.7244716373643;1081.3674000709673;1095.9287646302964;21194.15223391047;22964.4258893529;27300.77146528411;28087.71912280851;28350.724217410265;993.8918106311411;1077.4397313397146;1125.5610311978335;1161.4192903548226;1178.7347846734588;14205.21871297068;14421.567352979428;15055.02142360743;15164.772528412082;15331.770023449657;391929.08070919494;429361;488196;579518;580354;37573.06067348338;41569.37645935313;42449.36325955113;47040.2943955841;47368.76315618469;1388.8888888889066;1406.535843171751;1426.621945184527;1431.3487363249697;1432.5917113622777;24870.751292486842;26727.06455274056;27190.456190876466;27358.726412735617;27938.882444701983;41638.37542436805;41790.32838686419;41844.79077604593;41931.7903410481;41962.79018604887;413269.9336503298;488800;489700;492803;495347;29739.10782676523;30044.699553005066;30069;30335.241618959702;30377.69622303749;37484.43773343347;38518.614813851505;38544.6145538541;38780.41829372505;40373;33079;33112.83443582863;33390.165245869044;34119.31761364808;34180.14549636279;31559.89540366076;32462.35075298528;32711.67288327086;32949.83525082359;32994.17514562155;32476.837615811768;33046.504302435;33225;33232;33861.66138338585;27700.58449123305;28768.568471472525;29929.700702993563;30067.849660751555;30258.697413025588;19314;22036.33890983273;22840.62956222632;23239.070437182323;23358.883205583865 09:19:15 INFO - PROCESS | 1638 | |2;jquery.html;386.2487461886632;406.56497547624053;425.1136380646352;436.51328768423207;444.7576071041282;19317.903410482944;19410.708839367402;20158.395248142544;20816.47958801027;20852.582948341023;21145;21267.680984785202;21801.236956706518;22161;22161.33515994519;6393.296737358898;6760.0873882026035;6760.3577660122455;6771.052052712625;6777.93222067778;5720.227769251161;6382.489400847921;6586.571872828251;6636.336366363374;6653.135092437977;6240.812775616716;6677.399034086931;6681.164854393201;6682.131322928012;6689.6989635466525;6419.51853610981;6577.506686998494;6591.868162636768;6592.538522303441;6599;6395.168628078367;6414.069959855795;7236.49344545882;7644.885326720104;7676.961615191888;29247.41505169885;29269;29364.8531757342;29750.553741693893;30233.546496802566;2922.1817890990346;2940.9564093994977;2973.271548470617;3031.227037105546;3045.086474057796;17431.73852392215;17542.91228543849;17774.644507109915;17788.199531021135;18114.1884019002;2104.189886893557;2120.162535798366;2146.5384942237492;2150.323146892206;2172.576347612216;94493;160997;169647;177284.11357943257;180210;24973.5005299893;25546.744532554436;25648;25651.84566694503;25829.48341033169;42836;42947;43479.56520434818;44363.11273774506;44398.33402498965;11745.4714537846;11921.225120367148;11973;11977.221480603734;12001.699957501132;7834.569098699626;9411.905880941104;9416.340856140141;9583.089606487372;9597.90402095984;11694.76610467786;11899.821502677467;11954.820339791573;12036.976856967081;12177.391130443442;4117.155983023473;4144.44050053245;4831.202851529459;4891.559759621651;4945.307656928033;857.4383778624886;879.3800370738614;884.8761173435724;907.4328544659588;917.8072604753077;20183.798162018487;20679.689804652942;20699;20815.24372879816;20912.89543552303;897.7217062710581;907.2379201470796;928.2156577691975;928.6471140966389;930;10265.435401053013;10580.365178089185;10653.680389588324;10681.412522311348;10706;761276;780680;784158;797766;799428;142524.28737856244;160902;171607;172748.13625932042;174976;1228.3182833527478;1335.2589312931223;1338.9062765606313;1340.854632587871;1400.551823416497;17480.47558573245;18527;18824.341148059753;19240.71138932945;19760.308389206308;60681.089783652395;62616.37383626105;63255.683721581096;63475.68262158844;64157;93918.06081939093;93963;94845.10309793627;95069;96470.51764741131;11422.25755325901;11454.370009649216;11472.88527114718;11791.29252244868;11825.70435739079;9080.727578172666;12328.506859725869;12706.618801435601;12899.032572556907;13362.331883405985;8664.826703466022;10792.892071079503;11523.711907202387;11549.94225028903;11663.475143618727;11153.330800152016;11154.219204655596;11291.435428228391;11381.715457113638;11440.656780296604;9906.554205060933;11247.718807029889;11447.084233261152;11499.942500287445;11530.423478826193;11167.274127181707;11238.988491035847;11323.32060076398;11335.433228338716;11861.406929653655;11321.433928303717;11394.772104557696;11461.312321260752;11473.13951453661;11481.598144064918 09:19:15 INFO - PROCESS | 1638 | |3;mootools.html;1271.942762575684;1553.5106441470944;1567.730138587744;1576.0701186300084;1587.3015873015875;10779.191560632955;11108.333499989985;11412;11461.598844040449;11486.368249746261;9657.95171024144;10661.413622250773;10924.344539327645;11111.666650000492;11123.332600044003;2404.6032404653256;2444.4133407982135;2445.364205306624;2445.4131008557906;2445.4619983603666;2408.7952524035477;2411;2436.3421876093444;2437.427204606925;2457.348802567321;2291.7364503082194;2297.379707478988;2300.5973954557967;2305.942351441219;2313.618252988255;2014.788447213036;2320.141547627372;2330.6387509935903;2339.4619237575366;2346.9321458736235;2362.7637236276405;2404.495056038234;2405.9879700601387;2420.044082587368;2424.7575242475787;10802.891971080267;10914.017738403581;11001.559937602486;11015.008649221609;11029.889701102966;5908.172855800191;6034.0948857671265;6039.547033972426;6065.636061836304;6070.362611925772;3508.386032444312;3583.498310236569;3631.1648320886343;3638.1450359165574;3706.814659267026;1187.2105050141645;1209.5464200924653;1211.6122840690982;1252.3988485526997;1253.2355263289387;13827.79258311126;13978.510752123728;15108.320125594484;15459;15700.528984130495;4731.178496278957;4846.127697014571;4846.61227101831;4866.221404575251;4881.804727810848;11879.109066820023;11940.701482462835;11960;11964.700882478008;12014.038876889828;5768.307803063659;5829.7085145743395;5847.970760146172;5895.941040589625;5919.911201331984;5221.269022336875;5346.599005074635;5406.864828379244;5452.154915988028;5458.890822183614;3868.903277418031;3995.780232087205;4016.5180178378782;4071.2875246831686;4074.714769966133;915.7563671359646;1064.6699523147695;1076.838474228872;1078.1105587890022;1083.2200815412891;905.542700936023;907.3240435875315;924.8242833861544;926.4302453990882;944.6882528765491;6455.257645370749;6523.151990241238;6619.735210591619;6654.334566543307;6664.533482656265;823.2179429541959;866.7053201911381;924.1867156902018;988.7182153086336;1001.602764144015;4062.1875624875142;4143.357779544175;4214.346776249687;4250.489941207075;4267.679924005712;13404.463821447032;13876.58370248894;13895.652608684864;13899.096558723651;14039.649008774863;11545.711357215969;13531.39108740109;13546.729065418833;13819.930900345435;13894.235817029958;10097.24270679702;10131.7467063324;10232.897671023195;10278.845817312746;10304.484775761332;6364.64994425311;6448.548601597843;6528.118703975093;6574.638394888327;6599.868002639824;11993.520259189883;12254.87745122573;12261.203021803554;12309.199902006341;12419.689507762378;11347.14896382791;11462.025727813229;12395.75208495843;12562.49250089995;12788.808167877303;8306.584670766559;8461.40770146084;8572.014218364719;8576.31389488827;8586.914130858611;10357.63748268807;10383.325083869524;10388.74028149272;10475.16198704117;10500.159987201157;9226.35415520934;9227.538623068955;9244.352895297265;9331.440113592935;9423.764405889908;8971.237444817263;9047.773805654648;9061.864072038792;9062.229710474683;9199.862002070107;7926.405519585939;7934.880976785237;8021;8062.596870156586;8083.514989100438;4985.451600323889;5694.1458781183155;5813.651180929158;5868.853278668067;6051.001584738468;5675.659460432387;5873.911891321719;5911.763529458945;5953.642781433128;6069.4840938518955 09:19:15 INFO - PROCESS | 1638 | |4;prototype.html;347.4146063882358;355.4561520873062;364.94348860050155;377.0912101834579;390.4592139886258;4829.396325459317;5241.514392928708;5416.51251387375;5432.409613876278;5457.91813122803;4374.212641724488;4437.378766972626;4805.279208118784;5203.349581302337;5223.268742376071;4198.895027624319;4242.38485419614;4387.9122417551625;4397.032652816383;4430.49049359323;3897.8635747748835;4184.3305071188615;4332;4391.27543955247;4468.262736648449;3790.9620903791024;3893.9026524336978;4100.138970816132;4411.514733379342;4448.799804008828;3671;3684.1894783147663;3824.7131465140224;4250.277452833026;4501.279795232764;3878.70909681775;4008.518977722663;4021.77880216588;4032.2540330039037;4051.6961227908023;4736.052789442091;4802.415202983042;4835.468098509161;5132.384113906318;5154.92267615986;4223.619874211305;4523.411956445674;4701.976490117561;5056.898862022776;5107.565856902168;4605.5854973052255;4974.726390048546;5086.46592107827;5317.883244518655;5566.3301009696925;4057.9594204057876;4136.58634136587;4308.676849236288;4571.3828633134435;4589.862304130881;5189.040027594907;5307.3896501902;5391.78432862685;5692.526900211263;5760.654360738327;4367.541408152162;4550.726956382628;4583.2666773316105;4613.538646135359;4614;4377.759223242752;4713.175194340976;4713.505081966413;4728.929066064012;4748.7863046163;4460.320048219725;4532.410786597721;5086.415062267886;5093.261477085876;5119.1553393690365;5296.708681022581;5444.292242008513;5446.373667028263;5574.299838549281;5694.519424949543;1527.5259374703464;1596.090228569704;1612.7984651434756;1623.8551820966427;1624.3340230505437;1121.4841173060483;1145.8395824584566;1154.3766366162347;1206.3968015992004;1207.3299318877932;4594.448666160082;5239.711815850089;5248.186531087687;5254.763535640829;5259.789608415697;5558.832055498359;5953.732082056318;5974.581779275496;5997.580169388101;6050.848728781728;4429.667774916894;4444.5777651123735;5144.948550514522;5194.142966410575;5245;2573.125137453275;2645.632159217528;2649.5628221343263;2995.0715278263806;3086.382723455273;5481.75332110056;5831.475167234969;5887.705614719247;5899.380565040695;6074.453299203093;5540.9168862467095;5552.972235138798;5738.741756620962;5881.147233651182;5955.860676829562;4912.778924948386;5431.321084864313;5700.714964251853;5967.522598192134;6102.572819902653;5121.539061484485;5773.575098458574;5962.157086055603;5993.61041532299;5994.340622531431;4626.715032326951;5016.372953380827;5343.957928203963;5625.887482250414;5645.322561292671;5114.309568208244;5363.919541206807;6086.717480944646;6119.357467465852;6142.3759981611065;4325.762083085335;4396.538363471789;4429.867103986886;4629.814807407801;4669.319045143813;4769.61843052548;5469.988052210195;5549.694766787704;5598;5903;4779.808807647655;5323.813666521653;5507.944920550904;5703.287089113861;5752.511036561939;4612.654050946126;4645.581897629229;5708.80019199326;5734.971325143348;5738.741756620879;4774.928376074431;5543.8891222176135;5560.666360018412;5572.331320241435;5576.219329293821;5034.471380504994;5526.169890765281;5761.740721667619;5795.971020144872;5796.94203057964;4306.526282109028;5052.924206136838;5459.607800010972;5628.915566266427;5681 09:19:15 INFO - PROCESS | 1638 | |5;yui.html;715.1026903376599;766.5745511241263;805.1023109233205;837.6691206973242;862.5687156421789;15244;15439.919205655595;17556.912215438922;18478.16848241829;18647.813521864795;16913.238904249338;17073.743893841573;17115.82884171158;17350.392736254234;17736.201870915807;933.929955253357;949.1979277510493;957.6887511558737;960.5005397193455;960.9471479068649;923.8198551282503;945.7682867697424;946.011418068117;947.1806887042693;953.7138858342504;1007.853861190127;1009.5356136177368;1014.077189757321;1015.0712098429927;1016.547636301846;848.5417874347845;958.242988039448;1007.4005966449951;1010.4745532323187;1010.7271036820091;643.9130717353191;643.9227292724903;674.9730010799562;698.9021536984826;746.8319628083638;16124.677506449805;16269.674606507804;16713.749293760604;16742.162891855358;16759;14549.563513094625;14708.705825883422;14890.851091489054;14916.2541872907;15447.995880267747;9382.765430864283;11619.244749091282;11689.240199387013;11728.17902746808;12252.632421027294;3733.085394078441;3758.6993040556963;3780.6597406233573;3842.9231415371814;3883.456316115746;106046;106881.8623627523;111576.4421177897;112004;112674;11430.542778288942;11463;11581.76836463266;11746.58886938953;12231.816522751988;23591;24252.272431827074;24942.625860612105;25367.492650146894;25455.61816572753;8001;8043.718469853527;8065.717699880476;8264.380171487162;8384.077751447336;4367.126574685076;4408.294672852328;4413.91858994542;4434.84478043267;4477.2164871147415;3501.9824900875333;3574.946375804365;3586.5158203642172;3591.353556359828;3673.779573225615;699.7620808924888;700.0758998122429;701.6947627781931;703.1281211297953;721.2174790352421;659.6569783712442;673.2089794491453;679.7994591595491;697.396751809681;702.5117543307455;10339.63811266617;10340.534675939602;10369.740756480998;10393.37639741618;10514.894851051544;682.5495173185674;703.9190493093257;711.3313485323882;716.7276434954683;717.7882524655134;4650.67445278834;4865.391826021747;5119.795208191705;5129.461406552333;5205.765740541685;84195.73706394409;96061;105823;107588.77233683002;108192;80463;82884.75672864912;84461;85806;88717.66923496153;76060.61969690227;79667.60166199131;80416.39167216622;80464.19535804684;83927.16072839315;10669.733256668645;11055.557777688802;11156.609518666808;11259.042981346349;11278.830817537579;51079.744601278246;51420;51771.22343164931;52129.218061729865;52625.473745262054;65648;67502;67659.66170169118;68228.6588567054;69096;12396.690082748004;12447.751044979232;12777.387412977823;12814;12855.678608034874;14320.498782542561;14594;14625.781020882054;14943.85056149468;14972.1016738996;13378.819193704945;13816.585502434944;13918.58244252674;14082.15507069538;14392.568222953329;11935.880641193477;13010.414531345923;13567;13835.930820345833;13917.164970101827;12847.743045139232;13276.867231327564;13306.933465332611;13370.331483425594;13702.10936289138;10629.521671524646;10986.230963832455;11084.168687348643;11273.7745245093;11621.941890290494;10175;11123.27698699582;11419.885801141882;11501.769964600828;11503 09:19:15 INFO - PROCESS | 1638 | __end_tp_report 09:19:15 INFO - PROCESS | 1638 | __start_cc_report 09:19:15 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,415 09:19:15 INFO - PROCESS | 1638 | __end_cc_report 09:19:15 INFO - PROCESS | 1638 | __startTimestamp1489079955655__endTimestamp 09:19:15 INFO - PROCESS | 1638 | 09:19:15 INFO - PROCESS | 1638 | ------- Summary: start ------- 09:19:15 INFO - PROCESS | 1638 | Number of tests: 6 09:19:15 INFO - PROCESS | 1638 | 09:19:15 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:6034.61 Median:6298.46 stddev:674.68 (10.7%) stddev-sans-first:640.51 09:19:15 INFO - PROCESS | 1638 | Values: 3253.8 3480.8 3516.2 3518.5 3534.0 5541.7 5659.5 6084.3 6115.6 6210.6 4729.9 6155.5 6161.8 6233.0 6338.1 5514.6 5564.2 5572.9 5581.4 5650.4 5638.3 5638.4 5640.3 5642.0 5680.2 5412.9 5416.6 5587.5 5624.9 5646.5 5555.8 5582.1 5621.6 5672.7 5698.7 5448.5 5564.1 5564.3 5565.9 5606.3 5943.9 6202.2 6236.2 6248.5 6312.2 6346.3 6361.3 6375.9 6388.6 6394.2 6516.2 6585.4 6606.2 6607.7 6626.3 6326.3 6334.6 6344.3 6348.9 6359.6 6323.8 6452.9 6529.4 6636.0 6655.9 6421.2 6426.6 6440.1 6441.2 6458.6 6492.2 6592.5 6610.5 6613.4 6614.8 5331.3 6324.5 6350.9 6362.9 6485.4 5180.3 5911.7 6018.4 6430.7 6514.3 5211.7 5227.4 5242.6 5274.1 5286.9 4681.8 4687.9 4695.8 4716.1 4767.5 6041.1 6051.1 6073.7 6081.0 6202.5 6487.9 6516.6 6578.0 6601.7 6607.8 6215.3 6227.1 6230.4 6230.7 6240.1 5628.2 5767.0 5809.6 5819.5 5852.8 6549.8 6680.4 6728.7 6736.8 6749.1 6502.9 6608.4 6633.0 6661.9 6696.2 6772.5 6829.9 6846.6 6854.3 6854.4 6291.5 6351.4 6365.2 6379.7 6392.6 5509.3 6099.7 6549.1 6593.4 6677.3 6337.1 6350.7 6377.6 6383.4 6420.6 6246.8 6298.5 6311.4 6331.5 6382.5 5416.8 5633.5 6314.3 6358.0 6368.6 6284.8 6395.5 6395.5 6412.6 6423.9 5745.3 6281.9 6290.6 6314.8 6357.2 09:19:15 INFO - PROCESS | 1638 | 09:19:15 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:55952.78 Median:17835.88 stddev:118247.86 (663.0%) stddev-sans-first:118506.58 09:19:15 INFO - PROCESS | 1638 | Values: 613.4 679.7 765.6 911.5 912.1 38513.6 39151.4 40736.4 40799.8 40883.2 39312.2 39550.0 39569.2 39596.2 39730.0 7773.7 10506.8 10905.3 10933.8 10938.6 10660.7 10736.2 10754.3 10759.6 10771.5 9082.8 10759.1 10769.3 10801.7 10802.8 10728.1 10738.2 10755.8 10761.9 10787.8 10604.6 10664.6 10673.1 10682.7 10689.9 33651.7 33710.3 33796.8 33865.8 34068.0 3209.4 3705.3 3771.1 3776.8 3949.1 24629.9 24670.4 25087.6 25897.6 26032.0 3062.2 3069.1 3080.8 3092.6 3105.9 334395.0 335991.0 337496.0 337900.3 338102.0 44831.6 44900.7 45090.1 45113.9 45532.0 46738.8 46902.8 46970.5 47049.5 47097.0 13724.8 13827.7 14054.2 14086.9 14089.9 10173.5 11900.3 12120.5 12134.9 12179.8 9141.8 9303.0 9656.4 10348.9 11495.4 5073.8 5370.9 5649.4 5682.1 5708.6 1005.4 1009.9 1059.7 1081.4 1095.9 21194.2 22964.4 27300.8 28087.7 28350.7 993.9 1077.4 1125.6 1161.4 1178.7 14205.2 14421.6 15055.0 15164.8 15331.8 391929.1 429361.0 488196.0 579518.0 580354.0 37573.1 41569.4 42449.4 47040.3 47368.8 1388.9 1406.5 1426.6 1431.3 1432.6 24870.8 26727.1 27190.5 27358.7 27938.9 41638.4 41790.3 41844.8 41931.8 41962.8 413269.9 488800.0 489700.0 492803.0 495347.0 29739.1 30044.7 30069.0 30335.2 30377.7 37484.4 38518.6 38544.6 38780.4 40373.0 33079.0 33112.8 33390.2 34119.3 34180.1 31559.9 32462.4 32711.7 32949.8 32994.2 32476.8 33046.5 33225.0 33232.0 33861.7 27700.6 28768.6 29929.7 30067.8 30258.7 19314.0 22036.3 22840.6 23239.1 23358.9 09:19:15 INFO - PROCESS | 1638 | 09:19:15 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:45306.65 Median:23567.42 stddev:131037.81 (556.0%) stddev-sans-first:131362.00 09:19:15 INFO - PROCESS | 1638 | Values: 386.2 406.6 425.1 436.5 444.8 19317.9 19410.7 20158.4 20816.5 20852.6 21145.0 21267.7 21801.2 22161.0 22161.3 6393.3 6760.1 6760.4 6771.1 6777.9 5720.2 6382.5 6586.6 6636.3 6653.1 6240.8 6677.4 6681.2 6682.1 6689.7 6419.5 6577.5 6591.9 6592.5 6599.0 6395.2 6414.1 7236.5 7644.9 7677.0 29247.4 29269.0 29364.9 29750.6 30233.5 2922.2 2941.0 2973.3 3031.2 3045.1 17431.7 17542.9 17774.6 17788.2 18114.2 2104.2 2120.2 2146.5 2150.3 2172.6 94493.0 160997.0 169647.0 177284.1 180210.0 24973.5 25546.7 25648.0 25651.8 25829.5 42836.0 42947.0 43479.6 44363.1 44398.3 11745.5 11921.2 11973.0 11977.2 12001.7 7834.6 9411.9 9416.3 9583.1 9597.9 11694.8 11899.8 11954.8 12037.0 12177.4 4117.2 4144.4 4831.2 4891.6 4945.3 857.4 879.4 884.9 907.4 917.8 20183.8 20679.7 20699.0 20815.2 20912.9 897.7 907.2 928.2 928.6 930.0 10265.4 10580.4 10653.7 10681.4 10706.0 761276.0 780680.0 784158.0 797766.0 799428.0 142524.3 160902.0 171607.0 172748.1 174976.0 1228.3 1335.3 1338.9 1340.9 1400.6 17480.5 18527.0 18824.3 19240.7 19760.3 60681.1 62616.4 63255.7 63475.7 64157.0 93918.1 93963.0 94845.1 95069.0 96470.5 11422.3 11454.4 11472.9 11791.3 11825.7 9080.7 12328.5 12706.6 12899.0 13362.3 8664.8 10792.9 11523.7 11549.9 11663.5 11153.3 11154.2 11291.4 11381.7 11440.7 9906.6 11247.7 11447.1 11499.9 11530.4 11167.3 11239.0 11323.3 11335.4 11861.4 11321.4 11394.8 11461.3 11473.1 11481.6 09:19:15 INFO - PROCESS | 1638 | 09:19:15 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6739.82 Median:2445.39 stddev:4190.56 (171.4%) stddev-sans-first:4182.17 09:19:15 INFO - PROCESS | 1638 | Values: 1271.9 1553.5 1567.7 1576.1 1587.3 10779.2 11108.3 11412.0 11461.6 11486.4 9658.0 10661.4 10924.3 11111.7 11123.3 2404.6 2444.4 2445.4 2445.4 2445.5 2408.8 2411.0 2436.3 2437.4 2457.3 2291.7 2297.4 2300.6 2305.9 2313.6 2014.8 2320.1 2330.6 2339.5 2346.9 2362.8 2404.5 2406.0 2420.0 2424.8 10802.9 10914.0 11001.6 11015.0 11029.9 5908.2 6034.1 6039.5 6065.6 6070.4 3508.4 3583.5 3631.2 3638.1 3706.8 1187.2 1209.5 1211.6 1252.4 1253.2 13827.8 13978.5 15108.3 15459.0 15700.5 4731.2 4846.1 4846.6 4866.2 4881.8 11879.1 11940.7 11960.0 11964.7 12014.0 5768.3 5829.7 5848.0 5895.9 5919.9 5221.3 5346.6 5406.9 5452.2 5458.9 3868.9 3995.8 4016.5 4071.3 4074.7 915.8 1064.7 1076.8 1078.1 1083.2 905.5 907.3 924.8 926.4 944.7 6455.3 6523.2 6619.7 6654.3 6664.5 823.2 866.7 924.2 988.7 1001.6 4062.2 4143.4 4214.3 4250.5 4267.7 13404.5 13876.6 13895.7 13899.1 14039.6 11545.7 13531.4 13546.7 13819.9 13894.2 10097.2 10131.7 10232.9 10278.8 10304.5 6364.6 6448.5 6528.1 6574.6 6599.9 11993.5 12254.9 12261.2 12309.2 12419.7 11347.1 11462.0 12395.8 12562.5 12788.8 8306.6 8461.4 8572.0 8576.3 8586.9 10357.6 10383.3 10388.7 10475.2 10500.2 9226.4 9227.5 9244.4 9331.4 9423.8 8971.2 9047.8 9061.9 9062.2 9199.9 7926.4 7934.9 8021.0 8062.6 8083.5 4985.5 5694.1 5813.7 5868.9 6051.0 5675.7 5873.9 5911.8 5953.6 6069.5 09:19:15 INFO - PROCESS | 1638 | 09:19:15 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4655.11 Median:5025.42 stddev:1310.40 (26.1%) stddev-sans-first:1273.57 09:19:15 INFO - PROCESS | 1638 | Values: 347.4 355.5 364.9 377.1 390.5 4829.4 5241.5 5416.5 5432.4 5457.9 4374.2 4437.4 4805.3 5203.3 5223.3 4198.9 4242.4 4387.9 4397.0 4430.5 3897.9 4184.3 4332.0 4391.3 4468.3 3791.0 3893.9 4100.1 4411.5 4448.8 3671.0 3684.2 3824.7 4250.3 4501.3 3878.7 4008.5 4021.8 4032.3 4051.7 4736.1 4802.4 4835.5 5132.4 5154.9 4223.6 4523.4 4702.0 5056.9 5107.6 4605.6 4974.7 5086.5 5317.9 5566.3 4058.0 4136.6 4308.7 4571.4 4589.9 5189.0 5307.4 5391.8 5692.5 5760.7 4367.5 4550.7 4583.3 4613.5 4614.0 4377.8 4713.2 4713.5 4728.9 4748.8 4460.3 4532.4 5086.4 5093.3 5119.2 5296.7 5444.3 5446.4 5574.3 5694.5 1527.5 1596.1 1612.8 1623.9 1624.3 1121.5 1145.8 1154.4 1206.4 1207.3 4594.4 5239.7 5248.2 5254.8 5259.8 5558.8 5953.7 5974.6 5997.6 6050.8 4429.7 4444.6 5144.9 5194.1 5245.0 2573.1 2645.6 2649.6 2995.1 3086.4 5481.8 5831.5 5887.7 5899.4 6074.5 5540.9 5553.0 5738.7 5881.1 5955.9 4912.8 5431.3 5700.7 5967.5 6102.6 5121.5 5773.6 5962.2 5993.6 5994.3 4626.7 5016.4 5344.0 5625.9 5645.3 5114.3 5363.9 6086.7 6119.4 6142.4 4325.8 4396.5 4429.9 4629.8 4669.3 4769.6 5470.0 5549.7 5598.0 5903.0 4779.8 5323.8 5507.9 5703.3 5752.5 4612.7 4645.6 5708.8 5735.0 5738.7 4774.9 5543.9 5560.7 5572.3 5576.2 5034.5 5526.2 5761.7 5796.0 5796.9 4306.5 5052.9 5459.6 5628.9 5681.0 09:19:15 INFO - PROCESS | 1638 | 09:19:15 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20879.39 Median:23921.64 stddev:29365.81 (122.8%) stddev-sans-first:29409.16 09:19:15 INFO - PROCESS | 1638 | Values: 715.1 766.6 805.1 837.7 862.6 15244.0 15439.9 17556.9 18478.2 18647.8 16913.2 17073.7 17115.8 17350.4 17736.2 933.9 949.2 957.7 960.5 960.9 923.8 945.8 946.0 947.2 953.7 1007.9 1009.5 1014.1 1015.1 1016.5 848.5 958.2 1007.4 1010.5 1010.7 643.9 643.9 675.0 698.9 746.8 16124.7 16269.7 16713.7 16742.2 16759.0 14549.6 14708.7 14890.9 14916.3 15448.0 9382.8 11619.2 11689.2 11728.2 12252.6 3733.1 3758.7 3780.7 3842.9 3883.5 106046.0 106881.9 111576.4 112004.0 112674.0 11430.5 11463.0 11581.8 11746.6 12231.8 23591.0 24252.3 24942.6 25367.5 25455.6 8001.0 8043.7 8065.7 8264.4 8384.1 4367.1 4408.3 4413.9 4434.8 4477.2 3502.0 3574.9 3586.5 3591.4 3673.8 699.8 700.1 701.7 703.1 721.2 659.7 673.2 679.8 697.4 702.5 10339.6 10340.5 10369.7 10393.4 10514.9 682.5 703.9 711.3 716.7 717.8 4650.7 4865.4 5119.8 5129.5 5205.8 84195.7 96061.0 105823.0 107588.8 108192.0 80463.0 82884.8 84461.0 85806.0 88717.7 76060.6 79667.6 80416.4 80464.2 83927.2 10669.7 11055.6 11156.6 11259.0 11278.8 51079.7 51420.0 51771.2 52129.2 52625.5 65648.0 67502.0 67659.7 68228.7 69096.0 12396.7 12447.8 12777.4 12814.0 12855.7 14320.5 14594.0 14625.8 14943.9 14972.1 13378.8 13816.6 13918.6 14082.2 14392.6 11935.9 13010.4 13567.0 13835.9 13917.2 12847.7 13276.9 13306.9 13370.3 13702.1 10629.5 10986.2 11084.2 11273.8 11621.9 10175.0 11123.3 11419.9 11501.8 11503.0 09:19:15 INFO - PROCESS | 1638 | -------- Summary: end -------- 09:19:15 INFO - PROCESS | 1638 | 09:19:16 INFO - TEST-INFO | 1638: exit 0 09:19:16 INFO - TEST-OK | dromaeo_css | took 1117223ms 09:19:16 INFO - TEST-START | kraken 09:19:16 INFO - operating with platform_type : mac_ 09:19:16 INFO - Initialising browser for kraken test... 09:19:16 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmpC4UKwV/profile http://localhost:49236/getInfo.html) 09:19:19 INFO - PROCESS | 1647 | 2017-03-09 09:19:19.191 plugin-container[1648:10201] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 09:19:19 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:19 INFO - PROCESS | 1647 | 2017-03-09 09:19:19.199 plugin-container[1648:10201] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c27, name = 'com.apple.CFPasteboardClient' 09:19:19 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:19 INFO - PROCESS | 1647 | 2017-03-09 09:19:19.199 plugin-container[1648:10201] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:19:19 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 09:19:19 INFO - PROCESS | 1647 | colorDepth:24 09:19:19 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 09:19:19 INFO - PROCESS | 1647 | __metrics 09:19:19 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:19:20 INFO - TEST-INFO | 1647: exit 0 09:19:20 INFO - Browser initialized. 09:19:20 INFO - Running cycle 1/1 for kraken test... 09:19:20 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmpC4UKwV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:22 INFO - PROCESS | 1649 | 2017-03-09 09:19:22.718 plugin-container[1650:10322] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 09:19:22 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:22 INFO - PROCESS | 1649 | 2017-03-09 09:19:22.719 plugin-container[1650:10322] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 09:19:22 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:22 INFO - PROCESS | 1649 | 2017-03-09 09:19:22.719 plugin-container[1650:10322] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:19:32 INFO - PROCESS | 1649 | RSS: Main: 220196864 09:19:32 INFO - PROCESS | 1649 | 09:19:35 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 09:19:36 INFO - PROCESS | 1649 | RSS: Main: 234807296 09:19:36 INFO - PROCESS | 1649 | 09:19:39 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 09:19:39 INFO - PROCESS | 1649 | RSS: Main: 243109888 09:19:39 INFO - PROCESS | 1649 | 09:19:42 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 09:19:43 INFO - PROCESS | 1649 | RSS: Main: 246734848 09:19:43 INFO - PROCESS | 1649 | 09:19:45 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 09:19:46 INFO - PROCESS | 1649 | RSS: Main: 247271424 09:19:46 INFO - PROCESS | 1649 | 09:19:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:19:48 INFO - PROCESS | 1649 | RSS: Main: 248664064 09:19:48 INFO - PROCESS | 1649 | 09:19:51 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 09:19:52 INFO - PROCESS | 1649 | RSS: Main: 252534784 09:19:52 INFO - PROCESS | 1649 | 09:19:55 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 09:19:56 INFO - PROCESS | 1649 | RSS: Main: 249483264 09:19:56 INFO - PROCESS | 1649 | 09:19:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 09:20:00 INFO - PROCESS | 1649 | RSS: Main: 250765312 09:20:00 INFO - PROCESS | 1649 | 09:20:02 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:03 INFO - PROCESS | 1649 | RSS: Main: 250806272 09:20:03 INFO - PROCESS | 1649 | 09:20:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:05 INFO - PROCESS | 1649 | RSS: Main: 250814464 09:20:05 INFO - PROCESS | 1649 | 09:20:07 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:08 INFO - PROCESS | 1649 | RSS: Main: 250814464 09:20:08 INFO - PROCESS | 1649 | 09:20:11 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:12 INFO - PROCESS | 1649 | RSS: Main: 251224064 09:20:12 INFO - PROCESS | 1649 | 09:20:15 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:15 INFO - PROCESS | 1649 | RSS: Main: 250970112 09:20:15 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 09:20:17 INFO - PROCESS | 1649 | RSS: Main: 252989440 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | __start_tp_report 09:20:17 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 09:20:17 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 09:20:17 INFO - PROCESS | 1649 | |i|pagename|runs| 09:20:17 INFO - PROCESS | 1649 | |0;ai-astar;109;122;121;145;120;121;121;160;99;158 09:20:17 INFO - PROCESS | 1649 | |1;audio-beat-detection;143;112;111;150;114;117;126;143;138;114 09:20:17 INFO - PROCESS | 1649 | |2;audio-dft;200;196;201;201;198;197;199;195;201;197 09:20:17 INFO - PROCESS | 1649 | |3;audio-fft;86;59;61;90;63;61;62;53;70;63 09:20:17 INFO - PROCESS | 1649 | |4;audio-oscillator;89;71;86;74;71;69;82;70;73;84 09:20:17 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;92;93;116;92;92;99;93;92;93;131 09:20:17 INFO - PROCESS | 1649 | |6;imaging-darkroom;155;99;99;117;130;98;100;98;126;147 09:20:17 INFO - PROCESS | 1649 | |7;imaging-desaturate;142;99;75;93;149;124;89;94;90;90 09:20:17 INFO - PROCESS | 1649 | |8;json-parse-financial;67;68;94;104;107;106;70;71;71;71 09:20:17 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;56;63;57;81;89;89;80;56;51;52 09:20:17 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;87;82;87;85;83;84;87;91;85;74 09:20:17 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;141;160;133;159;129;137;148;142;187;140 09:20:17 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;171;174;167;169;172;168;169;170;171;169 09:20:17 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;66;66;66;65;67;64;64;66;66;66 09:20:17 INFO - PROCESS | 1649 | __end_tp_report 09:20:17 INFO - PROCESS | 1649 | __start_cc_report 09:20:17 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1015 09:20:17 INFO - PROCESS | 1649 | __end_cc_report 09:20:17 INFO - PROCESS | 1649 | __startTimestamp1489080017617__endTimestamp 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | ------- Summary: start ------- 09:20:17 INFO - PROCESS | 1649 | Number of tests: 14 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:127.60 Median:133.50 stddev:20.17 (15.1%) stddev-sans-first:20.24 09:20:17 INFO - PROCESS | 1649 | Values: 109.0 122.0 121.0 145.0 120.0 121.0 121.0 160.0 99.0 158.0 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:126.80 Median:132.00 stddev:15.21 (11.5%) stddev-sans-first:14.96 09:20:17 INFO - PROCESS | 1649 | Values: 143.0 112.0 111.0 150.0 114.0 117.0 126.0 143.0 138.0 114.0 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:198.50 Median:199.50 stddev:2.22 (1.1%) stddev-sans-first:2.29 09:20:17 INFO - PROCESS | 1649 | Values: 200.0 196.0 201.0 201.0 198.0 197.0 199.0 195.0 201.0 197.0 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:66.80 Median:63.00 stddev:11.96 (19.0%) stddev-sans-first:10.48 09:20:17 INFO - PROCESS | 1649 | Values: 86.0 59.0 61.0 90.0 63.0 61.0 62.0 53.0 70.0 63.0 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:78.00 stddev:7.52 (9.6%) stddev-sans-first:6.58 09:20:17 INFO - PROCESS | 1649 | Values: 89.0 71.0 86.0 74.0 71.0 69.0 82.0 70.0 73.0 84.0 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:99.30 Median:92.50 stddev:13.40 (14.5%) stddev-sans-first:13.95 09:20:17 INFO - PROCESS | 1649 | Values: 92.0 93.0 116.0 92.0 92.0 99.0 93.0 92.0 93.0 131.0 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:116.90 Median:126.50 stddev:21.74 (17.2%) stddev-sans-first:18.17 09:20:17 INFO - PROCESS | 1649 | Values: 155.0 99.0 99.0 117.0 130.0 98.0 100.0 98.0 126.0 147.0 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:104.50 Median:90.00 stddev:24.88 (27.6%) stddev-sans-first:22.38 09:20:17 INFO - PROCESS | 1649 | Values: 142.0 99.0 75.0 93.0 149.0 124.0 89.0 94.0 90.0 90.0 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:82.90 Median:70.50 stddev:17.48 (24.8%) stddev-sans-first:17.56 09:20:17 INFO - PROCESS | 1649 | Values: 67.0 68.0 94.0 104.0 107.0 106.0 70.0 71.0 71.0 71.0 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:71.50 stddev:15.53 (21.7%) stddev-sans-first:15.91 09:20:17 INFO - PROCESS | 1649 | Values: 56.0 63.0 57.0 81.0 89.0 89.0 80.0 56.0 51.0 52.0 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:86.00 stddev:4.48 (5.2%) stddev-sans-first:4.66 09:20:17 INFO - PROCESS | 1649 | Values: 87.0 82.0 87.0 85.0 83.0 84.0 87.0 91.0 85.0 74.0 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:145.00 stddev:17.13 (11.8%) stddev-sans-first:18.00 09:20:17 INFO - PROCESS | 1649 | Values: 141.0 160.0 133.0 159.0 129.0 137.0 148.0 142.0 187.0 140.0 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.00 Median:170.50 stddev:2.05 (1.2%) stddev-sans-first:2.15 09:20:17 INFO - PROCESS | 1649 | Values: 171.0 174.0 167.0 169.0 172.0 168.0 169.0 170.0 171.0 169.0 09:20:17 INFO - PROCESS | 1649 | 09:20:17 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:0.97 (1.5%) stddev-sans-first:1.01 09:20:17 INFO - PROCESS | 1649 | Values: 66.0 66.0 66.0 65.0 67.0 64.0 64.0 66.0 66.0 66.0 09:20:17 INFO - PROCESS | 1649 | -------- Summary: end -------- 09:20:17 INFO - PROCESS | 1649 | 09:20:18 INFO - TEST-INFO | 1649: exit 0 09:20:18 INFO - TEST-OK | kraken | took 62192ms 09:20:18 INFO - SUITE-END | took 1179s 09:20:18 INFO - Completed test suite (00:19:39) 09:20:18 INFO - javascript benchmark 09:20:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7795.242330972085, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3253.7722359434842, 3480.8085555294465, 3516.173699180694, 3518.524999125118, 3534.0, 5541.6674999499955, 5659.547236221103, 6084.269887613487, 6115.571909966304, 6210.627362358262, 4729.858104256869, 6155.476784473325, 6161.845953851145, 6232.968835155829, 6338.112664227012, 5514.641548299358, 5564.1931919871795, 5572.91328191003, 5581.413951535092, 5650.434956504358, 5638.323401191863, 5638.4361563843695, 5640.323161220639, 5642.0126477866415, 5680.233168522238, 5412.9458705413035, 5416.566674666017, 5587.469190426903, 5624.91562626561, 5646.548276137899, 5555.833325000256, 5582.078956972095, 5621.634593751393, 5672.744726487318, 5698.715064246771, 5448.482394172587, 5564.081926482126, 5564.27664403629, 5565.888682226333, 5606.327240731098, 5943.851403714899, 6202.224721909762, 6236.220472440945, 6248.50011999039, 6312.242530896322, 6346.270178929436, 6361.268454127789, 6375.904361434582, 6388.616682999035, 6394.168758061469, 6516.185476815398, 6585.407313341774, 6606.240282367493, 6607.735690572371, 6626.271110177925, 6326.272478664966, 6334.588251763713, 6344.333844946306, 6348.936510634927, 6359.618422894641, 6323.7786677466065, 6452.903206451907, 6529.379708927644, 6636.0, 6655.900161497581, 6421.197350331208, 6426.614403135827, 6440.098386225932, 6441.162648855712, 6458.644774537445, 6492.188476440445, 6592.538522303393, 6610.537262391586, 6613.371729685672, 6614.834629134214, 5331.253624492574, 6324.462420694293, 6350.888594495945, 6362.872742545123, 6485.416312531895, 5180.32655754757, 5911.669874278339, 6018.368071352533, 6430.678466076677, 6514.315996820265, 5211.687298762011, 5227.424983251839, 5242.606804489678, 5274.12976858814, 5286.9471305287225, 4681.789319480631, 4687.929681054787, 4695.81216751333, 4716.080364328992, 4767.4994125617, 6041.063635136561, 6051.062085376773, 6073.726682299219, 6080.969595151996, 6202.503799696014, 6487.870242595121, 6516.609003459807, 6578.04618330349, 6601.70292336846, 6607.80176594703, 6215.34738852414, 6227.097070924781, 6230.43926046658, 6230.6573138476915, 6240.095186197976, 5628.212050312959, 5766.971165144148, 5809.564282678816, 5819.53443724501, 5852.824415267549, 6549.803505894831, 6680.365365290289, 6728.6972086255255, 6736.831579210363, 6749.055132281583, 6502.902456463063, 6608.372204640552, 6633.005049242653, 6661.900071498834, 6696.229933557704, 6772.458203343819, 6829.863402732017, 6846.554973926679, 6854.314568543105, 6854.417374523221, 6291.528135389773, 6351.428371446592, 6365.204349456318, 6379.6810159490915, 6392.648404337806, 5509.311336082989, 6099.725512352044, 6549.115869357575, 6593.439557637655, 6677.332266773478, 6337.112804207507, 6350.697637228523, 6377.553571250153, 6383.425491705769, 6420.55056146084, 6246.781362652286, 6298.464630506458, 6311.431971122621, 6331.5251356147555, 6382.489400848014, 5416.7562459688625, 5633.464820842013, 6314.305426403, 6358.046293056079, 6368.64972426521, 6284.811455656338, 6395.456386207225, 6395.488360931207, 6412.647304398116, 6423.9036414452885, 5745.3105627324985, 6281.905771413341, 6290.559660823698, 6314.810555683337, 6357.205349331333], "value": 5993.020836350516, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.3651670521011, 679.7281087564974, 765.5865832450477, 911.530561760693, 912.0788004115841, 38513.614863851355, 39151.41272880905, 40736.38895416564, 40799.79600101995, 40883.18233635325, 39312.21375572494, 39550.0, 39569.208615827665, 39596.20807583847, 39730.0, 7773.689052437896, 10506.842397364007, 10905.291156074869, 10933.835992460079, 10938.617148399808, 10660.680179594625, 10736.194785391088, 10754.300970436936, 10759.569617215302, 10771.461426928661, 9082.818343633156, 10759.139268858511, 10769.299995500307, 10801.67594972152, 10802.783944321109, 10728.141748660088, 10738.194635402337, 10755.83866242007, 10761.946190269076, 10787.78424431507, 10604.575816967312, 10664.573417063308, 10673.146148308195, 10682.679519614425, 10689.9465502672, 33651.6634833651, 33710.32579348424, 33796.81711140098, 33865.830670846735, 34067.97796066122, 3209.406259841913, 3705.314516814398, 3771.113788259757, 3776.811159442017, 3949.0522274653877, 24629.876850615634, 24670.383240418952, 25087.62368564473, 25897.611535826978, 26032.0, 3062.2344413896526, 3069.125299289714, 3080.799748016394, 3092.567040614316, 3105.8757649693985, 334395.0, 335991.0, 337496.0, 337900.3104984484, 338102.0, 44831.55168448339, 44900.65298041064, 45090.098198035856, 45113.87215319578, 45532.0, 46738.766306168254, 46902.82742931386, 46970.5302946973, 47049.5295047052, 47097.0, 13724.794128088086, 13827.723445531034, 14054.227017514135, 14086.859131408555, 14089.85910140906, 10173.542190601293, 11900.345480998454, 12120.45457954394, 12134.939325303494, 12179.817302740466, 9141.771455713528, 9302.995806735145, 9656.37233579815, 10348.896511034944, 11495.42522873853, 5073.797048118107, 5370.872116855504, 5649.435056494318, 5682.119271512922, 5708.571857110734, 1005.3867141043958, 1009.9446078847621, 1059.7244716373643, 1081.3674000709673, 1095.9287646302964, 21194.15223391047, 22964.4258893529, 27300.77146528411, 28087.71912280851, 28350.724217410265, 993.8918106311411, 1077.4397313397146, 1125.5610311978335, 1161.4192903548226, 1178.7347846734588, 14205.21871297068, 14421.567352979428, 15055.02142360743, 15164.772528412082, 15331.770023449657, 391929.08070919494, 429361.0, 488196.0, 579518.0, 580354.0, 37573.06067348338, 41569.37645935313, 42449.36325955113, 47040.2943955841, 47368.76315618469, 1388.8888888889066, 1406.535843171751, 1426.621945184527, 1431.3487363249697, 1432.5917113622777, 24870.751292486842, 26727.06455274056, 27190.456190876466, 27358.726412735617, 27938.882444701983, 41638.37542436805, 41790.32838686419, 41844.79077604593, 41931.7903410481, 41962.79018604887, 413269.9336503298, 488800.0, 489700.0, 492803.0, 495347.0, 29739.10782676523, 30044.699553005066, 30069.0, 30335.241618959702, 30377.69622303749, 37484.43773343347, 38518.614813851505, 38544.6145538541, 38780.41829372505, 40373.0, 33079.0, 33112.83443582863, 33390.165245869044, 34119.31761364808, 34180.14549636279, 31559.89540366076, 32462.35075298528, 32711.67288327086, 32949.83525082359, 32994.17514562155, 32476.837615811768, 33046.504302435, 33225.0, 33232.0, 33861.66138338585, 27700.58449123305, 28768.568471472525, 29929.700702993563, 30067.849660751555, 30258.697413025588, 19314.0, 22036.33890983273, 22840.62956222632, 23239.070437182323, 23358.883205583865], "value": 18120.3060948248, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.2487461886632, 406.56497547624053, 425.1136380646352, 436.51328768423207, 444.7576071041282, 19317.903410482944, 19410.708839367402, 20158.395248142544, 20816.47958801027, 20852.582948341023, 21145.0, 21267.680984785202, 21801.236956706518, 22161.0, 22161.33515994519, 6393.296737358898, 6760.0873882026035, 6760.3577660122455, 6771.052052712625, 6777.93222067778, 5720.227769251161, 6382.489400847921, 6586.571872828251, 6636.336366363374, 6653.135092437977, 6240.812775616716, 6677.399034086931, 6681.164854393201, 6682.131322928012, 6689.6989635466525, 6419.51853610981, 6577.506686998494, 6591.868162636768, 6592.538522303441, 6599.0, 6395.168628078367, 6414.069959855795, 7236.49344545882, 7644.885326720104, 7676.961615191888, 29247.41505169885, 29269.0, 29364.8531757342, 29750.553741693893, 30233.546496802566, 2922.1817890990346, 2940.9564093994977, 2973.271548470617, 3031.227037105546, 3045.086474057796, 17431.73852392215, 17542.91228543849, 17774.644507109915, 17788.199531021135, 18114.1884019002, 2104.189886893557, 2120.162535798366, 2146.5384942237492, 2150.323146892206, 2172.576347612216, 94493.0, 160997.0, 169647.0, 177284.11357943257, 180210.0, 24973.5005299893, 25546.744532554436, 25648.0, 25651.84566694503, 25829.48341033169, 42836.0, 42947.0, 43479.56520434818, 44363.11273774506, 44398.33402498965, 11745.4714537846, 11921.225120367148, 11973.0, 11977.221480603734, 12001.699957501132, 7834.569098699626, 9411.905880941104, 9416.340856140141, 9583.089606487372, 9597.90402095984, 11694.76610467786, 11899.821502677467, 11954.820339791573, 12036.976856967081, 12177.391130443442, 4117.155983023473, 4144.44050053245, 4831.202851529459, 4891.559759621651, 4945.307656928033, 857.4383778624886, 879.3800370738614, 884.8761173435724, 907.4328544659588, 917.8072604753077, 20183.798162018487, 20679.689804652942, 20699.0, 20815.24372879816, 20912.89543552303, 897.7217062710581, 907.2379201470796, 928.2156577691975, 928.6471140966389, 930.0, 10265.435401053013, 10580.365178089185, 10653.680389588324, 10681.412522311348, 10706.0, 761276.0, 780680.0, 784158.0, 797766.0, 799428.0, 142524.28737856244, 160902.0, 171607.0, 172748.13625932042, 174976.0, 1228.3182833527478, 1335.2589312931223, 1338.9062765606313, 1340.854632587871, 1400.551823416497, 17480.47558573245, 18527.0, 18824.341148059753, 19240.71138932945, 19760.308389206308, 60681.089783652395, 62616.37383626105, 63255.683721581096, 63475.68262158844, 64157.0, 93918.06081939093, 93963.0, 94845.10309793627, 95069.0, 96470.51764741131, 11422.25755325901, 11454.370009649216, 11472.88527114718, 11791.29252244868, 11825.70435739079, 9080.727578172666, 12328.506859725869, 12706.618801435601, 12899.032572556907, 13362.331883405985, 8664.826703466022, 10792.892071079503, 11523.711907202387, 11549.94225028903, 11663.475143618727, 11153.330800152016, 11154.219204655596, 11291.435428228391, 11381.715457113638, 11440.656780296604, 9906.554205060933, 11247.718807029889, 11447.084233261152, 11499.942500287445, 11530.423478826193, 11167.274127181707, 11238.988491035847, 11323.32060076398, 11335.433228338716, 11861.406929653655, 11321.433928303717, 11394.772104557696, 11461.312321260752, 11473.13951453661, 11481.598144064918], "value": 12003.001502267372, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1271.942762575684, 1553.5106441470944, 1567.730138587744, 1576.0701186300084, 1587.3015873015875, 10779.191560632955, 11108.333499989985, 11412.0, 11461.598844040449, 11486.368249746261, 9657.95171024144, 10661.413622250773, 10924.344539327645, 11111.666650000492, 11123.332600044003, 2404.6032404653256, 2444.4133407982135, 2445.364205306624, 2445.4131008557906, 2445.4619983603666, 2408.7952524035477, 2411.0, 2436.3421876093444, 2437.427204606925, 2457.348802567321, 2291.7364503082194, 2297.379707478988, 2300.5973954557967, 2305.942351441219, 2313.618252988255, 2014.788447213036, 2320.141547627372, 2330.6387509935903, 2339.4619237575366, 2346.9321458736235, 2362.7637236276405, 2404.495056038234, 2405.9879700601387, 2420.044082587368, 2424.7575242475787, 10802.891971080267, 10914.017738403581, 11001.559937602486, 11015.008649221609, 11029.889701102966, 5908.172855800191, 6034.0948857671265, 6039.547033972426, 6065.636061836304, 6070.362611925772, 3508.386032444312, 3583.498310236569, 3631.1648320886343, 3638.1450359165574, 3706.814659267026, 1187.2105050141645, 1209.5464200924653, 1211.6122840690982, 1252.3988485526997, 1253.2355263289387, 13827.79258311126, 13978.510752123728, 15108.320125594484, 15459.0, 15700.528984130495, 4731.178496278957, 4846.127697014571, 4846.61227101831, 4866.221404575251, 4881.804727810848, 11879.109066820023, 11940.701482462835, 11960.0, 11964.700882478008, 12014.038876889828, 5768.307803063659, 5829.7085145743395, 5847.970760146172, 5895.941040589625, 5919.911201331984, 5221.269022336875, 5346.599005074635, 5406.864828379244, 5452.154915988028, 5458.890822183614, 3868.903277418031, 3995.780232087205, 4016.5180178378782, 4071.2875246831686, 4074.714769966133, 915.7563671359646, 1064.6699523147695, 1076.838474228872, 1078.1105587890022, 1083.2200815412891, 905.542700936023, 907.3240435875315, 924.8242833861544, 926.4302453990882, 944.6882528765491, 6455.257645370749, 6523.151990241238, 6619.735210591619, 6654.334566543307, 6664.533482656265, 823.2179429541959, 866.7053201911381, 924.1867156902018, 988.7182153086336, 1001.602764144015, 4062.1875624875142, 4143.357779544175, 4214.346776249687, 4250.489941207075, 4267.679924005712, 13404.463821447032, 13876.58370248894, 13895.652608684864, 13899.096558723651, 14039.649008774863, 11545.711357215969, 13531.39108740109, 13546.729065418833, 13819.930900345435, 13894.235817029958, 10097.24270679702, 10131.7467063324, 10232.897671023195, 10278.845817312746, 10304.484775761332, 6364.64994425311, 6448.548601597843, 6528.118703975093, 6574.638394888327, 6599.868002639824, 11993.520259189883, 12254.87745122573, 12261.203021803554, 12309.199902006341, 12419.689507762378, 11347.14896382791, 11462.025727813229, 12395.75208495843, 12562.49250089995, 12788.808167877303, 8306.584670766559, 8461.40770146084, 8572.014218364719, 8576.31389488827, 8586.914130858611, 10357.63748268807, 10383.325083869524, 10388.74028149272, 10475.16198704117, 10500.159987201157, 9226.35415520934, 9227.538623068955, 9244.352895297265, 9331.440113592935, 9423.764405889908, 8971.237444817263, 9047.773805654648, 9061.864072038792, 9062.229710474683, 9199.862002070107, 7926.405519585939, 7934.880976785237, 8021.0, 8062.596870156586, 8083.514989100438, 4985.451600323889, 5694.1458781183155, 5813.651180929158, 5868.853278668067, 6051.001584738468, 5675.659460432387, 5873.911891321719, 5911.763529458945, 5953.642781433128, 6069.4840938518955], "value": 5134.003242863405, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.4146063882358, 355.4561520873062, 364.94348860050155, 377.0912101834579, 390.4592139886258, 4829.396325459317, 5241.514392928708, 5416.51251387375, 5432.409613876278, 5457.91813122803, 4374.212641724488, 4437.378766972626, 4805.279208118784, 5203.349581302337, 5223.268742376071, 4198.895027624319, 4242.38485419614, 4387.9122417551625, 4397.032652816383, 4430.49049359323, 3897.8635747748835, 4184.3305071188615, 4332.0, 4391.27543955247, 4468.262736648449, 3790.9620903791024, 3893.9026524336978, 4100.138970816132, 4411.514733379342, 4448.799804008828, 3671.0, 3684.1894783147663, 3824.7131465140224, 4250.277452833026, 4501.279795232764, 3878.70909681775, 4008.518977722663, 4021.77880216588, 4032.2540330039037, 4051.6961227908023, 4736.052789442091, 4802.415202983042, 4835.468098509161, 5132.384113906318, 5154.92267615986, 4223.619874211305, 4523.411956445674, 4701.976490117561, 5056.898862022776, 5107.565856902168, 4605.5854973052255, 4974.726390048546, 5086.46592107827, 5317.883244518655, 5566.3301009696925, 4057.9594204057876, 4136.58634136587, 4308.676849236288, 4571.3828633134435, 4589.862304130881, 5189.040027594907, 5307.3896501902, 5391.78432862685, 5692.526900211263, 5760.654360738327, 4367.541408152162, 4550.726956382628, 4583.2666773316105, 4613.538646135359, 4614.0, 4377.759223242752, 4713.175194340976, 4713.505081966413, 4728.929066064012, 4748.7863046163, 4460.320048219725, 4532.410786597721, 5086.415062267886, 5093.261477085876, 5119.1553393690365, 5296.708681022581, 5444.292242008513, 5446.373667028263, 5574.299838549281, 5694.519424949543, 1527.5259374703464, 1596.090228569704, 1612.7984651434756, 1623.8551820966427, 1624.3340230505437, 1121.4841173060483, 1145.8395824584566, 1154.3766366162347, 1206.3968015992004, 1207.3299318877932, 4594.448666160082, 5239.711815850089, 5248.186531087687, 5254.763535640829, 5259.789608415697, 5558.832055498359, 5953.732082056318, 5974.581779275496, 5997.580169388101, 6050.848728781728, 4429.667774916894, 4444.5777651123735, 5144.948550514522, 5194.142966410575, 5245.0, 2573.125137453275, 2645.632159217528, 2649.5628221343263, 2995.0715278263806, 3086.382723455273, 5481.75332110056, 5831.475167234969, 5887.705614719247, 5899.380565040695, 6074.453299203093, 5540.9168862467095, 5552.972235138798, 5738.741756620962, 5881.147233651182, 5955.860676829562, 4912.778924948386, 5431.321084864313, 5700.714964251853, 5967.522598192134, 6102.572819902653, 5121.539061484485, 5773.575098458574, 5962.157086055603, 5993.61041532299, 5994.340622531431, 4626.715032326951, 5016.372953380827, 5343.957928203963, 5625.887482250414, 5645.322561292671, 5114.309568208244, 5363.919541206807, 6086.717480944646, 6119.357467465852, 6142.3759981611065, 4325.762083085335, 4396.538363471789, 4429.867103986886, 4629.814807407801, 4669.319045143813, 4769.61843052548, 5469.988052210195, 5549.694766787704, 5598.0, 5903.0, 4779.808807647655, 5323.813666521653, 5507.944920550904, 5703.287089113861, 5752.511036561939, 4612.654050946126, 4645.581897629229, 5708.80019199326, 5734.971325143348, 5738.741756620879, 4774.928376074431, 5543.8891222176135, 5560.666360018412, 5572.331320241435, 5576.219329293821, 5034.471380504994, 5526.169890765281, 5761.740721667619, 5795.971020144872, 5796.94203057964, 4306.526282109028, 5052.924206136838, 5459.607800010972, 5628.915566266427, 5681.0], "value": 4272.013575613786, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.1026903376599, 766.5745511241263, 805.1023109233205, 837.6691206973242, 862.5687156421789, 15244.0, 15439.919205655595, 17556.912215438922, 18478.16848241829, 18647.813521864795, 16913.238904249338, 17073.743893841573, 17115.82884171158, 17350.392736254234, 17736.201870915807, 933.929955253357, 949.1979277510493, 957.6887511558737, 960.5005397193455, 960.9471479068649, 923.8198551282503, 945.7682867697424, 946.011418068117, 947.1806887042693, 953.7138858342504, 1007.853861190127, 1009.5356136177368, 1014.077189757321, 1015.0712098429927, 1016.547636301846, 848.5417874347845, 958.242988039448, 1007.4005966449951, 1010.4745532323187, 1010.7271036820091, 643.9130717353191, 643.9227292724903, 674.9730010799562, 698.9021536984826, 746.8319628083638, 16124.677506449805, 16269.674606507804, 16713.749293760604, 16742.162891855358, 16759.0, 14549.563513094625, 14708.705825883422, 14890.851091489054, 14916.2541872907, 15447.995880267747, 9382.765430864283, 11619.244749091282, 11689.240199387013, 11728.17902746808, 12252.632421027294, 3733.085394078441, 3758.6993040556963, 3780.6597406233573, 3842.9231415371814, 3883.456316115746, 106046.0, 106881.8623627523, 111576.4421177897, 112004.0, 112674.0, 11430.542778288942, 11463.0, 11581.76836463266, 11746.58886938953, 12231.816522751988, 23591.0, 24252.272431827074, 24942.625860612105, 25367.492650146894, 25455.61816572753, 8001.0, 8043.718469853527, 8065.717699880476, 8264.380171487162, 8384.077751447336, 4367.126574685076, 4408.294672852328, 4413.91858994542, 4434.84478043267, 4477.2164871147415, 3501.9824900875333, 3574.946375804365, 3586.5158203642172, 3591.353556359828, 3673.779573225615, 699.7620808924888, 700.0758998122429, 701.6947627781931, 703.1281211297953, 721.2174790352421, 659.6569783712442, 673.2089794491453, 679.7994591595491, 697.396751809681, 702.5117543307455, 10339.63811266617, 10340.534675939602, 10369.740756480998, 10393.37639741618, 10514.894851051544, 682.5495173185674, 703.9190493093257, 711.3313485323882, 716.7276434954683, 717.7882524655134, 4650.67445278834, 4865.391826021747, 5119.795208191705, 5129.461406552333, 5205.765740541685, 84195.73706394409, 96061.0, 105823.0, 107588.77233683002, 108192.0, 80463.0, 82884.75672864912, 84461.0, 85806.0, 88717.66923496153, 76060.61969690227, 79667.60166199131, 80416.39167216622, 80464.19535804684, 83927.16072839315, 10669.733256668645, 11055.557777688802, 11156.609518666808, 11259.042981346349, 11278.830817537579, 51079.744601278246, 51420.0, 51771.22343164931, 52129.218061729865, 52625.473745262054, 65648.0, 67502.0, 67659.66170169118, 68228.6588567054, 69096.0, 12396.690082748004, 12447.751044979232, 12777.387412977823, 12814.0, 12855.678608034874, 14320.498782542561, 14594.0, 14625.781020882054, 14943.85056149468, 14972.1016738996, 13378.819193704945, 13816.585502434944, 13918.58244252674, 14082.15507069538, 14392.568222953329, 11935.880641193477, 13010.414531345923, 13567.0, 13835.930820345833, 13917.164970101827, 12847.743045139232, 13276.867231327564, 13306.933465332611, 13370.331483425594, 13702.10936289138, 10629.521671524646, 10986.230963832455, 11084.168687348643, 11273.7745245093, 11621.941890290494, 10175.0, 11123.27698699582, 11419.885801141882, 11501.769964600828, 11503.0], "value": 7847.798841843519, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1535.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 122.0, 121.0, 145.0, 120.0, 121.0, 121.0, 160.0, 99.0, 158.0], "value": 127.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 112.0, 111.0, 150.0, 114.0, 117.0, 126.0, 143.0, 138.0, 114.0], "value": 126.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 196.0, 201.0, 201.0, 198.0, 197.0, 199.0, 195.0, 201.0, 197.0], "value": 198.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 59.0, 61.0, 90.0, 63.0, 61.0, 62.0, 53.0, 70.0, 63.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 86.0, 74.0, 71.0, 69.0, 82.0, 70.0, 73.0, 84.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 116.0, 92.0, 92.0, 99.0, 93.0, 92.0, 93.0, 131.0], "value": 99.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 99.0, 99.0, 117.0, 130.0, 98.0, 100.0, 98.0, 126.0, 147.0], "value": 116.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 99.0, 75.0, 93.0, 149.0, 124.0, 89.0, 94.0, 90.0, 90.0], "value": 104.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 94.0, 104.0, 107.0, 106.0, 70.0, 71.0, 71.0, 71.0], "value": 82.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 57.0, 81.0, 89.0, 89.0, 80.0, 56.0, 51.0, 52.0], "value": 67.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 82.0, 87.0, 85.0, 83.0, 84.0, 87.0, 91.0, 85.0, 74.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 160.0, 133.0, 159.0, 129.0, 137.0, 148.0, 142.0, 187.0, 140.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 174.0, 167.0, 169.0, 172.0, 168.0, 169.0, 170.0, 171.0, 169.0], "value": 170.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 67.0, 64.0, 64.0, 66.0, 66.0, 66.0], "value": 65.6, "unit": "score"}]}]} 09:20:18 INFO - Return code: 0 09:20:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:20:18 ERROR - # TBPL SUCCESS # 09:20:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:20:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:20:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:20:18 INFO - 2017-03-09 09:20:18.987 screenresolution[1654:10707] starting screenresolution argv=screenresolution get 09:20:18 INFO - 2017-03-09 09:20:18.990 screenresolution[1654:10707] Display 0: 1600x1200x32@60 09:20:18 INFO - 2017-03-09 09:20:18.996 screenresolution[1655:10711] starting screenresolution argv=screenresolution list 09:20:18 INFO - Available Modes on Display 0 09:20:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:20:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:20:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:20:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:20:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:20:19 INFO - Intel Iris: 09:20:19 INFO - Chipset Model: Intel Iris 09:20:19 INFO - Type: GPU 09:20:19 INFO - Bus: Built-In 09:20:19 INFO - VRAM (Dynamic, Max): 1536 MB 09:20:19 INFO - Vendor: Intel (0x8086) 09:20:19 INFO - Device ID: 0x0a2e 09:20:19 INFO - Revision ID: 0x0009 09:20:19 INFO - Displays: 09:20:19 INFO - Display: 09:20:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:20:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:20:19 INFO - Display Serial Number: 1600x1200 60 09:20:19 INFO - Main Display: Yes 09:20:19 INFO - Mirror: Off 09:20:19 INFO - Online: Yes 09:20:19 INFO - Rotation: Supported 09:20:19 INFO - Return code: 0 09:20:19 INFO - Running post-action listener: _resource_record_post_action 09:20:19 INFO - [mozharness: 2017-03-09 17:20:19.230428Z] Finished run-tests step (success) 09:20:19 INFO - Running post-run listener: _resource_record_post_run 09:20:19 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 38779392; Write bytes: 514822656; Read time: 242; Write time: 1636 09:20:19 INFO - TinderboxPrint: CPU usage
54.7% 09:20:19 INFO - TinderboxPrint: I/O read bytes / time
38,779,392 / 242 09:20:19 INFO - TinderboxPrint: I/O write bytes / time
514,822,656 / 1,636 09:20:19 INFO - TinderboxPrint: CPU idle
2,177.9 (45.3%) 09:20:19 INFO - TinderboxPrint: CPU system
968.4 (20.2%) 09:20:19 INFO - TinderboxPrint: CPU user
1,659.4 (34.5%) 09:20:19 INFO - TinderboxPrint: Swap in / out
247,648,256 / 0 09:20:19 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 211546624; Write bytes: 203581440; Read time: 16632; Write time: 451 09:20:19 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 29893120; Write bytes: 300079616; Read time: 176; Write time: 1153 09:20:19 INFO - Running post-run listener: _upload_blobber_files 09:20:19 INFO - Blob upload gear active. 09:20:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:19 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:20:19 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:20:19 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:20:20 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:20:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:20:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:20 INFO - (blobuploader) - INFO - Done attempting. 09:20:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:20:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:20:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:21 INFO - (blobuploader) - INFO - Done attempting. 09:20:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:20:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:20:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:22 INFO - (blobuploader) - INFO - Done attempting. 09:20:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:20:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:20:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:25 INFO - (blobuploader) - INFO - Done attempting. 09:20:25 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:25 INFO - Return code: 0 09:20:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edcc09f5d73390c102b3f182be216c8bcb79c687b771ff9e9e9b002e1181c30879934249d71e1ca425bc27b5fa945c8ebb76c3c57aadbda85d000dbe84f14d56", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c18002fc294fb9b198b30fdea797275fa09253a305a9fa712f3ff905e7b982fcb93518c5aa427a0dc585a9ab19906a69696286961160d9209dac576dd3cb7900", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7590992610d3f151c24ca0bb2d820207df86745c2d8249aa0ae99b3d5ebebbb0ff73e65e13772ab365c1425501998d2ff1f93a9b6653486f4c0696862bab77a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bcfcd754e80c3fe01245b862e1ddc9a4ca7c86585415bb55a1d5d36f280b781a60fa8f61cb88102b76ef969986d1261b0f93297be882dd91e5e024ff2c4ab0f"} 09:20:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:25 INFO - Contents: 09:20:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/edcc09f5d73390c102b3f182be216c8bcb79c687b771ff9e9e9b002e1181c30879934249d71e1ca425bc27b5fa945c8ebb76c3c57aadbda85d000dbe84f14d56", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c18002fc294fb9b198b30fdea797275fa09253a305a9fa712f3ff905e7b982fcb93518c5aa427a0dc585a9ab19906a69696286961160d9209dac576dd3cb7900", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7590992610d3f151c24ca0bb2d820207df86745c2d8249aa0ae99b3d5ebebbb0ff73e65e13772ab365c1425501998d2ff1f93a9b6653486f4c0696862bab77a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bcfcd754e80c3fe01245b862e1ddc9a4ca7c86585415bb55a1d5d36f280b781a60fa8f61cb88102b76ef969986d1261b0f93297be882dd91e5e024ff2c4ab0f"} 09:20:25 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:25 INFO - Copying logs to upload dir... 09:20:25 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.298392 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-09 09:20:25.415353) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-09 09:20:25.419288) ========= 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.mQdXI66W5S/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.Zab2gp7TIF/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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/edcc09f5d73390c102b3f182be216c8bcb79c687b771ff9e9e9b002e1181c30879934249d71e1ca425bc27b5fa945c8ebb76c3c57aadbda85d000dbe84f14d56", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c18002fc294fb9b198b30fdea797275fa09253a305a9fa712f3ff905e7b982fcb93518c5aa427a0dc585a9ab19906a69696286961160d9209dac576dd3cb7900", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7590992610d3f151c24ca0bb2d820207df86745c2d8249aa0ae99b3d5ebebbb0ff73e65e13772ab365c1425501998d2ff1f93a9b6653486f4c0696862bab77a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bcfcd754e80c3fe01245b862e1ddc9a4ca7c86585415bb55a1d5d36f280b781a60fa8f61cb88102b76ef969986d1261b0f93297be882dd91e5e024ff2c4ab0f"} build_url:https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010793 build_url: 'https://queue.taskcluster.net/v1/task/DJ2IoMlAT-aayifG8cndxQ/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/edcc09f5d73390c102b3f182be216c8bcb79c687b771ff9e9e9b002e1181c30879934249d71e1ca425bc27b5fa945c8ebb76c3c57aadbda85d000dbe84f14d56", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c18002fc294fb9b198b30fdea797275fa09253a305a9fa712f3ff905e7b982fcb93518c5aa427a0dc585a9ab19906a69696286961160d9209dac576dd3cb7900", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7590992610d3f151c24ca0bb2d820207df86745c2d8249aa0ae99b3d5ebebbb0ff73e65e13772ab365c1425501998d2ff1f93a9b6653486f4c0696862bab77a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bcfcd754e80c3fe01245b862e1ddc9a4ca7c86585415bb55a1d5d36f280b781a60fa8f61cb88102b76ef969986d1261b0f93297be882dd91e5e024ff2c4ab0f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-09 09:20:25.453403) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 09:20:25.453734) ========= 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.mQdXI66W5S/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.Zab2gp7TIF/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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.005178 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 09:20:25.501679) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-09 09:20:25.502030) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-09 09:20:25.502370) ========= ========= Total master_lag: 0.19 =========