builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0022 starttime: 1460749268.04 results: success (0) buildid: 20160415114641 builduid: 48579ca061c642c6bbc9183ea206671d revision: 009d7df1ba6d97a5abd2ed417a3464a8e948742c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 12:41:08.045241) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 12:41:08.045628) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 12:41:08.045914) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 12:41:08.068291) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 12:41:08.068559) ========= 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.N2OcaZYXsj/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.buHTe901oe/Listeners TMPDIR=/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-15 12:41:08-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-04-15 12:41:08 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138658 ========= master_lag: 1.40 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 12:41:09.604861) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 12:41:09.605146) ========= 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.N2OcaZYXsj/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.buHTe901oe/Listeners TMPDIR=/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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.028622 ========= master_lag: 1.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 12:41:10.728501) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-15 12:41:10.729062) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 009d7df1ba6d97a5abd2ed417a3464a8e948742c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 009d7df1ba6d97a5abd2ed417a3464a8e948742c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.N2OcaZYXsj/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.buHTe901oe/Listeners TMPDIR=/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-15 12:41:10,812 truncating revision to first 12 chars 2016-04-15 12:41:10,812 Setting DEBUG logging. 2016-04-15 12:41:10,812 attempt 1/10 2016-04-15 12:41:10,812 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/009d7df1ba6d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-15 12:41:11,898 unpacking tar archive at: fx-team-009d7df1ba6d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.332847 ========= master_lag: 3.81 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-15 12:41:15.869564) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 12:41:15.869929) ========= script_repo_revision: 009d7df1ba6d97a5abd2ed417a3464a8e948742c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 12:41:15.870335) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-04-15 12:41:15.870598) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-04-15 12:41:16.907054) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-04-15 12:41:16.907396) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.N2OcaZYXsj/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.buHTe901oe/Listeners TMPDIR=/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:41:17 INFO - MultiFileLogger online at 20160415 12:41:17 in /builds/slave/test 12:41:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:41:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:41:17 INFO - {'append_to_log': False, 12:41:17 INFO - 'base_work_dir': '/builds/slave/test', 12:41:17 INFO - 'blob_upload_branch': 'Fx-Team', 12:41:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:41:17 INFO - 'branch': 'Fx-Team', 12:41:17 INFO - 'buildbot_json_path': 'buildprops.json', 12:41:17 INFO - 'config_files': ('talos/mac_config.py',), 12:41:17 INFO - 'default_actions': ('clobber', 12:41:17 INFO - 'read-buildbot-config', 12:41:17 INFO - 'download-and-extract', 12:41:17 INFO - 'populate-webroot', 12:41:17 INFO - 'create-virtualenv', 12:41:17 INFO - 'install', 12:41:17 INFO - 'run-tests'), 12:41:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:41:17 INFO - 'download_minidump_stackwalk': True, 12:41:17 INFO - 'download_symbols': 'ondemand', 12:41:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:41:17 INFO - 'tooltool.py': '/tools/tooltool.py', 12:41:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:41:17 INFO - '/tools/misc-python/virtualenv.py')}, 12:41:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:41:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:41:17 INFO - 'installer_path': 'installer.exe', 12:41:17 INFO - 'log_level': 'info', 12:41:17 INFO - 'log_name': 'talos', 12:41:17 INFO - 'log_to_console': True, 12:41:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:41:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:41:17 INFO - 'opt_config_files': (), 12:41:17 INFO - 'pip_index': False, 12:41:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:41:17 INFO - 'cmd': ('bash', 12:41:17 INFO - '-c', 12:41:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:41:17 INFO - 'enabled': True, 12:41:17 INFO - 'halt_on_failure': False, 12:41:17 INFO - 'name': 'check_screen_resolution'},), 12:41:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:41:17 INFO - 'cmd': ('bash', 12:41:17 INFO - '-c', 12:41:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:41:17 INFO - 'enabled': True, 12:41:17 INFO - 'halt_on_failure': False, 12:41:17 INFO - 'name': 'check_screen_resolution'},), 12:41:17 INFO - 'run_cmd_checks_enabled': True, 12:41:17 INFO - 'sps_profile': False, 12:41:17 INFO - 'sps_profile_interval': 0, 12:41:17 INFO - 'suite': 'dromaeojs', 12:41:17 INFO - 'system_bits': '32', 12:41:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:41:17 INFO - 'title': 't-yosemite-r7-0022', 12:41:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:41:17 INFO - 'use_talos_json': True, 12:41:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:41:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:41:17 INFO - 'work_dir': 'build'} 12:41:17 INFO - ##### 12:41:17 INFO - ##### Running clobber step. 12:41:17 INFO - ##### 12:41:17 INFO - Running pre-action listener: _resource_record_pre_action 12:41:17 INFO - Running main action method: clobber 12:41:17 INFO - rmtree: /builds/slave/test/build 12:41:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:41:18 INFO - Running post-action listener: _resource_record_post_action 12:41:18 INFO - ##### 12:41:18 INFO - ##### Running read-buildbot-config step. 12:41:18 INFO - ##### 12:41:18 INFO - Running pre-action listener: _resource_record_pre_action 12:41:18 INFO - Running main action method: read_buildbot_config 12:41:18 INFO - Using buildbot properties: 12:41:18 INFO - { 12:41:18 INFO - "project": "", 12:41:18 INFO - "product": "firefox", 12:41:18 INFO - "script_repo_revision": "production", 12:41:18 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:41:18 INFO - "repository": "", 12:41:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:41:18 INFO - "buildid": "20160415114641", 12:41:18 INFO - "pgo_build": "False", 12:41:18 INFO - "basedir": "/builds/slave/test", 12:41:18 INFO - "buildnumber": 729, 12:41:18 INFO - "slavename": "t-yosemite-r7-0022", 12:41:18 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:41:18 INFO - "platform": "yosemite_r7", 12:41:18 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:41:18 INFO - "branch": "fx-team", 12:41:18 INFO - "repo_path": "integration/fx-team", 12:41:18 INFO - "revision": "009d7df1ba6d97a5abd2ed417a3464a8e948742c", 12:41:18 INFO - "stage_platform": "macosx64", 12:41:18 INFO - "builduid": "48579ca061c642c6bbc9183ea206671d", 12:41:18 INFO - "slavebuilddir": "test" 12:41:18 INFO - } 12:41:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 12:41:18 INFO - Running post-action listener: _resource_record_post_action 12:41:18 INFO - ##### 12:41:18 INFO - ##### Running download-and-extract step. 12:41:18 INFO - ##### 12:41:18 INFO - Running pre-action listener: _resource_record_pre_action 12:41:18 INFO - Running main action method: download_and_extract 12:41:18 INFO - mkdir: /builds/slave/test/build/tests 12:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:18 INFO - https://queue.taskcluster.net/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:41:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 12:41:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 12:41:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 12:41:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 12:41:23 INFO - Downloaded 1199 bytes. 12:41:23 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 12:41:23 INFO - Using the following test package requirements: 12:41:23 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 12:41:23 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:41:23 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 12:41:23 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:41:23 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 12:41:23 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:41:23 INFO - u'jsshell-mac.zip'], 12:41:23 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:41:23 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 12:41:23 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 12:41:23 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:41:23 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 12:41:23 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:41:23 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 12:41:23 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:41:23 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 12:41:23 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:41:23 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 12:41:23 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:41:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:23 INFO - https://queue.taskcluster.net/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:41:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:41:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:41:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:41:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:41:26 INFO - Downloaded 18033938 bytes. 12:41:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:41:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:41:29 INFO - Return code: 0 12:41:29 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:41:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:29 INFO - https://queue.taskcluster.net/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:41:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:41:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:41:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:41:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:41:31 INFO - Downloaded 18033938 bytes. 12:41:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:41:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:41:35 INFO - Return code: 0 12:41:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:35 INFO - https://queue.taskcluster.net/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:41:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 12:41:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 12:41:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 12:41:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:41:35 INFO - Downloaded 10841076 bytes. 12:41:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:41:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:41:36 INFO - Return code: 0 12:41:36 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:36 INFO - https://queue.taskcluster.net/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:41:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:41:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:41:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 12:41:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 12:41:39 INFO - Downloaded 109948952 bytes. 12:41:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:41:39 INFO - mkdir: /builds/slave/test/properties 12:41:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:41:39 INFO - Writing to file /builds/slave/test/properties/build_url 12:41:39 INFO - Contents: 12:41:39 INFO - build_url:https://queue.taskcluster.net/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:41:41 INFO - Running post-action listener: _resource_record_post_action 12:41:41 INFO - Running post-action listener: set_extra_try_arguments 12:41:41 INFO - ##### 12:41:41 INFO - ##### Running populate-webroot step. 12:41:41 INFO - ##### 12:41:41 INFO - Running pre-action listener: _resource_record_pre_action 12:41:41 INFO - Running main action method: populate_webroot 12:41:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:41:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:41:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:41:41 INFO - '--tppagecycles', 12:41:41 INFO - '7'], 12:41:41 INFO - 'tests': ['tsvgm']}}, 12:41:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:41:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:41:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:41:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:41:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:41:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:41:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:41:41 INFO - 'talos_options': ['--disable-e10s'], 12:41:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:41:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:41:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:41:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:41:41 INFO - 'talos_options': ['--disable-e10s'], 12:41:41 INFO - 'tests': ['damp', 'tps']}, 12:41:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:41:41 INFO - 'tests': ['damp', 'tps']}, 12:41:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:41:41 INFO - 'tests': ['dromaeo_dom']}, 12:41:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:41:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:41:41 INFO - 'tests': ['a11yr', 12:41:41 INFO - 'ts_paint', 12:41:41 INFO - 'tpaint', 12:41:41 INFO - 'sessionrestore', 12:41:41 INFO - 'sessionrestore_no_auto_restore', 12:41:41 INFO - 'tabpaint']}, 12:41:41 INFO - 'other-e10s': {'tests': ['a11yr', 12:41:41 INFO - 'ts_paint', 12:41:41 INFO - 'tpaint', 12:41:41 INFO - 'sessionrestore', 12:41:41 INFO - 'sessionrestore_no_auto_restore', 12:41:41 INFO - 'tabpaint']}, 12:41:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:41:41 INFO - 'ts_paint', 12:41:41 INFO - 'tpaint', 12:41:41 INFO - 'sessionrestore', 12:41:41 INFO - 'sessionrestore_no_auto_restore', 12:41:41 INFO - 'tabpaint']}, 12:41:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:41:41 INFO - 'ts_paint', 12:41:41 INFO - 'tpaint', 12:41:41 INFO - 'sessionrestore', 12:41:41 INFO - 'sessionrestore_no_auto_restore', 12:41:41 INFO - 'tabpaint']}, 12:41:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:41:41 INFO - 'tests': ['a11yr', 12:41:41 INFO - 'ts_paint', 12:41:41 INFO - 'tpaint', 12:41:41 INFO - 'sessionrestore', 12:41:41 INFO - 'sessionrestore_no_auto_restore', 12:41:41 INFO - 'tabpaint']}, 12:41:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:41:41 INFO - 'tests': ['a11yr', 12:41:41 INFO - 'ts_paint', 12:41:41 INFO - 'tpaint', 12:41:41 INFO - 'sessionrestore', 12:41:41 INFO - 'sessionrestore_no_auto_restore', 12:41:41 INFO - 'tabpaint']}, 12:41:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:41:41 INFO - 'tests': ['tsvgx', 12:41:41 INFO - 'tsvgr_opacity', 12:41:41 INFO - 'tart', 12:41:41 INFO - 'tscrollx', 12:41:41 INFO - 'cart']}, 12:41:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:41:41 INFO - 'tsvgr_opacity', 12:41:41 INFO - 'tart', 12:41:41 INFO - 'tscrollx', 12:41:41 INFO - 'cart']}, 12:41:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:41:41 INFO - 'talos_options': ['--disable-e10s'], 12:41:41 INFO - 'tests': ['tp5o']}, 12:41:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:41:41 INFO - 'tests': ['tp5o']}, 12:41:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:41:41 INFO - 'talos_options': ['--disable-e10s', 12:41:41 INFO - '--xperf_path', 12:41:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:41:41 INFO - 'tests': ['tp5n']}, 12:41:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:41:41 INFO - 'talos_options': ['--xperf_path', 12:41:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:41:41 INFO - 'tests': ['tp5n']}}, 12:41:41 INFO - 'talos.zip': {'path': '', 12:41:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:41:41 INFO - Running post-action listener: _resource_record_post_action 12:41:41 INFO - ##### 12:41:41 INFO - ##### Running create-virtualenv step. 12:41:41 INFO - ##### 12:41:41 INFO - Running pre-action listener: _resource_record_pre_action 12:41:41 INFO - Running main action method: create_virtualenv 12:41:41 INFO - Creating virtualenv /builds/slave/test/build/venv 12:41:41 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 12:41:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:41:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:41:41 INFO - Using real prefix '/tools/python27' 12:41:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:41:42 INFO - Installing distribute.............................................................................................................................................................................................done. 12:41:44 INFO - Installing pip.................done. 12:41:44 INFO - Return code: 0 12:41:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:41:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:44 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 0x10205fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febcb4b0fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efee00>, '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 0x101d27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef9b20>, '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.N2OcaZYXsj/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.buHTe901oe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 12:41:44 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 12:41:44 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 12:41:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N2OcaZYXsj/Render', 12:41:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:44 INFO - 'HOME': '/Users/cltbld', 12:41:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:44 INFO - 'LOGNAME': 'cltbld', 12:41:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:44 INFO - 'MOZ_NO_REMOTE': '1', 12:41:44 INFO - 'NO_EM_RESTART': '1', 12:41:44 INFO - 'PAGER': '/bin/cat', 12:41:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:44 INFO - 'PWD': '/builds/slave/test', 12:41:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:44 INFO - 'SHELL': '/bin/bash', 12:41:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buHTe901oe/Listeners', 12:41:44 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 12:41:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:44 INFO - 'USER': 'cltbld', 12:41:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:44 INFO - 'XPC_FLAGS': '0x0', 12:41:44 INFO - 'XPC_SERVICE_NAME': '0', 12:41:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:45 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 12:41:45 INFO - Cleaning up... 12:41:45 INFO - Return code: 0 12:41:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:41:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:45 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10205fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febcb4b0fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efee00>, '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 0x101d27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef9b20>, '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.N2OcaZYXsj/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.buHTe901oe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 12:41:45 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>=0.7.1'] in /builds/slave/test/build 12:41:45 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>=0.7.1 12:41:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N2OcaZYXsj/Render', 12:41:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:45 INFO - 'HOME': '/Users/cltbld', 12:41:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:45 INFO - 'LOGNAME': 'cltbld', 12:41:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:45 INFO - 'MOZ_NO_REMOTE': '1', 12:41:45 INFO - 'NO_EM_RESTART': '1', 12:41:45 INFO - 'PAGER': '/bin/cat', 12:41:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:45 INFO - 'PWD': '/builds/slave/test', 12:41:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:45 INFO - 'SHELL': '/bin/bash', 12:41:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buHTe901oe/Listeners', 12:41:45 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 12:41:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:45 INFO - 'USER': 'cltbld', 12:41:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:45 INFO - 'XPC_FLAGS': '0x0', 12:41:45 INFO - 'XPC_SERVICE_NAME': '0', 12:41:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:45 INFO - Downloading/unpacking psutil>=0.7.1 12:41:45 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 12:41:45 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 12:41:45 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 12:41:45 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 12:41:45 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 12:41:45 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 12:41:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:41:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:41:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:41:48 INFO - Installing collected packages: psutil 12:41:48 INFO - Running setup.py install for psutil 12:41:48 INFO - building 'psutil._psutil_osx' extension 12:41:48 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 12:41:49 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 12:41:49 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 12:41:49 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 12:41:49 INFO - building 'psutil._psutil_posix' extension 12:41:49 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 12:41:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:41:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:41:49 INFO - ^ 12:41:49 INFO - 1 warning generated. 12:41:49 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 12:41:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:41:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:41:49 INFO - Successfully installed psutil 12:41:49 INFO - Cleaning up... 12:41:49 INFO - Return code: 0 12:41:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:50 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10205fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febcb4b0fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efee00>, '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 0x101d27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef9b20>, '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.N2OcaZYXsj/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.buHTe901oe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 12:41:50 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.0.0'] in /builds/slave/test/build 12:41:50 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.0.0 12:41:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N2OcaZYXsj/Render', 12:41:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:50 INFO - 'HOME': '/Users/cltbld', 12:41:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:50 INFO - 'LOGNAME': 'cltbld', 12:41:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:50 INFO - 'MOZ_NO_REMOTE': '1', 12:41:50 INFO - 'NO_EM_RESTART': '1', 12:41:50 INFO - 'PAGER': '/bin/cat', 12:41:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:50 INFO - 'PWD': '/builds/slave/test', 12:41:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:50 INFO - 'SHELL': '/bin/bash', 12:41:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buHTe901oe/Listeners', 12:41:50 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 12:41:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:50 INFO - 'USER': 'cltbld', 12:41:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:50 INFO - 'XPC_FLAGS': '0x0', 12:41:50 INFO - 'XPC_SERVICE_NAME': '0', 12:41:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:41:50 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 12:41:50 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 12:41:50 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 12:41:50 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 12:41:50 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 12:41:50 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 12:41:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:41:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:41:53 INFO - Installing collected packages: mozsystemmonitor 12:41:53 INFO - Running setup.py install for mozsystemmonitor 12:41:53 INFO - Successfully installed mozsystemmonitor 12:41:53 INFO - Cleaning up... 12:41:53 INFO - Return code: 0 12:41:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:53 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 0x10205fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febcb4b0fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efee00>, '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 0x101d27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef9b20>, '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.N2OcaZYXsj/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.buHTe901oe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 12:41:53 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 12:41:53 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 12:41:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N2OcaZYXsj/Render', 12:41:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:53 INFO - 'HOME': '/Users/cltbld', 12:41:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:53 INFO - 'LOGNAME': 'cltbld', 12:41:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:53 INFO - 'MOZ_NO_REMOTE': '1', 12:41:53 INFO - 'NO_EM_RESTART': '1', 12:41:53 INFO - 'PAGER': '/bin/cat', 12:41:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:53 INFO - 'PWD': '/builds/slave/test', 12:41:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:53 INFO - 'SHELL': '/bin/bash', 12:41:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buHTe901oe/Listeners', 12:41:53 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 12:41:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:53 INFO - 'USER': 'cltbld', 12:41:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:53 INFO - 'XPC_FLAGS': '0x0', 12:41:53 INFO - 'XPC_SERVICE_NAME': '0', 12:41:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:53 INFO - Downloading/unpacking blobuploader==1.2.4 12:41:53 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 12:41:53 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 12:41:53 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 12:41:53 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 12:41:53 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 12:41:53 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 12:41:56 INFO - Downloading blobuploader-1.2.4.tar.gz 12:41:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:41:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:41:56 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 12:41:56 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 12:41:56 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 12:41:56 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 12:41:56 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 12:41:56 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 12:41:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:41:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:41:56 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 12:41:56 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 12:41:56 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 12:41:56 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 12:41:56 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 12:41:56 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 12:41:57 INFO - Downloading docopt-0.6.1.tar.gz 12:41:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:41:57 INFO - Installing collected packages: blobuploader, requests, docopt 12:41:57 INFO - Running setup.py install for blobuploader 12:41:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:41:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:41:57 INFO - Running setup.py install for requests 12:41:57 INFO - Running setup.py install for docopt 12:41:58 INFO - Successfully installed blobuploader requests docopt 12:41:58 INFO - Cleaning up... 12:41:58 INFO - Return code: 0 12:41:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:41:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:41:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:41:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:41:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:41:58 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 0x10205fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febcb4b0fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efee00>, '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 0x101d27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef9b20>, '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.N2OcaZYXsj/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.buHTe901oe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 12:41:58 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 12:41:58 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 12:41:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N2OcaZYXsj/Render', 12:41:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:58 INFO - 'HOME': '/Users/cltbld', 12:41:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:58 INFO - 'LOGNAME': 'cltbld', 12:41:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:58 INFO - 'MOZ_NO_REMOTE': '1', 12:41:58 INFO - 'NO_EM_RESTART': '1', 12:41:58 INFO - 'PAGER': '/bin/cat', 12:41:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:58 INFO - 'PWD': '/builds/slave/test', 12:41:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:58 INFO - 'SHELL': '/bin/bash', 12:41:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buHTe901oe/Listeners', 12:41:58 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 12:41:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:58 INFO - 'USER': 'cltbld', 12:41:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:58 INFO - 'XPC_FLAGS': '0x0', 12:41:58 INFO - 'XPC_SERVICE_NAME': '0', 12:41:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:41:58 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-5Bgj6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:41:58 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-uBgION-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:41:58 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-G3aSfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:41:58 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-zs02yA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:41:58 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-omOihu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:41:58 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-WwOU4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:41:59 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-YnrQmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:41:59 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-xg903u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:41:59 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-TqgIQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:41:59 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-PlGSBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:41:59 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-Q8Ffx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:41:59 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-zVQlcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:41:59 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-bn9ZKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:41:59 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-nYJAs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:41:59 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-ut8gfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:41:59 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-Y_CdH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:42:00 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-Pw4DTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:42:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:42:00 INFO - Running setup.py install for manifestparser 12:42:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:42:00 INFO - Running setup.py install for mozcrash 12:42:00 INFO - Running setup.py install for mozdebug 12:42:00 INFO - Running setup.py install for mozdevice 12:42:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:42:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:42:00 INFO - Running setup.py install for mozfile 12:42:00 INFO - Running setup.py install for mozhttpd 12:42:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:42:01 INFO - Running setup.py install for mozinfo 12:42:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:42:01 INFO - Running setup.py install for mozInstall 12:42:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:42:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:42:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:42:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:42:01 INFO - Running setup.py install for mozleak 12:42:01 INFO - Running setup.py install for mozlog 12:42:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:42:01 INFO - Running setup.py install for moznetwork 12:42:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:42:01 INFO - Running setup.py install for mozprocess 12:42:01 INFO - Running setup.py install for mozprofile 12:42:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:42:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:42:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:42:02 INFO - Running setup.py install for mozrunner 12:42:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:42:02 INFO - Running setup.py install for mozscreenshot 12:42:02 INFO - Running setup.py install for moztest 12:42:02 INFO - Running setup.py install for mozversion 12:42:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:42:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:42:02 INFO - Cleaning up... 12:42:02 INFO - Return code: 0 12:42:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:42:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:42:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:02 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 0x10205fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febcb4b0fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efee00>, '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 0x101d27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef9b20>, '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.N2OcaZYXsj/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.buHTe901oe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 12:42:02 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 12:42:02 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 12:42:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N2OcaZYXsj/Render', 12:42:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:02 INFO - 'HOME': '/Users/cltbld', 12:42:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:02 INFO - 'LOGNAME': 'cltbld', 12:42:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:02 INFO - 'MOZ_NO_REMOTE': '1', 12:42:02 INFO - 'NO_EM_RESTART': '1', 12:42:02 INFO - 'PAGER': '/bin/cat', 12:42:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:02 INFO - 'PWD': '/builds/slave/test', 12:42:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:02 INFO - 'SHELL': '/bin/bash', 12:42:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buHTe901oe/Listeners', 12:42:02 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 12:42:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:02 INFO - 'USER': 'cltbld', 12:42:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:02 INFO - 'XPC_FLAGS': '0x0', 12:42:02 INFO - 'XPC_SERVICE_NAME': '0', 12:42:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:42:03 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-U_B6di-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:42:03 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)) 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:42:03 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-HZgWy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:42:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:42:03 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-Y_PAdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:42:03 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)) 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:42:03 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-78XEiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:42:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:42:03 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-LRvoLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:42:03 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)) 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:42:03 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-25p9gE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:42:03 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)) 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:42:03 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-rURoPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:42:03 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)) 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:42:03 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-Qc4ZQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:42:03 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)) 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:42:03 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-9rvd3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:42:03 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)) 12:42:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:42:03 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-skeBS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:42:04 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-TDBai6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:42:04 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)) 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:42:04 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-x8jqxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:42:04 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-jpOlQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:42:04 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)) 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:42:04 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-611wrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:04 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-dXFjJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:04 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)) 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:42:04 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-4cbOnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:42:04 INFO - Running setup.py (path:/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/pip-aXf9x6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:42:04 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)) 12:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:42:04 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)) 12:42:04 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:42:04 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:42:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:42:04 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 12:42:04 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 12:42:04 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 12:42:04 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 12:42:04 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 12:42:04 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 12:42:07 INFO - Downloading blessings-1.6.tar.gz 12:42:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:42:07 INFO - Installing collected packages: blessings 12:42:07 INFO - Running setup.py install for blessings 12:42:07 INFO - Successfully installed blessings 12:42:07 INFO - Cleaning up... 12:42:07 INFO - Return code: 0 12:42:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:42:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:42:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:42:07 INFO - Reading from file tmpfile_stdout 12:42:07 INFO - Current package versions: 12:42:07 INFO - blessings == 1.6 12:42:07 INFO - blobuploader == 1.2.4 12:42:07 INFO - docopt == 0.6.1 12:42:07 INFO - manifestparser == 1.1 12:42:07 INFO - mozInstall == 1.12 12:42:07 INFO - mozcrash == 0.17 12:42:07 INFO - mozdebug == 0.1 12:42:07 INFO - mozdevice == 0.48 12:42:07 INFO - mozfile == 1.2 12:42:07 INFO - mozhttpd == 0.7 12:42:07 INFO - mozinfo == 0.9 12:42:07 INFO - mozleak == 0.1 12:42:07 INFO - mozlog == 3.1 12:42:07 INFO - moznetwork == 0.27 12:42:07 INFO - mozprocess == 0.22 12:42:07 INFO - mozprofile == 0.28 12:42:07 INFO - mozrunner == 6.11 12:42:07 INFO - mozscreenshot == 0.1 12:42:07 INFO - mozsystemmonitor == 0.0 12:42:07 INFO - moztest == 0.7 12:42:07 INFO - mozversion == 1.4 12:42:07 INFO - psutil == 3.1.1 12:42:07 INFO - requests == 1.2.3 12:42:07 INFO - wsgiref == 0.1.2 12:42:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:42:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:42:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:07 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 0x10205fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febcb4b0fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efee00>, '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 0x101d27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef9b20>, '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.N2OcaZYXsj/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.buHTe901oe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 12:42:07 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 12:42:07 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 12:42:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N2OcaZYXsj/Render', 12:42:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:07 INFO - 'HOME': '/Users/cltbld', 12:42:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:07 INFO - 'LOGNAME': 'cltbld', 12:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:07 INFO - 'MOZ_NO_REMOTE': '1', 12:42:07 INFO - 'NO_EM_RESTART': '1', 12:42:07 INFO - 'PAGER': '/bin/cat', 12:42:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:07 INFO - 'PWD': '/builds/slave/test', 12:42:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:07 INFO - 'SHELL': '/bin/bash', 12:42:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buHTe901oe/Listeners', 12:42:07 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 12:42:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:07 INFO - 'USER': 'cltbld', 12:42:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:07 INFO - 'XPC_FLAGS': '0x0', 12:42:07 INFO - 'XPC_SERVICE_NAME': '0', 12:42:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 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)) 12:42:08 INFO - Cleaning up... 12:42:08 INFO - Return code: 0 12:42:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:42: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:42:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10205fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febcb4b0fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efee00>, '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 0x101d27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef9b20>, '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.N2OcaZYXsj/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.buHTe901oe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 12:42: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'] in /builds/slave/test/build 12:42: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 12:42:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N2OcaZYXsj/Render', 12:42:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:08 INFO - 'HOME': '/Users/cltbld', 12:42:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:08 INFO - 'LOGNAME': 'cltbld', 12:42:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:08 INFO - 'MOZ_NO_REMOTE': '1', 12:42:08 INFO - 'NO_EM_RESTART': '1', 12:42:08 INFO - 'PAGER': '/bin/cat', 12:42:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:08 INFO - 'PWD': '/builds/slave/test', 12:42:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:08 INFO - 'SHELL': '/bin/bash', 12:42:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buHTe901oe/Listeners', 12:42:08 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 12:42:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:08 INFO - 'USER': 'cltbld', 12:42:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:08 INFO - 'XPC_FLAGS': '0x0', 12:42:08 INFO - 'XPC_SERVICE_NAME': '0', 12:42:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:08 INFO - Downloading/unpacking jsonschema 12:42: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 12:42: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 12:42: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 12:42: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 12:42: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 12:42: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 12:42:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:42:10 INFO - Downloading/unpacking functools32 (from jsonschema) 12:42:10 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 12:42:10 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 12:42:10 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 12:42:10 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 12:42:10 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 12:42:10 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 12:42:11 INFO - Downloading functools32-3.2.3-2.tar.gz 12:42:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:42:11 INFO - warning: no files found matching '*.txt' 12:42:11 INFO - no previously-included directories found matching 'build' 12:42:11 INFO - no previously-included directories found matching 'dist' 12:42:11 INFO - no previously-included directories found matching '.git*' 12:42:11 INFO - Installing collected packages: jsonschema, functools32 12:42:11 INFO - Running setup.py install for functools32 12:42:11 INFO - warning: no files found matching '*.txt' 12:42:11 INFO - no previously-included directories found matching 'build' 12:42:11 INFO - no previously-included directories found matching 'dist' 12:42:11 INFO - no previously-included directories found matching '.git*' 12:42:11 INFO - Successfully installed jsonschema functools32 12:42:11 INFO - Cleaning up... 12:42:11 INFO - Return code: 0 12:42:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:42:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:42:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:42:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:42:11 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10205fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febcb4b0fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efee00>, '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 0x101d27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef9b20>, '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.N2OcaZYXsj/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.buHTe901oe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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 12:42:11 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', 'flake8'] in /builds/slave/test/build 12:42:11 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 flake8 12:42:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N2OcaZYXsj/Render', 12:42:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:11 INFO - 'HOME': '/Users/cltbld', 12:42:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:11 INFO - 'LOGNAME': 'cltbld', 12:42:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:11 INFO - 'MOZ_NO_REMOTE': '1', 12:42:11 INFO - 'NO_EM_RESTART': '1', 12:42:11 INFO - 'PAGER': '/bin/cat', 12:42:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:11 INFO - 'PWD': '/builds/slave/test', 12:42:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:11 INFO - 'SHELL': '/bin/bash', 12:42:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buHTe901oe/Listeners', 12:42:11 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 12:42:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:11 INFO - 'USER': 'cltbld', 12:42:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:11 INFO - 'XPC_FLAGS': '0x0', 12:42:11 INFO - 'XPC_SERVICE_NAME': '0', 12:42:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:11 INFO - Downloading/unpacking flake8 12:42: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 12:42: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 12:42: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 12:42: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 12:42: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 12:42: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 12:42:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:42:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:42:14 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 12:42:14 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 12:42:14 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 12:42:14 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 12:42:14 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 12:42:14 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 12:42:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:42:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:42:14 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 12:42:14 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 12:42:14 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 12:42:14 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 12:42:14 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 12:42:14 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 12:42:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:42:14 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 12:42:14 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 12:42:14 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 12:42:14 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 12:42:14 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 12:42:14 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 12:42:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:42:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:42:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:42:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:42:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:42:15 INFO - no previously-included directories found matching 'docs/_build' 12:42:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:42:15 INFO - Running setup.py install for pep8 12:42:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:42:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:42:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:42:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:42:15 INFO - no previously-included directories found matching 'docs/_build' 12:42:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:42:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:42:15 INFO - Cleaning up... 12:42:15 INFO - Return code: 0 12:42:15 INFO - Running post-action listener: _resource_record_post_action 12:42:15 INFO - Running post-action listener: _start_resource_monitoring 12:42:15 INFO - Starting resource monitoring. 12:42:15 INFO - ##### 12:42:15 INFO - ##### Running install step. 12:42:15 INFO - ##### 12:42:15 INFO - Running pre-action listener: _resource_record_pre_action 12:42:15 INFO - Running main action method: install 12:42:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:42:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:42:16 INFO - Reading from file tmpfile_stdout 12:42:16 INFO - Detecting whether we're running mozinstall >=1.0... 12:42:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:42:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:42:16 INFO - Reading from file tmpfile_stdout 12:42:16 INFO - Output received: 12:42:16 INFO - Usage: mozinstall [options] installer 12:42:16 INFO - Options: 12:42:16 INFO - -h, --help show this help message and exit 12:42:16 INFO - -d DEST, --destination=DEST 12:42:16 INFO - Directory to install application into. [default: 12:42:16 INFO - "/builds/slave/test"] 12:42:16 INFO - --app=APP Application being installed. [default: firefox] 12:42:16 INFO - mkdir: /builds/slave/test/build/application 12:42:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:42:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:42:49 INFO - Reading from file tmpfile_stdout 12:42:49 INFO - Output received: 12:42:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:42:49 INFO - Running post-action listener: _resource_record_post_action 12:42:49 INFO - ##### 12:42:49 INFO - ##### Running run-tests step. 12:42:49 INFO - ##### 12:42:49 INFO - Running pre-action listener: _resource_record_pre_action 12:42:49 INFO - Running main action method: run_tests 12:42:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:42:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:42:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:42:49 INFO - 2016-04-15 12:42:49.598 screenresolution[1639:7559] starting screenresolution argv=screenresolution get 12:42:49 INFO - 2016-04-15 12:42:49.601 screenresolution[1639:7559] Display 0: 1600x1200x32@60 12:42:49 INFO - 2016-04-15 12:42:49.607 screenresolution[1640:7561] starting screenresolution argv=screenresolution list 12:42:49 INFO - Available Modes on Display 0 12:42:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:42:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:42:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:42:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:42:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:42:49 INFO - Intel Iris: 12:42:49 INFO - Chipset Model: Intel Iris 12:42:49 INFO - Type: GPU 12:42:49 INFO - Bus: Built-In 12:42:49 INFO - VRAM (Dynamic, Max): 1536 MB 12:42:49 INFO - Vendor: Intel (0x8086) 12:42:49 INFO - Device ID: 0x0a2e 12:42:49 INFO - Revision ID: 0x0009 12:42:49 INFO - Displays: 12:42:49 INFO - Display: 12:42:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:42:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:42:49 INFO - Display Serial Number: 1600x1200 60 12:42:49 INFO - Main Display: Yes 12:42:49 INFO - Mirror: Off 12:42:49 INFO - Online: Yes 12:42:49 INFO - Rotation: Supported 12:42:49 INFO - Return code: 0 12:42:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:42:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:42:49 INFO - Python 2.7.3 12:42:49 INFO - Return code: 0 12:42:49 INFO - grabbing minidump binary from tooltool 12:42: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:42: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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101efee00>, '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 0x101d27db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:42: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 12:42: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 12:42:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:42:49 INFO - Return code: 0 12:42:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:42:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:42:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:42:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:42:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:42:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:42:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:42:51 INFO - Return code: 0 12:42:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--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 12:42:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0022 --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 12:42:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N2OcaZYXsj/Render', 12:42:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:42:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:42:51 INFO - 'HOME': '/Users/cltbld', 12:42:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:42:51 INFO - 'LOGNAME': 'cltbld', 12:42:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:42:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:51 INFO - 'MOZ_NO_REMOTE': '1', 12:42:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:42:51 INFO - 'NO_EM_RESTART': '1', 12:42:51 INFO - 'PAGER': '/bin/cat', 12:42:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:51 INFO - 'PWD': '/builds/slave/test', 12:42:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:42:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:42:51 INFO - 'SHELL': '/bin/bash', 12:42:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.buHTe901oe/Listeners', 12:42:51 INFO - 'TMPDIR': '/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/', 12:42:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:42:51 INFO - 'USER': 'cltbld', 12:42:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:42:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:51 INFO - 'XPC_FLAGS': '0x0', 12:42:51 INFO - 'XPC_SERVICE_NAME': '0', 12:42:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:42:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--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 12:42:51 INFO - mozversion application_buildid: 20160415114641 12:42:51 INFO - mozversion application_changeset: 009d7df1ba6d97a5abd2ed417a3464a8e948742c 12:42:51 INFO - mozversion application_display_name: Nightly 12:42:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:42:51 INFO - mozversion application_name: Firefox 12:42:51 INFO - mozversion application_remotingname: firefox 12:42:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:42:51 INFO - mozversion application_vendor: Mozilla 12:42:51 INFO - mozversion application_version: 48.0a1 12:42:51 INFO - mozversion platform_buildid: 20160415114641 12:42:51 INFO - mozversion platform_changeset: 009d7df1ba6d97a5abd2ed417a3464a8e948742c 12:42:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:42:51 INFO - mozversion platform_version: 48.0a1 12:42:51 INFO - using testdate: 1460749371 12:42:51 INFO - actual date: 1460749371 12:42:51 INFO - starting webserver on 'localhost:49276' 12:42:51 INFO - SUITE-START | Running 2 tests 12:42:51 INFO - TEST-START | dromaeo_css 12:42:51 INFO - operating with platform_type : mac_ 12:42:51 INFO - Initialising browser for dromaeo_css test... 12:42:51 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/tmpMTHLUt/profile http://localhost:49276/getInfo.html) 12:42:53 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 12:42:53 INFO - PROCESS | 1658 | colorDepth:24 12:42:53 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 12:42:53 INFO - PROCESS | 1658 | __metrics 12:42:53 INFO - PROCESS | 1658 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:42:53 ERROR - PROCESS | 1658 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:42:54 INFO - TEST-INFO | 1658: exit 0 12:42:54 INFO - Browser initialized. 12:42:54 INFO - Running cycle 1/1 for dromaeo_css test... 12:42:54 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/tmpMTHLUt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:43:06 INFO - PROCESS | 1660 | RSS: Main: 238845952 12:43:06 INFO - PROCESS | 1660 | 12:43:58 INFO - PROCESS | 1660 | 1460749438018 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:46:08 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 12:46:09 INFO - PROCESS | 1660 | RSS: Main: 320241664 12:46:09 INFO - PROCESS | 1660 | 12:49:12 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 12:49:13 INFO - PROCESS | 1660 | RSS: Main: 371990528 12:49:13 INFO - PROCESS | 1660 | 12:52:15 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 12:52:16 INFO - PROCESS | 1660 | RSS: Main: 376745984 12:52:16 INFO - PROCESS | 1660 | 12:55:18 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 12:55:18 INFO - PROCESS | 1660 | RSS: Main: 373125120 12:55:18 INFO - PROCESS | 1660 | 12:58:21 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 12:58:22 INFO - PROCESS | 1660 | RSS: Main: 336035840 12:58:22 INFO - PROCESS | 1660 | 13:01:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 13:01:25 INFO - PROCESS | 1660 | RSS: Main: 414162944 13:01:25 INFO - PROCESS | 1660 | 13:01:25 INFO - PROCESS | 1660 | __start_tp_report 13:01:25 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 13:01:25 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 13:01:25 INFO - PROCESS | 1660 | |i|pagename|runs| 13:01:25 INFO - PROCESS | 1660 | |0;dojo.html;3165.3985742708887;3416.5729283839505;3423.229773301006;3435.604905435875;3495.2834668892865;5502.0921547944645;5584.804531841386;5594.049011668021;5602.971985140074;5606.83179504615;5554.389017208104;5604.299462567179;5614.410486898868;5616.606837521375;5616.9157462638095;5101.846944591659;5112.642115051929;5136.332276804029;5145.974270128644;5193.792248310063;5203.895922081556;5213.661112027725;5215.426303106655;5219.582433405319;5230.137027390495;5119.974400127994;5161.071007218699;5176.55999240065;5212.192110222922;5256.605754568403;5188.818391356303;5228.032813929435;5241.68549887008;5245.86885327866;5297.337832770903;5032.597392208614;5058.721770302632;5077.416097148838;5147.408048074444;5172.301739265189;5410.972945135289;5432.565394768409;5441.700706461143;5479.232907392969;5513.7794488220425;6059.242594675666;6133.233345831771;6137.631742095488;6138.294096178952;6160.383961603849;6029.939700602981;6118.632882027093;6141.884937092159;6213.937860621381;6221.191245138149;4731.503192164842;5708.343540492813;5710.143478478179;5721.742521586497;5741.024025915521;6284.5600807943;6285.874282514415;6287.528435367366;6303.149074874925;6326.335734747785;5916.171735956969;6007.609505382136;6029.427204415573;6065.787697430569;6074.848128796727;6158.969205153946;6174.938250617526;6178.135061091451;6180.134781130645;6189.690515474208;6107.694615269219;6108.17539632144;6109.2974307955155;6113.969430152821;6118.514459361828;5973.372795856372;6116.174316467309;6142.907856382158;6149.2313460817395;6153.138560601519;4861.80552777892;4900.191468407673;4913.459519452857;4913.992631510603;4924.827631032968;4370.584794444522;4381.452318460192;4381.846635367747;4421.46942366918;4423.62399196072;5461.1262198048125;5540.36285827135;5556.888862222814;5560.026995275811;5569.415211402826;6110.771402862501;6192.59748116371;6209.627422354582;6230.688465576703;6258.123862659231;5548.278723765885;5556.555475561945;5558.499735023867;5569.997400467954;5584.860378490489;4269.658427325869;5078.289039534468;5190.2214667800135;5331.573474122061;5352.625316227826;4896.922677010981;6233.906491402633;6280.780172694116;6286.465650419766;6292.993121100602;6000.069989151689;6168.290646575521;6210.658413787104;6215.595986260879;6227.875442491216;6014.097885317063;6206.751729930933;6263.655498947602;6271.717772700331;6314.557980941474;6036.7585296589405;6071.605345652519;6103.3591472894705;6121.112438696453;6128.938710612837;6083.726232319467;6164.938350616616;6170.259568851587;6190.907136393048;6200.596961197508;5795.072788353843;5933;5976.820695379146;5993.160957466045;6020.217371741645;5865.589408741347;5974.074018527135;6027.945109605889;6032.306284777307;6046.7278972445465;5849.766009359578;5859.414058594107;5873.500752436091;5874.295084589706;5877.853053673693;5756.481916627524;5911.55663325261;6025.337212906489;6035.879282414415;6081.087836824512;5887.028640274307;5897.469227769349;5916.171735957056;5932.199153114447;5935.34711181761 13:01:25 INFO - PROCESS | 1660 | |1;ext.html;493.8617187187589;514.6628958032491;533.0458479322014;584;596.9134475501053;30126.84936575317;30590.847045764767;37602.81198594007;38455;38587.80706096465;40171.799141004325;40651.796741016326;40753.38869916953;40834.795826020905;40912;9318.906810931889;9562.952185239099;9563.80872382552;9566.473843938615;9572.090651388107;8503.404761666687;8929.330300227477;9579;11861.347625880573;11876.46555904982;11379.431028448544;11475;11494.310341379543;11502.71243218922;11512.65462036136;8495.74512764618;11394.202405831595;11461.426928653618;11474.827877581843;11514.481848316846;11355.886441135566;11503.539858405653;11509.366984815832;11510.424478776027;11529.827052594133;31345.216369590715;38046.23907521862;38484.42273365902;38558.61441385578;38629.42055869164;3177.8411079445937;3188.21888637283;3368.5789276340456;3757.4927384802977;3955.11642917431;27779.58330625042;28058.158255252172;28155;28338.716612833814;29155.41689166226;2692.8653567321558;2732.2076597786618;2773.1126039667315;2819.84490853003;2900;337042;337046;337405;337626;337755;59458.81082378328;59484;59898.70050649719;60019;60109.69945150246;63456.682716586125;66546.66726666335;68321;68370.3162968374;68386;16316.42892498757;16524.421645242357;16557.503274901774;16581.336746530244;16673.416430424877;11835.349055801842;12130.514779408724;12131.090168237417;12833.230006199472;12936.935315323552;16801.663966720596;16987.89578677382;19021.524461888563;19447.656385697457;19961.401157965283;6816.829579260559;7965.6017199139815;8026.5986700664735;8096.878546821802;8168.264856162974;816.608028146493;992.9056739609725;1002.2733518199393;1021.4177918586334;1091.792559413709;23361.41596460068;29297;29569.85215073911;29779.553306700418;29956.85021574922;1100.301308668985;1114.428257434919;1116.7654792493504;1149.4884776274646;1223.3577371879655;15143.404969241823;15210.239488025776;15372.495392116069;15406.383744650313;15419.383224671112;179902;239727;240745;240919.79540102187;241350;46842.06315873664;56414;58760;58840.70579647074;58952;1312.1012106706937;1354.5597680753795;1433.2976841347872;1472.3374481483374;1488.2038109611306;28591.85704071466;28715.712842871304;28751.856240718662;29308.560371594016;29502;45484;45628.771856140505;46148.76925615351;46236.7688161557;46240.30639540477;339839;341114.29442852625;341706;342286.28856855555;342305;23246.186383476495;25721.971121154944;26529.469410612066;26829.926802927665;26861.597076043483;43049.78475107605;44456.77771611121;46364.6090617282;47186.05627887354;47546.28680569863;36812.63187368092;36831.8158409217;36907.8154609236;37582.68460603975;37710;35978.64021359858;36106.8194659025;36314.4552831713;36589;36919.63080369269;36152;36526.452103219;36611.338866113176;37016.25967480689;37085.81457092805;31640.050798476084;32678.673213268514;32732;32774.836125819216;33040.50439243461;23886.473792035315;24434.87782561147;24537.023230846742;24739.62890556607;24825.25524234276 13:01:25 INFO - PROCESS | 1660 | |2;jquery.html;394.41626392938446;396.3005295653171;400.7595442734358;400.9599040095989;411.0894368972725;14362.497312594036;19806.7028994565;20814.6877796833;20829.27097551586;21064;15986;19780.802191978077;20020.899895500537;20261.898690506565;20704.68942965853;6047.909281360783;6268.091126786613;6270.216222972129;6272.184615999914;6275.874482510347;5705.229793977822;6116.327204007578;6117.296510901237;6192.071189321615;6245.656488893109;3893.649571538561;4148.522919864224;5828.125781132842;6185.969070154643;6188.721507532149;6082.9695851520455;6165.013597824349;6169.01295792671;6171.105189747484;6187.907181392237;5704.743286552115;5911.349751527328;5911.881762364728;5918.822435326904;5920;13067.346632668328;15738.842611573853;24530.01879924801;24985;25041.248762537154;2134.5944270588693;2159.719236499245;2167.5664867026658;2206.205765924266;2247.5954328220914;9459.858102128474;9784.266180036526;9918.950405248;10151.137153341975;12461.501539938392;1725.8015328237163;1750.7461418094433;1817.2458429751637;1842.253887175696;1946.805319468042;117658;122748.99999999911;123140.1528977066;124747;126555.73444265623;11576;11747.588834390755;11762.000229980547;11833.171677982458;11903.34531600753;14968;15042;15050.924745376422;15086.924565377103;15087.698246035017;8669.739907802776;8733.51965641883;8748.212660860427;8778.60496277669;8813.735587932373;8575.742727718178;8599.656013759504;8613.913860861436;8708.25979791712;8722.127787221227;4603.700759450625;4605.194091034055;4609.953900460952;4611.100835337144;4677.415323084614;1602.5352647732027;1643.4001589420016;1783.6254386578964;1966.3412756726657;1970.7438033055612;690.6270613868555;737.8413641067089;752.5296689569019;816.3673153306116;983.9458829764432;20175.39473815788;20401.591968160552;21427.78572214289;21482.24812131568;21511.892440537697;928.456852741154;948.9335746497679;953.275510611926;972.6838777397375;1013.1185868294631;10364.896351036545;10791.730206744736;10807.621733239299;10813.567457301777;10853.728656783644;633697;641415;646229;665510;666618;102560;118155;121901;123908.38045809713;126436.36781816032;1017.4810846468355;1033.7002269341956;1062.5218651606808;1107.800595892747;1113.2485572238706;14355.856441435451;17893.552661183574;18118.909405452887;18322.083895804888;18376.908115459337;50788.49211507838;51132.977340453726;52312.69218269574;52645.47354526406;52684.20973685475;73947.63026184835;74259.62870185614;75927;76772;76848.23151768411;7614.695412183451;7634.274743899321;7793.454458187873;8096.595170241582;8190.795230119056;10017.647617571534;11294.887051129384;11596.420178991186;11608;12049.879501204876;8992.955035224782;10330.173586112942;10822.783544328911;10862.945685271523;10866.782664346827;10274.486275686335;10446.477676116012;10646.20153488476;10699.839502407314;10740.677779666623;10292.794144116926;10491.318064325795;10971.23201375896;11033.779324413626;11398.94300528492;7786;8043.718469853527;8044.557549334842;8151.062627797879;8406.243438090602;10660.360378377321;10997.230193886671;11049.723756906142;11080.889191107986;11108.666739997812 13:01:25 INFO - PROCESS | 1660 | |3;mootools.html;1414.250447262951;1579.7472404415296;1603.5830684022155;1824.5985883105711;1941.7087436884465;7729.227077292268;8437.957810210948;8658.523781192032;8666.220040196382;8668.653253869854;8319.417640765134;8510.489370637766;8525.062243153263;8530.57347132644;8535.48787072776;2434.646976188456;2461.1386014894824;2467.0501856785177;2467.814913881457;2468.7407822178684;2379.6192609182535;2417.758224177577;2429.4776623026046;2430.2344761400104;2437.475942672325;2401;2419.1412048722714;2427.417419819248;2439.2072576412647;2440.2923152285903;1873.7539536208405;1995.860289779716;2351.1300818697105;2377.8335516513853;2413.384586930339;2372.9644055339186;2403.435192729707;2408.8313818032743;2409.325388891113;2410.626352915301;8400.831983360298;8495.702650407206;8653.394262401634;8684;8684.913150868473;5216.2958000669805;5395.487428694268;5401.513863752242;5422.319081084876;5449.727513624303;2903.5209190483547;3524.947125793115;3648.1426864686778;3651.6896063834606;3652.5982141964364;1051.458498873081;1059.5602824827688;1063.4682658670665;1069.0859315285475;1069.4118234970813;11251.212415130944;11407.942960285229;11413.54345826166;11420.200585958986;11440.141989350832;4410.360497727812;4411.955880441219;4570.908581828345;4606.216943119678;4650.069986002813;8905.975812781613;8917.554122293988;8918.821623567492;8929.973053098976;8935.508547029845;5220.738963051832;5241.711705856138;5249.763760630781;5254.632175747737;5270.130428479258;4211.494620645542;4378;4550.453945526558;4794.256890182028;4920.917398172396;3731.440283957428;3748.9437658435145;3782.1868298315994;3817.9427308590393;3829.463875057494;1183.627157445402;1191.434068817305;1195.6445801770499;1202.2666173634077;1208.6132437620054;907.5462268865567;924.9398789078823;928.1785619726455;938.5742041548267;960.7886265021684;5662.546996240291;5843.620164689367;5897.5871688982215;5900.439458251545;5970.283565972111;439.48141193391467;450.25707582489156;460.36009946174846;471.6274143426723;477.36033714822304;3838.9616103838603;3913.2760439318818;3913.6477717005037;3983.701222408331;4009.799510024487;10154.39073655583;10558.944105589533;10712.089472394933;10724.034836864563;10727.141828653845;10592.311499752492;10662.306950048222;10684.94657526723;10690.572377104829;10692.893071069346;7953.164917683675;8089.39329550286;8093.757187284391;8238.340932725368;8239.505629662359;5653.915191272216;5719.5138413235345;5729.656220626775;5818.040023396092;5825.76696932118;9581.616735330508;9609.231261499202;9769.511524423608;9888.406695598287;9888.505574721092;9856.457894815854;9959.900400995897;10061.24540659468;10149.64476243328;10195.84706229422;6115.663638499926;6360.49116070704;6600.867982640416;6946.37482626566;6964.26875178099;8202.26179643835;8214.671413143407;8291.917080829115;8293.751187464386;8297.543635099886;7139.785806425815;7184.425245980205;7184.8445466358835;7332.96333518329;7356.080489938758;5286.39206491243;7097.645117744195;7104.573725576482;7140.642967851691;7248.347648711641;6505.316941721051;6582.868342633216;6777.6272305023695;6780.457563394818;6782.355676210752;4765.309030190534;4766.642501812447;4771.6898401603785;4825.300331451849;4865.367502224688;4835.274708793569;4852.102361063215;4920.21276595743;4931.950680493149;5075.5178258065425 13:01:25 INFO - PROCESS | 1660 | |4;prototype.html;314.83156511266463;323;332.6457322951057;332.682288414564;336.122719701579;5668.489835914766;5826.679532625709;5864.648121112737;5873.471387575123;5910.408959104098;5342.626016178869;5706.115552089423;5836.970815145929;5855.677937713434;5861.17943487912;4473.865784026484;4477.992451698358;4500.5499450054895;4513.961788788581;4525.275955847057;3797.582265950743;4232.280512312899;4510.278355463127;4512.187806194884;4522.253828118356;4389.5390983946545;4432.072048658828;4437.64498840092;4451;4455.576720211575;4456.955430445702;4457.777111144446;4482.731036137842;4508.436445444319;4532.841350552715;3642.1805093853936;3767.4913886625227;3799.4680744695765;3816;3840.423936409533;4826.686265392738;5579.832605021856;5696.205695205997;5696.629719068247;5712.1431785232135;5054.064997975386;5063.772130254148;5065.189569668835;5082.949170508285;5096.566791822699;4879.487653796371;4920.704757714549;5703.572232082569;6229.37706229378;6241.285041007176;3823.751456155341;3847.8076096195077;4008.4588580541545;4153.833846646131;4158.91682166358;5729.770809167629;5883.8529036774;6545.770898018594;6612;6615.93384066158;3600.8199590020395;4238.576142385798;4246.320588705854;4268.210381079511;4271.295236285978;3616.294822509638;4290.37789520524;4294.377315289265;4311.590398912161;4331.133773245364;4239.830406783694;5045.924311135336;5053.646244762831;5063.898722025612;5083.466236045236;4974.77613507393;5936.317323507852;6034.698265086816;6229.314775374705;6369.20384951881;1412.2303344677175;1431.8281806183325;1439.7696368580978;1440.51742666208;1450.9056911300731;941.5574679900436;977.9217662586976;978.2760757039739;979.1823827104448;989.3618615992644;4663.790129444184;4858.46556878743;5764;5786.399812149351;5819;6447.2907980122145;6458.3208365824285;6472.449841763503;6488.124103246096;6507.934920650828;4827.324174615556;5752.568557358215;5790.276215473066;5797.159411885253;5819.421056050498;2948.1597744642672;3269.54226408303;3394.2702319001532;3406.4379377402947;3419.5554577904713;6694.732210711614;6721.495887808336;6744.258131605519;6750.966245168743;6761.526693131434;5032.0690672225755;6484.735476582116;6597.670116494156;6628.8674226515195;6631.30371311015;6477.740890364427;6689.896167132369;6689.899651505231;6713.798586042427;6731.596104233761;6191.473724733448;6338.84479277048;6393.7122829471855;6406.391392817862;6452.903206451813;6282.7800477527835;6287.371262873676;6320.146780184617;6332.208473940757;6341.524385671185;5416.7020813855615;5977.282726072899;6361.823062733409;6662.467002639874;6700.664966751546;4075.3071977764444;4216.641585465147;4229.725067870579;4238.89402764921;4246.256905041667;6387.297397286202;6397.712102955285;6410.743190583677;6413.422791948747;6439.774607888889;5861.677607731615;6145.201123853871;6209.187879393507;6223.097650840693;6230.252369715663;5474.479924407175;5865.853353666193;5888.4700376966275;6037.124616930432;6179.62922224668;6150.815475535741;6174.691265436621;6189.226346706661;6190;6195.2875419325555;5929.792457264148;6262.780802672067;6291.182146321132;6321.241451025906;6360.745570177141;5187.403448603308;6160.630362178284;6180.505559555135;6218.9378106218355;6224.844378890564 13:01:25 INFO - PROCESS | 1660 | |5;yui.html;612.5865041097259;693.7051753004972;711.4237467651207;733.4902242941155;751.7594369801664;14865.702685946275;15265.847341526582;15529.22353882307;15538;15540.378384864593;13844.653883652914;14148.50480233192;14200.147991120513;14284.857151428483;14303.642408939782;924.3067699225597;936.2089034765646;937.4328531238605;942.2462030375706;942.519315149272;762.4586543554083;775.8138046868739;796.8764841449585;894.7092195036606;930.2743859789375;971.8639390485303;972.2125078686252;974.0551664885086;975.878015248094;975.9316847820655;774.4888373673405;937.5874615169304;957.8419560772464;964.8456247000481;965.2133511188395;720.7045111504258;721.0842230367404;724.9311315425026;726.963651817407;727.0838743183626;15150.242487875563;15151.242437878172;15172.848271517254;15195;15248.618784530365;12419.130660853743;12669.493220271179;12675;12868.09923305369;13029.022823288291;9829.55766990487;10196.694099177037;10377.169826413945;10507.73730656732;10926.945365273123;3554.111472131967;3573.714102871764;3635.2911182319194;3645.052286405527;3650;87848.56075719581;88714.55642721809;89227.66158507628;89581;90838.09161908363;9538.856917146248;9743.12311891919;9848.803023939625;10045.19638428924;10098.14165795901;21889.796061216468;21925.671114933288;21960.89019554892;21968.231111911005;21972.79149646753;7297;7418.7032518699725;7471.514351567131;7510.078633297444;7529.924700752922;4050.1899620075515;4112.670986321087;4119.814608342632;4121.5466298707115;4122.381642753551;3555.377808883435;3617.692496137805;3661.139632186434;3661.1945372018113;3665.8533658653355;627.8524546731514;637.3339859846471;645.3127419298432;653.4804830160034;655.7540922154192;400.679456434851;532.3000254665103;606.8469907176312;610.2737742086991;613.957023008394;7665.961670191613;7884.566348850868;7964.402669799788;8125.593720313961;8196.754097376968;511.72878374461595;538.7252501224325;540.2058973309229;574.8131857146444;577.8295402856168;3749.4563288323584;3795.6204379561823;3798.582155962842;3808.2383523295453;3843.1160833008566;83886;84662.30675386458;85954;86286.56856715801;87859;73900.63049684717;74029.62985185147;74587.88118178232;74747;75718.48563028709;67271.66364168147;67385;67747.66126169337;68094.63810723857;68879.44531424798;7174.067371241706;7364.447666425147;7505.812354691177;7581.50720203185;7623.237676232332;27600.723992760617;40127;40210.597894021856;40310.596894031856;40911;56578;57560.71219643875;57849.421505784405;58041.709791450776;58394.4160558389;10158;10169.796604068026;10313.74215644615;10412.271141020055;10879.728006799576;10894.346339219672;10940.835887461853;11029.283096598696;11066.502007409525;11257.662270131908;10510.894891050992;10559.04968552834;10610.045095941094;10640.255182137485;10712.89287107119;10066.798664026825;11431.142664300378;11633;11739.589114380955;11786.941065294619;10862.565497380332;10922.453877305945;11196.496157672762;11227.27022743519;11770.058395328524;9195.494247816434;9462.716118516455;9485.905140948502;9529.809403812023;9878.950605246928;8242.464239824392;9013.729588112366;9583.12506248109;9596.712098637052;9835.901640983499 13:01:25 INFO - PROCESS | 1660 | __end_tp_report 13:01:25 INFO - PROCESS | 1660 | __start_cc_report 13:01:25 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1220 13:01:25 INFO - PROCESS | 1660 | __end_cc_report 13:01:25 INFO - PROCESS | 1660 | __startTimestamp1460750485762__endTimestamp 13:01:25 INFO - PROCESS | 1660 | 13:01:25 INFO - PROCESS | 1660 | ------- Summary: start ------- 13:01:25 INFO - PROCESS | 1660 | Number of tests: 6 13:01:25 INFO - PROCESS | 1660 | 13:01:25 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5647.17 Median:5874.30 stddev:633.27 (10.8%) stddev-sans-first:604.55 13:01:25 INFO - PROCESS | 1660 | Values: 3165.4 3416.6 3423.2 3435.6 3495.3 5502.1 5584.8 5594.0 5603.0 5606.8 5554.4 5604.3 5614.4 5616.6 5616.9 5101.8 5112.6 5136.3 5146.0 5193.8 5203.9 5213.7 5215.4 5219.6 5230.1 5120.0 5161.1 5176.6 5212.2 5256.6 5188.8 5228.0 5241.7 5245.9 5297.3 5032.6 5058.7 5077.4 5147.4 5172.3 5411.0 5432.6 5441.7 5479.2 5513.8 6059.2 6133.2 6137.6 6138.3 6160.4 6029.9 6118.6 6141.9 6213.9 6221.2 4731.5 5708.3 5710.1 5721.7 5741.0 6284.6 6285.9 6287.5 6303.1 6326.3 5916.2 6007.6 6029.4 6065.8 6074.8 6159.0 6174.9 6178.1 6180.1 6189.7 6107.7 6108.2 6109.3 6114.0 6118.5 5973.4 6116.2 6142.9 6149.2 6153.1 4861.8 4900.2 4913.5 4914.0 4924.8 4370.6 4381.5 4381.8 4421.5 4423.6 5461.1 5540.4 5556.9 5560.0 5569.4 6110.8 6192.6 6209.6 6230.7 6258.1 5548.3 5556.6 5558.5 5570.0 5584.9 4269.7 5078.3 5190.2 5331.6 5352.6 4896.9 6233.9 6280.8 6286.5 6293.0 6000.1 6168.3 6210.7 6215.6 6227.9 6014.1 6206.8 6263.7 6271.7 6314.6 6036.8 6071.6 6103.4 6121.1 6128.9 6083.7 6164.9 6170.3 6190.9 6200.6 5795.1 5933.0 5976.8 5993.2 6020.2 5865.6 5974.1 6027.9 6032.3 6046.7 5849.8 5859.4 5873.5 5874.3 5877.9 5756.5 5911.6 6025.3 6035.9 6081.1 5887.0 5897.5 5916.2 5932.2 5935.3 13:01:25 INFO - PROCESS | 1660 | 13:01:25 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:47434.77 Median:32705.34 stddev:80576.20 (246.4%) stddev-sans-first:80725.15 13:01:25 INFO - PROCESS | 1660 | Values: 493.9 514.7 533.0 584.0 596.9 30126.8 30590.8 37602.8 38455.0 38587.8 40171.8 40651.8 40753.4 40834.8 40912.0 9318.9 9563.0 9563.8 9566.5 9572.1 8503.4 8929.3 9579.0 11861.3 11876.5 11379.4 11475.0 11494.3 11502.7 11512.7 8495.7 11394.2 11461.4 11474.8 11514.5 11355.9 11503.5 11509.4 11510.4 11529.8 31345.2 38046.2 38484.4 38558.6 38629.4 3177.8 3188.2 3368.6 3757.5 3955.1 27779.6 28058.2 28155.0 28338.7 29155.4 2692.9 2732.2 2773.1 2819.8 2900.0 337042.0 337046.0 337405.0 337626.0 337755.0 59458.8 59484.0 59898.7 60019.0 60109.7 63456.7 66546.7 68321.0 68370.3 68386.0 16316.4 16524.4 16557.5 16581.3 16673.4 11835.3 12130.5 12131.1 12833.2 12936.9 16801.7 16987.9 19021.5 19447.7 19961.4 6816.8 7965.6 8026.6 8096.9 8168.3 816.6 992.9 1002.3 1021.4 1091.8 23361.4 29297.0 29569.9 29779.6 29956.9 1100.3 1114.4 1116.8 1149.5 1223.4 15143.4 15210.2 15372.5 15406.4 15419.4 179902.0 239727.0 240745.0 240919.8 241350.0 46842.1 56414.0 58760.0 58840.7 58952.0 1312.1 1354.6 1433.3 1472.3 1488.2 28591.9 28715.7 28751.9 29308.6 29502.0 45484.0 45628.8 46148.8 46236.8 46240.3 339839.0 341114.3 341706.0 342286.3 342305.0 23246.2 25722.0 26529.5 26829.9 26861.6 43049.8 44456.8 46364.6 47186.1 47546.3 36812.6 36831.8 36907.8 37582.7 37710.0 35978.6 36106.8 36314.5 36589.0 36919.6 36152.0 36526.5 36611.3 37016.3 37085.8 31640.1 32678.7 32732.0 32774.8 33040.5 23886.5 24434.9 24537.0 24739.6 24825.3 13:01:25 INFO - PROCESS | 1660 | 13:01:25 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:36001.61 Median:2163.64 stddev:108082.98 (4995.4%) stddev-sans-first:108353.11 13:01:25 INFO - PROCESS | 1660 | Values: 394.4 396.3 400.8 401.0 411.1 14362.5 19806.7 20814.7 20829.3 21064.0 15986.0 19780.8 20020.9 20261.9 20704.7 6047.9 6268.1 6270.2 6272.2 6275.9 5705.2 6116.3 6117.3 6192.1 6245.7 3893.6 4148.5 5828.1 6186.0 6188.7 6083.0 6165.0 6169.0 6171.1 6187.9 5704.7 5911.3 5911.9 5918.8 5920.0 13067.3 15738.8 24530.0 24985.0 25041.2 2134.6 2159.7 2167.6 2206.2 2247.6 9459.9 9784.3 9919.0 10151.1 12461.5 1725.8 1750.7 1817.2 1842.3 1946.8 117658.0 122749.0 123140.2 124747.0 126555.7 11576.0 11747.6 11762.0 11833.2 11903.3 14968.0 15042.0 15050.9 15086.9 15087.7 8669.7 8733.5 8748.2 8778.6 8813.7 8575.7 8599.7 8613.9 8708.3 8722.1 4603.7 4605.2 4610.0 4611.1 4677.4 1602.5 1643.4 1783.6 1966.3 1970.7 690.6 737.8 752.5 816.4 983.9 20175.4 20401.6 21427.8 21482.2 21511.9 928.5 948.9 953.3 972.7 1013.1 10364.9 10791.7 10807.6 10813.6 10853.7 633697.0 641415.0 646229.0 665510.0 666618.0 102560.0 118155.0 121901.0 123908.4 126436.4 1017.5 1033.7 1062.5 1107.8 1113.2 14355.9 17893.6 18118.9 18322.1 18376.9 50788.5 51133.0 52312.7 52645.5 52684.2 73947.6 74259.6 75927.0 76772.0 76848.2 7614.7 7634.3 7793.5 8096.6 8190.8 10017.6 11294.9 11596.4 11608.0 12049.9 8993.0 10330.2 10822.8 10862.9 10866.8 10274.5 10446.5 10646.2 10699.8 10740.7 10292.8 10491.3 10971.2 11033.8 11398.9 7786.0 8043.7 8044.6 8151.1 8406.2 10660.4 10997.2 11049.7 11080.9 11108.7 13:01:25 INFO - PROCESS | 1660 | 13:01:25 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5555.40 Median:4830.29 stddev:3115.88 (64.5%) stddev-sans-first:3109.08 13:01:25 INFO - PROCESS | 1660 | Values: 1414.3 1579.7 1603.6 1824.6 1941.7 7729.2 8438.0 8658.5 8666.2 8668.7 8319.4 8510.5 8525.1 8530.6 8535.5 2434.6 2461.1 2467.1 2467.8 2468.7 2379.6 2417.8 2429.5 2430.2 2437.5 2401.0 2419.1 2427.4 2439.2 2440.3 1873.8 1995.9 2351.1 2377.8 2413.4 2373.0 2403.4 2408.8 2409.3 2410.6 8400.8 8495.7 8653.4 8684.0 8684.9 5216.3 5395.5 5401.5 5422.3 5449.7 2903.5 3524.9 3648.1 3651.7 3652.6 1051.5 1059.6 1063.5 1069.1 1069.4 11251.2 11407.9 11413.5 11420.2 11440.1 4410.4 4412.0 4570.9 4606.2 4650.1 8906.0 8917.6 8918.8 8930.0 8935.5 5220.7 5241.7 5249.8 5254.6 5270.1 4211.5 4378.0 4550.5 4794.3 4920.9 3731.4 3748.9 3782.2 3817.9 3829.5 1183.6 1191.4 1195.6 1202.3 1208.6 907.5 924.9 928.2 938.6 960.8 5662.5 5843.6 5897.6 5900.4 5970.3 439.5 450.3 460.4 471.6 477.4 3839.0 3913.3 3913.6 3983.7 4009.8 10154.4 10558.9 10712.1 10724.0 10727.1 10592.3 10662.3 10684.9 10690.6 10692.9 7953.2 8089.4 8093.8 8238.3 8239.5 5653.9 5719.5 5729.7 5818.0 5825.8 9581.6 9609.2 9769.5 9888.4 9888.5 9856.5 9959.9 10061.2 10149.6 10195.8 6115.7 6360.5 6600.9 6946.4 6964.3 8202.3 8214.7 8291.9 8293.8 8297.5 7139.8 7184.4 7184.8 7333.0 7356.1 5286.4 7097.6 7104.6 7140.6 7248.3 6505.3 6582.9 6777.6 6780.5 6782.4 4765.3 4766.6 4771.7 4825.3 4865.4 4835.3 4852.1 4920.2 4932.0 5075.5 13:01:25 INFO - PROCESS | 1660 | 13:01:25 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:5046.71 Median:5758.28 stddev:1581.24 (27.5%) stddev-sans-first:1545.27 13:01:25 INFO - PROCESS | 1660 | Values: 314.8 323.0 332.6 332.7 336.1 5668.5 5826.7 5864.6 5873.5 5910.4 5342.6 5706.1 5837.0 5855.7 5861.2 4473.9 4478.0 4500.5 4514.0 4525.3 3797.6 4232.3 4510.3 4512.2 4522.3 4389.5 4432.1 4437.6 4451.0 4455.6 4457.0 4457.8 4482.7 4508.4 4532.8 3642.2 3767.5 3799.5 3816.0 3840.4 4826.7 5579.8 5696.2 5696.6 5712.1 5054.1 5063.8 5065.2 5082.9 5096.6 4879.5 4920.7 5703.6 6229.4 6241.3 3823.8 3847.8 4008.5 4153.8 4158.9 5729.8 5883.9 6545.8 6612.0 6615.9 3600.8 4238.6 4246.3 4268.2 4271.3 3616.3 4290.4 4294.4 4311.6 4331.1 4239.8 5045.9 5053.6 5063.9 5083.5 4974.8 5936.3 6034.7 6229.3 6369.2 1412.2 1431.8 1439.8 1440.5 1450.9 941.6 977.9 978.3 979.2 989.4 4663.8 4858.5 5764.0 5786.4 5819.0 6447.3 6458.3 6472.4 6488.1 6507.9 4827.3 5752.6 5790.3 5797.2 5819.4 2948.2 3269.5 3394.3 3406.4 3419.6 6694.7 6721.5 6744.3 6751.0 6761.5 5032.1 6484.7 6597.7 6628.9 6631.3 6477.7 6689.9 6689.9 6713.8 6731.6 6191.5 6338.8 6393.7 6406.4 6452.9 6282.8 6287.4 6320.1 6332.2 6341.5 5416.7 5977.3 6361.8 6662.5 6700.7 4075.3 4216.6 4229.7 4238.9 4246.3 6387.3 6397.7 6410.7 6413.4 6439.8 5861.7 6145.2 6209.2 6223.1 6230.3 5474.5 5865.9 5888.5 6037.1 6179.6 6150.8 6174.7 6189.2 6190.0 6195.3 5929.8 6262.8 6291.2 6321.2 6360.7 5187.4 6160.6 6180.5 6218.9 6224.8 13:01:25 INFO - PROCESS | 1660 | 13:01:25 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:17519.82 Median:57945.57 stddev:24648.39 (42.5%) stddev-sans-first:24684.84 13:01:25 INFO - PROCESS | 1660 | Values: 612.6 693.7 711.4 733.5 751.8 14865.7 15265.8 15529.2 15538.0 15540.4 13844.7 14148.5 14200.1 14284.9 14303.6 924.3 936.2 937.4 942.2 942.5 762.5 775.8 796.9 894.7 930.3 971.9 972.2 974.1 975.9 975.9 774.5 937.6 957.8 964.8 965.2 720.7 721.1 724.9 727.0 727.1 15150.2 15151.2 15172.8 15195.0 15248.6 12419.1 12669.5 12675.0 12868.1 13029.0 9829.6 10196.7 10377.2 10507.7 10926.9 3554.1 3573.7 3635.3 3645.1 3650.0 87848.6 88714.6 89227.7 89581.0 90838.1 9538.9 9743.1 9848.8 10045.2 10098.1 21889.8 21925.7 21960.9 21968.2 21972.8 7297.0 7418.7 7471.5 7510.1 7529.9 4050.2 4112.7 4119.8 4121.5 4122.4 3555.4 3617.7 3661.1 3661.2 3665.9 627.9 637.3 645.3 653.5 655.8 400.7 532.3 606.8 610.3 614.0 7666.0 7884.6 7964.4 8125.6 8196.8 511.7 538.7 540.2 574.8 577.8 3749.5 3795.6 3798.6 3808.2 3843.1 83886.0 84662.3 85954.0 86286.6 87859.0 73900.6 74029.6 74587.9 74747.0 75718.5 67271.7 67385.0 67747.7 68094.6 68879.4 7174.1 7364.4 7505.8 7581.5 7623.2 27600.7 40127.0 40210.6 40310.6 40911.0 56578.0 57560.7 57849.4 58041.7 58394.4 10158.0 10169.8 10313.7 10412.3 10879.7 10894.3 10940.8 11029.3 11066.5 11257.7 10510.9 10559.0 10610.0 10640.3 10712.9 10066.8 11431.1 11633.0 11739.6 11786.9 10862.6 10922.5 11196.5 11227.3 11770.1 9195.5 9462.7 9485.9 9529.8 9879.0 8242.5 9013.7 9583.1 9596.7 9835.9 13:01:25 INFO - PROCESS | 1660 | -------- Summary: end -------- 13:01:25 INFO - PROCESS | 1660 | 13:01:26 INFO - TEST-INFO | 1660: exit 0 13:01:26 INFO - TEST-OK | dromaeo_css | took 1115008ms 13:01:26 INFO - TEST-START | kraken 13:01:26 INFO - operating with platform_type : mac_ 13:01:26 INFO - Initialising browser for kraken test... 13:01:26 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/tmpFIlKw1/profile http://localhost:49276/getInfo.html) 13:01:28 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 13:01:28 INFO - PROCESS | 1666 | colorDepth:24 13:01:28 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 13:01:28 INFO - PROCESS | 1666 | __metrics 13:01:28 INFO - PROCESS | 1666 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:01:28 ERROR - PROCESS | 1666 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:01:29 INFO - TEST-INFO | 1666: exit 0 13:01:29 INFO - Browser initialized. 13:01:29 INFO - Running cycle 1/1 for kraken test... 13:01:29 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/T/tmpFIlKw1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:01:41 INFO - PROCESS | 1668 | RSS: Main: 239046656 13:01:41 INFO - PROCESS | 1668 | 13:01:43 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 13:01:44 INFO - PROCESS | 1668 | RSS: Main: 286908416 13:01:44 INFO - PROCESS | 1668 | 13:01:47 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 13:01:47 INFO - PROCESS | 1668 | RSS: Main: 299737088 13:01:47 INFO - PROCESS | 1668 | 13:01:51 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 13:01:51 INFO - PROCESS | 1668 | RSS: Main: 387092480 13:01:51 INFO - PROCESS | 1668 | 13:01:53 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 13:01:54 INFO - PROCESS | 1668 | RSS: Main: 288935936 13:01:54 INFO - PROCESS | 1668 | 13:01:56 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:01:57 INFO - PROCESS | 1668 | RSS: Main: 293138432 13:01:57 INFO - PROCESS | 1668 | 13:01:59 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 13:02:00 INFO - PROCESS | 1668 | RSS: Main: 332038144 13:02:00 INFO - PROCESS | 1668 | 13:02:03 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 13:02:04 INFO - PROCESS | 1668 | RSS: Main: 333381632 13:02:04 INFO - PROCESS | 1668 | 13:02:06 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 13:02:07 INFO - PROCESS | 1668 | RSS: Main: 331177984 13:02:07 INFO - PROCESS | 1668 | 13:02:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:02:09 INFO - PROCESS | 1668 | RSS: Main: 321224704 13:02:09 INFO - PROCESS | 1668 | 13:02:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:02:12 INFO - PROCESS | 1668 | RSS: Main: 328228864 13:02:12 INFO - PROCESS | 1668 | 13:02:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:02:14 INFO - PROCESS | 1668 | RSS: Main: 330354688 13:02:14 INFO - PROCESS | 1668 | 13:02:17 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:02:18 INFO - PROCESS | 1668 | RSS: Main: 334737408 13:02:18 INFO - PROCESS | 1668 | 13:02:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:02:21 INFO - PROCESS | 1668 | RSS: Main: 332304384 13:02:21 INFO - PROCESS | 1668 | 13:02:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 13:02:24 INFO - PROCESS | 1668 | RSS: Main: 396505088 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | __start_tp_report 13:02:24 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 13:02:24 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 13:02:24 INFO - PROCESS | 1668 | |i|pagename|runs| 13:02:24 INFO - PROCESS | 1668 | |0;ai-astar;112;108;109;113;113;112;115;112;111;113 13:02:24 INFO - PROCESS | 1668 | |1;audio-beat-detection;156;121;141;169;120;140;137;120;171;115 13:02:24 INFO - PROCESS | 1668 | |2;audio-dft;187;185;187;184;186;186;186;182;221;184 13:02:24 INFO - PROCESS | 1668 | |3;audio-fft;76;73;88;90;55;59;56;82;85;55 13:02:24 INFO - PROCESS | 1668 | |4;audio-oscillator;91;93;130;76;79;84;122;78;75;123 13:02:24 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;87;83;87;84;86;85;87;84;86;83 13:02:24 INFO - PROCESS | 1668 | |6;imaging-darkroom;99;122;93;93;92;92;93;92;92;93 13:02:24 INFO - PROCESS | 1668 | |7;imaging-desaturate;85;85;85;83;83;83;84;84;84;83 13:02:24 INFO - PROCESS | 1668 | |8;json-parse-financial;57;58;57;58;57;59;58;76;58;76 13:02:24 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;65;64;71;74;70;68;67;71;68;72 13:02:24 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;83;81;81;82;81;81;81;82;81;83 13:02:24 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;140;137;137;150;143;158;142;138;181;141 13:02:24 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;169;171;168;170;173;170;163;166;168;166 13:02:24 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;71;60;87;102;96;98;94;75;67;67 13:02:24 INFO - PROCESS | 1668 | __end_tp_report 13:02:24 INFO - PROCESS | 1668 | __start_cc_report 13:02:24 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,2014 13:02:24 INFO - PROCESS | 1668 | __end_cc_report 13:02:24 INFO - PROCESS | 1668 | __startTimestamp1460750544021__endTimestamp 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | ------- Summary: start ------- 13:02:24 INFO - PROCESS | 1668 | Number of tests: 14 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.50 stddev:2.04 (1.8%) stddev-sans-first:2.17 13:02:24 INFO - PROCESS | 1668 | Values: 112.0 108.0 109.0 113.0 113.0 112.0 115.0 112.0 111.0 113.0 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:140.50 stddev:20.67 (14.7%) stddev-sans-first:20.98 13:02:24 INFO - PROCESS | 1668 | Values: 156.0 121.0 141.0 169.0 120.0 140.0 137.0 120.0 171.0 115.0 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:188.80 Median:186.00 stddev:11.42 (6.1%) stddev-sans-first:12.09 13:02:24 INFO - PROCESS | 1668 | Values: 187.0 185.0 187.0 184.0 186.0 186.0 186.0 182.0 221.0 184.0 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:71.90 Median:79.00 stddev:14.41 (18.2%) stddev-sans-first:15.21 13:02:24 INFO - PROCESS | 1668 | Values: 76.0 73.0 88.0 90.0 55.0 59.0 56.0 82.0 85.0 55.0 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:78.50 stddev:21.56 (27.5%) stddev-sans-first:22.82 13:02:24 INFO - PROCESS | 1668 | Values: 91.0 93.0 130.0 76.0 79.0 84.0 122.0 78.0 75.0 123.0 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:85.20 Median:86.00 stddev:1.62 (1.9%) stddev-sans-first:1.58 13:02:24 INFO - PROCESS | 1668 | Values: 87.0 83.0 87.0 84.0 86.0 85.0 87.0 84.0 86.0 83.0 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:93.00 stddev:9.34 (10.0%) stddev-sans-first:9.85 13:02:24 INFO - PROCESS | 1668 | Values: 99.0 122.0 93.0 93.0 92.0 92.0 93.0 92.0 92.0 93.0 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:84.00 stddev:0.88 (1.0%) stddev-sans-first:0.83 13:02:24 INFO - PROCESS | 1668 | Values: 85.0 85.0 85.0 83.0 83.0 83.0 84.0 84.0 84.0 83.0 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:58.00 stddev:7.72 (13.3%) stddev-sans-first:8.02 13:02:24 INFO - PROCESS | 1668 | Values: 57.0 58.0 57.0 58.0 57.0 59.0 58.0 76.0 58.0 76.0 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:70.50 stddev:3.16 (4.5%) stddev-sans-first:3.00 13:02:24 INFO - PROCESS | 1668 | Values: 65.0 64.0 71.0 74.0 70.0 68.0 67.0 71.0 68.0 72.0 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:81.50 stddev:0.84 (1.0%) stddev-sans-first:0.73 13:02:24 INFO - PROCESS | 1668 | Values: 83.0 81.0 81.0 82.0 81.0 81.0 81.0 82.0 81.0 83.0 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:142.50 stddev:13.71 (9.6%) stddev-sans-first:14.33 13:02:24 INFO - PROCESS | 1668 | Values: 140.0 137.0 137.0 150.0 143.0 158.0 142.0 138.0 181.0 141.0 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:169.50 stddev:2.88 (1.7%) stddev-sans-first:3.04 13:02:24 INFO - PROCESS | 1668 | Values: 169.0 171.0 168.0 170.0 173.0 170.0 163.0 166.0 168.0 166.0 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.70 Median:81.00 stddev:15.36 (19.0%) stddev-sans-first:15.80 13:02:24 INFO - PROCESS | 1668 | Values: 71.0 60.0 87.0 102.0 96.0 98.0 94.0 75.0 67.0 67.0 13:02:24 INFO - PROCESS | 1668 | -------- Summary: end -------- 13:02:24 INFO - PROCESS | 1668 | 13:02:24 INFO - TEST-INFO | 1668: exit 0 13:02:24 INFO - TEST-OK | kraken | took 58430ms 13:02:24 INFO - SUITE-END | took 1173s 13:02:25 INFO - Completed test suite (00:19:33) 13:02:25 INFO - javascript benchmark 13:02:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3165.3985742708887, 3416.5729283839505, 3423.229773301006, 3435.604905435875, 3495.2834668892865, 5502.0921547944645, 5584.804531841386, 5594.049011668021, 5602.971985140074, 5606.83179504615, 5554.389017208104, 5604.299462567179, 5614.410486898868, 5616.606837521375, 5616.9157462638095, 5101.846944591659, 5112.642115051929, 5136.332276804029, 5145.974270128644, 5193.792248310063, 5203.895922081556, 5213.661112027725, 5215.426303106655, 5219.582433405319, 5230.137027390495, 5119.974400127994, 5161.071007218699, 5176.55999240065, 5212.192110222922, 5256.605754568403, 5188.818391356303, 5228.032813929435, 5241.68549887008, 5245.86885327866, 5297.337832770903, 5032.597392208614, 5058.721770302632, 5077.416097148838, 5147.408048074444, 5172.301739265189, 5410.972945135289, 5432.565394768409, 5441.700706461143, 5479.232907392969, 5513.7794488220425, 6059.242594675666, 6133.233345831771, 6137.631742095488, 6138.294096178952, 6160.383961603849, 6029.939700602981, 6118.632882027093, 6141.884937092159, 6213.937860621381, 6221.191245138149, 4731.503192164842, 5708.343540492813, 5710.143478478179, 5721.742521586497, 5741.024025915521, 6284.5600807943, 6285.874282514415, 6287.528435367366, 6303.149074874925, 6326.335734747785, 5916.171735956969, 6007.609505382136, 6029.427204415573, 6065.787697430569, 6074.848128796727, 6158.969205153946, 6174.938250617526, 6178.135061091451, 6180.134781130645, 6189.690515474208, 6107.694615269219, 6108.17539632144, 6109.2974307955155, 6113.969430152821, 6118.514459361828, 5973.372795856372, 6116.174316467309, 6142.907856382158, 6149.2313460817395, 6153.138560601519, 4861.80552777892, 4900.191468407673, 4913.459519452857, 4913.992631510603, 4924.827631032968, 4370.584794444522, 4381.452318460192, 4381.846635367747, 4421.46942366918, 4423.62399196072, 5461.1262198048125, 5540.36285827135, 5556.888862222814, 5560.026995275811, 5569.415211402826, 6110.771402862501, 6192.59748116371, 6209.627422354582, 6230.688465576703, 6258.123862659231, 5548.278723765885, 5556.555475561945, 5558.499735023867, 5569.997400467954, 5584.860378490489, 4269.658427325869, 5078.289039534468, 5190.2214667800135, 5331.573474122061, 5352.625316227826, 4896.922677010981, 6233.906491402633, 6280.780172694116, 6286.465650419766, 6292.993121100602, 6000.069989151689, 6168.290646575521, 6210.658413787104, 6215.595986260879, 6227.875442491216, 6014.097885317063, 6206.751729930933, 6263.655498947602, 6271.717772700331, 6314.557980941474, 6036.7585296589405, 6071.605345652519, 6103.3591472894705, 6121.112438696453, 6128.938710612837, 6083.726232319467, 6164.938350616616, 6170.259568851587, 6190.907136393048, 6200.596961197508, 5795.072788353843, 5933.0, 5976.820695379146, 5993.160957466045, 6020.217371741645, 5865.589408741347, 5974.074018527135, 6027.945109605889, 6032.306284777307, 6046.7278972445465, 5849.766009359578, 5859.414058594107, 5873.500752436091, 5874.295084589706, 5877.853053673693, 5756.481916627524, 5911.55663325261, 6025.337212906489, 6035.879282414415, 6081.087836824512, 5887.028640274307, 5897.469227769349, 5916.171735957056, 5932.199153114447, 5935.34711181761], "value": 5608.016897395315, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.8617187187589, 514.6628958032491, 533.0458479322014, 584.0, 596.9134475501053, 30126.84936575317, 30590.847045764767, 37602.81198594007, 38455.0, 38587.80706096465, 40171.799141004325, 40651.796741016326, 40753.38869916953, 40834.795826020905, 40912.0, 9318.906810931889, 9562.952185239099, 9563.80872382552, 9566.473843938615, 9572.090651388107, 8503.404761666687, 8929.330300227477, 9579.0, 11861.347625880573, 11876.46555904982, 11379.431028448544, 11475.0, 11494.310341379543, 11502.71243218922, 11512.65462036136, 8495.74512764618, 11394.202405831595, 11461.426928653618, 11474.827877581843, 11514.481848316846, 11355.886441135566, 11503.539858405653, 11509.366984815832, 11510.424478776027, 11529.827052594133, 31345.216369590715, 38046.23907521862, 38484.42273365902, 38558.61441385578, 38629.42055869164, 3177.8411079445937, 3188.21888637283, 3368.5789276340456, 3757.4927384802977, 3955.11642917431, 27779.58330625042, 28058.158255252172, 28155.0, 28338.716612833814, 29155.41689166226, 2692.8653567321558, 2732.2076597786618, 2773.1126039667315, 2819.84490853003, 2900.0, 337042.0, 337046.0, 337405.0, 337626.0, 337755.0, 59458.81082378328, 59484.0, 59898.70050649719, 60019.0, 60109.69945150246, 63456.682716586125, 66546.66726666335, 68321.0, 68370.3162968374, 68386.0, 16316.42892498757, 16524.421645242357, 16557.503274901774, 16581.336746530244, 16673.416430424877, 11835.349055801842, 12130.514779408724, 12131.090168237417, 12833.230006199472, 12936.935315323552, 16801.663966720596, 16987.89578677382, 19021.524461888563, 19447.656385697457, 19961.401157965283, 6816.829579260559, 7965.6017199139815, 8026.5986700664735, 8096.878546821802, 8168.264856162974, 816.608028146493, 992.9056739609725, 1002.2733518199393, 1021.4177918586334, 1091.792559413709, 23361.41596460068, 29297.0, 29569.85215073911, 29779.553306700418, 29956.85021574922, 1100.301308668985, 1114.428257434919, 1116.7654792493504, 1149.4884776274646, 1223.3577371879655, 15143.404969241823, 15210.239488025776, 15372.495392116069, 15406.383744650313, 15419.383224671112, 179902.0, 239727.0, 240745.0, 240919.79540102187, 241350.0, 46842.06315873664, 56414.0, 58760.0, 58840.70579647074, 58952.0, 1312.1012106706937, 1354.5597680753795, 1433.2976841347872, 1472.3374481483374, 1488.2038109611306, 28591.85704071466, 28715.712842871304, 28751.856240718662, 29308.560371594016, 29502.0, 45484.0, 45628.771856140505, 46148.76925615351, 46236.7688161557, 46240.30639540477, 339839.0, 341114.29442852625, 341706.0, 342286.28856855555, 342305.0, 23246.186383476495, 25721.971121154944, 26529.469410612066, 26829.926802927665, 26861.597076043483, 43049.78475107605, 44456.77771611121, 46364.6090617282, 47186.05627887354, 47546.28680569863, 36812.63187368092, 36831.8158409217, 36907.8154609236, 37582.68460603975, 37710.0, 35978.64021359858, 36106.8194659025, 36314.4552831713, 36589.0, 36919.63080369269, 36152.0, 36526.452103219, 36611.338866113176, 37016.25967480689, 37085.81457092805, 31640.050798476084, 32678.673213268514, 32732.0, 32774.836125819216, 33040.50439243461, 23886.473792035315, 24434.87782561147, 24537.023230846742, 24739.62890556607, 24825.25524234276], "value": 18852.956812275683, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.41626392938446, 396.3005295653171, 400.7595442734358, 400.9599040095989, 411.0894368972725, 14362.497312594036, 19806.7028994565, 20814.6877796833, 20829.27097551586, 21064.0, 15986.0, 19780.802191978077, 20020.899895500537, 20261.898690506565, 20704.68942965853, 6047.909281360783, 6268.091126786613, 6270.216222972129, 6272.184615999914, 6275.874482510347, 5705.229793977822, 6116.327204007578, 6117.296510901237, 6192.071189321615, 6245.656488893109, 3893.649571538561, 4148.522919864224, 5828.125781132842, 6185.969070154643, 6188.721507532149, 6082.9695851520455, 6165.013597824349, 6169.01295792671, 6171.105189747484, 6187.907181392237, 5704.743286552115, 5911.349751527328, 5911.881762364728, 5918.822435326904, 5920.0, 13067.346632668328, 15738.842611573853, 24530.01879924801, 24985.0, 25041.248762537154, 2134.5944270588693, 2159.719236499245, 2167.5664867026658, 2206.205765924266, 2247.5954328220914, 9459.858102128474, 9784.266180036526, 9918.950405248, 10151.137153341975, 12461.501539938392, 1725.8015328237163, 1750.7461418094433, 1817.2458429751637, 1842.253887175696, 1946.805319468042, 117658.0, 122748.99999999911, 123140.1528977066, 124747.0, 126555.73444265623, 11576.0, 11747.588834390755, 11762.000229980547, 11833.171677982458, 11903.34531600753, 14968.0, 15042.0, 15050.924745376422, 15086.924565377103, 15087.698246035017, 8669.739907802776, 8733.51965641883, 8748.212660860427, 8778.60496277669, 8813.735587932373, 8575.742727718178, 8599.656013759504, 8613.913860861436, 8708.25979791712, 8722.127787221227, 4603.700759450625, 4605.194091034055, 4609.953900460952, 4611.100835337144, 4677.415323084614, 1602.5352647732027, 1643.4001589420016, 1783.6254386578964, 1966.3412756726657, 1970.7438033055612, 690.6270613868555, 737.8413641067089, 752.5296689569019, 816.3673153306116, 983.9458829764432, 20175.39473815788, 20401.591968160552, 21427.78572214289, 21482.24812131568, 21511.892440537697, 928.456852741154, 948.9335746497679, 953.275510611926, 972.6838777397375, 1013.1185868294631, 10364.896351036545, 10791.730206744736, 10807.621733239299, 10813.567457301777, 10853.728656783644, 633697.0, 641415.0, 646229.0, 665510.0, 666618.0, 102560.0, 118155.0, 121901.0, 123908.38045809713, 126436.36781816032, 1017.4810846468355, 1033.7002269341956, 1062.5218651606808, 1107.800595892747, 1113.2485572238706, 14355.856441435451, 17893.552661183574, 18118.909405452887, 18322.083895804888, 18376.908115459337, 50788.49211507838, 51132.977340453726, 52312.69218269574, 52645.47354526406, 52684.20973685475, 73947.63026184835, 74259.62870185614, 75927.0, 76772.0, 76848.23151768411, 7614.695412183451, 7634.274743899321, 7793.454458187873, 8096.595170241582, 8190.795230119056, 10017.647617571534, 11294.887051129384, 11596.420178991186, 11608.0, 12049.879501204876, 8992.955035224782, 10330.173586112942, 10822.783544328911, 10862.945685271523, 10866.782664346827, 10274.486275686335, 10446.477676116012, 10646.20153488476, 10699.839502407314, 10740.677779666623, 10292.794144116926, 10491.318064325795, 10971.23201375896, 11033.779324413626, 11398.94300528492, 7786.0, 8043.718469853527, 8044.557549334842, 8151.062627797879, 8406.243438090602, 10660.360378377321, 10997.230193886671, 11049.723756906142, 11080.889191107986, 11108.666739997812], "value": 9415.922532925175, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.250447262951, 1579.7472404415296, 1603.5830684022155, 1824.5985883105711, 1941.7087436884465, 7729.227077292268, 8437.957810210948, 8658.523781192032, 8666.220040196382, 8668.653253869854, 8319.417640765134, 8510.489370637766, 8525.062243153263, 8530.57347132644, 8535.48787072776, 2434.646976188456, 2461.1386014894824, 2467.0501856785177, 2467.814913881457, 2468.7407822178684, 2379.6192609182535, 2417.758224177577, 2429.4776623026046, 2430.2344761400104, 2437.475942672325, 2401.0, 2419.1412048722714, 2427.417419819248, 2439.2072576412647, 2440.2923152285903, 1873.7539536208405, 1995.860289779716, 2351.1300818697105, 2377.8335516513853, 2413.384586930339, 2372.9644055339186, 2403.435192729707, 2408.8313818032743, 2409.325388891113, 2410.626352915301, 8400.831983360298, 8495.702650407206, 8653.394262401634, 8684.0, 8684.913150868473, 5216.2958000669805, 5395.487428694268, 5401.513863752242, 5422.319081084876, 5449.727513624303, 2903.5209190483547, 3524.947125793115, 3648.1426864686778, 3651.6896063834606, 3652.5982141964364, 1051.458498873081, 1059.5602824827688, 1063.4682658670665, 1069.0859315285475, 1069.4118234970813, 11251.212415130944, 11407.942960285229, 11413.54345826166, 11420.200585958986, 11440.141989350832, 4410.360497727812, 4411.955880441219, 4570.908581828345, 4606.216943119678, 4650.069986002813, 8905.975812781613, 8917.554122293988, 8918.821623567492, 8929.973053098976, 8935.508547029845, 5220.738963051832, 5241.711705856138, 5249.763760630781, 5254.632175747737, 5270.130428479258, 4211.494620645542, 4378.0, 4550.453945526558, 4794.256890182028, 4920.917398172396, 3731.440283957428, 3748.9437658435145, 3782.1868298315994, 3817.9427308590393, 3829.463875057494, 1183.627157445402, 1191.434068817305, 1195.6445801770499, 1202.2666173634077, 1208.6132437620054, 907.5462268865567, 924.9398789078823, 928.1785619726455, 938.5742041548267, 960.7886265021684, 5662.546996240291, 5843.620164689367, 5897.5871688982215, 5900.439458251545, 5970.283565972111, 439.48141193391467, 450.25707582489156, 460.36009946174846, 471.6274143426723, 477.36033714822304, 3838.9616103838603, 3913.2760439318818, 3913.6477717005037, 3983.701222408331, 4009.799510024487, 10154.39073655583, 10558.944105589533, 10712.089472394933, 10724.034836864563, 10727.141828653845, 10592.311499752492, 10662.306950048222, 10684.94657526723, 10690.572377104829, 10692.893071069346, 7953.164917683675, 8089.39329550286, 8093.757187284391, 8238.340932725368, 8239.505629662359, 5653.915191272216, 5719.5138413235345, 5729.656220626775, 5818.040023396092, 5825.76696932118, 9581.616735330508, 9609.231261499202, 9769.511524423608, 9888.406695598287, 9888.505574721092, 9856.457894815854, 9959.900400995897, 10061.24540659468, 10149.64476243328, 10195.84706229422, 6115.663638499926, 6360.49116070704, 6600.867982640416, 6946.37482626566, 6964.26875178099, 8202.26179643835, 8214.671413143407, 8291.917080829115, 8293.751187464386, 8297.543635099886, 7139.785806425815, 7184.425245980205, 7184.8445466358835, 7332.96333518329, 7356.080489938758, 5286.39206491243, 7097.645117744195, 7104.573725576482, 7140.642967851691, 7248.347648711641, 6505.316941721051, 6582.868342633216, 6777.6272305023695, 6780.457563394818, 6782.355676210752, 4765.309030190534, 4766.642501812447, 4771.6898401603785, 4825.300331451849, 4865.367502224688, 4835.274708793569, 4852.102361063215, 4920.21276595743, 4931.950680493149, 5075.5178258065425], "value": 4416.95105000511, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.83156511266463, 323.0, 332.6457322951057, 332.682288414564, 336.122719701579, 5668.489835914766, 5826.679532625709, 5864.648121112737, 5873.471387575123, 5910.408959104098, 5342.626016178869, 5706.115552089423, 5836.970815145929, 5855.677937713434, 5861.17943487912, 4473.865784026484, 4477.992451698358, 4500.5499450054895, 4513.961788788581, 4525.275955847057, 3797.582265950743, 4232.280512312899, 4510.278355463127, 4512.187806194884, 4522.253828118356, 4389.5390983946545, 4432.072048658828, 4437.64498840092, 4451.0, 4455.576720211575, 4456.955430445702, 4457.777111144446, 4482.731036137842, 4508.436445444319, 4532.841350552715, 3642.1805093853936, 3767.4913886625227, 3799.4680744695765, 3816.0, 3840.423936409533, 4826.686265392738, 5579.832605021856, 5696.205695205997, 5696.629719068247, 5712.1431785232135, 5054.064997975386, 5063.772130254148, 5065.189569668835, 5082.949170508285, 5096.566791822699, 4879.487653796371, 4920.704757714549, 5703.572232082569, 6229.37706229378, 6241.285041007176, 3823.751456155341, 3847.8076096195077, 4008.4588580541545, 4153.833846646131, 4158.91682166358, 5729.770809167629, 5883.8529036774, 6545.770898018594, 6612.0, 6615.93384066158, 3600.8199590020395, 4238.576142385798, 4246.320588705854, 4268.210381079511, 4271.295236285978, 3616.294822509638, 4290.37789520524, 4294.377315289265, 4311.590398912161, 4331.133773245364, 4239.830406783694, 5045.924311135336, 5053.646244762831, 5063.898722025612, 5083.466236045236, 4974.77613507393, 5936.317323507852, 6034.698265086816, 6229.314775374705, 6369.20384951881, 1412.2303344677175, 1431.8281806183325, 1439.7696368580978, 1440.51742666208, 1450.9056911300731, 941.5574679900436, 977.9217662586976, 978.2760757039739, 979.1823827104448, 989.3618615992644, 4663.790129444184, 4858.46556878743, 5764.0, 5786.399812149351, 5819.0, 6447.2907980122145, 6458.3208365824285, 6472.449841763503, 6488.124103246096, 6507.934920650828, 4827.324174615556, 5752.568557358215, 5790.276215473066, 5797.159411885253, 5819.421056050498, 2948.1597744642672, 3269.54226408303, 3394.2702319001532, 3406.4379377402947, 3419.5554577904713, 6694.732210711614, 6721.495887808336, 6744.258131605519, 6750.966245168743, 6761.526693131434, 5032.0690672225755, 6484.735476582116, 6597.670116494156, 6628.8674226515195, 6631.30371311015, 6477.740890364427, 6689.896167132369, 6689.899651505231, 6713.798586042427, 6731.596104233761, 6191.473724733448, 6338.84479277048, 6393.7122829471855, 6406.391392817862, 6452.903206451813, 6282.7800477527835, 6287.371262873676, 6320.146780184617, 6332.208473940757, 6341.524385671185, 5416.7020813855615, 5977.282726072899, 6361.823062733409, 6662.467002639874, 6700.664966751546, 4075.3071977764444, 4216.641585465147, 4229.725067870579, 4238.89402764921, 4246.256905041667, 6387.297397286202, 6397.712102955285, 6410.743190583677, 6413.422791948747, 6439.774607888889, 5861.677607731615, 6145.201123853871, 6209.187879393507, 6223.097650840693, 6230.252369715663, 5474.479924407175, 5865.853353666193, 5888.4700376966275, 6037.124616930432, 6179.62922224668, 6150.815475535741, 6174.691265436621, 6189.226346706661, 6190.0, 6195.2875419325555, 5929.792457264148, 6262.780802672067, 6291.182146321132, 6321.241451025906, 6360.745570177141, 5187.403448603308, 6160.630362178284, 6180.505559555135, 6218.9378106218355, 6224.844378890564], "value": 4537.067421831202, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [612.5865041097259, 693.7051753004972, 711.4237467651207, 733.4902242941155, 751.7594369801664, 14865.702685946275, 15265.847341526582, 15529.22353882307, 15538.0, 15540.378384864593, 13844.653883652914, 14148.50480233192, 14200.147991120513, 14284.857151428483, 14303.642408939782, 924.3067699225597, 936.2089034765646, 937.4328531238605, 942.2462030375706, 942.519315149272, 762.4586543554083, 775.8138046868739, 796.8764841449585, 894.7092195036606, 930.2743859789375, 971.8639390485303, 972.2125078686252, 974.0551664885086, 975.878015248094, 975.9316847820655, 774.4888373673405, 937.5874615169304, 957.8419560772464, 964.8456247000481, 965.2133511188395, 720.7045111504258, 721.0842230367404, 724.9311315425026, 726.963651817407, 727.0838743183626, 15150.242487875563, 15151.242437878172, 15172.848271517254, 15195.0, 15248.618784530365, 12419.130660853743, 12669.493220271179, 12675.0, 12868.09923305369, 13029.022823288291, 9829.55766990487, 10196.694099177037, 10377.169826413945, 10507.73730656732, 10926.945365273123, 3554.111472131967, 3573.714102871764, 3635.2911182319194, 3645.052286405527, 3650.0, 87848.56075719581, 88714.55642721809, 89227.66158507628, 89581.0, 90838.09161908363, 9538.856917146248, 9743.12311891919, 9848.803023939625, 10045.19638428924, 10098.14165795901, 21889.796061216468, 21925.671114933288, 21960.89019554892, 21968.231111911005, 21972.79149646753, 7297.0, 7418.7032518699725, 7471.514351567131, 7510.078633297444, 7529.924700752922, 4050.1899620075515, 4112.670986321087, 4119.814608342632, 4121.5466298707115, 4122.381642753551, 3555.377808883435, 3617.692496137805, 3661.139632186434, 3661.1945372018113, 3665.8533658653355, 627.8524546731514, 637.3339859846471, 645.3127419298432, 653.4804830160034, 655.7540922154192, 400.679456434851, 532.3000254665103, 606.8469907176312, 610.2737742086991, 613.957023008394, 7665.961670191613, 7884.566348850868, 7964.402669799788, 8125.593720313961, 8196.754097376968, 511.72878374461595, 538.7252501224325, 540.2058973309229, 574.8131857146444, 577.8295402856168, 3749.4563288323584, 3795.6204379561823, 3798.582155962842, 3808.2383523295453, 3843.1160833008566, 83886.0, 84662.30675386458, 85954.0, 86286.56856715801, 87859.0, 73900.63049684717, 74029.62985185147, 74587.88118178232, 74747.0, 75718.48563028709, 67271.66364168147, 67385.0, 67747.66126169337, 68094.63810723857, 68879.44531424798, 7174.067371241706, 7364.447666425147, 7505.812354691177, 7581.50720203185, 7623.237676232332, 27600.723992760617, 40127.0, 40210.597894021856, 40310.596894031856, 40911.0, 56578.0, 57560.71219643875, 57849.421505784405, 58041.709791450776, 58394.4160558389, 10158.0, 10169.796604068026, 10313.74215644615, 10412.271141020055, 10879.728006799576, 10894.346339219672, 10940.835887461853, 11029.283096598696, 11066.502007409525, 11257.662270131908, 10510.894891050992, 10559.04968552834, 10610.045095941094, 10640.255182137485, 10712.89287107119, 10066.798664026825, 11431.142664300378, 11633.0, 11739.589114380955, 11786.941065294619, 10862.565497380332, 10922.453877305945, 11196.496157672762, 11227.27022743519, 11770.058395328524, 9195.494247816434, 9462.716118516455, 9485.905140948502, 9529.809403812023, 9878.950605246928, 8242.464239824392, 9013.729588112366, 9583.12506248109, 9596.712098637052, 9835.901640983499], "value": 6741.105271728252, "unit": "score"}], "name": "dromaeo_css", "value": 7157.921569765875, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 108.0, 109.0, 113.0, 113.0, 112.0, 115.0, 112.0, 111.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 121.0, 141.0, 169.0, 120.0, 140.0, 137.0, 120.0, 171.0, 115.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 187.0, 184.0, 186.0, 186.0, 186.0, 182.0, 221.0, 184.0], "value": 188.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 88.0, 90.0, 55.0, 59.0, 56.0, 82.0, 85.0, 55.0], "value": 71.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 130.0, 76.0, 79.0, 84.0, 122.0, 78.0, 75.0, 123.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 84.0, 86.0, 85.0, 87.0, 84.0, 86.0, 83.0], "value": 85.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 122.0, 93.0, 93.0, 92.0, 92.0, 93.0, 92.0, 92.0, 93.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 83.0, 83.0, 83.0, 84.0, 84.0, 84.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 57.0, 58.0, 57.0, 59.0, 58.0, 76.0, 58.0, 76.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 71.0, 74.0, 70.0, 68.0, 67.0, 71.0, 68.0, 72.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 81.0, 82.0, 81.0, 81.0, 81.0, 82.0, 81.0, 83.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 137.0, 150.0, 143.0, 158.0, 142.0, 138.0, 181.0, 141.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 168.0, 170.0, 173.0, 170.0, 163.0, 166.0, 168.0, 166.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 60.0, 87.0, 102.0, 96.0, 98.0, 94.0, 75.0, 67.0, 67.0], "value": 81.7, "unit": "score"}], "name": "kraken", "value": 1480.6000000000001, "alertThreshold": 2.0}]} 13:02:25 INFO - Return code: 0 13:02:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:02:25 ERROR - # TBPL SUCCESS # 13:02:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:02:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:02:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:02:25 INFO - 2016-04-15 13:02:25.340 screenresolution[1672:9703] starting screenresolution argv=screenresolution get 13:02:25 INFO - 2016-04-15 13:02:25.342 screenresolution[1672:9703] Display 0: 1600x1200x32@60 13:02:25 INFO - 2016-04-15 13:02:25.348 screenresolution[1673:9706] starting screenresolution argv=screenresolution list 13:02:25 INFO - Available Modes on Display 0 13:02:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:02:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:02:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:02:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:02:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:02:25 INFO - Intel Iris: 13:02:25 INFO - Chipset Model: Intel Iris 13:02:25 INFO - Type: GPU 13:02:25 INFO - Bus: Built-In 13:02:25 INFO - VRAM (Dynamic, Max): 1536 MB 13:02:25 INFO - Vendor: Intel (0x8086) 13:02:25 INFO - Device ID: 0x0a2e 13:02:25 INFO - Revision ID: 0x0009 13:02:25 INFO - Displays: 13:02:25 INFO - Display: 13:02:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:02:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:02:25 INFO - Display Serial Number: 1600x1200 60 13:02:25 INFO - Main Display: Yes 13:02:25 INFO - Mirror: Off 13:02:25 INFO - Online: Yes 13:02:25 INFO - Rotation: Supported 13:02:25 INFO - Return code: 0 13:02:25 INFO - Running post-action listener: _resource_record_post_action 13:02:25 INFO - Running post-run listener: _resource_record_post_run 13:02:26 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 48028160; Write bytes: 701700096; Read time: 569; Write time: 2878 13:02:26 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 382775808; Write bytes: 372369408; Read time: 28087; Write time: 686 13:02:26 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 41853440; Write bytes: 304197632; Read time: 484; Write time: 2144 13:02:26 INFO - Running post-run listener: _upload_blobber_files 13:02:26 INFO - Blob upload gear active. 13:02:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:02:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:02:26 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:02:26 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:02:27 INFO - (blobuploader) - INFO - Open directory for files ... 13:02:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:02:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:02:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:28 INFO - (blobuploader) - INFO - Done attempting. 13:02:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:02:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:02:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:29 INFO - (blobuploader) - INFO - Done attempting. 13:02:29 INFO - (blobuploader) - INFO - Iteration through files over. 13:02:29 INFO - Return code: 0 13:02:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:02:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:02:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1a5e869c007e8ceb33e5ce1c868a1a0ba4dc8857b2ff2ee205cde2adc0e2f6eafbd3fc1e5864dafb7889638e0a038759cd8caf9cf99dfe84c7bbaa5c1faeb5cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7ca0ce9e4ce11cb8d361b6208eb971f36b08853403bf4c5a0fe2bea3d6065dd9bd58d4d3df501249a24d1fa05b0f4091f1eb25dc6743504d6cb2046875d5f7b"} 13:02:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:02:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:02:29 INFO - Contents: 13:02:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1a5e869c007e8ceb33e5ce1c868a1a0ba4dc8857b2ff2ee205cde2adc0e2f6eafbd3fc1e5864dafb7889638e0a038759cd8caf9cf99dfe84c7bbaa5c1faeb5cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7ca0ce9e4ce11cb8d361b6208eb971f36b08853403bf4c5a0fe2bea3d6065dd9bd58d4d3df501249a24d1fa05b0f4091f1eb25dc6743504d6cb2046875d5f7b"} 13:02:29 INFO - Running post-run listener: copy_logs_to_upload_dir 13:02:29 INFO - Copying logs to upload dir... 13:02:29 INFO - mkdir: /builds/slave/test/build/upload/logs 13:02:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.442525 ========= master_lag: 6.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-04-15 13:02:35.413096) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-15 13:02:35.416305) ========= 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.N2OcaZYXsj/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.buHTe901oe/Listeners TMPDIR=/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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/Fx-Team/sha512/1a5e869c007e8ceb33e5ce1c868a1a0ba4dc8857b2ff2ee205cde2adc0e2f6eafbd3fc1e5864dafb7889638e0a038759cd8caf9cf99dfe84c7bbaa5c1faeb5cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7ca0ce9e4ce11cb8d361b6208eb971f36b08853403bf4c5a0fe2bea3d6065dd9bd58d4d3df501249a24d1fa05b0f4091f1eb25dc6743504d6cb2046875d5f7b"} build_url:https://queue.taskcluster.net/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010654 build_url: 'https://queue.taskcluster.net/v1/task/N3dHd0ovStqYwqE6AgwXoQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1a5e869c007e8ceb33e5ce1c868a1a0ba4dc8857b2ff2ee205cde2adc0e2f6eafbd3fc1e5864dafb7889638e0a038759cd8caf9cf99dfe84c7bbaa5c1faeb5cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d7ca0ce9e4ce11cb8d361b6208eb971f36b08853403bf4c5a0fe2bea3d6065dd9bd58d4d3df501249a24d1fa05b0f4091f1eb25dc6743504d6cb2046875d5f7b"}' ========= master_lag: 0.24 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-15 13:02:35.669755) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 13:02:35.670088) ========= 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.N2OcaZYXsj/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.buHTe901oe/Listeners TMPDIR=/var/folders/wg/5zm5tl1j1x7gd9_hf2vmp85000000w/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.005177 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 13:02:35.704090) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-15 13:02:35.704385) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-15 13:02:35.705620) ========= ========= Total master_lag: 12.63 =========