builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0344 starttime: 1492204271.23 results: success (0) buildid: 20170414130706 builduid: 999e954d2cfb467ab689da86c29a1e11 revision: e53f7f1785ebedaf692d599d70e3a7a9e1bb5f4f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-14 14:11:11.231415) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-14 14:11:11.231925) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-14 14:11:11.232216) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-14 14:11:11.257672) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 14:11:11.258075) ========= 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.dYXwDrStUx/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.bKXb4VbJ0U/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-14 14:11:11-- 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% 155M=0s 2017-04-14 14:11:11 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082741 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 14:11:11.364619) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 14:11:11.364950) ========= 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.dYXwDrStUx/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.bKXb4VbJ0U/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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.021779 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 14:11:11.413450) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-14 14:11:11.413811) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e53f7f1785ebedaf692d599d70e3a7a9e1bb5f4f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e53f7f1785ebedaf692d599d70e3a7a9e1bb5f4f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dYXwDrStUx/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.bKXb4VbJ0U/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-14 14:11:11,507 truncating revision to first 12 chars 2017-04-14 14:11:11,508 Setting DEBUG logging. 2017-04-14 14:11:11,508 attempt 1/10 2017-04-14 14:11:11,508 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e53f7f1785eb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-14 14:11:13,225 unpacking tar archive at: mozilla-aurora-e53f7f1785eb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.976745 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-14 14:11:13.414537) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-14 14:11:13.414888) ========= script_repo_revision: e53f7f1785ebedaf692d599d70e3a7a9e1bb5f4f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-14 14:11:13.415289) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-14 14:11:13.415605) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-14 14:11:13.435818) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-14 14:11:13.436236) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dYXwDrStUx/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.bKXb4VbJ0U/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 14:11:13 INFO - MultiFileLogger online at 20170414 14:11:13 in /builds/slave/test 14:11:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 14:11:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:11:13 INFO - {'append_to_log': False, 14:11:13 INFO - 'base_work_dir': '/builds/slave/test', 14:11:13 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:11:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:11:13 INFO - 'branch': 'Mozilla-Aurora', 14:11:13 INFO - 'buildbot_json_path': 'buildprops.json', 14:11:13 INFO - 'config_files': ('talos/mac_config.py',), 14:11:13 INFO - 'default_actions': ('clobber', 14:11:13 INFO - 'read-buildbot-config', 14:11:13 INFO - 'download-and-extract', 14:11:13 INFO - 'populate-webroot', 14:11:13 INFO - 'create-virtualenv', 14:11:13 INFO - 'install', 14:11:13 INFO - 'run-tests'), 14:11:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:11:13 INFO - 'download_minidump_stackwalk': True, 14:11:13 INFO - 'download_symbols': 'ondemand', 14:11:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:11:13 INFO - 'tooltool.py': '/tools/tooltool.py', 14:11:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:11:13 INFO - '/tools/misc-python/virtualenv.py')}, 14:11:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:11:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:11:13 INFO - 'gecko_profile': False, 14:11:13 INFO - 'gecko_profile_interval': 0, 14:11:13 INFO - 'installer_path': 'installer.exe', 14:11:13 INFO - 'log_level': 'info', 14:11:13 INFO - 'log_name': 'talos', 14:11:13 INFO - 'log_to_console': True, 14:11:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:11:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:11:13 INFO - 'opt_config_files': (), 14:11:13 INFO - 'pip_index': False, 14:11:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:11:13 INFO - 'cmd': ('bash', 14:11:13 INFO - '-c', 14:11:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:11:13 INFO - 'enabled': True, 14:11:13 INFO - 'halt_on_failure': False, 14:11:13 INFO - 'name': 'check_screen_resolution'},), 14:11:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:11:13 INFO - 'cmd': ('bash', 14:11:13 INFO - '-c', 14:11:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:11:13 INFO - 'enabled': True, 14:11:13 INFO - 'halt_on_failure': False, 14:11:13 INFO - 'name': 'check_screen_resolution'},), 14:11:13 INFO - 'run_cmd_checks_enabled': True, 14:11:13 INFO - 'suite': 'dromaeojs', 14:11:13 INFO - 'system_bits': '32', 14:11:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:11:13 INFO - 'title': 't-yosemite-r7-0344', 14:11:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:11:13 INFO - 'use_talos_json': True, 14:11:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:11:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:11:13 INFO - 'work_dir': 'build'} 14:11:13 INFO - [mozharness: 2017-04-14 21:11:13.649916Z] Running clobber step. 14:11:13 INFO - Running pre-action listener: _resource_record_pre_action 14:11:13 INFO - Running main action method: clobber 14:11:13 INFO - rmtree: /builds/slave/test/build 14:11:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:11:15 INFO - Running post-action listener: _resource_record_post_action 14:11:15 INFO - [mozharness: 2017-04-14 21:11:15.402673Z] Finished clobber step (success) 14:11:15 INFO - [mozharness: 2017-04-14 21:11:15.402811Z] Running read-buildbot-config step. 14:11:15 INFO - Running pre-action listener: _resource_record_pre_action 14:11:15 INFO - Running main action method: read_buildbot_config 14:11:15 INFO - Using buildbot properties: 14:11:15 INFO - { 14:11:15 INFO - "project": "", 14:11:15 INFO - "product": "firefox", 14:11:15 INFO - "script_repo_revision": "production", 14:11:15 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 14:11:15 INFO - "repository": "", 14:11:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 14:11:15 INFO - "buildid": "20170414130706", 14:11:15 INFO - "pgo_build": "False", 14:11:15 INFO - "basedir": "/builds/slave/test", 14:11:15 INFO - "buildnumber": 19, 14:11:15 INFO - "slavename": "t-yosemite-r7-0344", 14:11:15 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 14:11:15 INFO - "platform": "yosemite_r7", 14:11:15 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:11:15 INFO - "branch": "mozilla-aurora", 14:11:15 INFO - "repo_path": "releases/mozilla-aurora", 14:11:15 INFO - "revision": "e53f7f1785ebedaf692d599d70e3a7a9e1bb5f4f", 14:11:15 INFO - "stage_platform": "macosx64", 14:11:15 INFO - "builduid": "999e954d2cfb467ab689da86c29a1e11", 14:11:15 INFO - "slavebuilddir": "test" 14:11:15 INFO - } 14:11:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 14:11:15 INFO - Running post-action listener: _resource_record_post_action 14:11:15 INFO - [mozharness: 2017-04-14 21:11:15.406077Z] Finished read-buildbot-config step (success) 14:11:15 INFO - [mozharness: 2017-04-14 21:11:15.406216Z] Running download-and-extract step. 14:11:15 INFO - Running pre-action listener: _resource_record_pre_action 14:11:15 INFO - Running main action method: download_and_extract 14:11:15 INFO - mkdir: /builds/slave/test/build/tests 14:11:15 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')]} 14:11:15 INFO - trying https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 14:11:15 INFO - Downloading https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 14:11:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 14:11:16 INFO - Downloaded 1199 bytes. 14:11:16 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 14:11:16 INFO - Using the following test package requirements: 14:11:16 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 14:11:16 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:11:16 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 14:11:16 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:11:16 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 14:11:16 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:11:16 INFO - u'jsshell-mac.zip'], 14:11:16 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:11:16 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 14:11:16 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 14:11:16 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:11:16 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 14:11:16 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:11:16 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 14:11:16 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:11:16 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 14:11:16 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 14:11:16 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 14:11:16 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:11:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 14:11:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:11:16 INFO - Fetch https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 14:11:18 INFO - Expected file size: 51790915 14:11:18 INFO - Obtained file size: 51790915 14:11:34 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 14:11:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 14:11:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:11:34 INFO - Fetch https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 14:11:35 INFO - Expected file size: 51790915 14:11:35 INFO - Obtained file size: 51790915 14:11:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 14:11:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:11:50 INFO - Fetch https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 14:11:52 INFO - Expected file size: 14404424 14:11:52 INFO - Obtained file size: 14404424 14:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:11:53 INFO - trying https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 14:11:53 INFO - Downloading https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 14:11:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 14:11:55 INFO - Downloaded 74744600 bytes. 14:11:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 14:11:55 INFO - mkdir: /builds/slave/test/properties 14:11:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:11:55 INFO - Writing to file /builds/slave/test/properties/build_url 14:11:55 INFO - Contents: 14:11:55 INFO - build_url:https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 14:11:55 INFO - Running post-action listener: _resource_record_post_action 14:11:55 INFO - Running post-action listener: set_extra_try_arguments 14:11:55 INFO - [mozharness: 2017-04-14 21:11:55.841539Z] Finished download-and-extract step (success) 14:11:55 INFO - [mozharness: 2017-04-14 21:11:55.841685Z] Running populate-webroot step. 14:11:55 INFO - Running pre-action listener: _resource_record_pre_action 14:11:55 INFO - Running main action method: populate_webroot 14:11:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:11:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:11:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:11:55 INFO - '--tppagecycles', 14:11:55 INFO - '7'], 14:11:55 INFO - 'tests': ['tsvgm']}}, 14:11:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:11:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:11:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:11:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:11:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:11:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:11:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:11:55 INFO - 'talos_options': ['--disable-e10s'], 14:11:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:11:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:11:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:11:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:11:55 INFO - 'talos_options': ['--disable-e10s'], 14:11:55 INFO - 'tests': ['damp', 'tps']}, 14:11:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:11:55 INFO - 'tests': ['damp', 'tps']}, 14:11:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:11:55 INFO - 'tests': ['dromaeo_dom']}, 14:11:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:11:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:11:55 INFO - 'tests': ['basic_compositor_video']}, 14:11:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:11:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:11:55 INFO - 'tests': ['a11yr', 14:11:55 INFO - 'ts_paint', 14:11:55 INFO - 'tpaint', 14:11:55 INFO - 'sessionrestore', 14:11:55 INFO - 'sessionrestore_no_auto_restore', 14:11:55 INFO - 'tabpaint']}, 14:11:55 INFO - 'other-e10s': {'tests': ['a11yr', 14:11:55 INFO - 'ts_paint', 14:11:55 INFO - 'tpaint', 14:11:55 INFO - 'sessionrestore', 14:11:55 INFO - 'sessionrestore_no_auto_restore', 14:11:55 INFO - 'tabpaint']}, 14:11:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:11:55 INFO - 'ts_paint', 14:11:55 INFO - 'tpaint', 14:11:55 INFO - 'sessionrestore', 14:11:55 INFO - 'sessionrestore_no_auto_restore', 14:11:55 INFO - 'tabpaint']}, 14:11:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:11:55 INFO - 'ts_paint', 14:11:55 INFO - 'tpaint', 14:11:55 INFO - 'sessionrestore', 14:11:55 INFO - 'sessionrestore_no_auto_restore', 14:11:55 INFO - 'tabpaint']}, 14:11:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:11:55 INFO - 'tests': ['a11yr', 14:11:55 INFO - 'ts_paint', 14:11:55 INFO - 'tpaint', 14:11:55 INFO - 'sessionrestore', 14:11:55 INFO - 'sessionrestore_no_auto_restore', 14:11:55 INFO - 'tabpaint']}, 14:11:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:11:55 INFO - 'tests': ['a11yr', 14:11:55 INFO - 'ts_paint', 14:11:55 INFO - 'tpaint', 14:11:55 INFO - 'sessionrestore', 14:11:55 INFO - 'sessionrestore_no_auto_restore', 14:11:55 INFO - 'tabpaint']}, 14:11:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:11:55 INFO - 'tests': ['tsvgx', 14:11:55 INFO - 'tsvgr_opacity', 14:11:55 INFO - 'tart', 14:11:55 INFO - 'tscrollx', 14:11:55 INFO - 'cart', 14:11:55 INFO - 'tsvg_static']}, 14:11:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:11:55 INFO - 'tsvgr_opacity', 14:11:55 INFO - 'tart', 14:11:55 INFO - 'tscrollx', 14:11:55 INFO - 'cart', 14:11:55 INFO - 'tsvg_static']}, 14:11:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:11:55 INFO - 'talos_options': ['--disable-e10s'], 14:11:55 INFO - 'tests': ['tp5o']}, 14:11:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:11:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:11:55 INFO - 'talos_options': ['--disable-e10s', 14:11:55 INFO - '--xperf_path', 14:11:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:11:55 INFO - 'tests': ['tp5n']}, 14:11:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:11:55 INFO - 'talos_options': ['--xperf_path', 14:11:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:11:55 INFO - 'tests': ['tp5n']}}, 14:11:55 INFO - 'talos.zip': {'path': '', 14:11:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:11:55 INFO - Running post-action listener: _resource_record_post_action 14:11:55 INFO - [mozharness: 2017-04-14 21:11:55.860163Z] Finished populate-webroot step (success) 14:11:55 INFO - [mozharness: 2017-04-14 21:11:55.860297Z] Running create-virtualenv step. 14:11:55 INFO - Running pre-action listener: _resource_record_pre_action 14:11:55 INFO - Running main action method: create_virtualenv 14:11:55 INFO - Creating virtualenv /builds/slave/test/build/venv 14:11:55 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 14:11:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:11:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:11:55 INFO - Using real prefix '/tools/python27' 14:11:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:11:56 INFO - Installing distribute.............................................................................................................................................................................................done. 14:11:59 INFO - Installing pip.................done. 14:11:59 INFO - Return code: 0 14:11:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:11:59 INFO - Reading from file tmpfile_stdout 14:11:59 INFO - Output received: 14:11:59 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) 14:11:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:11:59 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')]} 14:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:11:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:11:59 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')]} 14:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:11:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:11:59 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 0x101318b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f04672c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010f6d30>, '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 0x10101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ebbe8>, '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.dYXwDrStUx/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.bKXb4VbJ0U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 14:11:59 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 14:11:59 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 14:11:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dYXwDrStUx/Render', 14:11:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:11:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:11:59 INFO - 'HOME': '/Users/cltbld', 14:11:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:11:59 INFO - 'LOGNAME': 'cltbld', 14:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:11:59 INFO - 'MOZ_NO_REMOTE': '1', 14:11:59 INFO - 'NO_EM_RESTART': '1', 14:11:59 INFO - 'PAGER': '/bin/cat', 14:11:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:11:59 INFO - 'PWD': '/builds/slave/test', 14:11:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:11:59 INFO - 'SHELL': '/bin/bash', 14:11:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bKXb4VbJ0U/Listeners', 14:11:59 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 14:11:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:11:59 INFO - 'USER': 'cltbld', 14:11:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:11:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:11:59 INFO - 'XPC_FLAGS': '0x0', 14:11:59 INFO - 'XPC_SERVICE_NAME': '0', 14:11:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:00 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 14:12:00 INFO - Cleaning up... 14:12:00 INFO - Return code: 0 14:12:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:00 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 0x101318b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f04672c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010f6d30>, '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 0x10101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ebbe8>, '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.dYXwDrStUx/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.bKXb4VbJ0U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 14:12:00 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 14:12:00 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 14:12:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dYXwDrStUx/Render', 14:12:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:00 INFO - 'HOME': '/Users/cltbld', 14:12:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:00 INFO - 'LOGNAME': 'cltbld', 14:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:00 INFO - 'MOZ_NO_REMOTE': '1', 14:12:00 INFO - 'NO_EM_RESTART': '1', 14:12:00 INFO - 'PAGER': '/bin/cat', 14:12:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:00 INFO - 'PWD': '/builds/slave/test', 14:12:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:00 INFO - 'SHELL': '/bin/bash', 14:12:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bKXb4VbJ0U/Listeners', 14:12:00 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 14:12:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:00 INFO - 'USER': 'cltbld', 14:12:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:00 INFO - 'XPC_FLAGS': '0x0', 14:12:00 INFO - 'XPC_SERVICE_NAME': '0', 14:12:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:00 INFO - Downloading/unpacking psutil>=3.1.1 14:12:00 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 14:12:00 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 14:12:00 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 14:12:00 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 14:12:00 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 14:12:00 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 14:12:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:12:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:12:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:12:04 INFO - Installing collected packages: psutil 14:12:04 INFO - Running setup.py install for psutil 14:12:04 INFO - building 'psutil._psutil_osx' extension 14:12:04 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 14:12:04 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 14:12:04 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 14:12:04 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 14:12:04 INFO - building 'psutil._psutil_posix' extension 14:12:04 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 14:12:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:12:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:12:04 INFO - ^ 14:12:04 INFO - 1 warning generated. 14:12:04 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 14:12:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:12:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:12:04 INFO - Successfully installed psutil 14:12:04 INFO - Cleaning up... 14:12:05 INFO - Return code: 0 14:12:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:12:05 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')]} 14:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:05 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')]} 14:12:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:05 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 0x101318b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f04672c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010f6d30>, '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 0x10101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ebbe8>, '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.dYXwDrStUx/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.bKXb4VbJ0U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 14:12:05 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 14:12:05 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 14:12:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dYXwDrStUx/Render', 14:12:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:05 INFO - 'HOME': '/Users/cltbld', 14:12:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:05 INFO - 'LOGNAME': 'cltbld', 14:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:05 INFO - 'MOZ_NO_REMOTE': '1', 14:12:05 INFO - 'NO_EM_RESTART': '1', 14:12:05 INFO - 'PAGER': '/bin/cat', 14:12:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:05 INFO - 'PWD': '/builds/slave/test', 14:12:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:05 INFO - 'SHELL': '/bin/bash', 14:12:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bKXb4VbJ0U/Listeners', 14:12:05 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 14:12:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:05 INFO - 'USER': 'cltbld', 14:12:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:05 INFO - 'XPC_FLAGS': '0x0', 14:12:05 INFO - 'XPC_SERVICE_NAME': '0', 14:12:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:12:05 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 14:12:05 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 14:12:05 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 14:12:05 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 14:12:05 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 14:12:05 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 14:12:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:12:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:12:08 INFO - Installing collected packages: mozsystemmonitor 14:12:08 INFO - Running setup.py install for mozsystemmonitor 14:12:08 INFO - Successfully installed mozsystemmonitor 14:12:08 INFO - Cleaning up... 14:12:08 INFO - Return code: 0 14:12:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101318b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f04672c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010f6d30>, '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 0x10101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ebbe8>, '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.dYXwDrStUx/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.bKXb4VbJ0U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 14:12:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:12:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dYXwDrStUx/Render', 14:12:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:08 INFO - 'HOME': '/Users/cltbld', 14:12:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:08 INFO - 'LOGNAME': 'cltbld', 14:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:08 INFO - 'MOZ_NO_REMOTE': '1', 14:12:08 INFO - 'NO_EM_RESTART': '1', 14:12:08 INFO - 'PAGER': '/bin/cat', 14:12:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:08 INFO - 'PWD': '/builds/slave/test', 14:12:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:08 INFO - 'SHELL': '/bin/bash', 14:12:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bKXb4VbJ0U/Listeners', 14:12:08 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 14:12:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:08 INFO - 'USER': 'cltbld', 14:12:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:08 INFO - 'XPC_FLAGS': '0x0', 14:12:08 INFO - 'XPC_SERVICE_NAME': '0', 14:12:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:08 INFO - Downloading/unpacking jsonschema==2.5.1 14:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:12:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:12:11 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 14:12:11 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 14:12:11 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 14:12:11 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 14:12:11 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 14:12:11 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 14:12:11 INFO - Downloading functools32-3.2.3-2.tar.gz 14:12:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:12:11 INFO - warning: no files found matching '*.txt' 14:12:11 INFO - no previously-included directories found matching 'build' 14:12:11 INFO - no previously-included directories found matching 'dist' 14:12:11 INFO - no previously-included directories found matching '.git*' 14:12:11 INFO - Installing collected packages: jsonschema, functools32 14:12:12 INFO - Running setup.py install for functools32 14:12:12 INFO - warning: no files found matching '*.txt' 14:12:12 INFO - no previously-included directories found matching 'build' 14:12:12 INFO - no previously-included directories found matching 'dist' 14:12:12 INFO - no previously-included directories found matching '.git*' 14:12:12 INFO - Successfully installed jsonschema functools32 14:12:12 INFO - Cleaning up... 14:12:12 INFO - Return code: 0 14:12:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:12 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 0x101318b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f04672c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010f6d30>, '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 0x10101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ebbe8>, '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.dYXwDrStUx/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.bKXb4VbJ0U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 14:12:12 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 14:12:12 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 14:12:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dYXwDrStUx/Render', 14:12:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:12 INFO - 'HOME': '/Users/cltbld', 14:12:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:12 INFO - 'LOGNAME': 'cltbld', 14:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:12 INFO - 'MOZ_NO_REMOTE': '1', 14:12:12 INFO - 'NO_EM_RESTART': '1', 14:12:12 INFO - 'PAGER': '/bin/cat', 14:12:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:12 INFO - 'PWD': '/builds/slave/test', 14:12:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:12 INFO - 'SHELL': '/bin/bash', 14:12:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bKXb4VbJ0U/Listeners', 14:12:12 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 14:12:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:12 INFO - 'USER': 'cltbld', 14:12:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:12 INFO - 'XPC_FLAGS': '0x0', 14:12:12 INFO - 'XPC_SERVICE_NAME': '0', 14:12:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:12:12 INFO - Cleaning up... 14:12:12 INFO - Return code: 0 14:12:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:12 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 0x101318b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f04672c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010f6d30>, '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 0x10101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ebbe8>, '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.dYXwDrStUx/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.bKXb4VbJ0U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 14:12:12 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 14:12:12 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 14:12:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dYXwDrStUx/Render', 14:12:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:12 INFO - 'HOME': '/Users/cltbld', 14:12:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:12 INFO - 'LOGNAME': 'cltbld', 14:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:12 INFO - 'MOZ_NO_REMOTE': '1', 14:12:12 INFO - 'NO_EM_RESTART': '1', 14:12:12 INFO - 'PAGER': '/bin/cat', 14:12:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:12 INFO - 'PWD': '/builds/slave/test', 14:12:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:12 INFO - 'SHELL': '/bin/bash', 14:12:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bKXb4VbJ0U/Listeners', 14:12:12 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 14:12:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:12 INFO - 'USER': 'cltbld', 14:12:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:12 INFO - 'XPC_FLAGS': '0x0', 14:12:12 INFO - 'XPC_SERVICE_NAME': '0', 14:12:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:12 INFO - Downloading/unpacking blobuploader==1.2.4 14:12:12 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 14:12:12 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 14:12:12 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 14:12:12 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 14:12:12 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 14:12:12 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 14:12:15 INFO - Downloading blobuploader-1.2.4.tar.gz 14:12:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:12:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:12:15 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 14:12:15 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 14:12:15 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 14:12:15 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 14:12:15 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 14:12:15 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 14:12:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:12:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:16 INFO - Downloading docopt-0.6.1.tar.gz 14:12:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:12:16 INFO - Installing collected packages: blobuploader, requests, docopt 14:12:16 INFO - Running setup.py install for blobuploader 14:12:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:12:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:12:16 INFO - Running setup.py install for requests 14:12:17 INFO - Running setup.py install for docopt 14:12:17 INFO - Successfully installed blobuploader requests docopt 14:12:17 INFO - Cleaning up... 14:12:17 INFO - Return code: 0 14:12:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:12: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')]} 14:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12: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')]} 14:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:17 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 0x101318b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f04672c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010f6d30>, '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 0x10101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ebbe8>, '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.dYXwDrStUx/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.bKXb4VbJ0U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 14:12:17 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 14:12:17 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 14:12:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dYXwDrStUx/Render', 14:12:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:17 INFO - 'HOME': '/Users/cltbld', 14:12:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:17 INFO - 'LOGNAME': 'cltbld', 14:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:17 INFO - 'MOZ_NO_REMOTE': '1', 14:12:17 INFO - 'NO_EM_RESTART': '1', 14:12:17 INFO - 'PAGER': '/bin/cat', 14:12:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:17 INFO - 'PWD': '/builds/slave/test', 14:12:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:17 INFO - 'SHELL': '/bin/bash', 14:12:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bKXb4VbJ0U/Listeners', 14:12:17 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 14:12:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:17 INFO - 'USER': 'cltbld', 14:12:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:17 INFO - 'XPC_FLAGS': '0x0', 14:12:17 INFO - 'XPC_SERVICE_NAME': '0', 14:12:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:12:17 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-6CssuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:12:18 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-BdiqCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:12:18 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-cHuvxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:12:18 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-2n0K2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:12:18 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-VRUolX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:12:18 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-dlJEqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:12:18 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-mHwDH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:12:18 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-guJ7jI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:12:18 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-14IhMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:12:18 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-oI9GYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:12:18 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-ylUiYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:12:19 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-XMiQTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:12:19 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-rxGAli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:12:19 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-FTBkir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:12:19 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-sDdqSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:12:19 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-_s4m6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:12:19 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-80Fhb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:12:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:12:19 INFO - Running setup.py install for manifestparser 14:12:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:12:19 INFO - Running setup.py install for mozcrash 14:12:20 INFO - Running setup.py install for mozdebug 14:12:20 INFO - Running setup.py install for mozdevice 14:12:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:12:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:12:20 INFO - Running setup.py install for mozfile 14:12:20 INFO - Running setup.py install for mozhttpd 14:12:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:12:20 INFO - Running setup.py install for mozinfo 14:12:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:12:20 INFO - Running setup.py install for mozInstall 14:12:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:12:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:12:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:12:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:12:20 INFO - Running setup.py install for mozleak 14:12:21 INFO - Running setup.py install for mozlog 14:12:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:12:21 INFO - Running setup.py install for moznetwork 14:12:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:12:21 INFO - Running setup.py install for mozprocess 14:12:21 INFO - Running setup.py install for mozprofile 14:12:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:12:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:12:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:12:21 INFO - Running setup.py install for mozrunner 14:12:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:12:21 INFO - Running setup.py install for mozscreenshot 14:12:22 INFO - Running setup.py install for moztest 14:12:22 INFO - Running setup.py install for mozversion 14:12:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:12:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:12:22 INFO - Cleaning up... 14:12:22 INFO - Return code: 0 14:12:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:22 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 0x101318b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f04672c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010f6d30>, '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 0x10101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ebbe8>, '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.dYXwDrStUx/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.bKXb4VbJ0U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 14:12:22 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 14:12:22 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 14:12:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dYXwDrStUx/Render', 14:12:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:22 INFO - 'HOME': '/Users/cltbld', 14:12:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:22 INFO - 'LOGNAME': 'cltbld', 14:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:22 INFO - 'MOZ_NO_REMOTE': '1', 14:12:22 INFO - 'NO_EM_RESTART': '1', 14:12:22 INFO - 'PAGER': '/bin/cat', 14:12:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:22 INFO - 'PWD': '/builds/slave/test', 14:12:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:22 INFO - 'SHELL': '/bin/bash', 14:12:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bKXb4VbJ0U/Listeners', 14:12:22 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 14:12:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:22 INFO - 'USER': 'cltbld', 14:12:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:22 INFO - 'XPC_FLAGS': '0x0', 14:12:22 INFO - 'XPC_SERVICE_NAME': '0', 14:12:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:12:22 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-sieFDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:12:22 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)) 14:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:12:22 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-0bz7zX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:12:22 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)) 14:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:12:22 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-iv95R2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:12:22 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)) 14:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:12:23 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-EZGFi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:12:23 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)) 14:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:12:23 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-2FFUzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:12:23 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)) 14:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:12:23 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-7P0EXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:12:23 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)) 14:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:12:23 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-1vjFeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:12:23 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)) 14:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:12:23 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-rK74Hm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:12:23 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)) 14:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:12:23 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-6koIjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:12:23 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)) 14:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:12:23 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-hHHkoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:12:23 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)) 14:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:12:23 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-77w9PQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:12:23 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)) 14:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:12:23 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-vhY5ZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:12:23 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)) 14:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:12:23 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-okw9P6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:12:24 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)) 14:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:12:24 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-CNgDDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:12:24 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)) 14:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:12:24 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-YRk_kS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:12:24 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)) 14:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:12:24 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-1IYqG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:12:24 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)) 14:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:12:24 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-QVf7wB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:12:24 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)) 14:12:24 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)) 14:12:24 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)) 14:12:24 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)) 14:12:24 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)) 14:12:24 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)) 14:12:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:27 INFO - Downloading blessings-1.6.tar.gz 14:12:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:12:27 INFO - Installing collected packages: blessings 14:12:27 INFO - Running setup.py install for blessings 14:12:27 INFO - Successfully installed blessings 14:12:27 INFO - Cleaning up... 14:12:27 INFO - Return code: 0 14:12:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:12:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:12:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:12:27 INFO - Reading from file tmpfile_stdout 14:12:27 INFO - Current package versions: 14:12:27 INFO - blessings == 1.6 14:12:27 INFO - blobuploader == 1.2.4 14:12:27 INFO - docopt == 0.6.1 14:12:27 INFO - functools32 == 3.2.3-2 14:12:27 INFO - jsonschema == 2.5.1 14:12:27 INFO - manifestparser == 1.1 14:12:27 INFO - mozInstall == 1.12 14:12:27 INFO - mozcrash == 1.0 14:12:27 INFO - mozdebug == 0.1 14:12:27 INFO - mozdevice == 0.49 14:12:27 INFO - mozfile == 1.2 14:12:27 INFO - mozhttpd == 0.7 14:12:27 INFO - mozinfo == 0.9 14:12:27 INFO - mozleak == 0.1 14:12:27 INFO - mozlog == 3.4 14:12:27 INFO - moznetwork == 0.27 14:12:27 INFO - mozprocess == 0.25 14:12:27 INFO - mozprofile == 0.28 14:12:27 INFO - mozrunner == 6.13 14:12:27 INFO - mozscreenshot == 0.1 14:12:27 INFO - mozsystemmonitor == 0.3 14:12:27 INFO - moztest == 0.8 14:12:27 INFO - mozversion == 1.4 14:12:27 INFO - psutil == 3.1.1 14:12:27 INFO - requests == 1.2.3 14:12:27 INFO - wsgiref == 0.1.2 14:12:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:12: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')]} 14:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12: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')]} 14:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:27 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 0x101318b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f04672c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010f6d30>, '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 0x10101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ebbe8>, '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.dYXwDrStUx/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.bKXb4VbJ0U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 14:12:27 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 14:12:27 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 14:12:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dYXwDrStUx/Render', 14:12:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:27 INFO - 'HOME': '/Users/cltbld', 14:12:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:27 INFO - 'LOGNAME': 'cltbld', 14:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:27 INFO - 'MOZ_NO_REMOTE': '1', 14:12:27 INFO - 'NO_EM_RESTART': '1', 14:12:27 INFO - 'PAGER': '/bin/cat', 14:12:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:27 INFO - 'PWD': '/builds/slave/test', 14:12:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:27 INFO - 'SHELL': '/bin/bash', 14:12:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bKXb4VbJ0U/Listeners', 14:12:27 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 14:12:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:27 INFO - 'USER': 'cltbld', 14:12:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:27 INFO - 'XPC_FLAGS': '0x0', 14:12:27 INFO - 'XPC_SERVICE_NAME': '0', 14:12:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:28 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)) 14:12:28 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)) 14:12:28 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)) 14:12:28 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)) 14:12:28 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)) 14:12:28 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)) 14:12:28 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)) 14:12:28 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)) 14:12:28 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)) 14:12:28 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)) 14:12:28 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)) 14:12:28 INFO - Cleaning up... 14:12:28 INFO - Return code: 0 14:12:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:12:28 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')]} 14:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:28 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')]} 14:12:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:28 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 0x101318b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10131b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6f04672c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010f6d30>, '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 0x10101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ebbe8>, '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.dYXwDrStUx/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.bKXb4VbJ0U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 14:12:28 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 14:12:28 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 14:12:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dYXwDrStUx/Render', 14:12:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:28 INFO - 'HOME': '/Users/cltbld', 14:12:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:28 INFO - 'LOGNAME': 'cltbld', 14:12:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:28 INFO - 'MOZ_NO_REMOTE': '1', 14:12:28 INFO - 'NO_EM_RESTART': '1', 14:12:28 INFO - 'PAGER': '/bin/cat', 14:12:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:28 INFO - 'PWD': '/builds/slave/test', 14:12:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:28 INFO - 'SHELL': '/bin/bash', 14:12:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bKXb4VbJ0U/Listeners', 14:12:28 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 14:12:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:28 INFO - 'USER': 'cltbld', 14:12:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:28 INFO - 'XPC_FLAGS': '0x0', 14:12:28 INFO - 'XPC_SERVICE_NAME': '0', 14:12:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:12:28 INFO - Cleaning up... 14:12:28 INFO - Return code: 0 14:12:28 INFO - Running post-action listener: _resource_record_post_action 14:12:28 INFO - Running post-action listener: _start_resource_monitoring 14:12:28 INFO - Starting resource monitoring. 14:12:28 INFO - [mozharness: 2017-04-14 21:12:28.456649Z] Finished create-virtualenv step (success) 14:12:28 INFO - [mozharness: 2017-04-14 21:12:28.457639Z] Running install step. 14:12:28 INFO - Running pre-action listener: _resource_record_pre_action 14:12:28 INFO - Running main action method: install 14:12:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:12:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:12:28 INFO - Reading from file tmpfile_stdout 14:12:28 INFO - Detecting whether we're running mozinstall >=1.0... 14:12:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:12:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:12:28 INFO - Reading from file tmpfile_stdout 14:12:28 INFO - Output received: 14:12:28 INFO - Usage: mozinstall [options] installer 14:12:28 INFO - Options: 14:12:28 INFO - -h, --help show this help message and exit 14:12:28 INFO - -d DEST, --destination=DEST 14:12:28 INFO - Directory to install application into. [default: 14:12:28 INFO - "/builds/slave/test"] 14:12:28 INFO - --app=APP Application being installed. [default: firefox] 14:12:28 INFO - mkdir: /builds/slave/test/build/application 14:12:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:12:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 14:12:49 INFO - Reading from file tmpfile_stdout 14:12:49 INFO - Output received: 14:12:49 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:12:49 INFO - Running post-action listener: _resource_record_post_action 14:12:49 INFO - [mozharness: 2017-04-14 21:12:49.502621Z] Finished install step (success) 14:12:49 INFO - [mozharness: 2017-04-14 21:12:49.502791Z] Running run-tests step. 14:12:49 INFO - Running pre-action listener: _resource_record_pre_action 14:12:49 INFO - Running main action method: run_tests 14:12:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:12:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:12:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:12:49 INFO - 2017-04-14 14:12:49.517 screenresolution[2394:31474] starting screenresolution argv=screenresolution get 14:12:49 INFO - 2017-04-14 14:12:49.520 screenresolution[2394:31474] Display 0: 1600x1200x32@60 14:12:49 INFO - 2017-04-14 14:12:49.526 screenresolution[2395:31477] starting screenresolution argv=screenresolution list 14:12:49 INFO - Available Modes on Display 0 14:12:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:12:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:12:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:12:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:12:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:12:49 INFO - Intel Iris: 14:12:49 INFO - Chipset Model: Intel Iris 14:12:49 INFO - Type: GPU 14:12:49 INFO - Bus: Built-In 14:12:49 INFO - VRAM (Dynamic, Max): 1536 MB 14:12:49 INFO - Vendor: Intel (0x8086) 14:12:49 INFO - Device ID: 0x0a2e 14:12:49 INFO - Revision ID: 0x0009 14:12:49 INFO - Displays: 14:12:49 INFO - Display: 14:12:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:12:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:12:49 INFO - Display Serial Number: 1600x1200 60 14:12:49 INFO - Main Display: Yes 14:12:49 INFO - Mirror: Off 14:12:49 INFO - Online: Yes 14:12:49 INFO - Rotation: Supported 14:12:49 INFO - Return code: 0 14:12:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:12:49 INFO - Python 2.7.3 14:12:49 INFO - Return code: 0 14:12:49 INFO - grabbing minidump binary from tooltool 14:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:12:49 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 0x1010f6d30>, '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 0x10101ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ebbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:12:49 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 14:12:49 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 14:12:49 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 14:12:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:12:49 INFO - Return code: 0 14:12:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:12:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:12:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:12:49 INFO - ENV: RUST_BACKTRACE is now 1 14:12:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:12:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:12:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0344', '--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 14:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0344 --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 14:12:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dYXwDrStUx/Render', 14:12:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:49 INFO - 'HOME': '/Users/cltbld', 14:12:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:49 INFO - 'LOGNAME': 'cltbld', 14:12:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:12:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:49 INFO - 'MOZ_NO_REMOTE': '1', 14:12:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:12:49 INFO - 'NO_EM_RESTART': '1', 14:12:49 INFO - 'PAGER': '/bin/cat', 14:12:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:49 INFO - 'PWD': '/builds/slave/test', 14:12:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:12:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:49 INFO - 'RUST_BACKTRACE': '1', 14:12:49 INFO - 'SHELL': '/bin/bash', 14:12:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bKXb4VbJ0U/Listeners', 14:12:49 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 14:12:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:49 INFO - 'USER': 'cltbld', 14:12:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:49 INFO - 'XPC_FLAGS': '0x0', 14:12:49 INFO - 'XPC_SERVICE_NAME': '0', 14:12:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0344', '--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 14:12:50 INFO - mozversion application_buildid: 20170414130706 14:12:50 INFO - mozversion application_changeset: e53f7f1785ebedaf692d599d70e3a7a9e1bb5f4f 14:12:50 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:12:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:12:50 INFO - mozversion application_name: Firefox 14:12:50 INFO - mozversion application_remotingname: firefox-dev 14:12:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:12:50 INFO - mozversion application_vendor: Mozilla 14:12:50 INFO - mozversion application_version: 54.0a2 14:12:50 INFO - mozversion platform_buildid: 20170414130706 14:12:50 INFO - mozversion platform_changeset: e53f7f1785ebedaf692d599d70e3a7a9e1bb5f4f 14:12:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:12:50 INFO - mozversion platform_version: 54.0a2 14:12:50 INFO - using testdate: 1492204370 14:12:50 INFO - actual date: 1492204370 14:12:50 INFO - starting webserver on 'localhost:58017' 14:12:50 INFO - SUITE-START | Running 2 tests 14:12:50 INFO - TEST-START | dromaeo_css 14:12:50 INFO - operating with platform_type : mac_ 14:12:50 INFO - Initialising browser for dromaeo_css test... 14:12:50 INFO - TEST-INFO | started process 2405 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpY0fSXy/profile http://localhost:58017/getInfo.html) 14:12:52 INFO - PROCESS | 2405 | __metrics Screen width/height:1600/1200 14:12:52 INFO - PROCESS | 2405 | colorDepth:24 14:12:52 INFO - PROCESS | 2405 | Browser inner width/height: 1024/689 14:12:52 INFO - PROCESS | 2405 | __metrics 14:12:52 INFO - PROCESS | 2405 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:12:52 INFO - PROCESS | 2405 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:12:53 INFO - TEST-INFO | 2405: exit 0 14:12:53 INFO - Browser initialized. 14:12:53 INFO - Running cycle 1/1 for dromaeo_css test... 14:12:53 INFO - TEST-INFO | started process 2407 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpY0fSXy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:13:05 INFO - PROCESS | 2407 | RSS: Main: 214818816 14:13:05 INFO - PROCESS | 2407 | 14:16:10 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:58017/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58017/tests/dromaeo/cssquery-ext.html) 14:16:12 INFO - PROCESS | 2407 | RSS: Main: 385978368 14:16:12 INFO - PROCESS | 2407 | 14:17:55 INFO - PROCESS | 2407 | ************************* 14:17:55 INFO - PROCESS | 2407 | A coding exception was thrown and uncaught in a Task. 14:17:55 INFO - PROCESS | 2407 | 14:17:55 ERROR - PROCESS | 2407 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:17:55 INFO - PROCESS | 2407 | Full stack: 14:17:55 INFO - PROCESS | 2407 | ************************* 14:17:55 INFO - PROCESS | 2407 | ************************* 14:17:55 INFO - PROCESS | 2407 | A coding exception was thrown and uncaught in a Task. 14:17:55 INFO - PROCESS | 2407 | 14:17:55 ERROR - PROCESS | 2407 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:17:55 INFO - PROCESS | 2407 | Full stack: 14:17:55 INFO - PROCESS | 2407 | ************************* 14:17:55 INFO - PROCESS | 2407 | ************************* 14:17:55 INFO - PROCESS | 2407 | A coding exception was thrown and uncaught in a Task. 14:17:55 INFO - PROCESS | 2407 | 14:17:55 ERROR - PROCESS | 2407 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:17:55 INFO - PROCESS | 2407 | Full stack: 14:17:55 INFO - PROCESS | 2407 | ************************* 14:17:55 INFO - PROCESS | 2407 | ************************* 14:17:55 INFO - PROCESS | 2407 | A coding exception was thrown and uncaught in a Task. 14:17:55 INFO - PROCESS | 2407 | 14:17:55 ERROR - PROCESS | 2407 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:17:55 INFO - PROCESS | 2407 | Full stack: 14:17:55 INFO - PROCESS | 2407 | ************************* 14:19:14 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:58017/tests/dromaeo/cssquery-ext.html (next: http://localhost:58017/tests/dromaeo/cssquery-jquery.html) 14:19:15 INFO - PROCESS | 2407 | RSS: Main: 348528640 14:19:15 INFO - PROCESS | 2407 | 14:22:18 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:58017/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58017/tests/dromaeo/cssquery-mootools.html) 14:22:18 INFO - PROCESS | 2407 | RSS: Main: 328306688 14:22:18 INFO - PROCESS | 2407 | 14:25:21 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:58017/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58017/tests/dromaeo/cssquery-prototype.html) 14:25:21 INFO - PROCESS | 2407 | RSS: Main: 342433792 14:25:21 INFO - PROCESS | 2407 | 14:28:25 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:58017/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58017/tests/dromaeo/cssquery-yui.html) 14:28:26 INFO - PROCESS | 2407 | RSS: Main: 318840832 14:28:26 INFO - PROCESS | 2407 | 14:31:29 INFO - PROCESS | 2407 | Cycle 1(1): loaded http://localhost:58017/tests/dromaeo/cssquery-yui.html (next: http://localhost:58017/tests/dromaeo/cssquery-dojo.html) 14:31:30 INFO - PROCESS | 2407 | RSS: Main: 391946240 14:31:30 INFO - PROCESS | 2407 | 14:31:30 INFO - PROCESS | 2407 | __start_tp_report 14:31:30 INFO - PROCESS | 2407 | _x_x_mozilla_page_load 14:31:30 INFO - PROCESS | 2407 | _x_x_mozilla_page_load_details 14:31:30 INFO - PROCESS | 2407 | |i|pagename|runs| 14:31:30 INFO - PROCESS | 2407 | |0;dojo.html;3311.9172020699484;3369.5451114099596;3387.8306084695773;3417.982910085449;3438.17483803887;5955.493783028448;5966.9104963425525;5980.431858973401;5991.82024539264;6024.608400453967;4978.800847966077;5983.640981541111;6048.3044449888275;6063.454289113979;6077.1491991121275;5366.490183432567;5370.812021579245;5414.620976531644;5474.562035037207;5486.396496385394;5220;5297.284866543026;5325.7603407846545;5511.917321240185;5561.5272701820395;5368.087425137736;5448.618596698233;5466.371367292772;5466.453354664522;5521.475459831329;5448.945510544883;5463.808766693186;5464.890702185974;5470.452954704538;5470.671759694391;5311.654742441768;5401.864953376158;5405.540529055035;5422.755975981051;5485.067538518461;5748.885022299531;5755.769769209227;5821.359650438449;5823.56323275756;6035.426634469718;6349.873002539969;6357.396047375492;6373.044043393482;6391.58454700443;6413.294537600907;6411.166548348685;6482.93517064828;6498.122753428274;6505.7397704092255;6506.089147519351;5949.137375080589;6002.429769172009;6020.217371741645;6161.815145545641;6181.072839074175;6451.1936007999;6463.4506066983895;6465.515086368541;6504.544681872224;6551.4103730664465;6280.937190628127;6281.214848143982;6296.024116261987;6299.055141728777;6314.652694101776;6386.042093685985;6439.00195469703;6451.741930322734;6455.677216139175;6458.063580780855;6216.285127210338;6262.248530176317;6353.396427339396;6359.427651511386;6366.9681651591445;5844.444777746107;6252.218472690914;6342.016987366966;6358;6385.201849768779;5166.509181627739;5205.27126202332;5209.166533354645;5212.061828870764;5219.321488206585;4595.035042641016;4615.769211539477;4617.238155704271;4629.583337499641;4652.860414187579;5001.549860512571;5834.124881267844;5910.34986151523;5914.4381283777975;5926.111083337448;5266.315379000705;6329.3987071229085;6385;6424.550281480345;6429.228492580826;6034.09488576717;6057.3336932937345;6065.5450841187085;6066.241719785027;6091.48222401092;5625.4374562544235;5634.154876768435;5674.829755107354;5684.715764211773;5698.914516282259;6358.268799088164;6395.904061439175;6555.344465553407;6620.073189753342;6636.170478690164;6443.581167224115;6444.452221561032;6456.386643268882;6475.028745688184;6475.579087359307;6620.271770105381;6654.700538475683;6671.166104236971;6678.83302917431;6694.765683201064;6235.127082208585;6316.778912738032;6365.649889256136;6406.839829004312;6441.259255185713;6274.874502510015;6299.716512756845;6419.582727122722;6432.710528026344;6437;6217.813465596039;6240;6302.212223472066;6363.459105975859;6364.713587888464;6139.201903752483;6345.8730825384155;6347.523935704933;6381.553291269566;6401.103845461732;5950.494207992369;6278.937210628018;6281.623102613858;6281.874362512815;6288.151099601679;6245.406686364788;6247.00047992319;6261.5616906816085;6347.777827776005;6369.108324834619;6237.1891654084675;6241.563090583797;6244;6253.155823963889;6268.749250029948 14:31:30 INFO - PROCESS | 2407 | |1;ext.html;730.4813582356527;788.7633709887034;809.2676128104064;876.8728534362517;889.4885440871501;27445.451090978167;39165.82502524925;39618;39694.603053969455;39738.40392394113;39585;39708.205835883266;39749.40375894364;39801.20397592046;39855.60144398555;7328.230535793745;9157.725268241948;9340.953295233548;9345.813083738321;9347.158755711984;7347.485676002682;7384.778456646309;7419.2951669591575;7427.108746950346;7428.257174282582;10992.021436141491;11228.550857965713;11322.320660760339;11341.659750207466;11399.943000285028;11085.113190944705;11138.554457821678;11140.888591114066;11171.888281117166;11175.497102630401;7144.8928266076055;10919.890801091968;10971.615993440271;10981;10993.450327483593;27632.171034868785;38058.42912356317;38394;38411.80794096012;38422.80788596067;3668.816559172031;3850.8652197173246;3852.5184351956004;3889.86385476507;3944.960550394488;24213.152539661027;24231.971003624545;24308.756912430825;24819.875900620562;25099.874500627564;2803.4813559491354;3016.1102474769996;3110.6422761382505;3117.1583672408324;3118.236032172132;350348;351664;351814;352113;357241;43018.92452688708;43137.705868823985;43253.56746432495;43300;43369.34945975813;45177.54822451799;45225;45264.32103518384;45298.64104076883;45327;11599.724030356654;12464.688382790502;12576.937115314548;12592.81110783339;12599.559015434415;8783.560821958876;10305.381677099249;10325.535350909227;10328.638497652546;10337.328073675188;9010.27917766577;10117.696469105938;10315.638952636771;10487.737806554896;11698.35659038761;5488.420442392069;5621.634593751393;5680.261565996394;5694.857628559319;5704.172894930295;961.9182369498672;1010.6715317521835;1139.9316041037564;1165.0970497864223;1187.9703007424885;25833.870830645727;26148.607770883453;26549.86725066401;26612;26670.466590668468;1011.367895065584;1158.137187795098;1158.4439469054732;1176.5529098942347;1205.2045649871218;12990.415431305613;13365.398557064955;13619.25094119833;13690.178589284675;13692;482163;484391;485671;486105;486429;52321;55271.72364138153;55603.721981389834;55609.60975975633;55761.163582546294;1430.3491911180388;1457.3223451095291;1459.532949456185;1459.6569806095558;1464.8388677245493;19300.54775183177;24390.390240244134;24486.387840304134;25404.618930715686;25757.96968121254;40635.390469142396;40648;40696.79651601723;40707.1858562833;40793.59206408016;427312;428532;429283;429891;430481;29573.852130739207;31692.207694807814;31860.5220921691;31889.362212756077;31956.881509147068;36807.81596092002;37593.24813503769;38088.809555952044;38231.42652860265;38278.23443531058;19930.501737456678;24121.75878241195;25323.87338063298;27114;27176.320591985357;27393.904243830024;27532.58701119525;27619;27706;27915.441691165655;31280.843595782786;31383.372332553678;31733.36533269368;31757.682423175473;32570.34859302848;27339.179824605293;27363.726362736914;27412.725872741816;27966.3008424791;28415;17983.820161798216;20758.896205518875;22315.888420557792;22499;22500 14:31:30 INFO - PROCESS | 2407 | |2;jquery.html;381.4126245581804;387.9515060617423;407.753309247905;416.5064398687555;422.87102433757707;13063.673408164788;16723.832761672395;20851.165953361866;21076.36770896872;21323.25368612099;19566.413007609794;20444;20497.692534611993;20570.588588228227;20883.895580522116;6364.904526432108;6654.234763002244;6655.134832471772;6657.034729964153;6662.367075127855;5415;7514.586697731623;7737.961310193469;7740.26467485588;7742.225777422269;7429.4427917906105;7604.809879752995;7615.35269502093;7716.961415192916;7744.186860379664;7541.396688264926;7551.546907185587;7597.126330471983;7618.085829700416;7647.044119485036;7310.342069213743;7526.134494533144;7540.698372065111;7540.924590754132;7551.131619863731;26967.65161741905;27376.904923803224;27730.86134569314;28025.29936751577;28167.71832281692;2930.472514947309;2939.323955490228;2944.8969286074885;2945.6759756426777;3001.669816320203;15390.574948971176;16266.83733162665;16369.91815040929;16625.418110366078;16766.664666706718;1909.961800763977;1967.1147983407382;1979.416072258687;1980.3266889257577;2008.8694234874686;128614.99999999907;131398.02902956464;134154;137262;138843;14002.861230329041;16976.660466790596;21002.684959725615;22036.669449958263;22082;34790.82604586961;36421.635783642356;37854.05364865845;37961.430578541345;38834.611653883665;8003.559804210708;9637.855432168522;9829.869564999974;9900.60397584103;10080.596776128872;7824.256695613907;7849;7984.6806127754235;8019.318357939641;8030.036395632562;10464.424456654804;10765.30025548337;10808;10916.563337466412;10923.453827308604;3706.7405281630026;4282.186384586918;4314.583833053437;4377.5403582624;4382.298832186835;810.9240344717542;818.4843548564367;836.8033512124646;904.7421484876778;927.5918595817953;19796.4061078165;20518;20543.79456205449;21272.255471058437;21311.147554097974;916.2166347772692;930.6835675870101;938.2775263047415;942.1944237676825;952.1573407534382;10263.640772572924;11025.00774930244;11078.944605276922;11090.500927458284;11094.944525277322;626439;749929.0014199686;757267;760873;775786;117076;139956.30021849825;154768;155353.9001855814;155448.44551554567;1187.619961612293;1204.265398107154;1269.2004037456343;1274.5602767045355;1277.9105462617713;19190.904045479685;19623;19955.501112472306;19975.500612484804;19984.500387489847;62689.68655156695;63609;63642.3635763655;63708.044379335275;63954.36045639484;69125.65437172783;85795.57102214449;91883;92229.53885230531;92934;11013.614523491638;11368.488418021374;11557.24877882935;11770.470328835394;11789.705257368634;10501.63244286477;12317.630471085882;12416.317102559446;12416.441260143134;12864.2924639142;10741.355518668905;10811.729706757395;10893.945530272615;11052.447377631248;11141.55159829217;10122.342047766871;10125.797484050425;10137.442440665835;10401.115905148146;10531.842022369823;10968.945155274172;11030;11140.554377825121;11257.761646219073;11432.314061156358;11004.449777510932;11061.059809916247;11094.445277736242;11147.83278250843;11420.942895285803;8118.731093774943;9532.380395274286;9934.602615895283;10798.73003174927;11056 14:31:30 INFO - PROCESS | 2407 | |3;mootools.html;1465.9413623455061;1521.490300749249;1541.0214513783746;1549.2021608871426;1561.1881821452841;10530.842037369435;11077.723056923582;11093.83359249612;11111.499982500787;11114.11087113031;9009.099090090987;9328.113829186233;9440.291978101654;9460.337776355658;9474.715758527238;2288.187693368859;2317;2327.3948773318975;2327.406511339606;2327.906883724649;2143.2712877621607;2270.2735124760084;2311.456807650201;2412.9879350603223;2415.45652228249;2338.1232037985756;2385.85684858909;2390.605550084234;2404.495056038234;2409.590369637157;2340;2360.1503458754833;2378.8810559472045;2386.081358676922;2392.8803559822027;2346.9882650586637;2378.821588380861;2379.238643634038;2384;2387.8925448354867;10699.518521666465;10968.287061340987;10968.835467467994;10987.505562249718;10997.725056873562;5486.643368181095;5651.943480565183;5654.519365853907;5670.234518340054;5671.63134396267;3381.1209085637665;3499.860005599773;3527.94708079379;3566.4115420955513;3589.210373717804;1120.9831852522218;1123.6292023632182;1157.9942100289445;1163.5869266410386;1165.2114005002281;13648.454061837514;14091.436342546285;14540.636484087876;15106.924465377602;15159.393624255017;4542.795574199169;4662.254039353755;4664.953350466452;4672.57946784791;4677.18149323867;11540.653780386601;11561.190716649922;11568.710782230342;11578.652640420632;11593;4667.346571479996;4823.927641085386;5318.22885681574;5537.833864984057;5540.252065971043;5039.949600504021;5101.0818052750865;5121.615878809104;5124.30821839047;5129.948700513021;3109.4247564200696;3720.8697695580527;3787.5833658298116;3848.191879705237;3848.9037774055873;980.1178938954997;981.7545613596601;996.4768496539375;998.1366118307611;1008.6318493749728;918.3433844801;920.1672486399818;930.3534043839467;940.7365937537501;989.5942663508071;6302.684865756693;6336.966633666858;6509.772157974448;6522.64125473094;6574.9671251644395;961.927855410847;1016.8474728790741;1021.1524434719165;1040.9167266618804;1042.7653777900064;4191.811368488426;4194.140201258735;4255.255330317182;4317.373980772771;4348.934765978513;13637.590872273848;14274.214918179392;14289.785653215211;14296.999210055405;14337.283135843167;12710.491580336868;14188.574342769733;14197.077189982618;14249;14256.35846386915;8047.637856296481;9146.359754817233;9245.445273283625;9432.905670943202;9453.858192127123;6513.1532900722605;6572.14562106923;6700.827355212916;6757.054012438164;6920.446364290744;12264.509419623115;12650.810237846625;12657.556985505464;12712.555060572835;12734.74530509366;11389.943050285026;11748.765024699629;11748.941255293668;12906.419211135333;12999.935000324938;8012.879806802786;9369.016253293306;9441.244700423813;9442.858357124785;9457.479838608942;10209.489525523843;10289.897101029193;10415.635452759117;10430.217733670157;10434.947825260826;8667.219950204511;8669.089745576624;8742.91257087421;8812.779680508038;8863.069377715246;8256.628451719567;8473.66105355779;8578.871316930126;8617.82764344697;8922.509261990654;7760.767176984699;7909.485883417559;7936.84126317482;7984.560849153352;7989.3209077229085;6010.278766548042;6048.213732214784;6111.938880611315;6117.143599896107;6255.155554000334;4753.453352864465;5577.525910297508;5708.430181700066;6128.754849806136;6138.907916381348 14:31:30 INFO - PROCESS | 2407 | |4;prototype.html;358.0850925884366;367.79954924566107;373.2777076357247;376.2869362557951;376.6045652065331;4207.831686732531;4402.889927751808;4409.4488188976375;5192.8442146735615;5280.366356037281;4379.167958087961;4410.867673969778;5075.046107248718;5088.872778180547;5094.809232030226;3120.0639808057604;3926.293267211901;4056.533498647649;4139.751614903101;4197.244495990721;3707.9814600926957;4219.767912764797;4249.787510624472;4272.487301523822;4318.438602981623;3931.2727145478034;4079.2657321682086;4202.655150351889;4211.305134652778;4269.957300426987;4121.649659778923;4171.395147703566;4189.790510474494;4192.937105943429;4194.266003449399;3252.308799424286;3545.7872527648424;3749.887503374903;3864.937142285866;3916.960830391688;3712.0619889768905;4748.691335063244;5063.8480845574695;5066.212429254356;5067.214581739836;4835.709857408567;4845.442774080991;4847.951520484785;4863.2461968394955;4873.610111191097;5032.924506132411;5257.973710131463;5428.267183930158;5463.350275116565;5497.47773961477;3872.186840763443;4022.3162062449455;4268.295731204347;4303.0421158372865;4330.665387651522;4562.536230435561;4618.205985378864;4971.332680743954;4979.617936216143;5744.044498369746;3795.411711184771;3959.6832253419657;4190.643795277375;4431.8448854289945;4458.436399070482;3886.9611303887164;3992.8003599819895;4460.241758900995;4509.774511274423;4520.52534483881;3787.223619158066;4014.41790940312;4597.977010114928;4738.952610473852;4802.6638135330195;4459.6655250855665;4626.89476288288;5113.522804492906;5386.48828361305;5479.5342395896;1409.6264489910182;1672.7955871772303;1690.1549225387307;1706.6245426006258;1707.5048236011667;1177.6574704836494;1224.908131890112;1243.0676992255808;1263.4946021591436;1365.5388734054666;4679.742614156254;4699.342092107107;4728.621710263171;4788.592969597623;5073.492650734897;4893.437254715682;5165.302684137593;5341.839744807662;5515.062439385314;5588.91616625751;4590.816367345269;4656.604188643935;4703.576678098988;5097.770600322994;5122.897542049138;2467.543504451682;2802.9867892015754;2908.840013799261;2913.533834586456;2936.5741967415033;5004.196810424104;5461.344638643388;5557.972210138923;5840.88318233633;5853.560982926298;4991.251312303111;5494.862628434321;5529.962855563497;5669.922714684197;5689.715514224272;5265.710385928811;5735.913961290584;5896;5896.7936122235515;5934.6142500737315;4964.180910149785;5196.454372290855;5248.879629490832;5511.732301570581;5615.32616086056;4778.76106194682;4802.703270116979;4901.113071467439;5310.282802038666;5477.835664930059;5398.1902714593125;5577.581681373832;5602.607817452902;5656.236408084856;5825.8927456144165;3779.603141670195;3922.490076290064;4140.730852494578;4262.829486820617;4351.933776224845;4801.759912004456;5587.329520457572;5618.88762224745;5621.482199805948;5658.1426379678;4717.905641887074;4951.554360107608;5368.865778355416;5409.675419474844;5435.646682965595;4309.762963037063;4727.125481785881;5355.42015105537;5366.865828354323;5374;4474.507804141476;5008.799648014038;5086.821961231339;5273.920891186712;5290.640443318443;4847.515248475265;5103.7686371469545;5297.0400951648135;5307.946920530745;5406.648567843077;4297.312430011183;4376.846810361622;5102.413222479462;5157.974210128926;5181.974090129676 14:31:30 INFO - PROCESS | 2407 | |5;yui.html;703.2510376449081;716.0798374089289;731.765834932821;820.5035953248284;822.338017895594;13070.60788176355;14987;15627.687446251068;15745.976511526742;15850.207489625516;14187;15903;16696.49910502687;16930.661386772255;17204.483865484057;919.2232563483863;943.9669611563596;948.2461443152711;949.4493193947528;949.5726922884695;910.3672947301628;932.4591736792678;934;934.2666007184362;935.289180223032;991.7718924647336;995.2833959549113;995.3679413572397;995.7062666513395;996.4021587047786;989.0850962859398;989.6338354808695;990.3315262197988;991.6083147156869;991.7024892532247;734.988975165373;735.5366119344832;735.6285076036621;735.9668814903289;737.5353527277781;15524.456644017519;15815.60460988473;15950.441734539352;16239.83760162395;16342.182890855529;13909.721805563933;14162.858371416258;14193;14287;14488.420463181461;11578.768424631544;11598.130140239517;11807.40962951849;11895.702607434796;11895.702607434796;3594.0475773919934;3599.838007289652;3667.413213885792;3827.6937844972335;3855.267499175148;101320;101929.98070019278;104738.47630761798;105556.4722176392;108217.45891270573;10299.632939674457;10585.882823435344;10651.786964260826;10768.300060496043;11160.888391116147;22452.663210051862;22469.101235950708;22478;22504.774952250595;22528.549429011327;7196;7406.925930740731;7514.924850751532;7579.279968402993;7732.188120247405;4196.475440569929;4208.726432781859;4287.678424118204;4302.65578753699;4377.971176773456;3466.9133271667906;3549.9290014200087;3567.678908898212;3569.5716514017963;3576.8390422431053;512.9094377161879;624.7063879976404;630.5712115761327;631.2235949781794;631.3434028610286;672.5661948043485;682.5532252624088;688.6997428588538;701.7964790210782;734.7354952217197;9724.465154416575;10266.6406675766;10667.341500811302;11061.778764424667;11267.718307042389;574.9367569567344;605.279717136606;610.7984365159575;615.2217444932164;727.5561907236581;4538.9546104539195;4915.926261106087;4922.384701912261;4934.481879402683;4939.506049395031;93311.5334423337;93401.59897601542;94266;94917.5254123725;96237;55285.72357138189;69633.95549066768;72366;75550.48899022098;78378;47212.76393618079;52174.47825521772;73128;74478.99999999892;76650;10645.201609879445;10765.084967777513;10816.351018938889;11201.551937922392;11208.327500349678;48074.03851923012;48566.757166213945;48703.51296487131;49045;51413.7429312851;61398.386016139266;61569.94287243225;61883.690581546805;62411.37588624056;63018;12197.634070977885;12271.509139634672;12360.938195308965;12841.743165136832;12940.223586584834;14223.928880355532;14237.857621424067;14609.196494192922;14640.560783176521;14980.925095374454;12726.68183295425;12819.743605128031;13763.419483101392;13799.724005519633;14408.351624176728;12936.870631293566;13356.265405402795;13369.732605347644;13798.93100534491;14135.15189088658;12464.189827661174;13019.82856347334;13194.736105278032;13284.667883302995;13514.39185236647;8114.99907693405;8449.915500844912;8478.788030299293;8611.727543533667;9056.547172641473;9301;10836.512356943798;10940.945295273472;10986.780264394507;11110.722231944266 14:31:30 INFO - PROCESS | 2407 | __end_tp_report 14:31:30 INFO - PROCESS | 2407 | __start_cc_report 14:31:30 INFO - PROCESS | 2407 | _x_x_mozilla_cycle_collect,2018 14:31:30 INFO - PROCESS | 2407 | __end_cc_report 14:31:30 INFO - PROCESS | 2407 | __startTimestamp1492205490234__endTimestamp 14:31:30 INFO - PROCESS | 2407 | 14:31:30 INFO - PROCESS | 2407 | ------- Summary: start ------- 14:31:30 INFO - PROCESS | 2407 | Number of tests: 6 14:31:30 INFO - PROCESS | 2407 | 14:31:30 INFO - PROCESS | 2407 | [#0] dojo.html Cycles:165 Average:5940.34 Median:6237.19 stddev:664.23 (10.6%) stddev-sans-first:633.45 14:31:30 INFO - PROCESS | 2407 | Values: 3311.9 3369.5 3387.8 3418.0 3438.2 5955.5 5966.9 5980.4 5991.8 6024.6 4978.8 5983.6 6048.3 6063.5 6077.1 5366.5 5370.8 5414.6 5474.6 5486.4 5220.0 5297.3 5325.8 5511.9 5561.5 5368.1 5448.6 5466.4 5466.5 5521.5 5448.9 5463.8 5464.9 5470.5 5470.7 5311.7 5401.9 5405.5 5422.8 5485.1 5748.9 5755.8 5821.4 5823.6 6035.4 6349.9 6357.4 6373.0 6391.6 6413.3 6411.2 6482.9 6498.1 6505.7 6506.1 5949.1 6002.4 6020.2 6161.8 6181.1 6451.2 6463.5 6465.5 6504.5 6551.4 6280.9 6281.2 6296.0 6299.1 6314.7 6386.0 6439.0 6451.7 6455.7 6458.1 6216.3 6262.2 6353.4 6359.4 6367.0 5844.4 6252.2 6342.0 6358.0 6385.2 5166.5 5205.3 5209.2 5212.1 5219.3 4595.0 4615.8 4617.2 4629.6 4652.9 5001.5 5834.1 5910.3 5914.4 5926.1 5266.3 6329.4 6385.0 6424.6 6429.2 6034.1 6057.3 6065.5 6066.2 6091.5 5625.4 5634.2 5674.8 5684.7 5698.9 6358.3 6395.9 6555.3 6620.1 6636.2 6443.6 6444.5 6456.4 6475.0 6475.6 6620.3 6654.7 6671.2 6678.8 6694.8 6235.1 6316.8 6365.6 6406.8 6441.3 6274.9 6299.7 6419.6 6432.7 6437.0 6217.8 6240.0 6302.2 6363.5 6364.7 6139.2 6345.9 6347.5 6381.6 6401.1 5950.5 6278.9 6281.6 6281.9 6288.2 6245.4 6247.0 6261.6 6347.8 6369.1 6237.2 6241.6 6244.0 6253.2 6268.7 14:31:30 INFO - PROCESS | 2407 | 14:31:30 INFO - PROCESS | 2407 | [#1] ext.html Cycles:180 Average:54247.74 Median:31537.79 stddev:113307.07 (359.3%) stddev-sans-first:113553.67 14:31:30 INFO - PROCESS | 2407 | Values: 730.5 788.8 809.3 876.9 889.5 27445.5 39165.8 39618.0 39694.6 39738.4 39585.0 39708.2 39749.4 39801.2 39855.6 7328.2 9157.7 9341.0 9345.8 9347.2 7347.5 7384.8 7419.3 7427.1 7428.3 10992.0 11228.6 11322.3 11341.7 11399.9 11085.1 11138.6 11140.9 11171.9 11175.5 7144.9 10919.9 10971.6 10981.0 10993.5 27632.2 38058.4 38394.0 38411.8 38422.8 3668.8 3850.9 3852.5 3889.9 3945.0 24213.2 24232.0 24308.8 24819.9 25099.9 2803.5 3016.1 3110.6 3117.2 3118.2 350348.0 351664.0 351814.0 352113.0 357241.0 43018.9 43137.7 43253.6 43300.0 43369.3 45177.5 45225.0 45264.3 45298.6 45327.0 11599.7 12464.7 12576.9 12592.8 12599.6 8783.6 10305.4 10325.5 10328.6 10337.3 9010.3 10117.7 10315.6 10487.7 11698.4 5488.4 5621.6 5680.3 5694.9 5704.2 961.9 1010.7 1139.9 1165.1 1188.0 25833.9 26148.6 26549.9 26612.0 26670.5 1011.4 1158.1 1158.4 1176.6 1205.2 12990.4 13365.4 13619.3 13690.2 13692.0 482163.0 484391.0 485671.0 486105.0 486429.0 52321.0 55271.7 55603.7 55609.6 55761.2 1430.3 1457.3 1459.5 1459.7 1464.8 19300.5 24390.4 24486.4 25404.6 25758.0 40635.4 40648.0 40696.8 40707.2 40793.6 427312.0 428532.0 429283.0 429891.0 430481.0 29573.9 31692.2 31860.5 31889.4 31956.9 36807.8 37593.2 38088.8 38231.4 38278.2 19930.5 24121.8 25323.9 27114.0 27176.3 27393.9 27532.6 27619.0 27706.0 27915.4 31280.8 31383.4 31733.4 31757.7 32570.3 27339.2 27363.7 27412.7 27966.3 28415.0 17983.8 20758.9 22315.9 22499.0 22500.0 14:31:30 INFO - PROCESS | 2407 | 14:31:30 INFO - PROCESS | 2407 | [#2] jquery.html Cycles:180 Average:41876.03 Median:21679.96 stddev:122278.37 (564.0%) stddev-sans-first:122581.69 14:31:30 INFO - PROCESS | 2407 | Values: 381.4 388.0 407.8 416.5 422.9 13063.7 16723.8 20851.2 21076.4 21323.3 19566.4 20444.0 20497.7 20570.6 20883.9 6364.9 6654.2 6655.1 6657.0 6662.4 5415.0 7514.6 7738.0 7740.3 7742.2 7429.4 7604.8 7615.4 7717.0 7744.2 7541.4 7551.5 7597.1 7618.1 7647.0 7310.3 7526.1 7540.7 7540.9 7551.1 26967.7 27376.9 27730.9 28025.3 28167.7 2930.5 2939.3 2944.9 2945.7 3001.7 15390.6 16266.8 16369.9 16625.4 16766.7 1910.0 1967.1 1979.4 1980.3 2008.9 128615.0 131398.0 134154.0 137262.0 138843.0 14002.9 16976.7 21002.7 22036.7 22082.0 34790.8 36421.6 37854.1 37961.4 38834.6 8003.6 9637.9 9829.9 9900.6 10080.6 7824.3 7849.0 7984.7 8019.3 8030.0 10464.4 10765.3 10808.0 10916.6 10923.5 3706.7 4282.2 4314.6 4377.5 4382.3 810.9 818.5 836.8 904.7 927.6 19796.4 20518.0 20543.8 21272.3 21311.1 916.2 930.7 938.3 942.2 952.2 10263.6 11025.0 11078.9 11090.5 11094.9 626439.0 749929.0 757267.0 760873.0 775786.0 117076.0 139956.3 154768.0 155353.9 155448.4 1187.6 1204.3 1269.2 1274.6 1277.9 19190.9 19623.0 19955.5 19975.5 19984.5 62689.7 63609.0 63642.4 63708.0 63954.4 69125.7 85795.6 91883.0 92229.5 92934.0 11013.6 11368.5 11557.2 11770.5 11789.7 10501.6 12317.6 12416.3 12416.4 12864.3 10741.4 10811.7 10893.9 11052.4 11141.6 10122.3 10125.8 10137.4 10401.1 10531.8 10968.9 11030.0 11140.6 11257.8 11432.3 11004.4 11061.1 11094.4 11147.8 11420.9 8118.7 9532.4 9934.6 10798.7 11056.0 14:31:30 INFO - PROCESS | 2407 | 14:31:30 INFO - PROCESS | 2407 | [#3] mootools.html Cycles:180 Average:6618.60 Median:3655.04 stddev:4175.23 (114.2%) stddev-sans-first:4168.99 14:31:30 INFO - PROCESS | 2407 | Values: 1465.9 1521.5 1541.0 1549.2 1561.2 10530.8 11077.7 11093.8 11111.5 11114.1 9009.1 9328.1 9440.3 9460.3 9474.7 2288.2 2317.0 2327.4 2327.4 2327.9 2143.3 2270.3 2311.5 2413.0 2415.5 2338.1 2385.9 2390.6 2404.5 2409.6 2340.0 2360.2 2378.9 2386.1 2392.9 2347.0 2378.8 2379.2 2384.0 2387.9 10699.5 10968.3 10968.8 10987.5 10997.7 5486.6 5651.9 5654.5 5670.2 5671.6 3381.1 3499.9 3527.9 3566.4 3589.2 1121.0 1123.6 1158.0 1163.6 1165.2 13648.5 14091.4 14540.6 15106.9 15159.4 4542.8 4662.3 4665.0 4672.6 4677.2 11540.7 11561.2 11568.7 11578.7 11593.0 4667.3 4823.9 5318.2 5537.8 5540.3 5039.9 5101.1 5121.6 5124.3 5129.9 3109.4 3720.9 3787.6 3848.2 3848.9 980.1 981.8 996.5 998.1 1008.6 918.3 920.2 930.4 940.7 989.6 6302.7 6337.0 6509.8 6522.6 6575.0 961.9 1016.8 1021.2 1040.9 1042.8 4191.8 4194.1 4255.3 4317.4 4348.9 13637.6 14274.2 14289.8 14297.0 14337.3 12710.5 14188.6 14197.1 14249.0 14256.4 8047.6 9146.4 9245.4 9432.9 9453.9 6513.2 6572.1 6700.8 6757.1 6920.4 12264.5 12650.8 12657.6 12712.6 12734.7 11389.9 11748.8 11748.9 12906.4 12999.9 8012.9 9369.0 9441.2 9442.9 9457.5 10209.5 10289.9 10415.6 10430.2 10434.9 8667.2 8669.1 8742.9 8812.8 8863.1 8256.6 8473.7 8578.9 8617.8 8922.5 7760.8 7909.5 7936.8 7984.6 7989.3 6010.3 6048.2 6111.9 6117.1 6255.2 4753.5 5577.5 5708.4 6128.8 6138.9 14:31:30 INFO - PROCESS | 2407 | 14:31:30 INFO - PROCESS | 2407 | [#4] prototype.html Cycles:180 Average:4443.28 Median:4743.82 stddev:1227.25 (25.9%) stddev-sans-first:1191.77 14:31:30 INFO - PROCESS | 2407 | Values: 358.1 367.8 373.3 376.3 376.6 4207.8 4402.9 4409.4 5192.8 5280.4 4379.2 4410.9 5075.0 5088.9 5094.8 3120.1 3926.3 4056.5 4139.8 4197.2 3708.0 4219.8 4249.8 4272.5 4318.4 3931.3 4079.3 4202.7 4211.3 4270.0 4121.6 4171.4 4189.8 4192.9 4194.3 3252.3 3545.8 3749.9 3864.9 3917.0 3712.1 4748.7 5063.8 5066.2 5067.2 4835.7 4845.4 4848.0 4863.2 4873.6 5032.9 5258.0 5428.3 5463.4 5497.5 3872.2 4022.3 4268.3 4303.0 4330.7 4562.5 4618.2 4971.3 4979.6 5744.0 3795.4 3959.7 4190.6 4431.8 4458.4 3887.0 3992.8 4460.2 4509.8 4520.5 3787.2 4014.4 4598.0 4739.0 4802.7 4459.7 4626.9 5113.5 5386.5 5479.5 1409.6 1672.8 1690.2 1706.6 1707.5 1177.7 1224.9 1243.1 1263.5 1365.5 4679.7 4699.3 4728.6 4788.6 5073.5 4893.4 5165.3 5341.8 5515.1 5588.9 4590.8 4656.6 4703.6 5097.8 5122.9 2467.5 2803.0 2908.8 2913.5 2936.6 5004.2 5461.3 5558.0 5840.9 5853.6 4991.3 5494.9 5530.0 5669.9 5689.7 5265.7 5735.9 5896.0 5896.8 5934.6 4964.2 5196.5 5248.9 5511.7 5615.3 4778.8 4802.7 4901.1 5310.3 5477.8 5398.2 5577.6 5602.6 5656.2 5825.9 3779.6 3922.5 4140.7 4262.8 4351.9 4801.8 5587.3 5618.9 5621.5 5658.1 4717.9 4951.6 5368.9 5409.7 5435.6 4309.8 4727.1 5355.4 5366.9 5374.0 4474.5 5008.8 5086.8 5273.9 5290.6 4847.5 5103.8 5297.0 5307.9 5406.6 4297.3 4376.8 5102.4 5158.0 5182.0 14:31:30 INFO - PROCESS | 2407 | 14:31:30 INFO - PROCESS | 2407 | [#5] yui.html Cycles:180 Average:19176.51 Median:4295.17 stddev:26609.19 (619.5%) stddev-sans-first:26647.68 14:31:30 INFO - PROCESS | 2407 | Values: 703.3 716.1 731.8 820.5 822.3 13070.6 14987.0 15627.7 15746.0 15850.2 14187.0 15903.0 16696.5 16930.7 17204.5 919.2 944.0 948.2 949.4 949.6 910.4 932.5 934.0 934.3 935.3 991.8 995.3 995.4 995.7 996.4 989.1 989.6 990.3 991.6 991.7 735.0 735.5 735.6 736.0 737.5 15524.5 15815.6 15950.4 16239.8 16342.2 13909.7 14162.9 14193.0 14287.0 14488.4 11578.8 11598.1 11807.4 11895.7 11895.7 3594.0 3599.8 3667.4 3827.7 3855.3 101320.0 101930.0 104738.5 105556.5 108217.5 10299.6 10585.9 10651.8 10768.3 11160.9 22452.7 22469.1 22478.0 22504.8 22528.5 7196.0 7406.9 7514.9 7579.3 7732.2 4196.5 4208.7 4287.7 4302.7 4378.0 3466.9 3549.9 3567.7 3569.6 3576.8 512.9 624.7 630.6 631.2 631.3 672.6 682.6 688.7 701.8 734.7 9724.5 10266.6 10667.3 11061.8 11267.7 574.9 605.3 610.8 615.2 727.6 4539.0 4915.9 4922.4 4934.5 4939.5 93311.5 93401.6 94266.0 94917.5 96237.0 55285.7 69634.0 72366.0 75550.5 78378.0 47212.8 52174.5 73128.0 74479.0 76650.0 10645.2 10765.1 10816.4 11201.6 11208.3 48074.0 48566.8 48703.5 49045.0 51413.7 61398.4 61569.9 61883.7 62411.4 63018.0 12197.6 12271.5 12360.9 12841.7 12940.2 14223.9 14237.9 14609.2 14640.6 14980.9 12726.7 12819.7 13763.4 13799.7 14408.4 12936.9 13356.3 13369.7 13798.9 14135.2 12464.2 13019.8 13194.7 13284.7 13514.4 8115.0 8449.9 8478.8 8611.7 9056.5 9301.0 10836.5 10940.9 10986.8 11110.7 14:31:30 INFO - PROCESS | 2407 | -------- Summary: end -------- 14:31:30 INFO - PROCESS | 2407 | 14:31:30 INFO - TEST-INFO | 2407: exit 0 14:31:30 INFO - TEST-OK | dromaeo_css | took 1120817ms 14:31:30 INFO - TEST-START | kraken 14:31:30 INFO - operating with platform_type : mac_ 14:31:30 INFO - Initialising browser for kraken test... 14:31:30 INFO - TEST-INFO | started process 2411 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpYPufpu/profile http://localhost:58017/getInfo.html) 14:31:33 INFO - PROCESS | 2411 | __metrics Screen width/height:1600/1200 14:31:33 INFO - PROCESS | 2411 | colorDepth:24 14:31:33 INFO - PROCESS | 2411 | Browser inner width/height: 1024/689 14:31:33 INFO - PROCESS | 2411 | __metrics 14:31:33 INFO - PROCESS | 2411 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:31:33 INFO - PROCESS | 2411 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:31:34 INFO - TEST-INFO | 2411: exit 0 14:31:34 INFO - Browser initialized. 14:31:34 INFO - Running cycle 1/1 for kraken test... 14:31:34 INFO - TEST-INFO | started process 2412 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpYPufpu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:31:46 INFO - PROCESS | 2412 | RSS: Main: 215027712 14:31:46 INFO - PROCESS | 2412 | 14:31:49 INFO - PROCESS | 2412 | Cycle 1(1): loaded http://localhost:58017/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58017/tests/kraken/driver.html?testName=audio-beat-detection) 14:31:50 INFO - PROCESS | 2412 | RSS: Main: 267558912 14:31:50 INFO - PROCESS | 2412 | 14:31:52 INFO - PROCESS | 2412 | Cycle 1(1): loaded http://localhost:58017/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58017/tests/kraken/driver.html?testName=audio-dft) 14:31:53 INFO - PROCESS | 2412 | RSS: Main: 278523904 14:31:53 INFO - PROCESS | 2412 | 14:31:56 INFO - PROCESS | 2412 | Cycle 1(1): loaded http://localhost:58017/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58017/tests/kraken/driver.html?testName=audio-fft) 14:31:57 INFO - PROCESS | 2412 | RSS: Main: 375848960 14:31:57 INFO - PROCESS | 2412 | 14:31:59 INFO - PROCESS | 2412 | Cycle 1(1): loaded http://localhost:58017/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58017/tests/kraken/driver.html?testName=audio-oscillator) 14:31:59 INFO - PROCESS | 2412 | RSS: Main: 277815296 14:31:59 INFO - PROCESS | 2412 | 14:32:01 INFO - PROCESS | 2412 | Cycle 1(1): loaded http://localhost:58017/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58017/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:32:02 INFO - PROCESS | 2412 | RSS: Main: 279080960 14:32:02 INFO - PROCESS | 2412 | 14:32:05 INFO - PROCESS | 2412 | Cycle 1(1): loaded http://localhost:58017/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58017/tests/kraken/driver.html?testName=imaging-darkroom) 14:32:05 INFO - PROCESS | 2412 | RSS: Main: 319062016 14:32:05 INFO - PROCESS | 2412 | 14:32:09 INFO - PROCESS | 2412 | Cycle 1(1): loaded http://localhost:58017/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58017/tests/kraken/driver.html?testName=imaging-desaturate) 14:32:09 INFO - PROCESS | 2412 | RSS: Main: 320303104 14:32:09 INFO - PROCESS | 2412 | 14:32:12 INFO - PROCESS | 2412 | Cycle 1(1): loaded http://localhost:58017/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58017/tests/kraken/driver.html?testName=json-parse-financial) 14:32:13 INFO - PROCESS | 2412 | RSS: Main: 317640704 14:32:13 INFO - PROCESS | 2412 | 14:32:14 INFO - PROCESS | 2412 | Cycle 1(1): loaded http://localhost:58017/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58017/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:32:15 INFO - PROCESS | 2412 | RSS: Main: 295874560 14:32:15 INFO - PROCESS | 2412 | 14:32:17 INFO - PROCESS | 2412 | Cycle 1(1): loaded http://localhost:58017/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58017/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:32:17 INFO - PROCESS | 2412 | RSS: Main: 298893312 14:32:17 INFO - PROCESS | 2412 | 14:32:20 INFO - PROCESS | 2412 | Cycle 1(1): loaded http://localhost:58017/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58017/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:32:20 INFO - PROCESS | 2412 | RSS: Main: 307814400 14:32:20 INFO - PROCESS | 2412 | 14:32:23 INFO - PROCESS | 2412 | Cycle 1(1): loaded http://localhost:58017/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58017/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:32:24 INFO - PROCESS | 2412 | RSS: Main: 323112960 14:32:24 INFO - PROCESS | 2412 | 14:32:27 INFO - PROCESS | 2412 | Cycle 1(1): loaded http://localhost:58017/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58017/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:32:28 INFO - PROCESS | 2412 | RSS: Main: 315899904 14:32:28 INFO - PROCESS | 2412 | 14:32:29 INFO - PROCESS | 2412 | Cycle 1(1): loaded http://localhost:58017/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58017/tests/kraken/driver.html?testName=ai-astar) 14:32:30 INFO - PROCESS | 2412 | RSS: Main: 359927808 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | __start_tp_report 14:32:30 INFO - PROCESS | 2412 | _x_x_mozilla_page_load 14:32:30 INFO - PROCESS | 2412 | _x_x_mozilla_page_load_details 14:32:30 INFO - PROCESS | 2412 | |i|pagename|runs| 14:32:30 INFO - PROCESS | 2412 | |0;ai-astar;119;172;174;118;117;117;118;116;117;117 14:32:30 INFO - PROCESS | 2412 | |1;audio-beat-detection;145;113;111;146;109;111;110;106;128;102 14:32:30 INFO - PROCESS | 2412 | |2;audio-dft;184;185;183;187;184;186;183;186;186;184 14:32:30 INFO - PROCESS | 2412 | |3;audio-fft;85;62;61;92;64;61;59;61;76;59 14:32:30 INFO - PROCESS | 2412 | |4;audio-oscillator;90;68;86;70;69;68;81;68;68;82 14:32:30 INFO - PROCESS | 2412 | |5;imaging-gaussian-blur;89;88;89;89;89;89;89;89;90;91 14:32:30 INFO - PROCESS | 2412 | |6;imaging-darkroom;96;96;119;129;149;139;97;96;97;96 14:32:30 INFO - PROCESS | 2412 | |7;imaging-desaturate;84;86;85;88;85;86;85;83;83;85 14:32:30 INFO - PROCESS | 2412 | |8;json-parse-financial;63;63;61;62;64;63;64;66;64;62 14:32:30 INFO - PROCESS | 2412 | |9;json-stringify-tinderbox;57;65;62;64;60;56;57;58;59;59 14:32:30 INFO - PROCESS | 2412 | |10;stanford-crypto-aes;87;97;98;85;87;85;86;86;85;91 14:32:30 INFO - PROCESS | 2412 | |11;stanford-crypto-ccm;135;136;152;146;153;143;144;149;178;157 14:32:30 INFO - PROCESS | 2412 | |12;stanford-crypto-pbkdf2;219;167;171;164;167;169;166;166;166;164 14:32:30 INFO - PROCESS | 2412 | |13;stanford-crypto-sha256-iterative;65;66;65;65;64;73;83;74;63;64 14:32:30 INFO - PROCESS | 2412 | __end_tp_report 14:32:30 INFO - PROCESS | 2412 | __start_cc_report 14:32:30 INFO - PROCESS | 2412 | _x_x_mozilla_cycle_collect,2162 14:32:30 INFO - PROCESS | 2412 | __end_cc_report 14:32:30 INFO - PROCESS | 2412 | __startTimestamp1492205550170__endTimestamp 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | ------- Summary: start ------- 14:32:30 INFO - PROCESS | 2412 | Number of tests: 14 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | [#0] ai-astar Cycles:10 Average:128.50 Median:118.00 stddev:23.47 (19.9%) stddev-sans-first:24.64 14:32:30 INFO - PROCESS | 2412 | Values: 119.0 172.0 174.0 118.0 117.0 117.0 118.0 116.0 117.0 117.0 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | [#1] audio-beat-detection Cycles:10 Average:118.10 Median:112.00 stddev:15.92 (14.2%) stddev-sans-first:13.59 14:32:30 INFO - PROCESS | 2412 | Values: 145.0 113.0 111.0 146.0 109.0 111.0 110.0 106.0 128.0 102.0 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.50 stddev:1.40 (0.8%) stddev-sans-first:1.45 14:32:30 INFO - PROCESS | 2412 | Values: 184.0 185.0 183.0 187.0 184.0 186.0 183.0 186.0 186.0 184.0 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | [#3] audio-fft Cycles:10 Average:68.00 Median:63.00 stddev:11.97 (19.0%) stddev-sans-first:11.01 14:32:30 INFO - PROCESS | 2412 | Values: 85.0 62.0 61.0 92.0 64.0 61.0 59.0 61.0 76.0 59.0 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | [#4] audio-oscillator Cycles:10 Average:75.00 Median:75.50 stddev:8.74 (11.6%) stddev-sans-first:7.40 14:32:30 INFO - PROCESS | 2412 | Values: 90.0 68.0 86.0 70.0 69.0 68.0 81.0 68.0 68.0 82.0 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:89.00 stddev:0.79 (0.9%) stddev-sans-first:0.83 14:32:30 INFO - PROCESS | 2412 | Values: 89.0 88.0 89.0 89.0 89.0 89.0 89.0 89.0 90.0 91.0 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | [#6] imaging-darkroom Cycles:10 Average:111.40 Median:96.00 stddev:20.83 (21.7%) stddev-sans-first:21.34 14:32:30 INFO - PROCESS | 2412 | Values: 96.0 96.0 119.0 129.0 149.0 139.0 97.0 96.0 97.0 96.0 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:1.49 (1.8%) stddev-sans-first:1.54 14:32:30 INFO - PROCESS | 2412 | Values: 84.0 86.0 85.0 88.0 85.0 86.0 85.0 83.0 83.0 85.0 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:63.50 stddev:1.40 (2.2%) stddev-sans-first:1.48 14:32:30 INFO - PROCESS | 2412 | Values: 63.0 63.0 61.0 62.0 64.0 63.0 64.0 66.0 64.0 62.0 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:59.50 stddev:3.06 (5.1%) stddev-sans-first:3.08 14:32:30 INFO - PROCESS | 2412 | Values: 57.0 65.0 62.0 64.0 60.0 56.0 57.0 58.0 59.0 59.0 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:87.00 stddev:4.97 (5.7%) stddev-sans-first:5.23 14:32:30 INFO - PROCESS | 2412 | Values: 87.0 97.0 98.0 85.0 87.0 85.0 86.0 86.0 85.0 91.0 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:150.50 stddev:12.31 (8.2%) stddev-sans-first:11.92 14:32:30 INFO - PROCESS | 2412 | Values: 135.0 136.0 152.0 146.0 153.0 143.0 144.0 149.0 178.0 157.0 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:167.00 stddev:16.68 (10.0%) stddev-sans-first:2.24 14:32:30 INFO - PROCESS | 2412 | Values: 219.0 167.0 171.0 164.0 167.0 169.0 166.0 166.0 166.0 164.0 14:32:30 INFO - PROCESS | 2412 | 14:32:30 INFO - PROCESS | 2412 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:65.50 stddev:6.44 (9.8%) stddev-sans-first:6.73 14:32:30 INFO - PROCESS | 2412 | Values: 65.0 66.0 65.0 65.0 64.0 73.0 83.0 74.0 63.0 64.0 14:32:30 INFO - PROCESS | 2412 | -------- Summary: end -------- 14:32:30 INFO - PROCESS | 2412 | 14:32:31 INFO - TEST-INFO | 2412: exit 0 14:32:31 INFO - TEST-OK | kraken | took 60148ms 14:32:31 INFO - SUITE-END | took 1180s 14:32:31 INFO - Completed test suite (00:19:41) 14:32:31 INFO - javascript benchmark 14:32:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7531.201204941526, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3311.9172020699484, 3369.5451114099596, 3387.8306084695773, 3417.982910085449, 3438.17483803887, 5955.493783028448, 5966.9104963425525, 5980.431858973401, 5991.82024539264, 6024.608400453967, 4978.800847966077, 5983.640981541111, 6048.3044449888275, 6063.454289113979, 6077.1491991121275, 5366.490183432567, 5370.812021579245, 5414.620976531644, 5474.562035037207, 5486.396496385394, 5220.0, 5297.284866543026, 5325.7603407846545, 5511.917321240185, 5561.5272701820395, 5368.087425137736, 5448.618596698233, 5466.371367292772, 5466.453354664522, 5521.475459831329, 5448.945510544883, 5463.808766693186, 5464.890702185974, 5470.452954704538, 5470.671759694391, 5311.654742441768, 5401.864953376158, 5405.540529055035, 5422.755975981051, 5485.067538518461, 5748.885022299531, 5755.769769209227, 5821.359650438449, 5823.56323275756, 6035.426634469718, 6349.873002539969, 6357.396047375492, 6373.044043393482, 6391.58454700443, 6413.294537600907, 6411.166548348685, 6482.93517064828, 6498.122753428274, 6505.7397704092255, 6506.089147519351, 5949.137375080589, 6002.429769172009, 6020.217371741645, 6161.815145545641, 6181.072839074175, 6451.1936007999, 6463.4506066983895, 6465.515086368541, 6504.544681872224, 6551.4103730664465, 6280.937190628127, 6281.214848143982, 6296.024116261987, 6299.055141728777, 6314.652694101776, 6386.042093685985, 6439.00195469703, 6451.741930322734, 6455.677216139175, 6458.063580780855, 6216.285127210338, 6262.248530176317, 6353.396427339396, 6359.427651511386, 6366.9681651591445, 5844.444777746107, 6252.218472690914, 6342.016987366966, 6358.0, 6385.201849768779, 5166.509181627739, 5205.27126202332, 5209.166533354645, 5212.061828870764, 5219.321488206585, 4595.035042641016, 4615.769211539477, 4617.238155704271, 4629.583337499641, 4652.860414187579, 5001.549860512571, 5834.124881267844, 5910.34986151523, 5914.4381283777975, 5926.111083337448, 5266.315379000705, 6329.3987071229085, 6385.0, 6424.550281480345, 6429.228492580826, 6034.09488576717, 6057.3336932937345, 6065.5450841187085, 6066.241719785027, 6091.48222401092, 5625.4374562544235, 5634.154876768435, 5674.829755107354, 5684.715764211773, 5698.914516282259, 6358.268799088164, 6395.904061439175, 6555.344465553407, 6620.073189753342, 6636.170478690164, 6443.581167224115, 6444.452221561032, 6456.386643268882, 6475.028745688184, 6475.579087359307, 6620.271770105381, 6654.700538475683, 6671.166104236971, 6678.83302917431, 6694.765683201064, 6235.127082208585, 6316.778912738032, 6365.649889256136, 6406.839829004312, 6441.259255185713, 6274.874502510015, 6299.716512756845, 6419.582727122722, 6432.710528026344, 6437.0, 6217.813465596039, 6240.0, 6302.212223472066, 6363.459105975859, 6364.713587888464, 6139.201903752483, 6345.8730825384155, 6347.523935704933, 6381.553291269566, 6401.103845461732, 5950.494207992369, 6278.937210628018, 6281.623102613858, 6281.874362512815, 6288.151099601679, 6245.406686364788, 6247.00047992319, 6261.5616906816085, 6347.777827776005, 6369.108324834619, 6237.1891654084675, 6241.563090583797, 6244.0, 6253.155823963889, 6268.749250029948], "value": 5897.480512646488, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.4813582356527, 788.7633709887034, 809.2676128104064, 876.8728534362517, 889.4885440871501, 27445.451090978167, 39165.82502524925, 39618.0, 39694.603053969455, 39738.40392394113, 39585.0, 39708.205835883266, 39749.40375894364, 39801.20397592046, 39855.60144398555, 7328.230535793745, 9157.725268241948, 9340.953295233548, 9345.813083738321, 9347.158755711984, 7347.485676002682, 7384.778456646309, 7419.2951669591575, 7427.108746950346, 7428.257174282582, 10992.021436141491, 11228.550857965713, 11322.320660760339, 11341.659750207466, 11399.943000285028, 11085.113190944705, 11138.554457821678, 11140.888591114066, 11171.888281117166, 11175.497102630401, 7144.8928266076055, 10919.890801091968, 10971.615993440271, 10981.0, 10993.450327483593, 27632.171034868785, 38058.42912356317, 38394.0, 38411.80794096012, 38422.80788596067, 3668.816559172031, 3850.8652197173246, 3852.5184351956004, 3889.86385476507, 3944.960550394488, 24213.152539661027, 24231.971003624545, 24308.756912430825, 24819.875900620562, 25099.874500627564, 2803.4813559491354, 3016.1102474769996, 3110.6422761382505, 3117.1583672408324, 3118.236032172132, 350348.0, 351664.0, 351814.0, 352113.0, 357241.0, 43018.92452688708, 43137.705868823985, 43253.56746432495, 43300.0, 43369.34945975813, 45177.54822451799, 45225.0, 45264.32103518384, 45298.64104076883, 45327.0, 11599.724030356654, 12464.688382790502, 12576.937115314548, 12592.81110783339, 12599.559015434415, 8783.560821958876, 10305.381677099249, 10325.535350909227, 10328.638497652546, 10337.328073675188, 9010.27917766577, 10117.696469105938, 10315.638952636771, 10487.737806554896, 11698.35659038761, 5488.420442392069, 5621.634593751393, 5680.261565996394, 5694.857628559319, 5704.172894930295, 961.9182369498672, 1010.6715317521835, 1139.9316041037564, 1165.0970497864223, 1187.9703007424885, 25833.870830645727, 26148.607770883453, 26549.86725066401, 26612.0, 26670.466590668468, 1011.367895065584, 1158.137187795098, 1158.4439469054732, 1176.5529098942347, 1205.2045649871218, 12990.415431305613, 13365.398557064955, 13619.25094119833, 13690.178589284675, 13692.0, 482163.0, 484391.0, 485671.0, 486105.0, 486429.0, 52321.0, 55271.72364138153, 55603.721981389834, 55609.60975975633, 55761.163582546294, 1430.3491911180388, 1457.3223451095291, 1459.532949456185, 1459.6569806095558, 1464.8388677245493, 19300.54775183177, 24390.390240244134, 24486.387840304134, 25404.618930715686, 25757.96968121254, 40635.390469142396, 40648.0, 40696.79651601723, 40707.1858562833, 40793.59206408016, 427312.0, 428532.0, 429283.0, 429891.0, 430481.0, 29573.852130739207, 31692.207694807814, 31860.5220921691, 31889.362212756077, 31956.881509147068, 36807.81596092002, 37593.24813503769, 38088.809555952044, 38231.42652860265, 38278.23443531058, 19930.501737456678, 24121.75878241195, 25323.87338063298, 27114.0, 27176.320591985357, 27393.904243830024, 27532.58701119525, 27619.0, 27706.0, 27915.441691165655, 31280.843595782786, 31383.372332553678, 31733.36533269368, 31757.682423175473, 32570.34859302848, 27339.179824605293, 27363.726362736914, 27412.725872741816, 27966.3008424791, 28415.0, 17983.820161798216, 20758.896205518875, 22315.888420557792, 22499.0, 22500.0], "value": 17507.104913861953, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.4126245581804, 387.9515060617423, 407.753309247905, 416.5064398687555, 422.87102433757707, 13063.673408164788, 16723.832761672395, 20851.165953361866, 21076.36770896872, 21323.25368612099, 19566.413007609794, 20444.0, 20497.692534611993, 20570.588588228227, 20883.895580522116, 6364.904526432108, 6654.234763002244, 6655.134832471772, 6657.034729964153, 6662.367075127855, 5415.0, 7514.586697731623, 7737.961310193469, 7740.26467485588, 7742.225777422269, 7429.4427917906105, 7604.809879752995, 7615.35269502093, 7716.961415192916, 7744.186860379664, 7541.396688264926, 7551.546907185587, 7597.126330471983, 7618.085829700416, 7647.044119485036, 7310.342069213743, 7526.134494533144, 7540.698372065111, 7540.924590754132, 7551.131619863731, 26967.65161741905, 27376.904923803224, 27730.86134569314, 28025.29936751577, 28167.71832281692, 2930.472514947309, 2939.323955490228, 2944.8969286074885, 2945.6759756426777, 3001.669816320203, 15390.574948971176, 16266.83733162665, 16369.91815040929, 16625.418110366078, 16766.664666706718, 1909.961800763977, 1967.1147983407382, 1979.416072258687, 1980.3266889257577, 2008.8694234874686, 128614.99999999907, 131398.02902956464, 134154.0, 137262.0, 138843.0, 14002.861230329041, 16976.660466790596, 21002.684959725615, 22036.669449958263, 22082.0, 34790.82604586961, 36421.635783642356, 37854.05364865845, 37961.430578541345, 38834.611653883665, 8003.559804210708, 9637.855432168522, 9829.869564999974, 9900.60397584103, 10080.596776128872, 7824.256695613907, 7849.0, 7984.6806127754235, 8019.318357939641, 8030.036395632562, 10464.424456654804, 10765.30025548337, 10808.0, 10916.563337466412, 10923.453827308604, 3706.7405281630026, 4282.186384586918, 4314.583833053437, 4377.5403582624, 4382.298832186835, 810.9240344717542, 818.4843548564367, 836.8033512124646, 904.7421484876778, 927.5918595817953, 19796.4061078165, 20518.0, 20543.79456205449, 21272.255471058437, 21311.147554097974, 916.2166347772692, 930.6835675870101, 938.2775263047415, 942.1944237676825, 952.1573407534382, 10263.640772572924, 11025.00774930244, 11078.944605276922, 11090.500927458284, 11094.944525277322, 626439.0, 749929.0014199686, 757267.0, 760873.0, 775786.0, 117076.0, 139956.30021849825, 154768.0, 155353.9001855814, 155448.44551554567, 1187.619961612293, 1204.265398107154, 1269.2004037456343, 1274.5602767045355, 1277.9105462617713, 19190.904045479685, 19623.0, 19955.501112472306, 19975.500612484804, 19984.500387489847, 62689.68655156695, 63609.0, 63642.3635763655, 63708.044379335275, 63954.36045639484, 69125.65437172783, 85795.57102214449, 91883.0, 92229.53885230531, 92934.0, 11013.614523491638, 11368.488418021374, 11557.24877882935, 11770.470328835394, 11789.705257368634, 10501.63244286477, 12317.630471085882, 12416.317102559446, 12416.441260143134, 12864.2924639142, 10741.355518668905, 10811.729706757395, 10893.945530272615, 11052.447377631248, 11141.55159829217, 10122.342047766871, 10125.797484050425, 10137.442440665835, 10401.115905148146, 10531.842022369823, 10968.945155274172, 11030.0, 11140.554377825121, 11257.761646219073, 11432.314061156358, 11004.449777510932, 11061.059809916247, 11094.445277736242, 11147.83278250843, 11420.942895285803, 8118.731093774943, 9532.380395274286, 9934.602615895283, 10798.73003174927, 11056.0], "value": 11462.421276469235, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1465.9413623455061, 1521.490300749249, 1541.0214513783746, 1549.2021608871426, 1561.1881821452841, 10530.842037369435, 11077.723056923582, 11093.83359249612, 11111.499982500787, 11114.11087113031, 9009.099090090987, 9328.113829186233, 9440.291978101654, 9460.337776355658, 9474.715758527238, 2288.187693368859, 2317.0, 2327.3948773318975, 2327.406511339606, 2327.906883724649, 2143.2712877621607, 2270.2735124760084, 2311.456807650201, 2412.9879350603223, 2415.45652228249, 2338.1232037985756, 2385.85684858909, 2390.605550084234, 2404.495056038234, 2409.590369637157, 2340.0, 2360.1503458754833, 2378.8810559472045, 2386.081358676922, 2392.8803559822027, 2346.9882650586637, 2378.821588380861, 2379.238643634038, 2384.0, 2387.8925448354867, 10699.518521666465, 10968.287061340987, 10968.835467467994, 10987.505562249718, 10997.725056873562, 5486.643368181095, 5651.943480565183, 5654.519365853907, 5670.234518340054, 5671.63134396267, 3381.1209085637665, 3499.860005599773, 3527.94708079379, 3566.4115420955513, 3589.210373717804, 1120.9831852522218, 1123.6292023632182, 1157.9942100289445, 1163.5869266410386, 1165.2114005002281, 13648.454061837514, 14091.436342546285, 14540.636484087876, 15106.924465377602, 15159.393624255017, 4542.795574199169, 4662.254039353755, 4664.953350466452, 4672.57946784791, 4677.18149323867, 11540.653780386601, 11561.190716649922, 11568.710782230342, 11578.652640420632, 11593.0, 4667.346571479996, 4823.927641085386, 5318.22885681574, 5537.833864984057, 5540.252065971043, 5039.949600504021, 5101.0818052750865, 5121.615878809104, 5124.30821839047, 5129.948700513021, 3109.4247564200696, 3720.8697695580527, 3787.5833658298116, 3848.191879705237, 3848.9037774055873, 980.1178938954997, 981.7545613596601, 996.4768496539375, 998.1366118307611, 1008.6318493749728, 918.3433844801, 920.1672486399818, 930.3534043839467, 940.7365937537501, 989.5942663508071, 6302.684865756693, 6336.966633666858, 6509.772157974448, 6522.64125473094, 6574.9671251644395, 961.927855410847, 1016.8474728790741, 1021.1524434719165, 1040.9167266618804, 1042.7653777900064, 4191.811368488426, 4194.140201258735, 4255.255330317182, 4317.373980772771, 4348.934765978513, 13637.590872273848, 14274.214918179392, 14289.785653215211, 14296.999210055405, 14337.283135843167, 12710.491580336868, 14188.574342769733, 14197.077189982618, 14249.0, 14256.35846386915, 8047.637856296481, 9146.359754817233, 9245.445273283625, 9432.905670943202, 9453.858192127123, 6513.1532900722605, 6572.14562106923, 6700.827355212916, 6757.054012438164, 6920.446364290744, 12264.509419623115, 12650.810237846625, 12657.556985505464, 12712.555060572835, 12734.74530509366, 11389.943050285026, 11748.765024699629, 11748.941255293668, 12906.419211135333, 12999.935000324938, 8012.879806802786, 9369.016253293306, 9441.244700423813, 9442.858357124785, 9457.479838608942, 10209.489525523843, 10289.897101029193, 10415.635452759117, 10430.217733670157, 10434.947825260826, 8667.219950204511, 8669.089745576624, 8742.91257087421, 8812.779680508038, 8863.069377715246, 8256.628451719567, 8473.66105355779, 8578.871316930126, 8617.82764344697, 8922.509261990654, 7760.767176984699, 7909.485883417559, 7936.84126317482, 7984.560849153352, 7989.3209077229085, 6010.278766548042, 6048.213732214784, 6111.938880611315, 6117.143599896107, 6255.155554000334, 4753.453352864465, 5577.525910297508, 5708.430181700066, 6128.754849806136, 6138.907916381348], "value": 5041.953711105472, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.0850925884366, 367.79954924566107, 373.2777076357247, 376.2869362557951, 376.6045652065331, 4207.831686732531, 4402.889927751808, 4409.4488188976375, 5192.8442146735615, 5280.366356037281, 4379.167958087961, 4410.867673969778, 5075.046107248718, 5088.872778180547, 5094.809232030226, 3120.0639808057604, 3926.293267211901, 4056.533498647649, 4139.751614903101, 4197.244495990721, 3707.9814600926957, 4219.767912764797, 4249.787510624472, 4272.487301523822, 4318.438602981623, 3931.2727145478034, 4079.2657321682086, 4202.655150351889, 4211.305134652778, 4269.957300426987, 4121.649659778923, 4171.395147703566, 4189.790510474494, 4192.937105943429, 4194.266003449399, 3252.308799424286, 3545.7872527648424, 3749.887503374903, 3864.937142285866, 3916.960830391688, 3712.0619889768905, 4748.691335063244, 5063.8480845574695, 5066.212429254356, 5067.214581739836, 4835.709857408567, 4845.442774080991, 4847.951520484785, 4863.2461968394955, 4873.610111191097, 5032.924506132411, 5257.973710131463, 5428.267183930158, 5463.350275116565, 5497.47773961477, 3872.186840763443, 4022.3162062449455, 4268.295731204347, 4303.0421158372865, 4330.665387651522, 4562.536230435561, 4618.205985378864, 4971.332680743954, 4979.617936216143, 5744.044498369746, 3795.411711184771, 3959.6832253419657, 4190.643795277375, 4431.8448854289945, 4458.436399070482, 3886.9611303887164, 3992.8003599819895, 4460.241758900995, 4509.774511274423, 4520.52534483881, 3787.223619158066, 4014.41790940312, 4597.977010114928, 4738.952610473852, 4802.6638135330195, 4459.6655250855665, 4626.89476288288, 5113.522804492906, 5386.48828361305, 5479.5342395896, 1409.6264489910182, 1672.7955871772303, 1690.1549225387307, 1706.6245426006258, 1707.5048236011667, 1177.6574704836494, 1224.908131890112, 1243.0676992255808, 1263.4946021591436, 1365.5388734054666, 4679.742614156254, 4699.342092107107, 4728.621710263171, 4788.592969597623, 5073.492650734897, 4893.437254715682, 5165.302684137593, 5341.839744807662, 5515.062439385314, 5588.91616625751, 4590.816367345269, 4656.604188643935, 4703.576678098988, 5097.770600322994, 5122.897542049138, 2467.543504451682, 2802.9867892015754, 2908.840013799261, 2913.533834586456, 2936.5741967415033, 5004.196810424104, 5461.344638643388, 5557.972210138923, 5840.88318233633, 5853.560982926298, 4991.251312303111, 5494.862628434321, 5529.962855563497, 5669.922714684197, 5689.715514224272, 5265.710385928811, 5735.913961290584, 5896.0, 5896.7936122235515, 5934.6142500737315, 4964.180910149785, 5196.454372290855, 5248.879629490832, 5511.732301570581, 5615.32616086056, 4778.76106194682, 4802.703270116979, 4901.113071467439, 5310.282802038666, 5477.835664930059, 5398.1902714593125, 5577.581681373832, 5602.607817452902, 5656.236408084856, 5825.8927456144165, 3779.603141670195, 3922.490076290064, 4140.730852494578, 4262.829486820617, 4351.933776224845, 4801.759912004456, 5587.329520457572, 5618.88762224745, 5621.482199805948, 5658.1426379678, 4717.905641887074, 4951.554360107608, 5368.865778355416, 5409.675419474844, 5435.646682965595, 4309.762963037063, 4727.125481785881, 5355.42015105537, 5366.865828354323, 5374.0, 4474.507804141476, 5008.799648014038, 5086.821961231339, 5273.920891186712, 5290.640443318443, 4847.515248475265, 5103.7686371469545, 5297.0400951648135, 5307.946920530745, 5406.648567843077, 4297.312430011183, 4376.846810361622, 5102.413222479462, 5157.974210128926, 5181.974090129676], "value": 4101.10560665172, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.2510376449081, 716.0798374089289, 731.765834932821, 820.5035953248284, 822.338017895594, 13070.60788176355, 14987.0, 15627.687446251068, 15745.976511526742, 15850.207489625516, 14187.0, 15903.0, 16696.49910502687, 16930.661386772255, 17204.483865484057, 919.2232563483863, 943.9669611563596, 948.2461443152711, 949.4493193947528, 949.5726922884695, 910.3672947301628, 932.4591736792678, 934.0, 934.2666007184362, 935.289180223032, 991.7718924647336, 995.2833959549113, 995.3679413572397, 995.7062666513395, 996.4021587047786, 989.0850962859398, 989.6338354808695, 990.3315262197988, 991.6083147156869, 991.7024892532247, 734.988975165373, 735.5366119344832, 735.6285076036621, 735.9668814903289, 737.5353527277781, 15524.456644017519, 15815.60460988473, 15950.441734539352, 16239.83760162395, 16342.182890855529, 13909.721805563933, 14162.858371416258, 14193.0, 14287.0, 14488.420463181461, 11578.768424631544, 11598.130140239517, 11807.40962951849, 11895.702607434796, 11895.702607434796, 3594.0475773919934, 3599.838007289652, 3667.413213885792, 3827.6937844972335, 3855.267499175148, 101320.0, 101929.98070019278, 104738.47630761798, 105556.4722176392, 108217.45891270573, 10299.632939674457, 10585.882823435344, 10651.786964260826, 10768.300060496043, 11160.888391116147, 22452.663210051862, 22469.101235950708, 22478.0, 22504.774952250595, 22528.549429011327, 7196.0, 7406.925930740731, 7514.924850751532, 7579.279968402993, 7732.188120247405, 4196.475440569929, 4208.726432781859, 4287.678424118204, 4302.65578753699, 4377.971176773456, 3466.9133271667906, 3549.9290014200087, 3567.678908898212, 3569.5716514017963, 3576.8390422431053, 512.9094377161879, 624.7063879976404, 630.5712115761327, 631.2235949781794, 631.3434028610286, 672.5661948043485, 682.5532252624088, 688.6997428588538, 701.7964790210782, 734.7354952217197, 9724.465154416575, 10266.6406675766, 10667.341500811302, 11061.778764424667, 11267.718307042389, 574.9367569567344, 605.279717136606, 610.7984365159575, 615.2217444932164, 727.5561907236581, 4538.9546104539195, 4915.926261106087, 4922.384701912261, 4934.481879402683, 4939.506049395031, 93311.5334423337, 93401.59897601542, 94266.0, 94917.5254123725, 96237.0, 55285.72357138189, 69633.95549066768, 72366.0, 75550.48899022098, 78378.0, 47212.76393618079, 52174.47825521772, 73128.0, 74478.99999999892, 76650.0, 10645.201609879445, 10765.084967777513, 10816.351018938889, 11201.551937922392, 11208.327500349678, 48074.03851923012, 48566.757166213945, 48703.51296487131, 49045.0, 51413.7429312851, 61398.386016139266, 61569.94287243225, 61883.690581546805, 62411.37588624056, 63018.0, 12197.634070977885, 12271.509139634672, 12360.938195308965, 12841.743165136832, 12940.223586584834, 14223.928880355532, 14237.857621424067, 14609.196494192922, 14640.560783176521, 14980.925095374454, 12726.68183295425, 12819.743605128031, 13763.419483101392, 13799.724005519633, 14408.351624176728, 12936.870631293566, 13356.265405402795, 13369.732605347644, 13798.93100534491, 14135.15189088658, 12464.189827661174, 13019.82856347334, 13194.736105278032, 13284.667883302995, 13514.39185236647, 8114.99907693405, 8449.915500844912, 8478.788030299293, 8611.727543533667, 9056.547172641473, 9301.0, 10836.512356943798, 10940.945295273472, 10986.780264394507, 11110.722231944266], "value": 7455.690457915389, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1461.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 172.0, 174.0, 118.0, 117.0, 117.0, 118.0, 116.0, 117.0, 117.0], "value": 128.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 113.0, 111.0, 146.0, 109.0, 111.0, 110.0, 106.0, 128.0, 102.0], "value": 118.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 183.0, 187.0, 184.0, 186.0, 183.0, 186.0, 186.0, 184.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 62.0, 61.0, 92.0, 64.0, 61.0, 59.0, 61.0, 76.0, 59.0], "value": 68.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 68.0, 86.0, 70.0, 69.0, 68.0, 81.0, 68.0, 68.0, 82.0], "value": 75.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 89.0, 89.0, 89.0, 89.0, 89.0, 89.0, 90.0, 91.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 119.0, 129.0, 149.0, 139.0, 97.0, 96.0, 97.0, 96.0], "value": 111.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 88.0, 85.0, 86.0, 85.0, 83.0, 83.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 61.0, 62.0, 64.0, 63.0, 64.0, 66.0, 64.0, 62.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 65.0, 62.0, 64.0, 60.0, 56.0, 57.0, 58.0, 59.0, 59.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 97.0, 98.0, 85.0, 87.0, 85.0, 86.0, 86.0, 85.0, 91.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 136.0, 152.0, 146.0, 153.0, 143.0, 144.0, 149.0, 178.0, 157.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [219.0, 167.0, 171.0, 164.0, 167.0, 169.0, 166.0, 166.0, 166.0, 164.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 64.0, 73.0, 83.0, 74.0, 63.0, 64.0], "value": 68.2, "unit": "score"}]}]} 14:32:31 INFO - Return code: 0 14:32:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:32:31 ERROR - # TBPL SUCCESS # 14:32:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:32:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:32:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:32:31 INFO - 2017-04-14 14:32:31.511 screenresolution[2416:33577] starting screenresolution argv=screenresolution get 14:32:31 INFO - 2017-04-14 14:32:31.513 screenresolution[2416:33577] Display 0: 1600x1200x32@60 14:32:31 INFO - 2017-04-14 14:32:31.520 screenresolution[2417:33580] starting screenresolution argv=screenresolution list 14:32:31 INFO - Available Modes on Display 0 14:32:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:32:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:32:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:32:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:32:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:32:31 INFO - Intel Iris: 14:32:31 INFO - Chipset Model: Intel Iris 14:32:31 INFO - Type: GPU 14:32:31 INFO - Bus: Built-In 14:32:31 INFO - VRAM (Dynamic, Max): 1536 MB 14:32:31 INFO - Vendor: Intel (0x8086) 14:32:31 INFO - Device ID: 0x0a2e 14:32:31 INFO - Revision ID: 0x0009 14:32:31 INFO - Displays: 14:32:31 INFO - Display: 14:32:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:32:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:32:31 INFO - Display Serial Number: 1600x1200 60 14:32:31 INFO - Main Display: Yes 14:32:31 INFO - Mirror: Off 14:32:31 INFO - Online: Yes 14:32:31 INFO - Rotation: Supported 14:32:31 INFO - Return code: 0 14:32:31 INFO - Running post-action listener: _resource_record_post_action 14:32:31 INFO - [mozharness: 2017-04-14 21:32:31.756991Z] Finished run-tests step (success) 14:32:31 INFO - Running post-run listener: _resource_record_post_run 14:32:31 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 643072; Write bytes: 505935360; Read time: 6; Write time: 1544 14:32:31 INFO - TinderboxPrint: CPU usage
53.7% 14:32:31 INFO - TinderboxPrint: I/O read bytes / time
643,072 / 6 14:32:31 INFO - TinderboxPrint: I/O write bytes / time
505,935,360 / 1,544 14:32:31 INFO - TinderboxPrint: CPU idle
2,227.2 (46.3%) 14:32:31 INFO - TinderboxPrint: CPU system
959.1 (19.9%) 14:32:31 INFO - TinderboxPrint: CPU user
1,623.2 (33.8%) 14:32:31 INFO - TinderboxPrint: Swap in / out
208,527,360 / 0 14:32:31 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 209279488; Write bytes: 210408960; Read time: 16659; Write time: 453 14:32:31 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 503808; Write bytes: 293916672; Read time: 4; Write time: 1080 14:32:32 INFO - Running post-run listener: _upload_blobber_files 14:32:32 INFO - Blob upload gear active. 14:32:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:32:32 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 14:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:32:32 INFO - (blobuploader) - INFO - Open directory for files ... 14:32:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:32:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:32:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:32:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:32:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:32:33 INFO - (blobuploader) - INFO - Done attempting. 14:32:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:32:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:32:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:32:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:32:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:32:34 INFO - (blobuploader) - INFO - Done attempting. 14:32:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:32:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:32:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:32:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:32:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:32:35 INFO - (blobuploader) - INFO - Done attempting. 14:32:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:32:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:32:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:32:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:32:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:32:37 INFO - (blobuploader) - INFO - Done attempting. 14:32:37 INFO - (blobuploader) - INFO - Iteration through files over. 14:32:37 INFO - Return code: 0 14:32:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:32:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:32:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/047b79cb6082814e75ca9fb5c25a739b776292b763d0dc838821aa3409d11fac900ff31ec658fcb6ed9dfe605f7703a552b29af077fa3b3ae6a73761fd1e156e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ed3e7f0b494e5b9c8ac62b19e3adf6f8e708266576d75aa878f34ddaddd3781d13ffbc04b4167e49941a81e71446d064ef363ec7b0a3b1494ea44a7fcbb8779", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfdc45e922be0d1dd994abe32254590c6632dce01267cb8264218ed415b7d802c41dee387a9eea97d09196bdf4fc823cf16ba14ac5540d03402847189711084e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3893e09a774613140ce39eb69c75c8e4243ec4297d977d7970b87a4c63af75898aaf0b0d372873dd750ac74d6aba7fcf7e8dafe4313546e64b4bd12d83671b97"} 14:32:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:32:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:32:37 INFO - Contents: 14:32:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/047b79cb6082814e75ca9fb5c25a739b776292b763d0dc838821aa3409d11fac900ff31ec658fcb6ed9dfe605f7703a552b29af077fa3b3ae6a73761fd1e156e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ed3e7f0b494e5b9c8ac62b19e3adf6f8e708266576d75aa878f34ddaddd3781d13ffbc04b4167e49941a81e71446d064ef363ec7b0a3b1494ea44a7fcbb8779", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfdc45e922be0d1dd994abe32254590c6632dce01267cb8264218ed415b7d802c41dee387a9eea97d09196bdf4fc823cf16ba14ac5540d03402847189711084e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3893e09a774613140ce39eb69c75c8e4243ec4297d977d7970b87a4c63af75898aaf0b0d372873dd750ac74d6aba7fcf7e8dafe4313546e64b4bd12d83671b97"} 14:32:37 INFO - Running post-run listener: copy_logs_to_upload_dir 14:32:37 INFO - Copying logs to upload dir... 14:32:37 INFO - mkdir: /builds/slave/test/build/upload/logs 14:32:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.842928 ========= master_lag: 0.71 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-14 14:32:37.989090) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-14 14:32:37.994092) ========= 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.dYXwDrStUx/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.bKXb4VbJ0U/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/047b79cb6082814e75ca9fb5c25a739b776292b763d0dc838821aa3409d11fac900ff31ec658fcb6ed9dfe605f7703a552b29af077fa3b3ae6a73761fd1e156e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ed3e7f0b494e5b9c8ac62b19e3adf6f8e708266576d75aa878f34ddaddd3781d13ffbc04b4167e49941a81e71446d064ef363ec7b0a3b1494ea44a7fcbb8779", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfdc45e922be0d1dd994abe32254590c6632dce01267cb8264218ed415b7d802c41dee387a9eea97d09196bdf4fc823cf16ba14ac5540d03402847189711084e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3893e09a774613140ce39eb69c75c8e4243ec4297d977d7970b87a4c63af75898aaf0b0d372873dd750ac74d6aba7fcf7e8dafe4313546e64b4bd12d83671b97"} build_url:https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011850 build_url: 'https://queue.taskcluster.net/v1/task/cauYaRNPTGGDCQ_SXlOFYA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/047b79cb6082814e75ca9fb5c25a739b776292b763d0dc838821aa3409d11fac900ff31ec658fcb6ed9dfe605f7703a552b29af077fa3b3ae6a73761fd1e156e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1ed3e7f0b494e5b9c8ac62b19e3adf6f8e708266576d75aa878f34ddaddd3781d13ffbc04b4167e49941a81e71446d064ef363ec7b0a3b1494ea44a7fcbb8779", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfdc45e922be0d1dd994abe32254590c6632dce01267cb8264218ed415b7d802c41dee387a9eea97d09196bdf4fc823cf16ba14ac5540d03402847189711084e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3893e09a774613140ce39eb69c75c8e4243ec4297d977d7970b87a4c63af75898aaf0b0d372873dd750ac74d6aba7fcf7e8dafe4313546e64b4bd12d83671b97"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-14 14:32:38.047474) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 14:32:38.050893) ========= 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.dYXwDrStUx/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.bKXb4VbJ0U/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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.005360 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-14 14:32:38.103596) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-14 14:32:38.103969) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-14 14:32:38.104319) ========= ========= Total master_lag: 0.87 =========