builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0181 starttime: 1491854371.77 results: success (0) buildid: 20170410111721 builduid: f3152acd87544a93acba3d280c44a51c revision: 8c1af10581c25ff8948370a4503101efb0c163d2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 12:59:31.771870) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 12:59:31.772344) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 12:59:31.772649) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 12:59:31.797971) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 12:59:31.798378) ========= 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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-10 12:59:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 66.8M=0s 2017-04-10 12:59:31 (66.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.108872 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 12:59:31.931058) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 12:59:31.931409) ========= 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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.020880 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 12:59:31.979993) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 12:59:31.980350) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8c1af10581c25ff8948370a4503101efb0c163d2 --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 8c1af10581c25ff8948370a4503101efb0c163d2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QSXNnVM9oc/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.A3bww8b2Ze/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-10 12:59:32,061 truncating revision to first 12 chars 2017-04-10 12:59:32,061 Setting DEBUG logging. 2017-04-10 12:59:32,062 attempt 1/10 2017-04-10 12:59:32,062 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8c1af10581c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-10 12:59:33,228 unpacking tar archive at: mozilla-aurora-8c1af10581c2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.421558 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 12:59:33.425611) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 12:59:33.425966) ========= script_repo_revision: 8c1af10581c25ff8948370a4503101efb0c163d2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 12:59:33.426432) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 12:59:33.426714) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 12:59:33.446691) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-10 12:59:33.447162) ========= /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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:59:33 INFO - MultiFileLogger online at 20170410 12:59:33 in /builds/slave/test 12:59:33 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 12:59:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:59:33 INFO - {'append_to_log': False, 12:59:33 INFO - 'base_work_dir': '/builds/slave/test', 12:59:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:59:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:59:33 INFO - 'branch': 'Mozilla-Aurora', 12:59:33 INFO - 'buildbot_json_path': 'buildprops.json', 12:59:33 INFO - 'config_files': ('talos/mac_config.py',), 12:59:33 INFO - 'default_actions': ('clobber', 12:59:33 INFO - 'read-buildbot-config', 12:59:33 INFO - 'download-and-extract', 12:59:33 INFO - 'populate-webroot', 12:59:33 INFO - 'create-virtualenv', 12:59:33 INFO - 'install', 12:59:33 INFO - 'run-tests'), 12:59:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:59:33 INFO - 'download_minidump_stackwalk': True, 12:59:33 INFO - 'download_symbols': 'ondemand', 12:59:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:59:33 INFO - 'tooltool.py': '/tools/tooltool.py', 12:59:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:59:33 INFO - '/tools/misc-python/virtualenv.py')}, 12:59:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:59:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:59:33 INFO - 'gecko_profile': False, 12:59:33 INFO - 'gecko_profile_interval': 0, 12:59:33 INFO - 'installer_path': 'installer.exe', 12:59:33 INFO - 'log_level': 'info', 12:59:33 INFO - 'log_name': 'talos', 12:59:33 INFO - 'log_to_console': True, 12:59:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:59:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:59:33 INFO - 'opt_config_files': (), 12:59:33 INFO - 'pip_index': False, 12:59:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:59:33 INFO - 'cmd': ('bash', 12:59:33 INFO - '-c', 12:59:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:59:33 INFO - 'enabled': True, 12:59:33 INFO - 'halt_on_failure': False, 12:59:33 INFO - 'name': 'check_screen_resolution'},), 12:59:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:59:33 INFO - 'cmd': ('bash', 12:59:33 INFO - '-c', 12:59:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:59:33 INFO - 'enabled': True, 12:59:33 INFO - 'halt_on_failure': False, 12:59:33 INFO - 'name': 'check_screen_resolution'},), 12:59:33 INFO - 'run_cmd_checks_enabled': True, 12:59:33 INFO - 'suite': 'dromaeojs', 12:59:33 INFO - 'system_bits': '32', 12:59:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:59:33 INFO - 'title': 't-yosemite-r7-0181', 12:59:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:59:33 INFO - 'use_talos_json': True, 12:59:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:59:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:59:33 INFO - 'work_dir': 'build'} 12:59:33 INFO - [mozharness: 2017-04-10 19:59:33.651925Z] Running clobber step. 12:59:33 INFO - Running pre-action listener: _resource_record_pre_action 12:59:33 INFO - Running main action method: clobber 12:59:33 INFO - rmtree: /builds/slave/test/build 12:59:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:59:35 INFO - Running post-action listener: _resource_record_post_action 12:59:35 INFO - [mozharness: 2017-04-10 19:59:35.346513Z] Finished clobber step (success) 12:59:35 INFO - [mozharness: 2017-04-10 19:59:35.346711Z] Running read-buildbot-config step. 12:59:35 INFO - Running pre-action listener: _resource_record_pre_action 12:59:35 INFO - Running main action method: read_buildbot_config 12:59:35 INFO - Using buildbot properties: 12:59:35 INFO - { 12:59:35 INFO - "project": "", 12:59:35 INFO - "product": "firefox", 12:59:35 INFO - "script_repo_revision": "production", 12:59:35 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:59:35 INFO - "repository": "", 12:59:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:59:35 INFO - "buildid": "20170410111721", 12:59:35 INFO - "pgo_build": "False", 12:59:35 INFO - "basedir": "/builds/slave/test", 12:59:35 INFO - "buildnumber": 46, 12:59:35 INFO - "slavename": "t-yosemite-r7-0181", 12:59:35 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:59:35 INFO - "platform": "yosemite_r7", 12:59:35 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:59:35 INFO - "branch": "mozilla-aurora", 12:59:35 INFO - "repo_path": "releases/mozilla-aurora", 12:59:35 INFO - "revision": "8c1af10581c25ff8948370a4503101efb0c163d2", 12:59:35 INFO - "stage_platform": "macosx64", 12:59:35 INFO - "builduid": "f3152acd87544a93acba3d280c44a51c", 12:59:35 INFO - "slavebuilddir": "test" 12:59:35 INFO - } 12:59:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 12:59:35 INFO - Running post-action listener: _resource_record_post_action 12:59:35 INFO - [mozharness: 2017-04-10 19:59:35.349980Z] Finished read-buildbot-config step (success) 12:59:35 INFO - [mozharness: 2017-04-10 19:59:35.350098Z] Running download-and-extract step. 12:59:35 INFO - Running pre-action listener: _resource_record_pre_action 12:59:35 INFO - Running main action method: download_and_extract 12:59:35 INFO - mkdir: /builds/slave/test/build/tests 12:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:35 INFO - trying https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 12:59:35 INFO - Downloading https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/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 12:59:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/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 12:59:36 INFO - Downloaded 1199 bytes. 12:59:36 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 12:59:36 INFO - Using the following test package requirements: 12:59:36 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 12:59:36 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 12:59:36 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 12:59:36 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 12:59:36 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 12:59:36 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 12:59:36 INFO - u'jsshell-mac.zip'], 12:59:36 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 12:59:36 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 12:59:36 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 12:59:36 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 12:59:36 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 12:59:36 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 12:59:36 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 12:59:36 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 12:59:36 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 12:59:36 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 12:59:36 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 12:59:36 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:59:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 12:59:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:59:36 INFO - Fetch https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 12:59:38 INFO - Expected file size: 51790590 12:59:38 INFO - Obtained file size: 51790590 12:59:54 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 12:59:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 12:59:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:59:54 INFO - Fetch https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 12:59:56 INFO - Expected file size: 51790590 12:59:56 INFO - Obtained file size: 51790590 13:00:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 13:00:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:00:11 INFO - Fetch https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 13:00:11 INFO - Expected file size: 14404424 13:00:11 INFO - Obtained file size: 14404424 13:00: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')]} 13:00:12 INFO - trying https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:00:12 INFO - Downloading https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 13:00:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/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 13:00:14 INFO - Downloaded 74688039 bytes. 13:00:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:00:14 INFO - mkdir: /builds/slave/test/properties 13:00:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:00:14 INFO - Writing to file /builds/slave/test/properties/build_url 13:00:14 INFO - Contents: 13:00:14 INFO - build_url:https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:00:15 INFO - Running post-action listener: _resource_record_post_action 13:00:15 INFO - Running post-action listener: set_extra_try_arguments 13:00:15 INFO - [mozharness: 2017-04-10 20:00:15.006264Z] Finished download-and-extract step (success) 13:00:15 INFO - [mozharness: 2017-04-10 20:00:15.006400Z] Running populate-webroot step. 13:00:15 INFO - Running pre-action listener: _resource_record_pre_action 13:00:15 INFO - Running main action method: populate_webroot 13:00:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:00:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:00:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:00:15 INFO - '--tppagecycles', 13:00:15 INFO - '7'], 13:00:15 INFO - 'tests': ['tsvgm']}}, 13:00:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:00:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:00:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:00:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:00:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:00:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:00:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:00:15 INFO - 'talos_options': ['--disable-e10s'], 13:00:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:00:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:00:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:00:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:00:15 INFO - 'talos_options': ['--disable-e10s'], 13:00:15 INFO - 'tests': ['damp', 'tps']}, 13:00:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:00:15 INFO - 'tests': ['damp', 'tps']}, 13:00:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:00:15 INFO - 'tests': ['dromaeo_dom']}, 13:00:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:00:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:00:15 INFO - 'tests': ['basic_compositor_video']}, 13:00:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:00:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:00:15 INFO - 'tests': ['a11yr', 13:00:15 INFO - 'ts_paint', 13:00:15 INFO - 'tpaint', 13:00:15 INFO - 'sessionrestore', 13:00:15 INFO - 'sessionrestore_no_auto_restore', 13:00:15 INFO - 'tabpaint']}, 13:00:15 INFO - 'other-e10s': {'tests': ['a11yr', 13:00:15 INFO - 'ts_paint', 13:00:15 INFO - 'tpaint', 13:00:15 INFO - 'sessionrestore', 13:00:15 INFO - 'sessionrestore_no_auto_restore', 13:00:15 INFO - 'tabpaint']}, 13:00:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:00:15 INFO - 'ts_paint', 13:00:15 INFO - 'tpaint', 13:00:15 INFO - 'sessionrestore', 13:00:15 INFO - 'sessionrestore_no_auto_restore', 13:00:15 INFO - 'tabpaint']}, 13:00:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:00:15 INFO - 'ts_paint', 13:00:15 INFO - 'tpaint', 13:00:15 INFO - 'sessionrestore', 13:00:15 INFO - 'sessionrestore_no_auto_restore', 13:00:15 INFO - 'tabpaint']}, 13:00:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:00:15 INFO - 'tests': ['a11yr', 13:00:15 INFO - 'ts_paint', 13:00:15 INFO - 'tpaint', 13:00:15 INFO - 'sessionrestore', 13:00:15 INFO - 'sessionrestore_no_auto_restore', 13:00:15 INFO - 'tabpaint']}, 13:00:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:00:15 INFO - 'tests': ['a11yr', 13:00:15 INFO - 'ts_paint', 13:00:15 INFO - 'tpaint', 13:00:15 INFO - 'sessionrestore', 13:00:15 INFO - 'sessionrestore_no_auto_restore', 13:00:15 INFO - 'tabpaint']}, 13:00:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:00:15 INFO - 'tests': ['tsvgx', 13:00:15 INFO - 'tsvgr_opacity', 13:00:15 INFO - 'tart', 13:00:15 INFO - 'tscrollx', 13:00:15 INFO - 'cart', 13:00:15 INFO - 'tsvg_static']}, 13:00:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:00:15 INFO - 'tsvgr_opacity', 13:00:15 INFO - 'tart', 13:00:15 INFO - 'tscrollx', 13:00:15 INFO - 'cart', 13:00:15 INFO - 'tsvg_static']}, 13:00:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:00:15 INFO - 'talos_options': ['--disable-e10s'], 13:00:15 INFO - 'tests': ['tp5o']}, 13:00:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:00:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:00:15 INFO - 'talos_options': ['--disable-e10s', 13:00:15 INFO - '--xperf_path', 13:00:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:00:15 INFO - 'tests': ['tp5n']}, 13:00:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:00:15 INFO - 'talos_options': ['--xperf_path', 13:00:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:00:15 INFO - 'tests': ['tp5n']}}, 13:00:15 INFO - 'talos.zip': {'path': '', 13:00:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:00:15 INFO - Running post-action listener: _resource_record_post_action 13:00:15 INFO - [mozharness: 2017-04-10 20:00:15.024424Z] Finished populate-webroot step (success) 13:00:15 INFO - [mozharness: 2017-04-10 20:00:15.024517Z] Running create-virtualenv step. 13:00:15 INFO - Running pre-action listener: _resource_record_pre_action 13:00:15 INFO - Running main action method: create_virtualenv 13:00:15 INFO - Creating virtualenv /builds/slave/test/build/venv 13:00:15 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 13:00:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:00:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:00:15 INFO - Using real prefix '/tools/python27' 13:00:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:00:15 INFO - Installing distribute.............................................................................................................................................................................................done. 13:00:18 INFO - Installing pip.................done. 13:00:18 INFO - Return code: 0 13:00:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:00:18 INFO - Reading from file tmpfile_stdout 13:00:18 INFO - Output received: 13:00:18 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 13:00:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:00: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')]} 13:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00: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')]} 13:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc58d738c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a660d30>, '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 0x10a587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a655be8>, '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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 13:00:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:00:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSXNnVM9oc/Render', 13:00:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:18 INFO - 'HOME': '/Users/cltbld', 13:00:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:18 INFO - 'LOGNAME': 'cltbld', 13:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:18 INFO - 'MOZ_NO_REMOTE': '1', 13:00:18 INFO - 'NO_EM_RESTART': '1', 13:00:18 INFO - 'PAGER': '/bin/cat', 13:00:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:18 INFO - 'PWD': '/builds/slave/test', 13:00:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:18 INFO - 'SHELL': '/bin/bash', 13:00:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A3bww8b2Ze/Listeners', 13:00:18 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 13:00:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:18 INFO - 'USER': 'cltbld', 13:00:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:18 INFO - 'XPC_FLAGS': '0x0', 13:00:18 INFO - 'XPC_SERVICE_NAME': '0', 13:00:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:19 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 13:00:19 INFO - Cleaning up... 13:00:19 INFO - Return code: 0 13:00:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc58d738c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a660d30>, '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 0x10a587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a655be8>, '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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 13:00:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:00:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:00:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSXNnVM9oc/Render', 13:00:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:19 INFO - 'HOME': '/Users/cltbld', 13:00:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:19 INFO - 'LOGNAME': 'cltbld', 13:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:19 INFO - 'MOZ_NO_REMOTE': '1', 13:00:19 INFO - 'NO_EM_RESTART': '1', 13:00:19 INFO - 'PAGER': '/bin/cat', 13:00:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:19 INFO - 'PWD': '/builds/slave/test', 13:00:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:19 INFO - 'SHELL': '/bin/bash', 13:00:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A3bww8b2Ze/Listeners', 13:00:19 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 13:00:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:19 INFO - 'USER': 'cltbld', 13:00:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:19 INFO - 'XPC_FLAGS': '0x0', 13:00:19 INFO - 'XPC_SERVICE_NAME': '0', 13:00:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:19 INFO - Downloading/unpacking psutil>=3.1.1 13:00:19 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 13:00:19 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 13:00:19 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 13:00:19 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 13:00:19 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 13:00:19 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 13:00:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:00:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:00:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:00:22 INFO - Installing collected packages: psutil 13:00:22 INFO - Running setup.py install for psutil 13:00:23 INFO - building 'psutil._psutil_osx' extension 13:00:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:00:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:00:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:00:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:00:23 INFO - building 'psutil._psutil_posix' extension 13:00:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:00:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:00:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:00:23 INFO - ^ 13:00:23 INFO - 1 warning generated. 13:00:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:00:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:00:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:00:23 INFO - Successfully installed psutil 13:00:23 INFO - Cleaning up... 13:00:23 INFO - Return code: 0 13:00:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc58d738c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a660d30>, '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 0x10a587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a655be8>, '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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 13:00:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:00:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:00:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSXNnVM9oc/Render', 13:00:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:23 INFO - 'HOME': '/Users/cltbld', 13:00:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:23 INFO - 'LOGNAME': 'cltbld', 13:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:23 INFO - 'MOZ_NO_REMOTE': '1', 13:00:23 INFO - 'NO_EM_RESTART': '1', 13:00:23 INFO - 'PAGER': '/bin/cat', 13:00:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:23 INFO - 'PWD': '/builds/slave/test', 13:00:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:23 INFO - 'SHELL': '/bin/bash', 13:00:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A3bww8b2Ze/Listeners', 13:00:23 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 13:00:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:23 INFO - 'USER': 'cltbld', 13:00:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:23 INFO - 'XPC_FLAGS': '0x0', 13:00:23 INFO - 'XPC_SERVICE_NAME': '0', 13:00:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:00:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:00:26 INFO - Installing collected packages: mozsystemmonitor 13:00:26 INFO - Running setup.py install for mozsystemmonitor 13:00:26 INFO - Successfully installed mozsystemmonitor 13:00:26 INFO - Cleaning up... 13:00:26 INFO - Return code: 0 13:00:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc58d738c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a660d30>, '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 0x10a587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a655be8>, '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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 13:00:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:00:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSXNnVM9oc/Render', 13:00:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:26 INFO - 'HOME': '/Users/cltbld', 13:00:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:26 INFO - 'LOGNAME': 'cltbld', 13:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:26 INFO - 'MOZ_NO_REMOTE': '1', 13:00:26 INFO - 'NO_EM_RESTART': '1', 13:00:26 INFO - 'PAGER': '/bin/cat', 13:00:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:26 INFO - 'PWD': '/builds/slave/test', 13:00:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:26 INFO - 'SHELL': '/bin/bash', 13:00:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A3bww8b2Ze/Listeners', 13:00:26 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 13:00:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:26 INFO - 'USER': 'cltbld', 13:00:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:26 INFO - 'XPC_FLAGS': '0x0', 13:00:26 INFO - 'XPC_SERVICE_NAME': '0', 13:00:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:27 INFO - Downloading/unpacking jsonschema==2.5.1 13:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:00:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00:30 INFO - Downloading functools32-3.2.3-2.tar.gz 13:00:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:00:30 INFO - warning: no files found matching '*.txt' 13:00:30 INFO - no previously-included directories found matching 'build' 13:00:30 INFO - no previously-included directories found matching 'dist' 13:00:30 INFO - no previously-included directories found matching '.git*' 13:00:30 INFO - Installing collected packages: jsonschema, functools32 13:00:30 INFO - Running setup.py install for functools32 13:00:30 INFO - warning: no files found matching '*.txt' 13:00:30 INFO - no previously-included directories found matching 'build' 13:00:30 INFO - no previously-included directories found matching 'dist' 13:00:30 INFO - no previously-included directories found matching '.git*' 13:00:30 INFO - Successfully installed jsonschema functools32 13:00:30 INFO - Cleaning up... 13:00:30 INFO - Return code: 0 13:00:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:31 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 0x10a882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc58d738c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a660d30>, '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 0x10a587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a655be8>, '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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 13:00:31 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 13:00:31 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 13:00:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSXNnVM9oc/Render', 13:00:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:31 INFO - 'HOME': '/Users/cltbld', 13:00:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:31 INFO - 'LOGNAME': 'cltbld', 13:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:31 INFO - 'MOZ_NO_REMOTE': '1', 13:00:31 INFO - 'NO_EM_RESTART': '1', 13:00:31 INFO - 'PAGER': '/bin/cat', 13:00:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:31 INFO - 'PWD': '/builds/slave/test', 13:00:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:31 INFO - 'SHELL': '/bin/bash', 13:00:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A3bww8b2Ze/Listeners', 13:00:31 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 13:00:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:31 INFO - 'USER': 'cltbld', 13:00:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:31 INFO - 'XPC_FLAGS': '0x0', 13:00:31 INFO - 'XPC_SERVICE_NAME': '0', 13:00:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:00:31 INFO - Cleaning up... 13:00:31 INFO - Return code: 0 13:00:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00: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', '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 0x10a882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc58d738c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a660d30>, '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 0x10a587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a655be8>, '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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 13:00: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:00: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 blobuploader==1.2.4 13:00:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSXNnVM9oc/Render', 13:00:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:32 INFO - 'HOME': '/Users/cltbld', 13:00:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:32 INFO - 'LOGNAME': 'cltbld', 13:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:32 INFO - 'MOZ_NO_REMOTE': '1', 13:00:32 INFO - 'NO_EM_RESTART': '1', 13:00:32 INFO - 'PAGER': '/bin/cat', 13:00:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:32 INFO - 'PWD': '/builds/slave/test', 13:00:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:32 INFO - 'SHELL': '/bin/bash', 13:00:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A3bww8b2Ze/Listeners', 13:00:32 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 13:00:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:32 INFO - 'USER': 'cltbld', 13:00:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:32 INFO - 'XPC_FLAGS': '0x0', 13:00:32 INFO - 'XPC_SERVICE_NAME': '0', 13:00:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:32 INFO - Downloading/unpacking blobuploader==1.2.4 13:00:32 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 13:00:32 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 13:00:32 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 13:00:32 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 13:00:32 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 13:00:32 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 13:00:35 INFO - Downloading blobuploader-1.2.4.tar.gz 13:00:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:00:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:00:35 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 13:00:35 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 13:00:35 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 13:00:35 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 13:00:35 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 13:00:35 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 13:00:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:00:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:00:36 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 13:00:36 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 13:00:36 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 13:00:36 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 13:00:36 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 13:00:36 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 13:00:36 INFO - Downloading docopt-0.6.1.tar.gz 13:00:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:00:36 INFO - Installing collected packages: blobuploader, requests, docopt 13:00:36 INFO - Running setup.py install for blobuploader 13:00:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:00:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:00:36 INFO - Running setup.py install for requests 13:00:37 INFO - Running setup.py install for docopt 13:00:37 INFO - Successfully installed blobuploader requests docopt 13:00:37 INFO - Cleaning up... 13:00:37 INFO - Return code: 0 13:00:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:00:37 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')]} 13:00:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:37 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')]} 13:00:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:37 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 0x10a882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc58d738c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a660d30>, '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 0x10a587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a655be8>, '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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 13:00:37 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 13:00:37 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 13:00:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSXNnVM9oc/Render', 13:00:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:37 INFO - 'HOME': '/Users/cltbld', 13:00:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:37 INFO - 'LOGNAME': 'cltbld', 13:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:37 INFO - 'MOZ_NO_REMOTE': '1', 13:00:37 INFO - 'NO_EM_RESTART': '1', 13:00:37 INFO - 'PAGER': '/bin/cat', 13:00:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:37 INFO - 'PWD': '/builds/slave/test', 13:00:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:37 INFO - 'SHELL': '/bin/bash', 13:00:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A3bww8b2Ze/Listeners', 13:00:37 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 13:00:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:37 INFO - 'USER': 'cltbld', 13:00:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:37 INFO - 'XPC_FLAGS': '0x0', 13:00:37 INFO - 'XPC_SERVICE_NAME': '0', 13:00:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:00:37 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-4Bhhn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:00:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:00:37 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-UEfJbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:00:38 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Drq1Lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:00:38 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-tTy5wY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:00:38 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-vMcTEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:00:38 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Mma1tY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:00:38 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-bv5OyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:00:38 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-OQoXO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:00:38 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-09XfEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:00:38 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-csYmUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:00:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:00:38 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-mq5Q7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:00:39 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-O2dQAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:00:39 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-9V4OO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:00:39 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-tVglDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:39 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-nfbTw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:00:39 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-k2tnpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:00:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:00:39 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-HJbE9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:00:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:00:39 INFO - Running setup.py install for manifestparser 13:00:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:00:39 INFO - Running setup.py install for mozcrash 13:00:39 INFO - Running setup.py install for mozdebug 13:00:40 INFO - Running setup.py install for mozdevice 13:00:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:00:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:00:40 INFO - Running setup.py install for mozfile 13:00:40 INFO - Running setup.py install for mozhttpd 13:00:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:00:40 INFO - Running setup.py install for mozinfo 13:00:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:00:40 INFO - Running setup.py install for mozInstall 13:00:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:00:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:00:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:00:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:00:40 INFO - Running setup.py install for mozleak 13:00:40 INFO - Running setup.py install for mozlog 13:00:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:00:41 INFO - Running setup.py install for moznetwork 13:00:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:00:41 INFO - Running setup.py install for mozprocess 13:00:41 INFO - Running setup.py install for mozprofile 13:00:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:00:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:00:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:00:41 INFO - Running setup.py install for mozrunner 13:00:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:00:41 INFO - Running setup.py install for mozscreenshot 13:00:41 INFO - Running setup.py install for moztest 13:00:42 INFO - Running setup.py install for mozversion 13:00:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:00:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:00:42 INFO - Cleaning up... 13:00:42 INFO - Return code: 0 13:00:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:00:42 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')]} 13:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:42 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')]} 13:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:42 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 0x10a882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc58d738c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a660d30>, '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 0x10a587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a655be8>, '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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 13:00:42 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 13:00:42 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 13:00:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSXNnVM9oc/Render', 13:00:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:42 INFO - 'HOME': '/Users/cltbld', 13:00:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:42 INFO - 'LOGNAME': 'cltbld', 13:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:42 INFO - 'MOZ_NO_REMOTE': '1', 13:00:42 INFO - 'NO_EM_RESTART': '1', 13:00:42 INFO - 'PAGER': '/bin/cat', 13:00:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:42 INFO - 'PWD': '/builds/slave/test', 13:00:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:42 INFO - 'SHELL': '/bin/bash', 13:00:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A3bww8b2Ze/Listeners', 13:00:42 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 13:00:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:42 INFO - 'USER': 'cltbld', 13:00:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:42 INFO - 'XPC_FLAGS': '0x0', 13:00:42 INFO - 'XPC_SERVICE_NAME': '0', 13:00:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:00:42 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Unt2Jm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:00:42 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)) 13:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:00:42 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Fqvq7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:00:42 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)) 13:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:00:42 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-mLJk9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:00:42 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)) 13:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:00:42 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-i3R3ZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:00:42 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)) 13:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:00:42 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-WEPtgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:00:43 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)) 13:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:00:43 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-5vdIK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:00:43 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)) 13:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:00:43 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-FosfNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:00:43 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)) 13:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:00:43 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-49dOA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:00:43 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)) 13:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:00:43 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-4QS1Ju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:00:43 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)) 13:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:00:43 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-xGsgT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:00:43 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)) 13:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:00:43 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-99wWRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:00:43 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)) 13:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:00:43 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-4ch8wv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:00:43 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)) 13:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:00:43 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-HXSYaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:00:43 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)) 13:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:00:43 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-m7lZ1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:00:43 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)) 13:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:43 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-TN997z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:00:44 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)) 13:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:00:44 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-w7fkcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:00:44 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)) 13:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:00:44 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-z2fvbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:00:44 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)) 13:00:44 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)) 13:00:44 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)) 13:00:44 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)) 13:00:44 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)) 13:00:44 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)) 13:00:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:00:44 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 13:00:44 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 13:00:44 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 13:00:44 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 13:00:44 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 13:00:44 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 13:00:46 INFO - Downloading blessings-1.6.tar.gz 13:00:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:00:47 INFO - Installing collected packages: blessings 13:00:47 INFO - Running setup.py install for blessings 13:00:47 INFO - Successfully installed blessings 13:00:47 INFO - Cleaning up... 13:00:47 INFO - Return code: 0 13:00:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:00:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:00:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:00:47 INFO - Reading from file tmpfile_stdout 13:00:47 INFO - Current package versions: 13:00:47 INFO - blessings == 1.6 13:00:47 INFO - blobuploader == 1.2.4 13:00:47 INFO - docopt == 0.6.1 13:00:47 INFO - functools32 == 3.2.3-2 13:00:47 INFO - jsonschema == 2.5.1 13:00:47 INFO - manifestparser == 1.1 13:00:47 INFO - mozInstall == 1.12 13:00:47 INFO - mozcrash == 1.0 13:00:47 INFO - mozdebug == 0.1 13:00:47 INFO - mozdevice == 0.49 13:00:47 INFO - mozfile == 1.2 13:00:47 INFO - mozhttpd == 0.7 13:00:47 INFO - mozinfo == 0.9 13:00:47 INFO - mozleak == 0.1 13:00:47 INFO - mozlog == 3.4 13:00:47 INFO - moznetwork == 0.27 13:00:47 INFO - mozprocess == 0.25 13:00:47 INFO - mozprofile == 0.28 13:00:47 INFO - mozrunner == 6.13 13:00:47 INFO - mozscreenshot == 0.1 13:00:47 INFO - mozsystemmonitor == 0.3 13:00:47 INFO - moztest == 0.8 13:00:47 INFO - mozversion == 1.4 13:00:47 INFO - psutil == 3.1.1 13:00:47 INFO - requests == 1.2.3 13:00:47 INFO - wsgiref == 0.1.2 13:00:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:00:47 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')]} 13:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:47 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')]} 13:00:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:47 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 0x10a882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc58d738c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a660d30>, '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 0x10a587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a655be8>, '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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 13:00:47 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 13:00:47 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 13:00:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSXNnVM9oc/Render', 13:00:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:47 INFO - 'HOME': '/Users/cltbld', 13:00:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:47 INFO - 'LOGNAME': 'cltbld', 13:00:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:47 INFO - 'MOZ_NO_REMOTE': '1', 13:00:47 INFO - 'NO_EM_RESTART': '1', 13:00:47 INFO - 'PAGER': '/bin/cat', 13:00:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:47 INFO - 'PWD': '/builds/slave/test', 13:00:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:47 INFO - 'SHELL': '/bin/bash', 13:00:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A3bww8b2Ze/Listeners', 13:00:47 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 13:00:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:47 INFO - 'USER': 'cltbld', 13:00:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:47 INFO - 'XPC_FLAGS': '0x0', 13:00:47 INFO - 'XPC_SERVICE_NAME': '0', 13:00:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:47 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)) 13:00:47 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)) 13:00:47 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)) 13:00:47 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)) 13:00:47 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)) 13:00:47 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)) 13:00:47 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)) 13:00:47 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)) 13:00:47 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)) 13:00:47 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)) 13:00:47 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)) 13:00:47 INFO - Cleaning up... 13:00:47 INFO - Return code: 0 13:00:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:00:47 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')]} 13:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:47 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')]} 13:00:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc58d738c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a660d30>, '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 0x10a587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a655be8>, '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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 13:00:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:00:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:00:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSXNnVM9oc/Render', 13:00:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:47 INFO - 'HOME': '/Users/cltbld', 13:00:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:47 INFO - 'LOGNAME': 'cltbld', 13:00:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:47 INFO - 'MOZ_NO_REMOTE': '1', 13:00:47 INFO - 'NO_EM_RESTART': '1', 13:00:47 INFO - 'PAGER': '/bin/cat', 13:00:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:47 INFO - 'PWD': '/builds/slave/test', 13:00:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:47 INFO - 'SHELL': '/bin/bash', 13:00:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A3bww8b2Ze/Listeners', 13:00:47 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 13:00:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:47 INFO - 'USER': 'cltbld', 13:00:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:47 INFO - 'XPC_FLAGS': '0x0', 13:00:47 INFO - 'XPC_SERVICE_NAME': '0', 13:00:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:00:48 INFO - Cleaning up... 13:00:48 INFO - Return code: 0 13:00:48 INFO - Running post-action listener: _resource_record_post_action 13:00:48 INFO - Running post-action listener: _start_resource_monitoring 13:00:48 INFO - Starting resource monitoring. 13:00:48 INFO - [mozharness: 2017-04-10 20:00:48.133004Z] Finished create-virtualenv step (success) 13:00:48 INFO - [mozharness: 2017-04-10 20:00:48.134038Z] Running install step. 13:00:48 INFO - Running pre-action listener: _resource_record_pre_action 13:00:48 INFO - Running main action method: install 13:00:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:00:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:00:48 INFO - Reading from file tmpfile_stdout 13:00:48 INFO - Detecting whether we're running mozinstall >=1.0... 13:00:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:00:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:00:48 INFO - Reading from file tmpfile_stdout 13:00:48 INFO - Output received: 13:00:48 INFO - Usage: mozinstall [options] installer 13:00:48 INFO - Options: 13:00:48 INFO - -h, --help show this help message and exit 13:00:48 INFO - -d DEST, --destination=DEST 13:00:48 INFO - Directory to install application into. [default: 13:00:48 INFO - "/builds/slave/test"] 13:00:48 INFO - --app=APP Application being installed. [default: firefox] 13:00:48 INFO - mkdir: /builds/slave/test/build/application 13:00:48 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'] 13:00:48 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 13:01:08 INFO - Reading from file tmpfile_stdout 13:01:08 INFO - Output received: 13:01:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:01:08 INFO - Running post-action listener: _resource_record_post_action 13:01:08 INFO - [mozharness: 2017-04-10 20:01:08.632790Z] Finished install step (success) 13:01:08 INFO - [mozharness: 2017-04-10 20:01:08.632943Z] Running run-tests step. 13:01:08 INFO - Running pre-action listener: _resource_record_pre_action 13:01:08 INFO - Running main action method: run_tests 13:01:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:01:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:01:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:01:08 INFO - 2017-04-10 13:01:08.647 screenresolution[1921:13731] starting screenresolution argv=screenresolution get 13:01:08 INFO - 2017-04-10 13:01:08.650 screenresolution[1921:13731] Display 0: 1600x1200x32@60 13:01:08 INFO - 2017-04-10 13:01:08.656 screenresolution[1922:13733] starting screenresolution argv=screenresolution list 13:01:08 INFO - Available Modes on Display 0 13:01:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:01:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:01:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:01:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:01:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:01:08 INFO - Intel Iris: 13:01:08 INFO - Chipset Model: Intel Iris 13:01:08 INFO - Type: GPU 13:01:08 INFO - Bus: Built-In 13:01:08 INFO - VRAM (Dynamic, Max): 1536 MB 13:01:08 INFO - Vendor: Intel (0x8086) 13:01:08 INFO - Device ID: 0x0a2e 13:01:08 INFO - Revision ID: 0x0009 13:01:08 INFO - Displays: 13:01:08 INFO - Display: 13:01:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:01:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:01:08 INFO - Display Serial Number: 1600x1200 60 13:01:08 INFO - Main Display: Yes 13:01:08 INFO - Mirror: Off 13:01:08 INFO - Online: Yes 13:01:08 INFO - Rotation: Supported 13:01:08 INFO - Return code: 0 13:01:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:01:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:01:08 INFO - Python 2.7.3 13:01:08 INFO - Return code: 0 13:01:08 INFO - grabbing minidump binary from tooltool 13:01: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')]} 13:01:08 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 0x10a660d30>, '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 0x10a587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a655be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:01:08 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 13:01:08 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 13:01:08 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 13:01:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:01:09 INFO - Return code: 0 13:01:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:01:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:01:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:01:09 INFO - ENV: RUST_BACKTRACE is now 1 13:01:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:01:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:01:09 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/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 13:01:09 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/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0181 --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 13:01:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSXNnVM9oc/Render', 13:01:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:09 INFO - 'HOME': '/Users/cltbld', 13:01:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:09 INFO - 'LOGNAME': 'cltbld', 13:01:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:01:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:09 INFO - 'MOZ_NO_REMOTE': '1', 13:01:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:01:09 INFO - 'NO_EM_RESTART': '1', 13:01:09 INFO - 'PAGER': '/bin/cat', 13:01:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:09 INFO - 'PWD': '/builds/slave/test', 13:01:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:01:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:09 INFO - 'RUST_BACKTRACE': '1', 13:01:09 INFO - 'SHELL': '/bin/bash', 13:01:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A3bww8b2Ze/Listeners', 13:01:09 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 13:01:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:09 INFO - 'USER': 'cltbld', 13:01:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:09 INFO - 'XPC_FLAGS': '0x0', 13:01:09 INFO - 'XPC_SERVICE_NAME': '0', 13:01:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:09 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/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 13:01:09 INFO - mozversion application_buildid: 20170410111721 13:01:09 INFO - mozversion application_changeset: 8c1af10581c25ff8948370a4503101efb0c163d2 13:01:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:01:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:01:09 INFO - mozversion application_name: Firefox 13:01:09 INFO - mozversion application_remotingname: firefox-dev 13:01:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:01:09 INFO - mozversion application_vendor: Mozilla 13:01:09 INFO - mozversion application_version: 54.0a2 13:01:09 INFO - mozversion platform_buildid: 20170410111721 13:01:09 INFO - mozversion platform_changeset: 8c1af10581c25ff8948370a4503101efb0c163d2 13:01:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:01:09 INFO - mozversion platform_version: 54.0a2 13:01:09 INFO - using testdate: 1491854469 13:01:09 INFO - actual date: 1491854469 13:01:09 INFO - starting webserver on 'localhost:51536' 13:01:09 INFO - SUITE-START | Running 2 tests 13:01:09 INFO - TEST-START | dromaeo_css 13:01:09 INFO - operating with platform_type : mac_ 13:01:09 INFO - Initialising browser for dromaeo_css test... 13:01:09 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmp_g_WLV/profile http://localhost:51536/getInfo.html) 13:01:11 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 13:01:11 INFO - PROCESS | 1932 | colorDepth:24 13:01:11 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 13:01:11 INFO - PROCESS | 1932 | __metrics 13:01:11 INFO - PROCESS | 1932 | 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" 13:01:11 INFO - PROCESS | 1932 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:01:12 INFO - TEST-INFO | 1932: exit 0 13:01:12 INFO - Browser initialized. 13:01:12 INFO - Running cycle 1/1 for dromaeo_css test... 13:01:12 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmp_g_WLV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:01:24 INFO - PROCESS | 1934 | RSS: Main: 214917120 13:01:24 INFO - PROCESS | 1934 | 13:04:28 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:51536/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51536/tests/dromaeo/cssquery-ext.html) 13:04:31 INFO - PROCESS | 1934 | RSS: Main: 401117184 13:04:31 INFO - PROCESS | 1934 | 13:06:14 INFO - PROCESS | 1934 | ************************* 13:06:14 INFO - PROCESS | 1934 | A coding exception was thrown and uncaught in a Task. 13:06:14 INFO - PROCESS | 1934 | 13:06:14 ERROR - PROCESS | 1934 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:06:14 INFO - PROCESS | 1934 | Full stack: 13:06:14 INFO - PROCESS | 1934 | ************************* 13:06:14 INFO - PROCESS | 1934 | ************************* 13:06:14 INFO - PROCESS | 1934 | A coding exception was thrown and uncaught in a Task. 13:06:14 INFO - PROCESS | 1934 | 13:06:14 ERROR - PROCESS | 1934 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:06:14 INFO - PROCESS | 1934 | Full stack: 13:06:14 INFO - PROCESS | 1934 | ************************* 13:06:14 INFO - PROCESS | 1934 | ************************* 13:06:14 INFO - PROCESS | 1934 | A coding exception was thrown and uncaught in a Task. 13:06:14 INFO - PROCESS | 1934 | 13:06:14 ERROR - PROCESS | 1934 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:06:14 INFO - PROCESS | 1934 | Full stack: 13:06:14 INFO - PROCESS | 1934 | ************************* 13:06:14 INFO - PROCESS | 1934 | ************************* 13:06:14 INFO - PROCESS | 1934 | A coding exception was thrown and uncaught in a Task. 13:06:14 INFO - PROCESS | 1934 | 13:06:14 ERROR - PROCESS | 1934 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:06:14 INFO - PROCESS | 1934 | Full stack: 13:06:14 INFO - PROCESS | 1934 | ************************* 13:07:34 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:51536/tests/dromaeo/cssquery-ext.html (next: http://localhost:51536/tests/dromaeo/cssquery-jquery.html) 13:07:34 INFO - PROCESS | 1934 | RSS: Main: 350060544 13:07:34 INFO - PROCESS | 1934 | 13:10:37 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:51536/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51536/tests/dromaeo/cssquery-mootools.html) 13:10:37 INFO - PROCESS | 1934 | RSS: Main: 348368896 13:10:37 INFO - PROCESS | 1934 | 13:13:40 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:51536/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51536/tests/dromaeo/cssquery-prototype.html) 13:13:41 INFO - PROCESS | 1934 | RSS: Main: 325398528 13:13:41 INFO - PROCESS | 1934 | 13:16:44 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:51536/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51536/tests/dromaeo/cssquery-yui.html) 13:16:45 INFO - PROCESS | 1934 | RSS: Main: 329646080 13:16:45 INFO - PROCESS | 1934 | 13:19:49 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:51536/tests/dromaeo/cssquery-yui.html (next: http://localhost:51536/tests/dromaeo/cssquery-dojo.html) 13:19:49 INFO - PROCESS | 1934 | RSS: Main: 459169792 13:19:49 INFO - PROCESS | 1934 | 13:19:49 INFO - PROCESS | 1934 | __start_tp_report 13:19:49 INFO - PROCESS | 1934 | _x_x_mozilla_page_load 13:19:49 INFO - PROCESS | 1934 | _x_x_mozilla_page_load_details 13:19:49 INFO - PROCESS | 1934 | |i|pagename|runs| 13:19:49 INFO - PROCESS | 1934 | |0;dojo.html;3162.6204855417345;3329.733621310294;3367.7137443317324;3371.983140084299;3415.1120708615754;5958.374370691075;5959.284885813698;5963.642181469115;5975.671338076404;6000.459958603725;5963.135345374918;6010.669413182273;6022.156898034268;6024.2168518092585;6045.123457098718;5368.597355198366;5409.702466364349;5413.242146099529;5452.700101494416;5501.394846566875;5304.230886521452;5309.548688361494;5341.599380046493;5498.5666769879335;5560.2493663355335;5365.463453654643;5393.352797664285;5403.108487099644;5404.027275090482;5405.810796622119;5374.355077390738;5395.7032363220005;5399.83800485986;5411.485908838694;5419.864503387407;4448.243798554254;5205.713685747282;5322.308099946982;5323.733813309319;5338.5996050296;5956.37458066901;5957.553183511211;5960.04639257721;5962.07587823888;5973.163757074013;6239.126522286883;6244.500439964837;6258.499320054385;6281.152044473984;6283.654399008053;6334.113224148623;6346.714397852108;6347.650879201642;6349.492040636738;6356.141920840673;5981.0430331147245;6021.5182785377065;6038.516918646542;6068.3021452533085;6068.544859135583;5435.211894275308;6242.687865606792;6282.246130464374;6300.928842096854;6463.935360646427;6318.241810982711;6368.840778980562;6372.6495042773095;6377.394147556068;6389.584676995981;6344.080108384258;6354.745810167541;6359.109724638554;6363.745450182034;6365.61806291624;6229.56393052482;6319.526035547352;6325.588836725598;6333.904991425133;6346.30190679025;6210.099535567318;6271.749130034838;6302.338254483213;6333.936660633427;6349.68251587428;5150.613703972217;5158.3552055993005;5164.0962831505085;5178;5191.299174611455;4495.572920572606;4555.248384016666;4564.497905230422;4574.268117101248;4588.931166032512;5802.303723553201;5840.970795145997;5881.882362352729;5966.373530779292;6032.426919442646;6363.109164716943;6364.809055728336;6373.74505019794;6379.96810015947;6391.904121438182;6006.159137720722;6006.639601623917;6015.879682406327;6020.157177995085;6020.909686354708;5397.541208997201;5501.642393244426;5649.943500565024;5654.067078932012;5657.038303488417;6304.05439184126;6354.968225158845;6358.205224346957;6367.044943258548;6376.171097757262;6313.337099604475;6323.936760632335;6337.207849018872;6341.112244285896;6353.174087368612;6456.257530384066;6479.287278399466;6548.246951600437;6559.409653131241;6582.934170658232;6321.273053598711;6325.715342809676;6333.714982825692;6336.049592561153;6353.205849268841;6196.504279657706;6329.272133704683;6337.112804207507;6347.174867267226;6368.64972426521;6055.091736239423;6125.540584456273;6138.235132557408;6237.8752424950335;6246.187995560548;5260.973695131653;6285.654289013965;6317.526185536011;6322.462590679844;6323.399277068671;6151.661658608819;6161.229846269216;6170.352113028068;6183.227096612924;6189.690515474298;6101.389861013863;6108.311716708011;6166.938330616816;6287.30839607652;6331.620102793847;6019.217501724747;6030.638161710312;6039.818805435844;6043.153958445733;6061.121137435135 13:19:49 INFO - PROCESS | 1934 | |1;ext.html;794.8648729715948;819.1726356380057;867.0679020053444;888.9022207557167;972.5526257921356;32489.350212995727;37531.62468375315;37813.24373512528;37950.861474155754;39083.63207287745;37986.810065949634;38264.234715305676;38273.234535309275;38288;38293.234135317274;7421.740239091633;7620.199879012679;7831.099423566306;7994.080680721733;8004.319632831187;7476.775696729107;7555.357794587467;7563.924360756405;10996.28524145928;11285.774284514306;11189.552417903275;11197.552097916074;11200.327980321166;11242.156838237124;11250.606228781995;11107.278026928265;11130.666080017612;11134.944325278402;11141.997220250216;11147.996680298731;7304.196538380774;7466.328030477229;10744;10892.019718225398;10954.068904143158;38770.61229387727;39011.829645110694;39024.414633780514;39126.80436597799;39128.804355978034;3859.8842034739005;3884.3396622574232;3909.2181563687377;3921.1961547882615;3936.2259537413665;27028.459430811272;27116.45767084667;27348;28112.71887281142;28616.248842142948;3050.1612056684367;3058.143719758471;3059.0669845697053;3285.4086264472385;3286.5398844161837;306752;307853;307934;308077.4596127005;309378.45310773526;51151.74424127855;51231.463056108376;51492.227616585784;51548.48451515512;51576.742116289926;53242;53281.667958301354;53370.932581348156;53386;53404;14583.05210161336;14651;14682;14686.412543498354;14720.895932805082;12738.490460381667;12747.235165889892;12750.936245318713;12765.42555585001;12767.297798620979;10323.690289291333;12046.457909394097;12089.576864809866;12117;12213.75572488563;5327.3074500314715;5388.407275199725;5389.380221274603;5390.730463476811;5401.675899446046;1062.888396718349;1122.6239209864623;1129.447010360754;1143.2168964259508;1147.502509225452;25533.340332878302;25672.743272567408;25917.870410647825;26122.477550449265;26170.084047058263;1223.3516236394726;1240.55340077572;1242.5340497313507;1243.7077286837587;1294.6504443800309;13205.273709945854;13267.867321326856;13402.128861623964;13452;13462.461501539829;433202;433297;433333;434371;434395;55485;55617;55665.16502252469;55675;55737;1254.830597869276;1278.4630455208835;1416.8441471438134;1473.1602986297708;1474.793528905954;19746.40760777179;29980.100596982127;30266.697333026386;30304.84847575748;30863;52511;52726;52799.208011879084;52807.207891880884;52840;468080;471879;472687;472898;473074.63462682464;26528;34622.307553849285;34755.82622086874;34815.60737570469;34844.65155348516;35822.46266305955;36900.81549592235;37011.44482832706;37020.814895925345;37093.62906370902;24684.629730554414;25727.84224709855;26412.339691507867;26861;26950.921963121255;26057.218283451526;26338.4732305349;26612.60081098824;26850;27007.1897843065;25038.62442063334;30685.07944761661;31215.37569248557;31754.682453175174;32033.679663204002;28152.85923570369;28299.43401132007;28859;29162.416751664423;29170.562441562968;22035.118595256652;23140.603285375724;23275.76724232736;23413.648795268426;23555.88222058879 13:19:49 INFO - PROCESS | 1934 | |2;jquery.html;370.60900749709054;392.13142888502;409.8934277087958;424.7600105940144;456.83782257298657;12645.683857903545;14591.416343346267;20735.68896466552;20801.16795328189;21351;21231.363059108215;21607.351779446642;21978.120875165016;22023.559528809415;22250.777492225072;5685;6268.185135932334;6286.811395658137;6524.412802847743;6561.80314590563;4070.2470043041985;4307.935380969288;6500.41496265336;6519.445847103002;6586.769463068794;6473.546851720382;7037.718491260343;7368.189499155089;7470.47706660534;7561.357284630813;7185.676644550982;7394.37147842434;7473;7480.4389670774635;7489.775306740807;7022.613756243404;7165.7491987780695;7253.673584688701;7266.200717921024;7272.672729727175;25982.87008564945;26106.216813495437;26138.34654133643;26156.346091347677;26436.206913792616;3269.395161895057;3286.556314897482;3405.7105146062613;3419.991102624732;3533.94699079514;15145;15439.536813895602;15716.135612541306;15831.050136991817;16621.584460388585;1680.1179380825042;1702.1574320711204;1705.3775371989134;1717.1500107446893;1726.7237242041338;137193;138293.6170638291;139206;140030.89953650703;140505.5949440513;15645.69743320596;21168.047437865334;21383.893080534497;21524.35426937163;21605.78394216038;34586.48120278198;36252;36293.81853090718;36298.637013630054;36357.45463818045;9014;10621.787564248827;10814.621488247874;10835.891641083646;10995.285306455055;7173.390261827699;8785.912140878509;8811.339149563808;8964.058773828785;8966.910330896739;9847.655332063343;9967.750806229902;10031.197504199647;10165.644202452879;10191.694249172537;3290.9177270568425;3292.7859689120132;4025.041594428093;4166.91666166681;4217.683673724483;924.4407133684024;940.9200217981548;943.6697155995485;950.2065775077749;950.8668786369914;18274.45176644703;20950.685739713917;22555;22604.886975565016;22619.88690056539;959.8320293948531;960.7454024683465;968.0948313326921;979.6522234606755;984.8916619171886;11206.327620342805;11703.091354602415;11763.764724705457;11778.52885884555;11795.941020294844;746144;751665;758176;764747;766016;115368.42315788368;122845;132928;136699.31650341884;139010;1312.566852938528;1329.5944736855351;1346.6229455752405;1351.398627610704;1353.6074538384153;18753.343632973327;18907.62184756325;19131.521711957314;19138.52153696169;19179.424617261506;41296.38055429111;51561.7421912888;53549.66125846885;55842.72078639743;57465.850682986944;77822.2217777815;78713;78895.21104788879;79390.01524961922;81201.78197326927;8398.370122240734;9298.72103836886;11956.641300760992;12078.456469459072;12129.150959432754;7985.560794156145;10866.61966831157;12923.547675831302;13006.609801705965;13373.933130334286;11343.659690209306;11360.886391136313;11565.306081634792;11671.12466564994;11743.412829358327;8805.779855503664;8934.687285944961;9014.23379012765;9085.63657453721;9147.176754092163;11217.55129794799;11794.528218871492;11889.28664280146;12009.459574319002;12015.939920300343;11658.358790266277;11719.941400292944;12064.819027714415;12094.516219351128;12118.515259389878;10973.670789876316;11209.103271738404;11506.76986460283;11514.884851151717;11601.477933493183 13:19:49 INFO - PROCESS | 1934 | |3;mootools.html;1472.1167299620226;1540.4300408848728;1557.4159690116207;1582.7467605183174;1586.6985272798167;10622;11219.158563107769;11222.046126079273;11230.831537526923;11259.718507037318;10665.626703065394;11063.944680276587;11210.943945280282;11226.214165008452;11251.718707032329;2357.71707395112;2404.675368825204;2405.56699794037;2407.0973384980634;2410.770976757205;2243.55128974205;2312.6877871487386;2313.063209400196;2317.084751523156;2365.881705914706;2302.735185453669;2340.2862127051244;2341.1220792202926;2384.439656680687;2399.3401814501067;2245.202952951695;2266.252136794854;2270.6707527408516;2272.6591011348264;2291.2438895164555;2360.1267531013464;2364.2552595932225;2367.1005018093188;2372.5136347048815;2375.1924345722364;11037.558497660084;11088.279261848034;11094;11101.55593776248;11134.832977505344;4673.6027437667835;5603.579731520112;5759.136129580555;5790.449907258803;5792.275965504312;3393.6436674149104;3533.1873669056004;3546.2552863898354;3625.0393645683916;3643.2895585360866;1150.3040660400504;1200.6518109748163;1221.8411606491188;1227.9631611051682;1240.6092080994458;13643.931780341034;13965.371558279901;14232.644183895382;15141.772873406908;15149.621259468491;4007.358822588372;4545.613622842029;4676.602488788423;4678.087772884323;4689.3903792507435;11527.365994870363;11531;11534.59628912984;11537.423128843524;11538.653840384633;4796.184648609745;4994.800207991712;4996.500349964975;4996.850094497171;5671.716414179275;4315.3742707307265;4336.522982471925;5153.871153221199;5203.751099736036;5275.261463395128;3752.3996160614715;3766.114962983697;3771.3777226757825;3828.196078823423;3830.9042273943373;854.222657381792;996.5963784667076;996.6710985374809;1007.9244056695777;1010.7119470950744;914.6478605736706;940.210223412337;944.6268723854176;951.1534734086669;995.6465454763454;6487.805365839033;6554.180727409074;6715.56348837324;6758.189017317954;6773.153355830521;958.6021800952666;977.5161295158941;981.7153025622489;987.5654711926729;1013.852991316255;4189.036521600049;4276.593723596254;4286.87139385819;4375.103103863764;4415.432521454902;13463.596092117252;14144.29278536069;14147.929260353838;14159.221242831743;14225.85774142266;12856;13174.07781455308;13499.595012149652;14095;14097;7989;9696.224302055818;10105.898941010642;10127.139193168516;10176.796464070825;6150.292716337678;6252.687365631609;6512.934870651233;6748.426383757239;6924.757633482804;11228.943855280671;11982.58060967862;12143.33211673331;12541.372931353213;12549.87450125487;10619.522121504706;10647.94676026615;11546.653600392001;12485.56300529477;12796.104272701194;10123.03831136041;10250.743731406774;10300.948495257475;10345.896541034794;10436.37381757097;10204.2346823987;10307.123894469054;10468.005539473737;10480.109190718875;10483.318584291997;7725.729599463992;8712.172343627346;8765.824683506422;8933.776655583662;9127.452352858849;8654.913450865412;8655.956720216358;8719.084496128069;8750.649974001224;8757.649694012169;7896.960515197387;7971.80070498219;8119.634616442156;8125.4718443301;8126.83746325082;5967.343592204941;5985.221921150222;6043.222985633979;6048.153258543719;6102.511799056154;5978.342382338026;5996.220491336273;6018.398160183947;6030.969845150747;6127.509799215963 13:19:49 INFO - PROCESS | 1934 | |4;prototype.html;379.506641366224;382.0601320750952;382.4129960510616;390.4904100149304;394.26666400495077;4291.935620965688;4609.994652326232;4805.924208506559;5058.544730974211;5065.493450654937;4093.631573158415;4125.360569111785;4231.724937879036;4270.701050926436;4990.251462280659;3529.647035296469;4017.7991100445006;4126.649234815044;4201.537830838621;4244.108737165184;3662.6703596676293;3968.8610898618554;4090.5909409059154;4134.250723269022;4228.4080228768;3567.6967457766123;3752.6059763724825;4133.813978370966;4153.54311025787;4245.766482843443;3560.857565697369;4196.706230563862;4213.682474638937;4224.40858279838;4241.787910604473;3158.3209609933974;3280.327532855759;3732.682721968636;3814.5041144651295;3826.68485566234;4316.633086187647;4515.758166504204;4569.497355290949;4697.224957881946;4867;4058.918821623551;4160.771157586331;4769.952300476985;4781.474037855833;4804.165542330007;4404.546499655106;4752.7623618818925;5031.547160755549;5236.7119808410525;5327.002374109709;3639.4168536686625;4114.300568903293;4230.280852255093;4257.8296868125235;4279.828806847722;4704.64715146362;5368.355797304349;5497.505224529811;5507.504324610804;5597.473591637283;3799.848006079726;3804.429335599627;4332.566743325705;4363.585459381354;4410.42664453619;3687.6312368763447;3768.4724138620068;4143.81352839123;4358.670605465363;4404.801783919731;4141.28136823221;4233.618974292329;4621.722696638212;4755.881102972453;4855.684380515255;4559;5230.123432112323;5231.633785635046;5280.841574752763;5401.756920938567;1264.797632378815;1438.8633079857398;1475.6458449971929;1490.2250829568563;1505.6386467248;977.6215536094215;986.4673076538733;1059.8251288537306;1118.7134794985668;1133.659902029388;4220.641245494167;4935.7532123393685;4941.728204948762;4969.428515720666;4985.255160693729;4619.676622636383;5307.01820163271;5406.945645599149;5449.863753406117;5517.586181036439;4121.670266378683;4514.841980530731;4896.6572339935865;4912.484189160086;4941;2636.5649667804983;2756.627855239517;2801.369691819365;2815.5213613685723;2818.5490321548864;4956.0367683404875;5203.765830537635;5448.673079615236;5756.3086084840725;5763.8212296085;4727.952720472751;4878.926816097762;5372.7313634318125;5443.972780136074;5669.100851214806;5005.724685142352;5253;5259.842204733787;5540.113581826889;5702.486776190163;4982.153033984304;5145.819896303611;5352.116900711339;5383.865403364947;5404.567634589302;4887.804487820447;5033.39599248078;5085.364329458817;5399.4600539945695;5419;4776.808927642855;4819.759012049313;5127.76942030218;5323.3878104016985;5593.328800543961;4139.3583994480905;4171.666266698718;4189.2668782963465;4191.317556763613;4317.287647538246;4786.946871688223;4902.240152776325;5148.815772372301;5163.922541161811;5372.704501252469;4729.541196635659;4929.383827021622;5196.792128314976;5324.387695415076;5330.840074797762;4630.791614377294;4661.836835710734;4996.575291100297;5281.890802931427;5291.894162116813;4445.066536027471;4790.4251489821445;4797.592204662643;4872.9512704872495;5151.14558758854;4477.462704475483;4630.58324750774;4681.485036645898;5123.6157288202785;5330.347592246416;5131.692098474104;5134.614903882299;5157.458466861076;5201.843944681666;5220.295260139985 13:19:49 INFO - PROCESS | 1934 | |5;yui.html;723.1250187273399;745.2584678245144;774.7753151586039;780.3211206250563;851.9616617252221;13219.933900330498;15723;15774.0535567866;15800.130992795392;15804.972676776002;14308.785368219485;14350;14355.56933292003;14356.569302920903;14482.927585362086;923.7228831350618;955.3885513271512;968.2834702320303;979.0307595480459;987.9061489158554;973.746825825287;975.9951200243988;976.770458942148;977.5405559387077;978.0512902484578;1018.8879223285433;1020.5968642386252;1021.1167340250704;1022.2486472442748;1022.5858527296419;929.8047410043899;967.9207683432992;1018.9337693049965;1020.2552136940076;1021.4024795494645;759.654357267442;759.965801538932;760.2245709376405;761.0562902001534;762.3215338348874;15512.844871551253;15928;15975.76036359444;15979.840201598068;16250.268737906823;12994.870051299555;13101.541446049438;13322.666933326647;13406.329683515785;13451.417407599405;11454.656360309204;11500.424978751029;11529.308241505452;11923.761524769543;12387;3647.5805282392334;3671.834767435472;3694.9261014779822;3783.7729736215915;3804.6575808177395;84645.73031404534;86720.13279867184;100268.18488276319;104669.95330046678;108014.45992769986;10826.948349822645;10867.83698244527;10901.83647245292;10934;11058.668239952814;22543.661845072336;22574.435639109153;22613.43466413353;22991.080356785875;23014.53970920572;7418.232980272947;7646.808829779189;7874.133845277015;7965.243301886428;8004.839903201903;4342.088161486124;4364.170807546556;4446.3108218226225;4617.607503362252;4624.838130665411;3512.297540491912;3559;3574.6782789549065;3610.7833529988284;3618.3306088373733;621.8196722950385;633.6103296472636;634.0742515926798;636.5671343486474;642.3865208725656;665.5939876675225;666.8866292730247;686.5365878032309;689.689639662154;724.8006798130556;9085.318601104811;9429.90570094304;9556.617735290649;10088.646897358558;10675.039246467855;561.3300431217097;676.864627074587;688.1398252184769;709.7870638808337;712.8253577873454;4957.572997217733;4965.255211718199;4998.900021999612;5006.299118123466;5010.64925455222;79912.40175196463;98711.50644246876;100761;103166;104396;47904.5209547907;60698;70322.64838675773;84854.57572712221;91152.6327105094;74528;74885.62557187179;75219;76175;76498.61750691211;10352.585896564053;10851.404860462666;10867.51096200657;11201.21591488588;11236.606718764804;38318;45521.772391137834;45969.31046034379;48055.79860503376;48317.75841120913;67729.66135169292;68120;69442;70633.64683176551;73378.63310683412;12531.3754709414;12705.555305564261;12879.35603219854;13010.739785204432;13376.933115334361;14481.710365792836;14600.85399145995;14654.780178297548;14858.777118343018;14947.77578336304;13720.862791372358;13854.445822167001;13951.720965580835;13986.580402587531;14123.152610843383;13103.868961310265;13244.53644122451;13708.108972916729;13741.519046833313;13798.241096739775;12790.680232993878;13424.731505370033;13624.978126640342;13644.795328070286;13745.793813093012;9367.391119577205;9503.904960950302;9816.26378021637;9947.452890091114;10040.397576145455;9526.571304291461;11155.83266251023;11192.440377981231;11397.544098235978;11421.771564568828 13:19:49 INFO - PROCESS | 1934 | __end_tp_report 13:19:49 INFO - PROCESS | 1934 | __start_cc_report 13:19:49 INFO - PROCESS | 1934 | _x_x_mozilla_cycle_collect,2152 13:19:49 INFO - PROCESS | 1934 | __end_cc_report 13:19:49 INFO - PROCESS | 1934 | __startTimestamp1491855589277__endTimestamp 13:19:49 INFO - PROCESS | 1934 | 13:19:49 INFO - PROCESS | 1934 | ------- Summary: start ------- 13:19:49 INFO - PROCESS | 1934 | Number of tests: 6 13:19:49 INFO - PROCESS | 1934 | 13:19:49 INFO - PROCESS | 1934 | [#0] dojo.html Cycles:165 Average:5886.92 Median:6068.54 stddev:652.69 (10.8%) stddev-sans-first:618.71 13:19:49 INFO - PROCESS | 1934 | Values: 3162.6 3329.7 3367.7 3372.0 3415.1 5958.4 5959.3 5963.6 5975.7 6000.5 5963.1 6010.7 6022.2 6024.2 6045.1 5368.6 5409.7 5413.2 5452.7 5501.4 5304.2 5309.5 5341.6 5498.6 5560.2 5365.5 5393.4 5403.1 5404.0 5405.8 5374.4 5395.7 5399.8 5411.5 5419.9 4448.2 5205.7 5322.3 5323.7 5338.6 5956.4 5957.6 5960.0 5962.1 5973.2 6239.1 6244.5 6258.5 6281.2 6283.7 6334.1 6346.7 6347.7 6349.5 6356.1 5981.0 6021.5 6038.5 6068.3 6068.5 5435.2 6242.7 6282.2 6300.9 6463.9 6318.2 6368.8 6372.6 6377.4 6389.6 6344.1 6354.7 6359.1 6363.7 6365.6 6229.6 6319.5 6325.6 6333.9 6346.3 6210.1 6271.7 6302.3 6333.9 6349.7 5150.6 5158.4 5164.1 5178.0 5191.3 4495.6 4555.2 4564.5 4574.3 4588.9 5802.3 5841.0 5881.9 5966.4 6032.4 6363.1 6364.8 6373.7 6380.0 6391.9 6006.2 6006.6 6015.9 6020.2 6020.9 5397.5 5501.6 5649.9 5654.1 5657.0 6304.1 6355.0 6358.2 6367.0 6376.2 6313.3 6323.9 6337.2 6341.1 6353.2 6456.3 6479.3 6548.2 6559.4 6582.9 6321.3 6325.7 6333.7 6336.0 6353.2 6196.5 6329.3 6337.1 6347.2 6368.6 6055.1 6125.5 6138.2 6237.9 6246.2 5261.0 6285.7 6317.5 6322.5 6323.4 6151.7 6161.2 6170.4 6183.2 6189.7 6101.4 6108.3 6166.9 6287.3 6331.6 6019.2 6030.6 6039.8 6043.2 6061.1 13:19:49 INFO - PROCESS | 1934 | 13:19:49 INFO - PROCESS | 1934 | [#1] ext.html Cycles:180 Average:54033.73 Median:169269.04 stddev:109049.91 (64.4%) stddev-sans-first:109282.56 13:19:49 INFO - PROCESS | 1934 | Values: 794.9 819.2 867.1 888.9 972.6 32489.4 37531.6 37813.2 37950.9 39083.6 37986.8 38264.2 38273.2 38288.0 38293.2 7421.7 7620.2 7831.1 7994.1 8004.3 7476.8 7555.4 7563.9 10996.3 11285.8 11189.6 11197.6 11200.3 11242.2 11250.6 11107.3 11130.7 11134.9 11142.0 11148.0 7304.2 7466.3 10744.0 10892.0 10954.1 38770.6 39011.8 39024.4 39126.8 39128.8 3859.9 3884.3 3909.2 3921.2 3936.2 27028.5 27116.5 27348.0 28112.7 28616.2 3050.2 3058.1 3059.1 3285.4 3286.5 306752.0 307853.0 307934.0 308077.5 309378.5 51151.7 51231.5 51492.2 51548.5 51576.7 53242.0 53281.7 53370.9 53386.0 53404.0 14583.1 14651.0 14682.0 14686.4 14720.9 12738.5 12747.2 12750.9 12765.4 12767.3 10323.7 12046.5 12089.6 12117.0 12213.8 5327.3 5388.4 5389.4 5390.7 5401.7 1062.9 1122.6 1129.4 1143.2 1147.5 25533.3 25672.7 25917.9 26122.5 26170.1 1223.4 1240.6 1242.5 1243.7 1294.7 13205.3 13267.9 13402.1 13452.0 13462.5 433202.0 433297.0 433333.0 434371.0 434395.0 55485.0 55617.0 55665.2 55675.0 55737.0 1254.8 1278.5 1416.8 1473.2 1474.8 19746.4 29980.1 30266.7 30304.8 30863.0 52511.0 52726.0 52799.2 52807.2 52840.0 468080.0 471879.0 472687.0 472898.0 473074.6 26528.0 34622.3 34755.8 34815.6 34844.7 35822.5 36900.8 37011.4 37020.8 37093.6 24684.6 25727.8 26412.3 26861.0 26950.9 26057.2 26338.5 26612.6 26850.0 27007.2 25038.6 30685.1 31215.4 31754.7 32033.7 28152.9 28299.4 28859.0 29162.4 29170.6 22035.1 23140.6 23275.8 23413.6 23555.9 13:19:49 INFO - PROCESS | 1934 | 13:19:49 INFO - PROCESS | 1934 | [#2] jquery.html Cycles:180 Average:41672.66 Median:26122.28 stddev:125306.29 (479.7%) stddev-sans-first:125619.43 13:19:49 INFO - PROCESS | 1934 | Values: 370.6 392.1 409.9 424.8 456.8 12645.7 14591.4 20735.7 20801.2 21351.0 21231.4 21607.4 21978.1 22023.6 22250.8 5685.0 6268.2 6286.8 6524.4 6561.8 4070.2 4307.9 6500.4 6519.4 6586.8 6473.5 7037.7 7368.2 7470.5 7561.4 7185.7 7394.4 7473.0 7480.4 7489.8 7022.6 7165.7 7253.7 7266.2 7272.7 25982.9 26106.2 26138.3 26156.3 26436.2 3269.4 3286.6 3405.7 3420.0 3533.9 15145.0 15439.5 15716.1 15831.1 16621.6 1680.1 1702.2 1705.4 1717.2 1726.7 137193.0 138293.6 139206.0 140030.9 140505.6 15645.7 21168.0 21383.9 21524.4 21605.8 34586.5 36252.0 36293.8 36298.6 36357.5 9014.0 10621.8 10814.6 10835.9 10995.3 7173.4 8785.9 8811.3 8964.1 8966.9 9847.7 9967.8 10031.2 10165.6 10191.7 3290.9 3292.8 4025.0 4166.9 4217.7 924.4 940.9 943.7 950.2 950.9 18274.5 20950.7 22555.0 22604.9 22619.9 959.8 960.7 968.1 979.7 984.9 11206.3 11703.1 11763.8 11778.5 11795.9 746144.0 751665.0 758176.0 764747.0 766016.0 115368.4 122845.0 132928.0 136699.3 139010.0 1312.6 1329.6 1346.6 1351.4 1353.6 18753.3 18907.6 19131.5 19138.5 19179.4 41296.4 51561.7 53549.7 55842.7 57465.9 77822.2 78713.0 78895.2 79390.0 81201.8 8398.4 9298.7 11956.6 12078.5 12129.2 7985.6 10866.6 12923.5 13006.6 13373.9 11343.7 11360.9 11565.3 11671.1 11743.4 8805.8 8934.7 9014.2 9085.6 9147.2 11217.6 11794.5 11889.3 12009.5 12015.9 11658.4 11719.9 12064.8 12094.5 12118.5 10973.7 11209.1 11506.8 11514.9 11601.5 13:19:49 INFO - PROCESS | 1934 | 13:19:49 INFO - PROCESS | 1934 | [#3] mootools.html Cycles:180 Average:6679.69 Median:2391.89 stddev:4196.53 (175.4%) stddev-sans-first:4190.06 13:19:49 INFO - PROCESS | 1934 | Values: 1472.1 1540.4 1557.4 1582.7 1586.7 10622.0 11219.2 11222.0 11230.8 11259.7 10665.6 11063.9 11210.9 11226.2 11251.7 2357.7 2404.7 2405.6 2407.1 2410.8 2243.6 2312.7 2313.1 2317.1 2365.9 2302.7 2340.3 2341.1 2384.4 2399.3 2245.2 2266.3 2270.7 2272.7 2291.2 2360.1 2364.3 2367.1 2372.5 2375.2 11037.6 11088.3 11094.0 11101.6 11134.8 4673.6 5603.6 5759.1 5790.4 5792.3 3393.6 3533.2 3546.3 3625.0 3643.3 1150.3 1200.7 1221.8 1228.0 1240.6 13643.9 13965.4 14232.6 15141.8 15149.6 4007.4 4545.6 4676.6 4678.1 4689.4 11527.4 11531.0 11534.6 11537.4 11538.7 4796.2 4994.8 4996.5 4996.9 5671.7 4315.4 4336.5 5153.9 5203.8 5275.3 3752.4 3766.1 3771.4 3828.2 3830.9 854.2 996.6 996.7 1007.9 1010.7 914.6 940.2 944.6 951.2 995.6 6487.8 6554.2 6715.6 6758.2 6773.2 958.6 977.5 981.7 987.6 1013.9 4189.0 4276.6 4286.9 4375.1 4415.4 13463.6 14144.3 14147.9 14159.2 14225.9 12856.0 13174.1 13499.6 14095.0 14097.0 7989.0 9696.2 10105.9 10127.1 10176.8 6150.3 6252.7 6512.9 6748.4 6924.8 11228.9 11982.6 12143.3 12541.4 12549.9 10619.5 10647.9 11546.7 12485.6 12796.1 10123.0 10250.7 10300.9 10345.9 10436.4 10204.2 10307.1 10468.0 10480.1 10483.3 7725.7 8712.2 8765.8 8933.8 9127.5 8654.9 8656.0 8719.1 8750.6 8757.6 7897.0 7971.8 8119.6 8125.5 8126.8 5967.3 5985.2 6043.2 6048.2 6102.5 5978.3 5996.2 6018.4 6031.0 6127.5 13:19:49 INFO - PROCESS | 1934 | 13:19:49 INFO - PROCESS | 1934 | [#4] prototype.html Cycles:180 Average:4340.55 Median:4716.30 stddev:1207.66 (25.6%) stddev-sans-first:1173.89 13:19:49 INFO - PROCESS | 1934 | Values: 379.5 382.1 382.4 390.5 394.3 4291.9 4610.0 4805.9 5058.5 5065.5 4093.6 4125.4 4231.7 4270.7 4990.3 3529.6 4017.8 4126.6 4201.5 4244.1 3662.7 3968.9 4090.6 4134.3 4228.4 3567.7 3752.6 4133.8 4153.5 4245.8 3560.9 4196.7 4213.7 4224.4 4241.8 3158.3 3280.3 3732.7 3814.5 3826.7 4316.6 4515.8 4569.5 4697.2 4867.0 4058.9 4160.8 4770.0 4781.5 4804.2 4404.5 4752.8 5031.5 5236.7 5327.0 3639.4 4114.3 4230.3 4257.8 4279.8 4704.6 5368.4 5497.5 5507.5 5597.5 3799.8 3804.4 4332.6 4363.6 4410.4 3687.6 3768.5 4143.8 4358.7 4404.8 4141.3 4233.6 4621.7 4755.9 4855.7 4559.0 5230.1 5231.6 5280.8 5401.8 1264.8 1438.9 1475.6 1490.2 1505.6 977.6 986.5 1059.8 1118.7 1133.7 4220.6 4935.8 4941.7 4969.4 4985.3 4619.7 5307.0 5406.9 5449.9 5517.6 4121.7 4514.8 4896.7 4912.5 4941.0 2636.6 2756.6 2801.4 2815.5 2818.5 4956.0 5203.8 5448.7 5756.3 5763.8 4728.0 4878.9 5372.7 5444.0 5669.1 5005.7 5253.0 5259.8 5540.1 5702.5 4982.2 5145.8 5352.1 5383.9 5404.6 4887.8 5033.4 5085.4 5399.5 5419.0 4776.8 4819.8 5127.8 5323.4 5593.3 4139.4 4171.7 4189.3 4191.3 4317.3 4786.9 4902.2 5148.8 5163.9 5372.7 4729.5 4929.4 5196.8 5324.4 5330.8 4630.8 4661.8 4996.6 5281.9 5291.9 4445.1 4790.4 4797.6 4873.0 5151.1 4477.5 4630.6 4681.5 5123.6 5330.3 5131.7 5134.6 5157.5 5201.8 5220.3 13:19:49 INFO - PROCESS | 1934 | 13:19:49 INFO - PROCESS | 1934 | [#5] yui.html Cycles:180 Average:19497.49 Median:3632.96 stddev:27172.01 (747.9%) stddev-sans-first:27211.66 13:19:49 INFO - PROCESS | 1934 | Values: 723.1 745.3 774.8 780.3 852.0 13219.9 15723.0 15774.1 15800.1 15805.0 14308.8 14350.0 14355.6 14356.6 14482.9 923.7 955.4 968.3 979.0 987.9 973.7 976.0 976.8 977.5 978.1 1018.9 1020.6 1021.1 1022.2 1022.6 929.8 967.9 1018.9 1020.3 1021.4 759.7 760.0 760.2 761.1 762.3 15512.8 15928.0 15975.8 15979.8 16250.3 12994.9 13101.5 13322.7 13406.3 13451.4 11454.7 11500.4 11529.3 11923.8 12387.0 3647.6 3671.8 3694.9 3783.8 3804.7 84645.7 86720.1 100268.2 104670.0 108014.5 10826.9 10867.8 10901.8 10934.0 11058.7 22543.7 22574.4 22613.4 22991.1 23014.5 7418.2 7646.8 7874.1 7965.2 8004.8 4342.1 4364.2 4446.3 4617.6 4624.8 3512.3 3559.0 3574.7 3610.8 3618.3 621.8 633.6 634.1 636.6 642.4 665.6 666.9 686.5 689.7 724.8 9085.3 9429.9 9556.6 10088.6 10675.0 561.3 676.9 688.1 709.8 712.8 4957.6 4965.3 4998.9 5006.3 5010.6 79912.4 98711.5 100761.0 103166.0 104396.0 47904.5 60698.0 70322.6 84854.6 91152.6 74528.0 74885.6 75219.0 76175.0 76498.6 10352.6 10851.4 10867.5 11201.2 11236.6 38318.0 45521.8 45969.3 48055.8 48317.8 67729.7 68120.0 69442.0 70633.6 73378.6 12531.4 12705.6 12879.4 13010.7 13376.9 14481.7 14600.9 14654.8 14858.8 14947.8 13720.9 13854.4 13951.7 13986.6 14123.2 13103.9 13244.5 13708.1 13741.5 13798.2 12790.7 13424.7 13625.0 13644.8 13745.8 9367.4 9503.9 9816.3 9947.5 10040.4 9526.6 11155.8 11192.4 11397.5 11421.8 13:19:49 INFO - PROCESS | 1934 | -------- Summary: end -------- 13:19:49 INFO - PROCESS | 1934 | 13:19:49 INFO - TEST-INFO | 1934: exit 0 13:19:49 INFO - TEST-OK | dromaeo_css | took 1120726ms 13:19:49 INFO - TEST-START | kraken 13:19:49 INFO - operating with platform_type : mac_ 13:19:49 INFO - Initialising browser for kraken test... 13:19:49 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpOTk2iX/profile http://localhost:51536/getInfo.html) 13:19:52 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 13:19:52 INFO - PROCESS | 1939 | colorDepth:24 13:19:52 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 13:19:52 INFO - PROCESS | 1939 | __metrics 13:19:52 INFO - PROCESS | 1939 | 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" 13:19:52 INFO - PROCESS | 1939 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:19:53 INFO - TEST-INFO | 1939: exit 0 13:19:53 INFO - Browser initialized. 13:19:53 INFO - Running cycle 1/1 for kraken test... 13:19:53 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpOTk2iX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:20:05 INFO - PROCESS | 1940 | RSS: Main: 215195648 13:20:05 INFO - PROCESS | 1940 | 13:20:08 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:51536/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51536/tests/kraken/driver.html?testName=audio-beat-detection) 13:20:09 INFO - PROCESS | 1940 | RSS: Main: 267632640 13:20:09 INFO - PROCESS | 1940 | 13:20:11 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:51536/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51536/tests/kraken/driver.html?testName=audio-dft) 13:20:12 INFO - PROCESS | 1940 | RSS: Main: 278736896 13:20:12 INFO - PROCESS | 1940 | 13:20:15 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:51536/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51536/tests/kraken/driver.html?testName=audio-fft) 13:20:16 INFO - PROCESS | 1940 | RSS: Main: 375967744 13:20:16 INFO - PROCESS | 1940 | 13:20:18 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:51536/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51536/tests/kraken/driver.html?testName=audio-oscillator) 13:20:19 INFO - PROCESS | 1940 | RSS: Main: 279805952 13:20:19 INFO - PROCESS | 1940 | 13:20:21 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:51536/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51536/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:20:21 INFO - PROCESS | 1940 | RSS: Main: 281395200 13:20:21 INFO - PROCESS | 1940 | 13:20:24 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:51536/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51536/tests/kraken/driver.html?testName=imaging-darkroom) 13:20:25 INFO - PROCESS | 1940 | RSS: Main: 322985984 13:20:25 INFO - PROCESS | 1940 | 13:20:28 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:51536/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51536/tests/kraken/driver.html?testName=imaging-desaturate) 13:20:28 INFO - PROCESS | 1940 | RSS: Main: 323452928 13:20:28 INFO - PROCESS | 1940 | 13:20:32 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:51536/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51536/tests/kraken/driver.html?testName=json-parse-financial) 13:20:32 INFO - PROCESS | 1940 | RSS: Main: 321417216 13:20:32 INFO - PROCESS | 1940 | 13:20:34 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:51536/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51536/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:20:35 INFO - PROCESS | 1940 | RSS: Main: 294678528 13:20:35 INFO - PROCESS | 1940 | 13:20:36 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:51536/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51536/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:20:37 INFO - PROCESS | 1940 | RSS: Main: 297336832 13:20:37 INFO - PROCESS | 1940 | 13:20:39 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:51536/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51536/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:20:40 INFO - PROCESS | 1940 | RSS: Main: 306286592 13:20:40 INFO - PROCESS | 1940 | 13:20:43 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:51536/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51536/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:20:44 INFO - PROCESS | 1940 | RSS: Main: 320933888 13:20:44 INFO - PROCESS | 1940 | 13:20:46 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:51536/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51536/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:20:47 INFO - PROCESS | 1940 | RSS: Main: 314859520 13:20:47 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:51536/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51536/tests/kraken/driver.html?testName=ai-astar) 13:20:49 INFO - PROCESS | 1940 | RSS: Main: 359178240 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | __start_tp_report 13:20:49 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 13:20:49 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 13:20:49 INFO - PROCESS | 1940 | |i|pagename|runs| 13:20:49 INFO - PROCESS | 1940 | |0;ai-astar;117;115;116;118;118;115;116;116;116;118 13:20:49 INFO - PROCESS | 1940 | |1;audio-beat-detection;147;110;107;144;110;103;110;109;129;105 13:20:49 INFO - PROCESS | 1940 | |2;audio-dft;185;183;188;186;187;186;184;185;185;182 13:20:49 INFO - PROCESS | 1940 | |3;audio-fft;80;61;64;109;85;61;59;61;76;61 13:20:49 INFO - PROCESS | 1940 | |4;audio-oscillator;84;70;86;71;70;67;81;67;69;83 13:20:49 INFO - PROCESS | 1940 | |5;imaging-gaussian-blur;87;92;113;87;88;88;87;87;88;87 13:20:49 INFO - PROCESS | 1940 | |6;imaging-darkroom;95;96;95;97;99;95;102;162;94;95 13:20:49 INFO - PROCESS | 1940 | |7;imaging-desaturate;127;138;83;84;118;126;129;86;84;85 13:20:49 INFO - PROCESS | 1940 | |8;json-parse-financial;61;61;61;62;66;65;65;65;64;66 13:20:49 INFO - PROCESS | 1940 | |9;json-stringify-tinderbox;62;60;65;61;61;58;59;60;59;59 13:20:49 INFO - PROCESS | 1940 | |10;stanford-crypto-aes;86;83;81;85;84;82;82;82;91;89 13:20:49 INFO - PROCESS | 1940 | |11;stanford-crypto-ccm;136;137;134;141;138;124;135;141;179;149 13:20:49 INFO - PROCESS | 1940 | |12;stanford-crypto-pbkdf2;193;220;165;164;165;162;163;161;164;164 13:20:49 INFO - PROCESS | 1940 | |13;stanford-crypto-sha256-iterative;65;64;64;65;67;79;80;79;77;63 13:20:49 INFO - PROCESS | 1940 | __end_tp_report 13:20:49 INFO - PROCESS | 1940 | __start_cc_report 13:20:49 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,2179 13:20:49 INFO - PROCESS | 1940 | __end_cc_report 13:20:49 INFO - PROCESS | 1940 | __startTimestamp1491855649616__endTimestamp 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | ------- Summary: start ------- 13:20:49 INFO - PROCESS | 1940 | Number of tests: 14 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | [#0] ai-astar Cycles:10 Average:116.50 Median:116.50 stddev:1.18 (1.0%) stddev-sans-first:1.24 13:20:49 INFO - PROCESS | 1940 | Values: 117.0 115.0 116.0 118.0 118.0 115.0 116.0 116.0 116.0 118.0 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | [#1] audio-beat-detection Cycles:10 Average:117.40 Median:110.00 stddev:16.41 (14.9%) stddev-sans-first:13.46 13:20:49 INFO - PROCESS | 1940 | Values: 147.0 110.0 107.0 144.0 110.0 103.0 110.0 109.0 129.0 105.0 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | [#2] audio-dft Cycles:10 Average:185.10 Median:185.50 stddev:1.79 (1.0%) stddev-sans-first:1.90 13:20:49 INFO - PROCESS | 1940 | Values: 185.0 183.0 188.0 186.0 187.0 186.0 184.0 185.0 185.0 182.0 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | [#3] audio-fft Cycles:10 Average:71.70 Median:62.50 stddev:16.10 (25.8%) stddev-sans-first:16.80 13:20:49 INFO - PROCESS | 1940 | Values: 80.0 61.0 64.0 109.0 85.0 61.0 59.0 61.0 76.0 61.0 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | [#4] audio-oscillator Cycles:10 Average:74.80 Median:76.00 stddev:7.69 (10.1%) stddev-sans-first:7.40 13:20:49 INFO - PROCESS | 1940 | Values: 84.0 70.0 86.0 71.0 70.0 67.0 81.0 67.0 69.0 83.0 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | [#5] imaging-gaussian-blur Cycles:10 Average:90.40 Median:87.50 stddev:8.09 (9.2%) stddev-sans-first:8.48 13:20:49 INFO - PROCESS | 1940 | Values: 87.0 92.0 113.0 87.0 88.0 88.0 87.0 87.0 88.0 87.0 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | [#6] imaging-darkroom Cycles:10 Average:103.00 Median:95.00 stddev:20.87 (22.0%) stddev-sans-first:21.93 13:20:49 INFO - PROCESS | 1940 | Values: 95.0 96.0 95.0 97.0 99.0 95.0 102.0 162.0 94.0 95.0 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | [#7] imaging-desaturate Cycles:10 Average:106.00 Median:83.50 stddev:23.28 (27.9%) stddev-sans-first:23.41 13:20:49 INFO - PROCESS | 1940 | Values: 127.0 138.0 83.0 84.0 118.0 126.0 129.0 86.0 84.0 85.0 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:65.00 stddev:2.12 (3.3%) stddev-sans-first:2.03 13:20:49 INFO - PROCESS | 1940 | Values: 61.0 61.0 61.0 62.0 66.0 65.0 65.0 65.0 64.0 66.0 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.50 stddev:2.01 (3.3%) stddev-sans-first:2.05 13:20:49 INFO - PROCESS | 1940 | Values: 62.0 60.0 65.0 61.0 61.0 58.0 59.0 60.0 59.0 59.0 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:84.50 stddev:3.31 (3.9%) stddev-sans-first:3.46 13:20:49 INFO - PROCESS | 1940 | Values: 86.0 83.0 81.0 85.0 84.0 82.0 82.0 82.0 91.0 89.0 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | [#11] stanford-crypto-ccm Cycles:10 Average:141.40 Median:139.50 stddev:14.65 (10.5%) stddev-sans-first:15.40 13:20:49 INFO - PROCESS | 1940 | Values: 136.0 137.0 134.0 141.0 138.0 124.0 135.0 141.0 179.0 149.0 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.10 Median:164.50 stddev:19.26 (11.7%) stddev-sans-first:18.88 13:20:49 INFO - PROCESS | 1940 | Values: 193.0 220.0 165.0 164.0 165.0 162.0 163.0 161.0 164.0 164.0 13:20:49 INFO - PROCESS | 1940 | 13:20:49 INFO - PROCESS | 1940 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:72.00 stddev:7.38 (10.2%) stddev-sans-first:7.57 13:20:49 INFO - PROCESS | 1940 | Values: 65.0 64.0 64.0 65.0 67.0 79.0 80.0 79.0 77.0 63.0 13:20:49 INFO - PROCESS | 1940 | -------- Summary: end -------- 13:20:49 INFO - PROCESS | 1940 | 13:20:50 INFO - TEST-INFO | 1940: exit 0 13:20:50 INFO - TEST-OK | kraken | took 60612ms 13:20:50 INFO - SUITE-END | took 1181s 13:20:50 INFO - Completed test suite (00:19:41) 13:20:50 INFO - javascript benchmark 13:20:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7552.343536587722, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3162.6204855417345, 3329.733621310294, 3367.7137443317324, 3371.983140084299, 3415.1120708615754, 5958.374370691075, 5959.284885813698, 5963.642181469115, 5975.671338076404, 6000.459958603725, 5963.135345374918, 6010.669413182273, 6022.156898034268, 6024.2168518092585, 6045.123457098718, 5368.597355198366, 5409.702466364349, 5413.242146099529, 5452.700101494416, 5501.394846566875, 5304.230886521452, 5309.548688361494, 5341.599380046493, 5498.5666769879335, 5560.2493663355335, 5365.463453654643, 5393.352797664285, 5403.108487099644, 5404.027275090482, 5405.810796622119, 5374.355077390738, 5395.7032363220005, 5399.83800485986, 5411.485908838694, 5419.864503387407, 4448.243798554254, 5205.713685747282, 5322.308099946982, 5323.733813309319, 5338.5996050296, 5956.37458066901, 5957.553183511211, 5960.04639257721, 5962.07587823888, 5973.163757074013, 6239.126522286883, 6244.500439964837, 6258.499320054385, 6281.152044473984, 6283.654399008053, 6334.113224148623, 6346.714397852108, 6347.650879201642, 6349.492040636738, 6356.141920840673, 5981.0430331147245, 6021.5182785377065, 6038.516918646542, 6068.3021452533085, 6068.544859135583, 5435.211894275308, 6242.687865606792, 6282.246130464374, 6300.928842096854, 6463.935360646427, 6318.241810982711, 6368.840778980562, 6372.6495042773095, 6377.394147556068, 6389.584676995981, 6344.080108384258, 6354.745810167541, 6359.109724638554, 6363.745450182034, 6365.61806291624, 6229.56393052482, 6319.526035547352, 6325.588836725598, 6333.904991425133, 6346.30190679025, 6210.099535567318, 6271.749130034838, 6302.338254483213, 6333.936660633427, 6349.68251587428, 5150.613703972217, 5158.3552055993005, 5164.0962831505085, 5178.0, 5191.299174611455, 4495.572920572606, 4555.248384016666, 4564.497905230422, 4574.268117101248, 4588.931166032512, 5802.303723553201, 5840.970795145997, 5881.882362352729, 5966.373530779292, 6032.426919442646, 6363.109164716943, 6364.809055728336, 6373.74505019794, 6379.96810015947, 6391.904121438182, 6006.159137720722, 6006.639601623917, 6015.879682406327, 6020.157177995085, 6020.909686354708, 5397.541208997201, 5501.642393244426, 5649.943500565024, 5654.067078932012, 5657.038303488417, 6304.05439184126, 6354.968225158845, 6358.205224346957, 6367.044943258548, 6376.171097757262, 6313.337099604475, 6323.936760632335, 6337.207849018872, 6341.112244285896, 6353.174087368612, 6456.257530384066, 6479.287278399466, 6548.246951600437, 6559.409653131241, 6582.934170658232, 6321.273053598711, 6325.715342809676, 6333.714982825692, 6336.049592561153, 6353.205849268841, 6196.504279657706, 6329.272133704683, 6337.112804207507, 6347.174867267226, 6368.64972426521, 6055.091736239423, 6125.540584456273, 6138.235132557408, 6237.8752424950335, 6246.187995560548, 5260.973695131653, 6285.654289013965, 6317.526185536011, 6322.462590679844, 6323.399277068671, 6151.661658608819, 6161.229846269216, 6170.352113028068, 6183.227096612924, 6189.690515474298, 6101.389861013863, 6108.311716708011, 6166.938330616816, 6287.30839607652, 6331.620102793847, 6019.217501724747, 6030.638161710312, 6039.818805435844, 6043.153958445733, 6061.121137435135], "value": 5844.199414157995, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.8648729715948, 819.1726356380057, 867.0679020053444, 888.9022207557167, 972.5526257921356, 32489.350212995727, 37531.62468375315, 37813.24373512528, 37950.861474155754, 39083.63207287745, 37986.810065949634, 38264.234715305676, 38273.234535309275, 38288.0, 38293.234135317274, 7421.740239091633, 7620.199879012679, 7831.099423566306, 7994.080680721733, 8004.319632831187, 7476.775696729107, 7555.357794587467, 7563.924360756405, 10996.28524145928, 11285.774284514306, 11189.552417903275, 11197.552097916074, 11200.327980321166, 11242.156838237124, 11250.606228781995, 11107.278026928265, 11130.666080017612, 11134.944325278402, 11141.997220250216, 11147.996680298731, 7304.196538380774, 7466.328030477229, 10744.0, 10892.019718225398, 10954.068904143158, 38770.61229387727, 39011.829645110694, 39024.414633780514, 39126.80436597799, 39128.804355978034, 3859.8842034739005, 3884.3396622574232, 3909.2181563687377, 3921.1961547882615, 3936.2259537413665, 27028.459430811272, 27116.45767084667, 27348.0, 28112.71887281142, 28616.248842142948, 3050.1612056684367, 3058.143719758471, 3059.0669845697053, 3285.4086264472385, 3286.5398844161837, 306752.0, 307853.0, 307934.0, 308077.4596127005, 309378.45310773526, 51151.74424127855, 51231.463056108376, 51492.227616585784, 51548.48451515512, 51576.742116289926, 53242.0, 53281.667958301354, 53370.932581348156, 53386.0, 53404.0, 14583.05210161336, 14651.0, 14682.0, 14686.412543498354, 14720.895932805082, 12738.490460381667, 12747.235165889892, 12750.936245318713, 12765.42555585001, 12767.297798620979, 10323.690289291333, 12046.457909394097, 12089.576864809866, 12117.0, 12213.75572488563, 5327.3074500314715, 5388.407275199725, 5389.380221274603, 5390.730463476811, 5401.675899446046, 1062.888396718349, 1122.6239209864623, 1129.447010360754, 1143.2168964259508, 1147.502509225452, 25533.340332878302, 25672.743272567408, 25917.870410647825, 26122.477550449265, 26170.084047058263, 1223.3516236394726, 1240.55340077572, 1242.5340497313507, 1243.7077286837587, 1294.6504443800309, 13205.273709945854, 13267.867321326856, 13402.128861623964, 13452.0, 13462.461501539829, 433202.0, 433297.0, 433333.0, 434371.0, 434395.0, 55485.0, 55617.0, 55665.16502252469, 55675.0, 55737.0, 1254.830597869276, 1278.4630455208835, 1416.8441471438134, 1473.1602986297708, 1474.793528905954, 19746.40760777179, 29980.100596982127, 30266.697333026386, 30304.84847575748, 30863.0, 52511.0, 52726.0, 52799.208011879084, 52807.207891880884, 52840.0, 468080.0, 471879.0, 472687.0, 472898.0, 473074.63462682464, 26528.0, 34622.307553849285, 34755.82622086874, 34815.60737570469, 34844.65155348516, 35822.46266305955, 36900.81549592235, 37011.44482832706, 37020.814895925345, 37093.62906370902, 24684.629730554414, 25727.84224709855, 26412.339691507867, 26861.0, 26950.921963121255, 26057.218283451526, 26338.4732305349, 26612.60081098824, 26850.0, 27007.1897843065, 25038.62442063334, 30685.07944761661, 31215.37569248557, 31754.682453175174, 32033.679663204002, 28152.85923570369, 28299.43401132007, 28859.0, 29162.416751664423, 29170.562441562968, 22035.118595256652, 23140.603285375724, 23275.76724232736, 23413.648795268426, 23555.88222058879], "value": 18255.97014889405, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.60900749709054, 392.13142888502, 409.8934277087958, 424.7600105940144, 456.83782257298657, 12645.683857903545, 14591.416343346267, 20735.68896466552, 20801.16795328189, 21351.0, 21231.363059108215, 21607.351779446642, 21978.120875165016, 22023.559528809415, 22250.777492225072, 5685.0, 6268.185135932334, 6286.811395658137, 6524.412802847743, 6561.80314590563, 4070.2470043041985, 4307.935380969288, 6500.41496265336, 6519.445847103002, 6586.769463068794, 6473.546851720382, 7037.718491260343, 7368.189499155089, 7470.47706660534, 7561.357284630813, 7185.676644550982, 7394.37147842434, 7473.0, 7480.4389670774635, 7489.775306740807, 7022.613756243404, 7165.7491987780695, 7253.673584688701, 7266.200717921024, 7272.672729727175, 25982.87008564945, 26106.216813495437, 26138.34654133643, 26156.346091347677, 26436.206913792616, 3269.395161895057, 3286.556314897482, 3405.7105146062613, 3419.991102624732, 3533.94699079514, 15145.0, 15439.536813895602, 15716.135612541306, 15831.050136991817, 16621.584460388585, 1680.1179380825042, 1702.1574320711204, 1705.3775371989134, 1717.1500107446893, 1726.7237242041338, 137193.0, 138293.6170638291, 139206.0, 140030.89953650703, 140505.5949440513, 15645.69743320596, 21168.047437865334, 21383.893080534497, 21524.35426937163, 21605.78394216038, 34586.48120278198, 36252.0, 36293.81853090718, 36298.637013630054, 36357.45463818045, 9014.0, 10621.787564248827, 10814.621488247874, 10835.891641083646, 10995.285306455055, 7173.390261827699, 8785.912140878509, 8811.339149563808, 8964.058773828785, 8966.910330896739, 9847.655332063343, 9967.750806229902, 10031.197504199647, 10165.644202452879, 10191.694249172537, 3290.9177270568425, 3292.7859689120132, 4025.041594428093, 4166.91666166681, 4217.683673724483, 924.4407133684024, 940.9200217981548, 943.6697155995485, 950.2065775077749, 950.8668786369914, 18274.45176644703, 20950.685739713917, 22555.0, 22604.886975565016, 22619.88690056539, 959.8320293948531, 960.7454024683465, 968.0948313326921, 979.6522234606755, 984.8916619171886, 11206.327620342805, 11703.091354602415, 11763.764724705457, 11778.52885884555, 11795.941020294844, 746144.0, 751665.0, 758176.0, 764747.0, 766016.0, 115368.42315788368, 122845.0, 132928.0, 136699.31650341884, 139010.0, 1312.566852938528, 1329.5944736855351, 1346.6229455752405, 1351.398627610704, 1353.6074538384153, 18753.343632973327, 18907.62184756325, 19131.521711957314, 19138.52153696169, 19179.424617261506, 41296.38055429111, 51561.7421912888, 53549.66125846885, 55842.72078639743, 57465.850682986944, 77822.2217777815, 78713.0, 78895.21104788879, 79390.01524961922, 81201.78197326927, 8398.370122240734, 9298.72103836886, 11956.641300760992, 12078.456469459072, 12129.150959432754, 7985.560794156145, 10866.61966831157, 12923.547675831302, 13006.609801705965, 13373.933130334286, 11343.659690209306, 11360.886391136313, 11565.306081634792, 11671.12466564994, 11743.412829358327, 8805.779855503664, 8934.687285944961, 9014.23379012765, 9085.63657453721, 9147.176754092163, 11217.55129794799, 11794.528218871492, 11889.28664280146, 12009.459574319002, 12015.939920300343, 11658.358790266277, 11719.941400292944, 12064.819027714415, 12094.516219351128, 12118.515259389878, 10973.670789876316, 11209.103271738404, 11506.76986460283, 11514.884851151717, 11601.477933493183], "value": 11320.861317876403, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1472.1167299620226, 1540.4300408848728, 1557.4159690116207, 1582.7467605183174, 1586.6985272798167, 10622.0, 11219.158563107769, 11222.046126079273, 11230.831537526923, 11259.718507037318, 10665.626703065394, 11063.944680276587, 11210.943945280282, 11226.214165008452, 11251.718707032329, 2357.71707395112, 2404.675368825204, 2405.56699794037, 2407.0973384980634, 2410.770976757205, 2243.55128974205, 2312.6877871487386, 2313.063209400196, 2317.084751523156, 2365.881705914706, 2302.735185453669, 2340.2862127051244, 2341.1220792202926, 2384.439656680687, 2399.3401814501067, 2245.202952951695, 2266.252136794854, 2270.6707527408516, 2272.6591011348264, 2291.2438895164555, 2360.1267531013464, 2364.2552595932225, 2367.1005018093188, 2372.5136347048815, 2375.1924345722364, 11037.558497660084, 11088.279261848034, 11094.0, 11101.55593776248, 11134.832977505344, 4673.6027437667835, 5603.579731520112, 5759.136129580555, 5790.449907258803, 5792.275965504312, 3393.6436674149104, 3533.1873669056004, 3546.2552863898354, 3625.0393645683916, 3643.2895585360866, 1150.3040660400504, 1200.6518109748163, 1221.8411606491188, 1227.9631611051682, 1240.6092080994458, 13643.931780341034, 13965.371558279901, 14232.644183895382, 15141.772873406908, 15149.621259468491, 4007.358822588372, 4545.613622842029, 4676.602488788423, 4678.087772884323, 4689.3903792507435, 11527.365994870363, 11531.0, 11534.59628912984, 11537.423128843524, 11538.653840384633, 4796.184648609745, 4994.800207991712, 4996.500349964975, 4996.850094497171, 5671.716414179275, 4315.3742707307265, 4336.522982471925, 5153.871153221199, 5203.751099736036, 5275.261463395128, 3752.3996160614715, 3766.114962983697, 3771.3777226757825, 3828.196078823423, 3830.9042273943373, 854.222657381792, 996.5963784667076, 996.6710985374809, 1007.9244056695777, 1010.7119470950744, 914.6478605736706, 940.210223412337, 944.6268723854176, 951.1534734086669, 995.6465454763454, 6487.805365839033, 6554.180727409074, 6715.56348837324, 6758.189017317954, 6773.153355830521, 958.6021800952666, 977.5161295158941, 981.7153025622489, 987.5654711926729, 1013.852991316255, 4189.036521600049, 4276.593723596254, 4286.87139385819, 4375.103103863764, 4415.432521454902, 13463.596092117252, 14144.29278536069, 14147.929260353838, 14159.221242831743, 14225.85774142266, 12856.0, 13174.07781455308, 13499.595012149652, 14095.0, 14097.0, 7989.0, 9696.224302055818, 10105.898941010642, 10127.139193168516, 10176.796464070825, 6150.292716337678, 6252.687365631609, 6512.934870651233, 6748.426383757239, 6924.757633482804, 11228.943855280671, 11982.58060967862, 12143.33211673331, 12541.372931353213, 12549.87450125487, 10619.522121504706, 10647.94676026615, 11546.653600392001, 12485.56300529477, 12796.104272701194, 10123.03831136041, 10250.743731406774, 10300.948495257475, 10345.896541034794, 10436.37381757097, 10204.2346823987, 10307.123894469054, 10468.005539473737, 10480.109190718875, 10483.318584291997, 7725.729599463992, 8712.172343627346, 8765.824683506422, 8933.776655583662, 9127.452352858849, 8654.913450865412, 8655.956720216358, 8719.084496128069, 8750.649974001224, 8757.649694012169, 7896.960515197387, 7971.80070498219, 8119.634616442156, 8125.4718443301, 8126.83746325082, 5967.343592204941, 5985.221921150222, 6043.222985633979, 6048.153258543719, 6102.511799056154, 5978.342382338026, 5996.220491336273, 6018.398160183947, 6030.969845150747, 6127.509799215963], "value": 5078.506700019282, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.506641366224, 382.0601320750952, 382.4129960510616, 390.4904100149304, 394.26666400495077, 4291.935620965688, 4609.994652326232, 4805.924208506559, 5058.544730974211, 5065.493450654937, 4093.631573158415, 4125.360569111785, 4231.724937879036, 4270.701050926436, 4990.251462280659, 3529.647035296469, 4017.7991100445006, 4126.649234815044, 4201.537830838621, 4244.108737165184, 3662.6703596676293, 3968.8610898618554, 4090.5909409059154, 4134.250723269022, 4228.4080228768, 3567.6967457766123, 3752.6059763724825, 4133.813978370966, 4153.54311025787, 4245.766482843443, 3560.857565697369, 4196.706230563862, 4213.682474638937, 4224.40858279838, 4241.787910604473, 3158.3209609933974, 3280.327532855759, 3732.682721968636, 3814.5041144651295, 3826.68485566234, 4316.633086187647, 4515.758166504204, 4569.497355290949, 4697.224957881946, 4867.0, 4058.918821623551, 4160.771157586331, 4769.952300476985, 4781.474037855833, 4804.165542330007, 4404.546499655106, 4752.7623618818925, 5031.547160755549, 5236.7119808410525, 5327.002374109709, 3639.4168536686625, 4114.300568903293, 4230.280852255093, 4257.8296868125235, 4279.828806847722, 4704.64715146362, 5368.355797304349, 5497.505224529811, 5507.504324610804, 5597.473591637283, 3799.848006079726, 3804.429335599627, 4332.566743325705, 4363.585459381354, 4410.42664453619, 3687.6312368763447, 3768.4724138620068, 4143.81352839123, 4358.670605465363, 4404.801783919731, 4141.28136823221, 4233.618974292329, 4621.722696638212, 4755.881102972453, 4855.684380515255, 4559.0, 5230.123432112323, 5231.633785635046, 5280.841574752763, 5401.756920938567, 1264.797632378815, 1438.8633079857398, 1475.6458449971929, 1490.2250829568563, 1505.6386467248, 977.6215536094215, 986.4673076538733, 1059.8251288537306, 1118.7134794985668, 1133.659902029388, 4220.641245494167, 4935.7532123393685, 4941.728204948762, 4969.428515720666, 4985.255160693729, 4619.676622636383, 5307.01820163271, 5406.945645599149, 5449.863753406117, 5517.586181036439, 4121.670266378683, 4514.841980530731, 4896.6572339935865, 4912.484189160086, 4941.0, 2636.5649667804983, 2756.627855239517, 2801.369691819365, 2815.5213613685723, 2818.5490321548864, 4956.0367683404875, 5203.765830537635, 5448.673079615236, 5756.3086084840725, 5763.8212296085, 4727.952720472751, 4878.926816097762, 5372.7313634318125, 5443.972780136074, 5669.100851214806, 5005.724685142352, 5253.0, 5259.842204733787, 5540.113581826889, 5702.486776190163, 4982.153033984304, 5145.819896303611, 5352.116900711339, 5383.865403364947, 5404.567634589302, 4887.804487820447, 5033.39599248078, 5085.364329458817, 5399.4600539945695, 5419.0, 4776.808927642855, 4819.759012049313, 5127.76942030218, 5323.3878104016985, 5593.328800543961, 4139.3583994480905, 4171.666266698718, 4189.2668782963465, 4191.317556763613, 4317.287647538246, 4786.946871688223, 4902.240152776325, 5148.815772372301, 5163.922541161811, 5372.704501252469, 4729.541196635659, 4929.383827021622, 5196.792128314976, 5324.387695415076, 5330.840074797762, 4630.791614377294, 4661.836835710734, 4996.575291100297, 5281.890802931427, 5291.894162116813, 4445.066536027471, 4790.4251489821445, 4797.592204662643, 4872.9512704872495, 5151.14558758854, 4477.462704475483, 4630.58324750774, 4681.485036645898, 5123.6157288202785, 5330.347592246416, 5131.692098474104, 5134.614903882299, 5157.458466861076, 5201.843944681666, 5220.295260139985], "value": 3994.5766209205012, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.1250187273399, 745.2584678245144, 774.7753151586039, 780.3211206250563, 851.9616617252221, 13219.933900330498, 15723.0, 15774.0535567866, 15800.130992795392, 15804.972676776002, 14308.785368219485, 14350.0, 14355.56933292003, 14356.569302920903, 14482.927585362086, 923.7228831350618, 955.3885513271512, 968.2834702320303, 979.0307595480459, 987.9061489158554, 973.746825825287, 975.9951200243988, 976.770458942148, 977.5405559387077, 978.0512902484578, 1018.8879223285433, 1020.5968642386252, 1021.1167340250704, 1022.2486472442748, 1022.5858527296419, 929.8047410043899, 967.9207683432992, 1018.9337693049965, 1020.2552136940076, 1021.4024795494645, 759.654357267442, 759.965801538932, 760.2245709376405, 761.0562902001534, 762.3215338348874, 15512.844871551253, 15928.0, 15975.76036359444, 15979.840201598068, 16250.268737906823, 12994.870051299555, 13101.541446049438, 13322.666933326647, 13406.329683515785, 13451.417407599405, 11454.656360309204, 11500.424978751029, 11529.308241505452, 11923.761524769543, 12387.0, 3647.5805282392334, 3671.834767435472, 3694.9261014779822, 3783.7729736215915, 3804.6575808177395, 84645.73031404534, 86720.13279867184, 100268.18488276319, 104669.95330046678, 108014.45992769986, 10826.948349822645, 10867.83698244527, 10901.83647245292, 10934.0, 11058.668239952814, 22543.661845072336, 22574.435639109153, 22613.43466413353, 22991.080356785875, 23014.53970920572, 7418.232980272947, 7646.808829779189, 7874.133845277015, 7965.243301886428, 8004.839903201903, 4342.088161486124, 4364.170807546556, 4446.3108218226225, 4617.607503362252, 4624.838130665411, 3512.297540491912, 3559.0, 3574.6782789549065, 3610.7833529988284, 3618.3306088373733, 621.8196722950385, 633.6103296472636, 634.0742515926798, 636.5671343486474, 642.3865208725656, 665.5939876675225, 666.8866292730247, 686.5365878032309, 689.689639662154, 724.8006798130556, 9085.318601104811, 9429.90570094304, 9556.617735290649, 10088.646897358558, 10675.039246467855, 561.3300431217097, 676.864627074587, 688.1398252184769, 709.7870638808337, 712.8253577873454, 4957.572997217733, 4965.255211718199, 4998.900021999612, 5006.299118123466, 5010.64925455222, 79912.40175196463, 98711.50644246876, 100761.0, 103166.0, 104396.0, 47904.5209547907, 60698.0, 70322.64838675773, 84854.57572712221, 91152.6327105094, 74528.0, 74885.62557187179, 75219.0, 76175.0, 76498.61750691211, 10352.585896564053, 10851.404860462666, 10867.51096200657, 11201.21591488588, 11236.606718764804, 38318.0, 45521.772391137834, 45969.31046034379, 48055.79860503376, 48317.75841120913, 67729.66135169292, 68120.0, 69442.0, 70633.64683176551, 73378.63310683412, 12531.3754709414, 12705.555305564261, 12879.35603219854, 13010.739785204432, 13376.933115334361, 14481.710365792836, 14600.85399145995, 14654.780178297548, 14858.777118343018, 14947.77578336304, 13720.862791372358, 13854.445822167001, 13951.720965580835, 13986.580402587531, 14123.152610843383, 13103.868961310265, 13244.53644122451, 13708.108972916729, 13741.519046833313, 13798.241096739775, 12790.680232993878, 13424.731505370033, 13624.978126640342, 13644.795328070286, 13745.793813093012, 9367.391119577205, 9503.904960950302, 9816.26378021637, 9947.452890091114, 10040.397576145455, 9526.571304291461, 11155.83266251023, 11192.440377981231, 11397.544098235978, 11421.771564568828], "value": 7572.359198060816, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1457.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 116.0, 118.0, 118.0, 115.0, 116.0, 116.0, 116.0, 118.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 110.0, 107.0, 144.0, 110.0, 103.0, 110.0, 109.0, 129.0, 105.0], "value": 117.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 188.0, 186.0, 187.0, 186.0, 184.0, 185.0, 185.0, 182.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 61.0, 64.0, 109.0, 85.0, 61.0, 59.0, 61.0, 76.0, 61.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 86.0, 71.0, 70.0, 67.0, 81.0, 67.0, 69.0, 83.0], "value": 74.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 92.0, 113.0, 87.0, 88.0, 88.0, 87.0, 87.0, 88.0, 87.0], "value": 90.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 97.0, 99.0, 95.0, 102.0, 162.0, 94.0, 95.0], "value": 103.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 138.0, 83.0, 84.0, 118.0, 126.0, 129.0, 86.0, 84.0, 85.0], "value": 106.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 62.0, 66.0, 65.0, 65.0, 65.0, 64.0, 66.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 65.0, 61.0, 61.0, 58.0, 59.0, 60.0, 59.0, 59.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 81.0, 85.0, 84.0, 82.0, 82.0, 82.0, 91.0, 89.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 134.0, 141.0, 138.0, 124.0, 135.0, 141.0, 179.0, 149.0], "value": 141.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 220.0, 165.0, 164.0, 165.0, 162.0, 163.0, 161.0, 164.0, 164.0], "value": 172.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 65.0, 67.0, 79.0, 80.0, 79.0, 77.0, 63.0], "value": 70.3, "unit": "score"}]}]} 13:20:50 INFO - Return code: 0 13:20:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:20:50 ERROR - # TBPL SUCCESS # 13:20:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:20:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:20:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:20:50 INFO - 2017-04-10 13:20:50.974 screenresolution[1944:15827] starting screenresolution argv=screenresolution get 13:20:50 INFO - 2017-04-10 13:20:50.976 screenresolution[1944:15827] Display 0: 1600x1200x32@60 13:20:50 INFO - 2017-04-10 13:20:50.983 screenresolution[1945:15830] starting screenresolution argv=screenresolution list 13:20:50 INFO - Available Modes on Display 0 13:20:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:20:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:20:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:20:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:20:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:20:51 INFO - Intel Iris: 13:20:51 INFO - Chipset Model: Intel Iris 13:20:51 INFO - Type: GPU 13:20:51 INFO - Bus: Built-In 13:20:51 INFO - VRAM (Dynamic, Max): 1536 MB 13:20:51 INFO - Vendor: Intel (0x8086) 13:20:51 INFO - Device ID: 0x0a2e 13:20:51 INFO - Revision ID: 0x0009 13:20:51 INFO - Displays: 13:20:51 INFO - Display: 13:20:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:20:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:20:51 INFO - Display Serial Number: 1600x1200 60 13:20:51 INFO - Main Display: Yes 13:20:51 INFO - Mirror: Off 13:20:51 INFO - Online: Yes 13:20:51 INFO - Rotation: Supported 13:20:51 INFO - Return code: 0 13:20:51 INFO - Running post-action listener: _resource_record_post_action 13:20:51 INFO - [mozharness: 2017-04-10 20:20:51.213155Z] Finished run-tests step (success) 13:20:51 INFO - Running post-run listener: _resource_record_post_run 13:20:51 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 659456; Write bytes: 509728256; Read time: 11; Write time: 1662 13:20:51 INFO - TinderboxPrint: CPU usage
54.0% 13:20:51 INFO - TinderboxPrint: I/O read bytes / time
659,456 / 11 13:20:51 INFO - TinderboxPrint: I/O write bytes / time
509,728,256 / 1,662 13:20:51 INFO - TinderboxPrint: CPU idle
2,211.6 (46.0%) 13:20:51 INFO - TinderboxPrint: CPU system
963.2 (20.0%) 13:20:51 INFO - TinderboxPrint: CPU user
1,634.7 (34.0%) 13:20:51 INFO - TinderboxPrint: Swap in / out
209,199,104 / 0 13:20:51 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203588096; Write bytes: 202651648; Read time: 15695; Write time: 417 13:20:51 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 659456; Write bytes: 296283648; Read time: 11; Write time: 1210 13:20:51 INFO - Running post-run listener: _upload_blobber_files 13:20:51 INFO - Blob upload gear active. 13:20:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:20:51 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:20:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:20:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:20:52 INFO - (blobuploader) - INFO - Open directory for files ... 13:20:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:20:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:20:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:20:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:20:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:20:52 INFO - (blobuploader) - INFO - Done attempting. 13:20:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:20:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:20:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:20:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:20:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:20:54 INFO - (blobuploader) - INFO - Done attempting. 13:20:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:20:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:20:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:20:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:20:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:20:55 INFO - (blobuploader) - INFO - Done attempting. 13:20:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:20:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:20:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:20:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:20:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:20:57 INFO - (blobuploader) - INFO - Done attempting. 13:20:57 INFO - (blobuploader) - INFO - Iteration through files over. 13:20:57 INFO - Return code: 0 13:20:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:20:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:20:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf84745f96122d6dbac93c53cbb94266ec79b432d8be4d8ae26891382ef08d6eb648f7d1a551aae371737dcf74bc1e7c4e63f73c877bf073ad69d030e4706d6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0d2becf87604828083fe7c4a4430bdb0b1954f18406e1be3a2e4287e3b8e484c531ddab5b9355e8d5e44bef8f7d43ddc5cb0e28fee75f096ea27b7b498f6b1c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/65bece05e560b0edcff71d0f94bf9ee3bdd23d0e9c6fc440554ec7e2acd3ad2609d89fec6acb71f5adfc862e4980b307e487fad0a92161c4ebc434ca8006ce0a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f592a920e24d5eed516fcf1c2ec2737a3231ac2ca0343d58218f78c7a15cd303ebfaea6ebb3c4218cccb5727ab03e7148471e6fb21b6324f48425a673ade5a9f"} 13:20:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:20:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:20:57 INFO - Contents: 13:20:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf84745f96122d6dbac93c53cbb94266ec79b432d8be4d8ae26891382ef08d6eb648f7d1a551aae371737dcf74bc1e7c4e63f73c877bf073ad69d030e4706d6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0d2becf87604828083fe7c4a4430bdb0b1954f18406e1be3a2e4287e3b8e484c531ddab5b9355e8d5e44bef8f7d43ddc5cb0e28fee75f096ea27b7b498f6b1c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/65bece05e560b0edcff71d0f94bf9ee3bdd23d0e9c6fc440554ec7e2acd3ad2609d89fec6acb71f5adfc862e4980b307e487fad0a92161c4ebc434ca8006ce0a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f592a920e24d5eed516fcf1c2ec2737a3231ac2ca0343d58218f78c7a15cd303ebfaea6ebb3c4218cccb5727ab03e7148471e6fb21b6324f48425a673ade5a9f"} 13:20:57 INFO - Running post-run listener: copy_logs_to_upload_dir 13:20:57 INFO - Copying logs to upload dir... 13:20:57 INFO - mkdir: /builds/slave/test/build/upload/logs 13:20:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.207743 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-10 13:20:57.696499) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 13:20:57.700205) ========= 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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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/bf84745f96122d6dbac93c53cbb94266ec79b432d8be4d8ae26891382ef08d6eb648f7d1a551aae371737dcf74bc1e7c4e63f73c877bf073ad69d030e4706d6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0d2becf87604828083fe7c4a4430bdb0b1954f18406e1be3a2e4287e3b8e484c531ddab5b9355e8d5e44bef8f7d43ddc5cb0e28fee75f096ea27b7b498f6b1c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/65bece05e560b0edcff71d0f94bf9ee3bdd23d0e9c6fc440554ec7e2acd3ad2609d89fec6acb71f5adfc862e4980b307e487fad0a92161c4ebc434ca8006ce0a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f592a920e24d5eed516fcf1c2ec2737a3231ac2ca0343d58218f78c7a15cd303ebfaea6ebb3c4218cccb5727ab03e7148471e6fb21b6324f48425a673ade5a9f"} build_url:https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011272 build_url: 'https://queue.taskcluster.net/v1/task/VlBtWgYXQ7mSPgkPl5EkyA/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/bf84745f96122d6dbac93c53cbb94266ec79b432d8be4d8ae26891382ef08d6eb648f7d1a551aae371737dcf74bc1e7c4e63f73c877bf073ad69d030e4706d6e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0d2becf87604828083fe7c4a4430bdb0b1954f18406e1be3a2e4287e3b8e484c531ddab5b9355e8d5e44bef8f7d43ddc5cb0e28fee75f096ea27b7b498f6b1c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/65bece05e560b0edcff71d0f94bf9ee3bdd23d0e9c6fc440554ec7e2acd3ad2609d89fec6acb71f5adfc862e4980b307e487fad0a92161c4ebc434ca8006ce0a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f592a920e24d5eed516fcf1c2ec2737a3231ac2ca0343d58218f78c7a15cd303ebfaea6ebb3c4218cccb5727ab03e7148471e6fb21b6324f48425a673ade5a9f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 13:20:57.735078) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 13:20:57.735423) ========= 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.QSXNnVM9oc/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.A3bww8b2Ze/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.004707 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 13:20:57.785408) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 13:20:57.785852) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 13:20:57.786293) ========= ========= Total master_lag: 0.19 =========