builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0175 starttime: 1481691407.54 results: success (0) buildid: 20161213192606 builduid: ed8880dbdc7a4fa4996a31b9be17daf6 revision: 1ea0c60db5d25a7d522e2f252c1978ff4fc7538e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-13 20:56:47.540594) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-13 20:56:47.541110) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-13 20:56:47.541405) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-13 20:56:47.569094) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 20:56:47.569513) ========= 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.RpoJ1tLUAs/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.7TwAStV23j/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-13 20:56:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.1M=0.001s 2016-12-13 20:56:48 (12.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086319 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 20:56:47.680709) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 20:56:47.681045) ========= 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.RpoJ1tLUAs/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.7TwAStV23j/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.026214 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 20:56:47.730276) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-13 20:56:47.730647) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1ea0c60db5d25a7d522e2f252c1978ff4fc7538e --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 1ea0c60db5d25a7d522e2f252c1978ff4fc7538e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RpoJ1tLUAs/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.7TwAStV23j/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-13 20:56:48,270 truncating revision to first 12 chars 2016-12-13 20:56:48,270 Setting DEBUG logging. 2016-12-13 20:56:48,270 attempt 1/10 2016-12-13 20:56:48,270 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1ea0c60db5d2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-13 20:56:49,658 unpacking tar archive at: mozilla-central-1ea0c60db5d2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.665164 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-13 20:56:49.419521) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-13 20:56:49.420138) ========= script_repo_revision: 1ea0c60db5d25a7d522e2f252c1978ff4fc7538e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-13 20:56:49.420952) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-13 20:56:49.421507) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-13 20:56:49.441609) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-12-13 20:56:49.442025) ========= /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.RpoJ1tLUAs/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.7TwAStV23j/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 20:56:50 INFO - MultiFileLogger online at 20161213 20:56:50 in /builds/slave/test 20:56:50 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 20:56:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:56:50 INFO - {'append_to_log': False, 20:56:50 INFO - 'base_work_dir': '/builds/slave/test', 20:56:50 INFO - 'blob_upload_branch': 'Firefox', 20:56:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:56:50 INFO - 'branch': 'Firefox', 20:56:50 INFO - 'buildbot_json_path': 'buildprops.json', 20:56:50 INFO - 'config_files': ('talos/mac_config.py',), 20:56:50 INFO - 'default_actions': ('clobber', 20:56:50 INFO - 'read-buildbot-config', 20:56:50 INFO - 'download-and-extract', 20:56:50 INFO - 'populate-webroot', 20:56:50 INFO - 'create-virtualenv', 20:56:50 INFO - 'install', 20:56:50 INFO - 'run-tests'), 20:56:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:56:50 INFO - 'download_minidump_stackwalk': True, 20:56:50 INFO - 'download_symbols': 'ondemand', 20:56:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:56:50 INFO - 'tooltool.py': '/tools/tooltool.py', 20:56:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:56:50 INFO - '/tools/misc-python/virtualenv.py')}, 20:56:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:56:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:56:50 INFO - 'installer_path': 'installer.exe', 20:56:50 INFO - 'log_level': 'info', 20:56:50 INFO - 'log_name': 'talos', 20:56:50 INFO - 'log_to_console': True, 20:56:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:56:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:56:50 INFO - 'opt_config_files': (), 20:56:50 INFO - 'pip_index': False, 20:56:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:56:50 INFO - 'cmd': ('bash', 20:56:50 INFO - '-c', 20:56:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:56:50 INFO - 'enabled': True, 20:56:50 INFO - 'halt_on_failure': False, 20:56:50 INFO - 'name': 'check_screen_resolution'},), 20:56:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:56:50 INFO - 'cmd': ('bash', 20:56:50 INFO - '-c', 20:56:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:56:50 INFO - 'enabled': True, 20:56:50 INFO - 'halt_on_failure': False, 20:56:50 INFO - 'name': 'check_screen_resolution'},), 20:56:50 INFO - 'run_cmd_checks_enabled': True, 20:56:50 INFO - 'sps_profile': False, 20:56:50 INFO - 'sps_profile_interval': 0, 20:56:50 INFO - 'suite': 'dromaeojs-e10s', 20:56:50 INFO - 'system_bits': '32', 20:56:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:56:50 INFO - 'title': 't-yosemite-r7-0175', 20:56:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:56:50 INFO - 'use_talos_json': True, 20:56:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:56:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:56:50 INFO - 'work_dir': 'build'} 20:56:50 INFO - [mozharness: 2016-12-14 04:56:50.098757Z] Running clobber step. 20:56:50 INFO - Running pre-action listener: _resource_record_pre_action 20:56:50 INFO - Running main action method: clobber 20:56:50 INFO - rmtree: /builds/slave/test/build 20:56:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:56:52 INFO - Running post-action listener: _resource_record_post_action 20:56:52 INFO - [mozharness: 2016-12-14 04:56:52.503375Z] Finished clobber step (success) 20:56:52 INFO - [mozharness: 2016-12-14 04:56:52.503515Z] Running read-buildbot-config step. 20:56:52 INFO - Running pre-action listener: _resource_record_pre_action 20:56:52 INFO - Running main action method: read_buildbot_config 20:56:52 INFO - Using buildbot properties: 20:56:52 INFO - { 20:56:52 INFO - "project": "", 20:56:52 INFO - "product": "firefox", 20:56:52 INFO - "script_repo_revision": "production", 20:56:52 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 20:56:52 INFO - "repository": "", 20:56:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 20:56:52 INFO - "buildid": "20161213192606", 20:56:52 INFO - "pgo_build": "False", 20:56:52 INFO - "basedir": "/builds/slave/test", 20:56:52 INFO - "buildnumber": 32, 20:56:52 INFO - "slavename": "t-yosemite-r7-0175", 20:56:52 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 20:56:52 INFO - "platform": "yosemite_r7", 20:56:52 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 20:56:52 INFO - "branch": "mozilla-central", 20:56:52 INFO - "repo_path": "mozilla-central", 20:56:52 INFO - "revision": "1ea0c60db5d25a7d522e2f252c1978ff4fc7538e", 20:56:52 INFO - "stage_platform": "macosx64", 20:56:52 INFO - "builduid": "ed8880dbdc7a4fa4996a31b9be17daf6", 20:56:52 INFO - "slavebuilddir": "test" 20:56:52 INFO - } 20:56:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 20:56:52 INFO - Running post-action listener: _resource_record_post_action 20:56:52 INFO - [mozharness: 2016-12-14 04:56:52.506823Z] Finished read-buildbot-config step (success) 20:56:52 INFO - [mozharness: 2016-12-14 04:56:52.506956Z] Running download-and-extract step. 20:56:52 INFO - Running pre-action listener: _resource_record_pre_action 20:56:52 INFO - Running main action method: download_and_extract 20:56:52 INFO - mkdir: /builds/slave/test/build/tests 20:56:52 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')]} 20:56:52 INFO - trying https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 20:56:52 INFO - Downloading https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:56:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 20:56:53 INFO - Downloaded 1199 bytes. 20:56:53 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:56:53 INFO - Using the following test package requirements: 20:56:53 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:56:53 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:56:53 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 20:56:53 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:56:53 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 20:56:53 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:56:53 INFO - u'jsshell-mac.zip'], 20:56:53 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:56:53 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 20:56:53 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:56:53 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:56:53 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 20:56:53 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:56:53 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 20:56:53 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:56:53 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 20:56:53 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:56:53 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 20:56:53 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:56:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:56:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:56:53 INFO - Fetch https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:56:54 INFO - Expected file size: 16276031 20:56:54 INFO - Obtained file size: 16276031 20:57:01 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:57:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:57:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:57:01 INFO - Fetch https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:57:02 INFO - Expected file size: 16276031 20:57:02 INFO - Obtained file size: 16276031 20:57:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 20:57:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:57:08 INFO - Fetch https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 20:57:10 INFO - Expected file size: 14409734 20:57:10 INFO - Obtained file size: 14409734 20:57: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')]} 20:57:10 INFO - trying https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:57:10 INFO - Downloading https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 20:57:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 20:57:13 INFO - Downloaded 69668940 bytes. 20:57:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:57:13 INFO - mkdir: /builds/slave/test/properties 20:57:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:57:13 INFO - Writing to file /builds/slave/test/properties/build_url 20:57:13 INFO - Contents: 20:57:13 INFO - build_url:https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:57:14 INFO - Running post-action listener: _resource_record_post_action 20:57:14 INFO - Running post-action listener: set_extra_try_arguments 20:57:14 INFO - [mozharness: 2016-12-14 04:57:14.332024Z] Finished download-and-extract step (success) 20:57:14 INFO - [mozharness: 2016-12-14 04:57:14.332152Z] Running populate-webroot step. 20:57:14 INFO - Running pre-action listener: _resource_record_pre_action 20:57:14 INFO - Running main action method: populate_webroot 20:57:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:57:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:57:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:57:14 INFO - '--tppagecycles', 20:57:14 INFO - '7'], 20:57:14 INFO - 'tests': ['tsvgm']}}, 20:57:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:57:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:57:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:57:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:57:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:57:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:57:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:57:14 INFO - 'talos_options': ['--disable-e10s'], 20:57:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:57:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:57:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:57:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:57:14 INFO - 'talos_options': ['--disable-e10s'], 20:57:14 INFO - 'tests': ['damp', 'tps']}, 20:57:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:57:14 INFO - 'tests': ['damp', 'tps']}, 20:57:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:57:14 INFO - 'tests': ['dromaeo_dom']}, 20:57:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:57:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:57:14 INFO - 'tests': ['basic_compositor_video']}, 20:57:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:57:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:57:14 INFO - 'tests': ['a11yr', 20:57:14 INFO - 'ts_paint', 20:57:14 INFO - 'tpaint', 20:57:14 INFO - 'sessionrestore', 20:57:14 INFO - 'sessionrestore_no_auto_restore', 20:57:14 INFO - 'tabpaint']}, 20:57:14 INFO - 'other-e10s': {'tests': ['a11yr', 20:57:14 INFO - 'ts_paint', 20:57:14 INFO - 'tpaint', 20:57:14 INFO - 'sessionrestore', 20:57:14 INFO - 'sessionrestore_no_auto_restore', 20:57:14 INFO - 'tabpaint']}, 20:57:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:57:14 INFO - 'ts_paint', 20:57:14 INFO - 'tpaint', 20:57:14 INFO - 'sessionrestore', 20:57:14 INFO - 'sessionrestore_no_auto_restore', 20:57:14 INFO - 'tabpaint']}, 20:57:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:57:14 INFO - 'ts_paint', 20:57:14 INFO - 'tpaint', 20:57:14 INFO - 'sessionrestore', 20:57:14 INFO - 'sessionrestore_no_auto_restore', 20:57:14 INFO - 'tabpaint']}, 20:57:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:57:14 INFO - 'tests': ['a11yr', 20:57:14 INFO - 'ts_paint', 20:57:14 INFO - 'tpaint', 20:57:14 INFO - 'sessionrestore', 20:57:14 INFO - 'sessionrestore_no_auto_restore', 20:57:14 INFO - 'tabpaint']}, 20:57:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:57:14 INFO - 'tests': ['a11yr', 20:57:14 INFO - 'ts_paint', 20:57:14 INFO - 'tpaint', 20:57:14 INFO - 'sessionrestore', 20:57:14 INFO - 'sessionrestore_no_auto_restore', 20:57:14 INFO - 'tabpaint']}, 20:57:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:57:14 INFO - 'tests': ['tsvgx', 20:57:14 INFO - 'tsvgr_opacity', 20:57:14 INFO - 'tart', 20:57:14 INFO - 'tscrollx', 20:57:14 INFO - 'cart', 20:57:14 INFO - 'tsvg_static']}, 20:57:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:57:14 INFO - 'tsvgr_opacity', 20:57:14 INFO - 'tart', 20:57:14 INFO - 'tscrollx', 20:57:14 INFO - 'cart', 20:57:14 INFO - 'tsvg_static']}, 20:57:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:57:14 INFO - 'talos_options': ['--disable-e10s'], 20:57:14 INFO - 'tests': ['tp5o']}, 20:57:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:57:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:57:14 INFO - 'talos_options': ['--disable-e10s', 20:57:14 INFO - '--xperf_path', 20:57:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:57:14 INFO - 'tests': ['tp5n']}, 20:57:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:57:14 INFO - 'talos_options': ['--xperf_path', 20:57:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:57:14 INFO - 'tests': ['tp5n']}}, 20:57:14 INFO - 'talos.zip': {'path': '', 20:57:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:57:14 INFO - Running post-action listener: _resource_record_post_action 20:57:14 INFO - [mozharness: 2016-12-14 04:57:14.350735Z] Finished populate-webroot step (success) 20:57:14 INFO - [mozharness: 2016-12-14 04:57:14.350869Z] Running create-virtualenv step. 20:57:14 INFO - Running pre-action listener: _resource_record_pre_action 20:57:14 INFO - Running main action method: create_virtualenv 20:57:14 INFO - Creating virtualenv /builds/slave/test/build/venv 20:57:14 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 20:57:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:57:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:57:14 INFO - Using real prefix '/tools/python27' 20:57:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:57:15 INFO - Installing distribute.............................................................................................................................................................................................done. 20:57:18 INFO - Installing pip.................done. 20:57:18 INFO - Return code: 0 20:57:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:57:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:57:18 INFO - Reading from file tmpfile_stdout 20:57:18 INFO - Output received: 20:57:18 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) 20:57:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:57:18 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')]} 20:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:18 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')]} 20:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:18 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 0x10b28b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc36bde94c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a0d30>, '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 0x10af07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b098b20>, '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.RpoJ1tLUAs/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.7TwAStV23j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 20:57:18 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 20:57:18 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 20:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RpoJ1tLUAs/Render', 20:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:57:18 INFO - 'HOME': '/Users/cltbld', 20:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:57:18 INFO - 'LOGNAME': 'cltbld', 20:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:18 INFO - 'MOZ_NO_REMOTE': '1', 20:57:18 INFO - 'NO_EM_RESTART': '1', 20:57:18 INFO - 'PAGER': '/bin/cat', 20:57:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:18 INFO - 'PWD': '/builds/slave/test', 20:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:57:18 INFO - 'SHELL': '/bin/bash', 20:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TwAStV23j/Listeners', 20:57:18 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 20:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:57:18 INFO - 'USER': 'cltbld', 20:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:18 INFO - 'XPC_FLAGS': '0x0', 20:57:18 INFO - 'XPC_SERVICE_NAME': '0', 20:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:18 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 20:57:18 INFO - Cleaning up... 20:57:18 INFO - Return code: 0 20:57:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:57:18 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')]} 20:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:18 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')]} 20:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:18 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 0x10b28b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc36bde94c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a0d30>, '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 0x10af07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b098b20>, '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.RpoJ1tLUAs/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.7TwAStV23j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 20:57:18 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 20:57:18 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 20:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RpoJ1tLUAs/Render', 20:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:57:18 INFO - 'HOME': '/Users/cltbld', 20:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:57:18 INFO - 'LOGNAME': 'cltbld', 20:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:18 INFO - 'MOZ_NO_REMOTE': '1', 20:57:18 INFO - 'NO_EM_RESTART': '1', 20:57:18 INFO - 'PAGER': '/bin/cat', 20:57:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:18 INFO - 'PWD': '/builds/slave/test', 20:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:57:18 INFO - 'SHELL': '/bin/bash', 20:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TwAStV23j/Listeners', 20:57:18 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 20:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:57:18 INFO - 'USER': 'cltbld', 20:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:18 INFO - 'XPC_FLAGS': '0x0', 20:57:18 INFO - 'XPC_SERVICE_NAME': '0', 20:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:18 INFO - Downloading/unpacking psutil>=3.1.1 20:57:18 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 20:57:18 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 20:57:18 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 20:57:18 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 20:57:18 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 20:57:18 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 20:57:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:57:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:57:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:57:22 INFO - Installing collected packages: psutil 20:57:22 INFO - Running setup.py install for psutil 20:57:22 INFO - building 'psutil._psutil_osx' extension 20:57:22 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 20:57:23 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 20:57:23 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 20:57:23 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 20:57:23 INFO - building 'psutil._psutil_posix' extension 20:57:23 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 20:57:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:57:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:57:23 INFO - ^ 20:57:23 INFO - 1 warning generated. 20:57:23 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 20:57:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:57:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:57:23 INFO - Successfully installed psutil 20:57:23 INFO - Cleaning up... 20:57:23 INFO - Return code: 0 20:57:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:57:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:57:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b28b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc36bde94c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a0d30>, '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 0x10af07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b098b20>, '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.RpoJ1tLUAs/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.7TwAStV23j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 20:57:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:57:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:57:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RpoJ1tLUAs/Render', 20:57:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:57:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:57:23 INFO - 'HOME': '/Users/cltbld', 20:57:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:57:23 INFO - 'LOGNAME': 'cltbld', 20:57:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:23 INFO - 'MOZ_NO_REMOTE': '1', 20:57:23 INFO - 'NO_EM_RESTART': '1', 20:57:23 INFO - 'PAGER': '/bin/cat', 20:57:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:57:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:23 INFO - 'PWD': '/builds/slave/test', 20:57:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:57:23 INFO - 'SHELL': '/bin/bash', 20:57:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TwAStV23j/Listeners', 20:57:23 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 20:57:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:57:23 INFO - 'USER': 'cltbld', 20:57:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:57:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:57:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:23 INFO - 'XPC_FLAGS': '0x0', 20:57:23 INFO - 'XPC_SERVICE_NAME': '0', 20:57:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:57:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:57:23 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 20:57:23 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 20:57:23 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 20:57:23 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 20:57:23 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 20:57:23 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 20:57:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:57:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:57:26 INFO - Installing collected packages: mozsystemmonitor 20:57:26 INFO - Running setup.py install for mozsystemmonitor 20:57:26 INFO - Successfully installed mozsystemmonitor 20:57:26 INFO - Cleaning up... 20:57:26 INFO - Return code: 0 20:57:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:57:26 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')]} 20:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:26 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')]} 20:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:26 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 0x10b28b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc36bde94c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a0d30>, '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 0x10af07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b098b20>, '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.RpoJ1tLUAs/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.7TwAStV23j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 20:57:26 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 20:57:26 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 20:57:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RpoJ1tLUAs/Render', 20:57:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:57:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:57:26 INFO - 'HOME': '/Users/cltbld', 20:57:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:57:26 INFO - 'LOGNAME': 'cltbld', 20:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:26 INFO - 'MOZ_NO_REMOTE': '1', 20:57:26 INFO - 'NO_EM_RESTART': '1', 20:57:26 INFO - 'PAGER': '/bin/cat', 20:57:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:26 INFO - 'PWD': '/builds/slave/test', 20:57:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:57:26 INFO - 'SHELL': '/bin/bash', 20:57:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TwAStV23j/Listeners', 20:57:26 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 20:57:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:57:26 INFO - 'USER': 'cltbld', 20:57:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:57:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:26 INFO - 'XPC_FLAGS': '0x0', 20:57:26 INFO - 'XPC_SERVICE_NAME': '0', 20:57:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:27 INFO - Downloading/unpacking jsonschema==2.5.1 20:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:57:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:57:30 INFO - Downloading functools32-3.2.3-2.tar.gz 20:57:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:57:30 INFO - warning: no files found matching '*.txt' 20:57:30 INFO - no previously-included directories found matching 'build' 20:57:30 INFO - no previously-included directories found matching 'dist' 20:57:30 INFO - no previously-included directories found matching '.git*' 20:57:30 INFO - Installing collected packages: jsonschema, functools32 20:57:30 INFO - Running setup.py install for functools32 20:57:30 INFO - warning: no files found matching '*.txt' 20:57:30 INFO - no previously-included directories found matching 'build' 20:57:30 INFO - no previously-included directories found matching 'dist' 20:57:30 INFO - no previously-included directories found matching '.git*' 20:57:30 INFO - Successfully installed jsonschema functools32 20:57:30 INFO - Cleaning up... 20:57:30 INFO - Return code: 0 20:57:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:57: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')]} 20:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57: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')]} 20:57:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:30 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 0x10b28b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc36bde94c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a0d30>, '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 0x10af07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b098b20>, '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.RpoJ1tLUAs/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.7TwAStV23j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 20:57:30 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 20:57:30 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 20:57:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RpoJ1tLUAs/Render', 20:57:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:57:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:57:30 INFO - 'HOME': '/Users/cltbld', 20:57:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:57:30 INFO - 'LOGNAME': 'cltbld', 20:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:30 INFO - 'MOZ_NO_REMOTE': '1', 20:57:30 INFO - 'NO_EM_RESTART': '1', 20:57:30 INFO - 'PAGER': '/bin/cat', 20:57:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:30 INFO - 'PWD': '/builds/slave/test', 20:57:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:57:30 INFO - 'SHELL': '/bin/bash', 20:57:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TwAStV23j/Listeners', 20:57:30 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 20:57:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:57:30 INFO - 'USER': 'cltbld', 20:57:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:57:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:30 INFO - 'XPC_FLAGS': '0x0', 20:57:30 INFO - 'XPC_SERVICE_NAME': '0', 20:57:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:57:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:57:30 INFO - Cleaning up... 20:57:30 INFO - Return code: 0 20:57:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:57: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')]} 20:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57: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')]} 20:57:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:30 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 0x10b28b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc36bde94c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a0d30>, '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 0x10af07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b098b20>, '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.RpoJ1tLUAs/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.7TwAStV23j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 20:57:30 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 20:57:30 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 20:57:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RpoJ1tLUAs/Render', 20:57:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:57:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:57:30 INFO - 'HOME': '/Users/cltbld', 20:57:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:57:30 INFO - 'LOGNAME': 'cltbld', 20:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:30 INFO - 'MOZ_NO_REMOTE': '1', 20:57:30 INFO - 'NO_EM_RESTART': '1', 20:57:30 INFO - 'PAGER': '/bin/cat', 20:57:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:30 INFO - 'PWD': '/builds/slave/test', 20:57:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:57:30 INFO - 'SHELL': '/bin/bash', 20:57:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TwAStV23j/Listeners', 20:57:30 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 20:57:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:57:30 INFO - 'USER': 'cltbld', 20:57:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:57:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:30 INFO - 'XPC_FLAGS': '0x0', 20:57:30 INFO - 'XPC_SERVICE_NAME': '0', 20:57:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:57:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:30 INFO - Downloading/unpacking blobuploader==1.2.4 20:57:30 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 20:57:30 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 20:57:30 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 20:57:30 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 20:57:30 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 20:57:30 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 20:57:33 INFO - Downloading blobuploader-1.2.4.tar.gz 20:57:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:57:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:57:33 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 20:57:33 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 20:57:33 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 20:57:33 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 20:57:33 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 20:57:33 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 20:57:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:57:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:57:34 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 20:57:34 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 20:57:34 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 20:57:34 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 20:57:34 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 20:57:34 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 20:57:34 INFO - Downloading docopt-0.6.1.tar.gz 20:57:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:57:34 INFO - Installing collected packages: blobuploader, requests, docopt 20:57:34 INFO - Running setup.py install for blobuploader 20:57:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:57:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:57:35 INFO - Running setup.py install for requests 20:57:35 INFO - Running setup.py install for docopt 20:57:35 INFO - Successfully installed blobuploader requests docopt 20:57:35 INFO - Cleaning up... 20:57:35 INFO - Return code: 0 20:57:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:57:35 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')]} 20:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:35 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')]} 20:57:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:35 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 0x10b28b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc36bde94c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a0d30>, '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 0x10af07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b098b20>, '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.RpoJ1tLUAs/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.7TwAStV23j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 20:57:35 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 20:57:35 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 20:57:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RpoJ1tLUAs/Render', 20:57:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:57:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:57:35 INFO - 'HOME': '/Users/cltbld', 20:57:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:57:35 INFO - 'LOGNAME': 'cltbld', 20:57:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:35 INFO - 'MOZ_NO_REMOTE': '1', 20:57:35 INFO - 'NO_EM_RESTART': '1', 20:57:35 INFO - 'PAGER': '/bin/cat', 20:57:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:57:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:35 INFO - 'PWD': '/builds/slave/test', 20:57:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:57:35 INFO - 'SHELL': '/bin/bash', 20:57:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TwAStV23j/Listeners', 20:57:35 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 20:57:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:57:35 INFO - 'USER': 'cltbld', 20:57:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:57:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:57:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:35 INFO - 'XPC_FLAGS': '0x0', 20:57:35 INFO - 'XPC_SERVICE_NAME': '0', 20:57:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:57:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:57:36 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-JvFGkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:57:36 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-9efXUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:57:36 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-0MwLN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:57:36 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-RCKCbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:57:36 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-KfFMLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:57:36 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-47Zkdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:57:36 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-cnTpUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:57:36 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-yN_cxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:57:36 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-bJ_glZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:57:37 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-rh6HiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:57:37 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-UfpKKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:57:37 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-xAYX8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:57:37 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-_ICcUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:57:37 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-yIyVWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:57:37 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-mi7bpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:57:37 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-mYLWlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:57:37 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-p3bmf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:57:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:57:37 INFO - Running setup.py install for manifestparser 20:57:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:57:38 INFO - Running setup.py install for mozcrash 20:57:38 INFO - Running setup.py install for mozdebug 20:57:38 INFO - Running setup.py install for mozdevice 20:57:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:57:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:57:38 INFO - Running setup.py install for mozfile 20:57:38 INFO - Running setup.py install for mozhttpd 20:57:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:57:38 INFO - Running setup.py install for mozinfo 20:57:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:57:38 INFO - Running setup.py install for mozInstall 20:57:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:57:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:57:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:57:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:57:39 INFO - Running setup.py install for mozleak 20:57:39 INFO - Running setup.py install for mozlog 20:57:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:57:39 INFO - Running setup.py install for moznetwork 20:57:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:57:39 INFO - Running setup.py install for mozprocess 20:57:39 INFO - Running setup.py install for mozprofile 20:57:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:57:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:57:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:57:39 INFO - Running setup.py install for mozrunner 20:57:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:57:40 INFO - Running setup.py install for mozscreenshot 20:57:40 INFO - Running setup.py install for moztest 20:57:40 INFO - Running setup.py install for mozversion 20:57:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:57:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:57:40 INFO - Cleaning up... 20:57:40 INFO - Return code: 0 20:57:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:57:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:57:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:57:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:40 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 0x10b28b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc36bde94c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a0d30>, '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 0x10af07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b098b20>, '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.RpoJ1tLUAs/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.7TwAStV23j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 20:57:40 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 20:57:40 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 20:57:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RpoJ1tLUAs/Render', 20:57:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:57:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:57:40 INFO - 'HOME': '/Users/cltbld', 20:57:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:57:40 INFO - 'LOGNAME': 'cltbld', 20:57:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:40 INFO - 'MOZ_NO_REMOTE': '1', 20:57:40 INFO - 'NO_EM_RESTART': '1', 20:57:40 INFO - 'PAGER': '/bin/cat', 20:57:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:57:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:40 INFO - 'PWD': '/builds/slave/test', 20:57:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:57:40 INFO - 'SHELL': '/bin/bash', 20:57:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TwAStV23j/Listeners', 20:57:40 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 20:57:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:57:40 INFO - 'USER': 'cltbld', 20:57:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:57:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:57:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:40 INFO - 'XPC_FLAGS': '0x0', 20:57:40 INFO - 'XPC_SERVICE_NAME': '0', 20:57:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:57:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:57:40 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-sFvT9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:57:40 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)) 20:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:57:40 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-gO1T95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:57:41 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)) 20:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:57:41 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-M5jsDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:57:41 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)) 20:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:57:41 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-LQKvld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:57:41 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)) 20:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:57:41 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-32oPBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:57:41 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)) 20:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:57:41 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-NJNzwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:57:41 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)) 20:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:57:41 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-li4xjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:57:41 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)) 20:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:57:41 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-F4JuYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:57:41 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)) 20:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:57:41 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-irnCMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:57:41 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)) 20:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:57:41 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-jBZufm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:57:41 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)) 20:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:57:41 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-8YCzsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:57:42 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)) 20:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:57:42 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-5lstdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:57:42 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Q2t__d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:57:42 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)) 20:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:57:42 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-amLOZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:57:42 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)) 20:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:57:42 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-NTiz8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:57:42 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)) 20:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:57:42 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-c1YeVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:57:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:57:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:57:42 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-lBgNaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:57:42 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)) 20:57:42 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)) 20:57:42 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)) 20:57:42 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)) 20:57:42 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)) 20:57:42 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)) 20:57:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:57:42 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 20:57:42 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 20:57:42 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 20:57:42 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 20:57:42 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 20:57:42 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 20:57:45 INFO - Downloading blessings-1.6.tar.gz 20:57:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:57:45 INFO - Installing collected packages: blessings 20:57:45 INFO - Running setup.py install for blessings 20:57:45 INFO - Successfully installed blessings 20:57:45 INFO - Cleaning up... 20:57:45 INFO - Return code: 0 20:57:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:57:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:57:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:57:45 INFO - Reading from file tmpfile_stdout 20:57:45 INFO - Current package versions: 20:57:45 INFO - blessings == 1.6 20:57:45 INFO - blobuploader == 1.2.4 20:57:45 INFO - docopt == 0.6.1 20:57:45 INFO - functools32 == 3.2.3-2 20:57:45 INFO - jsonschema == 2.5.1 20:57:45 INFO - manifestparser == 1.1 20:57:45 INFO - mozInstall == 1.12 20:57:45 INFO - mozcrash == 1.0 20:57:45 INFO - mozdebug == 0.1 20:57:45 INFO - mozdevice == 0.49 20:57:45 INFO - mozfile == 1.2 20:57:45 INFO - mozhttpd == 0.7 20:57:45 INFO - mozinfo == 0.9 20:57:45 INFO - mozleak == 0.1 20:57:45 INFO - mozlog == 3.4 20:57:45 INFO - moznetwork == 0.27 20:57:45 INFO - mozprocess == 0.23 20:57:45 INFO - mozprofile == 0.28 20:57:45 INFO - mozrunner == 6.13 20:57:45 INFO - mozscreenshot == 0.1 20:57:45 INFO - mozsystemmonitor == 0.3 20:57:45 INFO - moztest == 0.7 20:57:45 INFO - mozversion == 1.4 20:57:45 INFO - psutil == 3.1.1 20:57:45 INFO - requests == 1.2.3 20:57:45 INFO - wsgiref == 0.1.2 20:57:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57: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')]} 20:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:46 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 0x10b28b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc36bde94c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a0d30>, '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 0x10af07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b098b20>, '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.RpoJ1tLUAs/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.7TwAStV23j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 20:57:46 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 20:57:46 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 20:57:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RpoJ1tLUAs/Render', 20:57:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:57:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:57:46 INFO - 'HOME': '/Users/cltbld', 20:57:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:57:46 INFO - 'LOGNAME': 'cltbld', 20:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:46 INFO - 'MOZ_NO_REMOTE': '1', 20:57:46 INFO - 'NO_EM_RESTART': '1', 20:57:46 INFO - 'PAGER': '/bin/cat', 20:57:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:46 INFO - 'PWD': '/builds/slave/test', 20:57:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:57:46 INFO - 'SHELL': '/bin/bash', 20:57:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TwAStV23j/Listeners', 20:57:46 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 20:57:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:57:46 INFO - 'USER': 'cltbld', 20:57:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:57:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:46 INFO - 'XPC_FLAGS': '0x0', 20:57:46 INFO - 'XPC_SERVICE_NAME': '0', 20:57:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:57:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:46 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)) 20:57:46 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)) 20:57:46 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)) 20:57:46 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)) 20:57:46 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)) 20:57:46 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)) 20:57:46 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)) 20:57:46 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)) 20:57:46 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)) 20:57:46 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)) 20:57:46 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)) 20:57:46 INFO - Cleaning up... 20:57:46 INFO - Return code: 0 20:57:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:57: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')]} 20:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:57:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57: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')]} 20:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:57:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:57:47 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 0x10b28b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b201570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc36bde94c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a0d30>, '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 0x10af07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b098b20>, '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.RpoJ1tLUAs/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.7TwAStV23j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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 20:57:47 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 20:57:47 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 20:57:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RpoJ1tLUAs/Render', 20:57:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:57:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:57:47 INFO - 'HOME': '/Users/cltbld', 20:57:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:57:47 INFO - 'LOGNAME': 'cltbld', 20:57:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:47 INFO - 'MOZ_NO_REMOTE': '1', 20:57:47 INFO - 'NO_EM_RESTART': '1', 20:57:47 INFO - 'PAGER': '/bin/cat', 20:57:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:57:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:47 INFO - 'PWD': '/builds/slave/test', 20:57:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:57:47 INFO - 'SHELL': '/bin/bash', 20:57:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TwAStV23j/Listeners', 20:57:47 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 20:57:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:57:47 INFO - 'USER': 'cltbld', 20:57:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:57:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:57:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:47 INFO - 'XPC_FLAGS': '0x0', 20:57:47 INFO - 'XPC_SERVICE_NAME': '0', 20:57:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:57:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:57:47 INFO - Cleaning up... 20:57:47 INFO - Return code: 0 20:57:47 INFO - Running post-action listener: _resource_record_post_action 20:57:47 INFO - Running post-action listener: _start_resource_monitoring 20:57:47 INFO - Starting resource monitoring. 20:57:47 INFO - [mozharness: 2016-12-14 04:57:47.598110Z] Finished create-virtualenv step (success) 20:57:47 INFO - [mozharness: 2016-12-14 04:57:47.599013Z] Running install step. 20:57:47 INFO - Running pre-action listener: _resource_record_pre_action 20:57:47 INFO - Running main action method: install 20:57:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:57:47 INFO - Reading from file tmpfile_stdout 20:57:47 INFO - Detecting whether we're running mozinstall >=1.0... 20:57:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:57:47 INFO - Reading from file tmpfile_stdout 20:57:47 INFO - Output received: 20:57:47 INFO - Usage: mozinstall [options] installer 20:57:47 INFO - Options: 20:57:47 INFO - -h, --help show this help message and exit 20:57:47 INFO - -d DEST, --destination=DEST 20:57:47 INFO - Directory to install application into. [default: 20:57:47 INFO - "/builds/slave/test"] 20:57:47 INFO - --app=APP Application being installed. [default: firefox] 20:57:47 INFO - mkdir: /builds/slave/test/build/application 20:57:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:58:09 INFO - Reading from file tmpfile_stdout 20:58:09 INFO - Output received: 20:58:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:58:09 INFO - Running post-action listener: _resource_record_post_action 20:58:09 INFO - [mozharness: 2016-12-14 04:58:09.744197Z] Finished install step (success) 20:58:09 INFO - [mozharness: 2016-12-14 04:58:09.744344Z] Running run-tests step. 20:58:09 INFO - Running pre-action listener: _resource_record_pre_action 20:58:09 INFO - Running main action method: run_tests 20:58:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:58:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:58:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:58:09 INFO - 2016-12-13 20:58:09.759 screenresolution[1622:7289] starting screenresolution argv=screenresolution get 20:58:09 INFO - 2016-12-13 20:58:09.761 screenresolution[1622:7289] Display 0: 1600x1200x32@60 20:58:09 INFO - 2016-12-13 20:58:09.767 screenresolution[1623:7292] starting screenresolution argv=screenresolution list 20:58:09 INFO - Available Modes on Display 0 20:58:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:58:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:58:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:58:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:58:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:58:09 INFO - Intel Iris: 20:58:09 INFO - Chipset Model: Intel Iris 20:58:09 INFO - Type: GPU 20:58:09 INFO - Bus: Built-In 20:58:09 INFO - VRAM (Dynamic, Max): 1536 MB 20:58:09 INFO - Vendor: Intel (0x8086) 20:58:09 INFO - Device ID: 0x0a2e 20:58:09 INFO - Revision ID: 0x0009 20:58:09 INFO - Displays: 20:58:09 INFO - Display: 20:58:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:58:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:58:09 INFO - Display Serial Number: 1600x1200 60 20:58:09 INFO - Main Display: Yes 20:58:09 INFO - Mirror: Off 20:58:09 INFO - Online: Yes 20:58:09 INFO - Rotation: Supported 20:58:09 INFO - Return code: 0 20:58:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:58:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:58:10 INFO - Python 2.7.3 20:58:10 INFO - Return code: 0 20:58:10 INFO - grabbing minidump binary from tooltool 20:58: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')]} 20:58:10 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 0x10b0a0d30>, '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 0x10af07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b098b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:58:10 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 20:58:10 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 20:58:10 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 20:58:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:58:10 INFO - Return code: 0 20:58:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:58:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:58:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:58:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:58:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:58:10 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/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--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 20:58:10 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/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0175 --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 20:58:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RpoJ1tLUAs/Render', 20:58:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:58:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:58:10 INFO - 'HOME': '/Users/cltbld', 20:58:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:58:10 INFO - 'LOGNAME': 'cltbld', 20:58:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:58:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:58:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:58:10 INFO - 'MOZ_NO_REMOTE': '1', 20:58:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:58:10 INFO - 'NO_EM_RESTART': '1', 20:58:10 INFO - 'PAGER': '/bin/cat', 20:58:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:58:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:58:10 INFO - 'PWD': '/builds/slave/test', 20:58:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:58:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:58:10 INFO - 'SHELL': '/bin/bash', 20:58:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7TwAStV23j/Listeners', 20:58:10 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 20:58:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:58:10 INFO - 'USER': 'cltbld', 20:58:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:58:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:58:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:58:10 INFO - 'XPC_FLAGS': '0x0', 20:58:10 INFO - 'XPC_SERVICE_NAME': '0', 20:58:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:58:10 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/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--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 20:58:10 INFO - mozversion application_buildid: 20161213192606 20:58:10 INFO - mozversion application_changeset: 1ea0c60db5d25a7d522e2f252c1978ff4fc7538e 20:58:10 INFO - mozversion application_display_name: Nightly 20:58:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:58:10 INFO - mozversion application_name: Firefox 20:58:10 INFO - mozversion application_remotingname: firefox 20:58:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:58:10 INFO - mozversion application_vendor: Mozilla 20:58:10 INFO - mozversion application_version: 53.0a1 20:58:10 INFO - mozversion platform_buildid: 20161213192606 20:58:10 INFO - mozversion platform_changeset: 1ea0c60db5d25a7d522e2f252c1978ff4fc7538e 20:58:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:58:10 INFO - mozversion platform_version: 53.0a1 20:58:10 INFO - using testdate: 1481691490 20:58:10 INFO - actual date: 1481691490 20:58:10 INFO - starting webserver on 'localhost:49248' 20:58:10 INFO - SUITE-START | Running 2 tests 20:58:10 INFO - TEST-START | dromaeo_css 20:58:10 INFO - operating with platform_type : mac_ 20:58:10 INFO - Initialising browser for dromaeo_css test... 20:58:10 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpi6VwOT/profile http://localhost:49248/getInfo.html) 20:58:13 INFO - PROCESS | 1633 | 2016-12-13 20:58:13.245 plugin-container[1635:7459] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9037, name = 'com.apple.tsm.portname' 20:58:13 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:58:13 INFO - PROCESS | 1633 | 2016-12-13 20:58:13.248 plugin-container[1635:7459] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903b, name = 'com.apple.CFPasteboardClient' 20:58:13 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:58:13 INFO - PROCESS | 1633 | 2016-12-13 20:58:13.248 plugin-container[1635:7459] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:58:13 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 20:58:13 INFO - PROCESS | 1633 | colorDepth:24 20:58:13 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 20:58:13 INFO - PROCESS | 1633 | __metrics 20:58:13 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 20:58:14 INFO - TEST-INFO | 1633: exit 0 20:58:14 INFO - Browser initialized. 20:58:14 INFO - Running cycle 1/1 for dromaeo_css test... 20:58:14 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpi6VwOT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:58:16 INFO - PROCESS | 1636 | 2016-12-13 20:58:16.478 plugin-container[1637:7582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 20:58:16 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:58:16 INFO - PROCESS | 1636 | 2016-12-13 20:58:16.478 plugin-container[1637:7582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 20:58:16 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:58:16 INFO - PROCESS | 1636 | 2016-12-13 20:58:16.478 plugin-container[1637:7582] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:58:26 INFO - PROCESS | 1636 | RSS: Main: 236863488 20:58:26 INFO - PROCESS | 1636 | 20:59:15 INFO - PROCESS | 1636 | 1481691555771 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:01:28 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 21:01:30 INFO - PROCESS | 1636 | RSS: Main: 253181952 21:01:30 INFO - PROCESS | 1636 | 21:04:33 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 21:04:34 INFO - PROCESS | 1636 | RSS: Main: 263716864 21:04:34 INFO - PROCESS | 1636 | 21:07:36 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 21:07:36 INFO - PROCESS | 1636 | RSS: Main: 268861440 21:07:36 INFO - PROCESS | 1636 | 21:10:39 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 21:10:39 INFO - PROCESS | 1636 | RSS: Main: 269115392 21:10:39 INFO - PROCESS | 1636 | 21:13:42 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 21:13:43 INFO - PROCESS | 1636 | RSS: Main: 273534976 21:13:43 INFO - PROCESS | 1636 | 21:16:46 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 21:16:46 INFO - PROCESS | 1636 | RSS: Main: 276480000 21:16:46 INFO - PROCESS | 1636 | 21:16:46 INFO - PROCESS | 1636 | __start_tp_report 21:16:46 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 21:16:46 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 21:16:46 INFO - PROCESS | 1636 | |i|pagename|runs| 21:16:46 INFO - PROCESS | 1636 | |0;dojo.html;2380.6786083878674;3370.5618269624947;3392.6268110507854;3431.9485207721873;3441.225724212052;4405.493368262651;5135.152699804533;5678.6308889922175;5685.374608793029;5693.800716974907;5631.859203519914;5684.346300175481;5727;5738.0245358289085;5754.597178197528;5169.819056333029;5205.115130427817;5275.973620131913;5283.577313814885;5290.021346050993;5189.481051894818;5243.344581927259;5301.469853014706;5312.574994000471;5320.973395133019;5254.527092561669;5288.4711528847;5323.22813192087;5330.573554115662;5369.838904832861;4511.977440112779;5124.718140502271;5147.22791581262;5151.407588127376;5172.922406163873;5064.645474816764;5127;5168.3797944246935;5180.4042535108565;5193;4444.466664000308;5667.008273552153;5715.914261286084;5720.08478643415;5727.885442291173;6171.59884607499;6241;6244.2819075805955;6248.343923888017;6269.780557680505;5345.358556973188;6071.878562428771;6192.133101365813;6253.249610046778;6263.71813268404;5944.3164036136395;5953.702314884238;5961.225040744675;5973.402659734079;5985.25184351956;6180.258368995749;6292.056191571301;6304.779332723333;6325.8734825304155;6326.905096423558;6108.311716707923;6124.808163753463;6126.632402055891;6173.59871608353;6210.161628180138;6164.599301045418;6253.6560489173535;6286.088517165077;6307.968460157762;6312.968435157794;6105.459747278077;6193.287771906288;6222.439980401786;6261.217347831521;6272.18461599989;6144.7462419631465;6186.628802271878;6188.350223226586;6203.751849926042;6263.906041409383;4881.267809828483;4949.7772600233075;4972.602191824645;4984.053029924303;4991.376077990251;4426.203283408953;4431.711938723973;4454.888627784267;4487.102579484116;4491.011977364975;5089.9745501272255;5645.830625081254;5708.686022268732;5730.856728581819;5822.272215973004;6125.264968203844;6236.158118654014;6263.561550691408;6270.435660790551;6286.9056964145575;4904.2643603459765;5944.048952167633;5952.553558483131;5955.910661340084;5958.970205148947;5430.701311427831;5462.399136095027;5476.534494568006;5488.615796894259;5504.697241651667;6193.318734939153;6215.129881816459;6264.843378915381;6266.404691554386;6312.084747711648;6228.626282423069;6261.5616906816085;6264.68676566179;6280.371962803683;6289.339619339903;6292.11910332563;6354.1104245406605;6374.872502550016;6406.9359306408205;6428.8071357859335;5744.080947048452;5791.305043394819;5824.475797178275;5840.678762668094;6250.687465626791;4420.668449866209;4947.6536642434685;5870.53035757147;6051.425114614105;6174.25908890936;6008.54935879802;6163.568550201443;6180.536459765445;6196.597221180789;6207.34822843613;6128.632282062912;6172.444479996821;6189.04069869171;6214.223222097238;6241.282252541016;4272.530021697548;5284.497972692588;5862.414951554154;5926.881462370642;6070;4712.929306060343;6085.57400981927;6122.418370254819;6123.557162997741;6123.953766440777;5799.101139323411;5828.70856457167;5960.344362120077;5980.222571065734;6064.3935606439 21:16:46 INFO - PROCESS | 1636 | |1;ext.html;419.903422212891;461.2573756252433;464.3707775963569;465.1023524597525;492.8324369714296;26568;35592.82203588978;37793.81103094481;39887.202255954864;39917.20165596686;40528.59471405285;40992.38511422324;41142.794286028606;41176.58823411765;41397.79301103498;8436.78908027301;8618.43980141292;9249.44503329799;9504.904950950506;11133.665990020272;11170.050545703625;11422.200445968785;11449.885501145007;11463.828042579367;11474.598389056384;11422.657320280405;11458.598949036785;11461.484233209483;11490.310581365104;11507.942460287686;9607.895092064397;11334.036606888425;11456.541738330458;11461.885381146165;11505.482253298622;11336.149788765875;11358.943205284004;11378.37418941958;11400.429978501124;11426.942865285704;39354.40968385476;39652;39679.40480892789;39709.40435893435;39745.40381894274;3412.522246885438;3451.861925522976;3509.2455122148604;3583.7133029357587;3621.0947263184203;24846.5030699385;30755.923542676122;30810.84594577035;31045.844770776228;31136.22159446032;3160.067780004904;3228.9192770180935;3261.5107733839877;3311.8840840570465;3316.9170770730925;420002;421324;422322;422454;422841;60020;60367.39632603706;60646;60755.39244607586;60797.696011520544;62776.05835912465;63236;63369.366306337266;63408.68295658584;63452.36547634557;15700.822438317173;15952.601184970237;15984.520464386087;15990.680186396206;16022;11029.669109926715;11282.435878206057;12264.938675306745;12541.749165016648;12546.12177147591;16869.24088416024;16880.746788798177;16916.49250522486;16934.661306773796;17123.828761712222;6564.540482166202;6589.53873228879;6604.735810567619;6638.634875081917;6653.434458071021;840.3487297344485;866.3675516872718;952.518977916148;976.5507866381544;1064.7338165458636;29202;29462.705372946424;30003.849980749957;30312;30594.08217753471;827.5820710541138;1024.7284469615558;1042.08296698906;1080.5677728908342;1125.2629527659396;12292.849374931486;15028.79769618428;15450.768238476432;15498.612534686723;15776.447824326093;503157;626446;626798;628224;630690;58000;58298.4170158293;58444.70777646085;58467.70766146142;58486;1347.615929460099;1384.190248704519;1390.2770559309104;1394.5258612071943;1396.2599822094103;28802.27994300159;29536;29660.406791864472;30277.848610756806;30472.695273047873;50770.98458030745;51220.97558048893;51268.230976534636;51312.74343628258;51337.74331128321;524517;527891.8502742234;528275;528936.3553182364;530019;39446.60553394429;40011.79994100011;40124.799376002935;40208.798956005034;40303.59696402998;45828.541714582425;46089.53910460853;46567;47030.82422939454;47619.80950476127;38310.42534362042;38404.615953841225;38462.807685961394;39174.804125979186;39853;38033;38253;38677.80661096789;39193.02017449587;39579.60420395759;37028.88913332604;37182.628173717916;37605.81197093997;38479.807600961816;38599;29502.262443439085;31808.04575862728;31969.520457193626;34303.833957912626;35029.824850875586;22394.32817015492;23307.18424855122;25118.623220651338;25277;25355.746442536078 21:16:46 INFO - PROCESS | 1636 | |2;jquery.html;313.38263620667277;316.9397814415261;374.09282489961845;382.45309207832787;400.69346949583576;17700.203490842912;18326.90836545817;19028.334008309695;19189.712154317698;19195.944223067727;19167.42497725067;19372.225110995543;20096.196152153898;20138.798612013874;20281.884496352694;5010.123228435018;5857.121431785224;5857.8828423431505;5865;5868.061110222366;5433.1035379162395;5577.717125061238;5718.113692377668;5722.198892155102;5780.855478613047;4374.147041326943;5541.196526503654;5790.507806836424;5801.216835727165;5802.883942321151;4872.878178045542;5614.214010038578;5843.2403787507565;5904.675242861641;5906.88186236275;4926.605871530269;5691.345495268014;5701.885962280731;5703.315602127771;5719.19931209631;17897.731534027;19084.427467176007;19680.14637877514;23208.955596998007;24626.876865615737;2153.676948457728;2185.519185779126;2191.638379667353;2304.746477887431;2890.1040677389865;8832.8675069874;9669.033096690346;9742.123208911162;9744.75638109046;10288.639897603623;1808.806187915969;1865.5149661088078;1869.5139263791377;1874.3158747057362;1943.7959014303435;115587.42206289;116747.66504669945;117665;117697.41151294274;118327.40836295763;9244.95377523108;9709;9792.363496372713;9802.215822734164;10048.69853904384;10123.443210623487;12231.266124032587;12329.938350308192;12369.196002259825;12392;5866.882662346729;7599.392048636096;7798.908152858604;7979.241972012649;8004.359651227888;8076.434649574473;8079.959600202079;8146.633401496947;8256.174382561696;8266.8346633067;3860.9034774130873;3871.1289959759392;3899.6880249579967;3937.074787424953;3987.5613682494904;1621.8646947137051;1637.598788296875;1640.6308580569275;1879.39859245043;1946.5912158446886;666.9166354205724;827.8758186271987;876.5179151466668;903.8192361527721;964.99035009649;14838.629034274014;19455.92992385404;20314.28899988523;20651.69022464664;20949.533532652873;718.3714250031222;934.4019827310527;937.3625934364698;975.5414954971152;998.4658207858761;7744.806379840549;10742.194335424874;10783.892161078445;10804.1896857736;10863.891361086446;539958;546397;553654;553801;558928;80879;92185;113229;113949;114420.42789785998;907.0566610724773;947.275334369208;950.9857352139724;960.1406740966795;965.6861520005885;17715.468535943943;17830.732539011668;17937.192826322585;18058;18343.633127337645;49216.26175607297;50745.492545075555;51139;51929.480705192465;51945.22082168695;69490.65254673896;72826.90759638503;73844.63077684578;73859;74100;6381.744730210739;7155.069840920647;7764.06831180262;7853.175416581405;7997.160298168841;8851.380403371959;11913.285202887855;11947.940260298643;12208.45061972231;12294.938525307316;10134;10446.947765261126;10478.79042419132;10478.842817357898;10637.893621063691;9994;10226.488675566341;10284.2801003929;10640.989106034915;10676.199285053808;10290.485475726333;10471.659453678527;10788.190885683762;10792.622258220925;10855.511501982272;6319.873602528016;7830.8825367620675;7935.484193527403;7939.882039459906;7986.480878742863;8419;9915.157211637092;10309.381437113798;10598.417087059977;10620 21:16:46 INFO - PROCESS | 1636 | |3;mootools.html;1408.753253370767;1463.4073200353853;1519.9164045977473;1826.8995205263714;1834.275461192828;6632.70152843122;6689.364510371513;6809.1828980522405;7612.162662107171;8032.915556399892;7754.573498457583;8043.517388956667;8054.637541310641;8133.186681331864;8159.714409995651;2258.9661155082686;2306.9077236910503;2313.0516488239823;2314.0743702518957;2315.8263130265213;2311.3643747969277;2326.5114325991563;2342.4143964009;2344.78896899279;2348.4598542335275;1900.4203717866046;2290.656401539774;2292.885355732215;2318.93043208704;2321.0599707118645;2265.2184996176293;2280.09936075251;2287.6797248385237;2295.357299956015;2295.36877358726;2273.7498875123724;2301.2981040782556;2306.527161931817;2308.4113551044375;2314.4213946513373;6952.409045231161;8159.877601835978;8175.386845986574;8225.917740822575;8261.215184557457;4995.650304478688;5070.771815268322;5175.197844334134;5175.327207463006;5179.792808287664;3243.594550681165;3337.516060171286;3522.5772907251294;3525.8589656413715;3538.752287339887;1004.4174378860243;1010.3786171504472;1040.979180416395;1055.5566662001977;1074.5970261152067;10434.269601128;10500.317479363894;10565.3132546385;10597.84103238452;10600.098991585724;4416.116776644684;4507.233770259064;4606.032733126081;4618.97690511552;4641.512641172696;9121.315901307296;9230.35387522867;9242.21441177494;9244.260459163252;9294.953525232331;5141.177411614124;5142.76857541404;5143.922841157386;5149.897002059938;5165.380154381498;3976.76139431635;4222.9653734835165;4285.721428107226;4728.101660684528;4766.737829419346;3481.816182497912;3538.5399898013093;3684.5578530576504;3822.2928758179823;3827.7798876651477;1236.1285293867982;1263.128441375448;1267.2396562062872;1267.512007876974;1279.5201799325253;873.8945234278642;884.7080463447047;896.1665650944684;906.0939060939061;906.5920335848895;5177.275181474522;5342.252084708144;5610.18652295423;5621.381648018714;5641.915371269434;970.8980557041409;981.6760469045198;983.2232536296389;1017.3234798858824;1045.1273186888882;3517.1558825881602;3675.6875665568728;3733.4213196954515;3786.1481166737813;3803.6576708096404;9108.225800807006;9419;9758.219342452587;10032.347897386646;10060.195184385231;9277.768055798659;9689.660861869801;9714.174295184846;10001.049900259464;10023.548940297704;7587.127480339721;7745.419093567893;7748;7772.455928085093;7794.961025194838;4708.764561771966;5625.8593535161945;5691.914621280601;5764.423557644202;5860.443257890493;9236.307276954121;9265.166135047879;9295.442273463614;9314.25485961135;9317;6880.518363714492;8338.583070846555;8600.182982616641;9347.112024357675;9369.765755856159;6231.314555398993;6446.194225721785;6997.300269972962;7048.365647091787;7170.42636589082;7695.345895598937;7845.9607701963405;7846.489978151402;7871;7872.409569282441;6887.483438742014;6901.240863505111;6959.930400695928;7010.544314619533;7130.714771409085;6241.43827055549;6551.91893337594;6947.096877406107;6973.7210511581;6987.510874238755;5254.343207099113;5345.385280692614;6072.939270607238;6131.601445906002;6495.220573531017;4689.413823272091;4732.148213321635;4771.1411945848695;4784.6889952153;4957.603391728725;4760.6429517787;4839.637027222902;4885.926711099408;4886.29148773433;4901.41183058021 21:16:46 INFO - PROCESS | 1636 | |4;prototype.html;307.75687207106387;351.40963181854477;362.26279521174416;365.1328095772541;372.821045897969;4548.95374063965;5240.554552863006;5425.647332923367;5571.637843540167;5671.497053280884;5175.896482070356;5409.675419474835;5540.778368865251;5564.86087847804;5584.469475399841;3770.3213421584105;4286.207051695431;4344.152890186407;4359.564043595631;4367.08291258836;3955.406688996645;3989.421533877586;4369.563043695621;4397.208502469554;4414.911701765979;4142.357934520139;4152.958470415302;4174.624283814456;4191.077962848169;4192.769397683126;4170.52039015514;4190.81141348637;4194.370844373338;4201.243776120297;4210.168576669159;3575.8927232183078;3589.5333606631234;3605.2789442111684;3609.314230296235;3624.6194149614175;4138.52406973199;4244.72409293395;4690.530946905316;5146.742662866842;5462.590305727086;3729.160938788778;4021.5978402159844;4800.063987522434;4842.951570484285;4855.514448555152;4813.735244561548;5558.582016822655;5659.264295641581;5858.882822343529;5907.970460147715;3822.8531440567717;3846.3268927937497;3927.7250592458254;3963.7225394222414;3981.2634662587516;5658.7170641467765;5746.741396637161;5939.46544810969;6139.662318572477;6213.471854892339;3193.4890417533084;3201.4717571600427;3726.06848287928;3860.4402361657403;4051.513818341818;3991.261616600938;4011.398290256485;4053.6554392876346;4087;4102.446169767043;4250.42619246404;4626.259798432235;4690.437147542317;4784.425868895755;4820.035992801454;5033.697978121251;5245.973770131125;5889.941100589025;5914.349421563625;5916.792912248136;1173.8180582009802;1237.7276999060373;1253.9874601254053;1280.5838102616688;1300.245857402723;830.8047608811925;884.5488800711684;888.8488956877346;890.3455959869491;890.7951171230653;5210.5831533477985;5232.305362738193;5232.947670523246;5254.343207099113;5289.973550132302;5020.698758074527;5232.128225113464;5607.579431542651;5747.885042299131;5773.39379365169;4593.311003349524;4627.259638457831;4725.5510726480925;4867.34290870735;4937.530934561211;2483.3982081557297;2681.8927242910454;2785.8183778172584;3036.3016506203253;3044.2237229506336;5058.671186372874;5428.538574221236;5769.74036168365;6038.437321980053;6063.151158837766;4719.528047195253;5183.403908550489;5669.858253543612;5745.540356771448;6226.62640241587;5077.847664570068;5665.461781130786;6079.270487541523;6305.873882522415;6306.873862522724;5367.833941687734;5713.742881570256;5760.452756988079;6041.848953776015;6112.921606653759;5247.9766445542755;5365.8000219909645;5880.5004723795155;5924.140999555127;5934.6142500737315;5249.83650423595;5784.392638773036;6294.496440284858;6304.747810087545;6317;3282.507623856345;3319.5850518685165;3747.6439738224826;3969.80150992455;3983.900402489961;5371.033214021513;5610.298712660918;5831.562632802472;6049.576529642883;6053.75784968614;5021.221710634857;5329.893402132013;5928.547505860919;6006.579539432372;6035.728392222272;4984.8753781155765;5638.464345887134;5911.3201981772645;5918.23063001807;5940.732667030081;4489.820407183676;4937.580585513145;5692.715364231689;5856.736446859816;5862.258402836602;5091.949080509147;5182.689038657692;5338.850078453731;5881.441263080001;5896.734646940812;4546.931796022996;4836.298736683231;5131.666441681279;5764.510016648464;5791.059994901587 21:16:46 INFO - PROCESS | 1636 | |5;yui.html;704.5314865614366;733.5415365396627;752.7316471745113;770.6647608290394;783.4868161354314;14436.350364233609;14907.627309317259;15150.772738408932;15672.294746736396;15690.686186276267;13078.34608269585;13336.733265334688;16224.918875405607;16408.507744767678;16478.42325518607;678.6945874356535;835.2691395029349;858.9570521473933;862.0818827948247;862.1335557764438;684.8390628202368;827.433208252346;846.547097302943;852.8763329317245;861.9439736417113;906.4153620914507;907.6097278170384;911.1799380557518;914.4284821986258;917.2616044084509;911.4485736129645;912.3157631776168;913.2374467319796;914.5838643417229;914.5930061122802;581.764385423904;683.2620769568854;684.0423407229869;684.2575805251308;684.9143857017873;14570.125792452382;14884.255787210595;14890;14930.776038359434;15073.321700523393;13133.762410816726;13583.864161358359;13609.843163331128;14328.426862925471;14631.048981816148;8596.82806343876;10974.341539507575;11016.834747478793;11219.831702524469;11460.71348216293;3654.214343916044;3663.322285377187;3711.6845068169237;3779.9055023624355;3792.2036372361836;110890;112792.87207127905;113300;113848;116516.83483165145;11089.889101109047;11173.608923687632;11215.439228038565;11303.773924521463;11438.59964901241;22761.544769104526;23625.645615315785;23651.645225321634;23657.29028129159;23698;6996.160460744743;7685.154632990367;7694.07671079474;7800.414968877357;7843.215678432111;3492.441209406483;3815.9618403815607;3865.7873816939777;3887.3002859485;3899.9610003900166;3504.737144714157;3567.375709250871;3588.946165807515;3613.837377318027;3754.605766394544;620.8106527509153;638.8913884639622;647.6373230990661;657.4247533408268;659.9144407449718;495.9652824302264;516.3571353664712;674.7435974329724;678.3691167214538;684.425082930331;7596.468247222752;8951.776205594912;9018.432996257654;9267.443953362685;9309.20871725911;636.9235691717024;668.2749217099405;677.1501765284635;678.5589366911448;708.4898872811567;4154.459920210354;4320.805563749639;4540.296254080623;4541.12685745052;4602;87177;98903;102457;108815;109438;83368.99999999879;84015;84835.57582212049;85333.57333213295;87857.56071219603;79894;79934.600326998;81543.59228203821;82114.58942705249;82178.58910705528;9788.951055244679;9838;9985.1512621428;9996.550155242887;10027.247956403153;50744;51600.22599661083;52416;52772.20841687454;53706.73146634242;59242.40757592541;59891.40108598858;61329;73744.63127684327;74975.62512187404;11019.944900275448;12993;13100.410481528545;13164.670883227613;13228.272445015617;14609.707805843611;14747.483838065617;15119.697606048037;15474.922625387251;15512.457064002705;13407.798883016958;13733.587992360246;13853.930730346687;13949;14116.929415352857;11559.826602601135;13517.459301628218;13736.381862816397;14406.279686015449;14470;13579.45682172702;13744.86255137436;13778.931105344811;13981.832815913549;14263.358148883533;11069.446527673423;11160.832587511031;11266.718332041764;11406.714832129263;11515.30908145514;9417.952910235404;11318.60384886525;11411.144164187554;11530.192886497865;11597.304161750322 21:16:46 INFO - PROCESS | 1636 | __end_tp_report 21:16:46 INFO - PROCESS | 1636 | __start_cc_report 21:16:46 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,384 21:16:46 INFO - PROCESS | 1636 | __end_cc_report 21:16:46 INFO - PROCESS | 1636 | __startTimestamp1481692606498__endTimestamp 21:16:46 INFO - PROCESS | 1636 | 21:16:46 INFO - PROCESS | 1636 | ------- Summary: start ------- 21:16:46 INFO - PROCESS | 1636 | Number of tests: 6 21:16:46 INFO - PROCESS | 1636 | 21:16:46 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5676.88 Median:5944.32 stddev:701.42 (11.8%) stddev-sans-first:654.18 21:16:46 INFO - PROCESS | 1636 | Values: 2380.7 3370.6 3392.6 3431.9 3441.2 4405.5 5135.2 5678.6 5685.4 5693.8 5631.9 5684.3 5727.0 5738.0 5754.6 5169.8 5205.1 5276.0 5283.6 5290.0 5189.5 5243.3 5301.5 5312.6 5321.0 5254.5 5288.5 5323.2 5330.6 5369.8 4512.0 5124.7 5147.2 5151.4 5172.9 5064.6 5127.0 5168.4 5180.4 5193.0 4444.5 5667.0 5715.9 5720.1 5727.9 6171.6 6241.0 6244.3 6248.3 6269.8 5345.4 6071.9 6192.1 6253.2 6263.7 5944.3 5953.7 5961.2 5973.4 5985.3 6180.3 6292.1 6304.8 6325.9 6326.9 6108.3 6124.8 6126.6 6173.6 6210.2 6164.6 6253.7 6286.1 6308.0 6313.0 6105.5 6193.3 6222.4 6261.2 6272.2 6144.7 6186.6 6188.4 6203.8 6263.9 4881.3 4949.8 4972.6 4984.1 4991.4 4426.2 4431.7 4454.9 4487.1 4491.0 5090.0 5645.8 5708.7 5730.9 5822.3 6125.3 6236.2 6263.6 6270.4 6286.9 4904.3 5944.0 5952.6 5955.9 5959.0 5430.7 5462.4 5476.5 5488.6 5504.7 6193.3 6215.1 6264.8 6266.4 6312.1 6228.6 6261.6 6264.7 6280.4 6289.3 6292.1 6354.1 6374.9 6406.9 6428.8 5744.1 5791.3 5824.5 5840.7 6250.7 4420.7 4947.7 5870.5 6051.4 6174.3 6008.5 6163.6 6180.5 6196.6 6207.3 6128.6 6172.4 6189.0 6214.2 6241.3 4272.5 5284.5 5862.4 5926.9 6070.0 4712.9 6085.6 6122.4 6123.6 6124.0 5799.1 5828.7 5960.3 5980.2 6064.4 21:16:46 INFO - PROCESS | 1636 | 21:16:46 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:66178.18 Median:37913.41 stddev:139583.48 (368.2%) stddev-sans-first:139887.73 21:16:46 INFO - PROCESS | 1636 | Values: 419.9 461.3 464.4 465.1 492.8 26568.0 35592.8 37793.8 39887.2 39917.2 40528.6 40992.4 41142.8 41176.6 41397.8 8436.8 8618.4 9249.4 9504.9 11133.7 11170.1 11422.2 11449.9 11463.8 11474.6 11422.7 11458.6 11461.5 11490.3 11507.9 9607.9 11334.0 11456.5 11461.9 11505.5 11336.1 11358.9 11378.4 11400.4 11426.9 39354.4 39652.0 39679.4 39709.4 39745.4 3412.5 3451.9 3509.2 3583.7 3621.1 24846.5 30755.9 30810.8 31045.8 31136.2 3160.1 3228.9 3261.5 3311.9 3316.9 420002.0 421324.0 422322.0 422454.0 422841.0 60020.0 60367.4 60646.0 60755.4 60797.7 62776.1 63236.0 63369.4 63408.7 63452.4 15700.8 15952.6 15984.5 15990.7 16022.0 11029.7 11282.4 12264.9 12541.7 12546.1 16869.2 16880.7 16916.5 16934.7 17123.8 6564.5 6589.5 6604.7 6638.6 6653.4 840.3 866.4 952.5 976.6 1064.7 29202.0 29462.7 30003.8 30312.0 30594.1 827.6 1024.7 1042.1 1080.6 1125.3 12292.8 15028.8 15450.8 15498.6 15776.4 503157.0 626446.0 626798.0 628224.0 630690.0 58000.0 58298.4 58444.7 58467.7 58486.0 1347.6 1384.2 1390.3 1394.5 1396.3 28802.3 29536.0 29660.4 30277.8 30472.7 50771.0 51221.0 51268.2 51312.7 51337.7 524517.0 527891.9 528275.0 528936.4 530019.0 39446.6 40011.8 40124.8 40208.8 40303.6 45828.5 46089.5 46567.0 47030.8 47619.8 38310.4 38404.6 38462.8 39174.8 39853.0 38033.0 38253.0 38677.8 39193.0 39579.6 37028.9 37182.6 37605.8 38479.8 38599.0 29502.3 31808.0 31969.5 34303.8 35029.8 22394.3 23307.2 25118.6 25277.0 25355.7 21:16:46 INFO - PROCESS | 1636 | 21:16:46 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:32138.68 Median:4623.51 stddev:91800.96 (1985.5%) stddev-sans-first:92027.38 21:16:46 INFO - PROCESS | 1636 | Values: 313.4 316.9 374.1 382.5 400.7 17700.2 18326.9 19028.3 19189.7 19195.9 19167.4 19372.2 20096.2 20138.8 20281.9 5010.1 5857.1 5857.9 5865.0 5868.1 5433.1 5577.7 5718.1 5722.2 5780.9 4374.1 5541.2 5790.5 5801.2 5802.9 4872.9 5614.2 5843.2 5904.7 5906.9 4926.6 5691.3 5701.9 5703.3 5719.2 17897.7 19084.4 19680.1 23209.0 24626.9 2153.7 2185.5 2191.6 2304.7 2890.1 8832.9 9669.0 9742.1 9744.8 10288.6 1808.8 1865.5 1869.5 1874.3 1943.8 115587.4 116747.7 117665.0 117697.4 118327.4 9245.0 9709.0 9792.4 9802.2 10048.7 10123.4 12231.3 12329.9 12369.2 12392.0 5866.9 7599.4 7798.9 7979.2 8004.4 8076.4 8080.0 8146.6 8256.2 8266.8 3860.9 3871.1 3899.7 3937.1 3987.6 1621.9 1637.6 1640.6 1879.4 1946.6 666.9 827.9 876.5 903.8 965.0 14838.6 19455.9 20314.3 20651.7 20949.5 718.4 934.4 937.4 975.5 998.5 7744.8 10742.2 10783.9 10804.2 10863.9 539958.0 546397.0 553654.0 553801.0 558928.0 80879.0 92185.0 113229.0 113949.0 114420.4 907.1 947.3 951.0 960.1 965.7 17715.5 17830.7 17937.2 18058.0 18343.6 49216.3 50745.5 51139.0 51929.5 51945.2 69490.7 72826.9 73844.6 73859.0 74100.0 6381.7 7155.1 7764.1 7853.2 7997.2 8851.4 11913.3 11947.9 12208.5 12294.9 10134.0 10446.9 10478.8 10478.8 10637.9 9994.0 10226.5 10284.3 10641.0 10676.2 10290.5 10471.7 10788.2 10792.6 10855.5 6319.9 7830.9 7935.5 7939.9 7986.5 8419.0 9915.2 10309.4 10598.4 10620.0 21:16:46 INFO - PROCESS | 1636 | 21:16:46 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5276.64 Median:5143.35 stddev:2846.27 (55.3%) stddev-sans-first:2839.41 21:16:46 INFO - PROCESS | 1636 | Values: 1408.8 1463.4 1519.9 1826.9 1834.3 6632.7 6689.4 6809.2 7612.2 8032.9 7754.6 8043.5 8054.6 8133.2 8159.7 2259.0 2306.9 2313.1 2314.1 2315.8 2311.4 2326.5 2342.4 2344.8 2348.5 1900.4 2290.7 2292.9 2318.9 2321.1 2265.2 2280.1 2287.7 2295.4 2295.4 2273.7 2301.3 2306.5 2308.4 2314.4 6952.4 8159.9 8175.4 8225.9 8261.2 4995.7 5070.8 5175.2 5175.3 5179.8 3243.6 3337.5 3522.6 3525.9 3538.8 1004.4 1010.4 1041.0 1055.6 1074.6 10434.3 10500.3 10565.3 10597.8 10600.1 4416.1 4507.2 4606.0 4619.0 4641.5 9121.3 9230.4 9242.2 9244.3 9295.0 5141.2 5142.8 5143.9 5149.9 5165.4 3976.8 4223.0 4285.7 4728.1 4766.7 3481.8 3538.5 3684.6 3822.3 3827.8 1236.1 1263.1 1267.2 1267.5 1279.5 873.9 884.7 896.2 906.1 906.6 5177.3 5342.3 5610.2 5621.4 5641.9 970.9 981.7 983.2 1017.3 1045.1 3517.2 3675.7 3733.4 3786.1 3803.7 9108.2 9419.0 9758.2 10032.3 10060.2 9277.8 9689.7 9714.2 10001.0 10023.5 7587.1 7745.4 7748.0 7772.5 7795.0 4708.8 5625.9 5691.9 5764.4 5860.4 9236.3 9265.2 9295.4 9314.3 9317.0 6880.5 8338.6 8600.2 9347.1 9369.8 6231.3 6446.2 6997.3 7048.4 7170.4 7695.3 7846.0 7846.5 7871.0 7872.4 6887.5 6901.2 6959.9 7010.5 7130.7 6241.4 6551.9 6947.1 6973.7 6987.5 5254.3 5345.4 6072.9 6131.6 6495.2 4689.4 4732.1 4771.1 4784.7 4957.6 4760.6 4839.6 4885.9 4886.3 4901.4 21:16:46 INFO - PROCESS | 1636 | 21:16:46 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4604.92 Median:5084.90 stddev:1437.08 (28.3%) stddev-sans-first:1404.45 21:16:46 INFO - PROCESS | 1636 | Values: 307.8 351.4 362.3 365.1 372.8 4549.0 5240.6 5425.6 5571.6 5671.5 5175.9 5409.7 5540.8 5564.9 5584.5 3770.3 4286.2 4344.2 4359.6 4367.1 3955.4 3989.4 4369.6 4397.2 4414.9 4142.4 4153.0 4174.6 4191.1 4192.8 4170.5 4190.8 4194.4 4201.2 4210.2 3575.9 3589.5 3605.3 3609.3 3624.6 4138.5 4244.7 4690.5 5146.7 5462.6 3729.2 4021.6 4800.1 4843.0 4855.5 4813.7 5558.6 5659.3 5858.9 5908.0 3822.9 3846.3 3927.7 3963.7 3981.3 5658.7 5746.7 5939.5 6139.7 6213.5 3193.5 3201.5 3726.1 3860.4 4051.5 3991.3 4011.4 4053.7 4087.0 4102.4 4250.4 4626.3 4690.4 4784.4 4820.0 5033.7 5246.0 5889.9 5914.3 5916.8 1173.8 1237.7 1254.0 1280.6 1300.2 830.8 884.5 888.8 890.3 890.8 5210.6 5232.3 5232.9 5254.3 5290.0 5020.7 5232.1 5607.6 5747.9 5773.4 4593.3 4627.3 4725.6 4867.3 4937.5 2483.4 2681.9 2785.8 3036.3 3044.2 5058.7 5428.5 5769.7 6038.4 6063.2 4719.5 5183.4 5669.9 5745.5 6226.6 5077.8 5665.5 6079.3 6305.9 6306.9 5367.8 5713.7 5760.5 6041.8 6112.9 5248.0 5365.8 5880.5 5924.1 5934.6 5249.8 5784.4 6294.5 6304.7 6317.0 3282.5 3319.6 3747.6 3969.8 3983.9 5371.0 5610.3 5831.6 6049.6 6053.8 5021.2 5329.9 5928.5 6006.6 6035.7 4984.9 5638.5 5911.3 5918.2 5940.7 4489.8 4937.6 5692.7 5856.7 5862.3 5091.9 5182.7 5338.9 5881.4 5896.7 4546.9 4836.3 5131.7 5764.5 5791.1 21:16:46 INFO - PROCESS | 1636 | 21:16:46 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:20654.75 Median:3893.63 stddev:29885.60 (767.6%) stddev-sans-first:29931.89 21:16:46 INFO - PROCESS | 1636 | Values: 704.5 733.5 752.7 770.7 783.5 14436.4 14907.6 15150.8 15672.3 15690.7 13078.3 13336.7 16224.9 16408.5 16478.4 678.7 835.3 859.0 862.1 862.1 684.8 827.4 846.5 852.9 861.9 906.4 907.6 911.2 914.4 917.3 911.4 912.3 913.2 914.6 914.6 581.8 683.3 684.0 684.3 684.9 14570.1 14884.3 14890.0 14930.8 15073.3 13133.8 13583.9 13609.8 14328.4 14631.0 8596.8 10974.3 11016.8 11219.8 11460.7 3654.2 3663.3 3711.7 3779.9 3792.2 110890.0 112792.9 113300.0 113848.0 116516.8 11089.9 11173.6 11215.4 11303.8 11438.6 22761.5 23625.6 23651.6 23657.3 23698.0 6996.2 7685.2 7694.1 7800.4 7843.2 3492.4 3816.0 3865.8 3887.3 3900.0 3504.7 3567.4 3588.9 3613.8 3754.6 620.8 638.9 647.6 657.4 659.9 496.0 516.4 674.7 678.4 684.4 7596.5 8951.8 9018.4 9267.4 9309.2 636.9 668.3 677.2 678.6 708.5 4154.5 4320.8 4540.3 4541.1 4602.0 87177.0 98903.0 102457.0 108815.0 109438.0 83369.0 84015.0 84835.6 85333.6 87857.6 79894.0 79934.6 81543.6 82114.6 82178.6 9789.0 9838.0 9985.2 9996.6 10027.2 50744.0 51600.2 52416.0 52772.2 53706.7 59242.4 59891.4 61329.0 73744.6 74975.6 11019.9 12993.0 13100.4 13164.7 13228.3 14609.7 14747.5 15119.7 15474.9 15512.5 13407.8 13733.6 13853.9 13949.0 14116.9 11559.8 13517.5 13736.4 14406.3 14470.0 13579.5 13744.9 13778.9 13981.8 14263.4 11069.4 11160.8 11266.7 11406.7 11515.3 9418.0 11318.6 11411.1 11530.2 11597.3 21:16:46 INFO - PROCESS | 1636 | -------- Summary: end -------- 21:16:46 INFO - PROCESS | 1636 | 21:16:47 INFO - TEST-INFO | 1636: exit 0 21:16:47 INFO - TEST-OK | dromaeo_css | took 1116766ms 21:16:47 INFO - TEST-START | kraken 21:16:47 INFO - operating with platform_type : mac_ 21:16:47 INFO - Initialising browser for kraken test... 21:16:47 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpdREXyo/profile http://localhost:49248/getInfo.html) 21:16:50 INFO - PROCESS | 1648 | 2016-12-13 21:16:50.030 plugin-container[1649:9927] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 21:16:50 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:16:50 INFO - PROCESS | 1648 | 2016-12-13 21:16:50.034 plugin-container[1649:9927] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470f, name = 'com.apple.CFPasteboardClient' 21:16:50 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:16:50 INFO - PROCESS | 1648 | 2016-12-13 21:16:50.034 plugin-container[1649:9927] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:16:50 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 21:16:50 INFO - PROCESS | 1648 | colorDepth:24 21:16:50 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 21:16:50 INFO - PROCESS | 1648 | __metrics 21:16:50 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 21:16:50 INFO - TEST-INFO | 1648: exit 0 21:16:50 INFO - Browser initialized. 21:16:50 INFO - Running cycle 1/1 for kraken test... 21:16:50 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpdREXyo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:16:53 INFO - PROCESS | 1650 | 2016-12-13 21:16:53.353 plugin-container[1651:10040] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 21:16:53 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:16:53 INFO - PROCESS | 1650 | 2016-12-13 21:16:53.353 plugin-container[1651:10040] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 21:16:53 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:16:53 INFO - PROCESS | 1650 | 2016-12-13 21:16:53.353 plugin-container[1651:10040] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:17:03 INFO - PROCESS | 1650 | RSS: Main: 236806144 21:17:03 INFO - PROCESS | 1650 | 21:17:06 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 21:17:07 INFO - PROCESS | 1650 | RSS: Main: 249393152 21:17:07 INFO - PROCESS | 1650 | 21:17:10 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 21:17:10 INFO - PROCESS | 1650 | RSS: Main: 257122304 21:17:10 INFO - PROCESS | 1650 | 21:17:14 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 21:17:14 INFO - PROCESS | 1650 | RSS: Main: 259760128 21:17:14 INFO - PROCESS | 1650 | 21:17:16 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 21:17:17 INFO - PROCESS | 1650 | RSS: Main: 261869568 21:17:17 INFO - PROCESS | 1650 | 21:17:19 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:17:20 INFO - PROCESS | 1650 | RSS: Main: 262488064 21:17:20 INFO - PROCESS | 1650 | 21:17:23 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 21:17:24 INFO - PROCESS | 1650 | RSS: Main: 269230080 21:17:24 INFO - PROCESS | 1650 | 21:17:27 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 21:17:28 INFO - PROCESS | 1650 | RSS: Main: 267358208 21:17:28 INFO - PROCESS | 1650 | 21:17:31 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 21:17:31 INFO - PROCESS | 1650 | RSS: Main: 267051008 21:17:31 INFO - PROCESS | 1650 | 21:17:33 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:17:33 INFO - PROCESS | 1650 | RSS: Main: 266985472 21:17:33 INFO - PROCESS | 1650 | 21:17:35 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:17:36 INFO - PROCESS | 1650 | RSS: Main: 266883072 21:17:36 INFO - PROCESS | 1650 | 21:17:38 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:17:39 INFO - PROCESS | 1650 | RSS: Main: 266985472 21:17:39 INFO - PROCESS | 1650 | 21:17:42 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:17:43 INFO - PROCESS | 1650 | RSS: Main: 267317248 21:17:43 INFO - PROCESS | 1650 | 21:17:46 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:17:47 INFO - PROCESS | 1650 | RSS: Main: 267112448 21:17:47 INFO - PROCESS | 1650 | 21:17:48 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 21:17:49 INFO - PROCESS | 1650 | RSS: Main: 269647872 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | __start_tp_report 21:17:49 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 21:17:49 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 21:17:49 INFO - PROCESS | 1650 | |i|pagename|runs| 21:17:49 INFO - PROCESS | 1650 | |0;ai-astar;119;176;156;118;118;118;118;133;174;119 21:17:49 INFO - PROCESS | 1650 | |1;audio-beat-detection;162;121;125;182;153;123;129;150;171;155 21:17:49 INFO - PROCESS | 1650 | |2;audio-dft;279;248;195;200;198;199;201;198;197;194 21:17:49 INFO - PROCESS | 1650 | |3;audio-fft;113;52;62;104;87;51;75;83;96;50 21:17:49 INFO - PROCESS | 1650 | |4;audio-oscillator;96;102;97;74;72;71;86;71;73;108 21:17:49 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;117;132;139;172;144;127;123;125;122;118 21:17:49 INFO - PROCESS | 1650 | |6;imaging-darkroom;113;154;153;97;97;98;124;153;115;152 21:17:49 INFO - PROCESS | 1650 | |7;imaging-desaturate;95;96;93;103;94;105;149;150;93;94 21:17:49 INFO - PROCESS | 1650 | |8;json-parse-financial;59;58;58;57;58;60;60;59;60;61 21:17:49 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;69;71;79;77;75;70;71;74;72;72 21:17:49 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;81;81;116;126;111;112;111;81;80;114 21:17:49 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;152;150;178;144;151;169;142;159;216;161 21:17:49 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;180;183;182;195;232;178;183;180;180;183 21:17:49 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;69;70;67;70;72;69;77;92;88;92 21:17:49 INFO - PROCESS | 1650 | __end_tp_report 21:17:49 INFO - PROCESS | 1650 | __start_cc_report 21:17:49 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,948 21:17:49 INFO - PROCESS | 1650 | __end_cc_report 21:17:49 INFO - PROCESS | 1650 | __startTimestamp1481692669101__endTimestamp 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | ------- Summary: start ------- 21:17:49 INFO - PROCESS | 1650 | Number of tests: 14 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:134.90 Median:126.00 stddev:24.30 (19.3%) stddev-sans-first:25.08 21:17:49 INFO - PROCESS | 1650 | Values: 119.0 176.0 156.0 118.0 118.0 118.0 118.0 133.0 174.0 119.0 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:147.10 Median:154.00 stddev:21.59 (14.0%) stddev-sans-first:22.22 21:17:49 INFO - PROCESS | 1650 | Values: 162.0 121.0 125.0 182.0 153.0 123.0 129.0 150.0 171.0 155.0 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:210.90 Median:199.50 stddev:28.75 (14.4%) stddev-sans-first:16.90 21:17:49 INFO - PROCESS | 1650 | Values: 279.0 248.0 195.0 200.0 198.0 199.0 201.0 198.0 197.0 194.0 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:77.30 Median:68.50 stddev:23.05 (33.6%) stddev-sans-first:20.51 21:17:49 INFO - PROCESS | 1650 | Values: 113.0 52.0 62.0 104.0 87.0 51.0 75.0 83.0 96.0 50.0 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:85.00 Median:73.50 stddev:14.57 (19.8%) stddev-sans-first:14.90 21:17:49 INFO - PROCESS | 1650 | Values: 96.0 102.0 97.0 74.0 72.0 71.0 86.0 71.0 73.0 108.0 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:131.90 Median:129.50 stddev:16.56 (12.8%) stddev-sans-first:16.67 21:17:49 INFO - PROCESS | 1650 | Values: 117.0 132.0 139.0 172.0 144.0 127.0 123.0 125.0 122.0 118.0 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:125.60 Median:153.50 stddev:25.11 (16.4%) stddev-sans-first:26.22 21:17:49 INFO - PROCESS | 1650 | Values: 113.0 154.0 153.0 97.0 97.0 98.0 124.0 153.0 115.0 152.0 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:107.20 Median:93.50 stddev:22.68 (24.3%) stddev-sans-first:23.62 21:17:49 INFO - PROCESS | 1650 | Values: 95.0 96.0 93.0 103.0 94.0 105.0 149.0 150.0 93.0 94.0 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:59.00 Median:59.50 stddev:1.25 (2.1%) stddev-sans-first:1.32 21:17:49 INFO - PROCESS | 1650 | Values: 59.0 58.0 58.0 57.0 58.0 60.0 60.0 59.0 60.0 61.0 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:73.00 stddev:3.20 (4.4%) stddev-sans-first:3.05 21:17:49 INFO - PROCESS | 1650 | Values: 69.0 71.0 79.0 77.0 75.0 70.0 71.0 74.0 72.0 72.0 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:101.30 Median:103.00 stddev:18.20 (17.7%) stddev-sans-first:17.76 21:17:49 INFO - PROCESS | 1650 | Values: 81.0 81.0 116.0 126.0 111.0 112.0 111.0 81.0 80.0 114.0 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:162.20 Median:160.00 stddev:21.91 (13.7%) stddev-sans-first:22.92 21:17:49 INFO - PROCESS | 1650 | Values: 152.0 150.0 178.0 144.0 151.0 169.0 142.0 159.0 216.0 161.0 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.60 Median:183.00 stddev:16.28 (8.9%) stddev-sans-first:17.04 21:17:49 INFO - PROCESS | 1650 | Values: 180.0 183.0 182.0 195.0 232.0 178.0 183.0 180.0 180.0 183.0 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.60 Median:74.50 stddev:10.11 (13.6%) stddev-sans-first:10.35 21:17:49 INFO - PROCESS | 1650 | Values: 69.0 70.0 67.0 70.0 72.0 69.0 77.0 92.0 88.0 92.0 21:17:49 INFO - PROCESS | 1650 | -------- Summary: end -------- 21:17:49 INFO - PROCESS | 1650 | 21:17:49 INFO - TEST-INFO | 1650: exit 0 21:17:49 INFO - TEST-OK | kraken | took 62855ms 21:17:49 INFO - SUITE-END | took 1179s 21:17:50 INFO - Completed test suite (00:19:39) 21:17:50 INFO - javascript benchmark 21:17:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7154.224612824145, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2380.6786083878674, 3370.5618269624947, 3392.6268110507854, 3431.9485207721873, 3441.225724212052, 4405.493368262651, 5135.152699804533, 5678.6308889922175, 5685.374608793029, 5693.800716974907, 5631.859203519914, 5684.346300175481, 5727.0, 5738.0245358289085, 5754.597178197528, 5169.819056333029, 5205.115130427817, 5275.973620131913, 5283.577313814885, 5290.021346050993, 5189.481051894818, 5243.344581927259, 5301.469853014706, 5312.574994000471, 5320.973395133019, 5254.527092561669, 5288.4711528847, 5323.22813192087, 5330.573554115662, 5369.838904832861, 4511.977440112779, 5124.718140502271, 5147.22791581262, 5151.407588127376, 5172.922406163873, 5064.645474816764, 5127.0, 5168.3797944246935, 5180.4042535108565, 5193.0, 4444.466664000308, 5667.008273552153, 5715.914261286084, 5720.08478643415, 5727.885442291173, 6171.59884607499, 6241.0, 6244.2819075805955, 6248.343923888017, 6269.780557680505, 5345.358556973188, 6071.878562428771, 6192.133101365813, 6253.249610046778, 6263.71813268404, 5944.3164036136395, 5953.702314884238, 5961.225040744675, 5973.402659734079, 5985.25184351956, 6180.258368995749, 6292.056191571301, 6304.779332723333, 6325.8734825304155, 6326.905096423558, 6108.311716707923, 6124.808163753463, 6126.632402055891, 6173.59871608353, 6210.161628180138, 6164.599301045418, 6253.6560489173535, 6286.088517165077, 6307.968460157762, 6312.968435157794, 6105.459747278077, 6193.287771906288, 6222.439980401786, 6261.217347831521, 6272.18461599989, 6144.7462419631465, 6186.628802271878, 6188.350223226586, 6203.751849926042, 6263.906041409383, 4881.267809828483, 4949.7772600233075, 4972.602191824645, 4984.053029924303, 4991.376077990251, 4426.203283408953, 4431.711938723973, 4454.888627784267, 4487.102579484116, 4491.011977364975, 5089.9745501272255, 5645.830625081254, 5708.686022268732, 5730.856728581819, 5822.272215973004, 6125.264968203844, 6236.158118654014, 6263.561550691408, 6270.435660790551, 6286.9056964145575, 4904.2643603459765, 5944.048952167633, 5952.553558483131, 5955.910661340084, 5958.970205148947, 5430.701311427831, 5462.399136095027, 5476.534494568006, 5488.615796894259, 5504.697241651667, 6193.318734939153, 6215.129881816459, 6264.843378915381, 6266.404691554386, 6312.084747711648, 6228.626282423069, 6261.5616906816085, 6264.68676566179, 6280.371962803683, 6289.339619339903, 6292.11910332563, 6354.1104245406605, 6374.872502550016, 6406.9359306408205, 6428.8071357859335, 5744.080947048452, 5791.305043394819, 5824.475797178275, 5840.678762668094, 6250.687465626791, 4420.668449866209, 4947.6536642434685, 5870.53035757147, 6051.425114614105, 6174.25908890936, 6008.54935879802, 6163.568550201443, 6180.536459765445, 6196.597221180789, 6207.34822843613, 6128.632282062912, 6172.444479996821, 6189.04069869171, 6214.223222097238, 6241.282252541016, 4272.530021697548, 5284.497972692588, 5862.414951554154, 5926.881462370642, 6070.0, 4712.929306060343, 6085.57400981927, 6122.418370254819, 6123.557162997741, 6123.953766440777, 5799.101139323411, 5828.70856457167, 5960.344362120077, 5980.222571065734, 6064.3935606439], "value": 5632.906919637548, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.903422212891, 461.2573756252433, 464.3707775963569, 465.1023524597525, 492.8324369714296, 26568.0, 35592.82203588978, 37793.81103094481, 39887.202255954864, 39917.20165596686, 40528.59471405285, 40992.38511422324, 41142.794286028606, 41176.58823411765, 41397.79301103498, 8436.78908027301, 8618.43980141292, 9249.44503329799, 9504.904950950506, 11133.665990020272, 11170.050545703625, 11422.200445968785, 11449.885501145007, 11463.828042579367, 11474.598389056384, 11422.657320280405, 11458.598949036785, 11461.484233209483, 11490.310581365104, 11507.942460287686, 9607.895092064397, 11334.036606888425, 11456.541738330458, 11461.885381146165, 11505.482253298622, 11336.149788765875, 11358.943205284004, 11378.37418941958, 11400.429978501124, 11426.942865285704, 39354.40968385476, 39652.0, 39679.40480892789, 39709.40435893435, 39745.40381894274, 3412.522246885438, 3451.861925522976, 3509.2455122148604, 3583.7133029357587, 3621.0947263184203, 24846.5030699385, 30755.923542676122, 30810.84594577035, 31045.844770776228, 31136.22159446032, 3160.067780004904, 3228.9192770180935, 3261.5107733839877, 3311.8840840570465, 3316.9170770730925, 420002.0, 421324.0, 422322.0, 422454.0, 422841.0, 60020.0, 60367.39632603706, 60646.0, 60755.39244607586, 60797.696011520544, 62776.05835912465, 63236.0, 63369.366306337266, 63408.68295658584, 63452.36547634557, 15700.822438317173, 15952.601184970237, 15984.520464386087, 15990.680186396206, 16022.0, 11029.669109926715, 11282.435878206057, 12264.938675306745, 12541.749165016648, 12546.12177147591, 16869.24088416024, 16880.746788798177, 16916.49250522486, 16934.661306773796, 17123.828761712222, 6564.540482166202, 6589.53873228879, 6604.735810567619, 6638.634875081917, 6653.434458071021, 840.3487297344485, 866.3675516872718, 952.518977916148, 976.5507866381544, 1064.7338165458636, 29202.0, 29462.705372946424, 30003.849980749957, 30312.0, 30594.08217753471, 827.5820710541138, 1024.7284469615558, 1042.08296698906, 1080.5677728908342, 1125.2629527659396, 12292.849374931486, 15028.79769618428, 15450.768238476432, 15498.612534686723, 15776.447824326093, 503157.0, 626446.0, 626798.0, 628224.0, 630690.0, 58000.0, 58298.4170158293, 58444.70777646085, 58467.70766146142, 58486.0, 1347.615929460099, 1384.190248704519, 1390.2770559309104, 1394.5258612071943, 1396.2599822094103, 28802.27994300159, 29536.0, 29660.406791864472, 30277.848610756806, 30472.695273047873, 50770.98458030745, 51220.97558048893, 51268.230976534636, 51312.74343628258, 51337.74331128321, 524517.0, 527891.8502742234, 528275.0, 528936.3553182364, 530019.0, 39446.60553394429, 40011.79994100011, 40124.799376002935, 40208.798956005034, 40303.59696402998, 45828.541714582425, 46089.53910460853, 46567.0, 47030.82422939454, 47619.80950476127, 38310.42534362042, 38404.615953841225, 38462.807685961394, 39174.804125979186, 39853.0, 38033.0, 38253.0, 38677.80661096789, 39193.02017449587, 39579.60420395759, 37028.88913332604, 37182.628173717916, 37605.81197093997, 38479.807600961816, 38599.0, 29502.262443439085, 31808.04575862728, 31969.520457193626, 34303.833957912626, 35029.824850875586, 22394.32817015492, 23307.18424855122, 25118.623220651338, 25277.0, 25355.746442536078], "value": 19995.53110888074, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.38263620667277, 316.9397814415261, 374.09282489961845, 382.45309207832787, 400.69346949583576, 17700.203490842912, 18326.90836545817, 19028.334008309695, 19189.712154317698, 19195.944223067727, 19167.42497725067, 19372.225110995543, 20096.196152153898, 20138.798612013874, 20281.884496352694, 5010.123228435018, 5857.121431785224, 5857.8828423431505, 5865.0, 5868.061110222366, 5433.1035379162395, 5577.717125061238, 5718.113692377668, 5722.198892155102, 5780.855478613047, 4374.147041326943, 5541.196526503654, 5790.507806836424, 5801.216835727165, 5802.883942321151, 4872.878178045542, 5614.214010038578, 5843.2403787507565, 5904.675242861641, 5906.88186236275, 4926.605871530269, 5691.345495268014, 5701.885962280731, 5703.315602127771, 5719.19931209631, 17897.731534027, 19084.427467176007, 19680.14637877514, 23208.955596998007, 24626.876865615737, 2153.676948457728, 2185.519185779126, 2191.638379667353, 2304.746477887431, 2890.1040677389865, 8832.8675069874, 9669.033096690346, 9742.123208911162, 9744.75638109046, 10288.639897603623, 1808.806187915969, 1865.5149661088078, 1869.5139263791377, 1874.3158747057362, 1943.7959014303435, 115587.42206289, 116747.66504669945, 117665.0, 117697.41151294274, 118327.40836295763, 9244.95377523108, 9709.0, 9792.363496372713, 9802.215822734164, 10048.69853904384, 10123.443210623487, 12231.266124032587, 12329.938350308192, 12369.196002259825, 12392.0, 5866.882662346729, 7599.392048636096, 7798.908152858604, 7979.241972012649, 8004.359651227888, 8076.434649574473, 8079.959600202079, 8146.633401496947, 8256.174382561696, 8266.8346633067, 3860.9034774130873, 3871.1289959759392, 3899.6880249579967, 3937.074787424953, 3987.5613682494904, 1621.8646947137051, 1637.598788296875, 1640.6308580569275, 1879.39859245043, 1946.5912158446886, 666.9166354205724, 827.8758186271987, 876.5179151466668, 903.8192361527721, 964.99035009649, 14838.629034274014, 19455.92992385404, 20314.28899988523, 20651.69022464664, 20949.533532652873, 718.3714250031222, 934.4019827310527, 937.3625934364698, 975.5414954971152, 998.4658207858761, 7744.806379840549, 10742.194335424874, 10783.892161078445, 10804.1896857736, 10863.891361086446, 539958.0, 546397.0, 553654.0, 553801.0, 558928.0, 80879.0, 92185.0, 113229.0, 113949.0, 114420.42789785998, 907.0566610724773, 947.275334369208, 950.9857352139724, 960.1406740966795, 965.6861520005885, 17715.468535943943, 17830.732539011668, 17937.192826322585, 18058.0, 18343.633127337645, 49216.26175607297, 50745.492545075555, 51139.0, 51929.480705192465, 51945.22082168695, 69490.65254673896, 72826.90759638503, 73844.63077684578, 73859.0, 74100.0, 6381.744730210739, 7155.069840920647, 7764.06831180262, 7853.175416581405, 7997.160298168841, 8851.380403371959, 11913.285202887855, 11947.940260298643, 12208.45061972231, 12294.938525307316, 10134.0, 10446.947765261126, 10478.79042419132, 10478.842817357898, 10637.893621063691, 9994.0, 10226.488675566341, 10284.2801003929, 10640.989106034915, 10676.199285053808, 10290.485475726333, 10471.659453678527, 10788.190885683762, 10792.622258220925, 10855.511501982272, 6319.873602528016, 7830.8825367620675, 7935.484193527403, 7939.882039459906, 7986.480878742863, 8419.0, 9915.157211637092, 10309.381437113798, 10598.417087059977, 10620.0], "value": 8893.298434338662, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.753253370767, 1463.4073200353853, 1519.9164045977473, 1826.8995205263714, 1834.275461192828, 6632.70152843122, 6689.364510371513, 6809.1828980522405, 7612.162662107171, 8032.915556399892, 7754.573498457583, 8043.517388956667, 8054.637541310641, 8133.186681331864, 8159.714409995651, 2258.9661155082686, 2306.9077236910503, 2313.0516488239823, 2314.0743702518957, 2315.8263130265213, 2311.3643747969277, 2326.5114325991563, 2342.4143964009, 2344.78896899279, 2348.4598542335275, 1900.4203717866046, 2290.656401539774, 2292.885355732215, 2318.93043208704, 2321.0599707118645, 2265.2184996176293, 2280.09936075251, 2287.6797248385237, 2295.357299956015, 2295.36877358726, 2273.7498875123724, 2301.2981040782556, 2306.527161931817, 2308.4113551044375, 2314.4213946513373, 6952.409045231161, 8159.877601835978, 8175.386845986574, 8225.917740822575, 8261.215184557457, 4995.650304478688, 5070.771815268322, 5175.197844334134, 5175.327207463006, 5179.792808287664, 3243.594550681165, 3337.516060171286, 3522.5772907251294, 3525.8589656413715, 3538.752287339887, 1004.4174378860243, 1010.3786171504472, 1040.979180416395, 1055.5566662001977, 1074.5970261152067, 10434.269601128, 10500.317479363894, 10565.3132546385, 10597.84103238452, 10600.098991585724, 4416.116776644684, 4507.233770259064, 4606.032733126081, 4618.97690511552, 4641.512641172696, 9121.315901307296, 9230.35387522867, 9242.21441177494, 9244.260459163252, 9294.953525232331, 5141.177411614124, 5142.76857541404, 5143.922841157386, 5149.897002059938, 5165.380154381498, 3976.76139431635, 4222.9653734835165, 4285.721428107226, 4728.101660684528, 4766.737829419346, 3481.816182497912, 3538.5399898013093, 3684.5578530576504, 3822.2928758179823, 3827.7798876651477, 1236.1285293867982, 1263.128441375448, 1267.2396562062872, 1267.512007876974, 1279.5201799325253, 873.8945234278642, 884.7080463447047, 896.1665650944684, 906.0939060939061, 906.5920335848895, 5177.275181474522, 5342.252084708144, 5610.18652295423, 5621.381648018714, 5641.915371269434, 970.8980557041409, 981.6760469045198, 983.2232536296389, 1017.3234798858824, 1045.1273186888882, 3517.1558825881602, 3675.6875665568728, 3733.4213196954515, 3786.1481166737813, 3803.6576708096404, 9108.225800807006, 9419.0, 9758.219342452587, 10032.347897386646, 10060.195184385231, 9277.768055798659, 9689.660861869801, 9714.174295184846, 10001.049900259464, 10023.548940297704, 7587.127480339721, 7745.419093567893, 7748.0, 7772.455928085093, 7794.961025194838, 4708.764561771966, 5625.8593535161945, 5691.914621280601, 5764.423557644202, 5860.443257890493, 9236.307276954121, 9265.166135047879, 9295.442273463614, 9314.25485961135, 9317.0, 6880.518363714492, 8338.583070846555, 8600.182982616641, 9347.112024357675, 9369.765755856159, 6231.314555398993, 6446.194225721785, 6997.300269972962, 7048.365647091787, 7170.42636589082, 7695.345895598937, 7845.9607701963405, 7846.489978151402, 7871.0, 7872.409569282441, 6887.483438742014, 6901.240863505111, 6959.930400695928, 7010.544314619533, 7130.714771409085, 6241.43827055549, 6551.91893337594, 6947.096877406107, 6973.7210511581, 6987.510874238755, 5254.343207099113, 5345.385280692614, 6072.939270607238, 6131.601445906002, 6495.220573531017, 4689.413823272091, 4732.148213321635, 4771.1411945848695, 4784.6889952153, 4957.603391728725, 4760.6429517787, 4839.637027222902, 4885.926711099408, 4886.29148773433, 4901.41183058021], "value": 4322.633098859496, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.75687207106387, 351.40963181854477, 362.26279521174416, 365.1328095772541, 372.821045897969, 4548.95374063965, 5240.554552863006, 5425.647332923367, 5571.637843540167, 5671.497053280884, 5175.896482070356, 5409.675419474835, 5540.778368865251, 5564.86087847804, 5584.469475399841, 3770.3213421584105, 4286.207051695431, 4344.152890186407, 4359.564043595631, 4367.08291258836, 3955.406688996645, 3989.421533877586, 4369.563043695621, 4397.208502469554, 4414.911701765979, 4142.357934520139, 4152.958470415302, 4174.624283814456, 4191.077962848169, 4192.769397683126, 4170.52039015514, 4190.81141348637, 4194.370844373338, 4201.243776120297, 4210.168576669159, 3575.8927232183078, 3589.5333606631234, 3605.2789442111684, 3609.314230296235, 3624.6194149614175, 4138.52406973199, 4244.72409293395, 4690.530946905316, 5146.742662866842, 5462.590305727086, 3729.160938788778, 4021.5978402159844, 4800.063987522434, 4842.951570484285, 4855.514448555152, 4813.735244561548, 5558.582016822655, 5659.264295641581, 5858.882822343529, 5907.970460147715, 3822.8531440567717, 3846.3268927937497, 3927.7250592458254, 3963.7225394222414, 3981.2634662587516, 5658.7170641467765, 5746.741396637161, 5939.46544810969, 6139.662318572477, 6213.471854892339, 3193.4890417533084, 3201.4717571600427, 3726.06848287928, 3860.4402361657403, 4051.513818341818, 3991.261616600938, 4011.398290256485, 4053.6554392876346, 4087.0, 4102.446169767043, 4250.42619246404, 4626.259798432235, 4690.437147542317, 4784.425868895755, 4820.035992801454, 5033.697978121251, 5245.973770131125, 5889.941100589025, 5914.349421563625, 5916.792912248136, 1173.8180582009802, 1237.7276999060373, 1253.9874601254053, 1280.5838102616688, 1300.245857402723, 830.8047608811925, 884.5488800711684, 888.8488956877346, 890.3455959869491, 890.7951171230653, 5210.5831533477985, 5232.305362738193, 5232.947670523246, 5254.343207099113, 5289.973550132302, 5020.698758074527, 5232.128225113464, 5607.579431542651, 5747.885042299131, 5773.39379365169, 4593.311003349524, 4627.259638457831, 4725.5510726480925, 4867.34290870735, 4937.530934561211, 2483.3982081557297, 2681.8927242910454, 2785.8183778172584, 3036.3016506203253, 3044.2237229506336, 5058.671186372874, 5428.538574221236, 5769.74036168365, 6038.437321980053, 6063.151158837766, 4719.528047195253, 5183.403908550489, 5669.858253543612, 5745.540356771448, 6226.62640241587, 5077.847664570068, 5665.461781130786, 6079.270487541523, 6305.873882522415, 6306.873862522724, 5367.833941687734, 5713.742881570256, 5760.452756988079, 6041.848953776015, 6112.921606653759, 5247.9766445542755, 5365.8000219909645, 5880.5004723795155, 5924.140999555127, 5934.6142500737315, 5249.83650423595, 5784.392638773036, 6294.496440284858, 6304.747810087545, 6317.0, 3282.507623856345, 3319.5850518685165, 3747.6439738224826, 3969.80150992455, 3983.900402489961, 5371.033214021513, 5610.298712660918, 5831.562632802472, 6049.576529642883, 6053.75784968614, 5021.221710634857, 5329.893402132013, 5928.547505860919, 6006.579539432372, 6035.728392222272, 4984.8753781155765, 5638.464345887134, 5911.3201981772645, 5918.23063001807, 5940.732667030081, 4489.820407183676, 4937.580585513145, 5692.715364231689, 5856.736446859816, 5862.258402836602, 5091.949080509147, 5182.689038657692, 5338.850078453731, 5881.441263080001, 5896.734646940812, 4546.931796022996, 4836.298736683231, 5131.666441681279, 5764.510016648464, 5791.059994901587], "value": 4156.150249159259, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.5314865614366, 733.5415365396627, 752.7316471745113, 770.6647608290394, 783.4868161354314, 14436.350364233609, 14907.627309317259, 15150.772738408932, 15672.294746736396, 15690.686186276267, 13078.34608269585, 13336.733265334688, 16224.918875405607, 16408.507744767678, 16478.42325518607, 678.6945874356535, 835.2691395029349, 858.9570521473933, 862.0818827948247, 862.1335557764438, 684.8390628202368, 827.433208252346, 846.547097302943, 852.8763329317245, 861.9439736417113, 906.4153620914507, 907.6097278170384, 911.1799380557518, 914.4284821986258, 917.2616044084509, 911.4485736129645, 912.3157631776168, 913.2374467319796, 914.5838643417229, 914.5930061122802, 581.764385423904, 683.2620769568854, 684.0423407229869, 684.2575805251308, 684.9143857017873, 14570.125792452382, 14884.255787210595, 14890.0, 14930.776038359434, 15073.321700523393, 13133.762410816726, 13583.864161358359, 13609.843163331128, 14328.426862925471, 14631.048981816148, 8596.82806343876, 10974.341539507575, 11016.834747478793, 11219.831702524469, 11460.71348216293, 3654.214343916044, 3663.322285377187, 3711.6845068169237, 3779.9055023624355, 3792.2036372361836, 110890.0, 112792.87207127905, 113300.0, 113848.0, 116516.83483165145, 11089.889101109047, 11173.608923687632, 11215.439228038565, 11303.773924521463, 11438.59964901241, 22761.544769104526, 23625.645615315785, 23651.645225321634, 23657.29028129159, 23698.0, 6996.160460744743, 7685.154632990367, 7694.07671079474, 7800.414968877357, 7843.215678432111, 3492.441209406483, 3815.9618403815607, 3865.7873816939777, 3887.3002859485, 3899.9610003900166, 3504.737144714157, 3567.375709250871, 3588.946165807515, 3613.837377318027, 3754.605766394544, 620.8106527509153, 638.8913884639622, 647.6373230990661, 657.4247533408268, 659.9144407449718, 495.9652824302264, 516.3571353664712, 674.7435974329724, 678.3691167214538, 684.425082930331, 7596.468247222752, 8951.776205594912, 9018.432996257654, 9267.443953362685, 9309.20871725911, 636.9235691717024, 668.2749217099405, 677.1501765284635, 678.5589366911448, 708.4898872811567, 4154.459920210354, 4320.805563749639, 4540.296254080623, 4541.12685745052, 4602.0, 87177.0, 98903.0, 102457.0, 108815.0, 109438.0, 83368.99999999879, 84015.0, 84835.57582212049, 85333.57333213295, 87857.56071219603, 79894.0, 79934.600326998, 81543.59228203821, 82114.58942705249, 82178.58910705528, 9788.951055244679, 9838.0, 9985.1512621428, 9996.550155242887, 10027.247956403153, 50744.0, 51600.22599661083, 52416.0, 52772.20841687454, 53706.73146634242, 59242.40757592541, 59891.40108598858, 61329.0, 73744.63127684327, 74975.62512187404, 11019.944900275448, 12993.0, 13100.410481528545, 13164.670883227613, 13228.272445015617, 14609.707805843611, 14747.483838065617, 15119.697606048037, 15474.922625387251, 15512.457064002705, 13407.798883016958, 13733.587992360246, 13853.930730346687, 13949.0, 14116.929415352857, 11559.826602601135, 13517.459301628218, 13736.381862816397, 14406.279686015449, 14470.0, 13579.45682172702, 13744.86255137436, 13778.931105344811, 13981.832815913549, 14263.358148883533, 11069.446527673423, 11160.832587511031, 11266.718332041764, 11406.714832129263, 11515.30908145514, 9417.952910235404, 11318.60384886525, 11411.144164187554, 11530.192886497865, 11597.304161750322], "value": 7450.076914967802, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1679.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 176.0, 156.0, 118.0, 118.0, 118.0, 118.0, 133.0, 174.0, 119.0], "value": 134.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 121.0, 125.0, 182.0, 153.0, 123.0, 129.0, 150.0, 171.0, 155.0], "value": 147.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [279.0, 248.0, 195.0, 200.0, 198.0, 199.0, 201.0, 198.0, 197.0, 194.0], "value": 210.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 52.0, 62.0, 104.0, 87.0, 51.0, 75.0, 83.0, 96.0, 50.0], "value": 77.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 102.0, 97.0, 74.0, 72.0, 71.0, 86.0, 71.0, 73.0, 108.0], "value": 85.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 132.0, 139.0, 172.0, 144.0, 127.0, 123.0, 125.0, 122.0, 118.0], "value": 131.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 154.0, 153.0, 97.0, 97.0, 98.0, 124.0, 153.0, 115.0, 152.0], "value": 125.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 93.0, 103.0, 94.0, 105.0, 149.0, 150.0, 93.0, 94.0], "value": 107.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 58.0, 57.0, 58.0, 60.0, 60.0, 59.0, 60.0, 61.0], "value": 59.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 79.0, 77.0, 75.0, 70.0, 71.0, 74.0, 72.0, 72.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 116.0, 126.0, 111.0, 112.0, 111.0, 81.0, 80.0, 114.0], "value": 101.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 150.0, 178.0, 144.0, 151.0, 169.0, 142.0, 159.0, 216.0, 161.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 183.0, 182.0, 195.0, 232.0, 178.0, 183.0, 180.0, 180.0, 183.0], "value": 187.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 67.0, 70.0, 72.0, 69.0, 77.0, 92.0, 88.0, 92.0], "value": 76.6, "unit": "score"}]}]} 21:17:50 INFO - Return code: 0 21:17:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:17:50 ERROR - # TBPL SUCCESS # 21:17:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:17:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:17:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:17:50 INFO - 2016-12-13 21:17:50.408 screenresolution[1655:10433] starting screenresolution argv=screenresolution get 21:17:50 INFO - 2016-12-13 21:17:50.410 screenresolution[1655:10433] Display 0: 1600x1200x32@60 21:17:50 INFO - 2016-12-13 21:17:50.416 screenresolution[1656:10436] starting screenresolution argv=screenresolution list 21:17:50 INFO - Available Modes on Display 0 21:17:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:17:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:17:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:17:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:17:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:17:50 INFO - Intel Iris: 21:17:50 INFO - Chipset Model: Intel Iris 21:17:50 INFO - Type: GPU 21:17:50 INFO - Bus: Built-In 21:17:50 INFO - VRAM (Dynamic, Max): 1536 MB 21:17:50 INFO - Vendor: Intel (0x8086) 21:17:50 INFO - Device ID: 0x0a2e 21:17:50 INFO - Revision ID: 0x0009 21:17:50 INFO - Displays: 21:17:50 INFO - Display: 21:17:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:17:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:17:50 INFO - Display Serial Number: 1600x1200 60 21:17:50 INFO - Main Display: Yes 21:17:50 INFO - Mirror: Off 21:17:50 INFO - Online: Yes 21:17:50 INFO - Rotation: Supported 21:17:50 INFO - Return code: 0 21:17:50 INFO - Running post-action listener: _resource_record_post_action 21:17:50 INFO - [mozharness: 2016-12-14 05:17:50.651029Z] Finished run-tests step (success) 21:17:50 INFO - Running post-run listener: _resource_record_post_run 21:17:50 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 39639552; Write bytes: 554992128; Read time: 383; Write time: 1745 21:17:50 INFO - TinderboxPrint: CPU usage
54.5% 21:17:50 INFO - TinderboxPrint: I/O read bytes / time
39,639,552 / 383 21:17:50 INFO - TinderboxPrint: I/O write bytes / time
554,992,128 / 1,745 21:17:50 INFO - TinderboxPrint: CPU idle
2,190.5 (45.5%) 21:17:50 INFO - TinderboxPrint: CPU system
971.0 (20.2%) 21:17:50 INFO - TinderboxPrint: CPU user
1,648.1 (34.3%) 21:17:50 INFO - TinderboxPrint: Swap in / out
289,402,880 / 0 21:17:50 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250380800; Write bytes: 250077184; Read time: 18170; Write time: 469 21:17:50 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 35445248; Write bytes: 303419904; Read time: 353; Write time: 1270 21:17:51 INFO - Running post-run listener: _upload_blobber_files 21:17:51 INFO - Blob upload gear active. 21:17:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:17:51 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 21:17:51 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'] 21:17:51 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 21:17:51 INFO - (blobuploader) - INFO - Open directory for files ... 21:17:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:17:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:17:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:17:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:17:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:17:53 INFO - (blobuploader) - INFO - Done attempting. 21:17:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:17:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:17:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:17:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:17:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:17:54 INFO - (blobuploader) - INFO - Done attempting. 21:17:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:17:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:17:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:17:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:17:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:17:56 INFO - (blobuploader) - INFO - Done attempting. 21:17:56 INFO - (blobuploader) - INFO - Iteration through files over. 21:17:56 INFO - Return code: 0 21:17:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:17:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:17:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14b24c0c3ae18bf66f602bed934c51e6fa88fc24b7a055659211ec2e10fbd7d76c36f5087a1a77ae741da4a44b2d631a60209be2c9a3fc6ab1a17881a5796a3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f250a647763df4e904f9d60e41fd9c1a5b03106b356de16cf9d3f1e2b0379a346b16759e3d97a7573808863c1c5b00f8dde162cdbe145a325dd981c402d1a220", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1d4c5f4193e3ce37528c1aec798eb1e2166b3eefba93d41b9ac8a4610a9d871ff633e80ba9de82666931a1dbeb815c71d9e69fceafcc2df790f026d8fcfdcf9"} 21:17:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:17:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:17:56 INFO - Contents: 21:17:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14b24c0c3ae18bf66f602bed934c51e6fa88fc24b7a055659211ec2e10fbd7d76c36f5087a1a77ae741da4a44b2d631a60209be2c9a3fc6ab1a17881a5796a3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f250a647763df4e904f9d60e41fd9c1a5b03106b356de16cf9d3f1e2b0379a346b16759e3d97a7573808863c1c5b00f8dde162cdbe145a325dd981c402d1a220", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1d4c5f4193e3ce37528c1aec798eb1e2166b3eefba93d41b9ac8a4610a9d871ff633e80ba9de82666931a1dbeb815c71d9e69fceafcc2df790f026d8fcfdcf9"} 21:17:56 INFO - Running post-run listener: copy_logs_to_upload_dir 21:17:56 INFO - Copying logs to upload dir... 21:17:56 INFO - mkdir: /builds/slave/test/build/upload/logs 21:17:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.703818 ========= master_lag: 0.71 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-12-13 21:17:56.855383) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-13 21:17:56.859589) ========= 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.RpoJ1tLUAs/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.7TwAStV23j/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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/14b24c0c3ae18bf66f602bed934c51e6fa88fc24b7a055659211ec2e10fbd7d76c36f5087a1a77ae741da4a44b2d631a60209be2c9a3fc6ab1a17881a5796a3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f250a647763df4e904f9d60e41fd9c1a5b03106b356de16cf9d3f1e2b0379a346b16759e3d97a7573808863c1c5b00f8dde162cdbe145a325dd981c402d1a220", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1d4c5f4193e3ce37528c1aec798eb1e2166b3eefba93d41b9ac8a4610a9d871ff633e80ba9de82666931a1dbeb815c71d9e69fceafcc2df790f026d8fcfdcf9"} build_url:https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011074 build_url: 'https://queue.taskcluster.net/v1/task/SGJxvOrkRPyQeJdtWUA1Xw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14b24c0c3ae18bf66f602bed934c51e6fa88fc24b7a055659211ec2e10fbd7d76c36f5087a1a77ae741da4a44b2d631a60209be2c9a3fc6ab1a17881a5796a3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f250a647763df4e904f9d60e41fd9c1a5b03106b356de16cf9d3f1e2b0379a346b16759e3d97a7573808863c1c5b00f8dde162cdbe145a325dd981c402d1a220", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1d4c5f4193e3ce37528c1aec798eb1e2166b3eefba93d41b9ac8a4610a9d871ff633e80ba9de82666931a1dbeb815c71d9e69fceafcc2df790f026d8fcfdcf9"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-13 21:17:56.895679) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 21:17:56.896037) ========= 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.RpoJ1tLUAs/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.7TwAStV23j/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.005030 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 21:17:56.945137) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-13 21:17:56.945510) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-13 21:17:56.945926) ========= ========= Total master_lag: 0.85 =========