builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0094 starttime: 1491475933.51 results: success (0) buildid: 20170406021021 builduid: 229d938eed51444fa5cb385fa4494ac2 revision: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:13.506270) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:13.506713) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:13.507005) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:13.533656) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:13.534055) ========= 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.PZtcC0vGmQ/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.b43D8BbqoA/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-06 03:52:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.1M=0.001s 2017-04-06 03:52:13 (14.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145768 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:13.704034) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:13.704353) ========= 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.PZtcC0vGmQ/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.b43D8BbqoA/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.028664 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:13.755559) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-06 03:52:13.755921) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PZtcC0vGmQ/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.b43D8BbqoA/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-06 03:52:13,866 truncating revision to first 12 chars 2017-04-06 03:52:13,866 Setting DEBUG logging. 2017-04-06 03:52:13,866 attempt 1/10 2017-04-06 03:52:13,866 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/632a3c9e2b4b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 03:52:15,588 unpacking tar archive at: mozilla-aurora-632a3c9e2b4b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.994610 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-06 03:52:15.778771) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:15.779280) ========= script_repo_revision: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:15.779777) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:15.780097) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:15.800706) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-06 03:52:15.801431) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PZtcC0vGmQ/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.b43D8BbqoA/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:52:16 INFO - MultiFileLogger online at 20170406 03:52:16 in /builds/slave/test 03:52:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:52:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:52:16 INFO - {'append_to_log': False, 03:52:16 INFO - 'base_work_dir': '/builds/slave/test', 03:52:16 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:52:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:52:16 INFO - 'branch': 'Mozilla-Aurora', 03:52:16 INFO - 'buildbot_json_path': 'buildprops.json', 03:52:16 INFO - 'config_files': ('talos/mac_config.py',), 03:52:16 INFO - 'default_actions': ('clobber', 03:52:16 INFO - 'read-buildbot-config', 03:52:16 INFO - 'download-and-extract', 03:52:16 INFO - 'populate-webroot', 03:52:16 INFO - 'create-virtualenv', 03:52:16 INFO - 'install', 03:52:16 INFO - 'run-tests'), 03:52:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:52:16 INFO - 'download_minidump_stackwalk': True, 03:52:16 INFO - 'download_symbols': 'ondemand', 03:52:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:52:16 INFO - 'tooltool.py': '/tools/tooltool.py', 03:52:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:52:16 INFO - '/tools/misc-python/virtualenv.py')}, 03:52:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:52:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:52:16 INFO - 'gecko_profile': False, 03:52:16 INFO - 'gecko_profile_interval': 0, 03:52:16 INFO - 'installer_path': 'installer.exe', 03:52:16 INFO - 'log_level': 'info', 03:52:16 INFO - 'log_name': 'talos', 03:52:16 INFO - 'log_to_console': True, 03:52:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:52:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:52:16 INFO - 'opt_config_files': (), 03:52:16 INFO - 'pip_index': False, 03:52:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:52:16 INFO - 'cmd': ('bash', 03:52:16 INFO - '-c', 03:52:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:52:16 INFO - 'enabled': True, 03:52:16 INFO - 'halt_on_failure': False, 03:52:16 INFO - 'name': 'check_screen_resolution'},), 03:52:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:52:16 INFO - 'cmd': ('bash', 03:52:16 INFO - '-c', 03:52:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:52:16 INFO - 'enabled': True, 03:52:16 INFO - 'halt_on_failure': False, 03:52:16 INFO - 'name': 'check_screen_resolution'},), 03:52:16 INFO - 'run_cmd_checks_enabled': True, 03:52:16 INFO - 'suite': 'dromaeojs', 03:52:16 INFO - 'system_bits': '32', 03:52:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:52:16 INFO - 'title': 't-yosemite-r7-0094', 03:52:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:52:16 INFO - 'use_talos_json': True, 03:52:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:52:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:52:16 INFO - 'work_dir': 'build'} 03:52:16 INFO - [mozharness: 2017-04-06 10:52:16.022361Z] Running clobber step. 03:52:16 INFO - Running pre-action listener: _resource_record_pre_action 03:52:16 INFO - Running main action method: clobber 03:52:16 INFO - rmtree: /builds/slave/test/build 03:52:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:52:18 INFO - Running post-action listener: _resource_record_post_action 03:52:18 INFO - [mozharness: 2017-04-06 10:52:18.517870Z] Finished clobber step (success) 03:52:18 INFO - [mozharness: 2017-04-06 10:52:18.518036Z] Running read-buildbot-config step. 03:52:18 INFO - Running pre-action listener: _resource_record_pre_action 03:52:18 INFO - Running main action method: read_buildbot_config 03:52:18 INFO - Using buildbot properties: 03:52:18 INFO - { 03:52:18 INFO - "project": "", 03:52:18 INFO - "product": "firefox", 03:52:18 INFO - "script_repo_revision": "production", 03:52:18 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:52:18 INFO - "repository": "", 03:52:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:52:18 INFO - "buildid": "20170406021021", 03:52:18 INFO - "pgo_build": "False", 03:52:18 INFO - "basedir": "/builds/slave/test", 03:52:18 INFO - "buildnumber": 11, 03:52:18 INFO - "slavename": "t-yosemite-r7-0094", 03:52:18 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:52:18 INFO - "platform": "yosemite_r7", 03:52:18 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:52:18 INFO - "branch": "mozilla-aurora", 03:52:18 INFO - "repo_path": "releases/mozilla-aurora", 03:52:18 INFO - "revision": "632a3c9e2b4b0dda89ca5924c616568af8c0dd85", 03:52:18 INFO - "stage_platform": "macosx64", 03:52:18 INFO - "builduid": "229d938eed51444fa5cb385fa4494ac2", 03:52:18 INFO - "slavebuilddir": "test" 03:52:18 INFO - } 03:52:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 03:52:18 INFO - Running post-action listener: _resource_record_post_action 03:52:18 INFO - [mozharness: 2017-04-06 10:52:18.521303Z] Finished read-buildbot-config step (success) 03:52:18 INFO - [mozharness: 2017-04-06 10:52:18.521454Z] Running download-and-extract step. 03:52:18 INFO - Running pre-action listener: _resource_record_pre_action 03:52:18 INFO - Running main action method: download_and_extract 03:52:18 INFO - mkdir: /builds/slave/test/build/tests 03:52: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')]} 03:52:18 INFO - trying https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 03:52:18 INFO - Downloading https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 03:52:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 03:52:19 INFO - Downloaded 1199 bytes. 03:52:19 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 03:52:19 INFO - Using the following test package requirements: 03:52:19 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 03:52:19 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:19 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 03:52:19 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:19 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 03:52:19 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:19 INFO - u'jsshell-mac.zip'], 03:52:19 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:19 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 03:52:19 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 03:52:19 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:19 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 03:52:19 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:19 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 03:52:19 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:19 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 03:52:19 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:19 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 03:52:19 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:52:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 03:52:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:52:19 INFO - Fetch https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 03:52:21 INFO - Expected file size: 51792994 03:52:21 INFO - Obtained file size: 51792994 03:52:37 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:52:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 03:52:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:52:37 INFO - Fetch https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 03:52:39 INFO - Expected file size: 51792994 03:52:39 INFO - Obtained file size: 51792994 03:52:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 03:52:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:52:54 INFO - Fetch https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 03:52:55 INFO - Expected file size: 14404438 03:52:55 INFO - Obtained file size: 14404438 03:52:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:52:55 INFO - trying https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 03:52:55 INFO - Downloading https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 03:52:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 03:52:58 INFO - Downloaded 74672956 bytes. 03:52:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 03:52:58 INFO - mkdir: /builds/slave/test/properties 03:52:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:52:58 INFO - Writing to file /builds/slave/test/properties/build_url 03:52:58 INFO - Contents: 03:52:58 INFO - build_url:https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 03:52:58 INFO - Running post-action listener: _resource_record_post_action 03:52:58 INFO - Running post-action listener: set_extra_try_arguments 03:52:58 INFO - [mozharness: 2017-04-06 10:52:58.748105Z] Finished download-and-extract step (success) 03:52:58 INFO - [mozharness: 2017-04-06 10:52:58.748227Z] Running populate-webroot step. 03:52:58 INFO - Running pre-action listener: _resource_record_pre_action 03:52:58 INFO - Running main action method: populate_webroot 03:52:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:52:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:52:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:52:58 INFO - '--tppagecycles', 03:52:58 INFO - '7'], 03:52:58 INFO - 'tests': ['tsvgm']}}, 03:52:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:52:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:52:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:52:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:52:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:52:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:52:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:52:58 INFO - 'talos_options': ['--disable-e10s'], 03:52:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:52:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:52:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:52:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:52:58 INFO - 'talos_options': ['--disable-e10s'], 03:52:58 INFO - 'tests': ['damp', 'tps']}, 03:52:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:52:58 INFO - 'tests': ['damp', 'tps']}, 03:52:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:52:58 INFO - 'tests': ['dromaeo_dom']}, 03:52:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:52:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:52:58 INFO - 'tests': ['basic_compositor_video']}, 03:52:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:52:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:52:58 INFO - 'tests': ['a11yr', 03:52:58 INFO - 'ts_paint', 03:52:58 INFO - 'tpaint', 03:52:58 INFO - 'sessionrestore', 03:52:58 INFO - 'sessionrestore_no_auto_restore', 03:52:58 INFO - 'tabpaint']}, 03:52:58 INFO - 'other-e10s': {'tests': ['a11yr', 03:52:58 INFO - 'ts_paint', 03:52:58 INFO - 'tpaint', 03:52:58 INFO - 'sessionrestore', 03:52:58 INFO - 'sessionrestore_no_auto_restore', 03:52:58 INFO - 'tabpaint']}, 03:52:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:52:58 INFO - 'ts_paint', 03:52:58 INFO - 'tpaint', 03:52:58 INFO - 'sessionrestore', 03:52:58 INFO - 'sessionrestore_no_auto_restore', 03:52:58 INFO - 'tabpaint']}, 03:52:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:52:58 INFO - 'ts_paint', 03:52:58 INFO - 'tpaint', 03:52:58 INFO - 'sessionrestore', 03:52:58 INFO - 'sessionrestore_no_auto_restore', 03:52:58 INFO - 'tabpaint']}, 03:52:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:52:58 INFO - 'tests': ['a11yr', 03:52:58 INFO - 'ts_paint', 03:52:58 INFO - 'tpaint', 03:52:58 INFO - 'sessionrestore', 03:52:58 INFO - 'sessionrestore_no_auto_restore', 03:52:58 INFO - 'tabpaint']}, 03:52:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:52:58 INFO - 'tests': ['a11yr', 03:52:58 INFO - 'ts_paint', 03:52:58 INFO - 'tpaint', 03:52:58 INFO - 'sessionrestore', 03:52:58 INFO - 'sessionrestore_no_auto_restore', 03:52:58 INFO - 'tabpaint']}, 03:52:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:52:58 INFO - 'tests': ['tsvgx', 03:52:58 INFO - 'tsvgr_opacity', 03:52:58 INFO - 'tart', 03:52:58 INFO - 'tscrollx', 03:52:58 INFO - 'cart', 03:52:58 INFO - 'tsvg_static']}, 03:52:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:52:58 INFO - 'tsvgr_opacity', 03:52:58 INFO - 'tart', 03:52:58 INFO - 'tscrollx', 03:52:58 INFO - 'cart', 03:52:58 INFO - 'tsvg_static']}, 03:52:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:52:58 INFO - 'talos_options': ['--disable-e10s'], 03:52:58 INFO - 'tests': ['tp5o']}, 03:52:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:52:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:52:58 INFO - 'talos_options': ['--disable-e10s', 03:52:58 INFO - '--xperf_path', 03:52:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:52:58 INFO - 'tests': ['tp5n']}, 03:52:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:52:58 INFO - 'talos_options': ['--xperf_path', 03:52:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:52:58 INFO - 'tests': ['tp5n']}}, 03:52:58 INFO - 'talos.zip': {'path': '', 03:52:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:52:58 INFO - Running post-action listener: _resource_record_post_action 03:52:58 INFO - [mozharness: 2017-04-06 10:52:58.766889Z] Finished populate-webroot step (success) 03:52:58 INFO - [mozharness: 2017-04-06 10:52:58.767031Z] Running create-virtualenv step. 03:52:58 INFO - Running pre-action listener: _resource_record_pre_action 03:52:58 INFO - Running main action method: create_virtualenv 03:52:58 INFO - Creating virtualenv /builds/slave/test/build/venv 03:52:58 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 03:52:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:52:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:52:58 INFO - Using real prefix '/tools/python27' 03:52:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:52:59 INFO - Installing distribute.............................................................................................................................................................................................done. 03:53:02 INFO - Installing pip.................done. 03:53:02 INFO - Return code: 0 03:53:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:53:02 INFO - Reading from file tmpfile_stdout 03:53:02 INFO - Output received: 03:53:02 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) 03:53:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:53:02 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')]} 03:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:02 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')]} 03:53:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:02 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 0x108a45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc63ca6c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108823d30>, '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 0x10874adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108818be8>, '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.PZtcC0vGmQ/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.b43D8BbqoA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:53:02 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 03:53:02 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 03:53:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PZtcC0vGmQ/Render', 03:53:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:02 INFO - 'HOME': '/Users/cltbld', 03:53:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:02 INFO - 'LOGNAME': 'cltbld', 03:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:02 INFO - 'MOZ_NO_REMOTE': '1', 03:53:02 INFO - 'NO_EM_RESTART': '1', 03:53:02 INFO - 'PAGER': '/bin/cat', 03:53:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:02 INFO - 'PWD': '/builds/slave/test', 03:53:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:02 INFO - 'SHELL': '/bin/bash', 03:53:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b43D8BbqoA/Listeners', 03:53:02 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:53:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:02 INFO - 'USER': 'cltbld', 03:53:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:02 INFO - 'XPC_FLAGS': '0x0', 03:53:02 INFO - 'XPC_SERVICE_NAME': '0', 03:53:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:03 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 03:53:03 INFO - Cleaning up... 03:53:03 INFO - Return code: 0 03:53:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:03 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 0x108a45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc63ca6c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108823d30>, '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 0x10874adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108818be8>, '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.PZtcC0vGmQ/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.b43D8BbqoA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:53:03 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 03:53:03 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 03:53:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PZtcC0vGmQ/Render', 03:53:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:03 INFO - 'HOME': '/Users/cltbld', 03:53:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:03 INFO - 'LOGNAME': 'cltbld', 03:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:03 INFO - 'MOZ_NO_REMOTE': '1', 03:53:03 INFO - 'NO_EM_RESTART': '1', 03:53:03 INFO - 'PAGER': '/bin/cat', 03:53:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:03 INFO - 'PWD': '/builds/slave/test', 03:53:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:03 INFO - 'SHELL': '/bin/bash', 03:53:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b43D8BbqoA/Listeners', 03:53:03 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:53:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:03 INFO - 'USER': 'cltbld', 03:53:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:03 INFO - 'XPC_FLAGS': '0x0', 03:53:03 INFO - 'XPC_SERVICE_NAME': '0', 03:53:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:03 INFO - Downloading/unpacking psutil>=3.1.1 03:53:03 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 03:53:03 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 03:53:03 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 03:53:03 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 03:53:03 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 03:53:03 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 03:53:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:53:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:53:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:53:06 INFO - Installing collected packages: psutil 03:53:06 INFO - Running setup.py install for psutil 03:53:06 INFO - building 'psutil._psutil_osx' extension 03:53:06 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 03:53:07 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 03:53:07 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 03:53:07 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 03:53:07 INFO - building 'psutil._psutil_posix' extension 03:53:07 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 03:53:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:53:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:53:07 INFO - ^ 03:53:07 INFO - 1 warning generated. 03:53:07 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 03:53:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:53:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:53:08 INFO - Successfully installed psutil 03:53:08 INFO - Cleaning up... 03:53:08 INFO - Return code: 0 03:53:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:53:08 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')]} 03:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:08 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')]} 03:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:08 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 0x108a45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc63ca6c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108823d30>, '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 0x10874adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108818be8>, '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.PZtcC0vGmQ/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.b43D8BbqoA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:53:08 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 03:53:08 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 03:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PZtcC0vGmQ/Render', 03:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:08 INFO - 'HOME': '/Users/cltbld', 03:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:08 INFO - 'LOGNAME': 'cltbld', 03:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:08 INFO - 'MOZ_NO_REMOTE': '1', 03:53:08 INFO - 'NO_EM_RESTART': '1', 03:53:08 INFO - 'PAGER': '/bin/cat', 03:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:08 INFO - 'PWD': '/builds/slave/test', 03:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:08 INFO - 'SHELL': '/bin/bash', 03:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b43D8BbqoA/Listeners', 03:53:08 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:08 INFO - 'USER': 'cltbld', 03:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:08 INFO - 'XPC_FLAGS': '0x0', 03:53:08 INFO - 'XPC_SERVICE_NAME': '0', 03:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:53:08 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 03:53:08 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 03:53:08 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 03:53:08 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 03:53:08 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 03:53:08 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 03:53:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:53:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:53:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:53:12 INFO - Installing collected packages: mozsystemmonitor 03:53:12 INFO - Running setup.py install for mozsystemmonitor 03:53:12 INFO - Successfully installed mozsystemmonitor 03:53:12 INFO - Cleaning up... 03:53:12 INFO - Return code: 0 03:53:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:53:12 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')]} 03:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:12 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')]} 03:53:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:13 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 0x108a45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc63ca6c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108823d30>, '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 0x10874adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108818be8>, '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.PZtcC0vGmQ/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.b43D8BbqoA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:53:13 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 03:53:13 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 03:53:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PZtcC0vGmQ/Render', 03:53:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:13 INFO - 'HOME': '/Users/cltbld', 03:53:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:13 INFO - 'LOGNAME': 'cltbld', 03:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:13 INFO - 'MOZ_NO_REMOTE': '1', 03:53:13 INFO - 'NO_EM_RESTART': '1', 03:53:13 INFO - 'PAGER': '/bin/cat', 03:53:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:13 INFO - 'PWD': '/builds/slave/test', 03:53:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:13 INFO - 'SHELL': '/bin/bash', 03:53:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b43D8BbqoA/Listeners', 03:53:13 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:53:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:13 INFO - 'USER': 'cltbld', 03:53:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:13 INFO - 'XPC_FLAGS': '0x0', 03:53:13 INFO - 'XPC_SERVICE_NAME': '0', 03:53:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:13 INFO - Downloading/unpacking jsonschema==2.5.1 03:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:53:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:53:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:53:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:53:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:53:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:53:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:53:16 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 03:53:16 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 03:53:16 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 03:53:16 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 03:53:16 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 03:53:16 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 03:53:16 INFO - Downloading functools32-3.2.3-2.tar.gz 03:53:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:53:16 INFO - warning: no files found matching '*.txt' 03:53:16 INFO - no previously-included directories found matching 'build' 03:53:16 INFO - no previously-included directories found matching 'dist' 03:53:16 INFO - no previously-included directories found matching '.git*' 03:53:16 INFO - Installing collected packages: jsonschema, functools32 03:53:16 INFO - Running setup.py install for functools32 03:53:16 INFO - warning: no files found matching '*.txt' 03:53:16 INFO - no previously-included directories found matching 'build' 03:53:16 INFO - no previously-included directories found matching 'dist' 03:53:16 INFO - no previously-included directories found matching '.git*' 03:53:17 INFO - Successfully installed jsonschema functools32 03:53:17 INFO - Cleaning up... 03:53:17 INFO - Return code: 0 03:53:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:53:17 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')]} 03:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:17 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')]} 03:53:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:17 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 0x108a45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc63ca6c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108823d30>, '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 0x10874adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108818be8>, '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.PZtcC0vGmQ/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.b43D8BbqoA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:53:17 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 03:53:17 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 03:53:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PZtcC0vGmQ/Render', 03:53:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:17 INFO - 'HOME': '/Users/cltbld', 03:53:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:17 INFO - 'LOGNAME': 'cltbld', 03:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:17 INFO - 'MOZ_NO_REMOTE': '1', 03:53:17 INFO - 'NO_EM_RESTART': '1', 03:53:17 INFO - 'PAGER': '/bin/cat', 03:53:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:17 INFO - 'PWD': '/builds/slave/test', 03:53:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:17 INFO - 'SHELL': '/bin/bash', 03:53:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b43D8BbqoA/Listeners', 03:53:17 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:53:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:17 INFO - 'USER': 'cltbld', 03:53:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:17 INFO - 'XPC_FLAGS': '0x0', 03:53:17 INFO - 'XPC_SERVICE_NAME': '0', 03:53:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:53:17 INFO - Cleaning up... 03:53:17 INFO - Return code: 0 03:53:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:53:17 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')]} 03:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:17 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')]} 03:53:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:17 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 0x108a45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc63ca6c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108823d30>, '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 0x10874adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108818be8>, '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.PZtcC0vGmQ/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.b43D8BbqoA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:53:17 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 03:53:17 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 03:53:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PZtcC0vGmQ/Render', 03:53:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:17 INFO - 'HOME': '/Users/cltbld', 03:53:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:17 INFO - 'LOGNAME': 'cltbld', 03:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:17 INFO - 'MOZ_NO_REMOTE': '1', 03:53:17 INFO - 'NO_EM_RESTART': '1', 03:53:17 INFO - 'PAGER': '/bin/cat', 03:53:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:17 INFO - 'PWD': '/builds/slave/test', 03:53:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:17 INFO - 'SHELL': '/bin/bash', 03:53:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b43D8BbqoA/Listeners', 03:53:17 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:53:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:17 INFO - 'USER': 'cltbld', 03:53:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:17 INFO - 'XPC_FLAGS': '0x0', 03:53:17 INFO - 'XPC_SERVICE_NAME': '0', 03:53:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:17 INFO - Downloading/unpacking blobuploader==1.2.4 03:53:17 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 03:53:17 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 03:53:17 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 03:53:17 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 03:53:17 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 03:53:17 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 03:53:20 INFO - Downloading blobuploader-1.2.4.tar.gz 03:53:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:53:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:53:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:53:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:53:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:53:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:53:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:53:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:53:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:53:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:53:21 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 03:53:21 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 03:53:21 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 03:53:21 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 03:53:21 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 03:53:21 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 03:53:21 INFO - Downloading docopt-0.6.1.tar.gz 03:53:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:53:21 INFO - Installing collected packages: blobuploader, requests, docopt 03:53:21 INFO - Running setup.py install for blobuploader 03:53:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:53:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:53:21 INFO - Running setup.py install for requests 03:53:22 INFO - Running setup.py install for docopt 03:53:22 INFO - Successfully installed blobuploader requests docopt 03:53:22 INFO - Cleaning up... 03:53:22 INFO - Return code: 0 03:53:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:53:22 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')]} 03:53:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:22 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')]} 03:53:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:22 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 0x108a45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc63ca6c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108823d30>, '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 0x10874adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108818be8>, '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.PZtcC0vGmQ/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.b43D8BbqoA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:53:22 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 03:53:22 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 03:53:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PZtcC0vGmQ/Render', 03:53:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:22 INFO - 'HOME': '/Users/cltbld', 03:53:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:22 INFO - 'LOGNAME': 'cltbld', 03:53:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:22 INFO - 'MOZ_NO_REMOTE': '1', 03:53:22 INFO - 'NO_EM_RESTART': '1', 03:53:22 INFO - 'PAGER': '/bin/cat', 03:53:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:22 INFO - 'PWD': '/builds/slave/test', 03:53:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:22 INFO - 'SHELL': '/bin/bash', 03:53:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b43D8BbqoA/Listeners', 03:53:22 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:53:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:22 INFO - 'USER': 'cltbld', 03:53:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:22 INFO - 'XPC_FLAGS': '0x0', 03:53:22 INFO - 'XPC_SERVICE_NAME': '0', 03:53:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:53:22 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-8rjeLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:53:22 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-dORaEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:53:22 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-CrtO5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:53:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-c6OZY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:53:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-roTy8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:53:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-ms35hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:53:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-swqIuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:53:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Sz8Q1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:53:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-hsnj1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:53:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-fw0Ccp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:53:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-uazd3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:53:23 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-jVStsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:53:24 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-horpju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:53:24 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-IKmOxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:53:24 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-dgaENE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:53:24 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-0x7h1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:53:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:53:24 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-eHPwAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:53:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:53:24 INFO - Running setup.py install for manifestparser 03:53:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:53:24 INFO - Running setup.py install for mozcrash 03:53:24 INFO - Running setup.py install for mozdebug 03:53:24 INFO - Running setup.py install for mozdevice 03:53:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:53:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:53:25 INFO - Running setup.py install for mozfile 03:53:25 INFO - Running setup.py install for mozhttpd 03:53:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:53:25 INFO - Running setup.py install for mozinfo 03:53:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:53:25 INFO - Running setup.py install for mozInstall 03:53:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:53:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:53:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:53:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:53:25 INFO - Running setup.py install for mozleak 03:53:25 INFO - Running setup.py install for mozlog 03:53:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:53:26 INFO - Running setup.py install for moznetwork 03:53:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:53:26 INFO - Running setup.py install for mozprocess 03:53:26 INFO - Running setup.py install for mozprofile 03:53:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:53:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:53:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:53:26 INFO - Running setup.py install for mozrunner 03:53:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:53:26 INFO - Running setup.py install for mozscreenshot 03:53:26 INFO - Running setup.py install for moztest 03:53:26 INFO - Running setup.py install for mozversion 03:53:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:53:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:53:27 INFO - Cleaning up... 03:53:27 INFO - Return code: 0 03:53:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:53:27 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')]} 03:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:27 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')]} 03:53:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:27 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 0x108a45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc63ca6c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108823d30>, '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 0x10874adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108818be8>, '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.PZtcC0vGmQ/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.b43D8BbqoA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:53:27 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 03:53:27 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 03:53:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PZtcC0vGmQ/Render', 03:53:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:27 INFO - 'HOME': '/Users/cltbld', 03:53:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:27 INFO - 'LOGNAME': 'cltbld', 03:53:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:27 INFO - 'MOZ_NO_REMOTE': '1', 03:53:27 INFO - 'NO_EM_RESTART': '1', 03:53:27 INFO - 'PAGER': '/bin/cat', 03:53:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:27 INFO - 'PWD': '/builds/slave/test', 03:53:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:27 INFO - 'SHELL': '/bin/bash', 03:53:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b43D8BbqoA/Listeners', 03:53:27 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:53:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:27 INFO - 'USER': 'cltbld', 03:53:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:27 INFO - 'XPC_FLAGS': '0x0', 03:53:27 INFO - 'XPC_SERVICE_NAME': '0', 03:53:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:53:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-y8NV4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:53:27 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)) 03:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:53:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-HwGUUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:53:27 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)) 03:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:53:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-H7T926-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:53:27 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)) 03:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:53:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Z33hCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:53:27 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)) 03:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:53:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-ecQrW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:53:27 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)) 03:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:53:27 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-k8msi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:53:28 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)) 03:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:53:28 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-bOmiBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:53:28 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)) 03:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:53:28 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-2rOza5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:53:28 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)) 03:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:53:28 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-CkgdMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:53:28 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)) 03:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:53:28 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-gaWKyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:53:28 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)) 03:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:53:28 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-KEvJ2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:53:28 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)) 03:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:53:28 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Pe1N9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:53:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:53:28 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-2Zu5aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:53:28 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)) 03:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:53:28 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-eKLuQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:53:28 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)) 03:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:53:28 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-T0S12B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:53:29 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)) 03:53:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:53:29 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-LYVyj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:53:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:53:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:53:29 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-HxmD_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:53:29 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)) 03:53:29 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)) 03:53:29 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)) 03:53:29 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)) 03:53:29 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)) 03:53:29 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)) 03:53:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:53: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 03:53: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 03:53: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 03:53: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 03:53: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 03:53: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 03:53:31 INFO - Downloading blessings-1.6.tar.gz 03:53:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:53:32 INFO - Installing collected packages: blessings 03:53:32 INFO - Running setup.py install for blessings 03:53:32 INFO - Successfully installed blessings 03:53:32 INFO - Cleaning up... 03:53:32 INFO - Return code: 0 03:53:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:53:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:53:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:53:32 INFO - Reading from file tmpfile_stdout 03:53:32 INFO - Current package versions: 03:53:32 INFO - blessings == 1.6 03:53:32 INFO - blobuploader == 1.2.4 03:53:32 INFO - docopt == 0.6.1 03:53:32 INFO - functools32 == 3.2.3-2 03:53:32 INFO - jsonschema == 2.5.1 03:53:32 INFO - manifestparser == 1.1 03:53:32 INFO - mozInstall == 1.12 03:53:32 INFO - mozcrash == 1.0 03:53:32 INFO - mozdebug == 0.1 03:53:32 INFO - mozdevice == 0.49 03:53:32 INFO - mozfile == 1.2 03:53:32 INFO - mozhttpd == 0.7 03:53:32 INFO - mozinfo == 0.9 03:53:32 INFO - mozleak == 0.1 03:53:32 INFO - mozlog == 3.4 03:53:32 INFO - moznetwork == 0.27 03:53:32 INFO - mozprocess == 0.25 03:53:32 INFO - mozprofile == 0.28 03:53:32 INFO - mozrunner == 6.13 03:53:32 INFO - mozscreenshot == 0.1 03:53:32 INFO - mozsystemmonitor == 0.3 03:53:32 INFO - moztest == 0.8 03:53:32 INFO - mozversion == 1.4 03:53:32 INFO - psutil == 3.1.1 03:53:32 INFO - requests == 1.2.3 03:53:32 INFO - wsgiref == 0.1.2 03:53:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:32 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 0x108a45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc63ca6c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108823d30>, '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 0x10874adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108818be8>, '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.PZtcC0vGmQ/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.b43D8BbqoA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:53:32 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 03:53:32 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 03:53:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PZtcC0vGmQ/Render', 03:53:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:32 INFO - 'HOME': '/Users/cltbld', 03:53:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:32 INFO - 'LOGNAME': 'cltbld', 03:53:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:32 INFO - 'MOZ_NO_REMOTE': '1', 03:53:32 INFO - 'NO_EM_RESTART': '1', 03:53:32 INFO - 'PAGER': '/bin/cat', 03:53:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:32 INFO - 'PWD': '/builds/slave/test', 03:53:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:32 INFO - 'SHELL': '/bin/bash', 03:53:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b43D8BbqoA/Listeners', 03:53:32 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:53:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:32 INFO - 'USER': 'cltbld', 03:53:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:32 INFO - 'XPC_FLAGS': '0x0', 03:53:32 INFO - 'XPC_SERVICE_NAME': '0', 03:53:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:32 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)) 03:53:32 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)) 03:53:32 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)) 03:53:32 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)) 03:53:32 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)) 03:53:32 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)) 03:53:32 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)) 03:53:32 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)) 03:53:32 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)) 03:53:32 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)) 03:53:32 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)) 03:53:32 INFO - Cleaning up... 03:53:32 INFO - Return code: 0 03:53:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a45b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc63ca6c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108823d30>, '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 0x10874adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108818be8>, '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.PZtcC0vGmQ/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.b43D8BbqoA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 03:53:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:53:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:53:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PZtcC0vGmQ/Render', 03:53:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:32 INFO - 'HOME': '/Users/cltbld', 03:53:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:32 INFO - 'LOGNAME': 'cltbld', 03:53:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:32 INFO - 'MOZ_NO_REMOTE': '1', 03:53:32 INFO - 'NO_EM_RESTART': '1', 03:53:32 INFO - 'PAGER': '/bin/cat', 03:53:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:32 INFO - 'PWD': '/builds/slave/test', 03:53:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:32 INFO - 'SHELL': '/bin/bash', 03:53:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b43D8BbqoA/Listeners', 03:53:32 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:53:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:32 INFO - 'USER': 'cltbld', 03:53:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:32 INFO - 'XPC_FLAGS': '0x0', 03:53:32 INFO - 'XPC_SERVICE_NAME': '0', 03:53:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:53:33 INFO - Cleaning up... 03:53:33 INFO - Return code: 0 03:53:33 INFO - Running post-action listener: _resource_record_post_action 03:53:33 INFO - Running post-action listener: _start_resource_monitoring 03:53:33 INFO - Starting resource monitoring. 03:53:33 INFO - [mozharness: 2017-04-06 10:53:33.145463Z] Finished create-virtualenv step (success) 03:53:33 INFO - [mozharness: 2017-04-06 10:53:33.146499Z] Running install step. 03:53:33 INFO - Running pre-action listener: _resource_record_pre_action 03:53:33 INFO - Running main action method: install 03:53:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:53:33 INFO - Reading from file tmpfile_stdout 03:53:33 INFO - Detecting whether we're running mozinstall >=1.0... 03:53:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:53:33 INFO - Reading from file tmpfile_stdout 03:53:33 INFO - Output received: 03:53:33 INFO - Usage: mozinstall [options] installer 03:53:33 INFO - Options: 03:53:33 INFO - -h, --help show this help message and exit 03:53:33 INFO - -d DEST, --destination=DEST 03:53:33 INFO - Directory to install application into. [default: 03:53:33 INFO - "/builds/slave/test"] 03:53:33 INFO - --app=APP Application being installed. [default: firefox] 03:53:33 INFO - mkdir: /builds/slave/test/build/application 03:53:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:53:53 INFO - Reading from file tmpfile_stdout 03:53:53 INFO - Output received: 03:53:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:53:53 INFO - Running post-action listener: _resource_record_post_action 03:53:53 INFO - [mozharness: 2017-04-06 10:53:53.285078Z] Finished install step (success) 03:53:53 INFO - [mozharness: 2017-04-06 10:53:53.285194Z] Running run-tests step. 03:53:53 INFO - Running pre-action listener: _resource_record_pre_action 03:53:53 INFO - Running main action method: run_tests 03:53:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:53:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:53:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:53:53 INFO - 2017-04-06 03:53:53.299 screenresolution[1625:7687] starting screenresolution argv=screenresolution get 03:53:53 INFO - 2017-04-06 03:53:53.302 screenresolution[1625:7687] Display 0: 1600x1200x32@60 03:53:53 INFO - 2017-04-06 03:53:53.308 screenresolution[1626:7690] starting screenresolution argv=screenresolution list 03:53:53 INFO - Available Modes on Display 0 03:53:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:53:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:53:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:53:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:53:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:53:53 INFO - Intel Iris: 03:53:53 INFO - Chipset Model: Intel Iris 03:53:53 INFO - Type: GPU 03:53:53 INFO - Bus: Built-In 03:53:53 INFO - VRAM (Dynamic, Max): 1536 MB 03:53:53 INFO - Vendor: Intel (0x8086) 03:53:53 INFO - Device ID: 0x0a2e 03:53:53 INFO - Revision ID: 0x0009 03:53:53 INFO - Displays: 03:53:53 INFO - Display: 03:53:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:53:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:53:53 INFO - Display Serial Number: 1600x1200 60 03:53:53 INFO - Main Display: Yes 03:53:53 INFO - Mirror: Off 03:53:53 INFO - Online: Yes 03:53:53 INFO - Rotation: Supported 03:53:53 INFO - Return code: 0 03:53:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:53:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:53:53 INFO - Python 2.7.3 03:53:53 INFO - Return code: 0 03:53:53 INFO - grabbing minidump binary from tooltool 03:53:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:53 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 0x108823d30>, '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 0x10874adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108818be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:53:53 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 03:53:53 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 03:53:53 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 03:53:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:53:53 INFO - Return code: 0 03:53:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:53:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:53:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:53:53 INFO - ENV: RUST_BACKTRACE is now 1 03:53:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:53:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:53:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:53:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0094 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:53:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PZtcC0vGmQ/Render', 03:53:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:53 INFO - 'HOME': '/Users/cltbld', 03:53:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:53 INFO - 'LOGNAME': 'cltbld', 03:53:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:53:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:53:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:53 INFO - 'MOZ_NO_REMOTE': '1', 03:53:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:53:53 INFO - 'NO_EM_RESTART': '1', 03:53:53 INFO - 'PAGER': '/bin/cat', 03:53:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:53 INFO - 'PWD': '/builds/slave/test', 03:53:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:53:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:53 INFO - 'RUST_BACKTRACE': '1', 03:53:53 INFO - 'SHELL': '/bin/bash', 03:53:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b43D8BbqoA/Listeners', 03:53:53 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 03:53:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:53 INFO - 'USER': 'cltbld', 03:53:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:53 INFO - 'XPC_FLAGS': '0x0', 03:53:53 INFO - 'XPC_SERVICE_NAME': '0', 03:53:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:53:53 INFO - mozversion application_buildid: 20170406021021 03:53:53 INFO - mozversion application_changeset: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 03:53:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:53:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:53:53 INFO - mozversion application_name: Firefox 03:53:53 INFO - mozversion application_remotingname: firefox-dev 03:53:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:53:53 INFO - mozversion application_vendor: Mozilla 03:53:53 INFO - mozversion application_version: 54.0a2 03:53:53 INFO - mozversion platform_buildid: 20170406021021 03:53:53 INFO - mozversion platform_changeset: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 03:53:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:53:53 INFO - mozversion platform_version: 54.0a2 03:53:53 INFO - using testdate: 1491476033 03:53:53 INFO - actual date: 1491476033 03:53:53 INFO - starting webserver on 'localhost:49241' 03:53:53 INFO - SUITE-START | Running 2 tests 03:53:53 INFO - TEST-START | dromaeo_css 03:53:53 INFO - operating with platform_type : mac_ 03:53:53 INFO - Initialising browser for dromaeo_css test... 03:53:53 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmp2qzt_9/profile http://localhost:49241/getInfo.html) 03:53:56 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 03:53:56 INFO - PROCESS | 1636 | colorDepth:24 03:53:56 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 03:53:56 INFO - PROCESS | 1636 | __metrics 03:53:56 INFO - PROCESS | 1636 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:53:56 INFO - PROCESS | 1636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:53:57 INFO - TEST-INFO | 1636: exit 0 03:53:57 INFO - Browser initialized. 03:53:57 INFO - Running cycle 1/1 for dromaeo_css test... 03:53:57 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmp2qzt_9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:54:09 INFO - PROCESS | 1639 | RSS: Main: 215191552 03:54:09 INFO - PROCESS | 1639 | 03:57:13 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 03:57:15 INFO - PROCESS | 1639 | RSS: Main: 402903040 03:57:15 INFO - PROCESS | 1639 | 03:58:59 INFO - PROCESS | 1639 | ************************* 03:58:59 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 03:58:59 INFO - PROCESS | 1639 | 03:58:59 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:58:59 INFO - PROCESS | 1639 | Full stack: 03:58:59 INFO - PROCESS | 1639 | ************************* 03:58:59 INFO - PROCESS | 1639 | ************************* 03:58:59 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 03:58:59 INFO - PROCESS | 1639 | 03:58:59 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:58:59 INFO - PROCESS | 1639 | Full stack: 03:58:59 INFO - PROCESS | 1639 | ************************* 03:58:59 INFO - PROCESS | 1639 | ************************* 03:58:59 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 03:58:59 INFO - PROCESS | 1639 | 03:58:59 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:58:59 INFO - PROCESS | 1639 | Full stack: 03:58:59 INFO - PROCESS | 1639 | ************************* 03:58:59 INFO - PROCESS | 1639 | ************************* 03:58:59 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 03:58:59 INFO - PROCESS | 1639 | 03:58:59 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:58:59 INFO - PROCESS | 1639 | Full stack: 03:58:59 INFO - PROCESS | 1639 | ************************* 04:00:18 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 04:00:19 INFO - PROCESS | 1639 | RSS: Main: 352210944 04:00:19 INFO - PROCESS | 1639 | 04:03:22 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 04:03:22 INFO - PROCESS | 1639 | RSS: Main: 329355264 04:03:22 INFO - PROCESS | 1639 | 04:06:25 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 04:06:26 INFO - PROCESS | 1639 | RSS: Main: 344899584 04:06:26 INFO - PROCESS | 1639 | 04:09:29 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 04:09:30 INFO - PROCESS | 1639 | RSS: Main: 324874240 04:09:30 INFO - PROCESS | 1639 | 04:12:33 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 04:12:34 INFO - PROCESS | 1639 | RSS: Main: 430497792 04:12:34 INFO - PROCESS | 1639 | 04:12:34 INFO - PROCESS | 1639 | __start_tp_report 04:12:34 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 04:12:34 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 04:12:34 INFO - PROCESS | 1639 | |i|pagename|runs| 04:12:34 INFO - PROCESS | 1639 | |0;dojo.html;3123.250419899224;3373.122988023115;3383.171123074846;3394.253264281857;3425.5033020212068;5907.88184236315;5937.614055086417;5955.761769529214;5967.791127310534;6037.8490537736525;5971.850703732409;6010.338862725108;6019.518438524919;6023.457888790008;6032.909506357408;5322.387925388582;5346.812861549846;5359.705216213107;5417.241586177938;5448.482394172547;5276.657017293883;5283.365996080475;5315.840524784263;5346.866328341784;5510.779568817263;5406.107992181285;5432.076546987022;5439.782408703647;5443.401225865172;5454.972725136368;5385.515303622654;5401.648892821973;5403.567714582824;5415.296011518478;5419.729013549307;5171.4052883918075;5242.528172464458;5300.522952934216;5368.114261146906;5443.346798384179;5812.041013232812;5841.824745257649;5966.0454327307425;6009.429104235091;6048.153258543807;6282.465990390822;6360.109584658106;6381.106645069647;6383.106365108889;6390.680465976682;6365.586236894587;6431.549791514596;6444.90332645006;6449.32282110381;6450.903236451457;5811.041178205591;5924.851878703023;5964.761409543569;5974.133750606225;5983.282006159288;6398.072279519443;6405.327440618761;6419.743210271537;6456.580322279036;6534.477241820643;5216.504432078947;6315.873682526324;6320.905186422207;6332.8416789579705;6358.682065896686;5315.911494144412;6410.0705397717065;6417.197850268716;6438.774642887476;6453.870922581616;5271.789128434896;6015.639061656315;6220.377962203744;6305.747770089237;6359.300476947532;5770.394108618619;6005.969970150209;6345.111684364193;6350.206224221973;6354.809355719336;5045.318881950963;5089.898202035939;5099.566536844335;5109.4379618241965;5137.357830271216;4466.798994045275;4501.122281155143;4582.95417045832;4587.128445595393;4610.95389046112;4888.877778055577;5162.483751624807;5871.735771890275;5878.118282257696;5895.675737834375;6343.651099189589;6347.841303967438;6368.363163683687;6374.904376434357;6383.425491705769;6025.78909738157;6095.3904609538695;6105.633661980295;6130.1724267224245;6139.109829074849;5674.659520428788;5685.545156387479;5686.744096515667;5687.9431205687415;5690.174924635905;6319.6840157991;6361.681915904093;6650.567713098633;6667.933320666828;6673;6359.395857393541;6381.617102973836;6390;6395;6396.552241343062;6392.87214255703;6514.088027676224;6580.473562114924;6651;6658.533902626769;5412.404635490172;6365.90451143224;6373.171487706569;6394.936050639434;6403.103565500741;6269.31037585856;6289.119523266838;6315.652639104894;6411.102445657519;6414.262359828493;6195.411435913581;6257.78097766576;6295.622262664072;6331.27190373113;6340.4927605792345;6302.086197501429;6341.334159913143;6349.14286571307;6358.0780786784735;6358.395952384516;6255.624662520263;6256.593321434092;6257.687115644108;6269.5611307208055;6295.874082518416;6093.512518998558;6144.433169541739;6207.906881396692;6269.874602508015;6283.748650053946;6191.690415479118;6215.160953271251;6217.968910155601;6221.160143380766;6221.906671399842 04:12:34 INFO - PROCESS | 1639 | |1;ext.html;631.9241690997081;678.4877417549749;732.5165390842045;800.4356928365501;874.6501399440227;26580.33549161272;27776.02783902564;31882.84058579704;39433.40849887247;39597.010074748076;40264.792056238286;40454.393184102264;40456;40480;40538;9033.36766426352;9175.311851611123;9206.49364284964;9217.815643687121;9232;7273.636318184096;7436.628168591576;9075.682351117712;10989.120870330355;11248.775024499506;11223.38271395073;11259.887401126007;11264.042556382676;11274.492647830826;11315.151363647718;11144.60993865215;11193.16051296156;11193.552257909674;11208.383538905357;11212.495437705324;7190.316919892602;7377.88933166003;7391.039164908528;7394.223606521346;10826.350418974887;27367;37547.24905501902;38138.618613813785;39405;39467.01332466683;3778.395456726938;3779;3788.9431658525145;3811.3711237645757;3835.9232815343817;26335.07827226057;26762.330941726417;27234.727652723617;28561.143165705063;28673.426531469253;2918.357961248522;3096.4116817804547;3146.6212671609114;3295.8681652733862;3300.50492426138;330863;332630;333062;333429;335927;51716.74141629268;51878.74060629673;51998.74000630048;52112.47887521076;52189.65146568413;54225.915481690936;54330.45669543254;54357.91284174373;54378.45621543813;54489.45510544844;14714.411423543152;14897;14907.329170187368;14915.030523015968;14930;12798.424070916832;12798.976081913424;12801.167924084904;12806.80789788154;12812.42344094518;10545.31455455393;10786.838197426889;10861;10864.402457864735;10866.239363244655;4723.834665786681;4759.8517693221165;6068.089786532056;6152.907706384318;6168.537359698041;904.303686161665;951.3340661536952;964.9179819715263;966.4829316315737;985.280745056113;25696.614550781753;26149;27966.580501292498;29178;29254;1033;1033.5710680067687;1044.1125043712784;1072.5280876414354;1072.871255449351;13109.88565971884;13675.111117777313;14165.78751318731;14490.565283041526;14821.629459263604;500287;503667;505118.4744076256;505232;506292.4685376623;57442;57867;57975;58049;58091.419085809444;1249.312877917142;1300.4343110747045;1307.1176955555152;1313.5665230473921;1328.0504439325928;26718.332041699112;27206.863965680837;27910.162695118368;28158.577621336106;28192.85903570469;42860.78569607132;42966;43078.569214307456;43120.78439607782;43130.78434607807;460021;460637;461637;461858.6907065443;463465;34174.829125855205;34582;34884;34898.82550587231;34993.60025598948;43329.56670433381;43920.12159756723;44590.77704611456;44936;45020;27434.72565274322;28050.158495245174;29765.702342976292;29989.550156748104;30410;28120.29699257535;28719.98440163753;28799;28818;28940.55297235172;33034.504482432305;33614.83192584021;33805;33960.83019584985;34200.31599368049;25068;25716.742832572185;28527;28954.84180632751;29126.7087329124;21975.340739777832;22652.730311571937;22660.093596255963;22696.432589185402;22818.42953926165 04:12:34 INFO - PROCESS | 1639 | |2;jquery.html;387.94956655634763;407.3360422511308;424.1432306740385;432.06026891510953;447.988800279993;12980.805287920677;14708.70582588349;20914;20983.685244721342;21596.2441314554;17648.205830737614;22108.447288817748;22678.319650410474;22908.541829163405;23611.881940590272;6287;6582.506312026605;7477.439192060618;7634.5037572557885;7639.433916047197;6453.709258148366;6548.607083575001;7555.017847679794;7577.355924746404;7610.315071643551;5803.767849286023;6397.5521713480075;7438.553686778783;7510.399168066541;7630.38956883448;6047.546434017467;6404.903926441107;7648;7657.11943126542;7662.9233707663325;7151.856962860766;7362.889556656654;7440.590767507785;7444;7460.142083660394;21112.049957751937;26319.34201644955;26356.813943372403;26890.32774180661;27274.5908811368;2921.532554791223;2925.517289647206;3074.892378766755;3117.5635411042244;3129.640091389497;15770.76343854843;16151.111688857109;16214.675706485805;16247.756283655755;17202.913985430117;1967.4294454608146;1999.5001249687577;2005.2780998840406;2005.6690646043385;2061.690746388039;132305.338473308;148317;169893;175440.12279938647;176419;19824.207031718568;20613.484662883548;20872.686909696367;21261.574768504543;21314.893425533082;33460.494277757556;33606;33632.66367336296;33707.49438758421;33783.817566385056;10351.430671312999;10357.689269321932;10512.21158413122;10584.25910186295;10772.330288805248;8724.476531408009;8767.517786521676;8773.736787896374;8943.955280223558;8944.910550894538;9354.953225233967;9409.905900940903;9488.00375960528;9588.993155718688;9627.374220675634;3222.854971526287;3378.223008708011;3624.9125262421167;3755.8122093895195;3843.8846834595006;985.3447457440865;989.6486747204639;992.5979978108877;998.4159266828841;1036.7511797168777;18062.63874722498;20744.792552074287;21416.250431234832;21431.999520067202;21598.676019859715;901.814114439523;984.6652138272879;990.1187942730975;1015.6597539824241;1027.845823126537;8514.403991720645;9685.988422805443;10854.18593605467;11145.2198346115;11556.653300401002;728055.0791738128;733830;760372;762516.1874190704;772264;100023;137680.31159844136;148763;160435;167143;1194.9223300485442;1212.3029258176584;1246.1339369138543;1256.3215863433643;1259.5465632372338;19992.800071999674;20955.847428391586;21077;21086.261980830597;21177.576448471253;62271.06593401012;62561.374386257376;62734;63060.68469657622;63452.365476346495;73797.26202738119;84759.5762021186;96170;98815.50592246992;101285;11137.832932505857;11639.53441862316;11773;11917.821232681343;11978;11269.718257043312;11938.58214962472;12175.33035683046;12467.189632673848;12531.624051278111;10208.64269750585;10261.179105671677;10517.9474102629;10607.310524815863;10702.250842440955;8694.565271736514;8742.91257087421;8767.386282960131;9994.650187243411;10137.543810528692;10534.736631584272;10538.367697938149;10918.344899306067;10932.125429965743;11012.944935275273;11590.13074019435;11648.576490256391;11922.463489142836;11941.402929853299;11974.22167559106;10494.317869338469;10606.628767993083;10698.73253168677;10729.89270107289;10775.461226938778 04:12:34 INFO - PROCESS | 1639 | |3;mootools.html;1541.583772381457;1561.3130222702002;1569.4114706984888;1587.5951632333754;1589.9205039748012;9381.906180938206;10602.681919542416;11100.55597776088;11161.776764464707;11172.217944743861;10568.947155264232;10992.890071099286;11146.6098686546;11164.051055660279;11166.553337866475;2399.5320912422085;2421.9152329668464;2432.8661923594195;2439.1584903208363;2441.3042282949273;2332.8017118544944;2407.205622144688;2426.2478631624163;2434.0385547708643;2434.914777982771;2345.3081341004445;2401.8438801477846;2420.697412823397;2428.9149879754214;2429.4533729910804;2315.363275099361;2369.5024044950583;2389.7012873390827;2394.1859767678984;2395.7484464131276;1910.8280254777137;2380.345405013635;2385.8210634202505;2391.294568102414;2400.099962514057;10140.543675534545;11022.724431889186;11034.944825275903;11038.613648522263;11047.889521104766;5725.656460612377;5849.268841394825;5938.0499120141;5983.431574000463;5987.341392446828;3544.3974524330924;3612.819359032038;3620.4388319810473;3704.2776658551593;3710.3692372296773;1161.6456980620908;1165.452237448398;1199.994000030003;1215.3680086355134;1222.718774681828;13742.793858092136;14094.859051409458;14289.285535723277;15396.461123860723;15644.139572323518;4218.915621687549;4580.862574122782;4598.770061496912;4758.381410416693;4768.284757286366;11785.469653865597;11806.704832379259;11807.527698891949;11833.585824496102;11837.585684501173;4866.853994380174;5098.368522072901;5552.805651802168;5738.311402631711;5801.767929282782;5193.584513238932;5256.816011439658;5338.813141540105;5431.972840135774;5474.698891561003;3845.7692538447222;3865.1496670732395;3892.2993861105274;3934.8622798201927;3937.940930886039;1017.8778546574458;1024.8360262357987;1025.7845852371086;1030.6804890483975;1037.527924794217;868.9746099602403;952.33812500313;962.2061799015839;968.4625033106694;1007.9294449388473;5587.776488940397;6501.642409667514;6772.8984065239065;6777.830554236183;6871.278515755874;906.3474298505066;957.6210257229567;965.285688590438;969.5297780576415;974.5906719177963;3991.702696623609;4086.734362266443;4231.936520952188;4251.234777739975;4286.249906266391;10889.727756805985;14088.929555352157;14093.647658808612;14113.647158821112;14135.575932722035;12114.57598984049;13414.865851341558;13996.23020733849;14012;14026.228557429235;10030.348027378197;10180.541875615616;10215.080642742189;10328.896711032794;10331.276810623334;5114.641975061859;6543.836404089936;6554.410103090554;6748.966255168692;6749.223839258548;10824.891751082389;11904.583339583072;12295.200811947196;12445;12447.253164810141;11600.30398176112;11622.244554103956;12007.519699211934;12055.397230138633;12380.81428778551;7885.250901164381;9223.35436519464;9253.907460925304;9453.716388508356;9458.33791634579;10214.795704086026;10436.739081523023;10442.060214580724;10447.164226861682;10483.423411712123;8434.24091831738;8689;8720.912790872264;8773.780655483664;8833.646654133763;8564.957175214084;8584.356173286904;8637.8704319434;8726.432781869158;8826.338024648305;7835;7972.003499562555;7975.521468711896;7981.680732770857;8003.199680032183;5410.59420543453;5413.621046526705;5504.091824848855;5613.354464236665;5659.943400566106;5937.584369094122;5995.46040856325;6001.549883758823;6041.24484439445;6089.725962331617 04:12:34 INFO - PROCESS | 1639 | |4;prototype.html;369.7910680465537;381.6107570278316;385.4603555022969;386.8259283322502;389.75445469354304;5258.50044245797;5307.52232299093;5310.362756469224;5314.627976041678;5314.946850531498;4335.036383341073;4841.56425921667;4841.661083724141;5019.920717045835;5055.848324550269;3812.6759225465867;3952.6837852971694;4215.768132752697;4219.578042195786;4291.892702682442;3806.7144964127665;4272.551382104865;4273.444452221207;4277.572242775728;4317.366406492038;4067.7966101694947;4185.623293903548;4242.2788126018495;4283.250431174548;4300.741955482666;3730.9253814923823;4206.326987681987;4219.683523735732;4221.598948099926;4227.619514243733;3646.106703857545;3672.05381147992;3900.4149377593303;3912.941305880414;3968.7696813987754;4331.487737112468;4507.242175551538;4678.883027924295;4972.8259510917305;5064.822731204427;3815.9236815263816;4558.51273775562;4579.335996280554;4708.529147085298;4776;4631.982546442143;5393.973030134864;5416.756245968941;5434.098065677021;5452.6455780374545;3688.5942546319884;3735.61782140607;4056.5132184137797;4066.7356621819486;4321.595481468536;4790.473047964721;4898.338724272249;5667.518260947824;5680.318361796611;5845.503132233765;3558.8576456941432;4111.773852438085;4403.471583410012;4446.466424029137;4516.277395616686;3908.9804550977065;4142.440770496005;4239.0674051708575;4530.207213737583;4556.453225612948;3983.820728067244;4352.521222665504;4456.77716114193;4609.124266389375;4723.409573803275;4667.369905062773;4802.135615589227;4883.560479556857;5019.723915184627;5118.436971933084;1276.7424087274028;1370.7464119137992;1418.9432422703183;1454.6581553334959;1538.9846101538842;1028.4806172882647;1032.4063663393579;1092.6451200511467;1165.4056431220138;1169.4328250798355;4289.806958686867;4576.496585375606;5111.795528178831;5125.923111153335;5181.922271165936;5371.892562148735;5583.609147359646;5589.496945274864;5619.269494965628;5656.632318899258;4256.87229383119;4641.791119399635;4712.458067322234;4961.975190124026;5014.687368862775;2726.922865468129;2832.8866845325956;2845.3070423098225;2915.897943572007;2935.8091724037868;4890.413150421901;5499.230107784913;5749.8275051748515;5794.335559874342;5821.446962538636;4460.219461594208;4687.81248750053;4869.878253043702;5540.058190107691;5856.619319744203;5068.771905264272;5169.922451163236;5855.707214639251;5872.853178670482;5878.470937615635;4617.89885602057;4805.663603547858;5502.111391519485;5574.526165275997;5645.774169033357;4965;4973.726445045412;5020.598352131894;5379.305518761577;5680.00599895025;5060.468650791761;5073.847784566469;5650.203149441053;5653.971730141323;5671.489565939085;3941.704372172041;4388.846390376321;4420.447444069491;4482.574155455227;4514.525974772601;4895.63282753802;5274.393444753749;5559.916601250903;5666.065099258741;5701.857453563694;3964.337837435301;4837.564619184291;4904.890896914361;5075.3148324975655;5475;4750.429948406075;5395.298611180521;5420.403755586961;5455.754491047992;5502.0371434997915;4396.428464299621;5104.2343648453025;5295.311609490742;5343.812966546152;5344.679319240858;4507.797149128362;4636.83771068011;5138.946515964143;5280.788768449219;5351.250824884597;4641.860744177545;5198.128673677548;5348.304720386325;5361.731913404392;5417.241586178017 04:12:34 INFO - PROCESS | 1639 | |5;yui.html;709.5321738700177;734.8713975054364;762.0816915616682;825.4345773145396;852.9104444033375;15121.697566048673;15927.362905483767;15959.12224827634;15976.600584985366;15979.041257524557;14460.060096093774;14495.49265775698;14620.268986550684;14629.195394253311;14631;933.0249888866117;947.5072962059725;948.5921053946801;949.7862980829293;951.8381875081253;961.125375907924;972.4019727867346;974.0211087856694;978.8433850583908;980.9754756131118;1014.1177175857015;1015.2892974917551;1016.4714348538793;1017;1017.1659239423675;1007.3452256033563;1011.6560369474414;1013.5236438873718;1013.6958912326272;1013.8225810483173;750.1598210004775;751.1024325930515;752.3078767533827;752.6349720385608;752.815560187752;13578;15923.964942278715;16183.190840458048;16301.755473667905;16392.344306227737;12596.433160507799;14041.5085472008;14251.57245282643;14297.642558936006;14313.49902753409;10175.542100605491;11693.941530292379;11785;11869.228500147463;11928.880711192951;3543.8050907200095;3607.4588811678195;3620.4931309616672;3756.4553139794575;3777.8299976501125;85706.57146714226;105238.47380763048;108218;108860.455697721;108896.91103088947;10694.946525267324;10731.570737170581;10793.946030269799;10932.508037138348;11081.390523521122;22284;22727.204547840745;22755.31734047948;22798.77201228;22843.086276549086;7690.961545192238;7761.573113478813;7863.292303692695;7924.049114106344;8032.83934321322;4177.289860723684;4182.916341673211;4283.201055556674;4291.334843099325;4441.866743997685;3536.7877927324444;3637.927241455156;3642.0894776305922;3647.7811331320204;3651.926961460756;529.6851554875288;543.7091156231397;640.6444423344996;641.1216633212435;641.7593402474072;648.1347401219425;664.8271449423185;677.4208052115468;679.8470344172621;690.8618276344752;9424.764380890532;10325.84511232332;10347.844782328271;10682.412467314218;10684.94657526723;594.3759052994338;662.552776875607;687.2817905288985;703.5462126928103;710.1761956130863;4804.135255654016;4991.450940396553;5447.6459030163705;5650.85872853182;5681.886362272731;83771;103122.96877031284;106913;107327.64864851389;114349;73537.52924941471;95734;97963;101788.49105754572;102381;73221.2677873225;80462.59768701353;82712;86295.1370486287;86873.56563217143;10894.782104358026;11008.724781880517;11101.888981110085;11197.38414387184;11464.713382165179;40459.59540404558;50660.74669626628;52256.47743522668;53997.73001134969;54005.91988160136;65360;65376.34623653703;69008.6549567249;70021.29978700148;70133;11611.954924056876;12633.93683031579;12925.741485170432;12953.740925181255;13427.865721342661;14818.62953426173;14910.85089149138;15277.847221527643;15731.921340393224;16076.67846643084;13682.52111176104;14247.501337453139;14475.493357732428;14584.343704533108;15050.698986020438;13461.86538134606;14082.929585352007;14123.940704447003;14458.927705361406;14459.927700361432;11389.658310250707;12328.81506777381;12876.678083047998;14059.367328470447;14508.419663213775;9921.107100361001;9995.70012899614;10061.496925153859;10401.635942741968;10834.404107774148;10915.779844945919;11281.379524126252;11289.943550282196;11534;11567.421628918688 04:12:34 INFO - PROCESS | 1639 | __end_tp_report 04:12:34 INFO - PROCESS | 1639 | __start_cc_report 04:12:34 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,1999 04:12:34 INFO - PROCESS | 1639 | __end_cc_report 04:12:34 INFO - PROCESS | 1639 | __startTimestamp1491477154241__endTimestamp 04:12:34 INFO - PROCESS | 1639 | 04:12:34 INFO - PROCESS | 1639 | ------- Summary: start ------- 04:12:34 INFO - PROCESS | 1639 | Number of tests: 6 04:12:34 INFO - PROCESS | 1639 | 04:12:34 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5892.31 Median:6139.11 stddev:674.98 (11.0%) stddev-sans-first:641.15 04:12:34 INFO - PROCESS | 1639 | Values: 3123.3 3373.1 3383.2 3394.3 3425.5 5907.9 5937.6 5955.8 5967.8 6037.8 5971.9 6010.3 6019.5 6023.5 6032.9 5322.4 5346.8 5359.7 5417.2 5448.5 5276.7 5283.4 5315.8 5346.9 5510.8 5406.1 5432.1 5439.8 5443.4 5455.0 5385.5 5401.6 5403.6 5415.3 5419.7 5171.4 5242.5 5300.5 5368.1 5443.3 5812.0 5841.8 5966.0 6009.4 6048.2 6282.5 6360.1 6381.1 6383.1 6390.7 6365.6 6431.5 6444.9 6449.3 6450.9 5811.0 5924.9 5964.8 5974.1 5983.3 6398.1 6405.3 6419.7 6456.6 6534.5 5216.5 6315.9 6320.9 6332.8 6358.7 5315.9 6410.1 6417.2 6438.8 6453.9 5271.8 6015.6 6220.4 6305.7 6359.3 5770.4 6006.0 6345.1 6350.2 6354.8 5045.3 5089.9 5099.6 5109.4 5137.4 4466.8 4501.1 4583.0 4587.1 4611.0 4888.9 5162.5 5871.7 5878.1 5895.7 6343.7 6347.8 6368.4 6374.9 6383.4 6025.8 6095.4 6105.6 6130.2 6139.1 5674.7 5685.5 5686.7 5687.9 5690.2 6319.7 6361.7 6650.6 6667.9 6673.0 6359.4 6381.6 6390.0 6395.0 6396.6 6392.9 6514.1 6580.5 6651.0 6658.5 5412.4 6365.9 6373.2 6394.9 6403.1 6269.3 6289.1 6315.7 6411.1 6414.3 6195.4 6257.8 6295.6 6331.3 6340.5 6302.1 6341.3 6349.1 6358.1 6358.4 6255.6 6256.6 6257.7 6269.6 6295.9 6093.5 6144.4 6207.9 6269.9 6283.7 6191.7 6215.2 6218.0 6221.2 6221.9 04:12:34 INFO - PROCESS | 1639 | 04:12:34 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:56593.09 Median:333245.50 stddev:116826.07 (35.1%) stddev-sans-first:117078.24 04:12:34 INFO - PROCESS | 1639 | Values: 631.9 678.5 732.5 800.4 874.7 26580.3 27776.0 31882.8 39433.4 39597.0 40264.8 40454.4 40456.0 40480.0 40538.0 9033.4 9175.3 9206.5 9217.8 9232.0 7273.6 7436.6 9075.7 10989.1 11248.8 11223.4 11259.9 11264.0 11274.5 11315.2 11144.6 11193.2 11193.6 11208.4 11212.5 7190.3 7377.9 7391.0 7394.2 10826.4 27367.0 37547.2 38138.6 39405.0 39467.0 3778.4 3779.0 3788.9 3811.4 3835.9 26335.1 26762.3 27234.7 28561.1 28673.4 2918.4 3096.4 3146.6 3295.9 3300.5 330863.0 332630.0 333062.0 333429.0 335927.0 51716.7 51878.7 51998.7 52112.5 52189.7 54225.9 54330.5 54357.9 54378.5 54489.5 14714.4 14897.0 14907.3 14915.0 14930.0 12798.4 12799.0 12801.2 12806.8 12812.4 10545.3 10786.8 10861.0 10864.4 10866.2 4723.8 4759.9 6068.1 6152.9 6168.5 904.3 951.3 964.9 966.5 985.3 25696.6 26149.0 27966.6 29178.0 29254.0 1033.0 1033.6 1044.1 1072.5 1072.9 13109.9 13675.1 14165.8 14490.6 14821.6 500287.0 503667.0 505118.5 505232.0 506292.5 57442.0 57867.0 57975.0 58049.0 58091.4 1249.3 1300.4 1307.1 1313.6 1328.1 26718.3 27206.9 27910.2 28158.6 28192.9 42860.8 42966.0 43078.6 43120.8 43130.8 460021.0 460637.0 461637.0 461858.7 463465.0 34174.8 34582.0 34884.0 34898.8 34993.6 43329.6 43920.1 44590.8 44936.0 45020.0 27434.7 28050.2 29765.7 29989.6 30410.0 28120.3 28720.0 28799.0 28818.0 28940.6 33034.5 33614.8 33805.0 33960.8 34200.3 25068.0 25716.7 28527.0 28954.8 29126.7 21975.3 22652.7 22660.1 22696.4 22818.4 04:12:34 INFO - PROCESS | 1639 | 04:12:34 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:43061.42 Median:22393.38 stddev:125425.67 (560.1%) stddev-sans-first:125736.60 04:12:34 INFO - PROCESS | 1639 | Values: 387.9 407.3 424.1 432.1 448.0 12980.8 14708.7 20914.0 20983.7 21596.2 17648.2 22108.4 22678.3 22908.5 23611.9 6287.0 6582.5 7477.4 7634.5 7639.4 6453.7 6548.6 7555.0 7577.4 7610.3 5803.8 6397.6 7438.6 7510.4 7630.4 6047.5 6404.9 7648.0 7657.1 7662.9 7151.9 7362.9 7440.6 7444.0 7460.1 21112.0 26319.3 26356.8 26890.3 27274.6 2921.5 2925.5 3074.9 3117.6 3129.6 15770.8 16151.1 16214.7 16247.8 17202.9 1967.4 1999.5 2005.3 2005.7 2061.7 132305.3 148317.0 169893.0 175440.1 176419.0 19824.2 20613.5 20872.7 21261.6 21314.9 33460.5 33606.0 33632.7 33707.5 33783.8 10351.4 10357.7 10512.2 10584.3 10772.3 8724.5 8767.5 8773.7 8944.0 8944.9 9355.0 9409.9 9488.0 9589.0 9627.4 3222.9 3378.2 3624.9 3755.8 3843.9 985.3 989.6 992.6 998.4 1036.8 18062.6 20744.8 21416.3 21432.0 21598.7 901.8 984.7 990.1 1015.7 1027.8 8514.4 9686.0 10854.2 11145.2 11556.7 728055.1 733830.0 760372.0 762516.2 772264.0 100023.0 137680.3 148763.0 160435.0 167143.0 1194.9 1212.3 1246.1 1256.3 1259.5 19992.8 20955.8 21077.0 21086.3 21177.6 62271.1 62561.4 62734.0 63060.7 63452.4 73797.3 84759.6 96170.0 98815.5 101285.0 11137.8 11639.5 11773.0 11917.8 11978.0 11269.7 11938.6 12175.3 12467.2 12531.6 10208.6 10261.2 10517.9 10607.3 10702.3 8694.6 8742.9 8767.4 9994.7 10137.5 10534.7 10538.4 10918.3 10932.1 11012.9 11590.1 11648.6 11922.5 11941.4 11974.2 10494.3 10606.6 10698.7 10729.9 10775.5 04:12:34 INFO - PROCESS | 1639 | 04:12:34 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:6660.98 Median:2433.45 stddev:4151.02 (170.6%) stddev-sans-first:4144.85 04:12:34 INFO - PROCESS | 1639 | Values: 1541.6 1561.3 1569.4 1587.6 1589.9 9381.9 10602.7 11100.6 11161.8 11172.2 10568.9 10992.9 11146.6 11164.1 11166.6 2399.5 2421.9 2432.9 2439.2 2441.3 2332.8 2407.2 2426.2 2434.0 2434.9 2345.3 2401.8 2420.7 2428.9 2429.5 2315.4 2369.5 2389.7 2394.2 2395.7 1910.8 2380.3 2385.8 2391.3 2400.1 10140.5 11022.7 11034.9 11038.6 11047.9 5725.7 5849.3 5938.0 5983.4 5987.3 3544.4 3612.8 3620.4 3704.3 3710.4 1161.6 1165.5 1200.0 1215.4 1222.7 13742.8 14094.9 14289.3 15396.5 15644.1 4218.9 4580.9 4598.8 4758.4 4768.3 11785.5 11806.7 11807.5 11833.6 11837.6 4866.9 5098.4 5552.8 5738.3 5801.8 5193.6 5256.8 5338.8 5432.0 5474.7 3845.8 3865.1 3892.3 3934.9 3937.9 1017.9 1024.8 1025.8 1030.7 1037.5 869.0 952.3 962.2 968.5 1007.9 5587.8 6501.6 6772.9 6777.8 6871.3 906.3 957.6 965.3 969.5 974.6 3991.7 4086.7 4231.9 4251.2 4286.2 10889.7 14088.9 14093.6 14113.6 14135.6 12114.6 13414.9 13996.2 14012.0 14026.2 10030.3 10180.5 10215.1 10328.9 10331.3 5114.6 6543.8 6554.4 6749.0 6749.2 10824.9 11904.6 12295.2 12445.0 12447.3 11600.3 11622.2 12007.5 12055.4 12380.8 7885.3 9223.4 9253.9 9453.7 9458.3 10214.8 10436.7 10442.1 10447.2 10483.4 8434.2 8689.0 8720.9 8773.8 8833.6 8565.0 8584.4 8637.9 8726.4 8826.3 7835.0 7972.0 7975.5 7981.7 8003.2 5410.6 5413.6 5504.1 5613.4 5659.9 5937.6 5995.5 6001.5 6041.2 6089.7 04:12:34 INFO - PROCESS | 1639 | 04:12:34 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4453.50 Median:4710.49 stddev:1248.16 (26.5%) stddev-sans-first:1213.44 04:12:34 INFO - PROCESS | 1639 | Values: 369.8 381.6 385.5 386.8 389.8 5258.5 5307.5 5310.4 5314.6 5314.9 4335.0 4841.6 4841.7 5019.9 5055.8 3812.7 3952.7 4215.8 4219.6 4291.9 3806.7 4272.6 4273.4 4277.6 4317.4 4067.8 4185.6 4242.3 4283.3 4300.7 3730.9 4206.3 4219.7 4221.6 4227.6 3646.1 3672.1 3900.4 3912.9 3968.8 4331.5 4507.2 4678.9 4972.8 5064.8 3815.9 4558.5 4579.3 4708.5 4776.0 4632.0 5394.0 5416.8 5434.1 5452.6 3688.6 3735.6 4056.5 4066.7 4321.6 4790.5 4898.3 5667.5 5680.3 5845.5 3558.9 4111.8 4403.5 4446.5 4516.3 3909.0 4142.4 4239.1 4530.2 4556.5 3983.8 4352.5 4456.8 4609.1 4723.4 4667.4 4802.1 4883.6 5019.7 5118.4 1276.7 1370.7 1418.9 1454.7 1539.0 1028.5 1032.4 1092.6 1165.4 1169.4 4289.8 4576.5 5111.8 5125.9 5181.9 5371.9 5583.6 5589.5 5619.3 5656.6 4256.9 4641.8 4712.5 4962.0 5014.7 2726.9 2832.9 2845.3 2915.9 2935.8 4890.4 5499.2 5749.8 5794.3 5821.4 4460.2 4687.8 4869.9 5540.1 5856.6 5068.8 5169.9 5855.7 5872.9 5878.5 4617.9 4805.7 5502.1 5574.5 5645.8 4965.0 4973.7 5020.6 5379.3 5680.0 5060.5 5073.8 5650.2 5654.0 5671.5 3941.7 4388.8 4420.4 4482.6 4514.5 4895.6 5274.4 5559.9 5666.1 5701.9 3964.3 4837.6 4904.9 5075.3 5475.0 4750.4 5395.3 5420.4 5455.8 5502.0 4396.4 5104.2 5295.3 5343.8 5344.7 4507.8 4636.8 5138.9 5280.8 5351.3 4641.9 5198.1 5348.3 5361.7 5417.2 04:12:34 INFO - PROCESS | 1639 | 04:12:34 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20851.50 Median:16347.05 stddev:29904.55 (182.9%) stddev-sans-first:29950.20 04:12:34 INFO - PROCESS | 1639 | Values: 709.5 734.9 762.1 825.4 852.9 15121.7 15927.4 15959.1 15976.6 15979.0 14460.1 14495.5 14620.3 14629.2 14631.0 933.0 947.5 948.6 949.8 951.8 961.1 972.4 974.0 978.8 981.0 1014.1 1015.3 1016.5 1017.0 1017.2 1007.3 1011.7 1013.5 1013.7 1013.8 750.2 751.1 752.3 752.6 752.8 13578.0 15924.0 16183.2 16301.8 16392.3 12596.4 14041.5 14251.6 14297.6 14313.5 10175.5 11693.9 11785.0 11869.2 11928.9 3543.8 3607.5 3620.5 3756.5 3777.8 85706.6 105238.5 108218.0 108860.5 108896.9 10694.9 10731.6 10793.9 10932.5 11081.4 22284.0 22727.2 22755.3 22798.8 22843.1 7691.0 7761.6 7863.3 7924.0 8032.8 4177.3 4182.9 4283.2 4291.3 4441.9 3536.8 3637.9 3642.1 3647.8 3651.9 529.7 543.7 640.6 641.1 641.8 648.1 664.8 677.4 679.8 690.9 9424.8 10325.8 10347.8 10682.4 10684.9 594.4 662.6 687.3 703.5 710.2 4804.1 4991.5 5447.6 5650.9 5681.9 83771.0 103123.0 106913.0 107327.6 114349.0 73537.5 95734.0 97963.0 101788.5 102381.0 73221.3 80462.6 82712.0 86295.1 86873.6 10894.8 11008.7 11101.9 11197.4 11464.7 40459.6 50660.7 52256.5 53997.7 54005.9 65360.0 65376.3 69008.7 70021.3 70133.0 11612.0 12633.9 12925.7 12953.7 13427.9 14818.6 14910.9 15277.8 15731.9 16076.7 13682.5 14247.5 14475.5 14584.3 15050.7 13461.9 14082.9 14123.9 14458.9 14459.9 11389.7 12328.8 12876.7 14059.4 14508.4 9921.1 9995.7 10061.5 10401.6 10834.4 10915.8 11281.4 11289.9 11534.0 11567.4 04:12:34 INFO - PROCESS | 1639 | -------- Summary: end -------- 04:12:34 INFO - PROCESS | 1639 | 04:12:34 INFO - TEST-INFO | 1639: exit 0 04:12:34 INFO - TEST-OK | dromaeo_css | took 1121019ms 04:12:34 INFO - TEST-START | kraken 04:12:34 INFO - operating with platform_type : mac_ 04:12:34 INFO - Initialising browser for kraken test... 04:12:34 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpKT8DFU/profile http://localhost:49241/getInfo.html) 04:12:37 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 04:12:37 INFO - PROCESS | 1650 | colorDepth:24 04:12:37 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 04:12:37 INFO - PROCESS | 1650 | __metrics 04:12:37 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:12:37 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:12:38 INFO - TEST-INFO | 1650: exit 0 04:12:38 INFO - Browser initialized. 04:12:38 INFO - Running cycle 1/1 for kraken test... 04:12:38 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpKT8DFU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:12:50 INFO - PROCESS | 1651 | RSS: Main: 211210240 04:12:50 INFO - PROCESS | 1651 | 04:12:53 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 04:12:54 INFO - PROCESS | 1651 | RSS: Main: 265506816 04:12:54 INFO - PROCESS | 1651 | 04:12:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 04:12:57 INFO - PROCESS | 1651 | RSS: Main: 279465984 04:12:57 INFO - PROCESS | 1651 | 04:13:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 04:13:01 INFO - PROCESS | 1651 | RSS: Main: 376430592 04:13:01 INFO - PROCESS | 1651 | 04:13:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 04:13:03 INFO - PROCESS | 1651 | RSS: Main: 279830528 04:13:03 INFO - PROCESS | 1651 | 04:13:05 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:13:06 INFO - PROCESS | 1651 | RSS: Main: 281198592 04:13:06 INFO - PROCESS | 1651 | 04:13:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 04:13:10 INFO - PROCESS | 1651 | RSS: Main: 321933312 04:13:10 INFO - PROCESS | 1651 | 04:13:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 04:13:13 INFO - PROCESS | 1651 | RSS: Main: 321257472 04:13:13 INFO - PROCESS | 1651 | 04:13:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 04:13:17 INFO - PROCESS | 1651 | RSS: Main: 319500288 04:13:17 INFO - PROCESS | 1651 | 04:13:18 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:13:19 INFO - PROCESS | 1651 | RSS: Main: 297054208 04:13:19 INFO - PROCESS | 1651 | 04:13:21 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:13:22 INFO - PROCESS | 1651 | RSS: Main: 299634688 04:13:22 INFO - PROCESS | 1651 | 04:13:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:13:24 INFO - PROCESS | 1651 | RSS: Main: 306364416 04:13:24 INFO - PROCESS | 1651 | 04:13:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:13:28 INFO - PROCESS | 1651 | RSS: Main: 321323008 04:13:28 INFO - PROCESS | 1651 | 04:13:31 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:13:32 INFO - PROCESS | 1651 | RSS: Main: 315031552 04:13:32 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 04:13:34 INFO - PROCESS | 1651 | RSS: Main: 359395328 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | __start_tp_report 04:13:34 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 04:13:34 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 04:13:34 INFO - PROCESS | 1651 | |i|pagename|runs| 04:13:34 INFO - PROCESS | 1651 | |0;ai-astar;104;114;114;116;115;115;117;112;124;170 04:13:34 INFO - PROCESS | 1651 | |1;audio-beat-detection;137;111;109;146;110;109;108;110;129;108 04:13:34 INFO - PROCESS | 1651 | |2;audio-dft;181;183;191;188;183;184;185;199;263;199 04:13:34 INFO - PROCESS | 1651 | |3;audio-fft;79;61;60;93;51;74;84;86;92;61 04:13:34 INFO - PROCESS | 1651 | |4;audio-oscillator;91;70;87;70;69;68;83;69;69;84 04:13:34 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;85;87;87;86;86;86;86;86;96;133 04:13:34 INFO - PROCESS | 1651 | |6;imaging-darkroom;94;94;93;96;93;94;92;96;93;105 04:13:34 INFO - PROCESS | 1651 | |7;imaging-desaturate;85;86;83;84;85;87;85;86;83;83 04:13:34 INFO - PROCESS | 1651 | |8;json-parse-financial;63;61;61;60;64;64;65;64;64;65 04:13:34 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;61;60;63;63;61;61;64;84;85;84 04:13:34 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;86;83;87;84;83;84;85;89;84;83 04:13:34 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;162;128;148;156;141;135;142;145;179;147 04:13:34 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;166;163;165;165;164;167;180;166;168;165 04:13:34 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;66;64;65;68;78;84;83;62;67;63 04:13:34 INFO - PROCESS | 1651 | __end_tp_report 04:13:34 INFO - PROCESS | 1651 | __start_cc_report 04:13:34 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2123 04:13:34 INFO - PROCESS | 1651 | __end_cc_report 04:13:34 INFO - PROCESS | 1651 | __startTimestamp1491477214267__endTimestamp 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | ------- Summary: start ------- 04:13:34 INFO - PROCESS | 1651 | Number of tests: 14 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:120.10 Median:115.50 stddev:18.21 (15.8%) stddev-sans-first:18.35 04:13:34 INFO - PROCESS | 1651 | Values: 104.0 114.0 114.0 116.0 115.0 115.0 117.0 112.0 124.0 170.0 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:117.70 Median:110.50 stddev:14.16 (12.8%) stddev-sans-first:13.18 04:13:34 INFO - PROCESS | 1651 | Values: 137.0 111.0 109.0 146.0 110.0 109.0 108.0 110.0 129.0 108.0 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:195.60 Median:189.50 stddev:24.55 (13.0%) stddev-sans-first:25.46 04:13:34 INFO - PROCESS | 1651 | Values: 181.0 183.0 191.0 188.0 183.0 184.0 185.0 199.0 263.0 199.0 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:74.10 Median:81.50 stddev:14.97 (18.4%) stddev-sans-first:15.77 04:13:34 INFO - PROCESS | 1651 | Values: 79.0 61.0 60.0 93.0 51.0 74.0 84.0 86.0 92.0 61.0 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:76.50 stddev:9.08 (11.9%) stddev-sans-first:7.84 04:13:34 INFO - PROCESS | 1651 | Values: 91.0 70.0 87.0 70.0 69.0 68.0 83.0 69.0 69.0 84.0 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:91.80 Median:86.00 stddev:14.82 (17.2%) stddev-sans-first:15.51 04:13:34 INFO - PROCESS | 1651 | Values: 85.0 87.0 87.0 86.0 86.0 86.0 86.0 86.0 96.0 133.0 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:94.00 stddev:3.74 (4.0%) stddev-sans-first:3.95 04:13:34 INFO - PROCESS | 1651 | Values: 94.0 94.0 93.0 96.0 93.0 94.0 92.0 96.0 93.0 105.0 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.42 (1.7%) stddev-sans-first:1.50 04:13:34 INFO - PROCESS | 1651 | Values: 85.0 86.0 83.0 84.0 85.0 87.0 85.0 86.0 83.0 83.0 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:64.00 stddev:1.79 (2.8%) stddev-sans-first:1.90 04:13:34 INFO - PROCESS | 1651 | Values: 63.0 61.0 61.0 60.0 64.0 64.0 65.0 64.0 64.0 65.0 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:63.50 stddev:10.93 (17.2%) stddev-sans-first:11.24 04:13:34 INFO - PROCESS | 1651 | Values: 61.0 60.0 63.0 63.0 61.0 61.0 64.0 84.0 85.0 84.0 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:84.50 stddev:1.99 (2.4%) stddev-sans-first:2.06 04:13:34 INFO - PROCESS | 1651 | Values: 86.0 83.0 87.0 84.0 83.0 84.0 85.0 89.0 84.0 83.0 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:147.50 stddev:14.47 (9.8%) stddev-sans-first:14.47 04:13:34 INFO - PROCESS | 1651 | Values: 162.0 128.0 148.0 156.0 141.0 135.0 142.0 145.0 179.0 147.0 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:166.00 stddev:4.82 (2.9%) stddev-sans-first:5.10 04:13:34 INFO - PROCESS | 1651 | Values: 166.0 163.0 165.0 165.0 164.0 167.0 180.0 166.0 168.0 165.0 04:13:34 INFO - PROCESS | 1651 | 04:13:34 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:67.50 stddev:8.38 (12.4%) stddev-sans-first:8.76 04:13:34 INFO - PROCESS | 1651 | Values: 66.0 64.0 65.0 68.0 78.0 84.0 83.0 62.0 67.0 63.0 04:13:34 INFO - PROCESS | 1651 | -------- Summary: end -------- 04:13:34 INFO - PROCESS | 1651 | 04:13:35 INFO - TEST-INFO | 1651: exit 0 04:13:35 INFO - TEST-OK | kraken | took 60395ms 04:13:35 INFO - SUITE-END | took 1181s 04:13:35 INFO - Completed test suite (00:19:41) 04:13:35 INFO - javascript benchmark 04:13:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7636.628390067727, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3123.250419899224, 3373.122988023115, 3383.171123074846, 3394.253264281857, 3425.5033020212068, 5907.88184236315, 5937.614055086417, 5955.761769529214, 5967.791127310534, 6037.8490537736525, 5971.850703732409, 6010.338862725108, 6019.518438524919, 6023.457888790008, 6032.909506357408, 5322.387925388582, 5346.812861549846, 5359.705216213107, 5417.241586177938, 5448.482394172547, 5276.657017293883, 5283.365996080475, 5315.840524784263, 5346.866328341784, 5510.779568817263, 5406.107992181285, 5432.076546987022, 5439.782408703647, 5443.401225865172, 5454.972725136368, 5385.515303622654, 5401.648892821973, 5403.567714582824, 5415.296011518478, 5419.729013549307, 5171.4052883918075, 5242.528172464458, 5300.522952934216, 5368.114261146906, 5443.346798384179, 5812.041013232812, 5841.824745257649, 5966.0454327307425, 6009.429104235091, 6048.153258543807, 6282.465990390822, 6360.109584658106, 6381.106645069647, 6383.106365108889, 6390.680465976682, 6365.586236894587, 6431.549791514596, 6444.90332645006, 6449.32282110381, 6450.903236451457, 5811.041178205591, 5924.851878703023, 5964.761409543569, 5974.133750606225, 5983.282006159288, 6398.072279519443, 6405.327440618761, 6419.743210271537, 6456.580322279036, 6534.477241820643, 5216.504432078947, 6315.873682526324, 6320.905186422207, 6332.8416789579705, 6358.682065896686, 5315.911494144412, 6410.0705397717065, 6417.197850268716, 6438.774642887476, 6453.870922581616, 5271.789128434896, 6015.639061656315, 6220.377962203744, 6305.747770089237, 6359.300476947532, 5770.394108618619, 6005.969970150209, 6345.111684364193, 6350.206224221973, 6354.809355719336, 5045.318881950963, 5089.898202035939, 5099.566536844335, 5109.4379618241965, 5137.357830271216, 4466.798994045275, 4501.122281155143, 4582.95417045832, 4587.128445595393, 4610.95389046112, 4888.877778055577, 5162.483751624807, 5871.735771890275, 5878.118282257696, 5895.675737834375, 6343.651099189589, 6347.841303967438, 6368.363163683687, 6374.904376434357, 6383.425491705769, 6025.78909738157, 6095.3904609538695, 6105.633661980295, 6130.1724267224245, 6139.109829074849, 5674.659520428788, 5685.545156387479, 5686.744096515667, 5687.9431205687415, 5690.174924635905, 6319.6840157991, 6361.681915904093, 6650.567713098633, 6667.933320666828, 6673.0, 6359.395857393541, 6381.617102973836, 6390.0, 6395.0, 6396.552241343062, 6392.87214255703, 6514.088027676224, 6580.473562114924, 6651.0, 6658.533902626769, 5412.404635490172, 6365.90451143224, 6373.171487706569, 6394.936050639434, 6403.103565500741, 6269.31037585856, 6289.119523266838, 6315.652639104894, 6411.102445657519, 6414.262359828493, 6195.411435913581, 6257.78097766576, 6295.622262664072, 6331.27190373113, 6340.4927605792345, 6302.086197501429, 6341.334159913143, 6349.14286571307, 6358.0780786784735, 6358.395952384516, 6255.624662520263, 6256.593321434092, 6257.687115644108, 6269.5611307208055, 6295.874082518416, 6093.512518998558, 6144.433169541739, 6207.906881396692, 6269.874602508015, 6283.748650053946, 6191.690415479118, 6215.160953271251, 6217.968910155601, 6221.160143380766, 6221.906671399842], "value": 5848.69235576623, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.9241690997081, 678.4877417549749, 732.5165390842045, 800.4356928365501, 874.6501399440227, 26580.33549161272, 27776.02783902564, 31882.84058579704, 39433.40849887247, 39597.010074748076, 40264.792056238286, 40454.393184102264, 40456.0, 40480.0, 40538.0, 9033.36766426352, 9175.311851611123, 9206.49364284964, 9217.815643687121, 9232.0, 7273.636318184096, 7436.628168591576, 9075.682351117712, 10989.120870330355, 11248.775024499506, 11223.38271395073, 11259.887401126007, 11264.042556382676, 11274.492647830826, 11315.151363647718, 11144.60993865215, 11193.16051296156, 11193.552257909674, 11208.383538905357, 11212.495437705324, 7190.316919892602, 7377.88933166003, 7391.039164908528, 7394.223606521346, 10826.350418974887, 27367.0, 37547.24905501902, 38138.618613813785, 39405.0, 39467.01332466683, 3778.395456726938, 3779.0, 3788.9431658525145, 3811.3711237645757, 3835.9232815343817, 26335.07827226057, 26762.330941726417, 27234.727652723617, 28561.143165705063, 28673.426531469253, 2918.357961248522, 3096.4116817804547, 3146.6212671609114, 3295.8681652733862, 3300.50492426138, 330863.0, 332630.0, 333062.0, 333429.0, 335927.0, 51716.74141629268, 51878.74060629673, 51998.74000630048, 52112.47887521076, 52189.65146568413, 54225.915481690936, 54330.45669543254, 54357.91284174373, 54378.45621543813, 54489.45510544844, 14714.411423543152, 14897.0, 14907.329170187368, 14915.030523015968, 14930.0, 12798.424070916832, 12798.976081913424, 12801.167924084904, 12806.80789788154, 12812.42344094518, 10545.31455455393, 10786.838197426889, 10861.0, 10864.402457864735, 10866.239363244655, 4723.834665786681, 4759.8517693221165, 6068.089786532056, 6152.907706384318, 6168.537359698041, 904.303686161665, 951.3340661536952, 964.9179819715263, 966.4829316315737, 985.280745056113, 25696.614550781753, 26149.0, 27966.580501292498, 29178.0, 29254.0, 1033.0, 1033.5710680067687, 1044.1125043712784, 1072.5280876414354, 1072.871255449351, 13109.88565971884, 13675.111117777313, 14165.78751318731, 14490.565283041526, 14821.629459263604, 500287.0, 503667.0, 505118.4744076256, 505232.0, 506292.4685376623, 57442.0, 57867.0, 57975.0, 58049.0, 58091.419085809444, 1249.312877917142, 1300.4343110747045, 1307.1176955555152, 1313.5665230473921, 1328.0504439325928, 26718.332041699112, 27206.863965680837, 27910.162695118368, 28158.577621336106, 28192.85903570469, 42860.78569607132, 42966.0, 43078.569214307456, 43120.78439607782, 43130.78434607807, 460021.0, 460637.0, 461637.0, 461858.6907065443, 463465.0, 34174.829125855205, 34582.0, 34884.0, 34898.82550587231, 34993.60025598948, 43329.56670433381, 43920.12159756723, 44590.77704611456, 44936.0, 45020.0, 27434.72565274322, 28050.158495245174, 29765.702342976292, 29989.550156748104, 30410.0, 28120.29699257535, 28719.98440163753, 28799.0, 28818.0, 28940.55297235172, 33034.504482432305, 33614.83192584021, 33805.0, 33960.83019584985, 34200.31599368049, 25068.0, 25716.742832572185, 28527.0, 28954.84180632751, 29126.7087329124, 21975.340739777832, 22652.730311571937, 22660.093596255963, 22696.432589185402, 22818.42953926165], "value": 18222.00062446129, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.94956655634763, 407.3360422511308, 424.1432306740385, 432.06026891510953, 447.988800279993, 12980.805287920677, 14708.70582588349, 20914.0, 20983.685244721342, 21596.2441314554, 17648.205830737614, 22108.447288817748, 22678.319650410474, 22908.541829163405, 23611.881940590272, 6287.0, 6582.506312026605, 7477.439192060618, 7634.5037572557885, 7639.433916047197, 6453.709258148366, 6548.607083575001, 7555.017847679794, 7577.355924746404, 7610.315071643551, 5803.767849286023, 6397.5521713480075, 7438.553686778783, 7510.399168066541, 7630.38956883448, 6047.546434017467, 6404.903926441107, 7648.0, 7657.11943126542, 7662.9233707663325, 7151.856962860766, 7362.889556656654, 7440.590767507785, 7444.0, 7460.142083660394, 21112.049957751937, 26319.34201644955, 26356.813943372403, 26890.32774180661, 27274.5908811368, 2921.532554791223, 2925.517289647206, 3074.892378766755, 3117.5635411042244, 3129.640091389497, 15770.76343854843, 16151.111688857109, 16214.675706485805, 16247.756283655755, 17202.913985430117, 1967.4294454608146, 1999.5001249687577, 2005.2780998840406, 2005.6690646043385, 2061.690746388039, 132305.338473308, 148317.0, 169893.0, 175440.12279938647, 176419.0, 19824.207031718568, 20613.484662883548, 20872.686909696367, 21261.574768504543, 21314.893425533082, 33460.494277757556, 33606.0, 33632.66367336296, 33707.49438758421, 33783.817566385056, 10351.430671312999, 10357.689269321932, 10512.21158413122, 10584.25910186295, 10772.330288805248, 8724.476531408009, 8767.517786521676, 8773.736787896374, 8943.955280223558, 8944.910550894538, 9354.953225233967, 9409.905900940903, 9488.00375960528, 9588.993155718688, 9627.374220675634, 3222.854971526287, 3378.223008708011, 3624.9125262421167, 3755.8122093895195, 3843.8846834595006, 985.3447457440865, 989.6486747204639, 992.5979978108877, 998.4159266828841, 1036.7511797168777, 18062.63874722498, 20744.792552074287, 21416.250431234832, 21431.999520067202, 21598.676019859715, 901.814114439523, 984.6652138272879, 990.1187942730975, 1015.6597539824241, 1027.845823126537, 8514.403991720645, 9685.988422805443, 10854.18593605467, 11145.2198346115, 11556.653300401002, 728055.0791738128, 733830.0, 760372.0, 762516.1874190704, 772264.0, 100023.0, 137680.31159844136, 148763.0, 160435.0, 167143.0, 1194.9223300485442, 1212.3029258176584, 1246.1339369138543, 1256.3215863433643, 1259.5465632372338, 19992.800071999674, 20955.847428391586, 21077.0, 21086.261980830597, 21177.576448471253, 62271.06593401012, 62561.374386257376, 62734.0, 63060.68469657622, 63452.365476346495, 73797.26202738119, 84759.5762021186, 96170.0, 98815.50592246992, 101285.0, 11137.832932505857, 11639.53441862316, 11773.0, 11917.821232681343, 11978.0, 11269.718257043312, 11938.58214962472, 12175.33035683046, 12467.189632673848, 12531.624051278111, 10208.64269750585, 10261.179105671677, 10517.9474102629, 10607.310524815863, 10702.250842440955, 8694.565271736514, 8742.91257087421, 8767.386282960131, 9994.650187243411, 10137.543810528692, 10534.736631584272, 10538.367697938149, 10918.344899306067, 10932.125429965743, 11012.944935275273, 11590.13074019435, 11648.576490256391, 11922.463489142836, 11941.402929853299, 11974.22167559106, 10494.317869338469, 10606.628767993083, 10698.73253168677, 10729.89270107289, 10775.461226938778], "value": 11536.893620460518, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1541.583772381457, 1561.3130222702002, 1569.4114706984888, 1587.5951632333754, 1589.9205039748012, 9381.906180938206, 10602.681919542416, 11100.55597776088, 11161.776764464707, 11172.217944743861, 10568.947155264232, 10992.890071099286, 11146.6098686546, 11164.051055660279, 11166.553337866475, 2399.5320912422085, 2421.9152329668464, 2432.8661923594195, 2439.1584903208363, 2441.3042282949273, 2332.8017118544944, 2407.205622144688, 2426.2478631624163, 2434.0385547708643, 2434.914777982771, 2345.3081341004445, 2401.8438801477846, 2420.697412823397, 2428.9149879754214, 2429.4533729910804, 2315.363275099361, 2369.5024044950583, 2389.7012873390827, 2394.1859767678984, 2395.7484464131276, 1910.8280254777137, 2380.345405013635, 2385.8210634202505, 2391.294568102414, 2400.099962514057, 10140.543675534545, 11022.724431889186, 11034.944825275903, 11038.613648522263, 11047.889521104766, 5725.656460612377, 5849.268841394825, 5938.0499120141, 5983.431574000463, 5987.341392446828, 3544.3974524330924, 3612.819359032038, 3620.4388319810473, 3704.2776658551593, 3710.3692372296773, 1161.6456980620908, 1165.452237448398, 1199.994000030003, 1215.3680086355134, 1222.718774681828, 13742.793858092136, 14094.859051409458, 14289.285535723277, 15396.461123860723, 15644.139572323518, 4218.915621687549, 4580.862574122782, 4598.770061496912, 4758.381410416693, 4768.284757286366, 11785.469653865597, 11806.704832379259, 11807.527698891949, 11833.585824496102, 11837.585684501173, 4866.853994380174, 5098.368522072901, 5552.805651802168, 5738.311402631711, 5801.767929282782, 5193.584513238932, 5256.816011439658, 5338.813141540105, 5431.972840135774, 5474.698891561003, 3845.7692538447222, 3865.1496670732395, 3892.2993861105274, 3934.8622798201927, 3937.940930886039, 1017.8778546574458, 1024.8360262357987, 1025.7845852371086, 1030.6804890483975, 1037.527924794217, 868.9746099602403, 952.33812500313, 962.2061799015839, 968.4625033106694, 1007.9294449388473, 5587.776488940397, 6501.642409667514, 6772.8984065239065, 6777.830554236183, 6871.278515755874, 906.3474298505066, 957.6210257229567, 965.285688590438, 969.5297780576415, 974.5906719177963, 3991.702696623609, 4086.734362266443, 4231.936520952188, 4251.234777739975, 4286.249906266391, 10889.727756805985, 14088.929555352157, 14093.647658808612, 14113.647158821112, 14135.575932722035, 12114.57598984049, 13414.865851341558, 13996.23020733849, 14012.0, 14026.228557429235, 10030.348027378197, 10180.541875615616, 10215.080642742189, 10328.896711032794, 10331.276810623334, 5114.641975061859, 6543.836404089936, 6554.410103090554, 6748.966255168692, 6749.223839258548, 10824.891751082389, 11904.583339583072, 12295.200811947196, 12445.0, 12447.253164810141, 11600.30398176112, 11622.244554103956, 12007.519699211934, 12055.397230138633, 12380.81428778551, 7885.250901164381, 9223.35436519464, 9253.907460925304, 9453.716388508356, 9458.33791634579, 10214.795704086026, 10436.739081523023, 10442.060214580724, 10447.164226861682, 10483.423411712123, 8434.24091831738, 8689.0, 8720.912790872264, 8773.780655483664, 8833.646654133763, 8564.957175214084, 8584.356173286904, 8637.8704319434, 8726.432781869158, 8826.338024648305, 7835.0, 7972.003499562555, 7975.521468711896, 7981.680732770857, 8003.199680032183, 5410.59420543453, 5413.621046526705, 5504.091824848855, 5613.354464236665, 5659.943400566106, 5937.584369094122, 5995.46040856325, 6001.549883758823, 6041.24484439445, 6089.725962331617], "value": 5092.143298468692, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.7910680465537, 381.6107570278316, 385.4603555022969, 386.8259283322502, 389.75445469354304, 5258.50044245797, 5307.52232299093, 5310.362756469224, 5314.627976041678, 5314.946850531498, 4335.036383341073, 4841.56425921667, 4841.661083724141, 5019.920717045835, 5055.848324550269, 3812.6759225465867, 3952.6837852971694, 4215.768132752697, 4219.578042195786, 4291.892702682442, 3806.7144964127665, 4272.551382104865, 4273.444452221207, 4277.572242775728, 4317.366406492038, 4067.7966101694947, 4185.623293903548, 4242.2788126018495, 4283.250431174548, 4300.741955482666, 3730.9253814923823, 4206.326987681987, 4219.683523735732, 4221.598948099926, 4227.619514243733, 3646.106703857545, 3672.05381147992, 3900.4149377593303, 3912.941305880414, 3968.7696813987754, 4331.487737112468, 4507.242175551538, 4678.883027924295, 4972.8259510917305, 5064.822731204427, 3815.9236815263816, 4558.51273775562, 4579.335996280554, 4708.529147085298, 4776.0, 4631.982546442143, 5393.973030134864, 5416.756245968941, 5434.098065677021, 5452.6455780374545, 3688.5942546319884, 3735.61782140607, 4056.5132184137797, 4066.7356621819486, 4321.595481468536, 4790.473047964721, 4898.338724272249, 5667.518260947824, 5680.318361796611, 5845.503132233765, 3558.8576456941432, 4111.773852438085, 4403.471583410012, 4446.466424029137, 4516.277395616686, 3908.9804550977065, 4142.440770496005, 4239.0674051708575, 4530.207213737583, 4556.453225612948, 3983.820728067244, 4352.521222665504, 4456.77716114193, 4609.124266389375, 4723.409573803275, 4667.369905062773, 4802.135615589227, 4883.560479556857, 5019.723915184627, 5118.436971933084, 1276.7424087274028, 1370.7464119137992, 1418.9432422703183, 1454.6581553334959, 1538.9846101538842, 1028.4806172882647, 1032.4063663393579, 1092.6451200511467, 1165.4056431220138, 1169.4328250798355, 4289.806958686867, 4576.496585375606, 5111.795528178831, 5125.923111153335, 5181.922271165936, 5371.892562148735, 5583.609147359646, 5589.496945274864, 5619.269494965628, 5656.632318899258, 4256.87229383119, 4641.791119399635, 4712.458067322234, 4961.975190124026, 5014.687368862775, 2726.922865468129, 2832.8866845325956, 2845.3070423098225, 2915.897943572007, 2935.8091724037868, 4890.413150421901, 5499.230107784913, 5749.8275051748515, 5794.335559874342, 5821.446962538636, 4460.219461594208, 4687.81248750053, 4869.878253043702, 5540.058190107691, 5856.619319744203, 5068.771905264272, 5169.922451163236, 5855.707214639251, 5872.853178670482, 5878.470937615635, 4617.89885602057, 4805.663603547858, 5502.111391519485, 5574.526165275997, 5645.774169033357, 4965.0, 4973.726445045412, 5020.598352131894, 5379.305518761577, 5680.00599895025, 5060.468650791761, 5073.847784566469, 5650.203149441053, 5653.971730141323, 5671.489565939085, 3941.704372172041, 4388.846390376321, 4420.447444069491, 4482.574155455227, 4514.525974772601, 4895.63282753802, 5274.393444753749, 5559.916601250903, 5666.065099258741, 5701.857453563694, 3964.337837435301, 4837.564619184291, 4904.890896914361, 5075.3148324975655, 5475.0, 4750.429948406075, 5395.298611180521, 5420.403755586961, 5455.754491047992, 5502.0371434997915, 4396.428464299621, 5104.2343648453025, 5295.311609490742, 5343.812966546152, 5344.679319240858, 4507.797149128362, 4636.83771068011, 5138.946515964143, 5280.788768449219, 5351.250824884597, 4641.860744177545, 5198.128673677548, 5348.304720386325, 5361.731913404392, 5417.241586178017], "value": 4093.2772623408678, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.5321738700177, 734.8713975054364, 762.0816915616682, 825.4345773145396, 852.9104444033375, 15121.697566048673, 15927.362905483767, 15959.12224827634, 15976.600584985366, 15979.041257524557, 14460.060096093774, 14495.49265775698, 14620.268986550684, 14629.195394253311, 14631.0, 933.0249888866117, 947.5072962059725, 948.5921053946801, 949.7862980829293, 951.8381875081253, 961.125375907924, 972.4019727867346, 974.0211087856694, 978.8433850583908, 980.9754756131118, 1014.1177175857015, 1015.2892974917551, 1016.4714348538793, 1017.0, 1017.1659239423675, 1007.3452256033563, 1011.6560369474414, 1013.5236438873718, 1013.6958912326272, 1013.8225810483173, 750.1598210004775, 751.1024325930515, 752.3078767533827, 752.6349720385608, 752.815560187752, 13578.0, 15923.964942278715, 16183.190840458048, 16301.755473667905, 16392.344306227737, 12596.433160507799, 14041.5085472008, 14251.57245282643, 14297.642558936006, 14313.49902753409, 10175.542100605491, 11693.941530292379, 11785.0, 11869.228500147463, 11928.880711192951, 3543.8050907200095, 3607.4588811678195, 3620.4931309616672, 3756.4553139794575, 3777.8299976501125, 85706.57146714226, 105238.47380763048, 108218.0, 108860.455697721, 108896.91103088947, 10694.946525267324, 10731.570737170581, 10793.946030269799, 10932.508037138348, 11081.390523521122, 22284.0, 22727.204547840745, 22755.31734047948, 22798.77201228, 22843.086276549086, 7690.961545192238, 7761.573113478813, 7863.292303692695, 7924.049114106344, 8032.83934321322, 4177.289860723684, 4182.916341673211, 4283.201055556674, 4291.334843099325, 4441.866743997685, 3536.7877927324444, 3637.927241455156, 3642.0894776305922, 3647.7811331320204, 3651.926961460756, 529.6851554875288, 543.7091156231397, 640.6444423344996, 641.1216633212435, 641.7593402474072, 648.1347401219425, 664.8271449423185, 677.4208052115468, 679.8470344172621, 690.8618276344752, 9424.764380890532, 10325.84511232332, 10347.844782328271, 10682.412467314218, 10684.94657526723, 594.3759052994338, 662.552776875607, 687.2817905288985, 703.5462126928103, 710.1761956130863, 4804.135255654016, 4991.450940396553, 5447.6459030163705, 5650.85872853182, 5681.886362272731, 83771.0, 103122.96877031284, 106913.0, 107327.64864851389, 114349.0, 73537.52924941471, 95734.0, 97963.0, 101788.49105754572, 102381.0, 73221.2677873225, 80462.59768701353, 82712.0, 86295.1370486287, 86873.56563217143, 10894.782104358026, 11008.724781880517, 11101.888981110085, 11197.38414387184, 11464.713382165179, 40459.59540404558, 50660.74669626628, 52256.47743522668, 53997.73001134969, 54005.91988160136, 65360.0, 65376.34623653703, 69008.6549567249, 70021.29978700148, 70133.0, 11611.954924056876, 12633.93683031579, 12925.741485170432, 12953.740925181255, 13427.865721342661, 14818.62953426173, 14910.85089149138, 15277.847221527643, 15731.921340393224, 16076.67846643084, 13682.52111176104, 14247.501337453139, 14475.493357732428, 14584.343704533108, 15050.698986020438, 13461.86538134606, 14082.929585352007, 14123.940704447003, 14458.927705361406, 14459.927700361432, 11389.658310250707, 12328.81506777381, 12876.678083047998, 14059.367328470447, 14508.419663213775, 9921.107100361001, 9995.70012899614, 10061.496925153859, 10401.635942741968, 10834.404107774148, 10915.779844945919, 11281.379524126252, 11289.943550282196, 11534.0, 11567.421628918688], "value": 7738.422422659801, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1456.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 114.0, 116.0, 115.0, 115.0, 117.0, 112.0, 124.0, 170.0], "value": 120.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 111.0, 109.0, 146.0, 110.0, 109.0, 108.0, 110.0, 129.0, 108.0], "value": 117.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 183.0, 191.0, 188.0, 183.0, 184.0, 185.0, 199.0, 263.0, 199.0], "value": 195.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 61.0, 60.0, 93.0, 51.0, 74.0, 84.0, 86.0, 92.0, 61.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 87.0, 70.0, 69.0, 68.0, 83.0, 69.0, 69.0, 84.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 87.0, 86.0, 86.0, 86.0, 86.0, 86.0, 96.0, 133.0], "value": 91.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 96.0, 93.0, 94.0, 92.0, 96.0, 93.0, 105.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 84.0, 85.0, 87.0, 85.0, 86.0, 83.0, 83.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 60.0, 64.0, 64.0, 65.0, 64.0, 64.0, 65.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 63.0, 63.0, 61.0, 61.0, 64.0, 84.0, 85.0, 84.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 87.0, 84.0, 83.0, 84.0, 85.0, 89.0, 84.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 128.0, 148.0, 156.0, 141.0, 135.0, 142.0, 145.0, 179.0, 147.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 165.0, 165.0, 164.0, 167.0, 180.0, 166.0, 168.0, 165.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 68.0, 78.0, 84.0, 83.0, 62.0, 67.0, 63.0], "value": 70.0, "unit": "score"}]}]} 04:13:35 INFO - Return code: 0 04:13:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:13:35 ERROR - # TBPL SUCCESS # 04:13:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:13:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:13:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:13:35 INFO - 2017-04-06 04:13:35.584 screenresolution[1655:10166] starting screenresolution argv=screenresolution get 04:13:35 INFO - 2017-04-06 04:13:35.587 screenresolution[1655:10166] Display 0: 1600x1200x32@60 04:13:35 INFO - 2017-04-06 04:13:35.594 screenresolution[1656:10169] starting screenresolution argv=screenresolution list 04:13:35 INFO - Available Modes on Display 0 04:13:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:13:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:13:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:13:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:13:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:13:35 INFO - Intel Iris: 04:13:35 INFO - Chipset Model: Intel Iris 04:13:35 INFO - Type: GPU 04:13:35 INFO - Bus: Built-In 04:13:35 INFO - VRAM (Dynamic, Max): 1536 MB 04:13:35 INFO - Vendor: Intel (0x8086) 04:13:35 INFO - Device ID: 0x0a2e 04:13:35 INFO - Revision ID: 0x0009 04:13:35 INFO - Displays: 04:13:35 INFO - Display: 04:13:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:13:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:13:35 INFO - Display Serial Number: 1600x1200 60 04:13:35 INFO - Main Display: Yes 04:13:35 INFO - Mirror: Off 04:13:35 INFO - Online: Yes 04:13:35 INFO - Rotation: Supported 04:13:35 INFO - Return code: 0 04:13:35 INFO - Running post-action listener: _resource_record_post_action 04:13:35 INFO - [mozharness: 2017-04-06 11:13:35.843390Z] Finished run-tests step (success) 04:13:35 INFO - Running post-run listener: _resource_record_post_run 04:13:35 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 47162880; Write bytes: 511374848; Read time: 480; Write time: 1872 04:13:35 INFO - TinderboxPrint: CPU usage
53.9% 04:13:35 INFO - TinderboxPrint: I/O read bytes / time
47,162,880 / 480 04:13:35 INFO - TinderboxPrint: I/O write bytes / time
511,374,848 / 1,872 04:13:35 INFO - TinderboxPrint: CPU idle
2,217.6 (46.1%) 04:13:35 INFO - TinderboxPrint: CPU system
959.6 (20.0%) 04:13:35 INFO - TinderboxPrint: CPU user
1,632.5 (33.9%) 04:13:35 INFO - TinderboxPrint: Swap in / out
261,480,448 / 0 04:13:35 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209420800; Write bytes: 209051648; Read time: 15965; Write time: 388 04:13:35 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 42288640; Write bytes: 299401216; Read time: 445; Write time: 1443 04:13:36 INFO - Running post-run listener: _upload_blobber_files 04:13:36 INFO - Blob upload gear active. 04:13:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:13:36 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 04:13:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:13:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:13:36 INFO - (blobuploader) - INFO - Open directory for files ... 04:13:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:13:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:13:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:13:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:13:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:13:37 INFO - (blobuploader) - INFO - Done attempting. 04:13:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:13:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:13:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:13:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:13:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:13:39 INFO - (blobuploader) - INFO - Done attempting. 04:13:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:13:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:13:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:13:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:13:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:13:39 INFO - (blobuploader) - INFO - Done attempting. 04:13:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:13:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:13:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:13:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:13:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:13:41 INFO - (blobuploader) - INFO - Done attempting. 04:13:41 INFO - (blobuploader) - INFO - Iteration through files over. 04:13:41 INFO - Return code: 0 04:13:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:13:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:13:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c19d6bd9beaec98bef084825e424922ff431f6c269f5e4376361baa18fef03095a47f8aa0e9e7d505f44396380c6097e3e3f8a71360a1d462ccd39a6ac805f73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33cfec9036ab6d625b146759feda119a3c8ddc974411ff902d3be7a0eadda12a83b97926d010247c361827de631234be4b8650083b9c2e2342a7f902c7d8d607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/03c4d7ae28cba2a19093cd1b0d0638329c49687833b56c41fc584a1e2191b76e893ae33844357c0e5db724cd02885519a5d0add661be3f1a034af678e798bbee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee6a4dfe54fb77c1f5faf7b59602aa7f308631f4927410df2f1dbfac960c34e1fbfffe8660ed54946156d10a7deb97fe69c1d1df71c0a6919e9f10bf1747d5b7"} 04:13:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:13:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:13:41 INFO - Contents: 04:13:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c19d6bd9beaec98bef084825e424922ff431f6c269f5e4376361baa18fef03095a47f8aa0e9e7d505f44396380c6097e3e3f8a71360a1d462ccd39a6ac805f73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33cfec9036ab6d625b146759feda119a3c8ddc974411ff902d3be7a0eadda12a83b97926d010247c361827de631234be4b8650083b9c2e2342a7f902c7d8d607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/03c4d7ae28cba2a19093cd1b0d0638329c49687833b56c41fc584a1e2191b76e893ae33844357c0e5db724cd02885519a5d0add661be3f1a034af678e798bbee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee6a4dfe54fb77c1f5faf7b59602aa7f308631f4927410df2f1dbfac960c34e1fbfffe8660ed54946156d10a7deb97fe69c1d1df71c0a6919e9f10bf1747d5b7"} 04:13:41 INFO - Running post-run listener: copy_logs_to_upload_dir 04:13:41 INFO - Copying logs to upload dir... 04:13:41 INFO - mkdir: /builds/slave/test/build/upload/logs 04:13:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.913988 ========= master_lag: 0.15 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-06 04:13:41.866890) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 04:13:41.870189) ========= 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.PZtcC0vGmQ/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.b43D8BbqoA/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c19d6bd9beaec98bef084825e424922ff431f6c269f5e4376361baa18fef03095a47f8aa0e9e7d505f44396380c6097e3e3f8a71360a1d462ccd39a6ac805f73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33cfec9036ab6d625b146759feda119a3c8ddc974411ff902d3be7a0eadda12a83b97926d010247c361827de631234be4b8650083b9c2e2342a7f902c7d8d607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/03c4d7ae28cba2a19093cd1b0d0638329c49687833b56c41fc584a1e2191b76e893ae33844357c0e5db724cd02885519a5d0add661be3f1a034af678e798bbee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee6a4dfe54fb77c1f5faf7b59602aa7f308631f4927410df2f1dbfac960c34e1fbfffe8660ed54946156d10a7deb97fe69c1d1df71c0a6919e9f10bf1747d5b7"} build_url:https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011403 build_url: 'https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c19d6bd9beaec98bef084825e424922ff431f6c269f5e4376361baa18fef03095a47f8aa0e9e7d505f44396380c6097e3e3f8a71360a1d462ccd39a6ac805f73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33cfec9036ab6d625b146759feda119a3c8ddc974411ff902d3be7a0eadda12a83b97926d010247c361827de631234be4b8650083b9c2e2342a7f902c7d8d607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/03c4d7ae28cba2a19093cd1b0d0638329c49687833b56c41fc584a1e2191b76e893ae33844357c0e5db724cd02885519a5d0add661be3f1a034af678e798bbee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee6a4dfe54fb77c1f5faf7b59602aa7f308631f4927410df2f1dbfac960c34e1fbfffe8660ed54946156d10a7deb97fe69c1d1df71c0a6919e9f10bf1747d5b7"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 04:13:41.909057) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 04:13:41.909416) ========= 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.PZtcC0vGmQ/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.b43D8BbqoA/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.005009 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 04:13:41.957715) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 04:13:41.958069) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 04:13:41.958411) ========= ========= Total master_lag: 0.30 =========