builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0104 starttime: 1461710027.26 results: success (0) buildid: 20160426134004 builduid: 69a9a98b06a7410caef8484159ba4e0f revision: 88c0b444691016e0fd49c901a8e54f82d4c6a665 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 15:33:47.262946) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 15:33:47.263344) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-04-26 15:33:47.263612) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-04-26 15:33:48.961377) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:33:48.961856) ========= 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.XAOJuUUqE3/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.hlYVSo0N6t/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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-26 15:33:48-- 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% 15.9M=0.001s 2016-04-26 15:33:49 (15.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132663 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:33:49.122548) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:33:49.122855) ========= 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.XAOJuUUqE3/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.hlYVSo0N6t/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.027491 ========= master_lag: 0.34 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:33:49.485683) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 15:33:49.486022) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 88c0b444691016e0fd49c901a8e54f82d4c6a665 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 88c0b444691016e0fd49c901a8e54f82d4c6a665 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XAOJuUUqE3/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.hlYVSo0N6t/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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-26 15:33:49,560 truncating revision to first 12 chars 2016-04-26 15:33:49,561 Setting DEBUG logging. 2016-04-26 15:33:49,561 attempt 1/10 2016-04-26 15:33:49,561 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/88c0b4446910?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 15:33:50,516 unpacking tar archive at: mozilla-aurora-88c0b4446910/testing/mozharness/ program finished with exit code 0 elapsedTime=1.203663 ========= master_lag: 0.49 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 15:33:51.183609) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 15:33:51.183950) ========= script_repo_revision: 88c0b444691016e0fd49c901a8e54f82d4c6a665 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 15:33:51.184352) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 15:33:51.184632) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 15:33:51.232520) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-04-26 15:33:51.232875) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XAOJuUUqE3/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.hlYVSo0N6t/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:33:51 INFO - MultiFileLogger online at 20160426 15:33:51 in /builds/slave/test 15:33:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:33:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:33:51 INFO - {'append_to_log': False, 15:33:51 INFO - 'base_work_dir': '/builds/slave/test', 15:33:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:33:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:33:51 INFO - 'branch': 'Mozilla-Aurora', 15:33:51 INFO - 'buildbot_json_path': 'buildprops.json', 15:33:51 INFO - 'config_files': ('talos/mac_config.py',), 15:33:51 INFO - 'default_actions': ('clobber', 15:33:51 INFO - 'read-buildbot-config', 15:33:51 INFO - 'download-and-extract', 15:33:51 INFO - 'populate-webroot', 15:33:51 INFO - 'create-virtualenv', 15:33:51 INFO - 'install', 15:33:51 INFO - 'run-tests'), 15:33:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:33:51 INFO - 'download_minidump_stackwalk': True, 15:33:51 INFO - 'download_symbols': 'ondemand', 15:33:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:33:51 INFO - 'tooltool.py': '/tools/tooltool.py', 15:33:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:33:51 INFO - '/tools/misc-python/virtualenv.py')}, 15:33:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:33:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:33:51 INFO - 'installer_path': 'installer.exe', 15:33:51 INFO - 'log_level': 'info', 15:33:51 INFO - 'log_name': 'talos', 15:33:51 INFO - 'log_to_console': True, 15:33:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:33:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:33:51 INFO - 'opt_config_files': (), 15:33:51 INFO - 'pip_index': False, 15:33:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:33:51 INFO - 'cmd': ('bash', 15:33:51 INFO - '-c', 15:33:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:33:51 INFO - 'enabled': True, 15:33:51 INFO - 'halt_on_failure': False, 15:33:51 INFO - 'name': 'check_screen_resolution'},), 15:33:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:33:51 INFO - 'cmd': ('bash', 15:33:51 INFO - '-c', 15:33:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:33:51 INFO - 'enabled': True, 15:33:51 INFO - 'halt_on_failure': False, 15:33:51 INFO - 'name': 'check_screen_resolution'},), 15:33:51 INFO - 'run_cmd_checks_enabled': True, 15:33:51 INFO - 'sps_profile': False, 15:33:51 INFO - 'sps_profile_interval': 0, 15:33:51 INFO - 'suite': 'dromaeojs', 15:33:51 INFO - 'system_bits': '32', 15:33:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:33:51 INFO - 'title': 't-yosemite-r7-0104', 15:33:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:33:51 INFO - 'use_talos_json': True, 15:33:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:33:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:33:51 INFO - 'work_dir': 'build'} 15:33:51 INFO - ##### 15:33:51 INFO - ##### Running clobber step. 15:33:51 INFO - ##### 15:33:51 INFO - Running pre-action listener: _resource_record_pre_action 15:33:51 INFO - Running main action method: clobber 15:33:51 INFO - rmtree: /builds/slave/test/build 15:33:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:33:53 INFO - Running post-action listener: _resource_record_post_action 15:33:53 INFO - ##### 15:33:53 INFO - ##### Running read-buildbot-config step. 15:33:53 INFO - ##### 15:33:53 INFO - Running pre-action listener: _resource_record_pre_action 15:33:53 INFO - Running main action method: read_buildbot_config 15:33:53 INFO - Using buildbot properties: 15:33:53 INFO - { 15:33:53 INFO - "project": "", 15:33:53 INFO - "product": "firefox", 15:33:53 INFO - "script_repo_revision": "production", 15:33:53 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:33:53 INFO - "repository": "", 15:33:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:33:53 INFO - "buildid": "20160426134004", 15:33:53 INFO - "pgo_build": "False", 15:33:53 INFO - "basedir": "/builds/slave/test", 15:33:53 INFO - "buildnumber": 293, 15:33:53 INFO - "slavename": "t-yosemite-r7-0104", 15:33:53 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:33:53 INFO - "platform": "yosemite_r7", 15:33:53 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:33:53 INFO - "branch": "mozilla-aurora", 15:33:53 INFO - "repo_path": "releases/mozilla-aurora", 15:33:53 INFO - "revision": "88c0b444691016e0fd49c901a8e54f82d4c6a665", 15:33:53 INFO - "stage_platform": "macosx64", 15:33:53 INFO - "builduid": "69a9a98b06a7410caef8484159ba4e0f", 15:33:53 INFO - "slavebuilddir": "test" 15:33:53 INFO - } 15:33:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 15:33:53 INFO - Running post-action listener: _resource_record_post_action 15:33:53 INFO - ##### 15:33:53 INFO - ##### Running download-and-extract step. 15:33:53 INFO - ##### 15:33:53 INFO - Running pre-action listener: _resource_record_pre_action 15:33:53 INFO - Running main action method: download_and_extract 15:33:53 INFO - mkdir: /builds/slave/test/build/tests 15:33: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')]} 15:33:53 INFO - https://queue.taskcluster.net/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:33:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:33:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:33:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:33:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 15:33:54 INFO - Downloaded 1199 bytes. 15:33:54 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:33:54 INFO - Using the following test package requirements: 15:33:54 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:33:54 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:33:54 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 15:33:54 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:33:54 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 15:33:54 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:33:54 INFO - u'jsshell-mac.zip'], 15:33:54 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:33:54 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 15:33:54 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:33:54 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:33:54 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 15:33:54 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:33:54 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 15:33:54 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:33:54 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 15:33:54 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:33:54 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 15:33:54 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:33:54 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')]} 15:33:54 INFO - https://queue.taskcluster.net/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:33:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:33:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:33:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:33:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:33:55 INFO - Downloaded 18110498 bytes. 15:33:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:33:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:33:58 INFO - Return code: 0 15:33:58 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:33: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')]} 15:33:58 INFO - https://queue.taskcluster.net/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:33:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:33:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:33:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:33:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:33:59 INFO - Downloaded 18110498 bytes. 15:33:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:33:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:34:03 INFO - Return code: 0 15:34:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:34:03 INFO - https://queue.taskcluster.net/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:34:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:34:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:34:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:34:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:34:04 INFO - Downloaded 10840145 bytes. 15:34:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:34:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:34:04 INFO - Return code: 0 15:34:04 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')]} 15:34:04 INFO - https://queue.taskcluster.net/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 15:34:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:34:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:34:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 15:34:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 15:34:08 INFO - Downloaded 114075138 bytes. 15:34:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:34:08 INFO - mkdir: /builds/slave/test/properties 15:34:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:34:08 INFO - Writing to file /builds/slave/test/properties/build_url 15:34:08 INFO - Contents: 15:34:08 INFO - build_url:https://queue.taskcluster.net/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:34:09 INFO - Running post-action listener: _resource_record_post_action 15:34:09 INFO - Running post-action listener: set_extra_try_arguments 15:34:09 INFO - ##### 15:34:09 INFO - ##### Running populate-webroot step. 15:34:09 INFO - ##### 15:34:09 INFO - Running pre-action listener: _resource_record_pre_action 15:34:09 INFO - Running main action method: populate_webroot 15:34:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:34:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:34:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:34:09 INFO - '--tppagecycles', 15:34:09 INFO - '7'], 15:34:09 INFO - 'tests': ['tsvgm']}}, 15:34:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:34:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:34:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:34:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:34:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:34:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:34:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:09 INFO - 'talos_options': ['--disable-e10s'], 15:34:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:34:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:34:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:09 INFO - 'talos_options': ['--disable-e10s'], 15:34:09 INFO - 'tests': ['damp', 'tps']}, 15:34:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:09 INFO - 'tests': ['damp', 'tps']}, 15:34:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:34:09 INFO - 'tests': ['dromaeo_dom']}, 15:34:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:34:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:34:09 INFO - 'tests': ['a11yr', 15:34:09 INFO - 'ts_paint', 15:34:09 INFO - 'tpaint', 15:34:09 INFO - 'sessionrestore', 15:34:09 INFO - 'sessionrestore_no_auto_restore', 15:34:09 INFO - 'tabpaint']}, 15:34:09 INFO - 'other-e10s': {'tests': ['a11yr', 15:34:09 INFO - 'ts_paint', 15:34:09 INFO - 'tpaint', 15:34:09 INFO - 'sessionrestore', 15:34:09 INFO - 'sessionrestore_no_auto_restore', 15:34:09 INFO - 'tabpaint']}, 15:34:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:34:09 INFO - 'ts_paint', 15:34:09 INFO - 'tpaint', 15:34:09 INFO - 'sessionrestore', 15:34:09 INFO - 'sessionrestore_no_auto_restore', 15:34:09 INFO - 'tabpaint']}, 15:34:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:34:09 INFO - 'ts_paint', 15:34:09 INFO - 'tpaint', 15:34:09 INFO - 'sessionrestore', 15:34:09 INFO - 'sessionrestore_no_auto_restore', 15:34:09 INFO - 'tabpaint']}, 15:34:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:34:09 INFO - 'tests': ['a11yr', 15:34:09 INFO - 'ts_paint', 15:34:09 INFO - 'tpaint', 15:34:09 INFO - 'sessionrestore', 15:34:09 INFO - 'sessionrestore_no_auto_restore', 15:34:09 INFO - 'tabpaint']}, 15:34:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:34:09 INFO - 'tests': ['a11yr', 15:34:09 INFO - 'ts_paint', 15:34:09 INFO - 'tpaint', 15:34:09 INFO - 'sessionrestore', 15:34:09 INFO - 'sessionrestore_no_auto_restore', 15:34:09 INFO - 'tabpaint']}, 15:34:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:34:09 INFO - 'tests': ['tsvgx', 15:34:09 INFO - 'tsvgr_opacity', 15:34:09 INFO - 'tart', 15:34:09 INFO - 'tscrollx', 15:34:09 INFO - 'cart']}, 15:34:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:34:09 INFO - 'tsvgr_opacity', 15:34:09 INFO - 'tart', 15:34:09 INFO - 'tscrollx', 15:34:09 INFO - 'cart']}, 15:34:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:09 INFO - 'talos_options': ['--disable-e10s'], 15:34:09 INFO - 'tests': ['tp5o']}, 15:34:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:09 INFO - 'tests': ['tp5o']}, 15:34:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:09 INFO - 'talos_options': ['--disable-e10s', 15:34:09 INFO - '--xperf_path', 15:34:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:34:09 INFO - 'tests': ['tp5n']}, 15:34:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:34:09 INFO - 'talos_options': ['--xperf_path', 15:34:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:34:09 INFO - 'tests': ['tp5n']}}, 15:34:09 INFO - 'talos.zip': {'path': '', 15:34:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:34:09 INFO - Running post-action listener: _resource_record_post_action 15:34:09 INFO - ##### 15:34:09 INFO - ##### Running create-virtualenv step. 15:34:09 INFO - ##### 15:34:09 INFO - Running pre-action listener: _resource_record_pre_action 15:34:09 INFO - Running main action method: create_virtualenv 15:34:09 INFO - Creating virtualenv /builds/slave/test/build/venv 15:34:09 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 15:34:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:34:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:34:09 INFO - Using real prefix '/tools/python27' 15:34:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:34:10 INFO - Installing distribute.............................................................................................................................................................................................done. 15:34:12 INFO - Installing pip.................done. 15:34:12 INFO - Return code: 0 15:34:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:34:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102ee7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca29cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d86e00>, '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 0x102befdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d81b20>, '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.XAOJuUUqE3/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.hlYVSo0N6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:34:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:34:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:34:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAOJuUUqE3/Render', 15:34:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:12 INFO - 'HOME': '/Users/cltbld', 15:34:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:12 INFO - 'LOGNAME': 'cltbld', 15:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:12 INFO - 'MOZ_NO_REMOTE': '1', 15:34:12 INFO - 'NO_EM_RESTART': '1', 15:34:12 INFO - 'PAGER': '/bin/cat', 15:34:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:12 INFO - 'PWD': '/builds/slave/test', 15:34:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:12 INFO - 'SHELL': '/bin/bash', 15:34:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hlYVSo0N6t/Listeners', 15:34:12 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:34:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:12 INFO - 'USER': 'cltbld', 15:34:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:12 INFO - 'XPC_FLAGS': '0x0', 15:34:12 INFO - 'XPC_SERVICE_NAME': '0', 15:34:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:13 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 15:34:13 INFO - Cleaning up... 15:34:13 INFO - Return code: 0 15:34:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:34:13 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')]} 15:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:13 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')]} 15:34:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102ee7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca29cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d86e00>, '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 0x102befdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d81b20>, '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.XAOJuUUqE3/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.hlYVSo0N6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:34:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 15:34:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:34:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAOJuUUqE3/Render', 15:34:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:13 INFO - 'HOME': '/Users/cltbld', 15:34:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:13 INFO - 'LOGNAME': 'cltbld', 15:34:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:13 INFO - 'MOZ_NO_REMOTE': '1', 15:34:13 INFO - 'NO_EM_RESTART': '1', 15:34:13 INFO - 'PAGER': '/bin/cat', 15:34:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:13 INFO - 'PWD': '/builds/slave/test', 15:34:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:13 INFO - 'SHELL': '/bin/bash', 15:34:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hlYVSo0N6t/Listeners', 15:34:13 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:34:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:13 INFO - 'USER': 'cltbld', 15:34:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:13 INFO - 'XPC_FLAGS': '0x0', 15:34:13 INFO - 'XPC_SERVICE_NAME': '0', 15:34:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:13 INFO - Downloading/unpacking psutil>=0.7.1 15:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:34:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:34:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:34:16 INFO - Installing collected packages: psutil 15:34:16 INFO - Running setup.py install for psutil 15:34:16 INFO - building 'psutil._psutil_osx' extension 15:34:16 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 15:34:16 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 15:34:16 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 15:34:16 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 15:34:17 INFO - building 'psutil._psutil_posix' extension 15:34:17 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 15:34:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:34:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:34:17 INFO - ^ 15:34:17 INFO - 1 warning generated. 15:34:17 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 15:34:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:34:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:34:17 INFO - Successfully installed psutil 15:34:17 INFO - Cleaning up... 15:34:17 INFO - Return code: 0 15:34:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:34:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102ee7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca29cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d86e00>, '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 0x102befdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d81b20>, '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.XAOJuUUqE3/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.hlYVSo0N6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:34:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 15:34:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 15:34:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAOJuUUqE3/Render', 15:34:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:17 INFO - 'HOME': '/Users/cltbld', 15:34:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:17 INFO - 'LOGNAME': 'cltbld', 15:34:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:17 INFO - 'MOZ_NO_REMOTE': '1', 15:34:17 INFO - 'NO_EM_RESTART': '1', 15:34:17 INFO - 'PAGER': '/bin/cat', 15:34:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:17 INFO - 'PWD': '/builds/slave/test', 15:34:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:17 INFO - 'SHELL': '/bin/bash', 15:34:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hlYVSo0N6t/Listeners', 15:34:17 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:34:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:17 INFO - 'USER': 'cltbld', 15:34:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:17 INFO - 'XPC_FLAGS': '0x0', 15:34:17 INFO - 'XPC_SERVICE_NAME': '0', 15:34:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:19 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:34:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:34:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:34:20 INFO - Installing collected packages: mozsystemmonitor 15:34:20 INFO - Running setup.py install for mozsystemmonitor 15:34:20 INFO - Successfully installed mozsystemmonitor 15:34:20 INFO - Cleaning up... 15:34:20 INFO - Return code: 0 15:34:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:34:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:34:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:20 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 0x102ee7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca29cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d86e00>, '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 0x102befdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d81b20>, '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.XAOJuUUqE3/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.hlYVSo0N6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:34:20 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 15:34:20 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 15:34:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAOJuUUqE3/Render', 15:34:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:20 INFO - 'HOME': '/Users/cltbld', 15:34:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:20 INFO - 'LOGNAME': 'cltbld', 15:34:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:20 INFO - 'MOZ_NO_REMOTE': '1', 15:34:20 INFO - 'NO_EM_RESTART': '1', 15:34:20 INFO - 'PAGER': '/bin/cat', 15:34:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:20 INFO - 'PWD': '/builds/slave/test', 15:34:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:20 INFO - 'SHELL': '/bin/bash', 15:34:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hlYVSo0N6t/Listeners', 15:34:20 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:34:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:20 INFO - 'USER': 'cltbld', 15:34:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:20 INFO - 'XPC_FLAGS': '0x0', 15:34:20 INFO - 'XPC_SERVICE_NAME': '0', 15:34:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:20 INFO - Downloading/unpacking blobuploader==1.2.4 15:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:22 INFO - Downloading blobuploader-1.2.4.tar.gz 15:34:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:34:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:34:23 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 15:34:23 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 15:34:23 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 15:34:23 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 15:34:23 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 15:34:23 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 15:34:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:34:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:34:23 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 15:34:23 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 15:34:23 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 15:34:23 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 15:34:23 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 15:34:23 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 15:34:24 INFO - Downloading docopt-0.6.1.tar.gz 15:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:34:24 INFO - Installing collected packages: blobuploader, requests, docopt 15:34:24 INFO - Running setup.py install for blobuploader 15:34:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:34:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:34:24 INFO - Running setup.py install for requests 15:34:24 INFO - Running setup.py install for docopt 15:34:25 INFO - Successfully installed blobuploader requests docopt 15:34:25 INFO - Cleaning up... 15:34:25 INFO - Return code: 0 15:34:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34:25 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')]} 15:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:25 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')]} 15:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:25 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 0x102ee7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca29cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d86e00>, '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 0x102befdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d81b20>, '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.XAOJuUUqE3/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.hlYVSo0N6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:34:25 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 15:34:25 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 15:34:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAOJuUUqE3/Render', 15:34:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:25 INFO - 'HOME': '/Users/cltbld', 15:34:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:25 INFO - 'LOGNAME': 'cltbld', 15:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:25 INFO - 'MOZ_NO_REMOTE': '1', 15:34:25 INFO - 'NO_EM_RESTART': '1', 15:34:25 INFO - 'PAGER': '/bin/cat', 15:34:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:25 INFO - 'PWD': '/builds/slave/test', 15:34:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:25 INFO - 'SHELL': '/bin/bash', 15:34:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hlYVSo0N6t/Listeners', 15:34:25 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:34:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:25 INFO - 'USER': 'cltbld', 15:34:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:25 INFO - 'XPC_FLAGS': '0x0', 15:34:25 INFO - 'XPC_SERVICE_NAME': '0', 15:34:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:34:25 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-BsaKR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:34:25 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-IHhE79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:34:25 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-P66PEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:34:25 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-zv2hbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:34:25 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-pdSOGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:34:25 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-8qcyDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:34:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-cX6kkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:34:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-sqp1FU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:34:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-hMowuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:34:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-7WuEmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:34:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Qk11rh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:34:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-1ecZxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:34:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-ZRek80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:34:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Sm1BaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-BG4Jz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:34:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-FbXPFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:34:27 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-SNYzwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:34:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:34:27 INFO - Running setup.py install for manifestparser 15:34:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:34:27 INFO - Running setup.py install for mozcrash 15:34:27 INFO - Running setup.py install for mozdebug 15:34:27 INFO - Running setup.py install for mozdevice 15:34:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:34:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:34:27 INFO - Running setup.py install for mozfile 15:34:27 INFO - Running setup.py install for mozhttpd 15:34:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Running setup.py install for mozinfo 15:34:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Running setup.py install for mozInstall 15:34:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Running setup.py install for mozleak 15:34:28 INFO - Running setup.py install for mozlog 15:34:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Running setup.py install for moznetwork 15:34:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:34:28 INFO - Running setup.py install for mozprocess 15:34:28 INFO - Running setup.py install for mozprofile 15:34:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:34:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:34:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:34:29 INFO - Running setup.py install for mozrunner 15:34:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:34:29 INFO - Running setup.py install for mozscreenshot 15:34:29 INFO - Running setup.py install for moztest 15:34:29 INFO - Running setup.py install for mozversion 15:34:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:34:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:34:29 INFO - Cleaning up... 15:34:29 INFO - Return code: 0 15:34:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34: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')]} 15:34:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34: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')]} 15:34:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:29 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 0x102ee7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca29cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d86e00>, '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 0x102befdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d81b20>, '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.XAOJuUUqE3/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.hlYVSo0N6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:34:29 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 15:34:29 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 15:34:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAOJuUUqE3/Render', 15:34:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:29 INFO - 'HOME': '/Users/cltbld', 15:34:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:29 INFO - 'LOGNAME': 'cltbld', 15:34:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:29 INFO - 'MOZ_NO_REMOTE': '1', 15:34:29 INFO - 'NO_EM_RESTART': '1', 15:34:29 INFO - 'PAGER': '/bin/cat', 15:34:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:29 INFO - 'PWD': '/builds/slave/test', 15:34:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:29 INFO - 'SHELL': '/bin/bash', 15:34:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hlYVSo0N6t/Listeners', 15:34:29 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:34:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:29 INFO - 'USER': 'cltbld', 15:34:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:29 INFO - 'XPC_FLAGS': '0x0', 15:34:29 INFO - 'XPC_SERVICE_NAME': '0', 15:34:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:34:30 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-ARu1yO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:34:30 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:34:30 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-RhrVJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:34:30 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:34:30 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-vUXg2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:34:30 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:34:30 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-kjZUTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:34:30 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:34:30 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-25VdE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:34:30 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:34:30 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-0_Jt4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:34:30 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:34:30 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-I3lQ_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:34:30 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:34:30 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-R8brJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:34:30 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:34:30 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-qhnu_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:34:30 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)) 15:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:34:30 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-1hW3E2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:34:31 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:34:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-W12enB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:34:31 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:34:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-mS9J39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:34:31 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:34:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-6ePFoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:34:31 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:34:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-fo5_3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:34:31 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-AQsUpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:34:31 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:34:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-7XGbCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:34:31 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)) 15:34:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:34:31 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-7mvzV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:34:31 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)) 15:34:31 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)) 15:34:31 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)) 15:34:31 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)) 15:34:31 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)) 15:34:31 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)) 15:34:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:34:31 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 15:34:31 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 15:34:31 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 15:34:31 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 15:34:31 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 15:34:31 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 15:34:34 INFO - Downloading blessings-1.6.tar.gz 15:34:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:34:34 INFO - Installing collected packages: blessings 15:34:34 INFO - Running setup.py install for blessings 15:34:34 INFO - Successfully installed blessings 15:34:34 INFO - Cleaning up... 15:34:34 INFO - Return code: 0 15:34:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:34:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:34:34 INFO - Reading from file tmpfile_stdout 15:34:34 INFO - Current package versions: 15:34:34 INFO - blessings == 1.6 15:34:34 INFO - blobuploader == 1.2.4 15:34:34 INFO - docopt == 0.6.1 15:34:34 INFO - manifestparser == 1.1 15:34:34 INFO - mozInstall == 1.12 15:34:34 INFO - mozcrash == 0.17 15:34:34 INFO - mozdebug == 0.1 15:34:34 INFO - mozdevice == 0.48 15:34:34 INFO - mozfile == 1.2 15:34:34 INFO - mozhttpd == 0.7 15:34:34 INFO - mozinfo == 0.9 15:34:34 INFO - mozleak == 0.1 15:34:34 INFO - mozlog == 3.1 15:34:34 INFO - moznetwork == 0.27 15:34:34 INFO - mozprocess == 0.22 15:34:34 INFO - mozprofile == 0.28 15:34:34 INFO - mozrunner == 6.11 15:34:34 INFO - mozscreenshot == 0.1 15:34:34 INFO - mozsystemmonitor == 0.0 15:34:34 INFO - moztest == 0.7 15:34:34 INFO - mozversion == 1.4 15:34:34 INFO - psutil == 3.1.1 15:34:34 INFO - requests == 1.2.3 15:34:34 INFO - wsgiref == 0.1.2 15:34:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:34:34 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')]} 15:34:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:34 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')]} 15:34:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:34 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 0x102ee7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca29cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d86e00>, '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 0x102befdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d81b20>, '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.XAOJuUUqE3/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.hlYVSo0N6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:34:34 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 15:34:34 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 15:34:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAOJuUUqE3/Render', 15:34:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:34 INFO - 'HOME': '/Users/cltbld', 15:34:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:34 INFO - 'LOGNAME': 'cltbld', 15:34:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:34 INFO - 'MOZ_NO_REMOTE': '1', 15:34:34 INFO - 'NO_EM_RESTART': '1', 15:34:34 INFO - 'PAGER': '/bin/cat', 15:34:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:34 INFO - 'PWD': '/builds/slave/test', 15:34:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:34 INFO - 'SHELL': '/bin/bash', 15:34:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hlYVSo0N6t/Listeners', 15:34:34 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:34:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:34 INFO - 'USER': 'cltbld', 15:34:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:34 INFO - 'XPC_FLAGS': '0x0', 15:34:34 INFO - 'XPC_SERVICE_NAME': '0', 15:34:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 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)) 15:34:35 INFO - Cleaning up... 15:34:35 INFO - Return code: 0 15:34:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:34: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')]} 15:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34: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')]} 15:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102ee7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca29cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d86e00>, '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 0x102befdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d81b20>, '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.XAOJuUUqE3/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.hlYVSo0N6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:34:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:34:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:34:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAOJuUUqE3/Render', 15:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:35 INFO - 'HOME': '/Users/cltbld', 15:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:35 INFO - 'LOGNAME': 'cltbld', 15:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:35 INFO - 'MOZ_NO_REMOTE': '1', 15:34:35 INFO - 'NO_EM_RESTART': '1', 15:34:35 INFO - 'PAGER': '/bin/cat', 15:34:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:35 INFO - 'PWD': '/builds/slave/test', 15:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:35 INFO - 'SHELL': '/bin/bash', 15:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hlYVSo0N6t/Listeners', 15:34:35 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:35 INFO - 'USER': 'cltbld', 15:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:35 INFO - 'XPC_FLAGS': '0x0', 15:34:35 INFO - 'XPC_SERVICE_NAME': '0', 15:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:35 INFO - Downloading/unpacking jsonschema 15:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:34:37 INFO - Downloading/unpacking functools32 (from jsonschema) 15:34:37 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 15:34:37 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 15:34:37 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 15:34:37 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 15:34:37 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 15:34:37 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 15:34:38 INFO - Downloading functools32-3.2.3-2.tar.gz 15:34:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:34:38 INFO - warning: no files found matching '*.txt' 15:34:38 INFO - no previously-included directories found matching 'build' 15:34:38 INFO - no previously-included directories found matching 'dist' 15:34:38 INFO - no previously-included directories found matching '.git*' 15:34:38 INFO - Installing collected packages: jsonschema, functools32 15:34:38 INFO - Running setup.py install for functools32 15:34:38 INFO - warning: no files found matching '*.txt' 15:34:38 INFO - no previously-included directories found matching 'build' 15:34:38 INFO - no previously-included directories found matching 'dist' 15:34:38 INFO - no previously-included directories found matching '.git*' 15:34:38 INFO - Successfully installed jsonschema functools32 15:34:38 INFO - Cleaning up... 15:34:38 INFO - Return code: 0 15:34:38 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:34:38 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')]} 15:34:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:34:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:38 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')]} 15:34:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:34:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:34:38 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 0x102ee7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ef7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca29cafca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d86e00>, '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 0x102befdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d81b20>, '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.XAOJuUUqE3/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.hlYVSo0N6t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:34:38 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 15:34:38 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 15:34:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAOJuUUqE3/Render', 15:34:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:34:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:34:38 INFO - 'HOME': '/Users/cltbld', 15:34:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:34:38 INFO - 'LOGNAME': 'cltbld', 15:34:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:34:38 INFO - 'MOZ_NO_REMOTE': '1', 15:34:38 INFO - 'NO_EM_RESTART': '1', 15:34:38 INFO - 'PAGER': '/bin/cat', 15:34:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:34:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:34:38 INFO - 'PWD': '/builds/slave/test', 15:34:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:34:38 INFO - 'SHELL': '/bin/bash', 15:34:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hlYVSo0N6t/Listeners', 15:34:38 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:34:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:34:38 INFO - 'USER': 'cltbld', 15:34:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:34:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:34:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:34:38 INFO - 'XPC_FLAGS': '0x0', 15:34:38 INFO - 'XPC_SERVICE_NAME': '0', 15:34:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:34:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:34:38 INFO - Downloading/unpacking flake8 15:34:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:34:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:34:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:34:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:34:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:34:41 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 15:34:41 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 15:34:41 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 15:34:41 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 15:34:41 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 15:34:41 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 15:34:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:34:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:34:41 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 15:34:41 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 15:34:41 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 15:34:41 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 15:34:41 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 15:34:41 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 15:34:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:34:42 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 15:34:42 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 15:34:42 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 15:34:42 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 15:34:42 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 15:34:42 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 15:34:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:34:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:34:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:34:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:34:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:34:42 INFO - no previously-included directories found matching 'docs/_build' 15:34:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:34:42 INFO - Running setup.py install for pep8 15:34:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:34:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:34:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:34:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:34:43 INFO - no previously-included directories found matching 'docs/_build' 15:34:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:34:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:34:43 INFO - Cleaning up... 15:34:43 INFO - Return code: 0 15:34:43 INFO - Running post-action listener: _resource_record_post_action 15:34:43 INFO - Running post-action listener: _start_resource_monitoring 15:34:43 INFO - Starting resource monitoring. 15:34:43 INFO - ##### 15:34:43 INFO - ##### Running install step. 15:34:43 INFO - ##### 15:34:43 INFO - Running pre-action listener: _resource_record_pre_action 15:34:43 INFO - Running main action method: install 15:34:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:34:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:34:43 INFO - Reading from file tmpfile_stdout 15:34:43 INFO - Detecting whether we're running mozinstall >=1.0... 15:34:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:34:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:34:43 INFO - Reading from file tmpfile_stdout 15:34:43 INFO - Output received: 15:34:43 INFO - Usage: mozinstall [options] installer 15:34:43 INFO - Options: 15:34:43 INFO - -h, --help show this help message and exit 15:34:43 INFO - -d DEST, --destination=DEST 15:34:43 INFO - Directory to install application into. [default: 15:34:43 INFO - "/builds/slave/test"] 15:34:43 INFO - --app=APP Application being installed. [default: firefox] 15:34:43 INFO - mkdir: /builds/slave/test/build/application 15:34:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:34:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:35:15 INFO - Reading from file tmpfile_stdout 15:35:15 INFO - Output received: 15:35:15 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:35:15 INFO - Running post-action listener: _resource_record_post_action 15:35:15 INFO - ##### 15:35:15 INFO - ##### Running run-tests step. 15:35:15 INFO - ##### 15:35:15 INFO - Running pre-action listener: _resource_record_pre_action 15:35:15 INFO - Running main action method: run_tests 15:35:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:35:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:35:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:35:15 INFO - 2016-04-26 15:35:15.181 screenresolution[1638:7598] starting screenresolution argv=screenresolution get 15:35:15 INFO - 2016-04-26 15:35:15.183 screenresolution[1638:7598] Display 0: 1600x1200x32@60 15:35:15 INFO - 2016-04-26 15:35:15.189 screenresolution[1639:7601] starting screenresolution argv=screenresolution list 15:35:15 INFO - Available Modes on Display 0 15:35:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:35:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:35:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:35:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:35:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:35:15 INFO - Intel Iris: 15:35:15 INFO - Chipset Model: Intel Iris 15:35:15 INFO - Type: GPU 15:35:15 INFO - Bus: Built-In 15:35:15 INFO - VRAM (Dynamic, Max): 1536 MB 15:35:15 INFO - Vendor: Intel (0x8086) 15:35:15 INFO - Device ID: 0x0a2e 15:35:15 INFO - Revision ID: 0x0009 15:35:15 INFO - Displays: 15:35:15 INFO - Display: 15:35:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:35:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:35:15 INFO - Display Serial Number: 1600x1200 60 15:35:15 INFO - Main Display: Yes 15:35:15 INFO - Mirror: Off 15:35:15 INFO - Online: Yes 15:35:15 INFO - Rotation: Supported 15:35:15 INFO - Return code: 0 15:35:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:35:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:35:15 INFO - Python 2.7.3 15:35:15 INFO - Return code: 0 15:35:15 INFO - grabbing minidump binary from tooltool 15:35:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:35:15 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 0x102d86e00>, '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 0x102befdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d81b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:35:15 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 15:35:15 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 15:35:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:35:15 INFO - Return code: 0 15:35:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:35:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:35:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:35:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:35:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:35:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:35:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:35:16 INFO - Return code: 0 15:35:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 15:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0104 --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 15:35:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XAOJuUUqE3/Render', 15:35:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:35:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:35:16 INFO - 'HOME': '/Users/cltbld', 15:35:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:35:16 INFO - 'LOGNAME': 'cltbld', 15:35:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:35:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:35:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:35:16 INFO - 'MOZ_NO_REMOTE': '1', 15:35:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:35:16 INFO - 'NO_EM_RESTART': '1', 15:35:16 INFO - 'PAGER': '/bin/cat', 15:35:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:35:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:35:16 INFO - 'PWD': '/builds/slave/test', 15:35:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:35:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:35:16 INFO - 'SHELL': '/bin/bash', 15:35:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hlYVSo0N6t/Listeners', 15:35:16 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 15:35:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:35:16 INFO - 'USER': 'cltbld', 15:35:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:35:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:35:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:35:16 INFO - 'XPC_FLAGS': '0x0', 15:35:16 INFO - 'XPC_SERVICE_NAME': '0', 15:35:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:35:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 15:35:16 INFO - mozversion application_buildid: 20160426134004 15:35:16 INFO - mozversion application_changeset: 88c0b444691016e0fd49c901a8e54f82d4c6a665 15:35:16 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:35:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:35:16 INFO - mozversion application_name: Firefox 15:35:16 INFO - mozversion application_remotingname: firefox-dev 15:35:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:35:16 INFO - mozversion application_vendor: Mozilla 15:35:16 INFO - mozversion application_version: 48.0a2 15:35:16 INFO - mozversion platform_buildid: 20160426134004 15:35:16 INFO - mozversion platform_changeset: 88c0b444691016e0fd49c901a8e54f82d4c6a665 15:35:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:35:16 INFO - mozversion platform_version: 48.0a2 15:35:16 INFO - using testdate: 1461710116 15:35:16 INFO - actual date: 1461710116 15:35:16 INFO - starting webserver on 'localhost:49274' 15:35:16 INFO - SUITE-START | Running 2 tests 15:35:16 INFO - TEST-START | dromaeo_css 15:35:16 INFO - operating with platform_type : mac_ 15:35:16 INFO - Initialising browser for dromaeo_css test... 15:35:16 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpVbaPfd/profile http://localhost:49274/getInfo.html) 15:35:19 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 15:35:19 INFO - PROCESS | 1657 | colorDepth:24 15:35:19 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 15:35:19 INFO - PROCESS | 1657 | __metrics 15:35:19 INFO - PROCESS | 1657 | 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" 15:35:19 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:35:19 INFO - TEST-INFO | 1657: exit 0 15:35:19 INFO - Browser initialized. 15:35:19 INFO - Running cycle 1/1 for dromaeo_css test... 15:35:19 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpVbaPfd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:35:31 INFO - PROCESS | 1659 | RSS: Main: 211275776 15:35:31 INFO - PROCESS | 1659 | 15:36:23 INFO - PROCESS | 1659 | 1461710183201 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] 15:38:33 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 15:38:34 INFO - PROCESS | 1659 | RSS: Main: 324616192 15:38:34 INFO - PROCESS | 1659 | 15:41:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 15:41:38 INFO - PROCESS | 1659 | RSS: Main: 359022592 15:41:38 INFO - PROCESS | 1659 | 15:44:40 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 15:44:41 INFO - PROCESS | 1659 | RSS: Main: 359497728 15:44:41 INFO - PROCESS | 1659 | 15:47:43 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 15:47:43 INFO - PROCESS | 1659 | RSS: Main: 358535168 15:47:43 INFO - PROCESS | 1659 | 15:50:46 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 15:50:47 INFO - PROCESS | 1659 | RSS: Main: 332754944 15:50:47 INFO - PROCESS | 1659 | 15:53:50 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 15:53:50 INFO - PROCESS | 1659 | RSS: Main: 383832064 15:53:50 INFO - PROCESS | 1659 | 15:53:50 INFO - PROCESS | 1659 | __start_tp_report 15:53:50 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 15:53:50 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 15:53:50 INFO - PROCESS | 1659 | |i|pagename|runs| 15:53:50 INFO - PROCESS | 1659 | |0;dojo.html;3351;3433.536472576203;3434.8797792077276;3447.172678557147;3478.0956951192684;5538.307711536058;5555.50000499955;5562.360328562207;5576.358718747345;5587.413321601234;5566.749496272667;5590.8043218487255;5596.748146333414;5599.7480113394895;5651.180578816069;5044.419891712463;5048.268001139832;5057.797688092481;5097.923531147036;5102.132637451624;5180.689158650474;5189.42916279209;5189.948100519003;5203.245529398235;5209.114450543398;5098.872528186788;5178.611604129686;5179.2749015137915;5206.5834733221445;5214.165733482643;5188.45521220274;5203.453637368059;5208.8958220835375;5209.088409528317;5247.606429517801;4122.690798190148;5018.698878067291;5121.641485096045;5132.5124113209185;5160.819371321985;4600.6549508786975;5494.615376923617;5555.2778138842095;5581.916271255935;5584.301962254718;6163.630182189083;6208.937910620926;6224.688765561704;6224.782132625336;6245.500359971192;6063.333033366371;6152.692365381713;6286.434220920093;6302.212223472066;6311.968440157816;5752.453516915886;5773.336066352339;5784.219130417382;5787.13193021046;5790.986577349031;6322.620642761449;6358.1416508771645;6364.236291644939;6376;6377.107204991305;5887.499562537147;6002.849928751816;6015.218021657157;6102.145699602059;6117.84887895955;6197.938020619827;6212.254529456494;6244.219472565929;6246;6272.6550039747335;5781.479666830005;6162.106494558354;6165.722542485599;6170.907436388457;6172.845678858064;6007.489363404072;6051.425114614105;6142.600730952474;6143.232095988002;6175.3824617538785;4822.903541929142;4859.878503037452;4864.635152363587;4874.4150701916;4885.071836351153;4331.45856767904;4363.956360436355;4379.211741886427;4448.64410847138;4490.371348011281;5642.548596112383;5652.943470565242;5666.121751128581;5673.574481913873;5676.091825307931;6276.686165691697;6277.497800175975;6288.717007734663;6296.33888441707;6301.495880329563;5427.3215848019;5466.070767969455;5585.162225666182;5600.859978500489;5747.885042299131;5364.785408583691;5371.409144994048;5373.650712703662;5376.166694162411;5388.703621300788;6275.560710750295;6298.968505157445;6302;6303.527235457359;6333.493320534254;5212.687238765686;6025.18659980897;6131.866048757656;6168.722407491584;6305.558610897193;6296;6317.147185129948;6330.936690633035;6335.176427064636;6340.4293613575;6173.475254603409;6185.690715464299;6190.2571691397325;6195.4424101831055;6200.0699895016105;5608.6073974821375;5944.286685597583;6025.156478092983;6136.8772624548155;6174.166487524127;6019.969900150646;6045.848853778691;6046.54650901193;6048.6975651218145;6073.30157031947;6001.36985616504;6044.909326360196;6052.576319657758;6056.424639659225;6068.180795592539;5229.973850130725;5413.323334583177;5791.0734282514595;5897.351291358033;5915.615484993462;4523.954760452353;6046.909296360646;6054;6066.817995460143;6076.969615151896;5920.526357891445;5922.496587790086;5924.7926322578505;5930.466258036798;5939.584229103922 15:53:50 INFO - PROCESS | 1659 | |1;ext.html;524.7166530073761;529.6768970927733;529.8304542546387;542.422320228956;560.4423598519473;25572.872135639318;32438.026859194204;32444.675553244462;32468.512972305434;32548.349033019327;40415.59584404155;41307.793461032656;41426;41496.58503414965;41529.58470415295;9426;9653.903460965406;9661.806763864754;9674.03259674034;9674.129328360446;8597.656093756274;8806.64773409063;9562.18721408681;9700.757481062994;9717.757056073584;11625.534978600846;11878.465469053914;11879.643610691694;11909.464074116642;11921.58274460394;11794.233374830652;11925.88074119265;11930.701732456671;11941.402929853559;11959.282443053358;7763.495372800807;10506.632267870664;11282.94358528202;11570.826437603442;11577.189596728233;39085.804570976965;39636;39683.00792480182;39713.80143099266;39717.40423893644;2698.541247987847;3250.983745081283;3424.6232914379398;3563.2873425314783;3781.357169281229;28927.710722892713;29329;29721;29738;30046.79812807485;2770.7783377329965;2773.3066733316673;2795.398989217328;2801.0616443491454;2891.2193707698802;485250;486349;486594;486621;487089;66646;67162.99255511172;67184.66407668029;67221.32778672248;67302.3269767296;68667.96998045033;68710;68882.96675549871;68904;69052.5115867467;17784.91107544454;18013.369532066314;18026.63946721058;18027.18877650509;18044.819551804576;13840.169589824643;13903.235322057182;13925.651858703412;13927.164370137823;13940.930295348458;15739.606509837346;15973.600659983593;18113.003784791963;18421.90789046046;18421.90789046073;6241.906371404432;6334;7358.595277259802;7511.244377811095;7634.427417943676;926.6802952981211;980.9166220871164;981.8036392721341;1024.4672770159475;1029.6344797596896;29073.563896541567;29505;29680.554791678143;29735.810567577053;30408.391832163234;1092.4373947417087;1107.7562936154034;1147.8909503597313;1169.2575214738702;1193.629974707826;12294.815577766341;13303.733925321632;15195.696086078438;15308.158051307286;15485.922570387076;460438;467781;468642;468876;639167;63797.362026379145;63980;64796;64840.027399589046;64989.350106499274;1420.996395082928;1446.0817380963374;1579.439299048844;1585.9127747973973;1601.4074792326915;24919.845108438516;25887;28697.42605147927;31320.686793131776;31665.032378809236;61976.69011654913;62400.687996561544;62945.649619781;62954.685226575406;63003;431350;433338;434158;436039;437093.8145309253;34681.9595412138;44552.331715023654;45011.774941126394;45452.54547454483;46084.53915460937;35771.82114089413;38126.428103579026;38607.42088868613;46454.53545464637;47128.29307560321;37345.813270933475;39282.410763839136;39471.407928880515;40336.1932761349;40396.16236457532;38436.23127537378;38553;39009.80495097506;39145.6085439142;40707;37944.430833536964;38410.6158938407;38677.6132238674;38934.2213155741;38969.2206155881;24950.37624059413;31103.688963110984;33869.45979051532;35167.648323516434;35190.648093518736;21957.67063494017;25615.359616009748;25830.87084564565;26022;26041.869790650926 15:53:50 INFO - PROCESS | 1659 | |2;jquery.html;395.6024195683338;397.85876014015025;401.9638232559069;402.2136722707108;412.63894092668914;14631.780523292158;17842.82157178428;21164.470888227803;21362.359129226108;21418.25036123736;15889.09330880296;19890.10494527746;20138.49653758657;20678.069486873126;20831;5668.206451096849;5942.405759424045;5959.284885813709;5975.1037344398255;5979.402059794008;5773.047447171232;5856.56075794315;5878.823635290948;5978.461938425541;6028.065649824262;4914.557689807916;5656.773729050833;5800.15897694834;5806.258122563236;5811.506021988115;5678.9432105678825;5776.451237132465;5779.422057794229;5781.306243250795;5782.10377391505;4720.89059071118;4975.502449755032;5146;5157.48425157485;5161.870953226162;23415;25457.981680732748;25958.610620840704;26294.73705262961;26537.86731066332;2171.0989939175224;2299.6435552489224;2529.6458495810602;2990.1179152150166;2990.596269503611;9283.118103780198;9713.61145554177;9909.65316213929;10276.486175691185;14399.992000559965;1704.369383328164;1763.1360633289723;1785.9017754023523;1835.2016872660436;1871.803460636627;127450;129531;131993.99999999904;134482;136572.31713841466;9276.628934842665;11472.598459053894;11614.709632259091;11700.414979251174;11776.17566770335;14686.265686715622;14950.850491495163;15027.948043636841;15034.02278851871;15055.698886022219;6359.777407790705;8261.545614991113;8471.66113355459;8778.253848422955;9025.097490251053;8917.761847869297;8922.955385223162;8984.775380615536;9076.228520575825;9109.772255693528;4544.2047641662575;4615.838445654453;4618.515055919147;4670.95329046712;4679.719216847;1641.6306331075411;1738.9304427823;1933.3040105561986;1948.7174359717762;1996.464490097568;885.1856292211181;926.6756635177769;1021.9131373833308;1040.2822052783556;1057.9629712960163;16236.02583844973;19428.708569371473;19987.500312492302;20783.552911597806;20922.895385522974;965.7006328038331;986.5116767200282;992.3153024413132;1015.1117771949545;1026.743314171457;8709.608067636971;10679.199060070527;10855.348679079281;10859.511321990529;10872;585507;586064.0696796488;595669;603034;605953;96545.03454965501;135457.3227133858;140882.2955885214;141075;145100.5489945108;1004.3923426326967;1031.9432431216205;1052.3528030261334;1054.7784965157402;1056.1128651932413;17678.02770847616;17729.37947171843;18024.36914707979;18791.436256912315;18795.96622185793;52887.471125288255;53382.932341353735;55505;56203.71898140483;56712.582185444044;80464;82203;82351;82653.86893945796;84720.57639711762;7777.222277772404;8292.253697167283;8333.2916702081;8405.369597280107;8692.521911294687;9387.765305867408;12600.306983116017;12810.29543375086;12927.806082908952;13061.804072938723;11007.669769906915;11177.888221117684;11294.265872718248;11333.603323883624;11406.942965285121;10822.296550724177;10946.835797463204;11073.335599864035;11183.88816111861;11297.661070167907;11247.325160490069;11432.085433165494;11572.710682233012;11645.243059201124;11890.34603096838;8192.18078192176;8264.710735124241;8375.07874133857;8408.327333813402;8693.956530217309;8277.089520152658;10303.536340864828;11229.101671866394;11493;11535.307881527135 15:53:50 INFO - PROCESS | 1659 | |3;mootools.html;1422.8506006869286;1580.249381543767;1600.3518574977136;1813.8004819469857;1928.4503916383833;7742;8572.528510931896;8816.382853200279;8830.91169088309;8832.205101540869;8452.366072544553;8664.870026949586;8676.8698469523;8694.695685651004;8703.738887833375;2076.979230207701;2410.132352353147;2456.815738819587;2462.6182941644074;2465.272744540356;2416.8791560421996;2440.1825388494876;2463.4949835283815;2468.5062987402594;2478.021181633254;2444.7310795812446;2454.165583701541;2468.5803413419762;2481.925542233736;2483.081259933827;2420.47959688666;2438.5366780311774;2450.13020377766;2452.2152911068465;2463.3964678653665;2411.7588241175918;2439.634054891763;2441.56051910656;2444;2445.608702607592;8492.490450572923;8683.305335573203;8763.430377025474;8769.298456123495;8770.473771573726;5196.792128314863;5440.591955603346;5448.101063324586;5454.372747134091;5461.098918678414;3508.912277193065;3537.7169826413824;3631.3100510902846;3664.120611053355;3666.5416822897137;1002.6390499420203;1048.2557384257186;1051.7948999945013;1059.0786016165907;1088.8747794003712;9587.616495340179;11606.941965290203;11829.349385783778;11841.881581184078;11901.285922844658;4580.175568397721;4638.350630911675;4695.2252878275385;4724.314974328703;4740.762961851894;9313.022132675971;9315.673951411804;9330.346875718771;9342.06579342074;9348.252139828934;5252.763625636855;5304.655197412156;5305.257263983045;5307.495787900143;5349.063913815145;4154.688398370135;4429.512753597101;4584.472785629695;4601.010782681673;4967.379077615298;3831.8467261309233;3877.302085624615;3927.9607203927594;3943.6056394360335;3962.6433620973676;1210.352461433129;1230.9876901231053;1233.888949994487;1241.875812418751;1245.725940293134;781.5349866829227;792.5046845721424;880.6081293824315;881.5768431153083;887.2768693514765;5521.889562208733;5713.285839270091;5930.406959304121;6000.309964354068;6157;406.02148821340427;428.6121060440244;469.66419010407725;484.1068229117273;992.950352482373;3910.7849068300948;3913.1977944521873;4016.096378314856;4035.7376770509823;4082.2856000199845;10404.895951040544;10761.085307748772;10853.131749460024;10980.011798938134;10981.670549883516;10755.300905441121;10763.78472430547;10765.677029689123;10790.784184316426;10797.028267455968;7698.807529811799;8044.718434854752;8147.185281471805;8212.63043163059;8310;5985.610935289193;6014.158017877413;6116.571839971337;6118.847028824315;6141.631502109888;8104;9711.174550163316;10064.54709538058;10200.3369780964;10255.897441025494;9625.133737963337;10129.59481620727;10167.79664406693;10481.685549433529;10524.684259472227;6926.549774264657;7154.785356439315;7337.376323012603;7505.66224519887;7584.08990921093;8295.543745093837;8304.916950830415;8351.749447516584;8399.622017009127;8419.326453883552;6034.9094763577705;7310.52481588695;7459;7592.962035189788;7623.809404764926;7108.715651373887;7158.749443769443;7249.855002899807;7289.781306560812;7364.484486086137;5571.470710282517;6953.756618518328;6998.685059172248;7007.054047703495;7022.508424410242;4778.617710583215;4844.46710861812;4851.830185943616;4958.404991401055;4971.080350135237;4140.627343539096;4857.975710121426;4942.802287908444;5020.397552293748;5068.569171620454 15:53:50 INFO - PROCESS | 1659 | |4;prototype.html;318.50631521142236;320.72097275370425;326.1438723351203;328.47772042452505;330.1762103551636;4653.976125252443;4951.331570238017;5287.206918962158;5366.70483123428;5375.489328513795;4574.428196475441;5275.498827611371;5307.177387504943;5339.733013349327;5375.274337964373;3619.475176099464;3892.800567679259;3930.4890364252606;4070.75575465473;4089.4683691120113;3413.9487907681405;4034.7175697701173;4112.773797441139;4147.4815648044;4155.750654960697;4006.2187873364705;4073.327900896363;4088.2028004539125;4098.4876890388705;4117.46472958515;3185.888493902714;3429.7770644908;3814.1036856338737;4079.6328330450256;4117.958820411787;2988.9103326900226;3573.5175751273764;3580.8030558319283;3591;3593.245418462126;4479.776011199459;5141.971605678879;5152.304438900738;5153.123968925292;5178.6116041297055;4118.8673114893345;4382.2631633627325;4622.468416132154;4690.530946905316;4705.411823522059;4402.647788176938;4685.5782979532005;5093.719845408501;5623.409541998113;5628.859278518029;3674.4855720199193;3962.385830196324;3982.3871332110543;4001.7598944063448;4002.619751123668;4636.605888499482;5142.820001299937;5337.866553336159;5340.813071542478;5916.881662366729;3956.624120708528;4048.190361927567;4055.7224474290506;4069.613386728256;4078.7144899856726;3592.263585964871;4113.321301985199;4115.52671442782;4134.607212314825;4135.483064616923;4687.882802929954;4705.058988202373;4708.387909571734;4712.562668386106;4758.262469317261;4945.357103576512;5168.741562921914;5369.246794792717;5651.57613179005;5671.744771485293;1386.002078503496;1499.295331194337;1516.2646116633423;1517.9165145916859;1526.8702160316498;872.4416373520952;988.9011098890054;1015.9949200253951;1018.6791160784329;1033.5968972100886;4278.4865816102265;4638.628909687217;5133.281340612317;5272.99813035522;5564.972175139179;5048.570871475892;5791.247137872049;5821.476067153976;5846.4153584642045;5847.6199047062655;4365.563443655609;4862;5198.090334191501;5219.451957544479;5220.399654039834;2842.7299406556344;3029.5304227844717;3106.6272047354464;3314.5359649649067;3356.44618637922;6598.373154550406;6607.867842643121;6627.86744265112;6646.933530664728;6670.666466676646;6228.28374736902;6389.840253993594;6482.448991835747;6518.478521718251;6543.411093001558;6235.875282494325;6638.970959501382;6643.335666433415;6646.368594983469;6670.132882725215;6006.189164462741;6340.809775706736;6348.841278968062;6352.936470635234;6356.809295721136;6235.314115447388;6240;6254.937450625435;6265.274605546601;6273.71768270438;5517.44825517445;5601.60788744784;6292.244930608356;6420.967895160495;6626.641538484695;3724.975631701304;3995.360742281221;4272.914541709086;4289.332620609334;4337.8481753138485;5311.362636483646;5356.705381204071;6294.300538854575;6340;6341.619502829845;4787.521247875185;5423.4034256231;5906.586538942232;6154.415330543591;6154.753809847556;5789.8263052108505;6076.817695469143;6092.208012958287;6106.297775755845;6117.696443920233;5689.146628005832;5701.230333904869;6003.759849606142;6011.338752737108;6030.095485677183;5124.692518448905;5287.5274091315305;6126.387361263838;6131.995322291956;6144.2319710036245;5261.4738526147075;5811.506021988178;5999.610025348338;6054.243219597551;6084.148219249397 15:53:50 INFO - PROCESS | 1659 | |5;yui.html;662.400527522592;736.5286216821236;737.7381029734447;746.727444482764;746.9589172595505;14413.279336033196;15049.473268435593;15053.62365940852;16205.027698338079;17006.48980530586;15544;15620.843791562082;15689.372425102982;15694.293756780944;15714.292856821441;704.376626685382;728.7995801154672;889.1952782731638;910.658503061352;924.0343840686498;889.2841262783456;897.0491279243989;899.4468401932797;900.4507250577143;903.7243640689587;853.0999795216045;960.3565611040584;969.4667932637021;969.9466529340883;970.9611615535371;739.5008369350694;918.4443411735904;933.1508327422081;967.4727273635885;972.5380444288977;712.2841544248049;718.2745427118648;719.0975325965932;721.4733244731375;723.2008630463315;14956.326965286587;15007.774883376758;15013.174275414847;15026.023308484913;15043.623909402244;11940.70148246292;12084.456199470957;12163.574274900337;12304.630861074182;12401.44193511285;9896.356736812084;9915.900840991642;9976;10008.399496030188;10236.385816851012;3260.2827377976805;3352.597688277422;3367.4948757686298;3401.744869134825;3434.553508043963;74366.25633743702;74827.62586187088;77306.61346693287;77550.6122469384;77588;9370.812583748424;9426.293028022788;9433.622655093857;9504.619815207314;9960.053794889474;20815.479613009797;20832.27087051946;20842.89578552128;20874.37376878696;20881.164753409998;7200.639968001579;7323.7436689715605;7400.5189662671755;7495.66269517873;7599.772006839804;4079.3880917862557;4114.259433301974;4185.748855068706;4208.326667733148;4353.608175264241;3521.647835216509;3602.405603075463;3608.3685355062757;3611.4582812578324;3614.29521243355;537.7311344327836;622.8131560531822;645.6384424722171;651.6871901487312;667.2393471442559;558.9972050139723;598.3448124303881;605.1709158452948;613.6042252746953;614.5575185866259;7842.686292548348;8451.197136272045;8662;8668.653253869774;8673.609687564074;587.0366055004923;602.235161345098;604.1753007145215;608.2123649873424;613.1936503497975;3809.980950095232;3893.770067451516;3972.5828787977425;4064.085580744309;4130.359794231899;87143.56428217818;95716.04283957211;97154.0284597145;97963.51018244863;99661.50169249107;65301.79632934864;70467;82098;83601;86064.27871442537;53619;68264.6586767063;69794.3020569798;70062;71057;8234.835303293781;8278.213569710868;8291.963504561929;8353.039400469024;8472.788180295542;33062.33875322432;40399;42420.939476512096;43888.34167487426;44287.335689964035;58819.23542293738;61458;61761;62386;63146.36853631405;10841.457927103456;10863.967923047614;10968.780624387307;11050.06074483678;11132.55469781232;11580.957713805798;12096.334701591497;12126.333051682243;12232.388380580758;12358.258504489759;11110.277831940897;11183.888161118284;11511.19421640477;11549.42252887369;11824.40877956116;11097.889021109686;11438.052212849105;11472.139589530652;11627.767444651228;12264.749841291887;11261.155413343868;11278.210525263154;11279.436028198394;11533.653990380302;11775.646730598095;9329.346945713734;9426.18662015697;9450.291228157777;9511.904880951102;10085;9292.674756383494;9363.4381937084;9367.672131475365;9436.433813971184;9612.279079068958 15:53:50 INFO - PROCESS | 1659 | __end_tp_report 15:53:50 INFO - PROCESS | 1659 | __start_cc_report 15:53:50 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1113 15:53:50 INFO - PROCESS | 1659 | __end_cc_report 15:53:50 INFO - PROCESS | 1659 | __startTimestamp1461711230402__endTimestamp 15:53:50 INFO - PROCESS | 1659 | 15:53:50 INFO - PROCESS | 1659 | ------- Summary: start ------- 15:53:50 INFO - PROCESS | 1659 | Number of tests: 6 15:53:50 INFO - PROCESS | 1659 | 15:53:50 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5663.28 Median:5887.50 stddev:654.38 (11.1%) stddev-sans-first:630.74 15:53:50 INFO - PROCESS | 1659 | Values: 3351.0 3433.5 3434.9 3447.2 3478.1 5538.3 5555.5 5562.4 5576.4 5587.4 5566.7 5590.8 5596.7 5599.7 5651.2 5044.4 5048.3 5057.8 5097.9 5102.1 5180.7 5189.4 5189.9 5203.2 5209.1 5098.9 5178.6 5179.3 5206.6 5214.2 5188.5 5203.5 5208.9 5209.1 5247.6 4122.7 5018.7 5121.6 5132.5 5160.8 4600.7 5494.6 5555.3 5581.9 5584.3 6163.6 6208.9 6224.7 6224.8 6245.5 6063.3 6152.7 6286.4 6302.2 6312.0 5752.5 5773.3 5784.2 5787.1 5791.0 6322.6 6358.1 6364.2 6376.0 6377.1 5887.5 6002.8 6015.2 6102.1 6117.8 6197.9 6212.3 6244.2 6246.0 6272.7 5781.5 6162.1 6165.7 6170.9 6172.8 6007.5 6051.4 6142.6 6143.2 6175.4 4822.9 4859.9 4864.6 4874.4 4885.1 4331.5 4364.0 4379.2 4448.6 4490.4 5642.5 5652.9 5666.1 5673.6 5676.1 6276.7 6277.5 6288.7 6296.3 6301.5 5427.3 5466.1 5585.2 5600.9 5747.9 5364.8 5371.4 5373.7 5376.2 5388.7 6275.6 6299.0 6302.0 6303.5 6333.5 5212.7 6025.2 6131.9 6168.7 6305.6 6296.0 6317.1 6330.9 6335.2 6340.4 6173.5 6185.7 6190.3 6195.4 6200.1 5608.6 5944.3 6025.2 6136.9 6174.2 6020.0 6045.8 6046.5 6048.7 6073.3 6001.4 6044.9 6052.6 6056.4 6068.2 5230.0 5413.3 5791.1 5897.4 5915.6 4524.0 6046.9 6054.0 6066.8 6077.0 5920.5 5922.5 5924.8 5930.5 5939.6 15:53:50 INFO - PROCESS | 1659 | 15:53:50 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:63255.70 Median:38268.52 stddev:126475.05 (330.5%) stddev-sans-first:126742.15 15:53:50 INFO - PROCESS | 1659 | Values: 524.7 529.7 529.8 542.4 560.4 25572.9 32438.0 32444.7 32468.5 32548.3 40415.6 41307.8 41426.0 41496.6 41529.6 9426.0 9653.9 9661.8 9674.0 9674.1 8597.7 8806.6 9562.2 9700.8 9717.8 11625.5 11878.5 11879.6 11909.5 11921.6 11794.2 11925.9 11930.7 11941.4 11959.3 7763.5 10506.6 11282.9 11570.8 11577.2 39085.8 39636.0 39683.0 39713.8 39717.4 2698.5 3251.0 3424.6 3563.3 3781.4 28927.7 29329.0 29721.0 29738.0 30046.8 2770.8 2773.3 2795.4 2801.1 2891.2 485250.0 486349.0 486594.0 486621.0 487089.0 66646.0 67163.0 67184.7 67221.3 67302.3 68668.0 68710.0 68883.0 68904.0 69052.5 17784.9 18013.4 18026.6 18027.2 18044.8 13840.2 13903.2 13925.7 13927.2 13940.9 15739.6 15973.6 18113.0 18421.9 18421.9 6241.9 6334.0 7358.6 7511.2 7634.4 926.7 980.9 981.8 1024.5 1029.6 29073.6 29505.0 29680.6 29735.8 30408.4 1092.4 1107.8 1147.9 1169.3 1193.6 12294.8 13303.7 15195.7 15308.2 15485.9 460438.0 467781.0 468642.0 468876.0 639167.0 63797.4 63980.0 64796.0 64840.0 64989.4 1421.0 1446.1 1579.4 1585.9 1601.4 24919.8 25887.0 28697.4 31320.7 31665.0 61976.7 62400.7 62945.6 62954.7 63003.0 431350.0 433338.0 434158.0 436039.0 437093.8 34682.0 44552.3 45011.8 45452.5 46084.5 35771.8 38126.4 38607.4 46454.5 47128.3 37345.8 39282.4 39471.4 40336.2 40396.2 38436.2 38553.0 39009.8 39145.6 40707.0 37944.4 38410.6 38677.6 38934.2 38969.2 24950.4 31103.7 33869.5 35167.6 35190.6 21957.7 25615.4 25830.9 26022.0 26041.9 15:53:50 INFO - PROCESS | 1659 | 15:53:50 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35517.73 Median:13993.81 stddev:99967.41 (714.4%) stddev-sans-first:100213.06 15:53:50 INFO - PROCESS | 1659 | Values: 395.6 397.9 402.0 402.2 412.6 14631.8 17842.8 21164.5 21362.4 21418.3 15889.1 19890.1 20138.5 20678.1 20831.0 5668.2 5942.4 5959.3 5975.1 5979.4 5773.0 5856.6 5878.8 5978.5 6028.1 4914.6 5656.8 5800.2 5806.3 5811.5 5678.9 5776.5 5779.4 5781.3 5782.1 4720.9 4975.5 5146.0 5157.5 5161.9 23415.0 25458.0 25958.6 26294.7 26537.9 2171.1 2299.6 2529.6 2990.1 2990.6 9283.1 9713.6 9909.7 10276.5 14400.0 1704.4 1763.1 1785.9 1835.2 1871.8 127450.0 129531.0 131994.0 134482.0 136572.3 9276.6 11472.6 11614.7 11700.4 11776.2 14686.3 14950.9 15027.9 15034.0 15055.7 6359.8 8261.5 8471.7 8778.3 9025.1 8917.8 8923.0 8984.8 9076.2 9109.8 4544.2 4615.8 4618.5 4671.0 4679.7 1641.6 1738.9 1933.3 1948.7 1996.5 885.2 926.7 1021.9 1040.3 1058.0 16236.0 19428.7 19987.5 20783.6 20922.9 965.7 986.5 992.3 1015.1 1026.7 8709.6 10679.2 10855.3 10859.5 10872.0 585507.0 586064.1 595669.0 603034.0 605953.0 96545.0 135457.3 140882.3 141075.0 145100.5 1004.4 1031.9 1052.4 1054.8 1056.1 17678.0 17729.4 18024.4 18791.4 18796.0 52887.5 53382.9 55505.0 56203.7 56712.6 80464.0 82203.0 82351.0 82653.9 84720.6 7777.2 8292.3 8333.3 8405.4 8692.5 9387.8 12600.3 12810.3 12927.8 13061.8 11007.7 11177.9 11294.3 11333.6 11406.9 10822.3 10946.8 11073.3 11183.9 11297.7 11247.3 11432.1 11572.7 11645.2 11890.3 8192.2 8264.7 8375.1 8408.3 8694.0 8277.1 10303.5 11229.1 11493.0 11535.3 15:53:50 INFO - PROCESS | 1659 | 15:53:50 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5652.02 Median:2664.29 stddev:3163.38 (118.7%) stddev-sans-first:3156.29 15:53:50 INFO - PROCESS | 1659 | Values: 1422.9 1580.2 1600.4 1813.8 1928.5 7742.0 8572.5 8816.4 8830.9 8832.2 8452.4 8664.9 8676.9 8694.7 8703.7 2077.0 2410.1 2456.8 2462.6 2465.3 2416.9 2440.2 2463.5 2468.5 2478.0 2444.7 2454.2 2468.6 2481.9 2483.1 2420.5 2438.5 2450.1 2452.2 2463.4 2411.8 2439.6 2441.6 2444.0 2445.6 8492.5 8683.3 8763.4 8769.3 8770.5 5196.8 5440.6 5448.1 5454.4 5461.1 3508.9 3537.7 3631.3 3664.1 3666.5 1002.6 1048.3 1051.8 1059.1 1088.9 9587.6 11606.9 11829.3 11841.9 11901.3 4580.2 4638.4 4695.2 4724.3 4740.8 9313.0 9315.7 9330.3 9342.1 9348.3 5252.8 5304.7 5305.3 5307.5 5349.1 4154.7 4429.5 4584.5 4601.0 4967.4 3831.8 3877.3 3928.0 3943.6 3962.6 1210.4 1231.0 1233.9 1241.9 1245.7 781.5 792.5 880.6 881.6 887.3 5521.9 5713.3 5930.4 6000.3 6157.0 406.0 428.6 469.7 484.1 993.0 3910.8 3913.2 4016.1 4035.7 4082.3 10404.9 10761.1 10853.1 10980.0 10981.7 10755.3 10763.8 10765.7 10790.8 10797.0 7698.8 8044.7 8147.2 8212.6 8310.0 5985.6 6014.2 6116.6 6118.8 6141.6 8104.0 9711.2 10064.5 10200.3 10255.9 9625.1 10129.6 10167.8 10481.7 10524.7 6926.5 7154.8 7337.4 7505.7 7584.1 8295.5 8304.9 8351.7 8399.6 8419.3 6034.9 7310.5 7459.0 7593.0 7623.8 7108.7 7158.7 7249.9 7289.8 7364.5 5571.5 6953.8 6998.7 7007.1 7022.5 4778.6 4844.5 4851.8 4958.4 4971.1 4140.6 4858.0 4942.8 5020.4 5068.6 15:53:50 INFO - PROCESS | 1659 | 15:53:50 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4735.71 Median:5142.40 stddev:1513.52 (29.4%) stddev-sans-first:1481.00 15:53:50 INFO - PROCESS | 1659 | Values: 318.5 320.7 326.1 328.5 330.2 4654.0 4951.3 5287.2 5366.7 5375.5 4574.4 5275.5 5307.2 5339.7 5375.3 3619.5 3892.8 3930.5 4070.8 4089.5 3413.9 4034.7 4112.8 4147.5 4155.8 4006.2 4073.3 4088.2 4098.5 4117.5 3185.9 3429.8 3814.1 4079.6 4118.0 2988.9 3573.5 3580.8 3591.0 3593.2 4479.8 5142.0 5152.3 5153.1 5178.6 4118.9 4382.3 4622.5 4690.5 4705.4 4402.6 4685.6 5093.7 5623.4 5628.9 3674.5 3962.4 3982.4 4001.8 4002.6 4636.6 5142.8 5337.9 5340.8 5916.9 3956.6 4048.2 4055.7 4069.6 4078.7 3592.3 4113.3 4115.5 4134.6 4135.5 4687.9 4705.1 4708.4 4712.6 4758.3 4945.4 5168.7 5369.2 5651.6 5671.7 1386.0 1499.3 1516.3 1517.9 1526.9 872.4 988.9 1016.0 1018.7 1033.6 4278.5 4638.6 5133.3 5273.0 5565.0 5048.6 5791.2 5821.5 5846.4 5847.6 4365.6 4862.0 5198.1 5219.5 5220.4 2842.7 3029.5 3106.6 3314.5 3356.4 6598.4 6607.9 6627.9 6646.9 6670.7 6228.3 6389.8 6482.4 6518.5 6543.4 6235.9 6639.0 6643.3 6646.4 6670.1 6006.2 6340.8 6348.8 6352.9 6356.8 6235.3 6240.0 6254.9 6265.3 6273.7 5517.4 5601.6 6292.2 6421.0 6626.6 3725.0 3995.4 4272.9 4289.3 4337.8 5311.4 5356.7 6294.3 6340.0 6341.6 4787.5 5423.4 5906.6 6154.4 6154.8 5789.8 6076.8 6092.2 6106.3 6117.7 5689.1 5701.2 6003.8 6011.3 6030.1 5124.7 5287.5 6126.4 6132.0 6144.2 5261.5 5811.5 5999.6 6054.2 6084.1 15:53:50 INFO - PROCESS | 1659 | 15:53:50 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:17768.17 Median:31036.28 stddev:24907.59 (80.3%) stddev-sans-first:24944.34 15:53:50 INFO - PROCESS | 1659 | Values: 662.4 736.5 737.7 746.7 747.0 14413.3 15049.5 15053.6 16205.0 17006.5 15544.0 15620.8 15689.4 15694.3 15714.3 704.4 728.8 889.2 910.7 924.0 889.3 897.0 899.4 900.5 903.7 853.1 960.4 969.5 969.9 971.0 739.5 918.4 933.2 967.5 972.5 712.3 718.3 719.1 721.5 723.2 14956.3 15007.8 15013.2 15026.0 15043.6 11940.7 12084.5 12163.6 12304.6 12401.4 9896.4 9915.9 9976.0 10008.4 10236.4 3260.3 3352.6 3367.5 3401.7 3434.6 74366.3 74827.6 77306.6 77550.6 77588.0 9370.8 9426.3 9433.6 9504.6 9960.1 20815.5 20832.3 20842.9 20874.4 20881.2 7200.6 7323.7 7400.5 7495.7 7599.8 4079.4 4114.3 4185.7 4208.3 4353.6 3521.6 3602.4 3608.4 3611.5 3614.3 537.7 622.8 645.6 651.7 667.2 559.0 598.3 605.2 613.6 614.6 7842.7 8451.2 8662.0 8668.7 8673.6 587.0 602.2 604.2 608.2 613.2 3810.0 3893.8 3972.6 4064.1 4130.4 87143.6 95716.0 97154.0 97963.5 99661.5 65301.8 70467.0 82098.0 83601.0 86064.3 53619.0 68264.7 69794.3 70062.0 71057.0 8234.8 8278.2 8292.0 8353.0 8472.8 33062.3 40399.0 42420.9 43888.3 44287.3 58819.2 61458.0 61761.0 62386.0 63146.4 10841.5 10864.0 10968.8 11050.1 11132.6 11581.0 12096.3 12126.3 12232.4 12358.3 11110.3 11183.9 11511.2 11549.4 11824.4 11097.9 11438.1 11472.1 11627.8 12264.7 11261.2 11278.2 11279.4 11533.7 11775.6 9329.3 9426.2 9450.3 9511.9 10085.0 9292.7 9363.4 9367.7 9436.4 9612.3 15:53:50 INFO - PROCESS | 1659 | -------- Summary: end -------- 15:53:50 INFO - PROCESS | 1659 | 15:53:50 INFO - TEST-INFO | 1659: exit 0 15:53:50 INFO - TEST-OK | dromaeo_css | took 1114098ms 15:53:50 INFO - TEST-START | kraken 15:53:50 INFO - operating with platform_type : mac_ 15:53:50 INFO - Initialising browser for kraken test... 15:53:50 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmp2JC2D2/profile http://localhost:49274/getInfo.html) 15:53:53 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 15:53:53 INFO - PROCESS | 1669 | colorDepth:24 15:53:53 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 15:53:53 INFO - PROCESS | 1669 | __metrics 15:53:53 INFO - PROCESS | 1669 | 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" 15:53:53 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:53:54 INFO - TEST-INFO | 1669: exit 0 15:53:54 INFO - Browser initialized. 15:53:54 INFO - Running cycle 1/1 for kraken test... 15:53:54 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmp2JC2D2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:54:06 INFO - PROCESS | 1671 | RSS: Main: 211681280 15:54:06 INFO - PROCESS | 1671 | 15:54:08 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 15:54:09 INFO - PROCESS | 1671 | RSS: Main: 270491648 15:54:09 INFO - PROCESS | 1671 | 15:54:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 15:54:13 INFO - PROCESS | 1671 | RSS: Main: 284868608 15:54:13 INFO - PROCESS | 1671 | 15:54:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 15:54:16 INFO - PROCESS | 1671 | RSS: Main: 372379648 15:54:16 INFO - PROCESS | 1671 | 15:54:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 15:54:19 INFO - PROCESS | 1671 | RSS: Main: 279502848 15:54:19 INFO - PROCESS | 1671 | 15:54:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:54:22 INFO - PROCESS | 1671 | RSS: Main: 280141824 15:54:22 INFO - PROCESS | 1671 | 15:54:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 15:54:25 INFO - PROCESS | 1671 | RSS: Main: 319082496 15:54:25 INFO - PROCESS | 1671 | 15:54:28 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 15:54:29 INFO - PROCESS | 1671 | RSS: Main: 318881792 15:54:29 INFO - PROCESS | 1671 | 15:54:31 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 15:54:32 INFO - PROCESS | 1671 | RSS: Main: 318828544 15:54:32 INFO - PROCESS | 1671 | 15:54:34 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:54:34 INFO - PROCESS | 1671 | RSS: Main: 308080640 15:54:34 INFO - PROCESS | 1671 | 15:54:36 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:54:37 INFO - PROCESS | 1671 | RSS: Main: 314744832 15:54:37 INFO - PROCESS | 1671 | 15:54:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:54:40 INFO - PROCESS | 1671 | RSS: Main: 317636608 15:54:40 INFO - PROCESS | 1671 | 15:54:42 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:54:43 INFO - PROCESS | 1671 | RSS: Main: 323006464 15:54:43 INFO - PROCESS | 1671 | 15:54:46 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:54:47 INFO - PROCESS | 1671 | RSS: Main: 320622592 15:54:47 INFO - PROCESS | 1671 | 15:54:48 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 15:54:49 INFO - PROCESS | 1671 | RSS: Main: 385363968 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | __start_tp_report 15:54:49 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 15:54:49 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 15:54:49 INFO - PROCESS | 1671 | |i|pagename|runs| 15:54:49 INFO - PROCESS | 1671 | |0;ai-astar;113;114;108;116;114;114;114;114;113;171 15:54:49 INFO - PROCESS | 1671 | |1;audio-beat-detection;156;127;157;158;123;153;166;145;186;122 15:54:49 INFO - PROCESS | 1671 | |2;audio-dft;186;188;188;184;186;184;184;186;188;187 15:54:49 INFO - PROCESS | 1671 | |3;audio-fft;69;81;73;153;56;56;56;69;146;74 15:54:49 INFO - PROCESS | 1671 | |4;audio-oscillator;90;93;144;78;80;83;125;80;78;126 15:54:49 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;87;86;89;85;87;85;87;85;91;85 15:54:49 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;94;93;97;93;93;94;93;93;94 15:54:49 INFO - PROCESS | 1671 | |7;imaging-desaturate;86;85;85;85;84;87;85;81;91;86 15:54:49 INFO - PROCESS | 1671 | |8;json-parse-financial;61;64;67;64;66;65;66;83;56;58 15:54:49 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;63;67;63;76;77;76;71;69;73;95 15:54:49 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;82;81;80;82;81;81;81;80;82;85 15:54:49 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;136;137;134;144;147;149;135;135;177;137 15:54:49 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;169;166;167;178;207;149;200;219;168;165 15:54:49 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;69;67;70;68;68;67;68;69;67;68 15:54:49 INFO - PROCESS | 1671 | __end_tp_report 15:54:49 INFO - PROCESS | 1671 | __start_cc_report 15:54:49 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2020 15:54:49 INFO - PROCESS | 1671 | __end_cc_report 15:54:49 INFO - PROCESS | 1671 | __startTimestamp1461711289039__endTimestamp 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | ------- Summary: start ------- 15:54:49 INFO - PROCESS | 1671 | Number of tests: 14 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:119.10 Median:114.00 stddev:18.35 (16.1%) stddev-sans-first:19.33 15:54:49 INFO - PROCESS | 1671 | Values: 113.0 114.0 108.0 116.0 114.0 114.0 114.0 114.0 113.0 171.0 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:149.30 Median:156.50 stddev:20.47 (13.1%) stddev-sans-first:21.57 15:54:49 INFO - PROCESS | 1671 | Values: 156.0 127.0 157.0 158.0 123.0 153.0 166.0 145.0 186.0 122.0 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:186.10 Median:186.50 stddev:1.66 (0.9%) stddev-sans-first:1.76 15:54:49 INFO - PROCESS | 1671 | Values: 186.0 188.0 188.0 184.0 186.0 184.0 184.0 186.0 188.0 187.0 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:83.30 Median:69.00 stddev:35.95 (52.1%) stddev-sans-first:37.76 15:54:49 INFO - PROCESS | 1671 | Values: 69.0 81.0 73.0 153.0 56.0 56.0 56.0 69.0 146.0 74.0 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:80.00 stddev:24.47 (30.6%) stddev-sans-first:25.80 15:54:49 INFO - PROCESS | 1671 | Values: 90.0 93.0 144.0 78.0 80.0 83.0 125.0 80.0 78.0 126.0 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.00 stddev:2.00 (2.3%) stddev-sans-first:2.12 15:54:49 INFO - PROCESS | 1671 | Values: 87.0 86.0 89.0 85.0 87.0 85.0 87.0 85.0 91.0 85.0 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:93.50 stddev:1.25 (1.3%) stddev-sans-first:1.30 15:54:49 INFO - PROCESS | 1671 | Values: 93.0 94.0 93.0 97.0 93.0 93.0 94.0 93.0 93.0 94.0 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:85.50 stddev:2.51 (2.9%) stddev-sans-first:2.65 15:54:49 INFO - PROCESS | 1671 | Values: 86.0 85.0 85.0 85.0 84.0 87.0 85.0 81.0 91.0 86.0 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:65.50 stddev:7.29 (11.1%) stddev-sans-first:7.58 15:54:49 INFO - PROCESS | 1671 | Values: 61.0 64.0 67.0 64.0 66.0 65.0 66.0 83.0 56.0 58.0 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:74.50 stddev:9.27 (12.4%) stddev-sans-first:9.10 15:54:49 INFO - PROCESS | 1671 | Values: 63.0 67.0 63.0 76.0 77.0 76.0 71.0 69.0 73.0 95.0 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:81.50 stddev:1.43 (1.8%) stddev-sans-first:1.51 15:54:49 INFO - PROCESS | 1671 | Values: 82.0 81.0 80.0 82.0 81.0 81.0 81.0 80.0 82.0 85.0 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:143.10 Median:140.50 stddev:13.08 (9.3%) stddev-sans-first:13.61 15:54:49 INFO - PROCESS | 1671 | Values: 136.0 137.0 134.0 144.0 147.0 149.0 135.0 135.0 177.0 137.0 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.80 Median:173.50 stddev:22.25 (12.8%) stddev-sans-first:23.32 15:54:49 INFO - PROCESS | 1671 | Values: 169.0 166.0 167.0 178.0 207.0 149.0 200.0 219.0 168.0 165.0 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:1.00 15:54:49 INFO - PROCESS | 1671 | Values: 69.0 67.0 70.0 68.0 68.0 67.0 68.0 69.0 67.0 68.0 15:54:49 INFO - PROCESS | 1671 | -------- Summary: end -------- 15:54:49 INFO - PROCESS | 1671 | 15:54:49 INFO - TEST-INFO | 1671: exit 0 15:54:49 INFO - TEST-OK | kraken | took 58867ms 15:54:49 INFO - SUITE-END | took 1172s 15:54:50 INFO - Completed test suite (00:19:33) 15:54:50 INFO - javascript benchmark 15:54:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3351.0, 3433.536472576203, 3434.8797792077276, 3447.172678557147, 3478.0956951192684, 5538.307711536058, 5555.50000499955, 5562.360328562207, 5576.358718747345, 5587.413321601234, 5566.749496272667, 5590.8043218487255, 5596.748146333414, 5599.7480113394895, 5651.180578816069, 5044.419891712463, 5048.268001139832, 5057.797688092481, 5097.923531147036, 5102.132637451624, 5180.689158650474, 5189.42916279209, 5189.948100519003, 5203.245529398235, 5209.114450543398, 5098.872528186788, 5178.611604129686, 5179.2749015137915, 5206.5834733221445, 5214.165733482643, 5188.45521220274, 5203.453637368059, 5208.8958220835375, 5209.088409528317, 5247.606429517801, 4122.690798190148, 5018.698878067291, 5121.641485096045, 5132.5124113209185, 5160.819371321985, 4600.6549508786975, 5494.615376923617, 5555.2778138842095, 5581.916271255935, 5584.301962254718, 6163.630182189083, 6208.937910620926, 6224.688765561704, 6224.782132625336, 6245.500359971192, 6063.333033366371, 6152.692365381713, 6286.434220920093, 6302.212223472066, 6311.968440157816, 5752.453516915886, 5773.336066352339, 5784.219130417382, 5787.13193021046, 5790.986577349031, 6322.620642761449, 6358.1416508771645, 6364.236291644939, 6376.0, 6377.107204991305, 5887.499562537147, 6002.849928751816, 6015.218021657157, 6102.145699602059, 6117.84887895955, 6197.938020619827, 6212.254529456494, 6244.219472565929, 6246.0, 6272.6550039747335, 5781.479666830005, 6162.106494558354, 6165.722542485599, 6170.907436388457, 6172.845678858064, 6007.489363404072, 6051.425114614105, 6142.600730952474, 6143.232095988002, 6175.3824617538785, 4822.903541929142, 4859.878503037452, 4864.635152363587, 4874.4150701916, 4885.071836351153, 4331.45856767904, 4363.956360436355, 4379.211741886427, 4448.64410847138, 4490.371348011281, 5642.548596112383, 5652.943470565242, 5666.121751128581, 5673.574481913873, 5676.091825307931, 6276.686165691697, 6277.497800175975, 6288.717007734663, 6296.33888441707, 6301.495880329563, 5427.3215848019, 5466.070767969455, 5585.162225666182, 5600.859978500489, 5747.885042299131, 5364.785408583691, 5371.409144994048, 5373.650712703662, 5376.166694162411, 5388.703621300788, 6275.560710750295, 6298.968505157445, 6302.0, 6303.527235457359, 6333.493320534254, 5212.687238765686, 6025.18659980897, 6131.866048757656, 6168.722407491584, 6305.558610897193, 6296.0, 6317.147185129948, 6330.936690633035, 6335.176427064636, 6340.4293613575, 6173.475254603409, 6185.690715464299, 6190.2571691397325, 6195.4424101831055, 6200.0699895016105, 5608.6073974821375, 5944.286685597583, 6025.156478092983, 6136.8772624548155, 6174.166487524127, 6019.969900150646, 6045.848853778691, 6046.54650901193, 6048.6975651218145, 6073.30157031947, 6001.36985616504, 6044.909326360196, 6052.576319657758, 6056.424639659225, 6068.180795592539, 5229.973850130725, 5413.323334583177, 5791.0734282514595, 5897.351291358033, 5915.615484993462, 4523.954760452353, 6046.909296360646, 6054.0, 6066.817995460143, 6076.969615151896, 5920.526357891445, 5922.496587790086, 5924.7926322578505, 5930.466258036798, 5939.584229103922], "value": 5622.905675426505, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [524.7166530073761, 529.6768970927733, 529.8304542546387, 542.422320228956, 560.4423598519473, 25572.872135639318, 32438.026859194204, 32444.675553244462, 32468.512972305434, 32548.349033019327, 40415.59584404155, 41307.793461032656, 41426.0, 41496.58503414965, 41529.58470415295, 9426.0, 9653.903460965406, 9661.806763864754, 9674.03259674034, 9674.129328360446, 8597.656093756274, 8806.64773409063, 9562.18721408681, 9700.757481062994, 9717.757056073584, 11625.534978600846, 11878.465469053914, 11879.643610691694, 11909.464074116642, 11921.58274460394, 11794.233374830652, 11925.88074119265, 11930.701732456671, 11941.402929853559, 11959.282443053358, 7763.495372800807, 10506.632267870664, 11282.94358528202, 11570.826437603442, 11577.189596728233, 39085.804570976965, 39636.0, 39683.00792480182, 39713.80143099266, 39717.40423893644, 2698.541247987847, 3250.983745081283, 3424.6232914379398, 3563.2873425314783, 3781.357169281229, 28927.710722892713, 29329.0, 29721.0, 29738.0, 30046.79812807485, 2770.7783377329965, 2773.3066733316673, 2795.398989217328, 2801.0616443491454, 2891.2193707698802, 485250.0, 486349.0, 486594.0, 486621.0, 487089.0, 66646.0, 67162.99255511172, 67184.66407668029, 67221.32778672248, 67302.3269767296, 68667.96998045033, 68710.0, 68882.96675549871, 68904.0, 69052.5115867467, 17784.91107544454, 18013.369532066314, 18026.63946721058, 18027.18877650509, 18044.819551804576, 13840.169589824643, 13903.235322057182, 13925.651858703412, 13927.164370137823, 13940.930295348458, 15739.606509837346, 15973.600659983593, 18113.003784791963, 18421.90789046046, 18421.90789046073, 6241.906371404432, 6334.0, 7358.595277259802, 7511.244377811095, 7634.427417943676, 926.6802952981211, 980.9166220871164, 981.8036392721341, 1024.4672770159475, 1029.6344797596896, 29073.563896541567, 29505.0, 29680.554791678143, 29735.810567577053, 30408.391832163234, 1092.4373947417087, 1107.7562936154034, 1147.8909503597313, 1169.2575214738702, 1193.629974707826, 12294.815577766341, 13303.733925321632, 15195.696086078438, 15308.158051307286, 15485.922570387076, 460438.0, 467781.0, 468642.0, 468876.0, 639167.0, 63797.362026379145, 63980.0, 64796.0, 64840.027399589046, 64989.350106499274, 1420.996395082928, 1446.0817380963374, 1579.439299048844, 1585.9127747973973, 1601.4074792326915, 24919.845108438516, 25887.0, 28697.42605147927, 31320.686793131776, 31665.032378809236, 61976.69011654913, 62400.687996561544, 62945.649619781, 62954.685226575406, 63003.0, 431350.0, 433338.0, 434158.0, 436039.0, 437093.8145309253, 34681.9595412138, 44552.331715023654, 45011.774941126394, 45452.54547454483, 46084.53915460937, 35771.82114089413, 38126.428103579026, 38607.42088868613, 46454.53545464637, 47128.29307560321, 37345.813270933475, 39282.410763839136, 39471.407928880515, 40336.1932761349, 40396.16236457532, 38436.23127537378, 38553.0, 39009.80495097506, 39145.6085439142, 40707.0, 37944.430833536964, 38410.6158938407, 38677.6132238674, 38934.2213155741, 38969.2206155881, 24950.37624059413, 31103.688963110984, 33869.45979051532, 35167.648323516434, 35190.648093518736, 21957.67063494017, 25615.359616009748, 25830.87084564565, 26022.0, 26041.869790650926], "value": 20250.719152974874, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.6024195683338, 397.85876014015025, 401.9638232559069, 402.2136722707108, 412.63894092668914, 14631.780523292158, 17842.82157178428, 21164.470888227803, 21362.359129226108, 21418.25036123736, 15889.09330880296, 19890.10494527746, 20138.49653758657, 20678.069486873126, 20831.0, 5668.206451096849, 5942.405759424045, 5959.284885813709, 5975.1037344398255, 5979.402059794008, 5773.047447171232, 5856.56075794315, 5878.823635290948, 5978.461938425541, 6028.065649824262, 4914.557689807916, 5656.773729050833, 5800.15897694834, 5806.258122563236, 5811.506021988115, 5678.9432105678825, 5776.451237132465, 5779.422057794229, 5781.306243250795, 5782.10377391505, 4720.89059071118, 4975.502449755032, 5146.0, 5157.48425157485, 5161.870953226162, 23415.0, 25457.981680732748, 25958.610620840704, 26294.73705262961, 26537.86731066332, 2171.0989939175224, 2299.6435552489224, 2529.6458495810602, 2990.1179152150166, 2990.596269503611, 9283.118103780198, 9713.61145554177, 9909.65316213929, 10276.486175691185, 14399.992000559965, 1704.369383328164, 1763.1360633289723, 1785.9017754023523, 1835.2016872660436, 1871.803460636627, 127450.0, 129531.0, 131993.99999999904, 134482.0, 136572.31713841466, 9276.628934842665, 11472.598459053894, 11614.709632259091, 11700.414979251174, 11776.17566770335, 14686.265686715622, 14950.850491495163, 15027.948043636841, 15034.02278851871, 15055.698886022219, 6359.777407790705, 8261.545614991113, 8471.66113355459, 8778.253848422955, 9025.097490251053, 8917.761847869297, 8922.955385223162, 8984.775380615536, 9076.228520575825, 9109.772255693528, 4544.2047641662575, 4615.838445654453, 4618.515055919147, 4670.95329046712, 4679.719216847, 1641.6306331075411, 1738.9304427823, 1933.3040105561986, 1948.7174359717762, 1996.464490097568, 885.1856292211181, 926.6756635177769, 1021.9131373833308, 1040.2822052783556, 1057.9629712960163, 16236.02583844973, 19428.708569371473, 19987.500312492302, 20783.552911597806, 20922.895385522974, 965.7006328038331, 986.5116767200282, 992.3153024413132, 1015.1117771949545, 1026.743314171457, 8709.608067636971, 10679.199060070527, 10855.348679079281, 10859.511321990529, 10872.0, 585507.0, 586064.0696796488, 595669.0, 603034.0, 605953.0, 96545.03454965501, 135457.3227133858, 140882.2955885214, 141075.0, 145100.5489945108, 1004.3923426326967, 1031.9432431216205, 1052.3528030261334, 1054.7784965157402, 1056.1128651932413, 17678.02770847616, 17729.37947171843, 18024.36914707979, 18791.436256912315, 18795.96622185793, 52887.471125288255, 53382.932341353735, 55505.0, 56203.71898140483, 56712.582185444044, 80464.0, 82203.0, 82351.0, 82653.86893945796, 84720.57639711762, 7777.222277772404, 8292.253697167283, 8333.2916702081, 8405.369597280107, 8692.521911294687, 9387.765305867408, 12600.306983116017, 12810.29543375086, 12927.806082908952, 13061.804072938723, 11007.669769906915, 11177.888221117684, 11294.265872718248, 11333.603323883624, 11406.942965285121, 10822.296550724177, 10946.835797463204, 11073.335599864035, 11183.88816111861, 11297.661070167907, 11247.325160490069, 11432.085433165494, 11572.710682233012, 11645.243059201124, 11890.34603096838, 8192.18078192176, 8264.710735124241, 8375.07874133857, 8408.327333813402, 8693.956530217309, 8277.089520152658, 10303.536340864828, 11229.101671866394, 11493.0, 11535.307881527135], "value": 9634.923141027562, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.8506006869286, 1580.249381543767, 1600.3518574977136, 1813.8004819469857, 1928.4503916383833, 7742.0, 8572.528510931896, 8816.382853200279, 8830.91169088309, 8832.205101540869, 8452.366072544553, 8664.870026949586, 8676.8698469523, 8694.695685651004, 8703.738887833375, 2076.979230207701, 2410.132352353147, 2456.815738819587, 2462.6182941644074, 2465.272744540356, 2416.8791560421996, 2440.1825388494876, 2463.4949835283815, 2468.5062987402594, 2478.021181633254, 2444.7310795812446, 2454.165583701541, 2468.5803413419762, 2481.925542233736, 2483.081259933827, 2420.47959688666, 2438.5366780311774, 2450.13020377766, 2452.2152911068465, 2463.3964678653665, 2411.7588241175918, 2439.634054891763, 2441.56051910656, 2444.0, 2445.608702607592, 8492.490450572923, 8683.305335573203, 8763.430377025474, 8769.298456123495, 8770.473771573726, 5196.792128314863, 5440.591955603346, 5448.101063324586, 5454.372747134091, 5461.098918678414, 3508.912277193065, 3537.7169826413824, 3631.3100510902846, 3664.120611053355, 3666.5416822897137, 1002.6390499420203, 1048.2557384257186, 1051.7948999945013, 1059.0786016165907, 1088.8747794003712, 9587.616495340179, 11606.941965290203, 11829.349385783778, 11841.881581184078, 11901.285922844658, 4580.175568397721, 4638.350630911675, 4695.2252878275385, 4724.314974328703, 4740.762961851894, 9313.022132675971, 9315.673951411804, 9330.346875718771, 9342.06579342074, 9348.252139828934, 5252.763625636855, 5304.655197412156, 5305.257263983045, 5307.495787900143, 5349.063913815145, 4154.688398370135, 4429.512753597101, 4584.472785629695, 4601.010782681673, 4967.379077615298, 3831.8467261309233, 3877.302085624615, 3927.9607203927594, 3943.6056394360335, 3962.6433620973676, 1210.352461433129, 1230.9876901231053, 1233.888949994487, 1241.875812418751, 1245.725940293134, 781.5349866829227, 792.5046845721424, 880.6081293824315, 881.5768431153083, 887.2768693514765, 5521.889562208733, 5713.285839270091, 5930.406959304121, 6000.309964354068, 6157.0, 406.02148821340427, 428.6121060440244, 469.66419010407725, 484.1068229117273, 992.950352482373, 3910.7849068300948, 3913.1977944521873, 4016.096378314856, 4035.7376770509823, 4082.2856000199845, 10404.895951040544, 10761.085307748772, 10853.131749460024, 10980.011798938134, 10981.670549883516, 10755.300905441121, 10763.78472430547, 10765.677029689123, 10790.784184316426, 10797.028267455968, 7698.807529811799, 8044.718434854752, 8147.185281471805, 8212.63043163059, 8310.0, 5985.610935289193, 6014.158017877413, 6116.571839971337, 6118.847028824315, 6141.631502109888, 8104.0, 9711.174550163316, 10064.54709538058, 10200.3369780964, 10255.897441025494, 9625.133737963337, 10129.59481620727, 10167.79664406693, 10481.685549433529, 10524.684259472227, 6926.549774264657, 7154.785356439315, 7337.376323012603, 7505.66224519887, 7584.08990921093, 8295.543745093837, 8304.916950830415, 8351.749447516584, 8399.622017009127, 8419.326453883552, 6034.9094763577705, 7310.52481588695, 7459.0, 7592.962035189788, 7623.809404764926, 7108.715651373887, 7158.749443769443, 7249.855002899807, 7289.781306560812, 7364.484486086137, 5571.470710282517, 6953.756618518328, 6998.685059172248, 7007.054047703495, 7022.508424410242, 4778.617710583215, 4844.46710861812, 4851.830185943616, 4958.404991401055, 4971.080350135237, 4140.627343539096, 4857.975710121426, 4942.802287908444, 5020.397552293748, 5068.569171620454], "value": 4502.878713376595, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.50631521142236, 320.72097275370425, 326.1438723351203, 328.47772042452505, 330.1762103551636, 4653.976125252443, 4951.331570238017, 5287.206918962158, 5366.70483123428, 5375.489328513795, 4574.428196475441, 5275.498827611371, 5307.177387504943, 5339.733013349327, 5375.274337964373, 3619.475176099464, 3892.800567679259, 3930.4890364252606, 4070.75575465473, 4089.4683691120113, 3413.9487907681405, 4034.7175697701173, 4112.773797441139, 4147.4815648044, 4155.750654960697, 4006.2187873364705, 4073.327900896363, 4088.2028004539125, 4098.4876890388705, 4117.46472958515, 3185.888493902714, 3429.7770644908, 3814.1036856338737, 4079.6328330450256, 4117.958820411787, 2988.9103326900226, 3573.5175751273764, 3580.8030558319283, 3591.0, 3593.245418462126, 4479.776011199459, 5141.971605678879, 5152.304438900738, 5153.123968925292, 5178.6116041297055, 4118.8673114893345, 4382.2631633627325, 4622.468416132154, 4690.530946905316, 4705.411823522059, 4402.647788176938, 4685.5782979532005, 5093.719845408501, 5623.409541998113, 5628.859278518029, 3674.4855720199193, 3962.385830196324, 3982.3871332110543, 4001.7598944063448, 4002.619751123668, 4636.605888499482, 5142.820001299937, 5337.866553336159, 5340.813071542478, 5916.881662366729, 3956.624120708528, 4048.190361927567, 4055.7224474290506, 4069.613386728256, 4078.7144899856726, 3592.263585964871, 4113.321301985199, 4115.52671442782, 4134.607212314825, 4135.483064616923, 4687.882802929954, 4705.058988202373, 4708.387909571734, 4712.562668386106, 4758.262469317261, 4945.357103576512, 5168.741562921914, 5369.246794792717, 5651.57613179005, 5671.744771485293, 1386.002078503496, 1499.295331194337, 1516.2646116633423, 1517.9165145916859, 1526.8702160316498, 872.4416373520952, 988.9011098890054, 1015.9949200253951, 1018.6791160784329, 1033.5968972100886, 4278.4865816102265, 4638.628909687217, 5133.281340612317, 5272.99813035522, 5564.972175139179, 5048.570871475892, 5791.247137872049, 5821.476067153976, 5846.4153584642045, 5847.6199047062655, 4365.563443655609, 4862.0, 5198.090334191501, 5219.451957544479, 5220.399654039834, 2842.7299406556344, 3029.5304227844717, 3106.6272047354464, 3314.5359649649067, 3356.44618637922, 6598.373154550406, 6607.867842643121, 6627.86744265112, 6646.933530664728, 6670.666466676646, 6228.28374736902, 6389.840253993594, 6482.448991835747, 6518.478521718251, 6543.411093001558, 6235.875282494325, 6638.970959501382, 6643.335666433415, 6646.368594983469, 6670.132882725215, 6006.189164462741, 6340.809775706736, 6348.841278968062, 6352.936470635234, 6356.809295721136, 6235.314115447388, 6240.0, 6254.937450625435, 6265.274605546601, 6273.71768270438, 5517.44825517445, 5601.60788744784, 6292.244930608356, 6420.967895160495, 6626.641538484695, 3724.975631701304, 3995.360742281221, 4272.914541709086, 4289.332620609334, 4337.8481753138485, 5311.362636483646, 5356.705381204071, 6294.300538854575, 6340.0, 6341.619502829845, 4787.521247875185, 5423.4034256231, 5906.586538942232, 6154.415330543591, 6154.753809847556, 5789.8263052108505, 6076.817695469143, 6092.208012958287, 6106.297775755845, 6117.696443920233, 5689.146628005832, 5701.230333904869, 6003.759849606142, 6011.338752737108, 6030.095485677183, 5124.692518448905, 5287.5274091315305, 6126.387361263838, 6131.995322291956, 6144.2319710036245, 5261.4738526147075, 5811.506021988178, 5999.610025348338, 6054.243219597551, 6084.148219249397], "value": 4270.556101335983, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.400527522592, 736.5286216821236, 737.7381029734447, 746.727444482764, 746.9589172595505, 14413.279336033196, 15049.473268435593, 15053.62365940852, 16205.027698338079, 17006.48980530586, 15544.0, 15620.843791562082, 15689.372425102982, 15694.293756780944, 15714.292856821441, 704.376626685382, 728.7995801154672, 889.1952782731638, 910.658503061352, 924.0343840686498, 889.2841262783456, 897.0491279243989, 899.4468401932797, 900.4507250577143, 903.7243640689587, 853.0999795216045, 960.3565611040584, 969.4667932637021, 969.9466529340883, 970.9611615535371, 739.5008369350694, 918.4443411735904, 933.1508327422081, 967.4727273635885, 972.5380444288977, 712.2841544248049, 718.2745427118648, 719.0975325965932, 721.4733244731375, 723.2008630463315, 14956.326965286587, 15007.774883376758, 15013.174275414847, 15026.023308484913, 15043.623909402244, 11940.70148246292, 12084.456199470957, 12163.574274900337, 12304.630861074182, 12401.44193511285, 9896.356736812084, 9915.900840991642, 9976.0, 10008.399496030188, 10236.385816851012, 3260.2827377976805, 3352.597688277422, 3367.4948757686298, 3401.744869134825, 3434.553508043963, 74366.25633743702, 74827.62586187088, 77306.61346693287, 77550.6122469384, 77588.0, 9370.812583748424, 9426.293028022788, 9433.622655093857, 9504.619815207314, 9960.053794889474, 20815.479613009797, 20832.27087051946, 20842.89578552128, 20874.37376878696, 20881.164753409998, 7200.639968001579, 7323.7436689715605, 7400.5189662671755, 7495.66269517873, 7599.772006839804, 4079.3880917862557, 4114.259433301974, 4185.748855068706, 4208.326667733148, 4353.608175264241, 3521.647835216509, 3602.405603075463, 3608.3685355062757, 3611.4582812578324, 3614.29521243355, 537.7311344327836, 622.8131560531822, 645.6384424722171, 651.6871901487312, 667.2393471442559, 558.9972050139723, 598.3448124303881, 605.1709158452948, 613.6042252746953, 614.5575185866259, 7842.686292548348, 8451.197136272045, 8662.0, 8668.653253869774, 8673.609687564074, 587.0366055004923, 602.235161345098, 604.1753007145215, 608.2123649873424, 613.1936503497975, 3809.980950095232, 3893.770067451516, 3972.5828787977425, 4064.085580744309, 4130.359794231899, 87143.56428217818, 95716.04283957211, 97154.0284597145, 97963.51018244863, 99661.50169249107, 65301.79632934864, 70467.0, 82098.0, 83601.0, 86064.27871442537, 53619.0, 68264.6586767063, 69794.3020569798, 70062.0, 71057.0, 8234.835303293781, 8278.213569710868, 8291.963504561929, 8353.039400469024, 8472.788180295542, 33062.33875322432, 40399.0, 42420.939476512096, 43888.34167487426, 44287.335689964035, 58819.23542293738, 61458.0, 61761.0, 62386.0, 63146.36853631405, 10841.457927103456, 10863.967923047614, 10968.780624387307, 11050.06074483678, 11132.55469781232, 11580.957713805798, 12096.334701591497, 12126.333051682243, 12232.388380580758, 12358.258504489759, 11110.277831940897, 11183.888161118284, 11511.19421640477, 11549.42252887369, 11824.40877956116, 11097.889021109686, 11438.052212849105, 11472.139589530652, 11627.767444651228, 12264.749841291887, 11261.155413343868, 11278.210525263154, 11279.436028198394, 11533.653990380302, 11775.646730598095, 9329.346945713734, 9426.18662015697, 9450.291228157777, 9511.904880951102, 10085.0, 9292.674756383494, 9363.4381937084, 9367.672131475365, 9436.433813971184, 9612.279079068958], "value": 6847.715042090845, "unit": "score"}], "name": "dromaeo_css", "value": 7243.84655448573, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 108.0, 116.0, 114.0, 114.0, 114.0, 114.0, 113.0, 171.0], "value": 119.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 127.0, 157.0, 158.0, 123.0, 153.0, 166.0, 145.0, 186.0, 122.0], "value": 149.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 188.0, 188.0, 184.0, 186.0, 184.0, 184.0, 186.0, 188.0, 187.0], "value": 186.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 81.0, 73.0, 153.0, 56.0, 56.0, 56.0, 69.0, 146.0, 74.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 144.0, 78.0, 80.0, 83.0, 125.0, 80.0, 78.0, 126.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 89.0, 85.0, 87.0, 85.0, 87.0, 85.0, 91.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 97.0, 93.0, 93.0, 94.0, 93.0, 93.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 85.0, 84.0, 87.0, 85.0, 81.0, 91.0, 86.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 67.0, 64.0, 66.0, 65.0, 66.0, 83.0, 56.0, 58.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 63.0, 76.0, 77.0, 76.0, 71.0, 69.0, 73.0, 95.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 80.0, 82.0, 81.0, 81.0, 81.0, 80.0, 82.0, 85.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 134.0, 144.0, 147.0, 149.0, 135.0, 135.0, 177.0, 137.0], "value": 143.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 167.0, 178.0, 207.0, 149.0, 200.0, 219.0, 168.0, 165.0], "value": 178.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 70.0, 68.0, 68.0, 67.0, 68.0, 69.0, 67.0, 68.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1510.8999999999999, "alertThreshold": 2.0}]} 15:54:50 INFO - Return code: 0 15:54:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:54:50 ERROR - # TBPL SUCCESS # 15:54:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:54:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:54:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:54:50 INFO - 2016-04-26 15:54:50.364 screenresolution[1675:10036] starting screenresolution argv=screenresolution get 15:54:50 INFO - 2016-04-26 15:54:50.367 screenresolution[1675:10036] Display 0: 1600x1200x32@60 15:54:50 INFO - 2016-04-26 15:54:50.373 screenresolution[1676:10039] starting screenresolution argv=screenresolution list 15:54:50 INFO - Available Modes on Display 0 15:54:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:54:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:54:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:54:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:54:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:54:50 INFO - Intel Iris: 15:54:50 INFO - Chipset Model: Intel Iris 15:54:50 INFO - Type: GPU 15:54:50 INFO - Bus: Built-In 15:54:50 INFO - VRAM (Dynamic, Max): 1536 MB 15:54:50 INFO - Vendor: Intel (0x8086) 15:54:50 INFO - Device ID: 0x0a2e 15:54:50 INFO - Revision ID: 0x0009 15:54:50 INFO - Displays: 15:54:50 INFO - Display: 15:54:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:54:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:54:50 INFO - Display Serial Number: 1600x1200 60 15:54:50 INFO - Main Display: Yes 15:54:50 INFO - Mirror: Off 15:54:50 INFO - Online: Yes 15:54:50 INFO - Rotation: Supported 15:54:50 INFO - Return code: 0 15:54:50 INFO - Running post-action listener: _resource_record_post_action 15:54:50 INFO - Running post-run listener: _resource_record_post_run 15:54:51 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 37807616; Write bytes: 654871552; Read time: 388; Write time: 1963 15:54:51 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342741504; Write bytes: 337241600; Read time: 26498; Write time: 658 15:54:51 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 36836864; Write bytes: 298296832; Read time: 372; Write time: 1245 15:54:51 INFO - Running post-run listener: _upload_blobber_files 15:54:51 INFO - Blob upload gear active. 15:54:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:54:51 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:54:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:54:52 INFO - (blobuploader) - INFO - Open directory for files ... 15:54:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:54:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:54:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:52 INFO - (blobuploader) - INFO - Done attempting. 15:54:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:54:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:54:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:54:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:54:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:54:53 INFO - (blobuploader) - INFO - Done attempting. 15:54:53 INFO - (blobuploader) - INFO - Iteration through files over. 15:54:53 INFO - Return code: 0 15:54:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:54:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:54:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fed163591382256fab47e71ddd1b615fb52f68da604f55712bacb349deb43cb37308f630e3851ed4b11617f069a8b7a8a53039f4c5189d602e0e17d4f5760173", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51df236654c98a153d169cc844259a28201faa9219fbd543ef18045f18c4f27c26eda2d34375c9b2f3f1aaad7e2544a045ebafcdb23aca46d11cfceb1bd46920"} 15:54:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:54:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:54:53 INFO - Contents: 15:54:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fed163591382256fab47e71ddd1b615fb52f68da604f55712bacb349deb43cb37308f630e3851ed4b11617f069a8b7a8a53039f4c5189d602e0e17d4f5760173", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51df236654c98a153d169cc844259a28201faa9219fbd543ef18045f18c4f27c26eda2d34375c9b2f3f1aaad7e2544a045ebafcdb23aca46d11cfceb1bd46920"} 15:54:53 INFO - Running post-run listener: copy_logs_to_upload_dir 15:54:53 INFO - Copying logs to upload dir... 15:54:53 INFO - mkdir: /builds/slave/test/build/upload/logs 15:54:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.655838 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-04-26 15:54:53.933947) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 15:54:53.941554) ========= 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.XAOJuUUqE3/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.hlYVSo0N6t/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fed163591382256fab47e71ddd1b615fb52f68da604f55712bacb349deb43cb37308f630e3851ed4b11617f069a8b7a8a53039f4c5189d602e0e17d4f5760173", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51df236654c98a153d169cc844259a28201faa9219fbd543ef18045f18c4f27c26eda2d34375c9b2f3f1aaad7e2544a045ebafcdb23aca46d11cfceb1bd46920"} build_url:https://queue.taskcluster.net/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010359 build_url: 'https://queue.taskcluster.net/v1/task/XHXMyptZRzayWvnGrpHa5w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fed163591382256fab47e71ddd1b615fb52f68da604f55712bacb349deb43cb37308f630e3851ed4b11617f069a8b7a8a53039f4c5189d602e0e17d4f5760173", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51df236654c98a153d169cc844259a28201faa9219fbd543ef18045f18c4f27c26eda2d34375c9b2f3f1aaad7e2544a045ebafcdb23aca46d11cfceb1bd46920"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 15:54:53.971477) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:54:53.971817) ========= 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.XAOJuUUqE3/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.hlYVSo0N6t/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.005139 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 15:54:54.020856) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 15:54:54.024209) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 15:54:54.024653) ========= ========= Total master_lag: 0.97 =========