builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0018 starttime: 1490749000.75 results: success (0) buildid: 20170328152823 builduid: 8e1dd61cf8834aa79307c0c038355146 revision: bbab5665154904a6f4cd39b83bd9140dc5888d81 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 17:56:40.751684) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 17:56:40.752114) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 17:56:40.752391) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 17:56:40.778524) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 17:56:40.778850) ========= 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.NXka1i79HG/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.hkefD3UbKy/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-28 17:56:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 147M=0s 2017-03-28 17:56:40 (147 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137043 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 17:56:40.937472) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 17:56:40.937812) ========= 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.NXka1i79HG/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.hkefD3UbKy/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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.021765 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 17:56:40.986691) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 17:56:40.987078) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev bbab5665154904a6f4cd39b83bd9140dc5888d81 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev bbab5665154904a6f4cd39b83bd9140dc5888d81 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NXka1i79HG/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.hkefD3UbKy/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-28 17:56:40,997 truncating revision to first 12 chars 2017-03-28 17:56:40,997 Setting DEBUG logging. 2017-03-28 17:56:40,997 attempt 1/10 2017-03-28 17:56:40,997 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/bbab56651549?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 17:56:42,031 unpacking tar archive at: pine-bbab56651549/testing/mozharness/ program finished with exit code 0 elapsedTime=1.298083 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 17:56:42.308912) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 17:56:42.309277) ========= script_repo_revision: bbab5665154904a6f4cd39b83bd9140dc5888d81 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 17:56:42.309890) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 17:56:42.310189) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 17:56:42.330677) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-28 17:56:42.331214) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NXka1i79HG/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.hkefD3UbKy/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 17:56:42 INFO - MultiFileLogger online at 20170328 17:56:42 in /builds/slave/test 17:56:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 17:56:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:56:42 INFO - {'append_to_log': False, 17:56:42 INFO - 'base_work_dir': '/builds/slave/test', 17:56:42 INFO - 'blob_upload_branch': 'Pine', 17:56:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:56:42 INFO - 'branch': 'Pine', 17:56:42 INFO - 'buildbot_json_path': 'buildprops.json', 17:56:42 INFO - 'config_files': ('talos/mac_config.py',), 17:56:42 INFO - 'default_actions': ('clobber', 17:56:42 INFO - 'read-buildbot-config', 17:56:42 INFO - 'download-and-extract', 17:56:42 INFO - 'populate-webroot', 17:56:42 INFO - 'create-virtualenv', 17:56:42 INFO - 'install', 17:56:42 INFO - 'run-tests'), 17:56:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:56:42 INFO - 'download_minidump_stackwalk': True, 17:56:42 INFO - 'download_symbols': 'ondemand', 17:56:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:56:42 INFO - 'tooltool.py': '/tools/tooltool.py', 17:56:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:56:42 INFO - '/tools/misc-python/virtualenv.py')}, 17:56:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:56:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:56:42 INFO - 'gecko_profile': False, 17:56:42 INFO - 'gecko_profile_interval': 0, 17:56:42 INFO - 'installer_path': 'installer.exe', 17:56:42 INFO - 'log_level': 'info', 17:56:42 INFO - 'log_name': 'talos', 17:56:42 INFO - 'log_to_console': True, 17:56:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:56:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:56:42 INFO - 'opt_config_files': (), 17:56:42 INFO - 'pip_index': False, 17:56:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:56:42 INFO - 'cmd': ('bash', 17:56:42 INFO - '-c', 17:56:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:56:42 INFO - 'enabled': True, 17:56:42 INFO - 'halt_on_failure': False, 17:56:42 INFO - 'name': 'check_screen_resolution'},), 17:56:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:56:42 INFO - 'cmd': ('bash', 17:56:42 INFO - '-c', 17:56:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:56:42 INFO - 'enabled': True, 17:56:42 INFO - 'halt_on_failure': False, 17:56:42 INFO - 'name': 'check_screen_resolution'},), 17:56:42 INFO - 'run_cmd_checks_enabled': True, 17:56:42 INFO - 'suite': 'dromaeojs', 17:56:42 INFO - 'system_bits': '32', 17:56:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:56:42 INFO - 'title': 't-yosemite-r7-0018', 17:56:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:56:42 INFO - 'use_talos_json': True, 17:56:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:56:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:56:42 INFO - 'work_dir': 'build'} 17:56:42 INFO - [mozharness: 2017-03-29 00:56:42.462789Z] Running clobber step. 17:56:42 INFO - Running pre-action listener: _resource_record_pre_action 17:56:42 INFO - Running main action method: clobber 17:56:42 INFO - rmtree: /builds/slave/test/build 17:56:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:56:42 INFO - Running post-action listener: _resource_record_post_action 17:56:42 INFO - [mozharness: 2017-03-29 00:56:42.654442Z] Finished clobber step (success) 17:56:42 INFO - [mozharness: 2017-03-29 00:56:42.654553Z] Running read-buildbot-config step. 17:56:42 INFO - Running pre-action listener: _resource_record_pre_action 17:56:42 INFO - Running main action method: read_buildbot_config 17:56:42 INFO - Using buildbot properties: 17:56:42 INFO - { 17:56:42 INFO - "project": "", 17:56:42 INFO - "product": "firefox", 17:56:42 INFO - "script_repo_revision": "default", 17:56:42 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 17:56:42 INFO - "repository": "", 17:56:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 17:56:42 INFO - "buildid": "20170328152823", 17:56:42 INFO - "pgo_build": "False", 17:56:42 INFO - "basedir": "/builds/slave/test", 17:56:42 INFO - "buildnumber": 0, 17:56:42 INFO - "slavename": "t-yosemite-r7-0018", 17:56:42 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 17:56:42 INFO - "platform": "yosemite_r7", 17:56:42 INFO - "scheduler": "tests-pine-macosx64-talos", 17:56:42 INFO - "branch": "pine", 17:56:42 INFO - "repo_path": "projects/pine", 17:56:42 INFO - "revision": "bbab5665154904a6f4cd39b83bd9140dc5888d81", 17:56:42 INFO - "stage_platform": "macosx64", 17:56:42 INFO - "builduid": "8e1dd61cf8834aa79307c0c038355146", 17:56:42 INFO - "slavebuilddir": "test" 17:56:42 INFO - } 17:56:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 17:56:42 INFO - Running post-action listener: _resource_record_post_action 17:56:42 INFO - [mozharness: 2017-03-29 00:56:42.657669Z] Finished read-buildbot-config step (success) 17:56:42 INFO - [mozharness: 2017-03-29 00:56:42.657795Z] Running download-and-extract step. 17:56:42 INFO - Running pre-action listener: _resource_record_pre_action 17:56:42 INFO - Running main action method: download_and_extract 17:56:42 INFO - mkdir: /builds/slave/test/build/tests 17:56: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')]} 17:56:42 INFO - trying https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:56:42 INFO - Downloading https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:56:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 17:56:44 INFO - Downloaded 1325 bytes. 17:56:44 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:56:44 INFO - Using the following test package requirements: 17:56:44 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:44 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:56:44 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:56:44 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:44 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:56:44 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:44 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:56:44 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:44 INFO - u'jsshell-mac.zip'], 17:56:44 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:44 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:56:44 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:56:44 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:44 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:56:44 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:44 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:56:44 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:44 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:56:44 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:44 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:56:44 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:56:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:56:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:56:44 INFO - Fetch https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:56:45 INFO - Expected file size: 52507122 17:56:45 INFO - Obtained file size: 52507122 17:57:02 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:57:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:57:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:57:02 INFO - Fetch https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:57:04 INFO - Expected file size: 52507122 17:57:04 INFO - Obtained file size: 52507122 17:57:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:57:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:57:19 INFO - Fetch https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:57:20 INFO - Expected file size: 14404171 17:57:20 INFO - Obtained file size: 14404171 17:57:20 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')]} 17:57:20 INFO - trying https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:57:20 INFO - Downloading https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:57:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 17:57:22 INFO - Downloaded 75643280 bytes. 17:57:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:57:22 INFO - mkdir: /builds/slave/test/properties 17:57:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:57:22 INFO - Writing to file /builds/slave/test/properties/build_url 17:57:22 INFO - Contents: 17:57:22 INFO - build_url:https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:57:23 INFO - Running post-action listener: _resource_record_post_action 17:57:23 INFO - Running post-action listener: set_extra_try_arguments 17:57:23 INFO - [mozharness: 2017-03-29 00:57:23.348688Z] Finished download-and-extract step (success) 17:57:23 INFO - [mozharness: 2017-03-29 00:57:23.348807Z] Running populate-webroot step. 17:57:23 INFO - Running pre-action listener: _resource_record_pre_action 17:57:23 INFO - Running main action method: populate_webroot 17:57:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:57:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:57:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:57:23 INFO - '--tppagecycles', 17:57:23 INFO - '7'], 17:57:23 INFO - 'tests': ['tsvgm']}}, 17:57:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:57:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:57:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:57:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:57:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:57:23 INFO - 'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:57:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:57:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:57:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:57:23 INFO - 'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['damp', 'tps']}, 17:57:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:57:23 INFO - 'tests': ['damp', 'tps']}, 17:57:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['dromaeo_dom']}, 17:57:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:57:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['basic_compositor_video']}, 17:57:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:57:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['a11yr', 17:57:23 INFO - 'ts_paint', 17:57:23 INFO - 'tpaint', 17:57:23 INFO - 'sessionrestore', 17:57:23 INFO - 'sessionrestore_no_auto_restore', 17:57:23 INFO - 'tabpaint']}, 17:57:23 INFO - 'other-e10s': {'tests': ['a11yr', 17:57:23 INFO - 'ts_paint', 17:57:23 INFO - 'tpaint', 17:57:23 INFO - 'sessionrestore', 17:57:23 INFO - 'sessionrestore_no_auto_restore', 17:57:23 INFO - 'tabpaint']}, 17:57:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:57:23 INFO - 'ts_paint', 17:57:23 INFO - 'tpaint', 17:57:23 INFO - 'sessionrestore', 17:57:23 INFO - 'sessionrestore_no_auto_restore', 17:57:23 INFO - 'tabpaint']}, 17:57:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:57:23 INFO - 'ts_paint', 17:57:23 INFO - 'tpaint', 17:57:23 INFO - 'sessionrestore', 17:57:23 INFO - 'sessionrestore_no_auto_restore', 17:57:23 INFO - 'tabpaint']}, 17:57:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['a11yr', 17:57:23 INFO - 'ts_paint', 17:57:23 INFO - 'tpaint', 17:57:23 INFO - 'sessionrestore', 17:57:23 INFO - 'sessionrestore_no_auto_restore', 17:57:23 INFO - 'tabpaint']}, 17:57:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['a11yr', 17:57:23 INFO - 'ts_paint', 17:57:23 INFO - 'tpaint', 17:57:23 INFO - 'sessionrestore', 17:57:23 INFO - 'sessionrestore_no_auto_restore', 17:57:23 INFO - 'tabpaint']}, 17:57:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['tsvgx', 17:57:23 INFO - 'tsvgr_opacity', 17:57:23 INFO - 'tart', 17:57:23 INFO - 'tscrollx', 17:57:23 INFO - 'cart', 17:57:23 INFO - 'tsvg_static']}, 17:57:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:57:23 INFO - 'tsvgr_opacity', 17:57:23 INFO - 'tart', 17:57:23 INFO - 'tscrollx', 17:57:23 INFO - 'cart', 17:57:23 INFO - 'tsvg_static']}, 17:57:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:57:23 INFO - 'talos_options': ['--disable-e10s'], 17:57:23 INFO - 'tests': ['tp5o']}, 17:57:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:57:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:57:23 INFO - 'talos_options': ['--disable-e10s', 17:57:23 INFO - '--xperf_path', 17:57:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:57:23 INFO - 'tests': ['tp5n']}, 17:57:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:57:23 INFO - 'talos_options': ['--xperf_path', 17:57:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:57:23 INFO - 'tests': ['tp5n']}}, 17:57:23 INFO - 'talos.zip': {'path': '', 17:57:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:57:23 INFO - Running post-action listener: _resource_record_post_action 17:57:23 INFO - [mozharness: 2017-03-29 00:57:23.366902Z] Finished populate-webroot step (success) 17:57:23 INFO - [mozharness: 2017-03-29 00:57:23.367039Z] Running create-virtualenv step. 17:57:23 INFO - Running pre-action listener: _resource_record_pre_action 17:57:23 INFO - Running main action method: create_virtualenv 17:57:23 INFO - Creating virtualenv /builds/slave/test/build/venv 17:57:23 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 17:57:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:57:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:57:23 INFO - Using real prefix '/tools/python27' 17:57:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:57:24 INFO - Installing distribute.............................................................................................................................................................................................done. 17:57:27 INFO - Installing pip.................done. 17:57:27 INFO - Return code: 0 17:57:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:57:27 INFO - Reading from file tmpfile_stdout 17:57:27 INFO - Output received: 17:57:27 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) 17:57:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:27 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 0x10fe89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc228561550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc67d30>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc5cbe8>, '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.NXka1i79HG/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.hkefD3UbKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 17:57:27 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 17:57:27 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 17:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXka1i79HG/Render', 17:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:27 INFO - 'HOME': '/Users/cltbld', 17:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:27 INFO - 'LOGNAME': 'cltbld', 17:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:27 INFO - 'MOZ_NO_REMOTE': '1', 17:57:27 INFO - 'NO_EM_RESTART': '1', 17:57:27 INFO - 'PAGER': '/bin/cat', 17:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:27 INFO - 'PWD': '/builds/slave/test', 17:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:27 INFO - 'SHELL': '/bin/bash', 17:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hkefD3UbKy/Listeners', 17:57:27 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 17:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:27 INFO - 'USER': 'cltbld', 17:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:27 INFO - 'XPC_FLAGS': '0x0', 17:57:27 INFO - 'XPC_SERVICE_NAME': '0', 17:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:27 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 17:57:27 INFO - Cleaning up... 17:57:27 INFO - Return code: 0 17:57:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:27 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 0x10fe89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc228561550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc67d30>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc5cbe8>, '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.NXka1i79HG/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.hkefD3UbKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 17:57:27 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 17:57:27 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 17:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXka1i79HG/Render', 17:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:27 INFO - 'HOME': '/Users/cltbld', 17:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:27 INFO - 'LOGNAME': 'cltbld', 17:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:27 INFO - 'MOZ_NO_REMOTE': '1', 17:57:27 INFO - 'NO_EM_RESTART': '1', 17:57:27 INFO - 'PAGER': '/bin/cat', 17:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:27 INFO - 'PWD': '/builds/slave/test', 17:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:27 INFO - 'SHELL': '/bin/bash', 17:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hkefD3UbKy/Listeners', 17:57:27 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 17:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:27 INFO - 'USER': 'cltbld', 17:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:27 INFO - 'XPC_FLAGS': '0x0', 17:57:27 INFO - 'XPC_SERVICE_NAME': '0', 17:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:27 INFO - Downloading/unpacking psutil>=3.1.1 17:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:57:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:57:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:57:31 INFO - Installing collected packages: psutil 17:57:31 INFO - Running setup.py install for psutil 17:57:31 INFO - building 'psutil._psutil_osx' extension 17:57:31 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 17:57:31 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 17:57:32 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 17:57:32 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 17:57:32 INFO - building 'psutil._psutil_posix' extension 17:57:32 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 17:57:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:57:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:57:32 INFO - ^ 17:57:32 INFO - 1 warning generated. 17:57:32 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 17:57:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:57:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:57:32 INFO - Successfully installed psutil 17:57:32 INFO - Cleaning up... 17:57:32 INFO - Return code: 0 17:57:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:57:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc228561550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc67d30>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc5cbe8>, '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.NXka1i79HG/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.hkefD3UbKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 17:57: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:57: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 mozsystemmonitor==0.3 17:57:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXka1i79HG/Render', 17:57:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:32 INFO - 'HOME': '/Users/cltbld', 17:57:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:32 INFO - 'LOGNAME': 'cltbld', 17:57:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:32 INFO - 'MOZ_NO_REMOTE': '1', 17:57:32 INFO - 'NO_EM_RESTART': '1', 17:57:32 INFO - 'PAGER': '/bin/cat', 17:57:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:32 INFO - 'PWD': '/builds/slave/test', 17:57:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:32 INFO - 'SHELL': '/bin/bash', 17:57:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hkefD3UbKy/Listeners', 17:57:32 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 17:57:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:32 INFO - 'USER': 'cltbld', 17:57:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:32 INFO - 'XPC_FLAGS': '0x0', 17:57:32 INFO - 'XPC_SERVICE_NAME': '0', 17:57:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:57: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 17:57: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 17:57: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 17:57: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 17:57: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 17:57: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 17:57:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:57:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:57:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:57:35 INFO - Installing collected packages: mozsystemmonitor 17:57:35 INFO - Running setup.py install for mozsystemmonitor 17:57:35 INFO - Successfully installed mozsystemmonitor 17:57:35 INFO - Cleaning up... 17:57:35 INFO - Return code: 0 17:57:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:35 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 0x10fe89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc228561550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc67d30>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc5cbe8>, '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.NXka1i79HG/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.hkefD3UbKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 17:57:35 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 17:57:35 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 17:57:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXka1i79HG/Render', 17:57:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:35 INFO - 'HOME': '/Users/cltbld', 17:57:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:35 INFO - 'LOGNAME': 'cltbld', 17:57:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:35 INFO - 'MOZ_NO_REMOTE': '1', 17:57:35 INFO - 'NO_EM_RESTART': '1', 17:57:35 INFO - 'PAGER': '/bin/cat', 17:57:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:35 INFO - 'PWD': '/builds/slave/test', 17:57:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:35 INFO - 'SHELL': '/bin/bash', 17:57:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hkefD3UbKy/Listeners', 17:57:35 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 17:57:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:35 INFO - 'USER': 'cltbld', 17:57:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:35 INFO - 'XPC_FLAGS': '0x0', 17:57:35 INFO - 'XPC_SERVICE_NAME': '0', 17:57:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:35 INFO - Downloading/unpacking jsonschema==2.5.1 17:57: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 17:57: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 17:57: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 17:57: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 17:57: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 17:57: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 17:57:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:57:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:57:38 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 17:57:38 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 17:57:38 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 17:57:38 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 17:57:38 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 17:57:38 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 17:57:38 INFO - Downloading functools32-3.2.3-2.tar.gz 17:57:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:57:39 INFO - warning: no files found matching '*.txt' 17:57:39 INFO - no previously-included directories found matching 'build' 17:57:39 INFO - no previously-included directories found matching 'dist' 17:57:39 INFO - no previously-included directories found matching '.git*' 17:57:39 INFO - Installing collected packages: jsonschema, functools32 17:57:39 INFO - Running setup.py install for functools32 17:57:39 INFO - warning: no files found matching '*.txt' 17:57:39 INFO - no previously-included directories found matching 'build' 17:57:39 INFO - no previously-included directories found matching 'dist' 17:57:39 INFO - no previously-included directories found matching '.git*' 17:57:39 INFO - Successfully installed jsonschema functools32 17:57:39 INFO - Cleaning up... 17:57:39 INFO - Return code: 0 17:57:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:57:39 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')]} 17:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:39 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')]} 17:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:40 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 0x10fe89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc228561550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc67d30>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc5cbe8>, '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.NXka1i79HG/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.hkefD3UbKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 17:57:40 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 17:57:40 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 17:57:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXka1i79HG/Render', 17:57:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:40 INFO - 'HOME': '/Users/cltbld', 17:57:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:40 INFO - 'LOGNAME': 'cltbld', 17:57:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:40 INFO - 'MOZ_NO_REMOTE': '1', 17:57:40 INFO - 'NO_EM_RESTART': '1', 17:57:40 INFO - 'PAGER': '/bin/cat', 17:57:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:40 INFO - 'PWD': '/builds/slave/test', 17:57:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:40 INFO - 'SHELL': '/bin/bash', 17:57:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hkefD3UbKy/Listeners', 17:57:40 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 17:57:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:40 INFO - 'USER': 'cltbld', 17:57:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:40 INFO - 'XPC_FLAGS': '0x0', 17:57:40 INFO - 'XPC_SERVICE_NAME': '0', 17:57:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:57:40 INFO - Cleaning up... 17:57:40 INFO - Return code: 0 17:57:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:57:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:40 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 0x10fe89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc228561550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc67d30>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc5cbe8>, '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.NXka1i79HG/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.hkefD3UbKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 17:57:40 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 17:57:40 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 17:57:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXka1i79HG/Render', 17:57:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:40 INFO - 'HOME': '/Users/cltbld', 17:57:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:40 INFO - 'LOGNAME': 'cltbld', 17:57:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:40 INFO - 'MOZ_NO_REMOTE': '1', 17:57:40 INFO - 'NO_EM_RESTART': '1', 17:57:40 INFO - 'PAGER': '/bin/cat', 17:57:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:40 INFO - 'PWD': '/builds/slave/test', 17:57:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:40 INFO - 'SHELL': '/bin/bash', 17:57:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hkefD3UbKy/Listeners', 17:57:40 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 17:57:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:40 INFO - 'USER': 'cltbld', 17:57:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:40 INFO - 'XPC_FLAGS': '0x0', 17:57:40 INFO - 'XPC_SERVICE_NAME': '0', 17:57:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:40 INFO - Downloading/unpacking blobuploader==1.2.4 17:57:40 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 17:57:40 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 17:57:40 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 17:57:40 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 17:57:40 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 17:57:40 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 17:57:43 INFO - Downloading blobuploader-1.2.4.tar.gz 17:57:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:57:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:57:43 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 17:57:43 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 17:57:43 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 17:57:43 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 17:57:43 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 17:57:43 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 17:57:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:57:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:57: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 17:57: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 17:57: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 17:57: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 17:57: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 17:57: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 17:57:44 INFO - Downloading docopt-0.6.1.tar.gz 17:57:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:57:44 INFO - Installing collected packages: blobuploader, requests, docopt 17:57:44 INFO - Running setup.py install for blobuploader 17:57:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:57:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:57:44 INFO - Running setup.py install for requests 17:57:45 INFO - Running setup.py install for docopt 17:57:45 INFO - Successfully installed blobuploader requests docopt 17:57:45 INFO - Cleaning up... 17:57:45 INFO - Return code: 0 17:57:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:45 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 0x10fe89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc228561550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc67d30>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc5cbe8>, '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.NXka1i79HG/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.hkefD3UbKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 17:57:45 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 17:57:45 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 17:57:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXka1i79HG/Render', 17:57:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:45 INFO - 'HOME': '/Users/cltbld', 17:57:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:45 INFO - 'LOGNAME': 'cltbld', 17:57:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:45 INFO - 'MOZ_NO_REMOTE': '1', 17:57:45 INFO - 'NO_EM_RESTART': '1', 17:57:45 INFO - 'PAGER': '/bin/cat', 17:57:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:45 INFO - 'PWD': '/builds/slave/test', 17:57:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:45 INFO - 'SHELL': '/bin/bash', 17:57:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hkefD3UbKy/Listeners', 17:57:45 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 17:57:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:45 INFO - 'USER': 'cltbld', 17:57:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:45 INFO - 'XPC_FLAGS': '0x0', 17:57:45 INFO - 'XPC_SERVICE_NAME': '0', 17:57:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:57:46 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-OfCkJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:57:46 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-g8yPBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:57:46 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-NMVpZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:57:46 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-X11xTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:57:46 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-Lk3de5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:57:46 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-RELNu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:57:46 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-DpkfmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:57:46 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-nlYI5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:57:46 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-1dkPtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:57:46 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-9NtTjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:57:47 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-FIBH1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:57:47 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-p0lWjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:57:47 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-awvBRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:57:47 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-i___Gf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:47 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-JJeA0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:57:47 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-95KAju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:57:47 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-q9JApV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:57:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:57:47 INFO - Running setup.py install for manifestparser 17:57:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:57:47 INFO - Running setup.py install for mozcrash 17:57:48 INFO - Running setup.py install for mozdebug 17:57:48 INFO - Running setup.py install for mozdevice 17:57:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:57:48 INFO - Running setup.py install for mozfile 17:57:48 INFO - Running setup.py install for mozhttpd 17:57:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:57:48 INFO - Running setup.py install for mozinfo 17:57:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:57:48 INFO - Running setup.py install for mozInstall 17:57:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:57:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:57:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:57:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:57:48 INFO - Running setup.py install for mozleak 17:57:49 INFO - Running setup.py install for mozlog 17:57:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:57:49 INFO - Running setup.py install for moznetwork 17:57:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:57:49 INFO - Running setup.py install for mozprocess 17:57:49 INFO - Running setup.py install for mozprofile 17:57:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:57:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:57:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:57:49 INFO - Running setup.py install for mozrunner 17:57:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:57:49 INFO - Running setup.py install for mozscreenshot 17:57:50 INFO - Running setup.py install for moztest 17:57:50 INFO - Running setup.py install for mozversion 17:57:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:57:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:57:50 INFO - Cleaning up... 17:57:50 INFO - Return code: 0 17:57:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:57:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:50 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 0x10fe89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc228561550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc67d30>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc5cbe8>, '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.NXka1i79HG/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.hkefD3UbKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 17:57:50 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 17:57:50 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 17:57:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXka1i79HG/Render', 17:57:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:50 INFO - 'HOME': '/Users/cltbld', 17:57:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:50 INFO - 'LOGNAME': 'cltbld', 17:57:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:50 INFO - 'MOZ_NO_REMOTE': '1', 17:57:50 INFO - 'NO_EM_RESTART': '1', 17:57:50 INFO - 'PAGER': '/bin/cat', 17:57:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:50 INFO - 'PWD': '/builds/slave/test', 17:57:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:50 INFO - 'SHELL': '/bin/bash', 17:57:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hkefD3UbKy/Listeners', 17:57:50 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 17:57:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:50 INFO - 'USER': 'cltbld', 17:57:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:50 INFO - 'XPC_FLAGS': '0x0', 17:57:50 INFO - 'XPC_SERVICE_NAME': '0', 17:57:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:57:50 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-wqi_yp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:57:50 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)) 17:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:57:50 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-fXHsId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:57:50 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)) 17:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:57:50 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-NPhQdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:57:50 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)) 17:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:57:50 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-Li3A3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:57:51 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)) 17:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:57:51 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-9zsSkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:57:51 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)) 17:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:57:51 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-gvBs91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:57:51 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)) 17:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:57:51 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-08GIar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:57:51 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)) 17:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:57:51 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-bYQ9uo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:57:51 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)) 17:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:57:51 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-f86XdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:57:51 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)) 17:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:57:51 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-tkN5sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:57:51 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)) 17:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:57:51 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-HJBPYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:57:51 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)) 17:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:57:51 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-adgVKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:57:51 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)) 17:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:57:51 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-D4vse6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:57:51 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)) 17:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:57:51 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-tgxE4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:57:52 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)) 17:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:52 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-XF6kQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:52 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)) 17:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:57:52 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-UaWQCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:57:52 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)) 17:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:57:52 INFO - Running setup.py (path:/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/pip-MgN9da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:57:52 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)) 17:57:52 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)) 17:57:52 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)) 17:57:52 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)) 17:57:52 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)) 17:57:52 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)) 17:57:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:57:52 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 17:57:52 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 17:57:52 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 17:57:52 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 17:57:52 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 17:57:52 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 17:57:54 INFO - Downloading blessings-1.6.tar.gz 17:57:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:57:55 INFO - Installing collected packages: blessings 17:57:55 INFO - Running setup.py install for blessings 17:57:55 INFO - Successfully installed blessings 17:57:55 INFO - Cleaning up... 17:57:55 INFO - Return code: 0 17:57:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:57:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:57:55 INFO - Reading from file tmpfile_stdout 17:57:55 INFO - Current package versions: 17:57:55 INFO - blessings == 1.6 17:57:55 INFO - blobuploader == 1.2.4 17:57:55 INFO - docopt == 0.6.1 17:57:55 INFO - functools32 == 3.2.3-2 17:57:55 INFO - jsonschema == 2.5.1 17:57:55 INFO - manifestparser == 1.1 17:57:55 INFO - mozInstall == 1.12 17:57:55 INFO - mozcrash == 1.0 17:57:55 INFO - mozdebug == 0.1 17:57:55 INFO - mozdevice == 0.49 17:57:55 INFO - mozfile == 1.2 17:57:55 INFO - mozhttpd == 0.7 17:57:55 INFO - mozinfo == 0.9 17:57:55 INFO - mozleak == 0.1 17:57:55 INFO - mozlog == 3.4 17:57:55 INFO - moznetwork == 0.27 17:57:55 INFO - mozprocess == 0.25 17:57:55 INFO - mozprofile == 0.28 17:57:55 INFO - mozrunner == 6.13 17:57:55 INFO - mozscreenshot == 0.1 17:57:55 INFO - mozsystemmonitor == 0.3 17:57:55 INFO - moztest == 0.8 17:57:55 INFO - mozversion == 1.4 17:57:55 INFO - psutil == 3.1.1 17:57:55 INFO - requests == 1.2.3 17:57:55 INFO - wsgiref == 0.1.2 17:57:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:55 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 0x10fe89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc228561550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc67d30>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc5cbe8>, '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.NXka1i79HG/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.hkefD3UbKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 17:57:55 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 17:57:55 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 17:57:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXka1i79HG/Render', 17:57:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:55 INFO - 'HOME': '/Users/cltbld', 17:57:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:55 INFO - 'LOGNAME': 'cltbld', 17:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:55 INFO - 'MOZ_NO_REMOTE': '1', 17:57:55 INFO - 'NO_EM_RESTART': '1', 17:57:55 INFO - 'PAGER': '/bin/cat', 17:57:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:55 INFO - 'PWD': '/builds/slave/test', 17:57:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:55 INFO - 'SHELL': '/bin/bash', 17:57:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hkefD3UbKy/Listeners', 17:57:55 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 17:57:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:55 INFO - 'USER': 'cltbld', 17:57:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:55 INFO - 'XPC_FLAGS': '0x0', 17:57:55 INFO - 'XPC_SERVICE_NAME': '0', 17:57:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:55 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)) 17:57:55 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)) 17:57:55 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)) 17:57:55 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)) 17:57:55 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)) 17:57:55 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)) 17:57:55 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)) 17:57:55 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)) 17:57:55 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)) 17:57:55 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)) 17:57:55 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)) 17:57:55 INFO - Cleaning up... 17:57:55 INFO - Return code: 0 17:57:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc228561550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc67d30>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc5cbe8>, '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.NXka1i79HG/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.hkefD3UbKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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 17:57:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:57:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXka1i79HG/Render', 17:57:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:55 INFO - 'HOME': '/Users/cltbld', 17:57:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:55 INFO - 'LOGNAME': 'cltbld', 17:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:55 INFO - 'MOZ_NO_REMOTE': '1', 17:57:55 INFO - 'NO_EM_RESTART': '1', 17:57:55 INFO - 'PAGER': '/bin/cat', 17:57:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:55 INFO - 'PWD': '/builds/slave/test', 17:57:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:55 INFO - 'SHELL': '/bin/bash', 17:57:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hkefD3UbKy/Listeners', 17:57:55 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 17:57:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:55 INFO - 'USER': 'cltbld', 17:57:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:55 INFO - 'XPC_FLAGS': '0x0', 17:57:55 INFO - 'XPC_SERVICE_NAME': '0', 17:57:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:57:56 INFO - Cleaning up... 17:57:56 INFO - Return code: 0 17:57:56 INFO - Running post-action listener: _resource_record_post_action 17:57:56 INFO - Running post-action listener: _start_resource_monitoring 17:57:56 INFO - Starting resource monitoring. 17:57:56 INFO - [mozharness: 2017-03-29 00:57:56.242080Z] Finished create-virtualenv step (success) 17:57:56 INFO - [mozharness: 2017-03-29 00:57:56.243026Z] Running install step. 17:57:56 INFO - Running pre-action listener: _resource_record_pre_action 17:57:56 INFO - Running main action method: install 17:57:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:57:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:57:56 INFO - Reading from file tmpfile_stdout 17:57:56 INFO - Detecting whether we're running mozinstall >=1.0... 17:57:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:57:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:57:56 INFO - Reading from file tmpfile_stdout 17:57:56 INFO - Output received: 17:57:56 INFO - Usage: mozinstall [options] installer 17:57:56 INFO - Options: 17:57:56 INFO - -h, --help show this help message and exit 17:57:56 INFO - -d DEST, --destination=DEST 17:57:56 INFO - Directory to install application into. [default: 17:57:56 INFO - "/builds/slave/test"] 17:57:56 INFO - --app=APP Application being installed. [default: firefox] 17:57:56 INFO - mkdir: /builds/slave/test/build/application 17:57:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:57:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:58:17 INFO - Reading from file tmpfile_stdout 17:58:17 INFO - Output received: 17:58:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:58:17 INFO - Running post-action listener: _resource_record_post_action 17:58:17 INFO - [mozharness: 2017-03-29 00:58:17.044535Z] Finished install step (success) 17:58:17 INFO - [mozharness: 2017-03-29 00:58:17.044696Z] Running run-tests step. 17:58:17 INFO - Running pre-action listener: _resource_record_pre_action 17:58:17 INFO - Running main action method: run_tests 17:58:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:58:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:58:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:58:17 INFO - 2017-03-28 17:58:17.059 screenresolution[1888:8835] starting screenresolution argv=screenresolution get 17:58:17 INFO - 2017-03-28 17:58:17.062 screenresolution[1888:8835] Display 0: 1600x1200x32@60 17:58:17 INFO - 2017-03-28 17:58:17.067 screenresolution[1889:8837] starting screenresolution argv=screenresolution list 17:58:17 INFO - Available Modes on Display 0 17:58:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:58:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:58:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:58:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:58:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:58:17 INFO - Intel Iris: 17:58:17 INFO - Chipset Model: Intel Iris 17:58:17 INFO - Type: GPU 17:58:17 INFO - Bus: Built-In 17:58:17 INFO - VRAM (Dynamic, Max): 1536 MB 17:58:17 INFO - Vendor: Intel (0x8086) 17:58:17 INFO - Device ID: 0x0a2e 17:58:17 INFO - Revision ID: 0x0009 17:58:17 INFO - Displays: 17:58:17 INFO - Display: 17:58:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:58:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:58:17 INFO - Display Serial Number: 1600x1200 60 17:58:17 INFO - Main Display: Yes 17:58:17 INFO - Mirror: Off 17:58:17 INFO - Online: Yes 17:58:17 INFO - Rotation: Supported 17:58:17 INFO - Return code: 0 17:58:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:58:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:58:17 INFO - Python 2.7.3 17:58:17 INFO - Return code: 0 17:58:17 INFO - grabbing minidump binary from tooltool 17:58:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:58:17 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 0x10fc67d30>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fc5cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:58:17 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 17:58:17 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 17:58:17 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 17:58:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:58:17 INFO - Return code: 0 17:58:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:58:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:58:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:58:17 INFO - ENV: RUST_BACKTRACE is now 1 17:58:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:58:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:58:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--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 17:58:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0018 --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 17:58:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NXka1i79HG/Render', 17:58:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:17 INFO - 'HOME': '/Users/cltbld', 17:58:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:17 INFO - 'LOGNAME': 'cltbld', 17:58:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:58:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:58:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:17 INFO - 'MOZ_NO_REMOTE': '1', 17:58:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:58:17 INFO - 'NO_EM_RESTART': '1', 17:58:17 INFO - 'PAGER': '/bin/cat', 17:58:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:17 INFO - 'PWD': '/builds/slave/test', 17:58:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:58:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:17 INFO - 'RUST_BACKTRACE': '1', 17:58:17 INFO - 'SHELL': '/bin/bash', 17:58:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hkefD3UbKy/Listeners', 17:58:17 INFO - 'TMPDIR': '/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/', 17:58:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:17 INFO - 'USER': 'cltbld', 17:58:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:17 INFO - 'XPC_FLAGS': '0x0', 17:58:17 INFO - 'XPC_SERVICE_NAME': '0', 17:58:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--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 17:58:17 INFO - mozversion application_buildid: 20170328152823 17:58:17 INFO - mozversion application_changeset: bbab5665154904a6f4cd39b83bd9140dc5888d81 17:58:17 INFO - mozversion application_display_name: Nightly 17:58:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:58:17 INFO - mozversion application_name: Firefox 17:58:17 INFO - mozversion application_remotingname: firefox 17:58:17 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 17:58:17 INFO - mozversion application_vendor: Mozilla 17:58:17 INFO - mozversion application_version: 55.0a1 17:58:17 INFO - mozversion platform_buildid: 20170328152823 17:58:17 INFO - mozversion platform_changeset: bbab5665154904a6f4cd39b83bd9140dc5888d81 17:58:17 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 17:58:17 INFO - mozversion platform_version: 55.0a1 17:58:17 INFO - using testdate: 1490749097 17:58:17 INFO - actual date: 1490749097 17:58:17 INFO - starting webserver on 'localhost:49430' 17:58:17 INFO - SUITE-START | Running 2 tests 17:58:17 INFO - TEST-START | dromaeo_css 17:58:17 INFO - operating with platform_type : mac_ 17:58:17 INFO - Initialising browser for dromaeo_css test... 17:58:17 INFO - TEST-INFO | started process 1899 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmpk2xChC/profile http://localhost:49430/getInfo.html) 17:58:20 INFO - PID 1899 | __metrics Screen width/height:1600/1200 17:58:20 INFO - PID 1899 | colorDepth:24 17:58:20 INFO - PID 1899 | Browser inner width/height: 1024/689 17:58:20 INFO - PID 1899 | __metrics 17:58:20 ERROR - PID 1899 | 1490749100068 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:58:20 INFO - PID 1899 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:58:20 INFO - PID 1899 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:58:20 INFO - TEST-INFO | 1899: exit 0 17:58:20 INFO - Browser initialized. 17:58:20 INFO - Running cycle 1/1 for dromaeo_css test... 17:58:20 INFO - TEST-INFO | started process 1901 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmpk2xChC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:58:33 INFO - PID 1901 | RSS: Main: 234721280 17:58:33 INFO - PID 1901 | 18:01:38 INFO - PID 1901 | Cycle 1(1): loaded http://localhost:49430/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49430/tests/dromaeo/cssquery-ext.html) 18:01:40 INFO - PID 1901 | RSS: Main: 373587968 18:01:40 INFO - PID 1901 | 18:03:23 INFO - PID 1901 | ************************* 18:03:23 INFO - PID 1901 | A coding exception was thrown and uncaught in a Task. 18:03:23 INFO - PID 1901 | 18:03:23 ERROR - PID 1901 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:03:23 INFO - PID 1901 | Full stack: 18:03:23 INFO - PID 1901 | ************************* 18:03:23 INFO - PID 1901 | ************************* 18:03:23 INFO - PID 1901 | A coding exception was thrown and uncaught in a Task. 18:03:23 INFO - PID 1901 | 18:03:23 ERROR - PID 1901 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:03:23 INFO - PID 1901 | Full stack: 18:03:23 INFO - PID 1901 | ************************* 18:03:23 INFO - PID 1901 | ************************* 18:03:23 INFO - PID 1901 | A coding exception was thrown and uncaught in a Task. 18:03:23 INFO - PID 1901 | 18:03:23 ERROR - PID 1901 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:03:23 INFO - PID 1901 | Full stack: 18:03:23 INFO - PID 1901 | ************************* 18:03:23 INFO - PID 1901 | ************************* 18:03:23 INFO - PID 1901 | A coding exception was thrown and uncaught in a Task. 18:03:23 INFO - PID 1901 | 18:03:23 ERROR - PID 1901 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:03:23 INFO - PID 1901 | Full stack: 18:03:23 INFO - PID 1901 | ************************* 18:04:43 INFO - PID 1901 | Cycle 1(1): loaded http://localhost:49430/tests/dromaeo/cssquery-ext.html (next: http://localhost:49430/tests/dromaeo/cssquery-jquery.html) 18:04:44 INFO - PID 1901 | RSS: Main: 371085312 18:04:44 INFO - PID 1901 | 18:07:46 INFO - PID 1901 | Cycle 1(1): loaded http://localhost:49430/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49430/tests/dromaeo/cssquery-mootools.html) 18:07:47 INFO - PID 1901 | RSS: Main: 334086144 18:07:47 INFO - PID 1901 | 18:10:50 INFO - PID 1901 | Cycle 1(1): loaded http://localhost:49430/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49430/tests/dromaeo/cssquery-prototype.html) 18:10:50 INFO - PID 1901 | RSS: Main: 338169856 18:10:50 INFO - PID 1901 | 18:13:54 INFO - PID 1901 | Cycle 1(1): loaded http://localhost:49430/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49430/tests/dromaeo/cssquery-yui.html) 18:13:55 INFO - PID 1901 | RSS: Main: 339963904 18:13:55 INFO - PID 1901 | 18:16:58 INFO - PID 1901 | Cycle 1(1): loaded http://localhost:49430/tests/dromaeo/cssquery-yui.html (next: http://localhost:49430/tests/dromaeo/cssquery-dojo.html) 18:16:58 INFO - PID 1901 | RSS: Main: 523763712 18:16:58 INFO - PID 1901 | 18:16:58 INFO - PID 1901 | __start_tp_report 18:16:58 INFO - PID 1901 | _x_x_mozilla_page_load 18:16:58 INFO - PID 1901 | _x_x_mozilla_page_load_details 18:16:58 INFO - PID 1901 | |i|pagename|runs| 18:16:58 INFO - PID 1901 | |0;dojo.html;3450.4479283314645;3462.7402944779133;3491.8079505627197;3502.1594817243854;3526.1889765353967;6041.365656606059;6144.109104179903;6252.499800015999;6253.1245625612455;6257.155284036659;6118.357572454884;6139.048447490635;6151.507879369651;6154.876902461948;6159.414855588723;4787.3058406530945;5579.218909352674;5597.6641401515835;5604.943950560503;5813.418658134195;5714.428557144294;5714.9714251428895;5715.828525144231;5716.685582292972;5727.684977326246;5480.04099282626;5616.045272303698;5715.199872017921;5746.166805813175;5766.106253530709;5479.753411096471;5514.476124768141;5632.887342253132;5669.0646043402785;5701.173329867146;5402.51377376038;5528.917066244009;5535.640183388107;5557.3331200255825;5558.221848941152;6110.862540392697;6112.541559383064;6113.059265338615;6121.209103132296;6138.84652883677;6397.5201859860235;6562.639054851981;6573.868522629568;6623.53635245533;6636.203655561315;5871.295444546639;6726.495512836508;6742.8314292142595;6743.662816859187;6786.321367863224;6187.5149964008315;6295.150154729099;6317.178766760384;6323.30443651198;6328.778492752824;6571.3757193066585;6650.501212409089;6651.301613330627;6660.034295027194;6683.331666833278;6330.68346582669;6336.6831658416895;6367.872642547123;6437.7103030362805;6523.8695226095215;6379.202599675041;6443.548951573345;6445.1943507061615;6601.306862779339;6662.733490660319;6605.471562275006;6605.669716514155;6627.9337206627315;6634.535582509178;6636.668166591651;6646.468282537386;6656.7670131545165;6657.300983396771;6671.699773510204;6672.7330906764155;5227.686338819683;5274.947250527445;5289.3917199521775;5329.440408757102;5330.546903513167;4697.741624210633;4698.718076915396;4739.715617062987;4746;4772.880677983078;6305.558610897193;6319.873602527924;6337.683115844282;6341.55609107358;6344.651044192614;6646.102776125258;6650.900236496456;6697.497687673346;6702.296258892844;6721.1598550181225;6116.33577150815;6196.969015154896;6203.658798766111;6206.751729930843;6219.533534984895;5809.215755872988;5817.970910145422;5822.941770582324;5823.213866128019;5825.7087145642545;6679.398854103154;6712.93287067123;6717.731290748316;6729.4279986200745;6752.8649427010205;6482.416582507597;6556.409923106752;6559.311272316529;6612.0743095965645;6691.665416729047;6901;6914.412274956484;6914.757983470555;6915.446764258948;6923.9653801730665;6593.208814942238;6594.208694956444;6597.670116494252;6600.53796234259;6608.471322294301;6392.87214255703;6445.70994305248;6449.677516124269;6659.733610655713;6714.52998290115;5318.308619879391;6383.680815959091;6411.647359395278;6427.228732552124;6453.548251622341;6484;6492.0261960706275;6503.967480162569;6516.6415847128865;6517.543771936109;6210.347913469021;6289.213848268966;6299.338569450325;6300.338464461349;6375.362463753588;6406.295307516262;6436.16329877113;6442.935570644421;6482.513811464065;6484.059811327237;5443.537299329443;6319.96840015797;6325.588836725598;6336.429721325103;6342.841428964128 18:16:58 INFO - PID 1901 | |1;ext.html;768.2010708862782;799.2846402469788;1121.5738019552568;1138.2715062360091;1138.6925530106876;36059.63940360596;36927;37034.25931481369;37062.62937370629;37259.4411083834;32533.67466325336;38832.02919927003;39204.21591568167;39239;39357;6107.847303817418;8470.025947016095;8616.870746938801;8635.093315201908;8653.226088651843;6210.50315974721;6309.0536419536975;6314.526410519206;6319.936800632004;8297.709580164696;8541.743747687578;8589.80959855236;8603.268722158624;8614.224719775219;8619.267362274215;8517.914820851774;8589.570521473965;8590.398672092955;8598.613062412207;8611.61247743853;8384.077751447336;8413.915860841375;8452.957735211285;8455.661773529051;8475.872861907077;38278.85163445073;38564.614353856385;38583.614163858285;38606.80696596527;38629.03427414309;2560.3215147985798;3043.802152860057;3049.451098802215;3063.203567072555;3085.058618109254;27756.30609234765;27793.305167370774;27893.58159627607;28940.131796046153;29089.12732618025;2770.598263251817;2771.9445611087667;2774.750272475487;2790.2326860113426;2821.478026565092;324925;326227;326914;327962.9999999976;329458;37462.87611371663;38241;50352.748236259315;50609.746951265006;50620.49379506231;39923.201535969696;39923.60076399257;39941.40234390592;40009;42160.78919605444;13388.598342049754;13776.10455320401;13817.170969741848;13818.792718109236;13845.446182152802;9844.015598440099;10021.148202402732;10042.799144017079;11289.37908415045;11291.717707057389;10784.94607526973;10871.347719136877;10880.891191087987;12092.939535302443;12257.570985015482;5109.284700141983;5202.531772140525;5226.92159617606;5230.764615592308;5233.921491177635;767.4742801181209;890.7327801659476;943.8018016216535;952.0955092661999;969.8060387922444;20356.69464958027;23051.884740576188;23715.88142059279;23784.643230351558;23810.52378952411;1020.1125021231576;1025.3898930136556;1058.074185088048;1058.2274939294205;1059.359087751899;11393.943030284796;11886.583969561198;12073.818892716616;12134.575289864812;12136.33250171249;422230;423042;423598;423975;424748;54337.45662543324;54432.45567544274;54453.99999999921;54569.45430545723;54716;1433.7132573485344;1466.7066586682492;1469.7942288079678;1471.9926400367929;1485.5394827603477;22379.77620223777;23042.308730738103;23176.652350215096;23242.070317187125;23244.3026709199;38185.236295274495;38227.80886095552;38321.4251786229;38370.61629383782;38373.424398633484;349032;349074;350043;350191.24904375314;350300;31817.681823181472;32265.87069452558;32448.35103297968;32481.512777307886;32617;29236.26909327284;30688.53967190449;33534.66465335315;40537.391939121524;42364.72905812831;26515.60226596564;26935.86532067327;29582.964596239028;29691.40617187601;29945.251368715955;28970;29686;29801.552976704934;29858;29871.253218669706;34526.48210276898;34993.650063500056;35154.64845351616;35160.29679406449;35457;29956.850215748782;30256.680556522468;30262.84868575643;30926.690733092382;31256.499952727543;20693.58612827765;23490.412739681644;24283.150089746774;24348;24872 18:16:58 INFO - PID 1901 | |2;jquery.html;417.55947475413427;419.93281075028;429.8602954039936;431.9892002699933;445.3809205204766;17898.552536186602;18517.16672749726;20477.48806279842;21204.25785097522;21272.255471058517;18344.724829127576;22189.889050554724;22520.54958900821;23176;23555.175568855095;6054.8486287842825;6386.64873431961;6398.392152745481;6399.776007839727;6413.326600706905;5996.22049133612;6196.194494715681;6209.782657606985;6284.057391391305;6292.433680968712;6227;6238.126662267264;6242.906356404657;6244.56288059836;6247.375262473762;5973.492253158465;6256.2805277393;6310.9999999999545;6343.270523889742;6345.587536810139;5338.145896656555;5482.91775623366;5595.300587426572;5602.943970560324;5604.075327570946;26794.464110717872;27010.5948410774;27042.5943610846;27084.86457567719;27264.45471090587;2025.3316405586122;2028.920802639094;2049.1496029147884;2080.739907511561;2118.925837595677;16401;17194.398196063205;17238.396656116976;17292.65414691699;17602.647947040987;1475.350845627931;1652.3886162120093;1687.8480936715755;1690.475952454743;1692.9285069408093;125906.37046814707;138837;144708.27645861704;145758.27120864435;149345;11844.28934263947;12135.878641213474;12186.268823870596;12216.633500994983;12333.753324933452;16039;16058;16100.516984490485;16121;16142.596435088983;8501.107383724744;8825.38222324444;8837.160469755363;8959.148880856308;9048.547572621343;7032.789016329519;7590.962045189849;7793.688252469951;7947.721829736047;8438.45150065256;6304.306526282106;7411.332980031715;7542.396608271325;7588.268223439557;7616.276453736886;2125.6077269388575;2133.5172055421335;2136.8504204705832;2138.6150492911424;2148.3232781673723;868.9522076285739;901.3915606965403;921.2077613252598;930.2976252928995;951.509972364233;20247.797522024885;20724.481887952923;20797.688034679188;20829.375118746462;21208.575828483343;931.3852857114407;940.6660635474445;980.6273616026007;983.5573991703762;984.2962281968283;9828.953024562408;10765.407902565277;10768.515416806262;10820.67537973862;10829.620963266249;688351;696127;700698;713045.4347728331;716405;160143.19928400515;163374;164057;164651.5302270467;166325.16837415737;1010.9839611190706;1170.1048697746062;1197.1679682620495;1223.3577371879835;1226.8589112252027;19876.608637395242;20211.79788202158;20339.491512712302;21756.35079188753;21910.561788764455;56851;57453.85092298047;58204.41795582159;58303;58434.707826460595;82999;83069.58465207636;83735;84501.30997380141;87657;8483.787905302417;8513.106123856905;8519.659213631385;8707.434016788888;9050.728478145666;13158.40787164599;13271.800922985969;13370.465181393025;13800.102993305403;14241.50154744579;10575.788484230427;10828.079613232963;11056.004959553678;11167.218294719292;11250.268732532686;9665.565049572646;10509.632162874568;10576.101031412416;11103.222774405715;11265.324080555192;11674;11719.648410547697;12003.339816310187;12054.939725301318;12108.455119519818;8452.281556067557;8553.572321383781;8799.252063574668;9067.591958362022;9124.771880703036;10967.50646220938;11192.328460292409;11537.769244615229;11592.942035289769;11596.826047609462 18:16:58 INFO - PID 1901 | |3;mootools.html;1178.3754610056662;1248.6254123762872;1440.834304055034;1449.833269174045;1454.4036944852612;9183.311251656127;9630.277729170313;10731.302465339748;10994.450277486134;11013.889861101386;10655.520501577428;10752.03231709146;10758.139348852093;10777.353358798458;10808.243422960395;2279.2592407467555;2323.465602911331;2324.5234726881035;2326.081197926818;2328.860268383902;2082.98958505208;2166.2959538150085;2166.7074944882397;2168.1327469012444;2173.9239126630573;2053.1068984991502;2097.737782777153;2228.3092241405134;2303.1248125712204;2308.8268379871492;2321.593721098801;2427.186892391051;2427.2111563741764;2427.684401027855;2429.708434987795;2044.1925439451334;2334.8365614407;2382.4996750682376;2382.630692242705;2383.48755017672;9418.905810941871;10459.529321180566;10693.946530267376;10728.785424291471;10745.19411044167;6076.574639775217;6332.525060620472;6333.556651034383;6340.936590634081;6341.365863413668;4006.8397264109694;4030.6573941215033;4087.9795601021806;4102.928021349229;4116.156187981486;1160.7446361800392;1168.4800263882576;1188.2751521571834;1217.1297522271618;1226.1662069792537;12446.502139914393;15371.769423458658;15376.308066136939;15377.077375357403;15429.382824687;5302.443243459458;5497.505224529811;5561.110222364402;5570.470805273492;5725.08398656213;12550.74898502025;12630.494780208872;12631.052671049709;12645.051621128452;12653.493860245488;6315.36846315374;6377.936220637827;6395.968020159962;6397.040443933354;6399.424051835357;5637.68992705397;5966.850828729316;6019.879602407927;6039.1545183674325;6047.2743270806905;4242.278812601865;4399.64802815774;4453.75504347264;4500.459944806578;4517.164324599959;1185.211834130311;1354.8577399372923;1416.3201663201514;1423.950161744334;1439.7912302716047;624.5347216323868;746.1531162131021;782.6008735544913;788.2511613966755;824.0729179672868;5580.302462192226;6113.8471538211015;6290.779822706183;6476.643784591798;6477.708503117371;514.5857584644323;556.6993823335354;582.7825125397817;589.8024161905723;599.5203836930439;3848.8845334640005;3970.781607011642;4044.9797751011056;4110.280700877334;4164.604362585549;13253.469861205444;13868.306584670727;13886.4445422184;13887.236202008784;13895.166290022631;12780.488780448864;13759.449622015007;13769.862301377058;13778.65553361174;13800.964927630468;9725.374148979736;9975.25185611082;9995.750106247402;10005.549750261402;10027.749306267257;6320.873582528416;6347.5556711029785;6425.485961123192;6645.667716614246;6665.566738162004;11935.104867134825;12154;12165.856242597432;12274.570390036306;12307.44616492278;12186.57346992851;12655.93672031634;12675.746485070431;12682.936585317015;12700.36498175106;8911.37620366568;9240.260779137787;9248.491332976482;9264.11990860867;9291.72124836256;8482.406231563979;9626.229901607714;9923.65267215644;10055.748606284902;10177.745556360855;8409.95795021021;8444.619992100494;8504.489730616182;8556.614952327285;8581.35639827003;8136.959315203386;8423.4945903246;8444.662213511636;8457.323414126731;8616.78458038554;7705.152433232228;7720.150783413932;7722.15056343813;7817.609119543887;7838.843223135391;5249;5678.5457163426;5812.5349972001295;5834.27071616048;5865.677387743715;5789.33422656383;5943.465088142088;6019.849503762266;6067.666278354556;6235.657038862906 18:16:58 INFO - PID 1901 | |4;prototype.html;355.3284292686821;387.9262940041392;391.13949311514665;396.9305371559976;399.4188455796816;6090.969545152268;6125.693715314228;6129.54028447867;6151.907721384183;6171.4445699887;5458.863528411782;5763.760791429841;5804.216430781854;5890.3226128995075;5922.703864806764;3550.431930891058;4697.131030759303;4703.412073490814;4732.929006064912;4770.713757174563;4698.2482802751565;4711.623070154379;4717.9292310615365;4719.669623126382;4732.195526760442;4308.3537469379535;4506.414166158394;4592.425946756655;4679.976600116995;4704.6236301096005;4262.488501379854;4273.829046838123;4538.205813982573;4546.954530454686;4555.521670224628;3743.008102852719;3907.8241479133508;3911.3155197840265;3923.4114882767526;3928.5285765708013;5601.215829783833;5792.855178620526;5909.497692696126;5924.851878703023;6015.0150450351275;4413.095315460323;4527.298268768313;5226.92159617606;5258.81594144207;5295.523402893758;5335.546478549328;5643.802466913638;5665.781856900745;5723.112917497794;6531;4168.6039826216465;4272.188284226018;4281.914361712748;4320.351947207944;4329.502107257674;6542.509311801634;6639.734410623618;6748.426383757435;6798.796036118875;6899.896501552481;4307.418498502725;4405.757683327448;4409.162259170747;4419.049904270597;4437.223485890021;3802.7338086334244;3805.1818858945458;3920.3923391874305;4461.475434406782;4493.752843593568;4365.781710914442;4862.975685121552;5042.4705405931845;5151.815082530964;5266.315379000705;5203.583713303003;6442.194725659293;6506.41442270198;6549.2468366137555;6561.40947314744;1486.680363721805;1508.6530098077285;1517.529565834595;1522.3377830643706;1531.2802982598162;886.8802711633847;982.4301904895144;997.2769741937037;1000.5297510170209;1012.832882574367;5750.5112065474905;6225.3774622537385;6228.723111762078;6234.671808114767;6237.438630523275;6423.582467139714;6640.115532413495;6765.492588055817;6777.72889084441;6835.282295359015;5947.345791962881;6120.479759220427;6145.26256849172;6217.222847144107;6224.844378890564;2972.316367235548;3325.617553981275;3475.5829300483597;3499.4051011328133;3529.435290353531;6022.651166045246;6847.144106986627;6911;6916.377526022682;6921.581075879432;5408.91886621701;6361.61830290184;6841.589504629738;6909.412699920463;6943.444524438033;6682.79951601443;6784.7964561063245;6856.24581296057;6924.896126558207;6938.306169383021;6162.630242185304;6667;6728.865422691617;6734.1043351359385;6784.491163162881;5080.822171223989;5414.83755487336;5805.564582656233;6328.6202827830475;6637.004449332446;6030.638161710312;6464.903026454701;6599.835004124935;6845.657717114024;6852.349026842442;4245.68157388191;4397.494288156775;4406.581374769392;4407.7796110195;4418.7127836690515;5679.6876171810945;6584.407403333723;6650.002499624901;6682.8973219420195;6740.224874139537;5776.942230577641;6447.613763040781;6491.318411566906;6500.967495162494;6524.67376631176;6346.174997250512;6450.903236451363;6466.482681385572;6498.64257465825;6507.056476810931;6288.208734566566;6324.525660575383;6336.746530138743;6355.586886852339;6367;6484.416402523795;6493.8701225976165;6496.707648155939;6504.544681872224;6521.543491955707;5231.581473482036;6293.779717709858;6365.299817020032;6381.489480841428;6393.520485963479 18:16:58 INFO - PID 1901 | |5;yui.html;717.3292971072048;720.7405334079733;733.7872017115036;736.4071922102706;767.3669222891116;15025.549233522997;16362.263698133584;16509.504714858544;17218.569535761613;18182.27270909162;13797.51708690196;17023.659526809457;17071.57321066974;17559.122043897787;17729;1008.9243306751986;1036.8548403223556;1038.9168866490663;1041.828098363769;1043.4209013997267;986.7089208683419;1005.8742657167854;1017.5726194998118;1019.1846522781782;1023.2837014090129;1056.3028401255174;1058.534244932231;1059.3379138038727;1059.5496913811598;1073.849661047454;1058.5554067291716;1058.8305871060593;1060.210143443132;1061.7929503746734;1063.7181146996052;792.1682233654698;795.3438413308985;795.8420498175199;796.080526991326;796.6454927557239;16365.509034728977;16692;16770.245338959776;16848.91575542127;17125.57186070358;12643.367831608382;14543.78184327236;14829.110253384832;14895.957282990195;14953;11749.177557570973;11853.348065856375;11886.643400697993;12081.227914973624;12112.333821639806;2974.5389464633017;3066.9483863555683;3084.2597776533694;3181.5545823584716;3186;88010.11989880083;110086;112096.53664308495;112955;116344.41827790892;11241.923691003723;11392.658220253406;11439.313641181556;11600.709982250342;11812.350320732443;23852.284431467084;24038.39904002414;24069.398265043514;24249;24531;8049.839003219903;8157.265846073882;8165.918340816515;8167.918320816716;8214.876776848352;3651.379265524867;4169.666426685919;4172.520160181557;4228;4228.640565551901;4105.527864295585;4179.1014931789205;4210.347396153601;4223.155368926227;4228.76741779199;824.7360844529693;829.4567058576642;841.4908980067094;845.6617353058825;854.457419538585;529.1797713544069;529.6266132376666;584.9064149736107;588.5968111843315;612.0941007309118;8917.331200160013;9448.960614332418;9534.809303813885;10189.337693050076;10329.12202462784;544.8556132624858;575.6143383932775;578.4504720515528;594.4199939466401;599.1970759182716;4576.610988065985;4751.23709389968;4798.2082956311815;4810.784747456145;4906.8773280668265;85728.57135714282;90578.64132038025;91054;91836.54081729549;92526;53839.46160538345;55367.89264214693;66236.66881665561;77068;78771;70134.64932675305;70849.6457517709;72964;73925.6303718478;75157.24842751611;10456.477176141314;10774.245802794043;10927;10997.670069897917;11147.498362573542;35555.644443556266;36256.19309972415;36766.32336766302;36971;37538.436923445624;39393.80303098466;46556;50196.24313149022;50274.74862625664;50344.74827625985;13246.337683115999;13310.173053213784;13408.396622152222;13854.37655305534;14228.861691064896;14366.497172598909;14393.136411815325;14393.568192954228;14396.99221054558;14423.567292981228;11657;13113.672158196121;13509.662258443617;13526.729465410834;14068.929655352067;13103.279319637511;13241.735165296834;13376.331183440983;13490;13701.931490342484;12175.39123043862;12259.264444133381;12363.505459781509;12363.93818030904;12710.428030738824;9256.95371523165;9324.720258392259;9403.34176607631;9517.238620910171;10094.747631308983;7575.166731659623;10154.289199755945;10628.309159904582;10851.457427128455;11200.943995279971 18:16:58 INFO - PID 1901 | __end_tp_report 18:16:58 INFO - PID 1901 | __start_cc_report 18:16:58 INFO - PID 1901 | _x_x_mozilla_cycle_collect,1952 18:16:58 INFO - PID 1901 | __end_cc_report 18:16:58 INFO - PID 1901 | __startTimestamp1490750218662__endTimestamp 18:16:58 INFO - PID 1901 | 18:16:58 INFO - PID 1901 | ------- Summary: start ------- 18:16:58 INFO - PID 1901 | Number of tests: 6 18:16:58 INFO - PID 1901 | 18:16:58 INFO - PID 1901 | [#0] dojo.html Cycles:165 Average:6126.03 Median:6336.43 stddev:686.51 (10.8%) stddev-sans-first:655.74 18:16:58 INFO - PID 1901 | Values: 3450.4 3462.7 3491.8 3502.2 3526.2 6041.4 6144.1 6252.5 6253.1 6257.2 6118.4 6139.0 6151.5 6154.9 6159.4 4787.3 5579.2 5597.7 5604.9 5813.4 5714.4 5715.0 5715.8 5716.7 5727.7 5480.0 5616.0 5715.2 5746.2 5766.1 5479.8 5514.5 5632.9 5669.1 5701.2 5402.5 5528.9 5535.6 5557.3 5558.2 6110.9 6112.5 6113.1 6121.2 6138.8 6397.5 6562.6 6573.9 6623.5 6636.2 5871.3 6726.5 6742.8 6743.7 6786.3 6187.5 6295.2 6317.2 6323.3 6328.8 6571.4 6650.5 6651.3 6660.0 6683.3 6330.7 6336.7 6367.9 6437.7 6523.9 6379.2 6443.5 6445.2 6601.3 6662.7 6605.5 6605.7 6627.9 6634.5 6636.7 6646.5 6656.8 6657.3 6671.7 6672.7 5227.7 5274.9 5289.4 5329.4 5330.5 4697.7 4698.7 4739.7 4746.0 4772.9 6305.6 6319.9 6337.7 6341.6 6344.7 6646.1 6650.9 6697.5 6702.3 6721.2 6116.3 6197.0 6203.7 6206.8 6219.5 5809.2 5818.0 5822.9 5823.2 5825.7 6679.4 6712.9 6717.7 6729.4 6752.9 6482.4 6556.4 6559.3 6612.1 6691.7 6901.0 6914.4 6914.8 6915.4 6924.0 6593.2 6594.2 6597.7 6600.5 6608.5 6392.9 6445.7 6449.7 6659.7 6714.5 5318.3 6383.7 6411.6 6427.2 6453.5 6484.0 6492.0 6504.0 6516.6 6517.5 6210.3 6289.2 6299.3 6300.3 6375.4 6406.3 6436.2 6442.9 6482.5 6484.1 5443.5 6320.0 6325.6 6336.4 6342.8 18:16:58 INFO - PID 1901 | 18:16:58 INFO - PID 1901 | [#1] ext.html Cycles:180 Average:49573.92 Median:34030.57 stddev:97768.02 (287.3%) stddev-sans-first:97973.62 18:16:58 INFO - PID 1901 | Values: 768.2 799.3 1121.6 1138.3 1138.7 36059.6 36927.0 37034.3 37062.6 37259.4 32533.7 38832.0 39204.2 39239.0 39357.0 6107.8 8470.0 8616.9 8635.1 8653.2 6210.5 6309.1 6314.5 6319.9 8297.7 8541.7 8589.8 8603.3 8614.2 8619.3 8517.9 8589.6 8590.4 8598.6 8611.6 8384.1 8413.9 8453.0 8455.7 8475.9 38278.9 38564.6 38583.6 38606.8 38629.0 2560.3 3043.8 3049.5 3063.2 3085.1 27756.3 27793.3 27893.6 28940.1 29089.1 2770.6 2771.9 2774.8 2790.2 2821.5 324925.0 326227.0 326914.0 327963.0 329458.0 37462.9 38241.0 50352.7 50609.7 50620.5 39923.2 39923.6 39941.4 40009.0 42160.8 13388.6 13776.1 13817.2 13818.8 13845.4 9844.0 10021.1 10042.8 11289.4 11291.7 10784.9 10871.3 10880.9 12092.9 12257.6 5109.3 5202.5 5226.9 5230.8 5233.9 767.5 890.7 943.8 952.1 969.8 20356.7 23051.9 23715.9 23784.6 23810.5 1020.1 1025.4 1058.1 1058.2 1059.4 11393.9 11886.6 12073.8 12134.6 12136.3 422230.0 423042.0 423598.0 423975.0 424748.0 54337.5 54432.5 54454.0 54569.5 54716.0 1433.7 1466.7 1469.8 1472.0 1485.5 22379.8 23042.3 23176.7 23242.1 23244.3 38185.2 38227.8 38321.4 38370.6 38373.4 349032.0 349074.0 350043.0 350191.2 350300.0 31817.7 32265.9 32448.4 32481.5 32617.0 29236.3 30688.5 33534.7 40537.4 42364.7 26515.6 26935.9 29583.0 29691.4 29945.3 28970.0 29686.0 29801.6 29858.0 29871.3 34526.5 34993.7 35154.6 35160.3 35457.0 29956.9 30256.7 30262.8 30926.7 31256.5 20693.6 23490.4 24283.2 24348.0 24872.0 18:16:58 INFO - PID 1901 | 18:16:58 INFO - PID 1901 | [#2] jquery.html Cycles:180 Average:40440.84 Median:22848.27 stddev:117832.54 (515.7%) stddev-sans-first:118124.77 18:16:58 INFO - PID 1901 | Values: 417.6 419.9 429.9 432.0 445.4 17898.6 18517.2 20477.5 21204.3 21272.3 18344.7 22189.9 22520.5 23176.0 23555.2 6054.8 6386.6 6398.4 6399.8 6413.3 5996.2 6196.2 6209.8 6284.1 6292.4 6227.0 6238.1 6242.9 6244.6 6247.4 5973.5 6256.3 6311.0 6343.3 6345.6 5338.1 5482.9 5595.3 5602.9 5604.1 26794.5 27010.6 27042.6 27084.9 27264.5 2025.3 2028.9 2049.1 2080.7 2118.9 16401.0 17194.4 17238.4 17292.7 17602.6 1475.4 1652.4 1687.8 1690.5 1692.9 125906.4 138837.0 144708.3 145758.3 149345.0 11844.3 12135.9 12186.3 12216.6 12333.8 16039.0 16058.0 16100.5 16121.0 16142.6 8501.1 8825.4 8837.2 8959.1 9048.5 7032.8 7591.0 7793.7 7947.7 8438.5 6304.3 7411.3 7542.4 7588.3 7616.3 2125.6 2133.5 2136.9 2138.6 2148.3 869.0 901.4 921.2 930.3 951.5 20247.8 20724.5 20797.7 20829.4 21208.6 931.4 940.7 980.6 983.6 984.3 9829.0 10765.4 10768.5 10820.7 10829.6 688351.0 696127.0 700698.0 713045.4 716405.0 160143.2 163374.0 164057.0 164651.5 166325.2 1011.0 1170.1 1197.2 1223.4 1226.9 19876.6 20211.8 20339.5 21756.4 21910.6 56851.0 57453.9 58204.4 58303.0 58434.7 82999.0 83069.6 83735.0 84501.3 87657.0 8483.8 8513.1 8519.7 8707.4 9050.7 13158.4 13271.8 13370.5 13800.1 14241.5 10575.8 10828.1 11056.0 11167.2 11250.3 9665.6 10509.6 10576.1 11103.2 11265.3 11674.0 11719.6 12003.3 12054.9 12108.5 8452.3 8553.6 8799.3 9067.6 9124.8 10967.5 11192.3 11537.8 11592.9 11596.8 18:16:58 INFO - PID 1901 | 18:16:58 INFO - PID 1901 | [#3] mootools.html Cycles:180 Average:6724.35 Median:4066.48 stddev:4171.74 (102.6%) stddev-sans-first:4162.62 18:16:58 INFO - PID 1901 | Values: 1178.4 1248.6 1440.8 1449.8 1454.4 9183.3 9630.3 10731.3 10994.5 11013.9 10655.5 10752.0 10758.1 10777.4 10808.2 2279.3 2323.5 2324.5 2326.1 2328.9 2083.0 2166.3 2166.7 2168.1 2173.9 2053.1 2097.7 2228.3 2303.1 2308.8 2321.6 2427.2 2427.2 2427.7 2429.7 2044.2 2334.8 2382.5 2382.6 2383.5 9418.9 10459.5 10693.9 10728.8 10745.2 6076.6 6332.5 6333.6 6340.9 6341.4 4006.8 4030.7 4088.0 4102.9 4116.2 1160.7 1168.5 1188.3 1217.1 1226.2 12446.5 15371.8 15376.3 15377.1 15429.4 5302.4 5497.5 5561.1 5570.5 5725.1 12550.7 12630.5 12631.1 12645.1 12653.5 6315.4 6377.9 6396.0 6397.0 6399.4 5637.7 5966.9 6019.9 6039.2 6047.3 4242.3 4399.6 4453.8 4500.5 4517.2 1185.2 1354.9 1416.3 1424.0 1439.8 624.5 746.2 782.6 788.3 824.1 5580.3 6113.8 6290.8 6476.6 6477.7 514.6 556.7 582.8 589.8 599.5 3848.9 3970.8 4045.0 4110.3 4164.6 13253.5 13868.3 13886.4 13887.2 13895.2 12780.5 13759.4 13769.9 13778.7 13801.0 9725.4 9975.3 9995.8 10005.5 10027.7 6320.9 6347.6 6425.5 6645.7 6665.6 11935.1 12154.0 12165.9 12274.6 12307.4 12186.6 12655.9 12675.7 12682.9 12700.4 8911.4 9240.3 9248.5 9264.1 9291.7 8482.4 9626.2 9923.7 10055.7 10177.7 8410.0 8444.6 8504.5 8556.6 8581.4 8137.0 8423.5 8444.7 8457.3 8616.8 7705.2 7720.2 7722.2 7817.6 7838.8 5249.0 5678.5 5812.5 5834.3 5865.7 5789.3 5943.5 6019.8 6067.7 6235.7 18:16:58 INFO - PID 1901 | 18:16:58 INFO - PID 1901 | [#4] prototype.html Cycles:180 Average:5225.73 Median:5899.91 stddev:1639.60 (27.8%) stddev-sans-first:1602.93 18:16:58 INFO - PID 1901 | Values: 355.3 387.9 391.1 396.9 399.4 6091.0 6125.7 6129.5 6151.9 6171.4 5458.9 5763.8 5804.2 5890.3 5922.7 3550.4 4697.1 4703.4 4732.9 4770.7 4698.2 4711.6 4717.9 4719.7 4732.2 4308.4 4506.4 4592.4 4680.0 4704.6 4262.5 4273.8 4538.2 4547.0 4555.5 3743.0 3907.8 3911.3 3923.4 3928.5 5601.2 5792.9 5909.5 5924.9 6015.0 4413.1 4527.3 5226.9 5258.8 5295.5 5335.5 5643.8 5665.8 5723.1 6531.0 4168.6 4272.2 4281.9 4320.4 4329.5 6542.5 6639.7 6748.4 6798.8 6899.9 4307.4 4405.8 4409.2 4419.0 4437.2 3802.7 3805.2 3920.4 4461.5 4493.8 4365.8 4863.0 5042.5 5151.8 5266.3 5203.6 6442.2 6506.4 6549.2 6561.4 1486.7 1508.7 1517.5 1522.3 1531.3 886.9 982.4 997.3 1000.5 1012.8 5750.5 6225.4 6228.7 6234.7 6237.4 6423.6 6640.1 6765.5 6777.7 6835.3 5947.3 6120.5 6145.3 6217.2 6224.8 2972.3 3325.6 3475.6 3499.4 3529.4 6022.7 6847.1 6911.0 6916.4 6921.6 5408.9 6361.6 6841.6 6909.4 6943.4 6682.8 6784.8 6856.2 6924.9 6938.3 6162.6 6667.0 6728.9 6734.1 6784.5 5080.8 5414.8 5805.6 6328.6 6637.0 6030.6 6464.9 6599.8 6845.7 6852.3 4245.7 4397.5 4406.6 4407.8 4418.7 5679.7 6584.4 6650.0 6682.9 6740.2 5776.9 6447.6 6491.3 6501.0 6524.7 6346.2 6450.9 6466.5 6498.6 6507.1 6288.2 6324.5 6336.7 6355.6 6367.0 6484.4 6493.9 6496.7 6504.5 6521.5 5231.6 6293.8 6365.3 6381.5 6393.5 18:16:58 INFO - PID 1901 | 18:16:58 INFO - PID 1901 | [#5] yui.html Cycles:180 Average:18670.89 Median:23945.34 stddev:25939.41 (108.3%) stddev-sans-first:25977.15 18:16:58 INFO - PID 1901 | Values: 717.3 720.7 733.8 736.4 767.4 15025.5 16362.3 16509.5 17218.6 18182.3 13797.5 17023.7 17071.6 17559.1 17729.0 1008.9 1036.9 1038.9 1041.8 1043.4 986.7 1005.9 1017.6 1019.2 1023.3 1056.3 1058.5 1059.3 1059.5 1073.8 1058.6 1058.8 1060.2 1061.8 1063.7 792.2 795.3 795.8 796.1 796.6 16365.5 16692.0 16770.2 16848.9 17125.6 12643.4 14543.8 14829.1 14896.0 14953.0 11749.2 11853.3 11886.6 12081.2 12112.3 2974.5 3066.9 3084.3 3181.6 3186.0 88010.1 110086.0 112096.5 112955.0 116344.4 11241.9 11392.7 11439.3 11600.7 11812.4 23852.3 24038.4 24069.4 24249.0 24531.0 8049.8 8157.3 8165.9 8167.9 8214.9 3651.4 4169.7 4172.5 4228.0 4228.6 4105.5 4179.1 4210.3 4223.2 4228.8 824.7 829.5 841.5 845.7 854.5 529.2 529.6 584.9 588.6 612.1 8917.3 9449.0 9534.8 10189.3 10329.1 544.9 575.6 578.5 594.4 599.2 4576.6 4751.2 4798.2 4810.8 4906.9 85728.6 90578.6 91054.0 91836.5 92526.0 53839.5 55367.9 66236.7 77068.0 78771.0 70134.6 70849.6 72964.0 73925.6 75157.2 10456.5 10774.2 10927.0 10997.7 11147.5 35555.6 36256.2 36766.3 36971.0 37538.4 39393.8 46556.0 50196.2 50274.7 50344.7 13246.3 13310.2 13408.4 13854.4 14228.9 14366.5 14393.1 14393.6 14397.0 14423.6 11657.0 13113.7 13509.7 13526.7 14068.9 13103.3 13241.7 13376.3 13490.0 13701.9 12175.4 12259.3 12363.5 12363.9 12710.4 9257.0 9324.7 9403.3 9517.2 10094.7 7575.2 10154.3 10628.3 10851.5 11200.9 18:16:58 INFO - PID 1901 | -------- Summary: end -------- 18:16:58 INFO - PID 1901 | 18:16:59 INFO - TEST-INFO | 1901: exit 0 18:16:59 INFO - TEST-OK | dromaeo_css | took 1121707ms 18:16:59 INFO - TEST-START | kraken 18:16:59 INFO - operating with platform_type : mac_ 18:16:59 INFO - Initialising browser for kraken test... 18:16:59 INFO - TEST-INFO | started process 1906 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmphyZZC_/profile http://localhost:49430/getInfo.html) 18:17:01 INFO - PID 1906 | __metrics Screen width/height:1600/1200 18:17:01 INFO - PID 1906 | colorDepth:24 18:17:01 INFO - PID 1906 | Browser inner width/height: 1024/689 18:17:01 INFO - PID 1906 | __metrics 18:17:01 ERROR - PID 1906 | 1490750221881 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 18:17:01 INFO - PID 1906 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:17:01 INFO - PID 1906 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:17:02 INFO - TEST-INFO | 1906: exit 0 18:17:02 INFO - Browser initialized. 18:17:02 INFO - Running cycle 1/1 for kraken test... 18:17:02 INFO - TEST-INFO | started process 1907 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/T/tmphyZZC_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:17:14 INFO - PID 1907 | RSS: Main: 234967040 18:17:14 INFO - PID 1907 | 18:17:17 INFO - PID 1907 | Cycle 1(1): loaded http://localhost:49430/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49430/tests/kraken/driver.html?testName=audio-beat-detection) 18:17:18 INFO - PID 1907 | RSS: Main: 287141888 18:17:18 INFO - PID 1907 | 18:17:21 INFO - PID 1907 | Cycle 1(1): loaded http://localhost:49430/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49430/tests/kraken/driver.html?testName=audio-dft) 18:17:22 INFO - PID 1907 | RSS: Main: 289644544 18:17:22 INFO - PID 1907 | 18:17:25 INFO - PID 1907 | Cycle 1(1): loaded http://localhost:49430/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49430/tests/kraken/driver.html?testName=audio-fft) 18:17:26 INFO - PID 1907 | RSS: Main: 379924480 18:17:26 INFO - PID 1907 | 18:17:28 INFO - PID 1907 | Cycle 1(1): loaded http://localhost:49430/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49430/tests/kraken/driver.html?testName=audio-oscillator) 18:17:29 INFO - PID 1907 | RSS: Main: 283787264 18:17:29 INFO - PID 1907 | 18:17:31 INFO - PID 1907 | Cycle 1(1): loaded http://localhost:49430/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49430/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:17:32 INFO - PID 1907 | RSS: Main: 285581312 18:17:32 INFO - PID 1907 | 18:17:35 INFO - PID 1907 | Cycle 1(1): loaded http://localhost:49430/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49430/tests/kraken/driver.html?testName=imaging-darkroom) 18:17:36 INFO - PID 1907 | RSS: Main: 326242304 18:17:36 INFO - PID 1907 | 18:17:39 INFO - PID 1907 | Cycle 1(1): loaded http://localhost:49430/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49430/tests/kraken/driver.html?testName=imaging-desaturate) 18:17:40 INFO - PID 1907 | RSS: Main: 338198528 18:17:40 INFO - PID 1907 | 18:17:43 INFO - PID 1907 | Cycle 1(1): loaded http://localhost:49430/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49430/tests/kraken/driver.html?testName=json-parse-financial) 18:17:44 INFO - PID 1907 | RSS: Main: 347570176 18:17:44 INFO - PID 1907 | 18:17:45 INFO - PID 1907 | Cycle 1(1): loaded http://localhost:49430/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49430/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:17:46 INFO - PID 1907 | RSS: Main: 304336896 18:17:46 INFO - PID 1907 | 18:17:48 INFO - PID 1907 | Cycle 1(1): loaded http://localhost:49430/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49430/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:17:49 INFO - PID 1907 | RSS: Main: 308219904 18:17:49 INFO - PID 1907 | 18:17:51 INFO - PID 1907 | Cycle 1(1): loaded http://localhost:49430/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49430/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:17:51 INFO - PID 1907 | RSS: Main: 309985280 18:17:51 INFO - PID 1907 | 18:17:55 INFO - PID 1907 | Cycle 1(1): loaded http://localhost:49430/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49430/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:17:55 INFO - PID 1907 | RSS: Main: 327659520 18:17:55 INFO - PID 1907 | 18:17:59 INFO - PID 1907 | Cycle 1(1): loaded http://localhost:49430/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49430/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:18:00 INFO - PID 1907 | RSS: Main: 311046144 18:18:00 INFO - PID 1907 | 18:18:01 INFO - PID 1907 | Cycle 1(1): loaded http://localhost:49430/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49430/tests/kraken/driver.html?testName=ai-astar) 18:18:02 INFO - PID 1907 | RSS: Main: 376659968 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | __start_tp_report 18:18:02 INFO - PID 1907 | _x_x_mozilla_page_load 18:18:02 INFO - PID 1907 | _x_x_mozilla_page_load_details 18:18:02 INFO - PID 1907 | |i|pagename|runs| 18:18:02 INFO - PID 1907 | |0;ai-astar;112;122;122;125;125;123;125;124;124;123 18:18:02 INFO - PID 1907 | |1;audio-beat-detection;151;134;154;196;120;120;116;120;144;121 18:18:02 INFO - PID 1907 | |2;audio-dft;220;217;217;222;213;215;216;223;215;219 18:18:02 INFO - PID 1907 | |3;audio-fft;81;67;64;98;68;64;84;92;110;62 18:18:02 INFO - PID 1907 | |4;audio-oscillator;93;77;96;76;75;76;120;110;107;93 18:18:02 INFO - PID 1907 | |5;imaging-gaussian-blur;96;96;108;144;96;96;111;145;112;136 18:18:02 INFO - PID 1907 | |6;imaging-darkroom;101;102;100;102;108;123;159;157;102;100 18:18:02 INFO - PID 1907 | |7;imaging-desaturate;102;102;104;104;102;103;102;101;102;102 18:18:02 INFO - PID 1907 | |8;json-parse-financial;67;65;64;66;64;67;66;65;66;65 18:18:02 INFO - PID 1907 | |9;json-stringify-tinderbox;58;60;91;103;97;75;65;65;66;66 18:18:02 INFO - PID 1907 | |10;stanford-crypto-aes;92;90;90;92;90;92;88;89;89;89 18:18:02 INFO - PID 1907 | |11;stanford-crypto-ccm;157;162;152;151;173;140;143;131;222;206 18:18:02 INFO - PID 1907 | |12;stanford-crypto-pbkdf2;201;212;198;201;202;219;234;249;226;200 18:18:02 INFO - PID 1907 | |13;stanford-crypto-sha256-iterative;73;72;83;87;74;64;71;74;72;72 18:18:02 INFO - PID 1907 | __end_tp_report 18:18:02 INFO - PID 1907 | __start_cc_report 18:18:02 INFO - PID 1907 | _x_x_mozilla_cycle_collect,2215 18:18:02 INFO - PID 1907 | __end_cc_report 18:18:02 INFO - PID 1907 | __startTimestamp1490750282175__endTimestamp 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | ------- Summary: start ------- 18:18:02 INFO - PID 1907 | Number of tests: 14 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | [#0] ai-astar Cycles:10 Average:122.50 Median:124.00 stddev:3.87 (3.1%) stddev-sans-first:1.22 18:18:02 INFO - PID 1907 | Values: 112.0 122.0 122.0 125.0 125.0 123.0 125.0 124.0 124.0 123.0 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | [#1] audio-beat-detection Cycles:10 Average:137.60 Median:139.00 stddev:24.91 (17.9%) stddev-sans-first:25.94 18:18:02 INFO - PID 1907 | Values: 151.0 134.0 154.0 196.0 120.0 120.0 116.0 120.0 144.0 121.0 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | [#2] audio-dft Cycles:10 Average:217.70 Median:218.00 stddev:3.23 (1.5%) stddev-sans-first:3.32 18:18:02 INFO - PID 1907 | Values: 220.0 217.0 217.0 222.0 213.0 215.0 216.0 223.0 215.0 219.0 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | [#3] audio-fft Cycles:10 Average:79.00 Median:74.50 stddev:16.75 (22.5%) stddev-sans-first:17.75 18:18:02 INFO - PID 1907 | Values: 81.0 67.0 64.0 98.0 68.0 64.0 84.0 92.0 110.0 62.0 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | [#4] audio-oscillator Cycles:10 Average:92.30 Median:76.50 stddev:16.25 (21.2%) stddev-sans-first:17.23 18:18:02 INFO - PID 1907 | Values: 93.0 77.0 96.0 76.0 75.0 76.0 120.0 110.0 107.0 93.0 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:120.50 stddev:20.25 (16.8%) stddev-sans-first:20.40 18:18:02 INFO - PID 1907 | Values: 96.0 96.0 108.0 144.0 96.0 96.0 111.0 145.0 112.0 136.0 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | [#6] imaging-darkroom Cycles:10 Average:115.40 Median:105.00 stddev:23.49 (22.4%) stddev-sans-first:24.33 18:18:02 INFO - PID 1907 | Values: 101.0 102.0 100.0 102.0 108.0 123.0 159.0 157.0 102.0 100.0 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | [#7] imaging-desaturate Cycles:10 Average:102.40 Median:102.00 stddev:0.97 (0.9%) stddev-sans-first:1.01 18:18:02 INFO - PID 1907 | Values: 102.0 102.0 104.0 104.0 102.0 103.0 102.0 101.0 102.0 102.0 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:66.00 stddev:1.08 (1.6%) stddev-sans-first:1.00 18:18:02 INFO - PID 1907 | Values: 67.0 65.0 64.0 66.0 64.0 67.0 66.0 65.0 66.0 65.0 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | [#9] json-stringify-tinderbox Cycles:10 Average:74.60 Median:66.00 stddev:16.32 (24.7%) stddev-sans-first:16.17 18:18:02 INFO - PID 1907 | Values: 58.0 60.0 91.0 103.0 97.0 75.0 65.0 65.0 66.0 66.0 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:90.00 stddev:1.45 (1.6%) stddev-sans-first:1.36 18:18:02 INFO - PID 1907 | Values: 92.0 90.0 90.0 92.0 90.0 92.0 88.0 89.0 89.0 89.0 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | [#11] stanford-crypto-ccm Cycles:10 Average:163.70 Median:159.50 stddev:29.21 (18.3%) stddev-sans-first:30.88 18:18:02 INFO - PID 1907 | Values: 157.0 162.0 152.0 151.0 173.0 140.0 143.0 131.0 222.0 206.0 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:214.20 Median:215.50 stddev:17.42 (8.1%) stddev-sans-first:17.81 18:18:02 INFO - PID 1907 | Values: 201.0 212.0 198.0 201.0 202.0 219.0 234.0 249.0 226.0 200.0 18:18:02 INFO - PID 1907 | 18:18:02 INFO - PID 1907 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.20 Median:73.50 stddev:6.43 (8.7%) stddev-sans-first:6.80 18:18:02 INFO - PID 1907 | Values: 73.0 72.0 83.0 87.0 74.0 64.0 71.0 74.0 72.0 72.0 18:18:02 INFO - PID 1907 | -------- Summary: end -------- 18:18:02 INFO - PID 1907 | 18:18:03 INFO - TEST-INFO | 1907: exit 0 18:18:03 INFO - TEST-OK | kraken | took 63765ms 18:18:03 INFO - SUITE-END | took 1185s 18:18:03 INFO - Completed test suite (00:19:45) 18:18:03 INFO - javascript benchmark 18:18:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7575.059589423628, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3450.4479283314645, 3462.7402944779133, 3491.8079505627197, 3502.1594817243854, 3526.1889765353967, 6041.365656606059, 6144.109104179903, 6252.499800015999, 6253.1245625612455, 6257.155284036659, 6118.357572454884, 6139.048447490635, 6151.507879369651, 6154.876902461948, 6159.414855588723, 4787.3058406530945, 5579.218909352674, 5597.6641401515835, 5604.943950560503, 5813.418658134195, 5714.428557144294, 5714.9714251428895, 5715.828525144231, 5716.685582292972, 5727.684977326246, 5480.04099282626, 5616.045272303698, 5715.199872017921, 5746.166805813175, 5766.106253530709, 5479.753411096471, 5514.476124768141, 5632.887342253132, 5669.0646043402785, 5701.173329867146, 5402.51377376038, 5528.917066244009, 5535.640183388107, 5557.3331200255825, 5558.221848941152, 6110.862540392697, 6112.541559383064, 6113.059265338615, 6121.209103132296, 6138.84652883677, 6397.5201859860235, 6562.639054851981, 6573.868522629568, 6623.53635245533, 6636.203655561315, 5871.295444546639, 6726.495512836508, 6742.8314292142595, 6743.662816859187, 6786.321367863224, 6187.5149964008315, 6295.150154729099, 6317.178766760384, 6323.30443651198, 6328.778492752824, 6571.3757193066585, 6650.501212409089, 6651.301613330627, 6660.034295027194, 6683.331666833278, 6330.68346582669, 6336.6831658416895, 6367.872642547123, 6437.7103030362805, 6523.8695226095215, 6379.202599675041, 6443.548951573345, 6445.1943507061615, 6601.306862779339, 6662.733490660319, 6605.471562275006, 6605.669716514155, 6627.9337206627315, 6634.535582509178, 6636.668166591651, 6646.468282537386, 6656.7670131545165, 6657.300983396771, 6671.699773510204, 6672.7330906764155, 5227.686338819683, 5274.947250527445, 5289.3917199521775, 5329.440408757102, 5330.546903513167, 4697.741624210633, 4698.718076915396, 4739.715617062987, 4746.0, 4772.880677983078, 6305.558610897193, 6319.873602527924, 6337.683115844282, 6341.55609107358, 6344.651044192614, 6646.102776125258, 6650.900236496456, 6697.497687673346, 6702.296258892844, 6721.1598550181225, 6116.33577150815, 6196.969015154896, 6203.658798766111, 6206.751729930843, 6219.533534984895, 5809.215755872988, 5817.970910145422, 5822.941770582324, 5823.213866128019, 5825.7087145642545, 6679.398854103154, 6712.93287067123, 6717.731290748316, 6729.4279986200745, 6752.8649427010205, 6482.416582507597, 6556.409923106752, 6559.311272316529, 6612.0743095965645, 6691.665416729047, 6901.0, 6914.412274956484, 6914.757983470555, 6915.446764258948, 6923.9653801730665, 6593.208814942238, 6594.208694956444, 6597.670116494252, 6600.53796234259, 6608.471322294301, 6392.87214255703, 6445.70994305248, 6449.677516124269, 6659.733610655713, 6714.52998290115, 5318.308619879391, 6383.680815959091, 6411.647359395278, 6427.228732552124, 6453.548251622341, 6484.0, 6492.0261960706275, 6503.967480162569, 6516.6415847128865, 6517.543771936109, 6210.347913469021, 6289.213848268966, 6299.338569450325, 6300.338464461349, 6375.362463753588, 6406.295307516262, 6436.16329877113, 6442.935570644421, 6482.513811464065, 6484.059811327237, 5443.537299329443, 6319.96840015797, 6325.588836725598, 6336.429721325103, 6342.841428964128], "value": 6081.362559452183, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.2010708862782, 799.2846402469788, 1121.5738019552568, 1138.2715062360091, 1138.6925530106876, 36059.63940360596, 36927.0, 37034.25931481369, 37062.62937370629, 37259.4411083834, 32533.67466325336, 38832.02919927003, 39204.21591568167, 39239.0, 39357.0, 6107.847303817418, 8470.025947016095, 8616.870746938801, 8635.093315201908, 8653.226088651843, 6210.50315974721, 6309.0536419536975, 6314.526410519206, 6319.936800632004, 8297.709580164696, 8541.743747687578, 8589.80959855236, 8603.268722158624, 8614.224719775219, 8619.267362274215, 8517.914820851774, 8589.570521473965, 8590.398672092955, 8598.613062412207, 8611.61247743853, 8384.077751447336, 8413.915860841375, 8452.957735211285, 8455.661773529051, 8475.872861907077, 38278.85163445073, 38564.614353856385, 38583.614163858285, 38606.80696596527, 38629.03427414309, 2560.3215147985798, 3043.802152860057, 3049.451098802215, 3063.203567072555, 3085.058618109254, 27756.30609234765, 27793.305167370774, 27893.58159627607, 28940.131796046153, 29089.12732618025, 2770.598263251817, 2771.9445611087667, 2774.750272475487, 2790.2326860113426, 2821.478026565092, 324925.0, 326227.0, 326914.0, 327962.9999999976, 329458.0, 37462.87611371663, 38241.0, 50352.748236259315, 50609.746951265006, 50620.49379506231, 39923.201535969696, 39923.60076399257, 39941.40234390592, 40009.0, 42160.78919605444, 13388.598342049754, 13776.10455320401, 13817.170969741848, 13818.792718109236, 13845.446182152802, 9844.015598440099, 10021.148202402732, 10042.799144017079, 11289.37908415045, 11291.717707057389, 10784.94607526973, 10871.347719136877, 10880.891191087987, 12092.939535302443, 12257.570985015482, 5109.284700141983, 5202.531772140525, 5226.92159617606, 5230.764615592308, 5233.921491177635, 767.4742801181209, 890.7327801659476, 943.8018016216535, 952.0955092661999, 969.8060387922444, 20356.69464958027, 23051.884740576188, 23715.88142059279, 23784.643230351558, 23810.52378952411, 1020.1125021231576, 1025.3898930136556, 1058.074185088048, 1058.2274939294205, 1059.359087751899, 11393.943030284796, 11886.583969561198, 12073.818892716616, 12134.575289864812, 12136.33250171249, 422230.0, 423042.0, 423598.0, 423975.0, 424748.0, 54337.45662543324, 54432.45567544274, 54453.99999999921, 54569.45430545723, 54716.0, 1433.7132573485344, 1466.7066586682492, 1469.7942288079678, 1471.9926400367929, 1485.5394827603477, 22379.77620223777, 23042.308730738103, 23176.652350215096, 23242.070317187125, 23244.3026709199, 38185.236295274495, 38227.80886095552, 38321.4251786229, 38370.61629383782, 38373.424398633484, 349032.0, 349074.0, 350043.0, 350191.24904375314, 350300.0, 31817.681823181472, 32265.87069452558, 32448.35103297968, 32481.512777307886, 32617.0, 29236.26909327284, 30688.53967190449, 33534.66465335315, 40537.391939121524, 42364.72905812831, 26515.60226596564, 26935.86532067327, 29582.964596239028, 29691.40617187601, 29945.251368715955, 28970.0, 29686.0, 29801.552976704934, 29858.0, 29871.253218669706, 34526.48210276898, 34993.650063500056, 35154.64845351616, 35160.29679406449, 35457.0, 29956.850215748782, 30256.680556522468, 30262.84868575643, 30926.690733092382, 31256.499952727543, 20693.58612827765, 23490.412739681644, 24283.150089746774, 24348.0, 24872.0], "value": 16842.245459302136, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.55947475413427, 419.93281075028, 429.8602954039936, 431.9892002699933, 445.3809205204766, 17898.552536186602, 18517.16672749726, 20477.48806279842, 21204.25785097522, 21272.255471058517, 18344.724829127576, 22189.889050554724, 22520.54958900821, 23176.0, 23555.175568855095, 6054.8486287842825, 6386.64873431961, 6398.392152745481, 6399.776007839727, 6413.326600706905, 5996.22049133612, 6196.194494715681, 6209.782657606985, 6284.057391391305, 6292.433680968712, 6227.0, 6238.126662267264, 6242.906356404657, 6244.56288059836, 6247.375262473762, 5973.492253158465, 6256.2805277393, 6310.9999999999545, 6343.270523889742, 6345.587536810139, 5338.145896656555, 5482.91775623366, 5595.300587426572, 5602.943970560324, 5604.075327570946, 26794.464110717872, 27010.5948410774, 27042.5943610846, 27084.86457567719, 27264.45471090587, 2025.3316405586122, 2028.920802639094, 2049.1496029147884, 2080.739907511561, 2118.925837595677, 16401.0, 17194.398196063205, 17238.396656116976, 17292.65414691699, 17602.647947040987, 1475.350845627931, 1652.3886162120093, 1687.8480936715755, 1690.475952454743, 1692.9285069408093, 125906.37046814707, 138837.0, 144708.27645861704, 145758.27120864435, 149345.0, 11844.28934263947, 12135.878641213474, 12186.268823870596, 12216.633500994983, 12333.753324933452, 16039.0, 16058.0, 16100.516984490485, 16121.0, 16142.596435088983, 8501.107383724744, 8825.38222324444, 8837.160469755363, 8959.148880856308, 9048.547572621343, 7032.789016329519, 7590.962045189849, 7793.688252469951, 7947.721829736047, 8438.45150065256, 6304.306526282106, 7411.332980031715, 7542.396608271325, 7588.268223439557, 7616.276453736886, 2125.6077269388575, 2133.5172055421335, 2136.8504204705832, 2138.6150492911424, 2148.3232781673723, 868.9522076285739, 901.3915606965403, 921.2077613252598, 930.2976252928995, 951.509972364233, 20247.797522024885, 20724.481887952923, 20797.688034679188, 20829.375118746462, 21208.575828483343, 931.3852857114407, 940.6660635474445, 980.6273616026007, 983.5573991703762, 984.2962281968283, 9828.953024562408, 10765.407902565277, 10768.515416806262, 10820.67537973862, 10829.620963266249, 688351.0, 696127.0, 700698.0, 713045.4347728331, 716405.0, 160143.19928400515, 163374.0, 164057.0, 164651.5302270467, 166325.16837415737, 1010.9839611190706, 1170.1048697746062, 1197.1679682620495, 1223.3577371879835, 1226.8589112252027, 19876.608637395242, 20211.79788202158, 20339.491512712302, 21756.35079188753, 21910.561788764455, 56851.0, 57453.85092298047, 58204.41795582159, 58303.0, 58434.707826460595, 82999.0, 83069.58465207636, 83735.0, 84501.30997380141, 87657.0, 8483.787905302417, 8513.106123856905, 8519.659213631385, 8707.434016788888, 9050.728478145666, 13158.40787164599, 13271.800922985969, 13370.465181393025, 13800.102993305403, 14241.50154744579, 10575.788484230427, 10828.079613232963, 11056.004959553678, 11167.218294719292, 11250.268732532686, 9665.565049572646, 10509.632162874568, 10576.101031412416, 11103.222774405715, 11265.324080555192, 11674.0, 11719.648410547697, 12003.339816310187, 12054.939725301318, 12108.455119519818, 8452.281556067557, 8553.572321383781, 8799.252063574668, 9067.591958362022, 9124.771880703036, 10967.50646220938, 11192.328460292409, 11537.769244615229, 11592.942035289769, 11596.826047609462], "value": 10325.381960463645, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1178.3754610056662, 1248.6254123762872, 1440.834304055034, 1449.833269174045, 1454.4036944852612, 9183.311251656127, 9630.277729170313, 10731.302465339748, 10994.450277486134, 11013.889861101386, 10655.520501577428, 10752.03231709146, 10758.139348852093, 10777.353358798458, 10808.243422960395, 2279.2592407467555, 2323.465602911331, 2324.5234726881035, 2326.081197926818, 2328.860268383902, 2082.98958505208, 2166.2959538150085, 2166.7074944882397, 2168.1327469012444, 2173.9239126630573, 2053.1068984991502, 2097.737782777153, 2228.3092241405134, 2303.1248125712204, 2308.8268379871492, 2321.593721098801, 2427.186892391051, 2427.2111563741764, 2427.684401027855, 2429.708434987795, 2044.1925439451334, 2334.8365614407, 2382.4996750682376, 2382.630692242705, 2383.48755017672, 9418.905810941871, 10459.529321180566, 10693.946530267376, 10728.785424291471, 10745.19411044167, 6076.574639775217, 6332.525060620472, 6333.556651034383, 6340.936590634081, 6341.365863413668, 4006.8397264109694, 4030.6573941215033, 4087.9795601021806, 4102.928021349229, 4116.156187981486, 1160.7446361800392, 1168.4800263882576, 1188.2751521571834, 1217.1297522271618, 1226.1662069792537, 12446.502139914393, 15371.769423458658, 15376.308066136939, 15377.077375357403, 15429.382824687, 5302.443243459458, 5497.505224529811, 5561.110222364402, 5570.470805273492, 5725.08398656213, 12550.74898502025, 12630.494780208872, 12631.052671049709, 12645.051621128452, 12653.493860245488, 6315.36846315374, 6377.936220637827, 6395.968020159962, 6397.040443933354, 6399.424051835357, 5637.68992705397, 5966.850828729316, 6019.879602407927, 6039.1545183674325, 6047.2743270806905, 4242.278812601865, 4399.64802815774, 4453.75504347264, 4500.459944806578, 4517.164324599959, 1185.211834130311, 1354.8577399372923, 1416.3201663201514, 1423.950161744334, 1439.7912302716047, 624.5347216323868, 746.1531162131021, 782.6008735544913, 788.2511613966755, 824.0729179672868, 5580.302462192226, 6113.8471538211015, 6290.779822706183, 6476.643784591798, 6477.708503117371, 514.5857584644323, 556.6993823335354, 582.7825125397817, 589.8024161905723, 599.5203836930439, 3848.8845334640005, 3970.781607011642, 4044.9797751011056, 4110.280700877334, 4164.604362585549, 13253.469861205444, 13868.306584670727, 13886.4445422184, 13887.236202008784, 13895.166290022631, 12780.488780448864, 13759.449622015007, 13769.862301377058, 13778.65553361174, 13800.964927630468, 9725.374148979736, 9975.25185611082, 9995.750106247402, 10005.549750261402, 10027.749306267257, 6320.873582528416, 6347.5556711029785, 6425.485961123192, 6645.667716614246, 6665.566738162004, 11935.104867134825, 12154.0, 12165.856242597432, 12274.570390036306, 12307.44616492278, 12186.57346992851, 12655.93672031634, 12675.746485070431, 12682.936585317015, 12700.36498175106, 8911.37620366568, 9240.260779137787, 9248.491332976482, 9264.11990860867, 9291.72124836256, 8482.406231563979, 9626.229901607714, 9923.65267215644, 10055.748606284902, 10177.745556360855, 8409.95795021021, 8444.619992100494, 8504.489730616182, 8556.614952327285, 8581.35639827003, 8136.959315203386, 8423.4945903246, 8444.662213511636, 8457.323414126731, 8616.78458038554, 7705.152433232228, 7720.150783413932, 7722.15056343813, 7817.609119543887, 7838.843223135391, 5249.0, 5678.5457163426, 5812.5349972001295, 5834.27071616048, 5865.677387743715, 5789.33422656383, 5943.465088142088, 6019.849503762266, 6067.666278354556, 6235.657038862906], "value": 5062.498176801155, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.3284292686821, 387.9262940041392, 391.13949311514665, 396.9305371559976, 399.4188455796816, 6090.969545152268, 6125.693715314228, 6129.54028447867, 6151.907721384183, 6171.4445699887, 5458.863528411782, 5763.760791429841, 5804.216430781854, 5890.3226128995075, 5922.703864806764, 3550.431930891058, 4697.131030759303, 4703.412073490814, 4732.929006064912, 4770.713757174563, 4698.2482802751565, 4711.623070154379, 4717.9292310615365, 4719.669623126382, 4732.195526760442, 4308.3537469379535, 4506.414166158394, 4592.425946756655, 4679.976600116995, 4704.6236301096005, 4262.488501379854, 4273.829046838123, 4538.205813982573, 4546.954530454686, 4555.521670224628, 3743.008102852719, 3907.8241479133508, 3911.3155197840265, 3923.4114882767526, 3928.5285765708013, 5601.215829783833, 5792.855178620526, 5909.497692696126, 5924.851878703023, 6015.0150450351275, 4413.095315460323, 4527.298268768313, 5226.92159617606, 5258.81594144207, 5295.523402893758, 5335.546478549328, 5643.802466913638, 5665.781856900745, 5723.112917497794, 6531.0, 4168.6039826216465, 4272.188284226018, 4281.914361712748, 4320.351947207944, 4329.502107257674, 6542.509311801634, 6639.734410623618, 6748.426383757435, 6798.796036118875, 6899.896501552481, 4307.418498502725, 4405.757683327448, 4409.162259170747, 4419.049904270597, 4437.223485890021, 3802.7338086334244, 3805.1818858945458, 3920.3923391874305, 4461.475434406782, 4493.752843593568, 4365.781710914442, 4862.975685121552, 5042.4705405931845, 5151.815082530964, 5266.315379000705, 5203.583713303003, 6442.194725659293, 6506.41442270198, 6549.2468366137555, 6561.40947314744, 1486.680363721805, 1508.6530098077285, 1517.529565834595, 1522.3377830643706, 1531.2802982598162, 886.8802711633847, 982.4301904895144, 997.2769741937037, 1000.5297510170209, 1012.832882574367, 5750.5112065474905, 6225.3774622537385, 6228.723111762078, 6234.671808114767, 6237.438630523275, 6423.582467139714, 6640.115532413495, 6765.492588055817, 6777.72889084441, 6835.282295359015, 5947.345791962881, 6120.479759220427, 6145.26256849172, 6217.222847144107, 6224.844378890564, 2972.316367235548, 3325.617553981275, 3475.5829300483597, 3499.4051011328133, 3529.435290353531, 6022.651166045246, 6847.144106986627, 6911.0, 6916.377526022682, 6921.581075879432, 5408.91886621701, 6361.61830290184, 6841.589504629738, 6909.412699920463, 6943.444524438033, 6682.79951601443, 6784.7964561063245, 6856.24581296057, 6924.896126558207, 6938.306169383021, 6162.630242185304, 6667.0, 6728.865422691617, 6734.1043351359385, 6784.491163162881, 5080.822171223989, 5414.83755487336, 5805.564582656233, 6328.6202827830475, 6637.004449332446, 6030.638161710312, 6464.903026454701, 6599.835004124935, 6845.657717114024, 6852.349026842442, 4245.68157388191, 4397.494288156775, 4406.581374769392, 4407.7796110195, 4418.7127836690515, 5679.6876171810945, 6584.407403333723, 6650.002499624901, 6682.8973219420195, 6740.224874139537, 5776.942230577641, 6447.613763040781, 6491.318411566906, 6500.967495162494, 6524.67376631176, 6346.174997250512, 6450.903236451363, 6466.482681385572, 6498.64257465825, 6507.056476810931, 6288.208734566566, 6324.525660575383, 6336.746530138743, 6355.586886852339, 6367.0, 6484.416402523795, 6493.8701225976165, 6496.707648155939, 6504.544681872224, 6521.543491955707, 5231.581473482036, 6293.779717709858, 6365.299817020032, 6381.489480841428, 6393.520485963479], "value": 4709.471266419643, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.3292971072048, 720.7405334079733, 733.7872017115036, 736.4071922102706, 767.3669222891116, 15025.549233522997, 16362.263698133584, 16509.504714858544, 17218.569535761613, 18182.27270909162, 13797.51708690196, 17023.659526809457, 17071.57321066974, 17559.122043897787, 17729.0, 1008.9243306751986, 1036.8548403223556, 1038.9168866490663, 1041.828098363769, 1043.4209013997267, 986.7089208683419, 1005.8742657167854, 1017.5726194998118, 1019.1846522781782, 1023.2837014090129, 1056.3028401255174, 1058.534244932231, 1059.3379138038727, 1059.5496913811598, 1073.849661047454, 1058.5554067291716, 1058.8305871060593, 1060.210143443132, 1061.7929503746734, 1063.7181146996052, 792.1682233654698, 795.3438413308985, 795.8420498175199, 796.080526991326, 796.6454927557239, 16365.509034728977, 16692.0, 16770.245338959776, 16848.91575542127, 17125.57186070358, 12643.367831608382, 14543.78184327236, 14829.110253384832, 14895.957282990195, 14953.0, 11749.177557570973, 11853.348065856375, 11886.643400697993, 12081.227914973624, 12112.333821639806, 2974.5389464633017, 3066.9483863555683, 3084.2597776533694, 3181.5545823584716, 3186.0, 88010.11989880083, 110086.0, 112096.53664308495, 112955.0, 116344.41827790892, 11241.923691003723, 11392.658220253406, 11439.313641181556, 11600.709982250342, 11812.350320732443, 23852.284431467084, 24038.39904002414, 24069.398265043514, 24249.0, 24531.0, 8049.839003219903, 8157.265846073882, 8165.918340816515, 8167.918320816716, 8214.876776848352, 3651.379265524867, 4169.666426685919, 4172.520160181557, 4228.0, 4228.640565551901, 4105.527864295585, 4179.1014931789205, 4210.347396153601, 4223.155368926227, 4228.76741779199, 824.7360844529693, 829.4567058576642, 841.4908980067094, 845.6617353058825, 854.457419538585, 529.1797713544069, 529.6266132376666, 584.9064149736107, 588.5968111843315, 612.0941007309118, 8917.331200160013, 9448.960614332418, 9534.809303813885, 10189.337693050076, 10329.12202462784, 544.8556132624858, 575.6143383932775, 578.4504720515528, 594.4199939466401, 599.1970759182716, 4576.610988065985, 4751.23709389968, 4798.2082956311815, 4810.784747456145, 4906.8773280668265, 85728.57135714282, 90578.64132038025, 91054.0, 91836.54081729549, 92526.0, 53839.46160538345, 55367.89264214693, 66236.66881665561, 77068.0, 78771.0, 70134.64932675305, 70849.6457517709, 72964.0, 73925.6303718478, 75157.24842751611, 10456.477176141314, 10774.245802794043, 10927.0, 10997.670069897917, 11147.498362573542, 35555.644443556266, 36256.19309972415, 36766.32336766302, 36971.0, 37538.436923445624, 39393.80303098466, 46556.0, 50196.24313149022, 50274.74862625664, 50344.74827625985, 13246.337683115999, 13310.173053213784, 13408.396622152222, 13854.37655305534, 14228.861691064896, 14366.497172598909, 14393.136411815325, 14393.568192954228, 14396.99221054558, 14423.567292981228, 11657.0, 13113.672158196121, 13509.662258443617, 13526.729465410834, 14068.929655352067, 13103.279319637511, 13241.735165296834, 13376.331183440983, 13490.0, 13701.931490342484, 12175.39123043862, 12259.264444133381, 12363.505459781509, 12363.93818030904, 12710.428030738824, 9256.95371523165, 9324.720258392259, 9403.34176607631, 9517.238620910171, 10094.747631308983, 7575.166731659623, 10154.289199755945, 10628.309159904582, 10851.457427128455, 11200.943995279971], "value": 7492.746676863526, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1663.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 122.0, 122.0, 125.0, 125.0, 123.0, 125.0, 124.0, 124.0, 123.0], "value": 122.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 134.0, 154.0, 196.0, 120.0, 120.0, 116.0, 120.0, 144.0, 121.0], "value": 137.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 217.0, 217.0, 222.0, 213.0, 215.0, 216.0, 223.0, 215.0, 219.0], "value": 217.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 67.0, 64.0, 98.0, 68.0, 64.0, 84.0, 92.0, 110.0, 62.0], "value": 79.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 77.0, 96.0, 76.0, 75.0, 76.0, 120.0, 110.0, 107.0, 93.0], "value": 92.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 108.0, 144.0, 96.0, 96.0, 111.0, 145.0, 112.0, 136.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 102.0, 100.0, 102.0, 108.0, 123.0, 159.0, 157.0, 102.0, 100.0], "value": 115.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 102.0, 104.0, 104.0, 102.0, 103.0, 102.0, 101.0, 102.0, 102.0], "value": 102.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 64.0, 66.0, 64.0, 67.0, 66.0, 65.0, 66.0, 65.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 91.0, 103.0, 97.0, 75.0, 65.0, 65.0, 66.0, 66.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 90.0, 92.0, 90.0, 92.0, 88.0, 89.0, 89.0, 89.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 162.0, 152.0, 151.0, 173.0, 140.0, 143.0, 131.0, 222.0, 206.0], "value": 163.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 212.0, 198.0, 201.0, 202.0, 219.0, 234.0, 249.0, 226.0, 200.0], "value": 214.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 83.0, 87.0, 74.0, 64.0, 71.0, 74.0, 72.0, 72.0], "value": 74.2, "unit": "score"}]}]} 18:18:03 INFO - Return code: 0 18:18:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:18:03 ERROR - # TBPL SUCCESS # 18:18:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:18:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:18:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:18:03 INFO - 2017-03-28 18:18:03.517 screenresolution[1911:10884] starting screenresolution argv=screenresolution get 18:18:03 INFO - 2017-03-28 18:18:03.520 screenresolution[1911:10884] Display 0: 1600x1200x32@60 18:18:03 INFO - 2017-03-28 18:18:03.527 screenresolution[1912:10887] starting screenresolution argv=screenresolution list 18:18:03 INFO - Available Modes on Display 0 18:18:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:18:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:18:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:18:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:18:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:18:03 INFO - Intel Iris: 18:18:03 INFO - Chipset Model: Intel Iris 18:18:03 INFO - Type: GPU 18:18:03 INFO - Bus: Built-In 18:18:03 INFO - VRAM (Dynamic, Max): 1536 MB 18:18:03 INFO - Vendor: Intel (0x8086) 18:18:03 INFO - Device ID: 0x0a2e 18:18:03 INFO - Revision ID: 0x0009 18:18:03 INFO - Displays: 18:18:03 INFO - Display: 18:18:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:18:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:18:03 INFO - Display Serial Number: 1600x1200 60 18:18:03 INFO - Main Display: Yes 18:18:03 INFO - Mirror: Off 18:18:03 INFO - Online: Yes 18:18:03 INFO - Rotation: Supported 18:18:03 INFO - Return code: 0 18:18:03 INFO - Running post-action listener: _resource_record_post_action 18:18:03 INFO - [mozharness: 2017-03-29 01:18:03.760421Z] Finished run-tests step (success) 18:18:03 INFO - Running post-run listener: _resource_record_post_run 18:18:03 INFO - Total resource usage - Wall time: 1207s; CPU: 54.0%; Read bytes: 2215936; Write bytes: 513668608; Read time: 21; Write time: 1790 18:18:03 INFO - TinderboxPrint: CPU usage
53.7% 18:18:03 INFO - TinderboxPrint: I/O read bytes / time
2,215,936 / 21 18:18:03 INFO - TinderboxPrint: I/O write bytes / time
513,668,608 / 1,790 18:18:03 INFO - TinderboxPrint: CPU idle
2,237.3 (46.3%) 18:18:03 INFO - TinderboxPrint: CPU system
967.9 (20.0%) 18:18:03 INFO - TinderboxPrint: CPU user
1,624.5 (33.6%) 18:18:03 INFO - TinderboxPrint: Swap in / out
213,966,848 / 0 18:18:03 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203813376; Write bytes: 202702848; Read time: 15744; Write time: 362 18:18:03 INFO - run-tests - Wall time: 1187s; CPU: 54.0%; Read bytes: 2215936; Write bytes: 300996096; Read time: 21; Write time: 1395 18:18:04 INFO - Running post-run listener: _upload_blobber_files 18:18:04 INFO - Blob upload gear active. 18:18:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:18:04 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 18:18:04 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:18:04 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:18:04 INFO - (blobuploader) - INFO - Open directory for files ... 18:18:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:18:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:18:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:18:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:18:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:18:05 INFO - (blobuploader) - INFO - Done attempting. 18:18:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:18:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:18:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:18:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:18:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:18:06 INFO - (blobuploader) - INFO - Done attempting. 18:18:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:18:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:18:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:18:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:18:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:18:07 INFO - (blobuploader) - INFO - Done attempting. 18:18:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:18:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:18:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:18:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:18:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:18:09 INFO - (blobuploader) - INFO - Done attempting. 18:18:09 INFO - (blobuploader) - INFO - Iteration through files over. 18:18:09 INFO - Return code: 0 18:18:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:18:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:18:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fe9cd95e90a5101f99ec5692a8bccfea654fcb5d2d6fc5df5ac914df3d644d3dc8126f26df2c48c8b98e7e115240a306c5f991a59340af4b7af463d3094ed68c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/604ea228562a4823c2ecb976011921bd1b23822b4ab274338637dc5096bd691d7abc823380ce95f1a600947ac19c4bbe6f271a5c7163bd2d62178e91a08f7e59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/89128735efaed6bfd96bae1081eba027f4a4bdced32ee679b9e02d078bb73826d104d1da9f83cc6e01ea69003a592b480510d2048919f95d7eefe85a1b10b8dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0db957df45b45c80733c2e5d3268f71944be44370a3358653499bd9da1ad475dd73d5c756399f4014a51b8d4d58131500ce087a6a476dd0b7d021ea813c1aadd"} 18:18:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:18:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:18:09 INFO - Contents: 18:18:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fe9cd95e90a5101f99ec5692a8bccfea654fcb5d2d6fc5df5ac914df3d644d3dc8126f26df2c48c8b98e7e115240a306c5f991a59340af4b7af463d3094ed68c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/604ea228562a4823c2ecb976011921bd1b23822b4ab274338637dc5096bd691d7abc823380ce95f1a600947ac19c4bbe6f271a5c7163bd2d62178e91a08f7e59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/89128735efaed6bfd96bae1081eba027f4a4bdced32ee679b9e02d078bb73826d104d1da9f83cc6e01ea69003a592b480510d2048919f95d7eefe85a1b10b8dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0db957df45b45c80733c2e5d3268f71944be44370a3358653499bd9da1ad475dd73d5c756399f4014a51b8d4d58131500ce087a6a476dd0b7d021ea813c1aadd"} 18:18:09 INFO - Running post-run listener: copy_logs_to_upload_dir 18:18:09 INFO - Copying logs to upload dir... 18:18:09 INFO - mkdir: /builds/slave/test/build/upload/logs 18:18:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.635697 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-28 18:18:10.003599) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 18:18:10.007130) ========= 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.NXka1i79HG/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.hkefD3UbKy/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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/Pine/sha512/fe9cd95e90a5101f99ec5692a8bccfea654fcb5d2d6fc5df5ac914df3d644d3dc8126f26df2c48c8b98e7e115240a306c5f991a59340af4b7af463d3094ed68c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/604ea228562a4823c2ecb976011921bd1b23822b4ab274338637dc5096bd691d7abc823380ce95f1a600947ac19c4bbe6f271a5c7163bd2d62178e91a08f7e59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/89128735efaed6bfd96bae1081eba027f4a4bdced32ee679b9e02d078bb73826d104d1da9f83cc6e01ea69003a592b480510d2048919f95d7eefe85a1b10b8dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0db957df45b45c80733c2e5d3268f71944be44370a3358653499bd9da1ad475dd73d5c756399f4014a51b8d4d58131500ce087a6a476dd0b7d021ea813c1aadd"} build_url:https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/AboJFfIeQvedGYJMSe87mg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/fe9cd95e90a5101f99ec5692a8bccfea654fcb5d2d6fc5df5ac914df3d644d3dc8126f26df2c48c8b98e7e115240a306c5f991a59340af4b7af463d3094ed68c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/604ea228562a4823c2ecb976011921bd1b23822b4ab274338637dc5096bd691d7abc823380ce95f1a600947ac19c4bbe6f271a5c7163bd2d62178e91a08f7e59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/89128735efaed6bfd96bae1081eba027f4a4bdced32ee679b9e02d078bb73826d104d1da9f83cc6e01ea69003a592b480510d2048919f95d7eefe85a1b10b8dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0db957df45b45c80733c2e5d3268f71944be44370a3358653499bd9da1ad475dd73d5c756399f4014a51b8d4d58131500ce087a6a476dd0b7d021ea813c1aadd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 18:18:10.041397) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 18:18:10.041746) ========= 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.NXka1i79HG/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.hkefD3UbKy/Listeners TMPDIR=/var/folders/8w/f4pjrf8x3_z5wkxqw3mwdcg400000w/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.005434 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 18:18:10.090631) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 18:18:10.091016) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 18:18:10.091397) ========= ========= Total master_lag: 0.18 =========