builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0210 starttime: 1491334972.63 results: success (0) buildid: 20170404111828 builduid: 56c77f91d866449a84f36efe0754f1e7 revision: 9cb970f15203dedce360ac31fa58625132c34533 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-04 12:42:52.634592) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-04 12:42:52.635108) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-04 12:42:52.635451) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-04 12:42:52.664210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 12:42:52.664840) ========= 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.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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-04-04 12:42:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 135M=0s 2017-04-04 12:42:52 (135 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119698 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 12:42:52.814206) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 12:42:52.815001) ========= 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.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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.035923 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 12:42:52.876615) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-04 12:42:52.877287) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9cb970f15203dedce360ac31fa58625132c34533 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 9cb970f15203dedce360ac31fa58625132c34533 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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-04-04 12:42:53,025 truncating revision to first 12 chars 2017-04-04 12:42:53,025 Setting DEBUG logging. 2017-04-04 12:42:53,025 attempt 1/10 2017-04-04 12:42:53,025 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9cb970f15203?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-04 12:42:54,337 unpacking tar archive at: mozilla-central-9cb970f15203/testing/mozharness/ program finished with exit code 0 elapsedTime=1.583278 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-04 12:42:54.489789) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-04 12:42:54.490290) ========= script_repo_revision: 9cb970f15203dedce360ac31fa58625132c34533 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-04 12:42:54.490801) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-04 12:42:54.491118) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-04 12:42:54.512011) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-04 12:42:54.512564) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:42:54 INFO - MultiFileLogger online at 20170404 12:42:54 in /builds/slave/test 12:42:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:42:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:42:54 INFO - {'append_to_log': False, 12:42:54 INFO - 'base_work_dir': '/builds/slave/test', 12:42:54 INFO - 'blob_upload_branch': 'Firefox', 12:42:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:42:54 INFO - 'branch': 'Firefox', 12:42:54 INFO - 'buildbot_json_path': 'buildprops.json', 12:42:54 INFO - 'config_files': ('talos/mac_config.py',), 12:42:54 INFO - 'default_actions': ('clobber', 12:42:54 INFO - 'read-buildbot-config', 12:42:54 INFO - 'download-and-extract', 12:42:54 INFO - 'populate-webroot', 12:42:54 INFO - 'create-virtualenv', 12:42:54 INFO - 'install', 12:42:54 INFO - 'run-tests'), 12:42:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:42:54 INFO - 'download_minidump_stackwalk': True, 12:42:54 INFO - 'download_symbols': 'ondemand', 12:42:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:42:54 INFO - 'tooltool.py': '/tools/tooltool.py', 12:42:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:42:54 INFO - '/tools/misc-python/virtualenv.py')}, 12:42:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:42:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:42:54 INFO - 'gecko_profile': False, 12:42:54 INFO - 'gecko_profile_interval': 0, 12:42:54 INFO - 'installer_path': 'installer.exe', 12:42:54 INFO - 'log_level': 'info', 12:42:54 INFO - 'log_name': 'talos', 12:42:54 INFO - 'log_to_console': True, 12:42:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:42:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:42:54 INFO - 'opt_config_files': (), 12:42:54 INFO - 'pip_index': False, 12:42:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:42:54 INFO - 'cmd': ('bash', 12:42:54 INFO - '-c', 12:42:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:42:54 INFO - 'enabled': True, 12:42:54 INFO - 'halt_on_failure': False, 12:42:54 INFO - 'name': 'check_screen_resolution'},), 12:42:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:42:54 INFO - 'cmd': ('bash', 12:42:54 INFO - '-c', 12:42:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:42:54 INFO - 'enabled': True, 12:42:54 INFO - 'halt_on_failure': False, 12:42:54 INFO - 'name': 'check_screen_resolution'},), 12:42:54 INFO - 'run_cmd_checks_enabled': True, 12:42:54 INFO - 'suite': 'dromaeojs-e10s', 12:42:54 INFO - 'system_bits': '32', 12:42:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:42:54 INFO - 'title': 't-yosemite-r7-0210', 12:42:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:42:54 INFO - 'use_talos_json': True, 12:42:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:42:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:42:54 INFO - 'work_dir': 'build'} 12:42:54 INFO - [mozharness: 2017-04-04 19:42:54.764499Z] Running clobber step. 12:42:54 INFO - Running pre-action listener: _resource_record_pre_action 12:42:54 INFO - Running main action method: clobber 12:42:54 INFO - rmtree: /builds/slave/test/build 12:42:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:42:57 INFO - Running post-action listener: _resource_record_post_action 12:42:57 INFO - [mozharness: 2017-04-04 19:42:57.123771Z] Finished clobber step (success) 12:42:57 INFO - [mozharness: 2017-04-04 19:42:57.123901Z] Running read-buildbot-config step. 12:42:57 INFO - Running pre-action listener: _resource_record_pre_action 12:42:57 INFO - Running main action method: read_buildbot_config 12:42:57 INFO - Using buildbot properties: 12:42:57 INFO - { 12:42:57 INFO - "project": "", 12:42:57 INFO - "product": "firefox", 12:42:57 INFO - "script_repo_revision": "production", 12:42:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:42:57 INFO - "repository": "", 12:42:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:42:57 INFO - "buildid": "20170404111828", 12:42:57 INFO - "pgo_build": "False", 12:42:57 INFO - "basedir": "/builds/slave/test", 12:42:57 INFO - "buildnumber": 23, 12:42:57 INFO - "slavename": "t-yosemite-r7-0210", 12:42:57 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:42:57 INFO - "platform": "yosemite_r7", 12:42:57 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:42:57 INFO - "branch": "mozilla-central", 12:42:57 INFO - "repo_path": "mozilla-central", 12:42:57 INFO - "revision": "9cb970f15203dedce360ac31fa58625132c34533", 12:42:57 INFO - "stage_platform": "macosx64", 12:42:57 INFO - "builduid": "56c77f91d866449a84f36efe0754f1e7", 12:42:57 INFO - "slavebuilddir": "test" 12:42:57 INFO - } 12:42:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 12:42:57 INFO - Running post-action listener: _resource_record_post_action 12:42:57 INFO - [mozharness: 2017-04-04 19:42:57.127438Z] Finished read-buildbot-config step (success) 12:42:57 INFO - [mozharness: 2017-04-04 19:42:57.127549Z] Running download-and-extract step. 12:42:57 INFO - Running pre-action listener: _resource_record_pre_action 12:42:57 INFO - Running main action method: download_and_extract 12:42:57 INFO - mkdir: /builds/slave/test/build/tests 12:42: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')]} 12:42:57 INFO - trying https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 12:42:57 INFO - Downloading https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:42:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 12:42:58 INFO - Downloaded 1325 bytes. 12:42:58 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:42:58 INFO - Using the following test package requirements: 12:42:58 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:42:58 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 12:42:58 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:42:58 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:42:58 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 12:42:58 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:42:58 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 12:42:58 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:42:58 INFO - u'jsshell-mac.zip'], 12:42:58 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:42:58 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 12:42:58 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:42:58 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:42:58 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 12:42:58 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:42:58 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 12:42:58 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:42:58 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 12:42:58 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:42:58 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 12:42:58 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:42:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:42:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:42:58 INFO - Fetch https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:42:59 INFO - Expected file size: 52521361 12:42:59 INFO - Obtained file size: 52521361 12:43:15 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:43:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:43:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:43:15 INFO - Fetch https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:43:17 INFO - Expected file size: 52521361 12:43:17 INFO - Obtained file size: 52521361 12:43:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 12:43:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:43:33 INFO - Fetch https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 12:43:34 INFO - Expected file size: 14404152 12:43:34 INFO - Obtained file size: 14404152 12:43:34 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')]} 12:43:34 INFO - trying https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:43:34 INFO - Downloading https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 12:43:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 12:43:36 INFO - Downloaded 70687460 bytes. 12:43:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:43:36 INFO - mkdir: /builds/slave/test/properties 12:43:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:43:36 INFO - Writing to file /builds/slave/test/properties/build_url 12:43:36 INFO - Contents: 12:43:36 INFO - build_url:https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:43:37 INFO - Running post-action listener: _resource_record_post_action 12:43:37 INFO - Running post-action listener: set_extra_try_arguments 12:43:37 INFO - [mozharness: 2017-04-04 19:43:37.452061Z] Finished download-and-extract step (success) 12:43:37 INFO - [mozharness: 2017-04-04 19:43:37.452195Z] Running populate-webroot step. 12:43:37 INFO - Running pre-action listener: _resource_record_pre_action 12:43:37 INFO - Running main action method: populate_webroot 12:43:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:43:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:43:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:43:37 INFO - '--tppagecycles', 12:43:37 INFO - '7'], 12:43:37 INFO - 'tests': ['tsvgm']}}, 12:43:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:43:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:43:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:43:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:43:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:43:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:43:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:43:37 INFO - 'talos_options': ['--disable-e10s'], 12:43:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:43:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:43:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:43:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:43:37 INFO - 'talos_options': ['--disable-e10s'], 12:43:37 INFO - 'tests': ['damp', 'tps']}, 12:43:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:43:37 INFO - 'tests': ['damp', 'tps']}, 12:43:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:43:37 INFO - 'tests': ['dromaeo_dom']}, 12:43:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:43:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:43:37 INFO - 'tests': ['basic_compositor_video']}, 12:43:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:43:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:43:37 INFO - 'tests': ['a11yr', 12:43:37 INFO - 'ts_paint', 12:43:37 INFO - 'tpaint', 12:43:37 INFO - 'sessionrestore', 12:43:37 INFO - 'sessionrestore_no_auto_restore', 12:43:37 INFO - 'tabpaint']}, 12:43:37 INFO - 'other-e10s': {'tests': ['a11yr', 12:43:37 INFO - 'ts_paint', 12:43:37 INFO - 'tpaint', 12:43:37 INFO - 'sessionrestore', 12:43:37 INFO - 'sessionrestore_no_auto_restore', 12:43:37 INFO - 'tabpaint']}, 12:43:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:43:37 INFO - 'ts_paint', 12:43:37 INFO - 'tpaint', 12:43:37 INFO - 'sessionrestore', 12:43:37 INFO - 'sessionrestore_no_auto_restore', 12:43:37 INFO - 'tabpaint']}, 12:43:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:43:37 INFO - 'ts_paint', 12:43:37 INFO - 'tpaint', 12:43:37 INFO - 'sessionrestore', 12:43:37 INFO - 'sessionrestore_no_auto_restore', 12:43:37 INFO - 'tabpaint']}, 12:43:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:43:37 INFO - 'tests': ['a11yr', 12:43:37 INFO - 'ts_paint', 12:43:37 INFO - 'tpaint', 12:43:37 INFO - 'sessionrestore', 12:43:37 INFO - 'sessionrestore_no_auto_restore', 12:43:37 INFO - 'tabpaint']}, 12:43:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:43:37 INFO - 'tests': ['a11yr', 12:43:37 INFO - 'ts_paint', 12:43:37 INFO - 'tpaint', 12:43:37 INFO - 'sessionrestore', 12:43:37 INFO - 'sessionrestore_no_auto_restore', 12:43:37 INFO - 'tabpaint']}, 12:43:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:43:37 INFO - 'tests': ['tsvgx', 12:43:37 INFO - 'tsvgr_opacity', 12:43:37 INFO - 'tart', 12:43:37 INFO - 'tscrollx', 12:43:37 INFO - 'cart', 12:43:37 INFO - 'tsvg_static']}, 12:43:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:43:37 INFO - 'tsvgr_opacity', 12:43:37 INFO - 'tart', 12:43:37 INFO - 'tscrollx', 12:43:37 INFO - 'cart', 12:43:37 INFO - 'tsvg_static']}, 12:43:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:43:37 INFO - 'talos_options': ['--disable-e10s'], 12:43:37 INFO - 'tests': ['tp5o']}, 12:43:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:43:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:43:37 INFO - 'talos_options': ['--disable-e10s', 12:43:37 INFO - '--xperf_path', 12:43:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:43:37 INFO - 'tests': ['tp5n']}, 12:43:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:43:37 INFO - 'talos_options': ['--xperf_path', 12:43:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:43:37 INFO - 'tests': ['tp5n']}}, 12:43:37 INFO - 'talos.zip': {'path': '', 12:43:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:43:37 INFO - Running post-action listener: _resource_record_post_action 12:43:37 INFO - [mozharness: 2017-04-04 19:43:37.470813Z] Finished populate-webroot step (success) 12:43:37 INFO - [mozharness: 2017-04-04 19:43:37.470947Z] Running create-virtualenv step. 12:43:37 INFO - Running pre-action listener: _resource_record_pre_action 12:43:37 INFO - Running main action method: create_virtualenv 12:43:37 INFO - Creating virtualenv /builds/slave/test/build/venv 12:43:37 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 12:43:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:43:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:43:37 INFO - Using real prefix '/tools/python27' 12:43:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:43:38 INFO - Installing distribute.............................................................................................................................................................................................done. 12:43:41 INFO - Installing pip.................done. 12:43:41 INFO - Return code: 0 12:43:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:43:41 INFO - Reading from file tmpfile_stdout 12:43:41 INFO - Output received: 12:43:41 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) 12:43:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:43: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')]} 12:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43: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')]} 12:43:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:41 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 0x10632eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106331768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff139d933a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10610cd30>, '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 0x106033db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106101be8>, '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.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:43:41 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 12:43:41 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 12:43:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fO0qtSTfrh/Render', 12:43:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:43:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:43:41 INFO - 'HOME': '/Users/cltbld', 12:43:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:43:41 INFO - 'LOGNAME': 'cltbld', 12:43:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:41 INFO - 'MOZ_NO_REMOTE': '1', 12:43:41 INFO - 'NO_EM_RESTART': '1', 12:43:41 INFO - 'PAGER': '/bin/cat', 12:43:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:43:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:41 INFO - 'PWD': '/builds/slave/test', 12:43:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:43:41 INFO - 'SHELL': '/bin/bash', 12:43:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ei8xlNxkBI/Listeners', 12:43:41 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:43:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:43:41 INFO - 'USER': 'cltbld', 12:43:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:43:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:43:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:41 INFO - 'XPC_FLAGS': '0x0', 12:43:41 INFO - 'XPC_SERVICE_NAME': '0', 12:43:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:43:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:41 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 12:43:41 INFO - Cleaning up... 12:43:41 INFO - Return code: 0 12:43:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:43: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')]} 12:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43: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')]} 12:43:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:41 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 0x10632eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106331768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff139d933a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10610cd30>, '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 0x106033db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106101be8>, '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.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:43:41 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 12:43:41 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 12:43:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fO0qtSTfrh/Render', 12:43:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:43:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:43:41 INFO - 'HOME': '/Users/cltbld', 12:43:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:43:41 INFO - 'LOGNAME': 'cltbld', 12:43:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:41 INFO - 'MOZ_NO_REMOTE': '1', 12:43:41 INFO - 'NO_EM_RESTART': '1', 12:43:41 INFO - 'PAGER': '/bin/cat', 12:43:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:43:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:41 INFO - 'PWD': '/builds/slave/test', 12:43:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:43:41 INFO - 'SHELL': '/bin/bash', 12:43:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ei8xlNxkBI/Listeners', 12:43:41 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:43:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:43:41 INFO - 'USER': 'cltbld', 12:43:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:43:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:43:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:41 INFO - 'XPC_FLAGS': '0x0', 12:43:41 INFO - 'XPC_SERVICE_NAME': '0', 12:43:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:43:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:41 INFO - Downloading/unpacking psutil>=3.1.1 12:43:41 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 12:43:41 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 12:43:41 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 12:43:41 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 12:43:41 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 12:43:41 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 12:43:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:43:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:43:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:43:45 INFO - Installing collected packages: psutil 12:43:45 INFO - Running setup.py install for psutil 12:43:45 INFO - building 'psutil._psutil_osx' extension 12:43:45 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 12:43:46 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 12:43:46 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 12:43:46 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 12:43:46 INFO - building 'psutil._psutil_posix' extension 12:43:46 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 12:43:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:43:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:43:46 INFO - ^ 12:43:46 INFO - 1 warning generated. 12:43:46 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 12:43:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:43:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:43:46 INFO - Successfully installed psutil 12:43:46 INFO - Cleaning up... 12:43:46 INFO - Return code: 0 12:43:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:43:46 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')]} 12:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:46 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')]} 12:43:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:46 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 0x10632eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106331768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff139d933a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10610cd30>, '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 0x106033db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106101be8>, '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.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:43:46 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 12:43:46 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 12:43:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fO0qtSTfrh/Render', 12:43:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:43:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:43:46 INFO - 'HOME': '/Users/cltbld', 12:43:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:43:46 INFO - 'LOGNAME': 'cltbld', 12:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:46 INFO - 'MOZ_NO_REMOTE': '1', 12:43:46 INFO - 'NO_EM_RESTART': '1', 12:43:46 INFO - 'PAGER': '/bin/cat', 12:43:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:43:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:46 INFO - 'PWD': '/builds/slave/test', 12:43:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:43:46 INFO - 'SHELL': '/bin/bash', 12:43:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ei8xlNxkBI/Listeners', 12:43:46 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:43:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:43:46 INFO - 'USER': 'cltbld', 12:43:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:43:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:46 INFO - 'XPC_FLAGS': '0x0', 12:43:46 INFO - 'XPC_SERVICE_NAME': '0', 12:43:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:43:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:43:46 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 12:43:46 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 12:43:46 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 12:43:46 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 12:43:46 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 12:43:46 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 12:43:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:43:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:43:49 INFO - Installing collected packages: mozsystemmonitor 12:43:49 INFO - Running setup.py install for mozsystemmonitor 12:43:49 INFO - Successfully installed mozsystemmonitor 12:43:49 INFO - Cleaning up... 12:43:49 INFO - Return code: 0 12:43:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:50 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 0x10632eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106331768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff139d933a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10610cd30>, '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 0x106033db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106101be8>, '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.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:43:50 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 12:43:50 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 12:43:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fO0qtSTfrh/Render', 12:43:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:43:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:43:50 INFO - 'HOME': '/Users/cltbld', 12:43:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:43:50 INFO - 'LOGNAME': 'cltbld', 12:43:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:50 INFO - 'MOZ_NO_REMOTE': '1', 12:43:50 INFO - 'NO_EM_RESTART': '1', 12:43:50 INFO - 'PAGER': '/bin/cat', 12:43:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:43:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:50 INFO - 'PWD': '/builds/slave/test', 12:43:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:43:50 INFO - 'SHELL': '/bin/bash', 12:43:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ei8xlNxkBI/Listeners', 12:43:50 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:43:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:43:50 INFO - 'USER': 'cltbld', 12:43:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:43:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:43:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:50 INFO - 'XPC_FLAGS': '0x0', 12:43:50 INFO - 'XPC_SERVICE_NAME': '0', 12:43:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:43:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:51 INFO - Downloading/unpacking jsonschema==2.5.1 12:43:51 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 12:43:51 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 12:43:51 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 12:43:51 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 12:43:51 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 12:43:51 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 12:43:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:43:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:43:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:43:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:43:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:43:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:43:54 INFO - Downloading functools32-3.2.3-2.tar.gz 12:43:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:43:54 INFO - warning: no files found matching '*.txt' 12:43:54 INFO - no previously-included directories found matching 'build' 12:43:54 INFO - no previously-included directories found matching 'dist' 12:43:54 INFO - no previously-included directories found matching '.git*' 12:43:54 INFO - Installing collected packages: jsonschema, functools32 12:43:54 INFO - Running setup.py install for functools32 12:43:54 INFO - warning: no files found matching '*.txt' 12:43:54 INFO - no previously-included directories found matching 'build' 12:43:54 INFO - no previously-included directories found matching 'dist' 12:43:54 INFO - no previously-included directories found matching '.git*' 12:43:54 INFO - Successfully installed jsonschema functools32 12:43:54 INFO - Cleaning up... 12:43:54 INFO - Return code: 0 12:43:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:43:54 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')]} 12:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:54 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')]} 12:43:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:54 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 0x10632eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106331768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff139d933a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10610cd30>, '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 0x106033db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106101be8>, '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.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:43:54 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 12:43:54 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 12:43:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fO0qtSTfrh/Render', 12:43:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:43:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:43:54 INFO - 'HOME': '/Users/cltbld', 12:43:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:43:54 INFO - 'LOGNAME': 'cltbld', 12:43:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:54 INFO - 'MOZ_NO_REMOTE': '1', 12:43:54 INFO - 'NO_EM_RESTART': '1', 12:43:54 INFO - 'PAGER': '/bin/cat', 12:43:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:43:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:54 INFO - 'PWD': '/builds/slave/test', 12:43:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:43:54 INFO - 'SHELL': '/bin/bash', 12:43:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ei8xlNxkBI/Listeners', 12:43:54 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:43:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:43:54 INFO - 'USER': 'cltbld', 12:43:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:43:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:43:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:54 INFO - 'XPC_FLAGS': '0x0', 12:43:54 INFO - 'XPC_SERVICE_NAME': '0', 12:43:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:43:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:43:55 INFO - Cleaning up... 12:43:55 INFO - Return code: 0 12:43:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:43:55 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')]} 12:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:55 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')]} 12:43:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:43:55 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 0x10632eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106331768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff139d933a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10610cd30>, '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 0x106033db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106101be8>, '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.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:43:55 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 12:43:55 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 12:43:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fO0qtSTfrh/Render', 12:43:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:43:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:43:55 INFO - 'HOME': '/Users/cltbld', 12:43:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:43:55 INFO - 'LOGNAME': 'cltbld', 12:43:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:55 INFO - 'MOZ_NO_REMOTE': '1', 12:43:55 INFO - 'NO_EM_RESTART': '1', 12:43:55 INFO - 'PAGER': '/bin/cat', 12:43:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:43:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:55 INFO - 'PWD': '/builds/slave/test', 12:43:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:43:55 INFO - 'SHELL': '/bin/bash', 12:43:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ei8xlNxkBI/Listeners', 12:43:55 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:43:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:43:55 INFO - 'USER': 'cltbld', 12:43:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:43:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:43:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:55 INFO - 'XPC_FLAGS': '0x0', 12:43:55 INFO - 'XPC_SERVICE_NAME': '0', 12:43:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:43:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:55 INFO - Downloading/unpacking blobuploader==1.2.4 12:43:55 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 12:43:55 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 12:43:55 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 12:43:55 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 12:43:55 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 12:43:55 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 12:43:57 INFO - Downloading blobuploader-1.2.4.tar.gz 12:43:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:43:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:43:58 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 12:43:58 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 12:43:58 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 12:43:58 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 12:43:58 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 12:43:58 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 12:43:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:43:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:43:58 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 12:43:58 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 12:43:58 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 12:43:58 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 12:43:58 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 12:43:58 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 12:43:59 INFO - Downloading docopt-0.6.1.tar.gz 12:43:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:43:59 INFO - Installing collected packages: blobuploader, requests, docopt 12:43:59 INFO - Running setup.py install for blobuploader 12:43:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:43:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:43:59 INFO - Running setup.py install for requests 12:43:59 INFO - Running setup.py install for docopt 12:44:00 INFO - Successfully installed blobuploader requests docopt 12:44:00 INFO - Cleaning up... 12:44:00 INFO - Return code: 0 12:44:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:44:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:44:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:00 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 0x10632eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106331768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff139d933a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10610cd30>, '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 0x106033db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106101be8>, '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.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:44:00 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 12:44:00 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 12:44:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fO0qtSTfrh/Render', 12:44:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:44:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:44:00 INFO - 'HOME': '/Users/cltbld', 12:44:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:44:00 INFO - 'LOGNAME': 'cltbld', 12:44:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:00 INFO - 'MOZ_NO_REMOTE': '1', 12:44:00 INFO - 'NO_EM_RESTART': '1', 12:44:00 INFO - 'PAGER': '/bin/cat', 12:44:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:44:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:00 INFO - 'PWD': '/builds/slave/test', 12:44:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:44:00 INFO - 'SHELL': '/bin/bash', 12:44:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ei8xlNxkBI/Listeners', 12:44:00 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:44:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:44:00 INFO - 'USER': 'cltbld', 12:44:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:44:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:44:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:00 INFO - 'XPC_FLAGS': '0x0', 12:44:00 INFO - 'XPC_SERVICE_NAME': '0', 12:44:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:44:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:44:00 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-x6eY47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:44:00 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-lWAFki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:44:00 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-7FCjkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:44:00 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-gj89wn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:44:00 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-qtdo9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:44:00 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-Ym79lW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:44:01 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-2M7lQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:44:01 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-5byMwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:44:01 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-4iHSKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:44:01 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-z3ghSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:44:01 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-BeqVK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:44:01 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-pp0kwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:44:01 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-4Ucsxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:44:01 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-pVXWla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:01 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-32bESo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:44:01 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-Elw_2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:44:02 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-JqGO6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:44:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:44:02 INFO - Running setup.py install for manifestparser 12:44:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:44:02 INFO - Running setup.py install for mozcrash 12:44:02 INFO - Running setup.py install for mozdebug 12:44:02 INFO - Running setup.py install for mozdevice 12:44:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:44:02 INFO - Running setup.py install for mozfile 12:44:02 INFO - Running setup.py install for mozhttpd 12:44:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:44:03 INFO - Running setup.py install for mozinfo 12:44:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:44:03 INFO - Running setup.py install for mozInstall 12:44:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:44:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:44:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:44:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:44:03 INFO - Running setup.py install for mozleak 12:44:03 INFO - Running setup.py install for mozlog 12:44:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:44:03 INFO - Running setup.py install for moznetwork 12:44:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:44:03 INFO - Running setup.py install for mozprocess 12:44:03 INFO - Running setup.py install for mozprofile 12:44:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Running setup.py install for mozrunner 12:44:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Running setup.py install for mozscreenshot 12:44:04 INFO - Running setup.py install for moztest 12:44:04 INFO - Running setup.py install for mozversion 12:44:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:44:04 INFO - Cleaning up... 12:44:04 INFO - Return code: 0 12:44:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:04 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 0x10632eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106331768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff139d933a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10610cd30>, '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 0x106033db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106101be8>, '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.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:44:04 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 12:44:04 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 12:44:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fO0qtSTfrh/Render', 12:44:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:44:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:44:04 INFO - 'HOME': '/Users/cltbld', 12:44:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:44:04 INFO - 'LOGNAME': 'cltbld', 12:44:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:04 INFO - 'MOZ_NO_REMOTE': '1', 12:44:04 INFO - 'NO_EM_RESTART': '1', 12:44:04 INFO - 'PAGER': '/bin/cat', 12:44:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:44:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:04 INFO - 'PWD': '/builds/slave/test', 12:44:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:44:04 INFO - 'SHELL': '/bin/bash', 12:44:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ei8xlNxkBI/Listeners', 12:44:04 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:44:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:44:04 INFO - 'USER': 'cltbld', 12:44:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:44:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:44:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:04 INFO - 'XPC_FLAGS': '0x0', 12:44:04 INFO - 'XPC_SERVICE_NAME': '0', 12:44:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:44:05 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-vkBHSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:44:05 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-X_ksgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:44:05 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-b2dyMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:44:05 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-KOlW6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:44:05 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-OdsljI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:44:05 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-t5JfCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:44:05 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-0lKJ1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:44:05 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-dgduZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:44:05 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-il0mZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:44:05 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-_UHOAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:44:06 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-vOoUSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:44:06 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-cEeWpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:44:06 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-b96BGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:44:06 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-1nObdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:06 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-Zhy_kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:44:06 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-A5f849-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:44:06 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-94l1qL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:44:06 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)) 12:44:06 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)) 12:44:06 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)) 12:44:06 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)) 12:44:06 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)) 12:44:06 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)) 12:44:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:44:06 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 12:44:06 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 12:44:06 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 12:44:06 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 12:44:06 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 12:44:06 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 12:44:09 INFO - Downloading blessings-1.6.tar.gz 12:44:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:44:09 INFO - Installing collected packages: blessings 12:44:09 INFO - Running setup.py install for blessings 12:44:09 INFO - Successfully installed blessings 12:44:09 INFO - Cleaning up... 12:44:09 INFO - Return code: 0 12:44:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:44:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:44:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:44:10 INFO - Reading from file tmpfile_stdout 12:44:10 INFO - Current package versions: 12:44:10 INFO - blessings == 1.6 12:44:10 INFO - blobuploader == 1.2.4 12:44:10 INFO - docopt == 0.6.1 12:44:10 INFO - functools32 == 3.2.3-2 12:44:10 INFO - jsonschema == 2.5.1 12:44:10 INFO - manifestparser == 1.1 12:44:10 INFO - mozInstall == 1.12 12:44:10 INFO - mozcrash == 1.0 12:44:10 INFO - mozdebug == 0.1 12:44:10 INFO - mozdevice == 0.49 12:44:10 INFO - mozfile == 1.2 12:44:10 INFO - mozhttpd == 0.7 12:44:10 INFO - mozinfo == 0.9 12:44:10 INFO - mozleak == 0.1 12:44:10 INFO - mozlog == 3.4 12:44:10 INFO - moznetwork == 0.27 12:44:10 INFO - mozprocess == 0.25 12:44:10 INFO - mozprofile == 0.28 12:44:10 INFO - mozrunner == 6.13 12:44:10 INFO - mozscreenshot == 0.1 12:44:10 INFO - mozsystemmonitor == 0.3 12:44:10 INFO - moztest == 0.8 12:44:10 INFO - mozversion == 1.4 12:44:10 INFO - psutil == 3.1.1 12:44:10 INFO - requests == 1.2.3 12:44:10 INFO - wsgiref == 0.1.2 12:44:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:44:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:10 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 0x10632eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106331768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff139d933a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10610cd30>, '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 0x106033db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106101be8>, '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.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:44:10 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 12:44:10 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 12:44:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fO0qtSTfrh/Render', 12:44:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:44:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:44:10 INFO - 'HOME': '/Users/cltbld', 12:44:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:44:10 INFO - 'LOGNAME': 'cltbld', 12:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:10 INFO - 'MOZ_NO_REMOTE': '1', 12:44:10 INFO - 'NO_EM_RESTART': '1', 12:44:10 INFO - 'PAGER': '/bin/cat', 12:44:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:10 INFO - 'PWD': '/builds/slave/test', 12:44:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:44:10 INFO - 'SHELL': '/bin/bash', 12:44:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ei8xlNxkBI/Listeners', 12:44:10 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:44:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:44:10 INFO - 'USER': 'cltbld', 12:44:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:44:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:10 INFO - 'XPC_FLAGS': '0x0', 12:44:10 INFO - 'XPC_SERVICE_NAME': '0', 12:44:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:44:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:10 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)) 12:44:10 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)) 12:44:10 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)) 12:44:10 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)) 12:44:10 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)) 12:44:10 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)) 12:44:10 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)) 12:44:10 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)) 12:44:10 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)) 12:44:10 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)) 12:44:10 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)) 12:44:10 INFO - Cleaning up... 12:44:10 INFO - Return code: 0 12:44:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:44:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:44:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10632eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106331768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff139d933a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10610cd30>, '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 0x106033db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106101be8>, '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.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:44:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:44:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fO0qtSTfrh/Render', 12:44:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:44:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:44:10 INFO - 'HOME': '/Users/cltbld', 12:44:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:44:10 INFO - 'LOGNAME': 'cltbld', 12:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:10 INFO - 'MOZ_NO_REMOTE': '1', 12:44:10 INFO - 'NO_EM_RESTART': '1', 12:44:10 INFO - 'PAGER': '/bin/cat', 12:44:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:10 INFO - 'PWD': '/builds/slave/test', 12:44:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:44:10 INFO - 'SHELL': '/bin/bash', 12:44:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ei8xlNxkBI/Listeners', 12:44:10 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:44:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:44:10 INFO - 'USER': 'cltbld', 12:44:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:44:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:10 INFO - 'XPC_FLAGS': '0x0', 12:44:10 INFO - 'XPC_SERVICE_NAME': '0', 12:44:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:44:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:44:10 INFO - Cleaning up... 12:44:10 INFO - Return code: 0 12:44:10 INFO - Running post-action listener: _resource_record_post_action 12:44:10 INFO - Running post-action listener: _start_resource_monitoring 12:44:10 INFO - Starting resource monitoring. 12:44:10 INFO - [mozharness: 2017-04-04 19:44:10.675108Z] Finished create-virtualenv step (success) 12:44:10 INFO - [mozharness: 2017-04-04 19:44:10.676052Z] Running install step. 12:44:10 INFO - Running pre-action listener: _resource_record_pre_action 12:44:10 INFO - Running main action method: install 12:44:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:44:10 INFO - Reading from file tmpfile_stdout 12:44:10 INFO - Detecting whether we're running mozinstall >=1.0... 12:44:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:44:11 INFO - Reading from file tmpfile_stdout 12:44:11 INFO - Output received: 12:44:11 INFO - Usage: mozinstall [options] installer 12:44:11 INFO - Options: 12:44:11 INFO - -h, --help show this help message and exit 12:44:11 INFO - -d DEST, --destination=DEST 12:44:11 INFO - Directory to install application into. [default: 12:44:11 INFO - "/builds/slave/test"] 12:44:11 INFO - --app=APP Application being installed. [default: firefox] 12:44:11 INFO - mkdir: /builds/slave/test/build/application 12:44:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:44:30 INFO - Reading from file tmpfile_stdout 12:44:30 INFO - Output received: 12:44:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:44:30 INFO - Running post-action listener: _resource_record_post_action 12:44:30 INFO - [mozharness: 2017-04-04 19:44:30.470602Z] Finished install step (success) 12:44:30 INFO - [mozharness: 2017-04-04 19:44:30.470736Z] Running run-tests step. 12:44:30 INFO - Running pre-action listener: _resource_record_pre_action 12:44:30 INFO - Running main action method: run_tests 12:44:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:44:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:44:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:44:30 INFO - 2017-04-04 12:44:30.485 screenresolution[1626:7510] starting screenresolution argv=screenresolution get 12:44:30 INFO - 2017-04-04 12:44:30.488 screenresolution[1626:7510] Display 0: 1600x1200x32@60 12:44:30 INFO - 2017-04-04 12:44:30.493 screenresolution[1627:7512] starting screenresolution argv=screenresolution list 12:44:30 INFO - Available Modes on Display 0 12:44:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:44:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:44:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:44:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:44:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:44:30 INFO - Intel Iris: 12:44:30 INFO - Chipset Model: Intel Iris 12:44:30 INFO - Type: GPU 12:44:30 INFO - Bus: Built-In 12:44:30 INFO - VRAM (Dynamic, Max): 1536 MB 12:44:30 INFO - Vendor: Intel (0x8086) 12:44:30 INFO - Device ID: 0x0a2e 12:44:30 INFO - Revision ID: 0x0009 12:44:30 INFO - Displays: 12:44:30 INFO - Display: 12:44:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:44:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:44:30 INFO - Display Serial Number: 1600x1200 60 12:44:30 INFO - Main Display: Yes 12:44:30 INFO - Mirror: Off 12:44:30 INFO - Online: Yes 12:44:30 INFO - Rotation: Supported 12:44:30 INFO - Return code: 0 12:44:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:44:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:44:30 INFO - Python 2.7.3 12:44:30 INFO - Return code: 0 12:44:30 INFO - grabbing minidump binary from tooltool 12:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:44:30 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 0x10610cd30>, '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 0x106033db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106101be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:44:30 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 12:44:30 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 12:44:30 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 12:44:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:44:30 INFO - Return code: 0 12:44:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:44:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:44:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:44:30 INFO - ENV: RUST_BACKTRACE is now 1 12:44:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:44:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:44:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0210', '--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 12:44:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0210 --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 12:44:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fO0qtSTfrh/Render', 12:44:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:44:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:44:30 INFO - 'HOME': '/Users/cltbld', 12:44:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:44:30 INFO - 'LOGNAME': 'cltbld', 12:44:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:44:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:30 INFO - 'MOZ_NO_REMOTE': '1', 12:44:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:44:30 INFO - 'NO_EM_RESTART': '1', 12:44:30 INFO - 'PAGER': '/bin/cat', 12:44:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:30 INFO - 'PWD': '/builds/slave/test', 12:44:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:44:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:44:30 INFO - 'RUST_BACKTRACE': '1', 12:44:30 INFO - 'SHELL': '/bin/bash', 12:44:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ei8xlNxkBI/Listeners', 12:44:30 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:44:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:44:30 INFO - 'USER': 'cltbld', 12:44:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:44:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:30 INFO - 'XPC_FLAGS': '0x0', 12:44:30 INFO - 'XPC_SERVICE_NAME': '0', 12:44:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:44:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0210', '--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 12:44:31 INFO - mozversion application_buildid: 20170404111828 12:44:31 INFO - mozversion application_changeset: 9cb970f15203dedce360ac31fa58625132c34533 12:44:31 INFO - mozversion application_display_name: Nightly 12:44:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:44:31 INFO - mozversion application_name: Firefox 12:44:31 INFO - mozversion application_remotingname: firefox 12:44:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:44:31 INFO - mozversion application_vendor: Mozilla 12:44:31 INFO - mozversion application_version: 55.0a1 12:44:31 INFO - mozversion platform_buildid: 20170404111828 12:44:31 INFO - mozversion platform_changeset: 9cb970f15203dedce360ac31fa58625132c34533 12:44:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:44:31 INFO - mozversion platform_version: 55.0a1 12:44:31 INFO - using testdate: 1491335071 12:44:31 INFO - actual date: 1491335071 12:44:31 INFO - starting webserver on 'localhost:49241' 12:44:31 INFO - SUITE-START | Running 2 tests 12:44:31 INFO - TEST-START | dromaeo_css 12:44:31 INFO - operating with platform_type : mac_ 12:44:31 INFO - Initialising browser for dromaeo_css test... 12:44:31 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmpMq876a/profile http://localhost:49241/getInfo.html) 12:44:34 INFO - PID 1637 | 2017-04-04 12:44:34.098 plugin-container[1639:7701] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 12:44:34 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:44:34 INFO - PID 1637 | 2017-04-04 12:44:34.101 plugin-container[1639:7701] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 12:44:34 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:44:34 INFO - PID 1637 | 2017-04-04 12:44:34.101 plugin-container[1639:7701] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:44:34 INFO - PID 1637 | __metrics Screen width/height:1600/1200 12:44:34 INFO - PID 1637 | colorDepth:24 12:44:34 INFO - PID 1637 | Browser inner width/height: 1024/689 12:44:34 INFO - PID 1637 | __metrics 12:44:34 ERROR - PID 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3355: TypeError: this.tabs is undefined 12:44:35 INFO - TEST-INFO | 1637: exit 0 12:44:35 INFO - Browser initialized. 12:44:35 INFO - Running cycle 1/1 for dromaeo_css test... 12:44:35 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmpMq876a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:44:37 INFO - PID 1640 | 2017-04-04 12:44:37.799 plugin-container[1641:7839] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 12:44:37 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:44:37 INFO - PID 1640 | 2017-04-04 12:44:37.800 plugin-container[1641:7839] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 12:44:37 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:44:37 INFO - PID 1640 | 2017-04-04 12:44:37.800 plugin-container[1641:7839] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:44:47 INFO - PID 1640 | RSS: Main: 233578496 12:44:47 INFO - PID 1640 | 12:47:51 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 12:47:53 INFO - PID 1640 | RSS: Main: 252583936 12:47:53 INFO - PID 1640 | 12:49:35 INFO - PID 1640 | ************************* 12:49:35 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 12:49:35 INFO - PID 1640 | 12:49:35 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:49:35 INFO - PID 1640 | Full stack: 12:49:35 INFO - PID 1640 | ************************* 12:49:35 INFO - PID 1640 | ************************* 12:49:35 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 12:49:35 INFO - PID 1640 | 12:49:35 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:49:35 INFO - PID 1640 | Full stack: 12:49:35 INFO - PID 1640 | ************************* 12:49:35 INFO - PID 1640 | ************************* 12:49:35 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 12:49:35 INFO - PID 1640 | 12:49:35 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:49:35 INFO - PID 1640 | Full stack: 12:49:35 INFO - PID 1640 | ************************* 12:49:35 INFO - PID 1640 | ************************* 12:49:35 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 12:49:35 INFO - PID 1640 | 12:49:35 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:49:35 INFO - PID 1640 | Full stack: 12:49:35 INFO - PID 1640 | ************************* 12:50:55 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 12:50:56 INFO - PID 1640 | RSS: Main: 260775936 12:50:56 INFO - PID 1640 | 12:53:57 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 12:53:58 INFO - PID 1640 | RSS: Main: 275058688 12:53:58 INFO - PID 1640 | 12:57:00 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 12:57:01 INFO - PID 1640 | RSS: Main: 267415552 12:57:01 INFO - PID 1640 | 13:00:04 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 13:00:04 INFO - PID 1640 | RSS: Main: 277749760 13:00:04 INFO - PID 1640 | 13:03:07 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 13:03:07 INFO - PID 1640 | RSS: Main: 271425536 13:03:07 INFO - PID 1640 | 13:03:07 INFO - PID 1640 | __start_tp_report 13:03:07 INFO - PID 1640 | _x_x_mozilla_page_load 13:03:07 INFO - PID 1640 | _x_x_mozilla_page_load_details 13:03:07 INFO - PID 1640 | |i|pagename|runs| 13:03:07 INFO - PID 1640 | |0;dojo.html;3564.857405703772;3572.231970126424;3582.8387722552484;3595.3887839067356;3624.0758606555323;6092.939070609293;6193.938060619393;6217.720202590883;6226.813195604128;6242.594231374957;6156.876862462748;6168.290646575645;6182.412670796279;6229.345918678541;6269.905951410732;5415.48552887475;5449.291592093023;5509.752061157237;5522.447755224465;5561;5405.594580406466;5462.781488740446;5551.472610102034;5563.916541251884;5576.804811831586;5239.52844244018;5380.784768609251;5434.972825135888;5487.6158668893195;5488.231647569343;5455.972720136374;5459.754311056012;5477.698726570037;5486.753096110645;5535.557155427556;4852.805887764485;5175.974120129413;5412.648177868426;5444.183372494118;5446.809361672362;5112.744362781883;5408.215808707755;6068.544859135583;6144.402455361638;6225.003999360126;6638.402543771083;6711.932880671179;6712.295209003082;6716.361945615159;6732.326767323278;5653.180288858092;6766.052752614638;6774.085498457744;6778.661066946633;6796.932030679679;5089.643724939293;6226.59527130735;6274.749010039593;6278.623282602967;6297.30729619746;6884.586924784428;6923.238443771181;6946.444284457231;6949.548279361826;6953;6163.907541386883;6309.779157729457;6462.3537646235;6532.575382600211;6533.607983521004;5978.491828194565;6799.3200679931615;6805.625690586966;6810.353016463428;6822.454203663695;6355;6431.099646049557;6672.132622759107;6696.83257918556;6715.026321183401;6538.476921846241;6542.24764152119;6576.901346479806;6649.734010639617;6656.101426307387;4173.728707633994;5307.28351672527;5352.785888564492;5379.515843574097;5421.403645598981;4242.981684395722;4571.085782843445;4749.976250118727;4769.856904292877;4795.136875362398;5227.634065615371;6076.635401875901;6283.994560870239;6289.150964619809;6395.552311338255;6778.8644227116165;6815.114035175396;6824;6824.727010919508;6826.351496607815;6294.2446906370615;6299.6535190564955;6345.270293916166;6361.363863613602;6393.073004414334;5740.225069615632;5816.505597024216;5818.76724930998;5942.435468630473;5968.820935371946;5769.884602307847;6769.796906092726;6816.079829222991;6942.51402401827;6968.756093536905;6754.527183097136;6780.830479238059;6799.490038247052;6805.863882722417;6821.11325527698;5931.495822854933;6972.930270697431;6982.685779140053;6996.930030699831;7004.929950700428;6336.398042185985;6413.262474815456;6547.803565893031;6626.768063117768;6689.297623749436;6336.112944187909;6800.149981252343;6822.590644561342;6827.556208846409;6846.041554182319;6296.370362963667;6389.584676995981;6535.411812936859;6535.509836762167;6551.148350714377;6508.967455162694;6509.934900650933;6512.218533775977;6521.282658907611;6522.315156908457;5202.505761952609;5243.292155559103;6191.690415479298;6246.250449946036;6398.872022559616;6288.937110628835;6449.871002580016;6456.418922297016;6514.511411644051;6547.934520654923;5265.3944796348915;5773.874094551522;5910.970445147746;6352.936470635234;6371.681415929278 13:03:07 INFO - PID 1640 | |1;ext.html;806.1615919443777;838.7735311465905;1023.6161439460202;1059.3379138038727;1159.965201043969;37649;38681.419778703304;38772.41841372382;38828.611713882856;38849.61150388495;31030.37939241214;38184;38209.808950955274;38265.617343826554;38314.233715325674;8062.838743225132;8207.712730054449;8253.29846963006;8477.872831907527;8752.212300892917;7497.850042999136;8686.174813392718;8786.6924657637;8792.560371981408;8846.778830529223;8748.956255218747;8788.472691638523;8817.779555511132;8820.955895220515;8846.5134417607;8689.478631282143;8744.431611945203;8772.210501054935;8783.692570760024;8790.51652159131;8614.095519970439;8635.093315201939;8679.132086791333;8688.695895643685;8698.956505217497;37612.811935940415;37962.24075518474;38010.049748756224;38014.049648759;38126.61873381258;3457.083872773717;3479.0954351868445;3483.651634836521;3488.2500262443696;3489;21608.675869861963;24975;30418.935337263312;30544.694553054407;30667;2627.921162365132;3060.3879224155257;3070.462669032933;3118.8128712277107;3137.043201823443;306507;308546;311447;311887;314511;47232.291515627294;47429.288560671615;47605.19158467314;48114;48615.51384486181;50358.49641503611;50682;50732;50739;50920.74539627352;12269.325187114795;13298.867011329763;13368.532101376406;13386.995975301892;13413.061085723906;10038.949805250926;10515.001074897873;10567.573377593926;10598.523066461874;10610.840837387444;11770.58802941893;13101;13654;13689.247091409869;13786.793198102036;5018.648694591417;5265.788368885001;5600.215969764236;5859.828034393138;5897.380775018647;860.4708104515678;970.7767213540925;976.3019441099648;997.7056768253383;1020.7601213714771;20900.913451014036;22327;23144;24336.756632433804;24577.631335529983;1059.3273271472517;1065.5844220753913;1086.4459125845717;1102.5204036244259;1107.7814404155527;10146;12185.573504927284;12218.877811221773;12349.691257718629;12365.196262242926;347012.2649386737;411700;417929;419947;420950;55069.72465137729;55316.44683553193;55319.446805532236;55423.722881385336;55457;1456.9344379502948;1462.473509536566;1491.5823569400586;1493.8954273200989;1497.7903093566908;23585.646215307126;23797.167099151447;23798.643020355055;23871.522569548164;23906.924188411216;38723.806380967915;47143.82140446516;51502.969940601724;51535;51942;332610;400262;402412;405747;407472;27821.582676260277;28480;33734.83132584419;34938.47592286067;35382.646173537934;42361.36457953072;42837;43710.56289437065;43784.78107609441;44040.11919761651;28210.71789282163;29433.411331773674;30095.849520752257;30120.945766898054;30247;27403.725962740118;27509.724902750717;29717.554236686898;29908.401831963674;30285.577153828082;34632.307353853284;35458.64541354657;35522.93431197068;35731.10672233215;35808.46287305641;29244;29827.254318642208;30134.849325753232;30777;30849.691503084683;23955.880220598785;24038.51922961496;24236;24379.250395011302;24546.631800523362 13:03:07 INFO - PID 1640 | |2;jquery.html;426.8186020941099;436.25836078666265;438.7214119034412;451.5958217395431;455.1534146487535;20932.790672093273;21700.34898953032;22002.339929802092;22204.0008199631;22437.887810560966;21963.6705449418;22270.888645556748;22839.657405138936;22845.885770571123;23144.88427557864;5239.711815850127;5565.387807341189;5978.22283103196;6203.131561581382;6238.157848690436;6069.484093852006;6192.102145188945;6223.657698826562;6225.937740622581;6230.750769969196;6008.158857759917;6085.178500902366;6117.632942023471;6177.104319873638;6187.226596675416;6024.5180385569265;6099.48154406876;6105.755769769204;6106.145139680426;6108.572399932006;5326.6537675050995;5349.358077030743;5443.1835224716215;5451.618386712932;5451.972740136313;26565;26614.6007809883;26752.73247267522;26870.059547915924;26904.865475672694;2162.275637661385;2222.6443768996883;2227.465408301996;2232.3526177408694;2233.3746550965757;16968.915155424267;17806.643867122715;17819;17977.460676179606;18012;2079.2618620389844;2117.3542069668747;2160.470684682263;2164.5346250556204;2169.5986242545023;145305;145422;146441.26779366142;147990.26004870015;149390.25304873407;10323.32898361604;12177.93911030457;12647.683807904692;12748.171368860994;12782.360881955865;16194.433194838124;16269.349226031063;16289.104099274653;16346.83653163477;16377.590560236089;8834.204921557091;9033.187013168847;9043.005269420359;9051.728448146434;9074.546272686339;8528.530930798866;8580.871286930702;8633.582596872886;8741.91258087411;8812.559372031374;7102.573845569283;7750;7830.921690783133;7907.683692652345;7910.881336779953;1747;2077.1587507059667;2180.869147851134;2184.6286131357706;2190.7371115466244;980.1668581705493;980.6273616025865;981.3081777346953;982.4792859784326;990.5740531571349;15810.841891581167;16554.58613534647;24322;26170;26225.344366390993;877.2499512916493;921.3504479342048;938.2728385501291;994.791328535818;1011.4937951426524;9256.444613323221;10764.838527422095;10766.838497422545;10780.407077610807;11338.60314888975;667381;669272;679675;688803;689622;151310;152192;152444.1756187295;155888.2205588965;157328.21335893247;1165.6969188011178;1207.6920385301692;1253.9122261441612;1258.2513404524293;1263.6335462715892;18912.905435472734;21106.15575376968;21166.153353865673;21644.67532987038;21803.12787488546;54521.72739136279;58641.41358586359;60648;62076.37923620706;62642;80121;80609;81501.59249203716;81636;82253;8590.527520986405;8593.31253499731;8619.439736417113;8910.55447227623;9085.318601104811;13934.79097813554;13984.860151398356;14164;14429.35067921967;14461.927690361481;11335.093192544411;11698.2981021139;11975;11985.34080625574;12097.637070887888;11186.161037922026;11212.887871121184;11355;11385.544578216779;11626.593069242535;9737.561809718438;11822.763544728885;11978.46096925658;12229.7770222977;12263.816042759187;8347.624356904076;8554.272886804443;8618.913810861812;8695.695650652196;8795.516246606498;11633.6509904703;11722.531098755955;11910.880891190976;11990.28058316469;12013.75972480528 13:03:07 INFO - PID 1640 | |3;mootools.html;1389.5275606293858;1419.4960788919934;1432.3769160415227;1437.1736251655295;1462.6855226126384;10715.981981711744;11131.72170695732;11154;11169.83245251322;11473.655790326304;11009.669709908696;11088.889111108887;11200.719982000433;11216.270942388739;11272.154588405881;2427.4174198192395;2476.455179860428;2478.764517370847;2481.789047930928;2486.950260994779;2407.987960060197;2413.8551686898754;2445.2664200739705;2467.4201562632766;2470.518248941457;2406.578848701479;2412.336607432961;2429.1012325439524;2449.951000979979;2451.8774061296954;2359.433735903388;2399.6520504526834;2408.735039145701;2410.4817464245184;2438.9512209755885;2334.8248881333798;2423.3941514621347;2423.866687332196;2433.0632706407982;2433.6106223004235;11026.944865275702;11076.39079850608;11090.889091109148;11095.50070246841;11098.667039988813;6244;6353.904691429632;6426.0682201081045;6460.741570337181;6476.186667733043;3830.119072613287;4077.7553346799286;4077.9184416311805;4123.463949686551;4139.9172016559805;1184.2953442701578;1188.512709788991;1223.6267938278822;1233.2477188914754;1236.4312416288517;13922.234277114754;14463.276836158255;15071.397144114222;15195.544133676007;15209.239538023165;4656.416818281733;5601.887962240814;5962.284525856925;5980.312264089598;5995.430434108753;12767.297798621164;12787.936060319826;12788.168769029984;12802.039847011325;12839.807402888964;6313.9368606314265;6369.522285828489;6372.55392122547;6376.139221205078;6386.584871983307;5732.971335143297;5761.740721667535;5771.913421298684;5930.584859059911;5965.3139888912465;4502.797374118172;4555.362249285103;4560.429946256718;4585.633149348044;4616.28447590624;1410.153907655419;1445.551878917539;1445.7614493608437;1461.2693653173412;1469.5003698742478;760.1752098972567;767.2634271099806;789.3763926498117;789.5144486140982;802.2579114319348;6432.839179020469;6451.3548645135115;6614.8346291343105;6632.0383544385795;6667.599944003376;486.2948724349707;583.988320233593;584.141312270961;586.0857062981762;591.8429470385386;4021.557628660845;4031.9193616127513;4070.2876996526084;4114.629683328515;4116.300228961084;13380.063395562413;13718.451261949609;13865.306734663429;13952.953528485405;13974.301284935917;13809.792853107212;13815.723685526435;13819.170849749047;13842.169469831842;13876.4449422024;7748.728794492165;10135.493225338556;10171.237157213238;10241.84637230442;10248.692539223835;5694.914576281276;5712.400197979152;6496.805095846943;6739.93260067393;6859.485538584527;11249.268797528463;12127.211731237441;12146;12237.571684990982;12240.816387754368;12042;12573.874261257637;12654.493820247088;12664.87335126637;12723.300218488072;8991.550422478718;9090.409123407222;9145.451272923647;9147.268218542633;9157.633694652139;10101.44442055694;10128.733908261467;10168.694939151837;10218.693439196835;10226.335288206243;8567;8645.394822362374;8650.740477785677;8693;8825.602847871989;8416.074231834371;8476.067632560536;8553.358498112791;8564.52895090776;8701.695440659547;7521.4734968551675;7700.961495192488;7738.690452381842;7796.181400952818;7826.530408175528;5730.484256416942;5798.507126894261;5846.35690074083;6008.309044459943;6075.726592303268;5792.884142317214;5863.501602363847;6042.848928776816;6094.573379863367;6191.84520386994 13:03:07 INFO - PID 1640 | |4;prototype.html;398.4063745019921;405.54475865624875;406;409.4308910614247;415.8004158004158;4946;5435.374931882835;6349.984002559591;6421.678916054196;6425.325340839209;5653.434656534345;6051.759389325189;6159.507239420846;6184.659843708594;6256.249250089984;3879.4568760373572;4448.132614140244;4547.454305483347;4573.222552166139;4744.5492678195515;4585.449746030481;4587.632989360844;4588.4723256825555;4602.562756538124;4609.239475486541;4347.891302717426;4576.084783043388;4577.977110114445;4584.7478388688605;4589.47221069578;4409.008892578266;4525.904056828575;4546.772661366918;4579.618598328867;4580.770961451914;3884.2037382336553;3887.2614203301564;3900.3759398496104;3900.7074469414906;3901.200253947934;6072.574919755619;6074.331823499411;6122.295935967376;6123.805857857719;6145.8156255312415;5139.691618502902;5359.785608575652;5371.382291036542;5400.972995134999;5413.296271484721;6618.602883826986;6626.403623673892;6631.403173714389;6645.435138013275;6651.966740166268;3663.688586470153;4414.514403415622;4425.1149770045795;4425.291953287458;4426.9154057255855;6233.532485063638;6740.39336459721;7015;7102.39629631482;7160.209947513122;3823;3959.9110244682365;4338.392625032498;4496.595306422438;4523.864284071483;4200.831966721297;4352.717073390283;4551.271796512542;4587.747673877902;4591.793369298322;5118.564921981599;5393.379761327497;5445.401005889349;5454.61817672767;5458.727063646802;5672.688002159838;6198.977168767103;6705.362990515893;6709.228438729609;6711.395974362331;1289.7356042011365;1546.2500687166714;1550.4805890026967;1559.5184574654124;1570.6701592665668;985.6747273399761;987.5161171026289;1012.7417508535276;1031.8761748590218;1033.705393962717;5782.5254560086905;5872.825434913035;6371.267304259976;6376.202974628171;6399.872002559922;5845.88308233833;6341.239051313872;6616;6964.4428445724225;7014.859702806018;5320.308359913187;5796.971015144982;5885.588008839341;6341.619502829845;6405.43782487009;3070.462669032955;3199.86404826288;3389.389909816257;3647.6686009606833;3670.284294562535;6891.483138764513;7135.179454362711;7182.491676747837;7214.206437291894;7217.566945983258;5789.015867302569;7068.575885446787;7083.397911177608;7178.497505174692;7184.82037949045;5362.973185134049;5785.479306862402;6751.358620931003;7082.964585177041;7164.570125792469;5416.404195538409;5914.585978981602;6482.897907355855;6937.89593156093;7000.579965202104;6571.244306904576;6732;6766.560173588701;6826.931730682828;6849.075374824534;6401.679916004275;6423.875821731272;6551.639659818756;6734.4949128816515;7203.747868824566;3755.4742336072427;4388.319810429389;4496.190685676479;4515.616172625232;4516.841910533116;6528.2492513359675;6886.311368863074;6902.137232845895;7048.577085374894;7056.6118863463;5498.285222920995;6679.165104361955;6706;6730.947061146398;6772.288909664414;5528.861778455571;5708.029634962149;6562.737490500326;6758.222804377363;6759.290274521301;5333.7866485340155;6405.327440618668;6410.391012853772;6536.869262614816;6584.670766461562;6711.697973591103;6735.25912149673;6747.493937954575;6756.391924726799;6764.526483146133;5409.891802164014;6216.2540495140875;6246.3441338660605;6514.9348506514325;6573.835654108686 13:03:07 INFO - PID 1640 | |5;yui.html;784.7790752171355;824.1181935329198;824.1799409587461;836.126248070766;836.6360633124591;15676.529704108894;15777.368905243791;16181.110038947852;16341.346346146169;16407.015579065264;12759.489620415172;13611.727765444684;15127.6974460511;15327.923360383182;15330.233488325566;953.7472569769034;989.5101924547359;994.2294721590789;996.6511720897701;998.0269237493422;984.807962447323;1004.4324956399647;1031.3244824639874;1032.4270030133273;1035.715178325959;1028.8765348158195;1037.3879411147423;1038.2732087538716;1048.4652827058217;1062.2617280989716;1022.330373605286;1038.9636362727306;1039.449091981251;1042.5881776698238;1047.2355180718118;775.1744392222265;784.4391260248892;785.5012067337225;786.2137862137862;786.5831109511968;16179.271932763055;16590.917045414815;16696.916515417346;16737.581560460963;16978.23597938096;14553.272336383245;14749.631259218499;14786.778198327034;14879.256037198096;15570.06579605216;13097.279649619268;13425.66435839102;13544.796828047587;13601.523946661819;13625.795613065811;3243.918902027445;3262.347530493887;3290;3293.2919422324076;3298.224917144469;97552.02447975571;114191;116598.99999999916;117251;120812.39593801975;11405.429728513542;11487.827682584768;11665.533378664759;11699.824502632468;11707.707307317385;24615.63076553853;25081.87459062693;25133.87433062823;25270.241892743248;25461.872690636428;7707.499012564165;7746.109197442253;7854.175311592315;7874.488158269694;8021.679132834738;3959.920801583952;4058.939115913264;4106.507219133663;4175.144095460423;4184.476940382452;4152.563980782034;4238.427812245369;4260.531341552427;4267.1679022590915;4281.914361712748;919.5723988345452;924.3760461688333;940.6754669638964;943.7640589852537;945.0691069296737;532.5127508329912;626.1328060636051;640.961542307463;644.490852226745;659.0443856408227;8228.876566851503;9475.526223688788;9568;10261.486925653688;10415.375077495222;606.1059936593489;619.3366238698051;625.2309659119218;626.6897885546683;664.0968283134932;4206.663466922639;4636.466806317317;4754.833580824655;4817.0365926814775;4819.2530157825595;82505.17494825095;96925.06149876963;103171.4841425788;103314.48342758238;105906;75460.62269688616;87924.00949881265;91091.54454227819;93307;93996;60443.39556604378;73115;75391.86912196322;76937;77195.45609087787;8371;8777.122287771428;10504.317219380715;10825.891741082489;11120.277181983145;36206.379362063584;38193.618063819005;38230;39308.41037384384;39557;45098.77450612726;45105;46220;48015;49273.50726492689;10528.942364795077;13348.799768003682;13389.330533473092;13446.731065378834;13635.318234088454;14305.785413218602;14331.856681433052;14494.637634059232;14569.562913112624;14599.343029563908;11716.589919352782;13226.206427614374;13431.328433578477;13583.913286936824;14045.508407205698;11770.058395328524;13273.734525309634;13638.249896255404;13709.545054387514;14469.638259043608;13539.166484835136;13715.451381944611;13947;14118.223497707313;14658.853411465749;8727.389082764399;10065.144462720458;10149.441780702133;10312;10524;9306.767330816783;9746.26902982265;10383.896161038292;10984.231103822656;11072.501737421997 13:03:07 INFO - PID 1640 | __end_tp_report 13:03:07 INFO - PID 1640 | __start_cc_report 13:03:07 INFO - PID 1640 | _x_x_mozilla_cycle_collect,427 13:03:07 INFO - PID 1640 | __end_cc_report 13:03:07 INFO - PID 1640 | __startTimestamp1491336187688__endTimestamp 13:03:07 INFO - PID 1640 | 13:03:07 INFO - PID 1640 | ------- Summary: start ------- 13:03:07 INFO - PID 1640 | Number of tests: 6 13:03:07 INFO - PID 1640 | 13:03:07 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:6087.16 Median:6296.37 stddev:763.11 (12.1%) stddev-sans-first:739.35 13:03:07 INFO - PID 1640 | Values: 3564.9 3572.2 3582.8 3595.4 3624.1 6092.9 6193.9 6217.7 6226.8 6242.6 6156.9 6168.3 6182.4 6229.3 6269.9 5415.5 5449.3 5509.8 5522.4 5561.0 5405.6 5462.8 5551.5 5563.9 5576.8 5239.5 5380.8 5435.0 5487.6 5488.2 5456.0 5459.8 5477.7 5486.8 5535.6 4852.8 5176.0 5412.6 5444.2 5446.8 5112.7 5408.2 6068.5 6144.4 6225.0 6638.4 6711.9 6712.3 6716.4 6732.3 5653.2 6766.1 6774.1 6778.7 6796.9 5089.6 6226.6 6274.7 6278.6 6297.3 6884.6 6923.2 6946.4 6949.5 6953.0 6163.9 6309.8 6462.4 6532.6 6533.6 5978.5 6799.3 6805.6 6810.4 6822.5 6355.0 6431.1 6672.1 6696.8 6715.0 6538.5 6542.2 6576.9 6649.7 6656.1 4173.7 5307.3 5352.8 5379.5 5421.4 4243.0 4571.1 4750.0 4769.9 4795.1 5227.6 6076.6 6284.0 6289.2 6395.6 6778.9 6815.1 6824.0 6824.7 6826.4 6294.2 6299.7 6345.3 6361.4 6393.1 5740.2 5816.5 5818.8 5942.4 5968.8 5769.9 6769.8 6816.1 6942.5 6968.8 6754.5 6780.8 6799.5 6805.9 6821.1 5931.5 6972.9 6982.7 6996.9 7004.9 6336.4 6413.3 6547.8 6626.8 6689.3 6336.1 6800.1 6822.6 6827.6 6846.0 6296.4 6389.6 6535.4 6535.5 6551.1 6509.0 6509.9 6512.2 6521.3 6522.3 5202.5 5243.3 6191.7 6246.3 6398.9 6288.9 6449.9 6456.4 6514.5 6547.9 5265.4 5773.9 5911.0 6352.9 6371.7 13:03:07 INFO - PID 1640 | 13:03:07 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:50713.05 Median:19213.74 stddev:98173.40 (511.0%) stddev-sans-first:98377.29 13:03:07 INFO - PID 1640 | Values: 806.2 838.8 1023.6 1059.3 1160.0 37649.0 38681.4 38772.4 38828.6 38849.6 31030.4 38184.0 38209.8 38265.6 38314.2 8062.8 8207.7 8253.3 8477.9 8752.2 7497.9 8686.2 8786.7 8792.6 8846.8 8749.0 8788.5 8817.8 8821.0 8846.5 8689.5 8744.4 8772.2 8783.7 8790.5 8614.1 8635.1 8679.1 8688.7 8699.0 37612.8 37962.2 38010.0 38014.0 38126.6 3457.1 3479.1 3483.7 3488.3 3489.0 21608.7 24975.0 30418.9 30544.7 30667.0 2627.9 3060.4 3070.5 3118.8 3137.0 306507.0 308546.0 311447.0 311887.0 314511.0 47232.3 47429.3 47605.2 48114.0 48615.5 50358.5 50682.0 50732.0 50739.0 50920.7 12269.3 13298.9 13368.5 13387.0 13413.1 10038.9 10515.0 10567.6 10598.5 10610.8 11770.6 13101.0 13654.0 13689.2 13786.8 5018.6 5265.8 5600.2 5859.8 5897.4 860.5 970.8 976.3 997.7 1020.8 20900.9 22327.0 23144.0 24336.8 24577.6 1059.3 1065.6 1086.4 1102.5 1107.8 10146.0 12185.6 12218.9 12349.7 12365.2 347012.3 411700.0 417929.0 419947.0 420950.0 55069.7 55316.4 55319.4 55423.7 55457.0 1456.9 1462.5 1491.6 1493.9 1497.8 23585.6 23797.2 23798.6 23871.5 23906.9 38723.8 47143.8 51503.0 51535.0 51942.0 332610.0 400262.0 402412.0 405747.0 407472.0 27821.6 28480.0 33734.8 34938.5 35382.6 42361.4 42837.0 43710.6 43784.8 44040.1 28210.7 29433.4 30095.8 30120.9 30247.0 27403.7 27509.7 29717.6 29908.4 30285.6 34632.3 35458.6 35522.9 35731.1 35808.5 29244.0 29827.3 30134.8 30777.0 30849.7 23955.9 24038.5 24236.0 24379.3 24546.6 13:03:07 INFO - PID 1640 | 13:03:07 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:39848.26 Median:23733.44 stddev:113859.66 (479.7%) stddev-sans-first:114140.59 13:03:07 INFO - PID 1640 | Values: 426.8 436.3 438.7 451.6 455.2 20932.8 21700.3 22002.3 22204.0 22437.9 21963.7 22270.9 22839.7 22845.9 23144.9 5239.7 5565.4 5978.2 6203.1 6238.2 6069.5 6192.1 6223.7 6225.9 6230.8 6008.2 6085.2 6117.6 6177.1 6187.2 6024.5 6099.5 6105.8 6106.1 6108.6 5326.7 5349.4 5443.2 5451.6 5452.0 26565.0 26614.6 26752.7 26870.1 26904.9 2162.3 2222.6 2227.5 2232.4 2233.4 16968.9 17806.6 17819.0 17977.5 18012.0 2079.3 2117.4 2160.5 2164.5 2169.6 145305.0 145422.0 146441.3 147990.3 149390.3 10323.3 12177.9 12647.7 12748.2 12782.4 16194.4 16269.3 16289.1 16346.8 16377.6 8834.2 9033.2 9043.0 9051.7 9074.5 8528.5 8580.9 8633.6 8741.9 8812.6 7102.6 7750.0 7830.9 7907.7 7910.9 1747.0 2077.2 2180.9 2184.6 2190.7 980.2 980.6 981.3 982.5 990.6 15810.8 16554.6 24322.0 26170.0 26225.3 877.2 921.4 938.3 994.8 1011.5 9256.4 10764.8 10766.8 10780.4 11338.6 667381.0 669272.0 679675.0 688803.0 689622.0 151310.0 152192.0 152444.2 155888.2 157328.2 1165.7 1207.7 1253.9 1258.3 1263.6 18912.9 21106.2 21166.2 21644.7 21803.1 54521.7 58641.4 60648.0 62076.4 62642.0 80121.0 80609.0 81501.6 81636.0 82253.0 8590.5 8593.3 8619.4 8910.6 9085.3 13934.8 13984.9 14164.0 14429.4 14461.9 11335.1 11698.3 11975.0 11985.3 12097.6 11186.2 11212.9 11355.0 11385.5 11626.6 9737.6 11822.8 11978.5 12229.8 12263.8 8347.6 8554.3 8618.9 8695.7 8795.5 11633.7 11722.5 11910.9 11990.3 12013.8 13:03:07 INFO - PID 1640 | 13:03:07 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:6840.25 Median:2477.61 stddev:4201.37 (169.6%) stddev-sans-first:4193.19 13:03:07 INFO - PID 1640 | Values: 1389.5 1419.5 1432.4 1437.2 1462.7 10716.0 11131.7 11154.0 11169.8 11473.7 11009.7 11088.9 11200.7 11216.3 11272.2 2427.4 2476.5 2478.8 2481.8 2487.0 2408.0 2413.9 2445.3 2467.4 2470.5 2406.6 2412.3 2429.1 2450.0 2451.9 2359.4 2399.7 2408.7 2410.5 2439.0 2334.8 2423.4 2423.9 2433.1 2433.6 11026.9 11076.4 11090.9 11095.5 11098.7 6244.0 6353.9 6426.1 6460.7 6476.2 3830.1 4077.8 4077.9 4123.5 4139.9 1184.3 1188.5 1223.6 1233.2 1236.4 13922.2 14463.3 15071.4 15195.5 15209.2 4656.4 5601.9 5962.3 5980.3 5995.4 12767.3 12787.9 12788.2 12802.0 12839.8 6313.9 6369.5 6372.6 6376.1 6386.6 5733.0 5761.7 5771.9 5930.6 5965.3 4502.8 4555.4 4560.4 4585.6 4616.3 1410.2 1445.6 1445.8 1461.3 1469.5 760.2 767.3 789.4 789.5 802.3 6432.8 6451.4 6614.8 6632.0 6667.6 486.3 584.0 584.1 586.1 591.8 4021.6 4031.9 4070.3 4114.6 4116.3 13380.1 13718.5 13865.3 13953.0 13974.3 13809.8 13815.7 13819.2 13842.2 13876.4 7748.7 10135.5 10171.2 10241.8 10248.7 5694.9 5712.4 6496.8 6739.9 6859.5 11249.3 12127.2 12146.0 12237.6 12240.8 12042.0 12573.9 12654.5 12664.9 12723.3 8991.6 9090.4 9145.5 9147.3 9157.6 10101.4 10128.7 10168.7 10218.7 10226.3 8567.0 8645.4 8650.7 8693.0 8825.6 8416.1 8476.1 8553.4 8564.5 8701.7 7521.5 7701.0 7738.7 7796.2 7826.5 5730.5 5798.5 5846.4 6008.3 6075.7 5792.9 5863.5 6042.8 6094.6 6191.8 13:03:07 INFO - PID 1640 | 13:03:07 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5331.72 Median:5879.21 stddev:1695.63 (28.8%) stddev-sans-first:1659.46 13:03:07 INFO - PID 1640 | Values: 398.4 405.5 406.0 409.4 415.8 4946.0 5435.4 6350.0 6421.7 6425.3 5653.4 6051.8 6159.5 6184.7 6256.2 3879.5 4448.1 4547.5 4573.2 4744.5 4585.4 4587.6 4588.5 4602.6 4609.2 4347.9 4576.1 4578.0 4584.7 4589.5 4409.0 4525.9 4546.8 4579.6 4580.8 3884.2 3887.3 3900.4 3900.7 3901.2 6072.6 6074.3 6122.3 6123.8 6145.8 5139.7 5359.8 5371.4 5401.0 5413.3 6618.6 6626.4 6631.4 6645.4 6652.0 3663.7 4414.5 4425.1 4425.3 4426.9 6233.5 6740.4 7015.0 7102.4 7160.2 3823.0 3959.9 4338.4 4496.6 4523.9 4200.8 4352.7 4551.3 4587.7 4591.8 5118.6 5393.4 5445.4 5454.6 5458.7 5672.7 6199.0 6705.4 6709.2 6711.4 1289.7 1546.3 1550.5 1559.5 1570.7 985.7 987.5 1012.7 1031.9 1033.7 5782.5 5872.8 6371.3 6376.2 6399.9 5845.9 6341.2 6616.0 6964.4 7014.9 5320.3 5797.0 5885.6 6341.6 6405.4 3070.5 3199.9 3389.4 3647.7 3670.3 6891.5 7135.2 7182.5 7214.2 7217.6 5789.0 7068.6 7083.4 7178.5 7184.8 5363.0 5785.5 6751.4 7083.0 7164.6 5416.4 5914.6 6482.9 6937.9 7000.6 6571.2 6732.0 6766.6 6826.9 6849.1 6401.7 6423.9 6551.6 6734.5 7203.7 3755.5 4388.3 4496.2 4515.6 4516.8 6528.2 6886.3 6902.1 7048.6 7056.6 5498.3 6679.2 6706.0 6730.9 6772.3 5528.9 5708.0 6562.7 6758.2 6759.3 5333.8 6405.3 6410.4 6536.9 6584.7 6711.7 6735.3 6747.5 6756.4 6764.5 5409.9 6216.3 6246.3 6514.9 6573.8 13:03:07 INFO - PID 1640 | 13:03:07 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:19708.77 Median:25202.06 stddev:28391.49 (112.7%) stddev-sans-first:28435.58 13:03:07 INFO - PID 1640 | Values: 784.8 824.1 824.2 836.1 836.6 15676.5 15777.4 16181.1 16341.3 16407.0 12759.5 13611.7 15127.7 15327.9 15330.2 953.7 989.5 994.2 996.7 998.0 984.8 1004.4 1031.3 1032.4 1035.7 1028.9 1037.4 1038.3 1048.5 1062.3 1022.3 1039.0 1039.4 1042.6 1047.2 775.2 784.4 785.5 786.2 786.6 16179.3 16590.9 16696.9 16737.6 16978.2 14553.3 14749.6 14786.8 14879.3 15570.1 13097.3 13425.7 13544.8 13601.5 13625.8 3243.9 3262.3 3290.0 3293.3 3298.2 97552.0 114191.0 116599.0 117251.0 120812.4 11405.4 11487.8 11665.5 11699.8 11707.7 24615.6 25081.9 25133.9 25270.2 25461.9 7707.5 7746.1 7854.2 7874.5 8021.7 3959.9 4058.9 4106.5 4175.1 4184.5 4152.6 4238.4 4260.5 4267.2 4281.9 919.6 924.4 940.7 943.8 945.1 532.5 626.1 641.0 644.5 659.0 8228.9 9475.5 9568.0 10261.5 10415.4 606.1 619.3 625.2 626.7 664.1 4206.7 4636.5 4754.8 4817.0 4819.3 82505.2 96925.1 103171.5 103314.5 105906.0 75460.6 87924.0 91091.5 93307.0 93996.0 60443.4 73115.0 75391.9 76937.0 77195.5 8371.0 8777.1 10504.3 10825.9 11120.3 36206.4 38193.6 38230.0 39308.4 39557.0 45098.8 45105.0 46220.0 48015.0 49273.5 10528.9 13348.8 13389.3 13446.7 13635.3 14305.8 14331.9 14494.6 14569.6 14599.3 11716.6 13226.2 13431.3 13583.9 14045.5 11770.1 13273.7 13638.2 13709.5 14469.6 13539.2 13715.5 13947.0 14118.2 14658.9 8727.4 10065.1 10149.4 10312.0 10524.0 9306.8 9746.3 10383.9 10984.2 11072.5 13:03:07 INFO - PID 1640 | -------- Summary: end -------- 13:03:07 INFO - PID 1640 | 13:03:08 INFO - TEST-INFO | 1640: exit 0 13:03:08 INFO - TEST-OK | dromaeo_css | took 1117276ms 13:03:08 INFO - TEST-START | kraken 13:03:08 INFO - operating with platform_type : mac_ 13:03:08 INFO - Initialising browser for kraken test... 13:03:08 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmpdOAa7G/profile http://localhost:49241/getInfo.html) 13:03:11 INFO - PID 1652 | 2017-04-04 13:03:11.245 plugin-container[1653:10290] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444f, name = 'com.apple.tsm.portname' 13:03:11 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:03:11 INFO - PID 1652 | 2017-04-04 13:03:11.249 plugin-container[1653:10290] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 13:03:11 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:03:11 INFO - PID 1652 | 2017-04-04 13:03:11.249 plugin-container[1653:10290] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:03:11 INFO - PID 1652 | __metrics Screen width/height:1600/1200 13:03:11 INFO - PID 1652 | colorDepth:24 13:03:11 INFO - PID 1652 | Browser inner width/height: 1024/689 13:03:11 INFO - PID 1652 | __metrics 13:03:11 ERROR - PID 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3355: TypeError: this.tabs is undefined 13:03:12 INFO - TEST-INFO | 1652: exit 0 13:03:12 INFO - Browser initialized. 13:03:12 INFO - Running cycle 1/1 for kraken test... 13:03:12 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmpdOAa7G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:03:15 INFO - PID 1654 | 2017-04-04 13:03:15.452 plugin-container[1655:10408] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 13:03:15 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:03:15 INFO - PID 1654 | 2017-04-04 13:03:15.453 plugin-container[1655:10408] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 13:03:15 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:03:15 INFO - PID 1654 | 2017-04-04 13:03:15.453 plugin-container[1655:10408] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:03:25 INFO - PID 1654 | RSS: Main: 234618880 13:03:25 INFO - PID 1654 | 13:03:28 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 13:03:29 INFO - PID 1654 | RSS: Main: 248360960 13:03:29 INFO - PID 1654 | 13:03:31 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 13:03:32 INFO - PID 1654 | RSS: Main: 256790528 13:03:32 INFO - PID 1654 | 13:03:36 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 13:03:37 INFO - PID 1654 | RSS: Main: 259108864 13:03:37 INFO - PID 1654 | 13:03:39 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 13:03:39 INFO - PID 1654 | RSS: Main: 261160960 13:03:39 INFO - PID 1654 | 13:03:41 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:03:42 INFO - PID 1654 | RSS: Main: 261439488 13:03:42 INFO - PID 1654 | 13:03:45 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 13:03:46 INFO - PID 1654 | RSS: Main: 267022336 13:03:46 INFO - PID 1654 | 13:03:49 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 13:03:50 INFO - PID 1654 | RSS: Main: 267993088 13:03:50 INFO - PID 1654 | 13:03:53 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 13:03:53 INFO - PID 1654 | RSS: Main: 267919360 13:03:53 INFO - PID 1654 | 13:03:55 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:03:55 INFO - PID 1654 | RSS: Main: 267677696 13:03:55 INFO - PID 1654 | 13:03:57 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:03:58 INFO - PID 1654 | RSS: Main: 267698176 13:03:58 INFO - PID 1654 | 13:04:00 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:04:01 INFO - PID 1654 | RSS: Main: 267964416 13:04:01 INFO - PID 1654 | 13:04:04 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:04:05 INFO - PID 1654 | RSS: Main: 268427264 13:04:05 INFO - PID 1654 | 13:04:08 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:04:09 INFO - PID 1654 | RSS: Main: 275976192 13:04:09 INFO - PID 1654 | 13:04:10 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 13:04:11 INFO - PID 1654 | RSS: Main: 278765568 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | __start_tp_report 13:04:11 INFO - PID 1654 | _x_x_mozilla_page_load 13:04:11 INFO - PID 1654 | _x_x_mozilla_page_load_details 13:04:11 INFO - PID 1654 | |i|pagename|runs| 13:04:11 INFO - PID 1654 | |0;ai-astar;108;122;121;124;121;123;104;183;181;122 13:04:11 INFO - PID 1654 | |1;audio-beat-detection;153;114;116;157;146;150;156;134;134;118 13:04:11 INFO - PID 1654 | |2;audio-dft;206;281;277;222;260;249;298;202;211;213 13:04:11 INFO - PID 1654 | |3;audio-fft;88;67;76;84;93;88;88;62;72;60 13:04:11 INFO - PID 1654 | |4;audio-oscillator;86;72;89;75;73;75;87;72;74;87 13:04:11 INFO - PID 1654 | |5;imaging-gaussian-blur;80;141;142;92;92;92;92;90;91;91 13:04:11 INFO - PID 1654 | |6;imaging-darkroom;101;103;101;118;113;109;99;151;160;101 13:04:11 INFO - PID 1654 | |7;imaging-desaturate;104;103;95;104;102;101;101;101;126;107 13:04:11 INFO - PID 1654 | |8;json-parse-financial;52;51;52;51;51;54;53;51;51;52 13:04:11 INFO - PID 1654 | |9;json-stringify-tinderbox;60;64;62;88;68;56;65;89;87;59 13:04:11 INFO - PID 1654 | |10;stanford-crypto-aes;99;85;88;74;103;115;88;87;85;87 13:04:11 INFO - PID 1654 | |11;stanford-crypto-ccm;162;151;150;145;157;183;174;146;215;162 13:04:11 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;197;196;195;197;197;196;241;195;196;196 13:04:11 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;73;73;77;72;71;69;70;73;73;70 13:04:11 INFO - PID 1654 | __end_tp_report 13:04:11 INFO - PID 1654 | __start_cc_report 13:04:11 INFO - PID 1654 | _x_x_mozilla_cycle_collect,993 13:04:11 INFO - PID 1654 | __end_cc_report 13:04:11 INFO - PID 1654 | __startTimestamp1491336251143__endTimestamp 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | ------- Summary: start ------- 13:04:11 INFO - PID 1654 | Number of tests: 14 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:130.90 Median:122.50 stddev:27.76 (22.7%) stddev-sans-first:28.18 13:04:11 INFO - PID 1654 | Values: 108.0 122.0 121.0 124.0 121.0 123.0 104.0 183.0 181.0 122.0 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:148.00 stddev:17.03 (11.5%) stddev-sans-first:17.15 13:04:11 INFO - PID 1654 | Values: 153.0 114.0 116.0 157.0 146.0 150.0 156.0 134.0 134.0 118.0 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:241.90 Median:254.50 stddev:35.52 (14.0%) stddev-sans-first:35.22 13:04:11 INFO - PID 1654 | Values: 206.0 281.0 277.0 222.0 260.0 249.0 298.0 202.0 211.0 213.0 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:77.80 Median:86.00 stddev:12.03 (14.0%) stddev-sans-first:12.18 13:04:11 INFO - PID 1654 | Values: 88.0 67.0 76.0 84.0 93.0 88.0 88.0 62.0 72.0 60.0 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:80.50 stddev:7.21 (9.0%) stddev-sans-first:7.19 13:04:11 INFO - PID 1654 | Values: 86.0 72.0 89.0 75.0 73.0 75.0 87.0 72.0 74.0 87.0 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:100.30 Median:91.50 stddev:22.02 (24.1%) stddev-sans-first:22.09 13:04:11 INFO - PID 1654 | Values: 80.0 141.0 142.0 92.0 92.0 92.0 92.0 90.0 91.0 91.0 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:115.60 Median:115.50 stddev:22.00 (19.0%) stddev-sans-first:22.69 13:04:11 INFO - PID 1654 | Values: 101.0 103.0 101.0 118.0 113.0 109.0 99.0 151.0 160.0 101.0 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:104.40 Median:104.00 stddev:8.19 (7.9%) stddev-sans-first:8.69 13:04:11 INFO - PID 1654 | Values: 104.0 103.0 95.0 104.0 102.0 101.0 101.0 101.0 126.0 107.0 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:1.03 (2.0%) stddev-sans-first:1.09 13:04:11 INFO - PID 1654 | Values: 52.0 51.0 52.0 51.0 51.0 54.0 53.0 51.0 51.0 52.0 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:66.50 stddev:12.99 (19.5%) stddev-sans-first:13.29 13:04:11 INFO - PID 1654 | Values: 60.0 64.0 62.0 88.0 68.0 56.0 65.0 89.0 87.0 59.0 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:87.00 stddev:11.52 (13.2%) stddev-sans-first:11.86 13:04:11 INFO - PID 1654 | Values: 99.0 85.0 88.0 74.0 103.0 115.0 88.0 87.0 85.0 87.0 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:164.50 Median:162.00 stddev:21.52 (13.3%) stddev-sans-first:22.80 13:04:11 INFO - PID 1654 | Values: 162.0 151.0 150.0 145.0 157.0 183.0 174.0 146.0 215.0 162.0 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:200.60 Median:196.50 stddev:14.21 (7.2%) stddev-sans-first:15.02 13:04:11 INFO - PID 1654 | Values: 197.0 196.0 195.0 197.0 197.0 196.0 241.0 195.0 196.0 196.0 13:04:11 INFO - PID 1654 | 13:04:11 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:73.00 stddev:2.28 (3.1%) stddev-sans-first:2.40 13:04:11 INFO - PID 1654 | Values: 73.0 73.0 77.0 72.0 71.0 69.0 70.0 73.0 73.0 70.0 13:04:11 INFO - PID 1654 | -------- Summary: end -------- 13:04:11 INFO - PID 1654 | 13:04:12 INFO - TEST-INFO | 1654: exit 0 13:04:12 INFO - TEST-OK | kraken | took 64109ms 13:04:12 INFO - SUITE-END | took 1181s 13:04:12 INFO - Completed test suite (00:19:41) 13:04:12 INFO - javascript benchmark 13:04:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7736.673792423197, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3564.857405703772, 3572.231970126424, 3582.8387722552484, 3595.3887839067356, 3624.0758606555323, 6092.939070609293, 6193.938060619393, 6217.720202590883, 6226.813195604128, 6242.594231374957, 6156.876862462748, 6168.290646575645, 6182.412670796279, 6229.345918678541, 6269.905951410732, 5415.48552887475, 5449.291592093023, 5509.752061157237, 5522.447755224465, 5561.0, 5405.594580406466, 5462.781488740446, 5551.472610102034, 5563.916541251884, 5576.804811831586, 5239.52844244018, 5380.784768609251, 5434.972825135888, 5487.6158668893195, 5488.231647569343, 5455.972720136374, 5459.754311056012, 5477.698726570037, 5486.753096110645, 5535.557155427556, 4852.805887764485, 5175.974120129413, 5412.648177868426, 5444.183372494118, 5446.809361672362, 5112.744362781883, 5408.215808707755, 6068.544859135583, 6144.402455361638, 6225.003999360126, 6638.402543771083, 6711.932880671179, 6712.295209003082, 6716.361945615159, 6732.326767323278, 5653.180288858092, 6766.052752614638, 6774.085498457744, 6778.661066946633, 6796.932030679679, 5089.643724939293, 6226.59527130735, 6274.749010039593, 6278.623282602967, 6297.30729619746, 6884.586924784428, 6923.238443771181, 6946.444284457231, 6949.548279361826, 6953.0, 6163.907541386883, 6309.779157729457, 6462.3537646235, 6532.575382600211, 6533.607983521004, 5978.491828194565, 6799.3200679931615, 6805.625690586966, 6810.353016463428, 6822.454203663695, 6355.0, 6431.099646049557, 6672.132622759107, 6696.83257918556, 6715.026321183401, 6538.476921846241, 6542.24764152119, 6576.901346479806, 6649.734010639617, 6656.101426307387, 4173.728707633994, 5307.28351672527, 5352.785888564492, 5379.515843574097, 5421.403645598981, 4242.981684395722, 4571.085782843445, 4749.976250118727, 4769.856904292877, 4795.136875362398, 5227.634065615371, 6076.635401875901, 6283.994560870239, 6289.150964619809, 6395.552311338255, 6778.8644227116165, 6815.114035175396, 6824.0, 6824.727010919508, 6826.351496607815, 6294.2446906370615, 6299.6535190564955, 6345.270293916166, 6361.363863613602, 6393.073004414334, 5740.225069615632, 5816.505597024216, 5818.76724930998, 5942.435468630473, 5968.820935371946, 5769.884602307847, 6769.796906092726, 6816.079829222991, 6942.51402401827, 6968.756093536905, 6754.527183097136, 6780.830479238059, 6799.490038247052, 6805.863882722417, 6821.11325527698, 5931.495822854933, 6972.930270697431, 6982.685779140053, 6996.930030699831, 7004.929950700428, 6336.398042185985, 6413.262474815456, 6547.803565893031, 6626.768063117768, 6689.297623749436, 6336.112944187909, 6800.149981252343, 6822.590644561342, 6827.556208846409, 6846.041554182319, 6296.370362963667, 6389.584676995981, 6535.411812936859, 6535.509836762167, 6551.148350714377, 6508.967455162694, 6509.934900650933, 6512.218533775977, 6521.282658907611, 6522.315156908457, 5202.505761952609, 5243.292155559103, 6191.690415479298, 6246.250449946036, 6398.872022559616, 6288.937110628835, 6449.871002580016, 6456.418922297016, 6514.511411644051, 6547.934520654923, 5265.3944796348915, 5773.874094551522, 5910.970445147746, 6352.936470635234, 6371.681415929278], "value": 6038.13513365123, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [806.1615919443777, 838.7735311465905, 1023.6161439460202, 1059.3379138038727, 1159.965201043969, 37649.0, 38681.419778703304, 38772.41841372382, 38828.611713882856, 38849.61150388495, 31030.37939241214, 38184.0, 38209.808950955274, 38265.617343826554, 38314.233715325674, 8062.838743225132, 8207.712730054449, 8253.29846963006, 8477.872831907527, 8752.212300892917, 7497.850042999136, 8686.174813392718, 8786.6924657637, 8792.560371981408, 8846.778830529223, 8748.956255218747, 8788.472691638523, 8817.779555511132, 8820.955895220515, 8846.5134417607, 8689.478631282143, 8744.431611945203, 8772.210501054935, 8783.692570760024, 8790.51652159131, 8614.095519970439, 8635.093315201939, 8679.132086791333, 8688.695895643685, 8698.956505217497, 37612.811935940415, 37962.24075518474, 38010.049748756224, 38014.049648759, 38126.61873381258, 3457.083872773717, 3479.0954351868445, 3483.651634836521, 3488.2500262443696, 3489.0, 21608.675869861963, 24975.0, 30418.935337263312, 30544.694553054407, 30667.0, 2627.921162365132, 3060.3879224155257, 3070.462669032933, 3118.8128712277107, 3137.043201823443, 306507.0, 308546.0, 311447.0, 311887.0, 314511.0, 47232.291515627294, 47429.288560671615, 47605.19158467314, 48114.0, 48615.51384486181, 50358.49641503611, 50682.0, 50732.0, 50739.0, 50920.74539627352, 12269.325187114795, 13298.867011329763, 13368.532101376406, 13386.995975301892, 13413.061085723906, 10038.949805250926, 10515.001074897873, 10567.573377593926, 10598.523066461874, 10610.840837387444, 11770.58802941893, 13101.0, 13654.0, 13689.247091409869, 13786.793198102036, 5018.648694591417, 5265.788368885001, 5600.215969764236, 5859.828034393138, 5897.380775018647, 860.4708104515678, 970.7767213540925, 976.3019441099648, 997.7056768253383, 1020.7601213714771, 20900.913451014036, 22327.0, 23144.0, 24336.756632433804, 24577.631335529983, 1059.3273271472517, 1065.5844220753913, 1086.4459125845717, 1102.5204036244259, 1107.7814404155527, 10146.0, 12185.573504927284, 12218.877811221773, 12349.691257718629, 12365.196262242926, 347012.2649386737, 411700.0, 417929.0, 419947.0, 420950.0, 55069.72465137729, 55316.44683553193, 55319.446805532236, 55423.722881385336, 55457.0, 1456.9344379502948, 1462.473509536566, 1491.5823569400586, 1493.8954273200989, 1497.7903093566908, 23585.646215307126, 23797.167099151447, 23798.643020355055, 23871.522569548164, 23906.924188411216, 38723.806380967915, 47143.82140446516, 51502.969940601724, 51535.0, 51942.0, 332610.0, 400262.0, 402412.0, 405747.0, 407472.0, 27821.582676260277, 28480.0, 33734.83132584419, 34938.47592286067, 35382.646173537934, 42361.36457953072, 42837.0, 43710.56289437065, 43784.78107609441, 44040.11919761651, 28210.71789282163, 29433.411331773674, 30095.849520752257, 30120.945766898054, 30247.0, 27403.725962740118, 27509.724902750717, 29717.554236686898, 29908.401831963674, 30285.577153828082, 34632.307353853284, 35458.64541354657, 35522.93431197068, 35731.10672233215, 35808.46287305641, 29244.0, 29827.254318642208, 30134.849325753232, 30777.0, 30849.691503084683, 23955.880220598785, 24038.51922961496, 24236.0, 24379.250395011302, 24546.631800523362], "value": 17617.662878475414, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.8186020941099, 436.25836078666265, 438.7214119034412, 451.5958217395431, 455.1534146487535, 20932.790672093273, 21700.34898953032, 22002.339929802092, 22204.0008199631, 22437.887810560966, 21963.6705449418, 22270.888645556748, 22839.657405138936, 22845.885770571123, 23144.88427557864, 5239.711815850127, 5565.387807341189, 5978.22283103196, 6203.131561581382, 6238.157848690436, 6069.484093852006, 6192.102145188945, 6223.657698826562, 6225.937740622581, 6230.750769969196, 6008.158857759917, 6085.178500902366, 6117.632942023471, 6177.104319873638, 6187.226596675416, 6024.5180385569265, 6099.48154406876, 6105.755769769204, 6106.145139680426, 6108.572399932006, 5326.6537675050995, 5349.358077030743, 5443.1835224716215, 5451.618386712932, 5451.972740136313, 26565.0, 26614.6007809883, 26752.73247267522, 26870.059547915924, 26904.865475672694, 2162.275637661385, 2222.6443768996883, 2227.465408301996, 2232.3526177408694, 2233.3746550965757, 16968.915155424267, 17806.643867122715, 17819.0, 17977.460676179606, 18012.0, 2079.2618620389844, 2117.3542069668747, 2160.470684682263, 2164.5346250556204, 2169.5986242545023, 145305.0, 145422.0, 146441.26779366142, 147990.26004870015, 149390.25304873407, 10323.32898361604, 12177.93911030457, 12647.683807904692, 12748.171368860994, 12782.360881955865, 16194.433194838124, 16269.349226031063, 16289.104099274653, 16346.83653163477, 16377.590560236089, 8834.204921557091, 9033.187013168847, 9043.005269420359, 9051.728448146434, 9074.546272686339, 8528.530930798866, 8580.871286930702, 8633.582596872886, 8741.91258087411, 8812.559372031374, 7102.573845569283, 7750.0, 7830.921690783133, 7907.683692652345, 7910.881336779953, 1747.0, 2077.1587507059667, 2180.869147851134, 2184.6286131357706, 2190.7371115466244, 980.1668581705493, 980.6273616025865, 981.3081777346953, 982.4792859784326, 990.5740531571349, 15810.841891581167, 16554.58613534647, 24322.0, 26170.0, 26225.344366390993, 877.2499512916493, 921.3504479342048, 938.2728385501291, 994.791328535818, 1011.4937951426524, 9256.444613323221, 10764.838527422095, 10766.838497422545, 10780.407077610807, 11338.60314888975, 667381.0, 669272.0, 679675.0, 688803.0, 689622.0, 151310.0, 152192.0, 152444.1756187295, 155888.2205588965, 157328.21335893247, 1165.6969188011178, 1207.6920385301692, 1253.9122261441612, 1258.2513404524293, 1263.6335462715892, 18912.905435472734, 21106.15575376968, 21166.153353865673, 21644.67532987038, 21803.12787488546, 54521.72739136279, 58641.41358586359, 60648.0, 62076.37923620706, 62642.0, 80121.0, 80609.0, 81501.59249203716, 81636.0, 82253.0, 8590.527520986405, 8593.31253499731, 8619.439736417113, 8910.55447227623, 9085.318601104811, 13934.79097813554, 13984.860151398356, 14164.0, 14429.35067921967, 14461.927690361481, 11335.093192544411, 11698.2981021139, 11975.0, 11985.34080625574, 12097.637070887888, 11186.161037922026, 11212.887871121184, 11355.0, 11385.544578216779, 11626.593069242535, 9737.561809718438, 11822.763544728885, 11978.46096925658, 12229.7770222977, 12263.816042759187, 8347.624356904076, 8554.272886804443, 8618.913810861812, 8695.695650652196, 8795.516246606498, 11633.6509904703, 11722.531098755955, 11910.880891190976, 11990.28058316469, 12013.75972480528], "value": 10563.650370199175, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.5275606293858, 1419.4960788919934, 1432.3769160415227, 1437.1736251655295, 1462.6855226126384, 10715.981981711744, 11131.72170695732, 11154.0, 11169.83245251322, 11473.655790326304, 11009.669709908696, 11088.889111108887, 11200.719982000433, 11216.270942388739, 11272.154588405881, 2427.4174198192395, 2476.455179860428, 2478.764517370847, 2481.789047930928, 2486.950260994779, 2407.987960060197, 2413.8551686898754, 2445.2664200739705, 2467.4201562632766, 2470.518248941457, 2406.578848701479, 2412.336607432961, 2429.1012325439524, 2449.951000979979, 2451.8774061296954, 2359.433735903388, 2399.6520504526834, 2408.735039145701, 2410.4817464245184, 2438.9512209755885, 2334.8248881333798, 2423.3941514621347, 2423.866687332196, 2433.0632706407982, 2433.6106223004235, 11026.944865275702, 11076.39079850608, 11090.889091109148, 11095.50070246841, 11098.667039988813, 6244.0, 6353.904691429632, 6426.0682201081045, 6460.741570337181, 6476.186667733043, 3830.119072613287, 4077.7553346799286, 4077.9184416311805, 4123.463949686551, 4139.9172016559805, 1184.2953442701578, 1188.512709788991, 1223.6267938278822, 1233.2477188914754, 1236.4312416288517, 13922.234277114754, 14463.276836158255, 15071.397144114222, 15195.544133676007, 15209.239538023165, 4656.416818281733, 5601.887962240814, 5962.284525856925, 5980.312264089598, 5995.430434108753, 12767.297798621164, 12787.936060319826, 12788.168769029984, 12802.039847011325, 12839.807402888964, 6313.9368606314265, 6369.522285828489, 6372.55392122547, 6376.139221205078, 6386.584871983307, 5732.971335143297, 5761.740721667535, 5771.913421298684, 5930.584859059911, 5965.3139888912465, 4502.797374118172, 4555.362249285103, 4560.429946256718, 4585.633149348044, 4616.28447590624, 1410.153907655419, 1445.551878917539, 1445.7614493608437, 1461.2693653173412, 1469.5003698742478, 760.1752098972567, 767.2634271099806, 789.3763926498117, 789.5144486140982, 802.2579114319348, 6432.839179020469, 6451.3548645135115, 6614.8346291343105, 6632.0383544385795, 6667.599944003376, 486.2948724349707, 583.988320233593, 584.141312270961, 586.0857062981762, 591.8429470385386, 4021.557628660845, 4031.9193616127513, 4070.2876996526084, 4114.629683328515, 4116.300228961084, 13380.063395562413, 13718.451261949609, 13865.306734663429, 13952.953528485405, 13974.301284935917, 13809.792853107212, 13815.723685526435, 13819.170849749047, 13842.169469831842, 13876.4449422024, 7748.728794492165, 10135.493225338556, 10171.237157213238, 10241.84637230442, 10248.692539223835, 5694.914576281276, 5712.400197979152, 6496.805095846943, 6739.93260067393, 6859.485538584527, 11249.268797528463, 12127.211731237441, 12146.0, 12237.571684990982, 12240.816387754368, 12042.0, 12573.874261257637, 12654.493820247088, 12664.87335126637, 12723.300218488072, 8991.550422478718, 9090.409123407222, 9145.451272923647, 9147.268218542633, 9157.633694652139, 10101.44442055694, 10128.733908261467, 10168.694939151837, 10218.693439196835, 10226.335288206243, 8567.0, 8645.394822362374, 8650.740477785677, 8693.0, 8825.602847871989, 8416.074231834371, 8476.067632560536, 8553.358498112791, 8564.52895090776, 8701.695440659547, 7521.4734968551675, 7700.961495192488, 7738.690452381842, 7796.181400952818, 7826.530408175528, 5730.484256416942, 5798.507126894261, 5846.35690074083, 6008.309044459943, 6075.726592303268, 5792.884142317214, 5863.501602363847, 6042.848928776816, 6094.573379863367, 6191.84520386994], "value": 5183.5308328497895, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.4063745019921, 405.54475865624875, 406.0, 409.4308910614247, 415.8004158004158, 4946.0, 5435.374931882835, 6349.984002559591, 6421.678916054196, 6425.325340839209, 5653.434656534345, 6051.759389325189, 6159.507239420846, 6184.659843708594, 6256.249250089984, 3879.4568760373572, 4448.132614140244, 4547.454305483347, 4573.222552166139, 4744.5492678195515, 4585.449746030481, 4587.632989360844, 4588.4723256825555, 4602.562756538124, 4609.239475486541, 4347.891302717426, 4576.084783043388, 4577.977110114445, 4584.7478388688605, 4589.47221069578, 4409.008892578266, 4525.904056828575, 4546.772661366918, 4579.618598328867, 4580.770961451914, 3884.2037382336553, 3887.2614203301564, 3900.3759398496104, 3900.7074469414906, 3901.200253947934, 6072.574919755619, 6074.331823499411, 6122.295935967376, 6123.805857857719, 6145.8156255312415, 5139.691618502902, 5359.785608575652, 5371.382291036542, 5400.972995134999, 5413.296271484721, 6618.602883826986, 6626.403623673892, 6631.403173714389, 6645.435138013275, 6651.966740166268, 3663.688586470153, 4414.514403415622, 4425.1149770045795, 4425.291953287458, 4426.9154057255855, 6233.532485063638, 6740.39336459721, 7015.0, 7102.39629631482, 7160.209947513122, 3823.0, 3959.9110244682365, 4338.392625032498, 4496.595306422438, 4523.864284071483, 4200.831966721297, 4352.717073390283, 4551.271796512542, 4587.747673877902, 4591.793369298322, 5118.564921981599, 5393.379761327497, 5445.401005889349, 5454.61817672767, 5458.727063646802, 5672.688002159838, 6198.977168767103, 6705.362990515893, 6709.228438729609, 6711.395974362331, 1289.7356042011365, 1546.2500687166714, 1550.4805890026967, 1559.5184574654124, 1570.6701592665668, 985.6747273399761, 987.5161171026289, 1012.7417508535276, 1031.8761748590218, 1033.705393962717, 5782.5254560086905, 5872.825434913035, 6371.267304259976, 6376.202974628171, 6399.872002559922, 5845.88308233833, 6341.239051313872, 6616.0, 6964.4428445724225, 7014.859702806018, 5320.308359913187, 5796.971015144982, 5885.588008839341, 6341.619502829845, 6405.43782487009, 3070.462669032955, 3199.86404826288, 3389.389909816257, 3647.6686009606833, 3670.284294562535, 6891.483138764513, 7135.179454362711, 7182.491676747837, 7214.206437291894, 7217.566945983258, 5789.015867302569, 7068.575885446787, 7083.397911177608, 7178.497505174692, 7184.82037949045, 5362.973185134049, 5785.479306862402, 6751.358620931003, 7082.964585177041, 7164.570125792469, 5416.404195538409, 5914.585978981602, 6482.897907355855, 6937.89593156093, 7000.579965202104, 6571.244306904576, 6732.0, 6766.560173588701, 6826.931730682828, 6849.075374824534, 6401.679916004275, 6423.875821731272, 6551.639659818756, 6734.4949128816515, 7203.747868824566, 3755.4742336072427, 4388.319810429389, 4496.190685676479, 4515.616172625232, 4516.841910533116, 6528.2492513359675, 6886.311368863074, 6902.137232845895, 7048.577085374894, 7056.6118863463, 5498.285222920995, 6679.165104361955, 6706.0, 6730.947061146398, 6772.288909664414, 5528.861778455571, 5708.029634962149, 6562.737490500326, 6758.222804377363, 6759.290274521301, 5333.7866485340155, 6405.327440618668, 6410.391012853772, 6536.869262614816, 6584.670766461562, 6711.697973591103, 6735.25912149673, 6747.493937954575, 6756.391924726799, 6764.526483146133, 5409.891802164014, 6216.2540495140875, 6246.3441338660605, 6514.9348506514325, 6573.835654108686], "value": 4803.824158179113, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.7790752171355, 824.1181935329198, 824.1799409587461, 836.126248070766, 836.6360633124591, 15676.529704108894, 15777.368905243791, 16181.110038947852, 16341.346346146169, 16407.015579065264, 12759.489620415172, 13611.727765444684, 15127.6974460511, 15327.923360383182, 15330.233488325566, 953.7472569769034, 989.5101924547359, 994.2294721590789, 996.6511720897701, 998.0269237493422, 984.807962447323, 1004.4324956399647, 1031.3244824639874, 1032.4270030133273, 1035.715178325959, 1028.8765348158195, 1037.3879411147423, 1038.2732087538716, 1048.4652827058217, 1062.2617280989716, 1022.330373605286, 1038.9636362727306, 1039.449091981251, 1042.5881776698238, 1047.2355180718118, 775.1744392222265, 784.4391260248892, 785.5012067337225, 786.2137862137862, 786.5831109511968, 16179.271932763055, 16590.917045414815, 16696.916515417346, 16737.581560460963, 16978.23597938096, 14553.272336383245, 14749.631259218499, 14786.778198327034, 14879.256037198096, 15570.06579605216, 13097.279649619268, 13425.66435839102, 13544.796828047587, 13601.523946661819, 13625.795613065811, 3243.918902027445, 3262.347530493887, 3290.0, 3293.2919422324076, 3298.224917144469, 97552.02447975571, 114191.0, 116598.99999999916, 117251.0, 120812.39593801975, 11405.429728513542, 11487.827682584768, 11665.533378664759, 11699.824502632468, 11707.707307317385, 24615.63076553853, 25081.87459062693, 25133.87433062823, 25270.241892743248, 25461.872690636428, 7707.499012564165, 7746.109197442253, 7854.175311592315, 7874.488158269694, 8021.679132834738, 3959.920801583952, 4058.939115913264, 4106.507219133663, 4175.144095460423, 4184.476940382452, 4152.563980782034, 4238.427812245369, 4260.531341552427, 4267.1679022590915, 4281.914361712748, 919.5723988345452, 924.3760461688333, 940.6754669638964, 943.7640589852537, 945.0691069296737, 532.5127508329912, 626.1328060636051, 640.961542307463, 644.490852226745, 659.0443856408227, 8228.876566851503, 9475.526223688788, 9568.0, 10261.486925653688, 10415.375077495222, 606.1059936593489, 619.3366238698051, 625.2309659119218, 626.6897885546683, 664.0968283134932, 4206.663466922639, 4636.466806317317, 4754.833580824655, 4817.0365926814775, 4819.2530157825595, 82505.17494825095, 96925.06149876963, 103171.4841425788, 103314.48342758238, 105906.0, 75460.62269688616, 87924.00949881265, 91091.54454227819, 93307.0, 93996.0, 60443.39556604378, 73115.0, 75391.86912196322, 76937.0, 77195.45609087787, 8371.0, 8777.122287771428, 10504.317219380715, 10825.891741082489, 11120.277181983145, 36206.379362063584, 38193.618063819005, 38230.0, 39308.41037384384, 39557.0, 45098.77450612726, 45105.0, 46220.0, 48015.0, 49273.50726492689, 10528.942364795077, 13348.799768003682, 13389.330533473092, 13446.731065378834, 13635.318234088454, 14305.785413218602, 14331.856681433052, 14494.637634059232, 14569.562913112624, 14599.343029563908, 11716.589919352782, 13226.206427614374, 13431.328433578477, 13583.913286936824, 14045.508407205698, 11770.058395328524, 13273.734525309634, 13638.249896255404, 13709.545054387514, 14469.638259043608, 13539.166484835136, 13715.451381944611, 13947.0, 14118.223497707313, 14658.853411465749, 8727.389082764399, 10065.144462720458, 10149.441780702133, 10312.0, 10524.0, 9306.767330816783, 9746.26902982265, 10383.896161038292, 10984.231103822656, 11072.501737421997], "value": 7663.303911848738, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1637.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 122.0, 121.0, 124.0, 121.0, 123.0, 104.0, 183.0, 181.0, 122.0], "value": 130.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 114.0, 116.0, 157.0, 146.0, 150.0, 156.0, 134.0, 134.0, 118.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 281.0, 277.0, 222.0, 260.0, 249.0, 298.0, 202.0, 211.0, 213.0], "value": 241.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 67.0, 76.0, 84.0, 93.0, 88.0, 88.0, 62.0, 72.0, 60.0], "value": 77.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 89.0, 75.0, 73.0, 75.0, 87.0, 72.0, 74.0, 87.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 141.0, 142.0, 92.0, 92.0, 92.0, 92.0, 90.0, 91.0, 91.0], "value": 100.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 103.0, 101.0, 118.0, 113.0, 109.0, 99.0, 151.0, 160.0, 101.0], "value": 115.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 103.0, 95.0, 104.0, 102.0, 101.0, 101.0, 101.0, 126.0, 107.0], "value": 104.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 51.0, 51.0, 54.0, 53.0, 51.0, 51.0, 52.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 62.0, 88.0, 68.0, 56.0, 65.0, 89.0, 87.0, 59.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 85.0, 88.0, 74.0, 103.0, 115.0, 88.0, 87.0, 85.0, 87.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 151.0, 150.0, 145.0, 157.0, 183.0, 174.0, 146.0, 215.0, 162.0], "value": 164.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 196.0, 195.0, 197.0, 197.0, 196.0, 241.0, 195.0, 196.0, 196.0], "value": 200.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 77.0, 72.0, 71.0, 69.0, 70.0, 73.0, 73.0, 70.0], "value": 72.1, "unit": "score"}]}]} 13:04:12 INFO - Return code: 0 13:04:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:04:12 ERROR - # TBPL SUCCESS # 13:04:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:04:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:04:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:04:12 INFO - 2017-04-04 13:04:12.953 screenresolution[1659:10816] starting screenresolution argv=screenresolution get 13:04:12 INFO - 2017-04-04 13:04:12.956 screenresolution[1659:10816] Display 0: 1600x1200x32@60 13:04:12 INFO - 2017-04-04 13:04:12.962 screenresolution[1660:10819] starting screenresolution argv=screenresolution list 13:04:12 INFO - Available Modes on Display 0 13:04:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:04:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:04:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:04:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:04:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:04:13 INFO - Intel Iris: 13:04:13 INFO - Chipset Model: Intel Iris 13:04:13 INFO - Type: GPU 13:04:13 INFO - Bus: Built-In 13:04:13 INFO - VRAM (Dynamic, Max): 1536 MB 13:04:13 INFO - Vendor: Intel (0x8086) 13:04:13 INFO - Device ID: 0x0a2e 13:04:13 INFO - Revision ID: 0x0009 13:04:13 INFO - Displays: 13:04:13 INFO - Display: 13:04:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:04:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:04:13 INFO - Display Serial Number: 1600x1200 60 13:04:13 INFO - Main Display: Yes 13:04:13 INFO - Mirror: Off 13:04:13 INFO - Online: Yes 13:04:13 INFO - Rotation: Supported 13:04:13 INFO - Return code: 0 13:04:13 INFO - Running post-action listener: _resource_record_post_action 13:04:13 INFO - [mozharness: 2017-04-04 20:04:13.196794Z] Finished run-tests step (success) 13:04:13 INFO - Running post-run listener: _resource_record_post_run 13:04:13 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 39152128; Write bytes: 523340288; Read time: 250; Write time: 1681 13:04:13 INFO - TinderboxPrint: CPU usage
54.4% 13:04:13 INFO - TinderboxPrint: I/O read bytes / time
39,152,128 / 250 13:04:13 INFO - TinderboxPrint: I/O write bytes / time
523,340,288 / 1,681 13:04:13 INFO - TinderboxPrint: CPU idle
2,191.2 (45.6%) 13:04:13 INFO - TinderboxPrint: CPU system
972.8 (20.2%) 13:04:13 INFO - TinderboxPrint: CPU user
1,645.7 (34.2%) 13:04:13 INFO - TinderboxPrint: Swap in / out
246,484,992 / 0 13:04:13 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 195426816; Write bytes: 194455552; Read time: 14880; Write time: 437 13:04:13 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 38185472; Write bytes: 312041984; Read time: 237; Write time: 1192 13:04:13 INFO - Running post-run listener: _upload_blobber_files 13:04:13 INFO - Blob upload gear active. 13:04:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:04:13 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 13:04:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:04:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:04:14 INFO - (blobuploader) - INFO - Open directory for files ... 13:04:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:04:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:04:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:15 INFO - (blobuploader) - INFO - Done attempting. 13:04:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:04:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:04:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:16 INFO - (blobuploader) - INFO - Done attempting. 13:04:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:04:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:04:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:17 INFO - (blobuploader) - INFO - Done attempting. 13:04:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:04:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:04:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:19 INFO - (blobuploader) - INFO - Done attempting. 13:04:19 INFO - (blobuploader) - INFO - Iteration through files over. 13:04:19 INFO - Return code: 0 13:04:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:04:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:04:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/facbd1859f1a0df7ef4ab993e60568992c3be2ad600de5c5edeff355beecfd096faa497d96e2b72836b2621f4a6749ad9dc6b97fa1af1efb6a250a72042ebbd1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2eb9b602c4aa79f1f7af2e5428702d40cc0f0aa84176e676fbc943ccce7c201064e521c935d6e9834ae1d8bc873cb9a06084b898e053262a7aa5931d7f6f111d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e07317d9c661ab347556f4c1e25959eec99c15525571c8d17f4bea8b9136871be9b48b711255ec62b5c16c4e2882c60aaf83d3b9a36d5220fe655fc63a4b447", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/879daae02ce9402dbbde3bb0b07729541460626c949b1c78571ee59eae7911d4c2b42d386d9c3e59733746712f96fdcfe51f0ea91b3895c5ddeb32e6d35be93a"} 13:04:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:04:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:04:19 INFO - Contents: 13:04:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/facbd1859f1a0df7ef4ab993e60568992c3be2ad600de5c5edeff355beecfd096faa497d96e2b72836b2621f4a6749ad9dc6b97fa1af1efb6a250a72042ebbd1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2eb9b602c4aa79f1f7af2e5428702d40cc0f0aa84176e676fbc943ccce7c201064e521c935d6e9834ae1d8bc873cb9a06084b898e053262a7aa5931d7f6f111d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e07317d9c661ab347556f4c1e25959eec99c15525571c8d17f4bea8b9136871be9b48b711255ec62b5c16c4e2882c60aaf83d3b9a36d5220fe655fc63a4b447", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/879daae02ce9402dbbde3bb0b07729541460626c949b1c78571ee59eae7911d4c2b42d386d9c3e59733746712f96fdcfe51f0ea91b3895c5ddeb32e6d35be93a"} 13:04:19 INFO - Running post-run listener: copy_logs_to_upload_dir 13:04:19 INFO - Copying logs to upload dir... 13:04:19 INFO - mkdir: /builds/slave/test/build/upload/logs 13:04:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.785239 ========= master_lag: 1.99 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-04 13:04:21.291201) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-04-04 13:04:21.295636) ========= 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.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/facbd1859f1a0df7ef4ab993e60568992c3be2ad600de5c5edeff355beecfd096faa497d96e2b72836b2621f4a6749ad9dc6b97fa1af1efb6a250a72042ebbd1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2eb9b602c4aa79f1f7af2e5428702d40cc0f0aa84176e676fbc943ccce7c201064e521c935d6e9834ae1d8bc873cb9a06084b898e053262a7aa5931d7f6f111d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e07317d9c661ab347556f4c1e25959eec99c15525571c8d17f4bea8b9136871be9b48b711255ec62b5c16c4e2882c60aaf83d3b9a36d5220fe655fc63a4b447", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/879daae02ce9402dbbde3bb0b07729541460626c949b1c78571ee59eae7911d4c2b42d386d9c3e59733746712f96fdcfe51f0ea91b3895c5ddeb32e6d35be93a"} build_url:https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011510 build_url: 'https://queue.taskcluster.net/v1/task/R4gM7LiASNm8kX4E9lxHyA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/facbd1859f1a0df7ef4ab993e60568992c3be2ad600de5c5edeff355beecfd096faa497d96e2b72836b2621f4a6749ad9dc6b97fa1af1efb6a250a72042ebbd1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2eb9b602c4aa79f1f7af2e5428702d40cc0f0aa84176e676fbc943ccce7c201064e521c935d6e9834ae1d8bc873cb9a06084b898e053262a7aa5931d7f6f111d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e07317d9c661ab347556f4c1e25959eec99c15525571c8d17f4bea8b9136871be9b48b711255ec62b5c16c4e2882c60aaf83d3b9a36d5220fe655fc63a4b447", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/879daae02ce9402dbbde3bb0b07729541460626c949b1c78571ee59eae7911d4c2b42d386d9c3e59733746712f96fdcfe51f0ea91b3895c5ddeb32e6d35be93a"}' ========= master_lag: 1.09 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-04-04 13:04:22.401858) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 13:04:22.402207) ========= 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.fO0qtSTfrh/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.Ei8xlNxkBI/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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.005343 ========= master_lag: 0.14 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-04 13:04:22.545087) ========= ========= Started reboot skipped (results: 3, elapsed: 4 secs) (at 2017-04-04 13:04:22.545451) ========= ========= Finished reboot skipped (results: 3, elapsed: 4 secs) (at 2017-04-04 13:04:27.394125) ========= ========= Total master_lag: 3.31 =========