builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0093 starttime: 1460996375.24 results: success (0) buildid: 20160418074021 builduid: ecbcdfd5295149c499e3f2b34e05cf2a revision: 72bdf0a085a53a9ff83511bee4dcc353731866a7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:35.241559) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:35.242007) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:35.242275) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:35.266461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:35.266814) ========= 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.5FLb3VRdeo/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.H5og91mo08/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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-18 09:19:35-- 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% 156M=0s 2016-04-18 09:19:35 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142424 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:35.426602) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:35.426924) ========= 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.5FLb3VRdeo/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.H5og91mo08/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.020170 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:35.475333) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-18 09:19:35.475688) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 72bdf0a085a53a9ff83511bee4dcc353731866a7 --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 72bdf0a085a53a9ff83511bee4dcc353731866a7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5FLb3VRdeo/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.H5og91mo08/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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-18 09:19:35,556 truncating revision to first 12 chars 2016-04-18 09:19:35,556 Setting DEBUG logging. 2016-04-18 09:19:35,556 attempt 1/10 2016-04-18 09:19:35,556 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/72bdf0a085a5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-18 09:19:37,340 unpacking tar archive at: mozilla-aurora-72bdf0a085a5/testing/mozharness/ program finished with exit code 0 elapsedTime=2.028939 ========= master_lag: 0.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-18 09:19:37.668536) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:37.671878) ========= script_repo_revision: 72bdf0a085a53a9ff83511bee4dcc353731866a7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:37.672309) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:37.672571) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 09:19:37.725194) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-18 09:19:37.725529) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.5FLb3VRdeo/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.H5og91mo08/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 09:19:37 INFO - MultiFileLogger online at 20160418 09:19:37 in /builds/slave/test 09:19:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:19:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:19:37 INFO - {'append_to_log': False, 09:19:37 INFO - 'base_work_dir': '/builds/slave/test', 09:19:37 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:19:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:19:37 INFO - 'branch': 'Mozilla-Aurora', 09:19:37 INFO - 'buildbot_json_path': 'buildprops.json', 09:19:37 INFO - 'config_files': ('talos/mac_config.py',), 09:19:37 INFO - 'default_actions': ('clobber', 09:19:37 INFO - 'read-buildbot-config', 09:19:37 INFO - 'download-and-extract', 09:19:37 INFO - 'populate-webroot', 09:19:37 INFO - 'create-virtualenv', 09:19:37 INFO - 'install', 09:19:37 INFO - 'run-tests'), 09:19:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:19:37 INFO - 'download_minidump_stackwalk': True, 09:19:37 INFO - 'download_symbols': 'ondemand', 09:19:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:19:37 INFO - 'tooltool.py': '/tools/tooltool.py', 09:19:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:19:37 INFO - '/tools/misc-python/virtualenv.py')}, 09:19:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:19:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:19:37 INFO - 'installer_path': 'installer.exe', 09:19:37 INFO - 'log_level': 'info', 09:19:37 INFO - 'log_name': 'talos', 09:19:37 INFO - 'log_to_console': True, 09:19:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:19:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:19:37 INFO - 'opt_config_files': (), 09:19:37 INFO - 'pip_index': False, 09:19:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:19:37 INFO - 'cmd': ('bash', 09:19:37 INFO - '-c', 09:19:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:19:37 INFO - 'enabled': True, 09:19:37 INFO - 'halt_on_failure': False, 09:19:37 INFO - 'name': 'check_screen_resolution'},), 09:19:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:19:37 INFO - 'cmd': ('bash', 09:19:37 INFO - '-c', 09:19:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:19:37 INFO - 'enabled': True, 09:19:37 INFO - 'halt_on_failure': False, 09:19:37 INFO - 'name': 'check_screen_resolution'},), 09:19:37 INFO - 'run_cmd_checks_enabled': True, 09:19:37 INFO - 'sps_profile': False, 09:19:37 INFO - 'sps_profile_interval': 0, 09:19:37 INFO - 'suite': 'dromaeojs-e10s', 09:19:37 INFO - 'system_bits': '32', 09:19:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:19:37 INFO - 'title': 't-yosemite-r7-0093', 09:19:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:19:37 INFO - 'use_talos_json': True, 09:19:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:19:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:19:37 INFO - 'work_dir': 'build'} 09:19:37 INFO - ##### 09:19:37 INFO - ##### Running clobber step. 09:19:37 INFO - ##### 09:19:37 INFO - Running pre-action listener: _resource_record_pre_action 09:19:37 INFO - Running main action method: clobber 09:19:37 INFO - rmtree: /builds/slave/test/build 09:19:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:19:39 INFO - Running post-action listener: _resource_record_post_action 09:19:39 INFO - ##### 09:19:39 INFO - ##### Running read-buildbot-config step. 09:19:39 INFO - ##### 09:19:39 INFO - Running pre-action listener: _resource_record_pre_action 09:19:39 INFO - Running main action method: read_buildbot_config 09:19:39 INFO - Using buildbot properties: 09:19:39 INFO - { 09:19:39 INFO - "project": "", 09:19:39 INFO - "product": "firefox", 09:19:39 INFO - "script_repo_revision": "production", 09:19:39 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:19:39 INFO - "repository": "", 09:19:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:19:39 INFO - "buildid": "20160418074021", 09:19:39 INFO - "pgo_build": "False", 09:19:39 INFO - "basedir": "/builds/slave/test", 09:19:39 INFO - "buildnumber": 274, 09:19:39 INFO - "slavename": "t-yosemite-r7-0093", 09:19:39 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:19:39 INFO - "platform": "yosemite_r7", 09:19:39 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:19:39 INFO - "branch": "mozilla-aurora", 09:19:39 INFO - "repo_path": "releases/mozilla-aurora", 09:19:39 INFO - "revision": "72bdf0a085a53a9ff83511bee4dcc353731866a7", 09:19:39 INFO - "stage_platform": "macosx64", 09:19:39 INFO - "builduid": "ecbcdfd5295149c499e3f2b34e05cf2a", 09:19:39 INFO - "slavebuilddir": "test" 09:19:39 INFO - } 09:19:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 09:19:39 INFO - Running post-action listener: _resource_record_post_action 09:19:39 INFO - ##### 09:19:39 INFO - ##### Running download-and-extract step. 09:19:39 INFO - ##### 09:19:39 INFO - Running pre-action listener: _resource_record_pre_action 09:19:39 INFO - Running main action method: download_and_extract 09:19:39 INFO - mkdir: /builds/slave/test/build/tests 09:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:19:39 INFO - https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:19:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 09:19:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 09:19:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 09:19:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 09:19:42 INFO - Downloaded 1277 bytes. 09:19:42 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 09:19:42 INFO - Using the following test package requirements: 09:19:42 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 09:19:42 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:42 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 09:19:42 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:42 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 09:19:42 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:42 INFO - u'jsshell-mac.zip'], 09:19:42 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:42 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 09:19:42 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 09:19:42 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:42 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 09:19:42 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:42 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 09:19:42 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:42 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 09:19:42 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 09:19:42 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 09:19:42 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 09:19:42 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:19:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:19:42 INFO - https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:19:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:19:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:19:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:19:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:19:44 INFO - Downloaded 17911052 bytes. 09:19:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:19:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:19:47 INFO - Return code: 0 09:19:47 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:19:47 INFO - https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:19:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:19:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:19:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 09:19:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:19:49 INFO - Downloaded 17911052 bytes. 09:19:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:19:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:19:53 INFO - Return code: 0 09:19: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')]} 09:19:53 INFO - https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:19:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 09:19:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 09:19:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 09:19:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:19:54 INFO - Downloaded 10845168 bytes. 09:19:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:19:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:19:54 INFO - Return code: 0 09:19: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')]} 09:19:54 INFO - https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 09:19:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 09:19:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 09:19:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 09:19:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 09:19:57 INFO - Downloaded 115243518 bytes. 09:19:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 09:19:57 INFO - mkdir: /builds/slave/test/properties 09:19:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:19:57 INFO - Writing to file /builds/slave/test/properties/build_url 09:19:57 INFO - Contents: 09:19:57 INFO - build_url:https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 09:19:58 INFO - Running post-action listener: _resource_record_post_action 09:19:58 INFO - Running post-action listener: set_extra_try_arguments 09:19:58 INFO - ##### 09:19:58 INFO - ##### Running populate-webroot step. 09:19:58 INFO - ##### 09:19:58 INFO - Running pre-action listener: _resource_record_pre_action 09:19:58 INFO - Running main action method: populate_webroot 09:19:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:19:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:19:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:19:58 INFO - '--tppagecycles', 09:19:58 INFO - '7'], 09:19:58 INFO - 'tests': ['tsvgm']}}, 09:19:58 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:19:58 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:19:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:19:58 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 09:19:58 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:19:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:19:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:19:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:58 INFO - 'talos_options': ['--e10s'], 09:19:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:19:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:58 INFO - 'tests': ['damp', 'tps']}, 09:19:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:58 INFO - 'talos_options': ['--e10s'], 09:19:58 INFO - 'tests': ['damp', 'tps']}, 09:19:58 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:19:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:19:58 INFO - 'other': {'tests': ['a11yr', 09:19:58 INFO - 'ts_paint', 09:19:58 INFO - 'tpaint', 09:19:58 INFO - 'sessionrestore', 09:19:58 INFO - 'sessionrestore_no_auto_restore', 09:19:58 INFO - 'tabpaint']}, 09:19:58 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:19:58 INFO - 'tests': ['a11yr', 09:19:58 INFO - 'ts_paint', 09:19:58 INFO - 'tpaint', 09:19:58 INFO - 'sessionrestore', 09:19:58 INFO - 'sessionrestore_no_auto_restore', 09:19:58 INFO - 'tabpaint']}, 09:19:58 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:19:58 INFO - 'tests': ['a11yr', 09:19:58 INFO - 'ts_paint', 09:19:58 INFO - 'tpaint', 09:19:58 INFO - 'sessionrestore', 09:19:58 INFO - 'sessionrestore_no_auto_restore', 09:19:58 INFO - 'tabpaint']}, 09:19:58 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:19:58 INFO - 'tests': ['a11yr', 09:19:58 INFO - 'ts_paint', 09:19:58 INFO - 'tpaint', 09:19:58 INFO - 'sessionrestore', 09:19:58 INFO - 'sessionrestore_no_auto_restore', 09:19:58 INFO - 'tabpaint']}, 09:19:58 INFO - 'other_l64': {'tests': ['a11yr', 09:19:58 INFO - 'ts_paint', 09:19:58 INFO - 'tpaint', 09:19:58 INFO - 'sessionrestore', 09:19:58 INFO - 'sessionrestore_no_auto_restore', 09:19:58 INFO - 'tabpaint']}, 09:19:58 INFO - 'other_nol64': {'tests': ['a11yr', 09:19:58 INFO - 'ts_paint', 09:19:58 INFO - 'tpaint', 09:19:58 INFO - 'sessionrestore', 09:19:58 INFO - 'sessionrestore_no_auto_restore', 09:19:58 INFO - 'tabpaint']}, 09:19:58 INFO - 'svgr': {'tests': ['tsvgx', 09:19:58 INFO - 'tsvgr_opacity', 09:19:58 INFO - 'tart', 09:19:58 INFO - 'tscrollx', 09:19:58 INFO - 'cart']}, 09:19:58 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:19:58 INFO - 'tests': ['tsvgx', 09:19:58 INFO - 'tsvgr_opacity', 09:19:58 INFO - 'tart', 09:19:58 INFO - 'tscrollx', 09:19:58 INFO - 'cart']}, 09:19:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:58 INFO - 'tests': ['tp5o']}, 09:19:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:58 INFO - 'talos_options': ['--e10s'], 09:19:58 INFO - 'tests': ['tp5o']}, 09:19:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:58 INFO - 'talos_options': ['--xperf_path', 09:19:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:19:58 INFO - 'tests': ['tp5n']}, 09:19:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:58 INFO - 'talos_options': ['--e10s', 09:19:58 INFO - '--xperf_path', 09:19:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:19:58 INFO - 'tests': ['tp5n']}}, 09:19:58 INFO - 'talos.zip': {'path': '', 09:19:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:19:58 INFO - Running post-action listener: _resource_record_post_action 09:19:58 INFO - ##### 09:19:58 INFO - ##### Running create-virtualenv step. 09:19:58 INFO - ##### 09:19:58 INFO - Running pre-action listener: _resource_record_pre_action 09:19:58 INFO - Running main action method: create_virtualenv 09:19:58 INFO - Creating virtualenv /builds/slave/test/build/venv 09:19:58 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 09:19:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:19:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:19:58 INFO - Using real prefix '/tools/python27' 09:19:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:19:59 INFO - Installing distribute.............................................................................................................................................................................................done. 09:20:02 INFO - Installing pip.................done. 09:20:02 INFO - Return code: 0 09:20:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:20:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:02 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 0x10b543a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b54a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2da477aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3e7d30>, '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 0x10b24bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3deb20>, '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.5FLb3VRdeo/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.H5og91mo08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 09:20:02 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 09:20:02 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 09:20:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5FLb3VRdeo/Render', 09:20:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:02 INFO - 'HOME': '/Users/cltbld', 09:20:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:02 INFO - 'LOGNAME': 'cltbld', 09:20:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:02 INFO - 'MOZ_NO_REMOTE': '1', 09:20:02 INFO - 'NO_EM_RESTART': '1', 09:20:02 INFO - 'PAGER': '/bin/cat', 09:20:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:02 INFO - 'PWD': '/builds/slave/test', 09:20:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:02 INFO - 'SHELL': '/bin/bash', 09:20:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H5og91mo08/Listeners', 09:20:02 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 09:20:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:02 INFO - 'USER': 'cltbld', 09:20:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:02 INFO - 'XPC_FLAGS': '0x0', 09:20:02 INFO - 'XPC_SERVICE_NAME': '0', 09:20:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:02 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 09:20:02 INFO - Cleaning up... 09:20:02 INFO - Return code: 0 09:20:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:20:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:02 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 0x10b543a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b54a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2da477aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3e7d30>, '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 0x10b24bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3deb20>, '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.5FLb3VRdeo/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.H5og91mo08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 09:20:02 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 09:20:02 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 09:20:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5FLb3VRdeo/Render', 09:20:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:02 INFO - 'HOME': '/Users/cltbld', 09:20:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:02 INFO - 'LOGNAME': 'cltbld', 09:20:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:02 INFO - 'MOZ_NO_REMOTE': '1', 09:20:02 INFO - 'NO_EM_RESTART': '1', 09:20:02 INFO - 'PAGER': '/bin/cat', 09:20:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:02 INFO - 'PWD': '/builds/slave/test', 09:20:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:02 INFO - 'SHELL': '/bin/bash', 09:20:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H5og91mo08/Listeners', 09:20:02 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 09:20:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:02 INFO - 'USER': 'cltbld', 09:20:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:02 INFO - 'XPC_FLAGS': '0x0', 09:20:02 INFO - 'XPC_SERVICE_NAME': '0', 09:20:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:02 INFO - Downloading/unpacking psutil>=0.7.1 09:20:02 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 09:20:02 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 09:20:02 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 09:20:02 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 09:20:02 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 09:20:02 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 09:20:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:20:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:20:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:20:05 INFO - Installing collected packages: psutil 09:20:05 INFO - Running setup.py install for psutil 09:20:05 INFO - building 'psutil._psutil_osx' extension 09:20:05 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 09:20:06 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 09:20:06 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 09:20:06 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 09:20:06 INFO - building 'psutil._psutil_posix' extension 09:20:06 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 09:20:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:20:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:20:06 INFO - ^ 09:20:06 INFO - 1 warning generated. 09:20:06 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 09:20:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:20:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:20:06 INFO - Successfully installed psutil 09:20:06 INFO - Cleaning up... 09:20:06 INFO - Return code: 0 09:20:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:20:06 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')]} 09:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:06 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')]} 09:20:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:06 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 0x10b543a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b54a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2da477aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3e7d30>, '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 0x10b24bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3deb20>, '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.5FLb3VRdeo/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.H5og91mo08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 09:20:06 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 09:20:06 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 09:20:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5FLb3VRdeo/Render', 09:20:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:06 INFO - 'HOME': '/Users/cltbld', 09:20:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:06 INFO - 'LOGNAME': 'cltbld', 09:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:06 INFO - 'MOZ_NO_REMOTE': '1', 09:20:06 INFO - 'NO_EM_RESTART': '1', 09:20:06 INFO - 'PAGER': '/bin/cat', 09:20:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:06 INFO - 'PWD': '/builds/slave/test', 09:20:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:06 INFO - 'SHELL': '/bin/bash', 09:20:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H5og91mo08/Listeners', 09:20:06 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 09:20:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:06 INFO - 'USER': 'cltbld', 09:20:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:06 INFO - 'XPC_FLAGS': '0x0', 09:20:06 INFO - 'XPC_SERVICE_NAME': '0', 09:20:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:20:06 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 09:20:06 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 09:20:06 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 09:20:06 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 09:20:06 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 09:20:06 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 09:20:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:20:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:20:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:20:09 INFO - Installing collected packages: mozsystemmonitor 09:20:09 INFO - Running setup.py install for mozsystemmonitor 09:20:09 INFO - Successfully installed mozsystemmonitor 09:20:09 INFO - Cleaning up... 09:20:09 INFO - Return code: 0 09:20:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:20:09 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')]} 09:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:09 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')]} 09:20:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:09 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 0x10b543a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b54a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2da477aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3e7d30>, '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 0x10b24bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3deb20>, '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.5FLb3VRdeo/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.H5og91mo08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 09:20:09 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 09:20:09 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 09:20:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5FLb3VRdeo/Render', 09:20:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:09 INFO - 'HOME': '/Users/cltbld', 09:20:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:09 INFO - 'LOGNAME': 'cltbld', 09:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:09 INFO - 'MOZ_NO_REMOTE': '1', 09:20:09 INFO - 'NO_EM_RESTART': '1', 09:20:09 INFO - 'PAGER': '/bin/cat', 09:20:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:09 INFO - 'PWD': '/builds/slave/test', 09:20:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:09 INFO - 'SHELL': '/bin/bash', 09:20:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H5og91mo08/Listeners', 09:20:09 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 09:20:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:09 INFO - 'USER': 'cltbld', 09:20:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:09 INFO - 'XPC_FLAGS': '0x0', 09:20:09 INFO - 'XPC_SERVICE_NAME': '0', 09:20:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:09 INFO - Downloading/unpacking blobuploader==1.2.4 09:20:09 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 09:20:09 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 09:20:09 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 09:20:09 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 09:20:09 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 09:20:09 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 09:20:12 INFO - Downloading blobuploader-1.2.4.tar.gz 09:20:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:20:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:20:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:20: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 09:20: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 09:20: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 09:20: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 09:20: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 09:20: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 09:20:13 INFO - Downloading docopt-0.6.1.tar.gz 09:20:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:20:13 INFO - Installing collected packages: blobuploader, requests, docopt 09:20:13 INFO - Running setup.py install for blobuploader 09:20:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:20:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:20:13 INFO - Running setup.py install for requests 09:20:14 INFO - Running setup.py install for docopt 09:20:14 INFO - Successfully installed blobuploader requests docopt 09:20:14 INFO - Cleaning up... 09:20:14 INFO - Return code: 0 09:20:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20:14 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')]} 09:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:14 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')]} 09:20:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:14 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 0x10b543a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b54a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2da477aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3e7d30>, '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 0x10b24bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3deb20>, '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.5FLb3VRdeo/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.H5og91mo08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 09:20:14 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 09:20:14 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 09:20:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5FLb3VRdeo/Render', 09:20:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:14 INFO - 'HOME': '/Users/cltbld', 09:20:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:14 INFO - 'LOGNAME': 'cltbld', 09:20:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:14 INFO - 'MOZ_NO_REMOTE': '1', 09:20:14 INFO - 'NO_EM_RESTART': '1', 09:20:14 INFO - 'PAGER': '/bin/cat', 09:20:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:14 INFO - 'PWD': '/builds/slave/test', 09:20:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:14 INFO - 'SHELL': '/bin/bash', 09:20:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H5og91mo08/Listeners', 09:20:14 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 09:20:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:14 INFO - 'USER': 'cltbld', 09:20:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:14 INFO - 'XPC_FLAGS': '0x0', 09:20:14 INFO - 'XPC_SERVICE_NAME': '0', 09:20:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:20:14 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-TamCFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:20:14 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-b56uv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:20:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:20:14 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-UWU4Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:20:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-0PD1i6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:20:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-y6C2oC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:20:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-QlBtsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:20:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-TTJQA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:20:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-MhKu2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:20:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-TLcyhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:20:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-ef5yRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:20:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-gYQeFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:20:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-9d6X_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:20:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-qTAiiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:20:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-QmHc6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-iur9C9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:20:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-53uNQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:20:16 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-IkIvZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:20:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:20:16 INFO - Running setup.py install for manifestparser 09:20:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:20:16 INFO - Running setup.py install for mozcrash 09:20:16 INFO - Running setup.py install for mozdebug 09:20:16 INFO - Running setup.py install for mozdevice 09:20:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:20:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:20:17 INFO - Running setup.py install for mozfile 09:20:17 INFO - Running setup.py install for mozhttpd 09:20:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:20:17 INFO - Running setup.py install for mozinfo 09:20:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:20:17 INFO - Running setup.py install for mozInstall 09:20:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:20:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:20:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:20:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:20:17 INFO - Running setup.py install for mozleak 09:20:17 INFO - Running setup.py install for mozlog 09:20:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:20:18 INFO - Running setup.py install for moznetwork 09:20:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:20:18 INFO - Running setup.py install for mozprocess 09:20:18 INFO - Running setup.py install for mozprofile 09:20:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:20:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:20:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:20:18 INFO - Running setup.py install for mozrunner 09:20:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:20:18 INFO - Running setup.py install for mozscreenshot 09:20:18 INFO - Running setup.py install for moztest 09:20:18 INFO - Running setup.py install for mozversion 09:20:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:20:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:20:19 INFO - Cleaning up... 09:20:19 INFO - Return code: 0 09:20:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:20:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:19 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 0x10b543a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b54a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2da477aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3e7d30>, '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 0x10b24bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3deb20>, '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.5FLb3VRdeo/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.H5og91mo08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 09:20:19 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 09:20:19 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 09:20:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5FLb3VRdeo/Render', 09:20:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:19 INFO - 'HOME': '/Users/cltbld', 09:20:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:19 INFO - 'LOGNAME': 'cltbld', 09:20:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:19 INFO - 'MOZ_NO_REMOTE': '1', 09:20:19 INFO - 'NO_EM_RESTART': '1', 09:20:19 INFO - 'PAGER': '/bin/cat', 09:20:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:19 INFO - 'PWD': '/builds/slave/test', 09:20:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:19 INFO - 'SHELL': '/bin/bash', 09:20:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H5og91mo08/Listeners', 09:20:19 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 09:20:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:19 INFO - 'USER': 'cltbld', 09:20:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:19 INFO - 'XPC_FLAGS': '0x0', 09:20:19 INFO - 'XPC_SERVICE_NAME': '0', 09:20:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:20:19 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-QWoNRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:20:19 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)) 09:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:20:19 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-2J0yHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:20:19 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)) 09:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:20:19 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-3nwA9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:20:19 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)) 09:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:20:19 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-ZzIq4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:20:19 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)) 09:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:20:19 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-i3zLHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:20:19 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)) 09:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:20:19 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-2hWb2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:20:19 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)) 09:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:20:19 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-0E0QEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:20:20 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)) 09:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:20:20 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Ad5pVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:20:20 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)) 09:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:20:20 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-1PmASK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:20:20 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)) 09:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:20:20 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-c3Wu36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:20:20 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)) 09:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:20:20 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-jwHZfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:20:20 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)) 09:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:20:20 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-A5QW5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:20:20 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)) 09:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:20:20 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-HFR6B7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:20:20 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)) 09:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:20:20 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-es4DXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:20:20 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)) 09:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:20 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-BQtYRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:20 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)) 09:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:20:20 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-1nZdc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:20:21 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)) 09:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:20:21 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-zbMlmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:20:21 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)) 09:20:21 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)) 09:20:21 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)) 09:20:21 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)) 09:20:21 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)) 09:20:21 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)) 09:20:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:20:21 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 09:20:21 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 09:20:21 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 09:20:21 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 09:20:21 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 09:20:21 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 09:20:23 INFO - Downloading blessings-1.6.tar.gz 09:20:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:20:23 INFO - Installing collected packages: blessings 09:20:23 INFO - Running setup.py install for blessings 09:20:23 INFO - Successfully installed blessings 09:20:23 INFO - Cleaning up... 09:20:23 INFO - Return code: 0 09:20:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:20:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:20:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:20:24 INFO - Reading from file tmpfile_stdout 09:20:24 INFO - Current package versions: 09:20:24 INFO - blessings == 1.6 09:20:24 INFO - blobuploader == 1.2.4 09:20:24 INFO - docopt == 0.6.1 09:20:24 INFO - manifestparser == 1.1 09:20:24 INFO - mozInstall == 1.12 09:20:24 INFO - mozcrash == 0.17 09:20:24 INFO - mozdebug == 0.1 09:20:24 INFO - mozdevice == 0.48 09:20:24 INFO - mozfile == 1.2 09:20:24 INFO - mozhttpd == 0.7 09:20:24 INFO - mozinfo == 0.9 09:20:24 INFO - mozleak == 0.1 09:20:24 INFO - mozlog == 3.1 09:20:24 INFO - moznetwork == 0.27 09:20:24 INFO - mozprocess == 0.22 09:20:24 INFO - mozprofile == 0.28 09:20:24 INFO - mozrunner == 6.11 09:20:24 INFO - mozscreenshot == 0.1 09:20:24 INFO - mozsystemmonitor == 0.0 09:20:24 INFO - moztest == 0.7 09:20:24 INFO - mozversion == 1.4 09:20:24 INFO - psutil == 3.1.1 09:20:24 INFO - requests == 1.2.3 09:20:24 INFO - wsgiref == 0.1.2 09:20:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20:24 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')]} 09:20:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:24 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')]} 09:20:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:24 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 0x10b543a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b54a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2da477aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3e7d30>, '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 0x10b24bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3deb20>, '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.5FLb3VRdeo/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.H5og91mo08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 09:20:24 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 09:20:24 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 09:20:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5FLb3VRdeo/Render', 09:20:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:24 INFO - 'HOME': '/Users/cltbld', 09:20:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:24 INFO - 'LOGNAME': 'cltbld', 09:20:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:24 INFO - 'MOZ_NO_REMOTE': '1', 09:20:24 INFO - 'NO_EM_RESTART': '1', 09:20:24 INFO - 'PAGER': '/bin/cat', 09:20:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:24 INFO - 'PWD': '/builds/slave/test', 09:20:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:24 INFO - 'SHELL': '/bin/bash', 09:20:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H5og91mo08/Listeners', 09:20:24 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 09:20:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:24 INFO - 'USER': 'cltbld', 09:20:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:24 INFO - 'XPC_FLAGS': '0x0', 09:20:24 INFO - 'XPC_SERVICE_NAME': '0', 09:20:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:24 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)) 09:20:24 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)) 09:20:24 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)) 09:20:24 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)) 09:20:24 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)) 09:20:24 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)) 09:20:24 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)) 09:20:24 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)) 09:20:24 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)) 09:20:24 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)) 09:20:24 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)) 09:20:24 INFO - Cleaning up... 09:20:24 INFO - Return code: 0 09:20:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:20:24 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')]} 09:20:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:24 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')]} 09:20:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:24 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 0x10b543a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b54a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2da477aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3e7d30>, '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 0x10b24bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3deb20>, '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.5FLb3VRdeo/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.H5og91mo08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 09:20:24 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 09:20:24 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 09:20:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5FLb3VRdeo/Render', 09:20:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:24 INFO - 'HOME': '/Users/cltbld', 09:20:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:24 INFO - 'LOGNAME': 'cltbld', 09:20:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:24 INFO - 'MOZ_NO_REMOTE': '1', 09:20:24 INFO - 'NO_EM_RESTART': '1', 09:20:24 INFO - 'PAGER': '/bin/cat', 09:20:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:24 INFO - 'PWD': '/builds/slave/test', 09:20:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:24 INFO - 'SHELL': '/bin/bash', 09:20:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H5og91mo08/Listeners', 09:20:24 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 09:20:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:24 INFO - 'USER': 'cltbld', 09:20:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:24 INFO - 'XPC_FLAGS': '0x0', 09:20:24 INFO - 'XPC_SERVICE_NAME': '0', 09:20:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:25 INFO - Downloading/unpacking jsonschema 09:20:25 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 09:20:25 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 09:20:25 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 09:20:25 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 09:20:25 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 09:20:25 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 09:20:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:20:27 INFO - Downloading/unpacking functools32 (from jsonschema) 09:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:28 INFO - Downloading functools32-3.2.3-2.tar.gz 09:20:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:20:28 INFO - warning: no files found matching '*.txt' 09:20:28 INFO - no previously-included directories found matching 'build' 09:20:28 INFO - no previously-included directories found matching 'dist' 09:20:28 INFO - no previously-included directories found matching '.git*' 09:20:28 INFO - Installing collected packages: jsonschema, functools32 09:20:28 INFO - Running setup.py install for functools32 09:20:28 INFO - warning: no files found matching '*.txt' 09:20:28 INFO - no previously-included directories found matching 'build' 09:20:28 INFO - no previously-included directories found matching 'dist' 09:20:28 INFO - no previously-included directories found matching '.git*' 09:20:28 INFO - Successfully installed jsonschema functools32 09:20:28 INFO - Cleaning up... 09:20:28 INFO - Return code: 0 09:20:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:20:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b543a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b54a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2da477aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3e7d30>, '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 0x10b24bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3deb20>, '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.5FLb3VRdeo/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.H5og91mo08/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 09:20:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:20:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:20:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5FLb3VRdeo/Render', 09:20:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:20:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:20:28 INFO - 'HOME': '/Users/cltbld', 09:20:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:20:28 INFO - 'LOGNAME': 'cltbld', 09:20:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:28 INFO - 'MOZ_NO_REMOTE': '1', 09:20:28 INFO - 'NO_EM_RESTART': '1', 09:20:28 INFO - 'PAGER': '/bin/cat', 09:20:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:20:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:28 INFO - 'PWD': '/builds/slave/test', 09:20:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:20:28 INFO - 'SHELL': '/bin/bash', 09:20:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H5og91mo08/Listeners', 09:20:28 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 09:20:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:20:28 INFO - 'USER': 'cltbld', 09:20:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:20:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:20:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:28 INFO - 'XPC_FLAGS': '0x0', 09:20:28 INFO - 'XPC_SERVICE_NAME': '0', 09:20:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:20:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:28 INFO - Downloading/unpacking flake8 09:20:28 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 09:20:28 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 09:20:28 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 09:20:28 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 09:20:28 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 09:20:28 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 09:20:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:20:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:20: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 09:20: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 09:20: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 09:20: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 09:20: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 09:20: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 09:20:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:20:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:20: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 09:20: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 09:20: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 09:20: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 09:20: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 09:20: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 09:20:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:20:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:20:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:20:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:20:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:20:32 INFO - no previously-included directories found matching 'docs/_build' 09:20:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:20:32 INFO - Running setup.py install for pep8 09:20:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:20:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:20:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:20:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:20:32 INFO - no previously-included directories found matching 'docs/_build' 09:20:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:20:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:20:32 INFO - Cleaning up... 09:20:32 INFO - Return code: 0 09:20:32 INFO - Running post-action listener: _resource_record_post_action 09:20:32 INFO - Running post-action listener: _start_resource_monitoring 09:20:32 INFO - Starting resource monitoring. 09:20:32 INFO - ##### 09:20:32 INFO - ##### Running install step. 09:20:32 INFO - ##### 09:20:32 INFO - Running pre-action listener: _resource_record_pre_action 09:20:32 INFO - Running main action method: install 09:20:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:20:33 INFO - Reading from file tmpfile_stdout 09:20:33 INFO - Detecting whether we're running mozinstall >=1.0... 09:20:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:20:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:20:33 INFO - Reading from file tmpfile_stdout 09:20:33 INFO - Output received: 09:20:33 INFO - Usage: mozinstall [options] installer 09:20:33 INFO - Options: 09:20:33 INFO - -h, --help show this help message and exit 09:20:33 INFO - -d DEST, --destination=DEST 09:20:33 INFO - Directory to install application into. [default: 09:20:33 INFO - "/builds/slave/test"] 09:20:33 INFO - --app=APP Application being installed. [default: firefox] 09:20:33 INFO - mkdir: /builds/slave/test/build/application 09:20:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:20:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:21:05 INFO - Reading from file tmpfile_stdout 09:21:05 INFO - Output received: 09:21:05 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:21:05 INFO - Running post-action listener: _resource_record_post_action 09:21:05 INFO - ##### 09:21:05 INFO - ##### Running run-tests step. 09:21:05 INFO - ##### 09:21:05 INFO - Running pre-action listener: _resource_record_pre_action 09:21:05 INFO - Running main action method: run_tests 09:21:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:21:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:21:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:21:05 INFO - 2016-04-18 09:21:05.525 screenresolution[1914:14150] starting screenresolution argv=screenresolution get 09:21:05 INFO - 2016-04-18 09:21:05.528 screenresolution[1914:14150] Display 0: 1600x1200x32@60 09:21:05 INFO - 2016-04-18 09:21:05.534 screenresolution[1915:14152] starting screenresolution argv=screenresolution list 09:21:05 INFO - Available Modes on Display 0 09:21:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:21:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:21:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:21:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:21:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:21:05 INFO - Intel Iris: 09:21:05 INFO - Chipset Model: Intel Iris 09:21:05 INFO - Type: GPU 09:21:05 INFO - Bus: Built-In 09:21:05 INFO - VRAM (Dynamic, Max): 1536 MB 09:21:05 INFO - Vendor: Intel (0x8086) 09:21:05 INFO - Device ID: 0x0a2e 09:21:05 INFO - Revision ID: 0x0009 09:21:05 INFO - Displays: 09:21:05 INFO - Display: 09:21:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:21:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:21:05 INFO - Display Serial Number: 1600x1200 60 09:21:05 INFO - Main Display: Yes 09:21:05 INFO - Mirror: Off 09:21:05 INFO - Online: Yes 09:21:05 INFO - Rotation: Supported 09:21:05 INFO - Return code: 0 09:21:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:21:05 INFO - Python 2.7.3 09:21:05 INFO - Return code: 0 09:21:05 INFO - grabbing minidump binary from tooltool 09:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:21:05 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 0x10b3e7d30>, '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 0x10b24bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b3deb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:21:05 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 09:21:05 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 09:21:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:21:05 INFO - Return code: 0 09:21:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:21:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:21:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:21:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:21:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:21:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:21:07 INFO - Return code: 0 09:21:07 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:21:07 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0093 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:21:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5FLb3VRdeo/Render', 09:21:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:07 INFO - 'HOME': '/Users/cltbld', 09:21:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:07 INFO - 'LOGNAME': 'cltbld', 09:21:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:21:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:07 INFO - 'MOZ_NO_REMOTE': '1', 09:21:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:21:07 INFO - 'NO_EM_RESTART': '1', 09:21:07 INFO - 'PAGER': '/bin/cat', 09:21:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:07 INFO - 'PWD': '/builds/slave/test', 09:21:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:21:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:07 INFO - 'SHELL': '/bin/bash', 09:21:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H5og91mo08/Listeners', 09:21:07 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 09:21:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:07 INFO - 'USER': 'cltbld', 09:21:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:07 INFO - 'XPC_FLAGS': '0x0', 09:21:07 INFO - 'XPC_SERVICE_NAME': '0', 09:21:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:21:07 INFO - mozversion application_buildid: 20160418074021 09:21:07 INFO - mozversion application_changeset: 72bdf0a085a53a9ff83511bee4dcc353731866a7 09:21:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:21:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:21:07 INFO - mozversion application_name: Firefox 09:21:07 INFO - mozversion application_remotingname: firefox-dev 09:21:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:21:07 INFO - mozversion application_vendor: Mozilla 09:21:07 INFO - mozversion application_version: 47.0a2 09:21:07 INFO - mozversion platform_buildid: 20160418074021 09:21:07 INFO - mozversion platform_changeset: 72bdf0a085a53a9ff83511bee4dcc353731866a7 09:21:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:21:07 INFO - mozversion platform_version: 47.0a2 09:21:07 INFO - using testdate: 1460996467 09:21:07 INFO - actual date: 1460996467 09:21:07 INFO - starting webserver on 'localhost:52463' 09:21:07 INFO - SUITE-START | Running 2 tests 09:21:07 INFO - TEST-START | dromaeo_css 09:21:07 INFO - operating with platform_type : mac_ 09:21:07 INFO - Initialising browser for dromaeo_css test... 09:21:07 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmp6E1lvs/profile http://localhost:52463/getInfo.html) 09:21:09 INFO - PROCESS | 1933 | __metrics Screen width/height:1600/1200 09:21:09 INFO - PROCESS | 1933 | colorDepth:24 09:21:09 INFO - PROCESS | 1933 | Browser inner width/height: 1024/689 09:21:09 INFO - PROCESS | 1933 | __metrics 09:21:09 ERROR - PROCESS | 1933 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 09:21:10 INFO - TEST-INFO | 1933: exit 0 09:21:10 INFO - Browser initialized. 09:21:10 INFO - Running cycle 1/1 for dromaeo_css test... 09:21:10 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmp6E1lvs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:21:22 INFO - PROCESS | 1936 | RSS: Main: 217153536 09:21:22 INFO - PROCESS | 1936 | 09:22:12 INFO - PROCESS | 1936 | 1460996532244 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] 09:24:23 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:52463/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52463/tests/dromaeo/cssquery-ext.html) 09:24:24 INFO - PROCESS | 1936 | RSS: Main: 228089856 09:24:24 INFO - PROCESS | 1936 | 09:27:26 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:52463/tests/dromaeo/cssquery-ext.html (next: http://localhost:52463/tests/dromaeo/cssquery-jquery.html) 09:27:27 INFO - PROCESS | 1936 | RSS: Main: 239382528 09:27:27 INFO - PROCESS | 1936 | 09:30:29 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:52463/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52463/tests/dromaeo/cssquery-mootools.html) 09:30:29 INFO - PROCESS | 1936 | RSS: Main: 241176576 09:30:29 INFO - PROCESS | 1936 | 09:33:31 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:52463/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52463/tests/dromaeo/cssquery-prototype.html) 09:33:31 INFO - PROCESS | 1936 | RSS: Main: 242896896 09:33:31 INFO - PROCESS | 1936 | 09:36:34 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:52463/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52463/tests/dromaeo/cssquery-yui.html) 09:36:35 INFO - PROCESS | 1936 | RSS: Main: 245747712 09:36:35 INFO - PROCESS | 1936 | 09:39:37 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:52463/tests/dromaeo/cssquery-yui.html (next: http://localhost:52463/tests/dromaeo/cssquery-dojo.html) 09:39:37 INFO - PROCESS | 1936 | RSS: Main: 246980608 09:39:37 INFO - PROCESS | 1936 | 09:39:37 INFO - PROCESS | 1936 | __start_tp_report 09:39:37 INFO - PROCESS | 1936 | _x_x_mozilla_page_load 09:39:37 INFO - PROCESS | 1936 | _x_x_mozilla_page_load_details 09:39:37 INFO - PROCESS | 1936 | |i|pagename|runs| 09:39:37 INFO - PROCESS | 1936 | |0;dojo.html;3475.843587038583;3494.860205591776;3529.8235088245583;3536.6993805526545;3549.485324627929;4593.471750748665;4653.464851542074;5731.971340143294;5779.367657462859;5783.190353350529;5730.255066841315;5752.511036561887;5783.855403614902;5799.391063938278;5805.15825205346;5171.534561889429;5208.374995000605;5253.4746525347355;5281.788728450857;5320.521153096221;5193;5285.709285989269;5293.153095504701;5309.893802123975;5335.67985920846;5219.373675158968;5268.8155914542995;5294.205869119624;5302.2311764794085;5314.548263397596;5285.920711189336;5309.177077552947;5319.095753721857;5326.440723723992;5336.012837625051;5216.58267338612;5258.7370631468275;5311.123664595337;5313.495217954288;5313.867153321159;4582.839600613996;5575.470330318653;5702.686352250625;5744.138379243105;5867.442592953663;6201.131841542143;6205.131281620576;6210.813675589694;6220.377962203788;6268.1851359323;6287.4655654269445;6293.087502312185;6306.1171435998995;6306.2747784004505;6378.649174295412;4702.929456058162;4838.491958344393;5011.198208286693;5948.7323070461935;6005.39946005396;6265.449326210039;6304.211973503312;6306.306306306303;6322.178116844781;6347.079673447324;6103.755849766048;6242.5942313749465;6244.65654389013;6251.999680051261;6284;4983.92845538211;6155.10750941111;6183.443490085914;6272.749090036439;6284.214473190851;6201.472874805692;6214.906776398358;6217.626942383471;6236.532260080512;6271.05934109878;5185.377754669388;5440.515238826362;6061.151438798572;6255.46828519581;6261.499080073583;4885.145099607555;4894.363732714724;4959.652824302264;4972.129877271463;4995.975020124949;4458.910821783611;4470.865874023784;4473.955260447419;4485.663575231871;4486.214912390318;5720.885582288331;5921.052631579013;5923.733431995484;5927.792527261525;5934.970325148347;5204.323437953042;6251.3123556408755;6272.247330320391;6277.18396608438;6304.5586808922935;5578.191162281528;5840.123981402823;5854.268216472941;5864.325602555676;5877.617954832922;5386.218998245311;5441.483059109379;5444.700541470257;5448.618596698272;5457.590680698963;6388.744450221939;6444.838879028156;6452.419282264619;6460.935390646034;6466.030095485715;6240.906386404298;6256.687165641791;6299.181106456132;6303.9684801575695;6309.211348581427;6273.404026617464;6332.873342533031;6408.679566021773;6426.550141490051;6458.903116453163;6130.601510901777;6156.630602163706;6159.938400616115;6180.412860778219;6193.473554747897;6243;6272.937270627235;6274.403931626488;6277.623342599459;6304.7162877671535;4964.553190212898;5923.644581325134;6102.664353460602;6166.321704612579;6218.409251121137;6193.597416167935;6224.253089629274;6231.40801623845;6241.563090583615;6243.406876346739;5214.8957020860125;5509.539971907528;5879.676617786063;6104.938950610437;6120.234970628671;6011.308699499614;6036.547258955684;6088.6042407243385;6126.975740326223;6182.598131121463;4571.611413029929;6094.481969032505;6096.664683442454;6104.298005729398;6114.724837382418 09:39:37 INFO - PROCESS | 1936 | |1;ext.html;467.5978658353815;546.4480874316939;608.7625825927892;610.4871907597618;662.5031226580065;31563.526547101756;35724.821375893116;38604.80697596512;38609.80695096528;38672.806635966816;42064;42103.57896421035;42349;42661.933451663725;42734.78632606832;9632.759181020461;9848.55681494331;9850.753731156707;9862.063104005108;9866.16137628299;9641.566129524153;9702.563384647672;9763.36538125023;9765.755856103584;9799.265055120859;9710.028997100304;9722.659706910259;9749.122578967892;9781.728375311199;9790.314677972545;9695.757606059835;9707.757306067335;9708.126268635822;9794.069563391502;9803.803923921552;8947.32895032875;8978.640854365884;9016.188543031092;9259.72220833376;9357;39572.40641390382;39575.604243957765;39771.80114099411;39799.40300895489;40019.399709004385;3324.1523411530147;3501.5447991760907;3534.9469757953652;3920.0983773731914;3983.24318379507;28743;29250;29284.707152928626;29339.706602934126;29606.407871842657;2657.760801527872;2659.5611724065507;2707.28257011892;2714.1993112031996;2741.300968253103;342431;408394;440608;465398;472492;63259;66754.99867501992;67156.6642166786;67657.3234267651;67682;76838;76934.61532692301;77753.22246777573;77973;78156.60921695356;18139.54651133706;18283.99438030921;18343.63312733738;18621.44135675932;18627.348042818438;11603.477843497063;13944.093633913762;13950.09324393911;13987.650308742159;14011.929940350234;21245.256416025364;22106.784126873175;22405.21581744631;22533.88733056357;22847;8487.363447741443;9490.240780737662;9522.95238523803;9534.427934323963;9571.521423928776;987.09680642213;1037.8131936251395;1103.4963989333833;1154.1286328821686;1200.4057991294364;29726.554101688493;30007.949721759633;30481.23796905095;30716;31037.68962310348;1155.122107198535;1199.3643369014437;1220.8840160184768;1238.1456794811552;1259.8866102050858;14363.497277595443;15615.687686246212;15690.843091569166;15739.842601573837;15740.212989350486;484173;489006;522358.3882080565;526769;529351;59955;60923;61491.6925415379;61761;61816.381836181965;1527.480656576747;1530.2807680390422;1550.116433632841;1556.2529985606745;1556.9844301556839;29703;29965.850170749007;30218.54672179963;30366.69633303727;30780.30235351361;51307.97384052223;51767;51958.220626691385;52043.73978130085;52214.47785522096;484631;488882;490522;491734;491811;33450.83274583709;33830.3233935315;34441;34621.65378346285;35284.82357588196;45661.315080274486;45897.48809588809;45949.310760337954;46977.53022469868;46995.53004469912;38122.856314310615;38164.855054348416;38221.2355752889;38254.808725956194;38944.41583376195;37451.625483745906;37610.247795044495;37745.81127094347;37900.24199515939;38184.809075955556;33480.49779253362;37282.88151355464;38122.618773811904;38152.809235953646;38375;30873.536896947015;31396.372072557962;31439.685603143676;31567;32706.345873082882;26140.8692956534;26364.868175658998;26591;26802.865985670727;26828.59757103684 09:39:37 INFO - PROCESS | 1936 | |2;jquery.html;357.3638922717563;374.1152175105874;377.44515562123667;384.89030626271517;387.35892098576863;20260.69608955865;20755.377338679828;20987.580248395025;21555.353339399822;21649.458763530918;12525.2484850909;13205.877500412476;20958.580828383423;21510.354689359305;21510.677339839876;4188.455500784894;5302.204669299616;5555.472230138131;5591.440855914417;5593.272874526306;4597.54024597541;5803.274590676166;6085.117657939618;6096.847578810521;6223.937760622381;5066.935943451861;5615.596100974756;6099.451049405553;6132.908006379907;6145.877082458348;6634.038064480647;6640.7343706251695;6685.832854178636;6702.128723265969;6734.3939045485895;5742.2247996520355;5751.223584816038;5873.295204575478;5941.435563621449;5980.73086711099;25402;25408;25484.74515254842;25546.233612991455;25925.61111583309;2079.5321052763156;2159.88120653364;2176.5211653436218;2578.7679108880293;2616.882240299191;9561.61753529858;10058.497075146213;10115;12861.549845755448;15097.622559435993;1532.3257766582667;1756.6837969165545;1789.973150402732;1793.641271745643;1814.3468351393487;79987;109805;113877;116363.10971498801;118313.40843295814;11838.348890810916;11937.462814173383;11942.701432464259;11971.820422693667;12117.151799374136;14304.642383940276;14501.477344878636;14502.637434064232;14552;14568;9110.86333704995;9116.1339672731;9119.407238529475;9122;9175.357724959322;7892.724871665106;9140.588673509708;9320.8814942206;9367.62529498826;9408.294377921684;3842.2891765023;4343.19650864591;4562.361269422284;4663.300504924221;4669.766511674403;1896.8713615398815;1898.1268616436319;1942.669746143159;1949.1716020691433;1952.6290004899024;878.297362110322;1034.270839058462;1049.7480604654827;1076.0960792933972;1107.9335239885634;19412.611747764964;19959.401217963485;20260.968456335704;20546.589068218553;20569.94300569931;917.9127982841619;997.7156510394502;1028.1928685981468;1047.6909311753052;1079.3739631013993;8108.351331893435;8466.830663386698;9419.670311539063;11283.379414132301;11391.886081139248;516085.41957289976;611628.6158289518;638337;657873;659925;119353.40323298328;119860;120272;122332.38833805775;123048;1056.2975621211963;1107.883672214422;1112.0491979357694;1116.5143162724241;1118.2563595209258;16100.275487602852;18832.435026949213;19404.805951940863;19415.708764368264;19803;51359.74320128375;51468.713282168246;52515;53087.73456132694;57033;65597;66781;70380;70693.29306707073;70961;8098.878516822135;8130.959345203236;8285.171482851667;8302.958485207535;8315.037601454502;9607.56765945548;11823.231489953125;13546;13656.248906309851;13976.440942362191;11465.94267028693;11894.583689571169;12088.516459341528;12243.816342754688;12316.445759940645;11756.177067605186;11801.35092569917;11937.820932685843;11957.940210298893;12035.939820300842;11929.403529823649;11990.940045299718;12150;12155;12201.572944946884;7437.256274372519;8353.331733461187;8462.619182136941;8477.364197685074;8769.298456123368;10979.710318709323;11803.645890623295;11922.4038798058;11969.820452693042;12063.698407539881 09:39:37 INFO - PROCESS | 1936 | |3;mootools.html;1478.8742956848666;1532.9958876935607;1900.743399641048;2042.948926276843;2076.4912596413883;8456;9009.414388064772;9094.49980251086;9298.25613950884;9321;9248.167664910157;9272.67545635903;9389.295802814782;9390.389624674392;9417.199538039258;2603.0368763557462;2611.6474275972787;2619.148776647597;2629.421527263999;2638.089858998652;2570.550153723091;2615.293870654922;2620.5414052540823;2626.6454028706166;2647.086755069498;2605.531004419204;2622.72461391554;2628.172125780373;2642.4715056988584;2642.8546429946346;2605.544029794788;2621.082621082625;2630.368711509224;2636.4068084680985;2637.353848307158;2578.432744796142;2607.9478410431875;2622.501724672325;2643.299525625711;2644.352133727249;8121;9031.961324447708;9296.814063718755;9312.90687093134;9315.39449935752;5743.195952566644;5880.529557635379;5885.440883116097;5905.4094590541035;5924.526037916957;3557.7153827693724;3695.7043436525014;3750.5499340079364;3762.698984081267;3764.5294338207723;1029.8764148302178;1051.5898799468137;1054.3410368519676;1085.5115198160859;1086.7772106718103;12333.198342107735;12333.938330308381;12492.375381230993;12505.187162834387;12584.81122783159;5091.236314552847;5127.461616530285;5139.280500729901;5212.113940630102;5234.92147617786;10635.734106647242;10657.413842238751;10662.573497060186;10712.196585256137;10754.73113172177;5711.685857277889;5715.714214289269;5849.239598852122;5877.265341832271;5902.76388944446;4627.375304333939;5060;5152.175651895679;5164.767585458663;5217.713025783542;3344.565206523185;3402.0644322811427;3743.232637309345;3908.804559772;4140.523839758406;1374.9725005499834;1381.599336192493;1384.0657556149558;1385.9376328065262;1392.401267455004;1413.7101894111681;1426.8359138698975;1447.1968057727852;1457.1985408025544;1461.9488317908822;8428.704995325133;8449.788755281168;8460.830783384297;8471.856299399626;8509.61706723199;529.4073908840791;531.7394476706463;535.2987586261931;539.4632340820908;540.1276937745513;6376.458001069868;6380.968095159495;6420.229572451242;6495.415412612888;6702.698378572976;10961.287516311573;11170.385628790333;11210.27133236337;11269.549218031187;11286.322820630625;8454.112318206622;10976.286541374786;11026.228164028442;11096.167787415976;11111.888881111085;8502.44734092282;8549.059603443617;8555.786105347415;8640.395172337876;8717.910261217348;7017.824554386181;7025.297470252934;8096.352291816523;8304.211099945496;8332.6666933322;9659.855102173333;9708.368956017835;9759.072888075621;9867.06262905023;9953.601855925683;9919.107280344804;10036.29745917808;10084.848727269244;10189.439580823126;10285.2800303978;6297.307296197506;7350.228226036189;7501.512401693873;7505.6997720090585;7605.163432022584;8508.574571271536;8509.787255318666;8549.316054715733;8561.914380856111;8569.65721371163;6097.939020609737;7403.370713489414;7667.88498172539;7709.768706938801;7725.49784264021;7466.290702383265;7520;7525.285097915689;7530.962345188239;7599.810004749926;7999.600019999093;8039.718609848627;8081.838363232585;8117;8139.715109971123;7244.673989670372;7258.237885022208;7335.77992660221;7338.96330518344;7360.889586656097;7399.112106547249;7448.217937116741;7454.552726836408;7459.813504662427;7498.925010749823 09:39:37 INFO - PROCESS | 1936 | |4;prototype.html;293.99706002939973;341.1011983423678;342.5307328959326;346.2814659581368;346.9930601387973;5535.446455354468;6879.690413931373;6879.83527577315;6917.654117294141;6931.896021559681;6738.191417029963;6768.289329620393;6774.356436138572;6784.491163162757;6817.6932038058285;4820.3733514643045;4899.069176856386;5013.699178049329;5050;5101.54086132248;4998.475160108191;5019.372578427697;5022.59819214462;5137.332146820908;5202.8179013734525;5028.572571331441;5029.924551131736;5032.748362581875;5053.696778193302;5068.923966140511;5049.823256186034;5059.2916991621205;5067.670601410915;5083.085044691954;5095.643304968654;3493.6855682988653;4168.791050595324;4314.331278651814;4338.327559228324;4360.520342762294;6334.208223972004;6561.475081993429;6644.700988455531;6648.3046823060295;6683.398494135551;5944.970275148597;5966.433188847052;5992.460678538909;5994.610350327215;6013.128096426038;7110.968909508092;7429.33136017761;7451.515651482637;7460.36586890115;7463.067116610424;4482.5069242383315;4895.902081958377;4939.851804445872;4950.282209079699;4996.475370086161;6479.870402591969;7691.61550920834;7736.574488403136;7838.7256446024685;7873.96063019687;4825.672939941474;4968.105740966588;4968.354113965162;4985.252212168204;4993.1262029144755;4014.2573623879093;4110.486189226347;4856.902861942742;5076.416212135578;5107.33604631403;5322.4411436799355;5564.8052318169475;5995.910061349084;6001.459868611846;6028.9397106029255;7442.8883566746545;7452.627368631655;7459.403247740168;7466.887996680054;7502.699892004259;1762.532928773876;1790.525510739629;1791.587934775009;1793.1661777273368;1797.8381945624956;1114.587602587048;1158.1719070864372;1160.4835848047537;1167.1538134852333;1168;5832.708364581754;6781.966090169518;6798.082258895085;6832.248452670202;6880.174379074443;7342.5227360221415;7613.276738709814;7622.31399174077;7626.313631773056;7657.195994420617;6729.091572637729;6910.516263861582;6960.408365289007;6965.82585435358;6969.58182509051;4023.0746928206677;4074.6129117733767;4088.754674719467;4089.2434899543678;4101.85643502476;8020.398470114765;8028.197180281925;8031.317338026333;8061.072976607648;8098.51408915467;7742.361001732583;7812.218778122142;7947.205279472122;7957.880631790527;7974.362051035903;6598.241202261705;7839.568823714751;7980.600969951479;8028.237317454832;8050.5169689818795;6351.920173570412;7645.694172233048;7721.382289416945;7735.265149810759;7754.534727916343;7546.886796698163;7570.697172113054;7587.848243035219;7594.696212151452;7596.962015189889;7751.37988960893;7987.92163058003;7998.160193179632;8027.719029834161;8041.517508949481;4992.425871024879;5012.523810238022;5017.247412888096;5022.949770502249;5031.6729412588065;7548.584827834302;7639.503432276884;7661.463697541119;7682.884756728542;7695.115061767969;7330.047093877762;7371.2677520783645;7383.593902335423;7393.260673932778;7416.0729908761405;5908.940910590839;7058.964705176647;7364.189939106587;7403.703851845866;7429.851402972018;7191;7216.494845360774;7220.711171553079;7230.349268565854;7231.385332246818;7367.889481657887;7376.520526165782;7379.963100184465;7410.4442166836625;7421.703131874665;5857.414258574108;7164;7171.4621403395995;7196.352328290479;7236.38490728294 09:39:37 INFO - PROCESS | 1936 | |5;yui.html;667.8864593019186;671.1677519875353;720.9891851622224;760.3802900635982;790.2137373313553;13640.795388069162;14278.92860535696;15317.38730450782;15403.537893863175;15577.844221557782;12533.937330313358;12544.184627999173;14262.928685356585;14783.852161478382;14804.55586332409;801.3949468151537;973.4159504297436;992.5136683025302;999.050901643438;1000.584757325709;981.249344251648;984.6602921991902;986.3194395866828;986.4968865878382;989.8861630912429;941.6327632223438;1025.933029649164;1034.8292531732254;1043.3739756146326;1044.2846650044744;1025.2976710952985;1028.6965345223177;1035.8290882004499;1037.4345981440133;1041.713528779584;768.6694721269852;773.5242825662233;773.8877862709876;778.796759007335;783.8510682970275;12777.936110319482;15226.543203703906;15331.693366132615;15418.306176222097;15527.45653902108;13496.325183740872;13500.459981600725;13540.661483462894;13612.659683507893;13616.114952528054;10322.277440579162;10351.792964140675;10360.792784144274;10384.065434110891;10684.839727404094;3540.7167426606065;3548.538689970287;3601.927961440783;3646.1249300168033;3806.1626442182674;86060.56969715175;86381;87242.56378718065;87435.56282218549;87548;9661.951690241503;9894.703158905244;9995.850062249072;10118.139958103644;10217.540210690537;21902.45243868884;22182.334529964126;22371.55256894853;22582.887085564795;22604.660930086062;7554.622268886534;7665.8850117248285;7779.988601481884;7863.764087077397;8019.679212831421;4201.558836322203;4201.663866890642;4214.578542145761;4278.379634952915;4283.100548884707;3757.6618104370737;3779.338615742234;3789.6778773803985;3813.9427908581392;3818.3127037133027;572.8825590753886;707.3209718670031;708.2563308526027;716.7634680555509;723.8660847743184;545.8198794397839;615.1203778596649;616.7810427298244;618.3414663383483;631.2204427531994;8120.26917577409;8396.454230475;8423.663053477792;8532.402731808837;8566.486010839244;568.573569822633;598.856274494127;600.8738164985315;630.9053641953743;638.1863124516279;3801.8669346572738;3890.747101438454;3985.7608543486904;4022.255882661717;4045.9190816183514;94855.52572237095;97734;97777;98648;98830.5058474703;80453.19546804574;81200;83414;84037.57981210055;84254;54483.72758136263;73214;73375.26624733792;74185;75044.87432688405;7547.471676982558;7662.501937374052;7722;7734.199350487134;7788.493747906368;50474.49525504698;51303.486965131364;52034.21948670848;52693.736531317096;52825.47174528359;50970.74514627403;63717.681411592646;65243.69512609626;68223.97664035142;71296;9968.750781230527;9990.650327238513;10099.293049486465;10174.338667986558;10192.796144077225;10828.931530832626;10938.179636527133;11015.889841101807;11045.889541104487;11133.443327833738;9365.203957663674;10150.644727434505;10239.897601023895;10338.483075846329;10620.734481638021;10353.84469232947;10398.844017339898;10417.739556511147;10789.56841726322;10937;10773.138148948223;11290.548378064785;11388.37363944991;12056.51773929033;12214.511419543118;8122;9825.557849896915;9834.704958851245;9843.655472058443;9851.556679949275;9140.63437462494;9219.539023048956;9673.854892176481;10004.949975250078;10130.797384052425 09:39:37 INFO - PROCESS | 1936 | __end_tp_report 09:39:37 INFO - PROCESS | 1936 | __start_cc_report 09:39:37 INFO - PROCESS | 1936 | _x_x_mozilla_cycle_collect,378 09:39:37 INFO - PROCESS | 1936 | __end_cc_report 09:39:37 INFO - PROCESS | 1936 | __startTimestamp1460997577492__endTimestamp 09:39:37 INFO - PROCESS | 1936 | 09:39:37 INFO - PROCESS | 1936 | ------- Summary: start ------- 09:39:37 INFO - PROCESS | 1936 | Number of tests: 6 09:39:37 INFO - PROCESS | 1936 | 09:39:37 INFO - PROCESS | 1936 | [#0] dojo.html Cycles:165 Average:5721.78 Median:5934.97 stddev:666.96 (11.2%) stddev-sans-first:645.32 09:39:37 INFO - PROCESS | 1936 | Values: 3475.8 3494.9 3529.8 3536.7 3549.5 4593.5 4653.5 5732.0 5779.4 5783.2 5730.3 5752.5 5783.9 5799.4 5805.2 5171.5 5208.4 5253.5 5281.8 5320.5 5193.0 5285.7 5293.2 5309.9 5335.7 5219.4 5268.8 5294.2 5302.2 5314.5 5285.9 5309.2 5319.1 5326.4 5336.0 5216.6 5258.7 5311.1 5313.5 5313.9 4582.8 5575.5 5702.7 5744.1 5867.4 6201.1 6205.1 6210.8 6220.4 6268.2 6287.5 6293.1 6306.1 6306.3 6378.6 4702.9 4838.5 5011.2 5948.7 6005.4 6265.4 6304.2 6306.3 6322.2 6347.1 6103.8 6242.6 6244.7 6252.0 6284.0 4983.9 6155.1 6183.4 6272.7 6284.2 6201.5 6214.9 6217.6 6236.5 6271.1 5185.4 5440.5 6061.2 6255.5 6261.5 4885.1 4894.4 4959.7 4972.1 4996.0 4458.9 4470.9 4474.0 4485.7 4486.2 5720.9 5921.1 5923.7 5927.8 5935.0 5204.3 6251.3 6272.2 6277.2 6304.6 5578.2 5840.1 5854.3 5864.3 5877.6 5386.2 5441.5 5444.7 5448.6 5457.6 6388.7 6444.8 6452.4 6460.9 6466.0 6240.9 6256.7 6299.2 6304.0 6309.2 6273.4 6332.9 6408.7 6426.6 6458.9 6130.6 6156.6 6159.9 6180.4 6193.5 6243.0 6272.9 6274.4 6277.6 6304.7 4964.6 5923.6 6102.7 6166.3 6218.4 6193.6 6224.3 6231.4 6241.6 6243.4 5214.9 5509.5 5879.7 6104.9 6120.2 6011.3 6036.5 6088.6 6127.0 6182.6 4571.6 6094.5 6096.7 6104.3 6114.7 09:39:37 INFO - PROCESS | 1936 | 09:39:37 INFO - PROCESS | 1936 | [#1] ext.html Cycles:180 Average:63366.31 Median:38641.31 stddev:126726.18 (328.0%) stddev-sans-first:126993.69 09:39:37 INFO - PROCESS | 1936 | Values: 467.6 546.4 608.8 610.5 662.5 31563.5 35724.8 38604.8 38609.8 38672.8 42064.0 42103.6 42349.0 42661.9 42734.8 9632.8 9848.6 9850.8 9862.1 9866.2 9641.6 9702.6 9763.4 9765.8 9799.3 9710.0 9722.7 9749.1 9781.7 9790.3 9695.8 9707.8 9708.1 9794.1 9803.8 8947.3 8978.6 9016.2 9259.7 9357.0 39572.4 39575.6 39771.8 39799.4 40019.4 3324.2 3501.5 3534.9 3920.1 3983.2 28743.0 29250.0 29284.7 29339.7 29606.4 2657.8 2659.6 2707.3 2714.2 2741.3 342431.0 408394.0 440608.0 465398.0 472492.0 63259.0 66755.0 67156.7 67657.3 67682.0 76838.0 76934.6 77753.2 77973.0 78156.6 18139.5 18284.0 18343.6 18621.4 18627.3 11603.5 13944.1 13950.1 13987.7 14011.9 21245.3 22106.8 22405.2 22533.9 22847.0 8487.4 9490.2 9523.0 9534.4 9571.5 987.1 1037.8 1103.5 1154.1 1200.4 29726.6 30007.9 30481.2 30716.0 31037.7 1155.1 1199.4 1220.9 1238.1 1259.9 14363.5 15615.7 15690.8 15739.8 15740.2 484173.0 489006.0 522358.4 526769.0 529351.0 59955.0 60923.0 61491.7 61761.0 61816.4 1527.5 1530.3 1550.1 1556.3 1557.0 29703.0 29965.9 30218.5 30366.7 30780.3 51308.0 51767.0 51958.2 52043.7 52214.5 484631.0 488882.0 490522.0 491734.0 491811.0 33450.8 33830.3 34441.0 34621.7 35284.8 45661.3 45897.5 45949.3 46977.5 46995.5 38122.9 38164.9 38221.2 38254.8 38944.4 37451.6 37610.2 37745.8 37900.2 38184.8 33480.5 37282.9 38122.6 38152.8 38375.0 30873.5 31396.4 31439.7 31567.0 32706.3 26140.9 26364.9 26591.0 26802.9 26828.6 09:39:37 INFO - PROCESS | 1936 | 09:39:37 INFO - PROCESS | 1936 | [#2] jquery.html Cycles:180 Average:34909.01 Median:11857.62 stddev:102672.85 (865.9%) stddev-sans-first:102928.10 09:39:37 INFO - PROCESS | 1936 | Values: 357.4 374.1 377.4 384.9 387.4 20260.7 20755.4 20987.6 21555.4 21649.5 12525.2 13205.9 20958.6 21510.4 21510.7 4188.5 5302.2 5555.5 5591.4 5593.3 4597.5 5803.3 6085.1 6096.8 6223.9 5066.9 5615.6 6099.5 6132.9 6145.9 6634.0 6640.7 6685.8 6702.1 6734.4 5742.2 5751.2 5873.3 5941.4 5980.7 25402.0 25408.0 25484.7 25546.2 25925.6 2079.5 2159.9 2176.5 2578.8 2616.9 9561.6 10058.5 10115.0 12861.5 15097.6 1532.3 1756.7 1790.0 1793.6 1814.3 79987.0 109805.0 113877.0 116363.1 118313.4 11838.3 11937.5 11942.7 11971.8 12117.2 14304.6 14501.5 14502.6 14552.0 14568.0 9110.9 9116.1 9119.4 9122.0 9175.4 7892.7 9140.6 9320.9 9367.6 9408.3 3842.3 4343.2 4562.4 4663.3 4669.8 1896.9 1898.1 1942.7 1949.2 1952.6 878.3 1034.3 1049.7 1076.1 1107.9 19412.6 19959.4 20261.0 20546.6 20569.9 917.9 997.7 1028.2 1047.7 1079.4 8108.4 8466.8 9419.7 11283.4 11391.9 516085.4 611628.6 638337.0 657873.0 659925.0 119353.4 119860.0 120272.0 122332.4 123048.0 1056.3 1107.9 1112.0 1116.5 1118.3 16100.3 18832.4 19404.8 19415.7 19803.0 51359.7 51468.7 52515.0 53087.7 57033.0 65597.0 66781.0 70380.0 70693.3 70961.0 8098.9 8131.0 8285.2 8303.0 8315.0 9607.6 11823.2 13546.0 13656.2 13976.4 11465.9 11894.6 12088.5 12243.8 12316.4 11756.2 11801.4 11937.8 11957.9 12035.9 11929.4 11990.9 12150.0 12155.0 12201.6 7437.3 8353.3 8462.6 8477.4 8769.3 10979.7 11803.6 11922.4 11969.8 12063.7 09:39:37 INFO - PROCESS | 1936 | 09:39:37 INFO - PROCESS | 1936 | [#3] mootools.html Cycles:180 Average:6264.89 Median:537.38 stddev:3305.94 (615.2%) stddev-sans-first:3295.64 09:39:37 INFO - PROCESS | 1936 | Values: 1478.9 1533.0 1900.7 2042.9 2076.5 8456.0 9009.4 9094.5 9298.3 9321.0 9248.2 9272.7 9389.3 9390.4 9417.2 2603.0 2611.6 2619.1 2629.4 2638.1 2570.6 2615.3 2620.5 2626.6 2647.1 2605.5 2622.7 2628.2 2642.5 2642.9 2605.5 2621.1 2630.4 2636.4 2637.4 2578.4 2607.9 2622.5 2643.3 2644.4 8121.0 9032.0 9296.8 9312.9 9315.4 5743.2 5880.5 5885.4 5905.4 5924.5 3557.7 3695.7 3750.5 3762.7 3764.5 1029.9 1051.6 1054.3 1085.5 1086.8 12333.2 12333.9 12492.4 12505.2 12584.8 5091.2 5127.5 5139.3 5212.1 5234.9 10635.7 10657.4 10662.6 10712.2 10754.7 5711.7 5715.7 5849.2 5877.3 5902.8 4627.4 5060.0 5152.2 5164.8 5217.7 3344.6 3402.1 3743.2 3908.8 4140.5 1375.0 1381.6 1384.1 1385.9 1392.4 1413.7 1426.8 1447.2 1457.2 1461.9 8428.7 8449.8 8460.8 8471.9 8509.6 529.4 531.7 535.3 539.5 540.1 6376.5 6381.0 6420.2 6495.4 6702.7 10961.3 11170.4 11210.3 11269.5 11286.3 8454.1 10976.3 11026.2 11096.2 11111.9 8502.4 8549.1 8555.8 8640.4 8717.9 7017.8 7025.3 8096.4 8304.2 8332.7 9659.9 9708.4 9759.1 9867.1 9953.6 9919.1 10036.3 10084.8 10189.4 10285.3 6297.3 7350.2 7501.5 7505.7 7605.2 8508.6 8509.8 8549.3 8561.9 8569.7 6097.9 7403.4 7667.9 7709.8 7725.5 7466.3 7520.0 7525.3 7531.0 7599.8 7999.6 8039.7 8081.8 8117.0 8139.7 7244.7 7258.2 7335.8 7339.0 7360.9 7399.1 7448.2 7454.6 7459.8 7498.9 09:39:37 INFO - PROCESS | 1936 | 09:39:37 INFO - PROCESS | 1936 | [#4] prototype.html Cycles:180 Average:6014.78 Median:6778.16 stddev:1923.28 (28.4%) stddev-sans-first:1880.13 09:39:37 INFO - PROCESS | 1936 | Values: 294.0 341.1 342.5 346.3 347.0 5535.4 6879.7 6879.8 6917.7 6931.9 6738.2 6768.3 6774.4 6784.5 6817.7 4820.4 4899.1 5013.7 5050.0 5101.5 4998.5 5019.4 5022.6 5137.3 5202.8 5028.6 5029.9 5032.7 5053.7 5068.9 5049.8 5059.3 5067.7 5083.1 5095.6 3493.7 4168.8 4314.3 4338.3 4360.5 6334.2 6561.5 6644.7 6648.3 6683.4 5945.0 5966.4 5992.5 5994.6 6013.1 7111.0 7429.3 7451.5 7460.4 7463.1 4482.5 4895.9 4939.9 4950.3 4996.5 6479.9 7691.6 7736.6 7838.7 7874.0 4825.7 4968.1 4968.4 4985.3 4993.1 4014.3 4110.5 4856.9 5076.4 5107.3 5322.4 5564.8 5995.9 6001.5 6028.9 7442.9 7452.6 7459.4 7466.9 7502.7 1762.5 1790.5 1791.6 1793.2 1797.8 1114.6 1158.2 1160.5 1167.2 1168.0 5832.7 6782.0 6798.1 6832.2 6880.2 7342.5 7613.3 7622.3 7626.3 7657.2 6729.1 6910.5 6960.4 6965.8 6969.6 4023.1 4074.6 4088.8 4089.2 4101.9 8020.4 8028.2 8031.3 8061.1 8098.5 7742.4 7812.2 7947.2 7957.9 7974.4 6598.2 7839.6 7980.6 8028.2 8050.5 6351.9 7645.7 7721.4 7735.3 7754.5 7546.9 7570.7 7587.8 7594.7 7597.0 7751.4 7987.9 7998.2 8027.7 8041.5 4992.4 5012.5 5017.2 5022.9 5031.7 7548.6 7639.5 7661.5 7682.9 7695.1 7330.0 7371.3 7383.6 7393.3 7416.1 5908.9 7059.0 7364.2 7403.7 7429.9 7191.0 7216.5 7220.7 7230.3 7231.4 7367.9 7376.5 7380.0 7410.4 7421.7 5857.4 7164.0 7171.5 7196.4 7236.4 09:39:37 INFO - PROCESS | 1936 | 09:39:37 INFO - PROCESS | 1936 | [#5] yui.html Cycles:180 Average:18669.47 Median:52759.60 stddev:26791.49 (50.8%) stddev-sans-first:26832.55 09:39:37 INFO - PROCESS | 1936 | Values: 667.9 671.2 721.0 760.4 790.2 13640.8 14278.9 15317.4 15403.5 15577.8 12533.9 12544.2 14262.9 14783.9 14804.6 801.4 973.4 992.5 999.1 1000.6 981.2 984.7 986.3 986.5 989.9 941.6 1025.9 1034.8 1043.4 1044.3 1025.3 1028.7 1035.8 1037.4 1041.7 768.7 773.5 773.9 778.8 783.9 12777.9 15226.5 15331.7 15418.3 15527.5 13496.3 13500.5 13540.7 13612.7 13616.1 10322.3 10351.8 10360.8 10384.1 10684.8 3540.7 3548.5 3601.9 3646.1 3806.2 86060.6 86381.0 87242.6 87435.6 87548.0 9662.0 9894.7 9995.9 10118.1 10217.5 21902.5 22182.3 22371.6 22582.9 22604.7 7554.6 7665.9 7780.0 7863.8 8019.7 4201.6 4201.7 4214.6 4278.4 4283.1 3757.7 3779.3 3789.7 3813.9 3818.3 572.9 707.3 708.3 716.8 723.9 545.8 615.1 616.8 618.3 631.2 8120.3 8396.5 8423.7 8532.4 8566.5 568.6 598.9 600.9 630.9 638.2 3801.9 3890.7 3985.8 4022.3 4045.9 94855.5 97734.0 97777.0 98648.0 98830.5 80453.2 81200.0 83414.0 84037.6 84254.0 54483.7 73214.0 73375.3 74185.0 75044.9 7547.5 7662.5 7722.0 7734.2 7788.5 50474.5 51303.5 52034.2 52693.7 52825.5 50970.7 63717.7 65243.7 68224.0 71296.0 9968.8 9990.7 10099.3 10174.3 10192.8 10828.9 10938.2 11015.9 11045.9 11133.4 9365.2 10150.6 10239.9 10338.5 10620.7 10353.8 10398.8 10417.7 10789.6 10937.0 10773.1 11290.5 11388.4 12056.5 12214.5 8122.0 9825.6 9834.7 9843.7 9851.6 9140.6 9219.5 9673.9 10004.9 10130.8 09:39:37 INFO - PROCESS | 1936 | -------- Summary: end -------- 09:39:37 INFO - PROCESS | 1936 | 09:39:38 INFO - TEST-INFO | 1936: exit 0 09:39:38 INFO - TEST-OK | dromaeo_css | took 1110823ms 09:39:38 INFO - TEST-START | kraken 09:39:38 INFO - operating with platform_type : mac_ 09:39:38 INFO - Initialising browser for kraken test... 09:39:38 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpErkoCQ/profile http://localhost:52463/getInfo.html) 09:39:40 INFO - PROCESS | 1942 | __metrics Screen width/height:1600/1200 09:39:40 INFO - PROCESS | 1942 | colorDepth:24 09:39:40 INFO - PROCESS | 1942 | Browser inner width/height: 1024/689 09:39:40 INFO - PROCESS | 1942 | __metrics 09:39:40 ERROR - PROCESS | 1942 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 09:39:41 INFO - TEST-INFO | 1942: exit 0 09:39:41 INFO - Browser initialized. 09:39:41 INFO - Running cycle 1/1 for kraken test... 09:39:41 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpErkoCQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:53 INFO - PROCESS | 1945 | RSS: Main: 218030080 09:39:53 INFO - PROCESS | 1945 | 09:39:56 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52463/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52463/tests/kraken/driver.html?testName=audio-beat-detection) 09:39:56 INFO - PROCESS | 1945 | RSS: Main: 230162432 09:39:56 INFO - PROCESS | 1945 | 09:39:59 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52463/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52463/tests/kraken/driver.html?testName=audio-dft) 09:40:00 INFO - PROCESS | 1945 | RSS: Main: 238919680 09:40:00 INFO - PROCESS | 1945 | 09:40:03 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52463/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52463/tests/kraken/driver.html?testName=audio-fft) 09:40:04 INFO - PROCESS | 1945 | RSS: Main: 242536448 09:40:04 INFO - PROCESS | 1945 | 09:40:05 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52463/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52463/tests/kraken/driver.html?testName=audio-oscillator) 09:40:06 INFO - PROCESS | 1945 | RSS: Main: 244297728 09:40:06 INFO - PROCESS | 1945 | 09:40:08 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52463/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52463/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:40:09 INFO - PROCESS | 1945 | RSS: Main: 244752384 09:40:09 INFO - PROCESS | 1945 | 09:40:11 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52463/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52463/tests/kraken/driver.html?testName=imaging-darkroom) 09:40:12 INFO - PROCESS | 1945 | RSS: Main: 245047296 09:40:12 INFO - PROCESS | 1945 | 09:40:15 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52463/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52463/tests/kraken/driver.html?testName=imaging-desaturate) 09:40:15 INFO - PROCESS | 1945 | RSS: Main: 244543488 09:40:15 INFO - PROCESS | 1945 | 09:40:19 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52463/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52463/tests/kraken/driver.html?testName=json-parse-financial) 09:40:19 INFO - PROCESS | 1945 | RSS: Main: 244641792 09:40:19 INFO - PROCESS | 1945 | 09:40:21 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52463/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52463/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:40:21 INFO - PROCESS | 1945 | RSS: Main: 244531200 09:40:21 INFO - PROCESS | 1945 | 09:40:23 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52463/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52463/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:40:24 INFO - PROCESS | 1945 | RSS: Main: 244572160 09:40:24 INFO - PROCESS | 1945 | 09:40:26 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52463/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52463/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:40:26 INFO - PROCESS | 1945 | RSS: Main: 245587968 09:40:26 INFO - PROCESS | 1945 | 09:40:29 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52463/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52463/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:40:30 INFO - PROCESS | 1945 | RSS: Main: 245661696 09:40:30 INFO - PROCESS | 1945 | 09:40:32 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52463/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52463/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:40:33 INFO - PROCESS | 1945 | RSS: Main: 245727232 09:40:33 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52463/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52463/tests/kraken/driver.html?testName=ai-astar) 09:40:35 INFO - PROCESS | 1945 | RSS: Main: 246628352 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | __start_tp_report 09:40:35 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 09:40:35 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 09:40:35 INFO - PROCESS | 1945 | |i|pagename|runs| 09:40:35 INFO - PROCESS | 1945 | |0;ai-astar;116;112;116;114;116;130;95;165;168;94 09:40:35 INFO - PROCESS | 1945 | |1;audio-beat-detection;175;152;160;184;149;142;135;119;193;140 09:40:35 INFO - PROCESS | 1945 | |2;audio-dft;192;197;198;201;196;198;194;195;207;195 09:40:35 INFO - PROCESS | 1945 | |3;audio-fft;65;70;76;108;59;56;56;60;124;57 09:40:35 INFO - PROCESS | 1945 | |4;audio-oscillator;83;79;123;81;81;85;120;80;80;121 09:40:35 INFO - PROCESS | 1945 | |5;imaging-gaussian-blur;90;88;88;91;90;86;90;102;122;86 09:40:35 INFO - PROCESS | 1945 | |6;imaging-darkroom;95;96;94;96;95;95;95;143;151;95 09:40:35 INFO - PROCESS | 1945 | |7;imaging-desaturate;90;98;91;125;123;90;123;103;139;142 09:40:35 INFO - PROCESS | 1945 | |8;json-parse-financial;57;62;59;59;62;61;61;69;56;56 09:40:35 INFO - PROCESS | 1945 | |9;json-stringify-tinderbox;63;64;81;85;70;69;66;96;100;98 09:40:35 INFO - PROCESS | 1945 | |10;stanford-crypto-aes;83;81;83;83;81;83;82;83;82;84 09:40:35 INFO - PROCESS | 1945 | |11;stanford-crypto-ccm;132;105;134;135;138;138;143;119;145;107 09:40:35 INFO - PROCESS | 1945 | |12;stanford-crypto-pbkdf2;168;137;121;175;177;127;138;134;135;134 09:40:35 INFO - PROCESS | 1945 | |13;stanford-crypto-sha256-iterative;65;67;64;65;68;68;78;89;87;67 09:40:35 INFO - PROCESS | 1945 | __end_tp_report 09:40:35 INFO - PROCESS | 1945 | __start_cc_report 09:40:35 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,928 09:40:35 INFO - PROCESS | 1945 | __end_cc_report 09:40:35 INFO - PROCESS | 1945 | __startTimestamp1460997635330__endTimestamp 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | ------- Summary: start ------- 09:40:35 INFO - PROCESS | 1945 | Number of tests: 14 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | [#0] ai-astar Cycles:10 Average:122.60 Median:147.50 stddev:25.41 (17.2%) stddev-sans-first:26.84 09:40:35 INFO - PROCESS | 1945 | Values: 116.0 112.0 116.0 114.0 116.0 130.0 95.0 165.0 168.0 94.0 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | [#1] audio-beat-detection Cycles:10 Average:154.90 Median:156.00 stddev:23.20 (14.9%) stddev-sans-first:23.44 09:40:35 INFO - PROCESS | 1945 | Values: 175.0 152.0 160.0 184.0 149.0 142.0 135.0 119.0 193.0 140.0 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | [#2] audio-dft Cycles:10 Average:197.30 Median:197.50 stddev:4.22 (2.1%) stddev-sans-first:4.01 09:40:35 INFO - PROCESS | 1945 | Values: 192.0 197.0 198.0 201.0 196.0 198.0 194.0 195.0 207.0 195.0 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | [#3] audio-fft Cycles:10 Average:73.10 Median:59.50 stddev:23.82 (40.0%) stddev-sans-first:25.08 09:40:35 INFO - PROCESS | 1945 | Values: 65.0 70.0 76.0 108.0 59.0 56.0 56.0 60.0 124.0 57.0 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:80.50 stddev:19.43 (24.1%) stddev-sans-first:20.25 09:40:35 INFO - PROCESS | 1945 | Values: 83.0 79.0 123.0 81.0 81.0 85.0 120.0 80.0 80.0 121.0 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | [#5] imaging-gaussian-blur Cycles:10 Average:93.30 Median:89.00 stddev:11.06 (12.4%) stddev-sans-first:11.66 09:40:35 INFO - PROCESS | 1945 | Values: 90.0 88.0 88.0 91.0 90.0 86.0 90.0 102.0 122.0 86.0 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | [#6] imaging-darkroom Cycles:10 Average:105.50 Median:95.00 stddev:21.96 (23.1%) stddev-sans-first:22.96 09:40:35 INFO - PROCESS | 1945 | Values: 95.0 96.0 94.0 96.0 95.0 95.0 95.0 143.0 151.0 95.0 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | [#7] imaging-desaturate Cycles:10 Average:112.40 Median:116.00 stddev:20.34 (17.5%) stddev-sans-first:19.90 09:40:35 INFO - PROCESS | 1945 | Values: 90.0 98.0 91.0 125.0 123.0 90.0 123.0 103.0 139.0 142.0 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:61.00 stddev:3.85 (6.3%) stddev-sans-first:3.91 09:40:35 INFO - PROCESS | 1945 | Values: 57.0 62.0 59.0 59.0 62.0 61.0 61.0 69.0 56.0 56.0 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | [#9] json-stringify-tinderbox Cycles:10 Average:79.20 Median:75.50 stddev:14.76 (19.6%) stddev-sans-first:14.45 09:40:35 INFO - PROCESS | 1945 | Values: 63.0 64.0 81.0 85.0 70.0 69.0 66.0 96.0 100.0 98.0 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.00 stddev:0.97 (1.2%) stddev-sans-first:1.01 09:40:35 INFO - PROCESS | 1945 | Values: 83.0 81.0 83.0 83.0 81.0 83.0 82.0 83.0 82.0 84.0 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | [#11] stanford-crypto-ccm Cycles:10 Average:129.60 Median:136.50 stddev:14.30 (10.5%) stddev-sans-first:15.14 09:40:35 INFO - PROCESS | 1945 | Values: 132.0 105.0 134.0 135.0 138.0 138.0 143.0 119.0 145.0 107.0 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.60 Median:137.50 stddev:20.57 (15.0%) stddev-sans-first:19.99 09:40:35 INFO - PROCESS | 1945 | Values: 168.0 137.0 121.0 175.0 177.0 127.0 138.0 134.0 135.0 134.0 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - PROCESS | 1945 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:68.00 stddev:9.39 (13.8%) stddev-sans-first:9.63 09:40:35 INFO - PROCESS | 1945 | Values: 65.0 67.0 64.0 65.0 68.0 68.0 78.0 89.0 87.0 67.0 09:40:35 INFO - PROCESS | 1945 | -------- Summary: end -------- 09:40:35 INFO - PROCESS | 1945 | 09:40:35 INFO - TEST-INFO | 1945: exit 0 09:40:36 INFO - TEST-OK | kraken | took 57973ms 09:40:36 INFO - SUITE-END | took 1168s 09:40:36 INFO - Completed test suite (00:19:29) 09:40:36 INFO - javascript benchmark 09:40:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3475.843587038583, 3494.860205591776, 3529.8235088245583, 3536.6993805526545, 3549.485324627929, 4593.471750748665, 4653.464851542074, 5731.971340143294, 5779.367657462859, 5783.190353350529, 5730.255066841315, 5752.511036561887, 5783.855403614902, 5799.391063938278, 5805.15825205346, 5171.534561889429, 5208.374995000605, 5253.4746525347355, 5281.788728450857, 5320.521153096221, 5193.0, 5285.709285989269, 5293.153095504701, 5309.893802123975, 5335.67985920846, 5219.373675158968, 5268.8155914542995, 5294.205869119624, 5302.2311764794085, 5314.548263397596, 5285.920711189336, 5309.177077552947, 5319.095753721857, 5326.440723723992, 5336.012837625051, 5216.58267338612, 5258.7370631468275, 5311.123664595337, 5313.495217954288, 5313.867153321159, 4582.839600613996, 5575.470330318653, 5702.686352250625, 5744.138379243105, 5867.442592953663, 6201.131841542143, 6205.131281620576, 6210.813675589694, 6220.377962203788, 6268.1851359323, 6287.4655654269445, 6293.087502312185, 6306.1171435998995, 6306.2747784004505, 6378.649174295412, 4702.929456058162, 4838.491958344393, 5011.198208286693, 5948.7323070461935, 6005.39946005396, 6265.449326210039, 6304.211973503312, 6306.306306306303, 6322.178116844781, 6347.079673447324, 6103.755849766048, 6242.5942313749465, 6244.65654389013, 6251.999680051261, 6284.0, 4983.92845538211, 6155.10750941111, 6183.443490085914, 6272.749090036439, 6284.214473190851, 6201.472874805692, 6214.906776398358, 6217.626942383471, 6236.532260080512, 6271.05934109878, 5185.377754669388, 5440.515238826362, 6061.151438798572, 6255.46828519581, 6261.499080073583, 4885.145099607555, 4894.363732714724, 4959.652824302264, 4972.129877271463, 4995.975020124949, 4458.910821783611, 4470.865874023784, 4473.955260447419, 4485.663575231871, 4486.214912390318, 5720.885582288331, 5921.052631579013, 5923.733431995484, 5927.792527261525, 5934.970325148347, 5204.323437953042, 6251.3123556408755, 6272.247330320391, 6277.18396608438, 6304.5586808922935, 5578.191162281528, 5840.123981402823, 5854.268216472941, 5864.325602555676, 5877.617954832922, 5386.218998245311, 5441.483059109379, 5444.700541470257, 5448.618596698272, 5457.590680698963, 6388.744450221939, 6444.838879028156, 6452.419282264619, 6460.935390646034, 6466.030095485715, 6240.906386404298, 6256.687165641791, 6299.181106456132, 6303.9684801575695, 6309.211348581427, 6273.404026617464, 6332.873342533031, 6408.679566021773, 6426.550141490051, 6458.903116453163, 6130.601510901777, 6156.630602163706, 6159.938400616115, 6180.412860778219, 6193.473554747897, 6243.0, 6272.937270627235, 6274.403931626488, 6277.623342599459, 6304.7162877671535, 4964.553190212898, 5923.644581325134, 6102.664353460602, 6166.321704612579, 6218.409251121137, 6193.597416167935, 6224.253089629274, 6231.40801623845, 6241.563090583615, 6243.406876346739, 5214.8957020860125, 5509.539971907528, 5879.676617786063, 6104.938950610437, 6120.234970628671, 6011.308699499614, 6036.547258955684, 6088.6042407243385, 6126.975740326223, 6182.598131121463, 4571.611413029929, 6094.481969032505, 6096.664683442454, 6104.298005729398, 6114.724837382418], "unit": "score", "name": "dojo.html", "value": 5684.047790579624}, {"lowerIsBetter": false, "replicates": [467.5978658353815, 546.4480874316939, 608.7625825927892, 610.4871907597618, 662.5031226580065, 31563.526547101756, 35724.821375893116, 38604.80697596512, 38609.80695096528, 38672.806635966816, 42064.0, 42103.57896421035, 42349.0, 42661.933451663725, 42734.78632606832, 9632.759181020461, 9848.55681494331, 9850.753731156707, 9862.063104005108, 9866.16137628299, 9641.566129524153, 9702.563384647672, 9763.36538125023, 9765.755856103584, 9799.265055120859, 9710.028997100304, 9722.659706910259, 9749.122578967892, 9781.728375311199, 9790.314677972545, 9695.757606059835, 9707.757306067335, 9708.126268635822, 9794.069563391502, 9803.803923921552, 8947.32895032875, 8978.640854365884, 9016.188543031092, 9259.72220833376, 9357.0, 39572.40641390382, 39575.604243957765, 39771.80114099411, 39799.40300895489, 40019.399709004385, 3324.1523411530147, 3501.5447991760907, 3534.9469757953652, 3920.0983773731914, 3983.24318379507, 28743.0, 29250.0, 29284.707152928626, 29339.706602934126, 29606.407871842657, 2657.760801527872, 2659.5611724065507, 2707.28257011892, 2714.1993112031996, 2741.300968253103, 342431.0, 408394.0, 440608.0, 465398.0, 472492.0, 63259.0, 66754.99867501992, 67156.6642166786, 67657.3234267651, 67682.0, 76838.0, 76934.61532692301, 77753.22246777573, 77973.0, 78156.60921695356, 18139.54651133706, 18283.99438030921, 18343.63312733738, 18621.44135675932, 18627.348042818438, 11603.477843497063, 13944.093633913762, 13950.09324393911, 13987.650308742159, 14011.929940350234, 21245.256416025364, 22106.784126873175, 22405.21581744631, 22533.88733056357, 22847.0, 8487.363447741443, 9490.240780737662, 9522.95238523803, 9534.427934323963, 9571.521423928776, 987.09680642213, 1037.8131936251395, 1103.4963989333833, 1154.1286328821686, 1200.4057991294364, 29726.554101688493, 30007.949721759633, 30481.23796905095, 30716.0, 31037.68962310348, 1155.122107198535, 1199.3643369014437, 1220.8840160184768, 1238.1456794811552, 1259.8866102050858, 14363.497277595443, 15615.687686246212, 15690.843091569166, 15739.842601573837, 15740.212989350486, 484173.0, 489006.0, 522358.3882080565, 526769.0, 529351.0, 59955.0, 60923.0, 61491.6925415379, 61761.0, 61816.381836181965, 1527.480656576747, 1530.2807680390422, 1550.116433632841, 1556.2529985606745, 1556.9844301556839, 29703.0, 29965.850170749007, 30218.54672179963, 30366.69633303727, 30780.30235351361, 51307.97384052223, 51767.0, 51958.220626691385, 52043.73978130085, 52214.47785522096, 484631.0, 488882.0, 490522.0, 491734.0, 491811.0, 33450.83274583709, 33830.3233935315, 34441.0, 34621.65378346285, 35284.82357588196, 45661.315080274486, 45897.48809588809, 45949.310760337954, 46977.53022469868, 46995.53004469912, 38122.856314310615, 38164.855054348416, 38221.2355752889, 38254.808725956194, 38944.41583376195, 37451.625483745906, 37610.247795044495, 37745.81127094347, 37900.24199515939, 38184.809075955556, 33480.49779253362, 37282.88151355464, 38122.618773811904, 38152.809235953646, 38375.0, 30873.536896947015, 31396.372072557962, 31439.685603143676, 31567.0, 32706.345873082882, 26140.8692956534, 26364.868175658998, 26591.0, 26802.865985670727, 26828.59757103684], "unit": "score", "name": "ext.html", "value": 20474.211057748464}, {"lowerIsBetter": false, "replicates": [357.3638922717563, 374.1152175105874, 377.44515562123667, 384.89030626271517, 387.35892098576863, 20260.69608955865, 20755.377338679828, 20987.580248395025, 21555.353339399822, 21649.458763530918, 12525.2484850909, 13205.877500412476, 20958.580828383423, 21510.354689359305, 21510.677339839876, 4188.455500784894, 5302.204669299616, 5555.472230138131, 5591.440855914417, 5593.272874526306, 4597.54024597541, 5803.274590676166, 6085.117657939618, 6096.847578810521, 6223.937760622381, 5066.935943451861, 5615.596100974756, 6099.451049405553, 6132.908006379907, 6145.877082458348, 6634.038064480647, 6640.7343706251695, 6685.832854178636, 6702.128723265969, 6734.3939045485895, 5742.2247996520355, 5751.223584816038, 5873.295204575478, 5941.435563621449, 5980.73086711099, 25402.0, 25408.0, 25484.74515254842, 25546.233612991455, 25925.61111583309, 2079.5321052763156, 2159.88120653364, 2176.5211653436218, 2578.7679108880293, 2616.882240299191, 9561.61753529858, 10058.497075146213, 10115.0, 12861.549845755448, 15097.622559435993, 1532.3257766582667, 1756.6837969165545, 1789.973150402732, 1793.641271745643, 1814.3468351393487, 79987.0, 109805.0, 113877.0, 116363.10971498801, 118313.40843295814, 11838.348890810916, 11937.462814173383, 11942.701432464259, 11971.820422693667, 12117.151799374136, 14304.642383940276, 14501.477344878636, 14502.637434064232, 14552.0, 14568.0, 9110.86333704995, 9116.1339672731, 9119.407238529475, 9122.0, 9175.357724959322, 7892.724871665106, 9140.588673509708, 9320.8814942206, 9367.62529498826, 9408.294377921684, 3842.2891765023, 4343.19650864591, 4562.361269422284, 4663.300504924221, 4669.766511674403, 1896.8713615398815, 1898.1268616436319, 1942.669746143159, 1949.1716020691433, 1952.6290004899024, 878.297362110322, 1034.270839058462, 1049.7480604654827, 1076.0960792933972, 1107.9335239885634, 19412.611747764964, 19959.401217963485, 20260.968456335704, 20546.589068218553, 20569.94300569931, 917.9127982841619, 997.7156510394502, 1028.1928685981468, 1047.6909311753052, 1079.3739631013993, 8108.351331893435, 8466.830663386698, 9419.670311539063, 11283.379414132301, 11391.886081139248, 516085.41957289976, 611628.6158289518, 638337.0, 657873.0, 659925.0, 119353.40323298328, 119860.0, 120272.0, 122332.38833805775, 123048.0, 1056.2975621211963, 1107.883672214422, 1112.0491979357694, 1116.5143162724241, 1118.2563595209258, 16100.275487602852, 18832.435026949213, 19404.805951940863, 19415.708764368264, 19803.0, 51359.74320128375, 51468.713282168246, 52515.0, 53087.73456132694, 57033.0, 65597.0, 66781.0, 70380.0, 70693.29306707073, 70961.0, 8098.878516822135, 8130.959345203236, 8285.171482851667, 8302.958485207535, 8315.037601454502, 9607.56765945548, 11823.231489953125, 13546.0, 13656.248906309851, 13976.440942362191, 11465.94267028693, 11894.583689571169, 12088.516459341528, 12243.816342754688, 12316.445759940645, 11756.177067605186, 11801.35092569917, 11937.820932685843, 11957.940210298893, 12035.939820300842, 11929.403529823649, 11990.940045299718, 12150.0, 12155.0, 12201.572944946884, 7437.256274372519, 8353.331733461187, 8462.619182136941, 8477.364197685074, 8769.298456123368, 10979.710318709323, 11803.645890623295, 11922.4038798058, 11969.820452693042, 12063.698407539881], "unit": "score", "name": "jquery.html", "value": 9675.893250505873}, {"lowerIsBetter": false, "replicates": [1478.8742956848666, 1532.9958876935607, 1900.743399641048, 2042.948926276843, 2076.4912596413883, 8456.0, 9009.414388064772, 9094.49980251086, 9298.25613950884, 9321.0, 9248.167664910157, 9272.67545635903, 9389.295802814782, 9390.389624674392, 9417.199538039258, 2603.0368763557462, 2611.6474275972787, 2619.148776647597, 2629.421527263999, 2638.089858998652, 2570.550153723091, 2615.293870654922, 2620.5414052540823, 2626.6454028706166, 2647.086755069498, 2605.531004419204, 2622.72461391554, 2628.172125780373, 2642.4715056988584, 2642.8546429946346, 2605.544029794788, 2621.082621082625, 2630.368711509224, 2636.4068084680985, 2637.353848307158, 2578.432744796142, 2607.9478410431875, 2622.501724672325, 2643.299525625711, 2644.352133727249, 8121.0, 9031.961324447708, 9296.814063718755, 9312.90687093134, 9315.39449935752, 5743.195952566644, 5880.529557635379, 5885.440883116097, 5905.4094590541035, 5924.526037916957, 3557.7153827693724, 3695.7043436525014, 3750.5499340079364, 3762.698984081267, 3764.5294338207723, 1029.8764148302178, 1051.5898799468137, 1054.3410368519676, 1085.5115198160859, 1086.7772106718103, 12333.198342107735, 12333.938330308381, 12492.375381230993, 12505.187162834387, 12584.81122783159, 5091.236314552847, 5127.461616530285, 5139.280500729901, 5212.113940630102, 5234.92147617786, 10635.734106647242, 10657.413842238751, 10662.573497060186, 10712.196585256137, 10754.73113172177, 5711.685857277889, 5715.714214289269, 5849.239598852122, 5877.265341832271, 5902.76388944446, 4627.375304333939, 5060.0, 5152.175651895679, 5164.767585458663, 5217.713025783542, 3344.565206523185, 3402.0644322811427, 3743.232637309345, 3908.804559772, 4140.523839758406, 1374.9725005499834, 1381.599336192493, 1384.0657556149558, 1385.9376328065262, 1392.401267455004, 1413.7101894111681, 1426.8359138698975, 1447.1968057727852, 1457.1985408025544, 1461.9488317908822, 8428.704995325133, 8449.788755281168, 8460.830783384297, 8471.856299399626, 8509.61706723199, 529.4073908840791, 531.7394476706463, 535.2987586261931, 539.4632340820908, 540.1276937745513, 6376.458001069868, 6380.968095159495, 6420.229572451242, 6495.415412612888, 6702.698378572976, 10961.287516311573, 11170.385628790333, 11210.27133236337, 11269.549218031187, 11286.322820630625, 8454.112318206622, 10976.286541374786, 11026.228164028442, 11096.167787415976, 11111.888881111085, 8502.44734092282, 8549.059603443617, 8555.786105347415, 8640.395172337876, 8717.910261217348, 7017.824554386181, 7025.297470252934, 8096.352291816523, 8304.211099945496, 8332.6666933322, 9659.855102173333, 9708.368956017835, 9759.072888075621, 9867.06262905023, 9953.601855925683, 9919.107280344804, 10036.29745917808, 10084.848727269244, 10189.439580823126, 10285.2800303978, 6297.307296197506, 7350.228226036189, 7501.512401693873, 7505.6997720090585, 7605.163432022584, 8508.574571271536, 8509.787255318666, 8549.316054715733, 8561.914380856111, 8569.65721371163, 6097.939020609737, 7403.370713489414, 7667.88498172539, 7709.768706938801, 7725.49784264021, 7466.290702383265, 7520.0, 7525.285097915689, 7530.962345188239, 7599.810004749926, 7999.600019999093, 8039.718609848627, 8081.838363232585, 8117.0, 8139.715109971123, 7244.673989670372, 7258.237885022208, 7335.77992660221, 7338.96330518344, 7360.889586656097, 7399.112106547249, 7448.217937116741, 7454.552726836408, 7459.813504662427, 7498.925010749823], "unit": "score", "name": "mootools.html", "value": 5044.880578344071}, {"lowerIsBetter": false, "replicates": [293.99706002939973, 341.1011983423678, 342.5307328959326, 346.2814659581368, 346.9930601387973, 5535.446455354468, 6879.690413931373, 6879.83527577315, 6917.654117294141, 6931.896021559681, 6738.191417029963, 6768.289329620393, 6774.356436138572, 6784.491163162757, 6817.6932038058285, 4820.3733514643045, 4899.069176856386, 5013.699178049329, 5050.0, 5101.54086132248, 4998.475160108191, 5019.372578427697, 5022.59819214462, 5137.332146820908, 5202.8179013734525, 5028.572571331441, 5029.924551131736, 5032.748362581875, 5053.696778193302, 5068.923966140511, 5049.823256186034, 5059.2916991621205, 5067.670601410915, 5083.085044691954, 5095.643304968654, 3493.6855682988653, 4168.791050595324, 4314.331278651814, 4338.327559228324, 4360.520342762294, 6334.208223972004, 6561.475081993429, 6644.700988455531, 6648.3046823060295, 6683.398494135551, 5944.970275148597, 5966.433188847052, 5992.460678538909, 5994.610350327215, 6013.128096426038, 7110.968909508092, 7429.33136017761, 7451.515651482637, 7460.36586890115, 7463.067116610424, 4482.5069242383315, 4895.902081958377, 4939.851804445872, 4950.282209079699, 4996.475370086161, 6479.870402591969, 7691.61550920834, 7736.574488403136, 7838.7256446024685, 7873.96063019687, 4825.672939941474, 4968.105740966588, 4968.354113965162, 4985.252212168204, 4993.1262029144755, 4014.2573623879093, 4110.486189226347, 4856.902861942742, 5076.416212135578, 5107.33604631403, 5322.4411436799355, 5564.8052318169475, 5995.910061349084, 6001.459868611846, 6028.9397106029255, 7442.8883566746545, 7452.627368631655, 7459.403247740168, 7466.887996680054, 7502.699892004259, 1762.532928773876, 1790.525510739629, 1791.587934775009, 1793.1661777273368, 1797.8381945624956, 1114.587602587048, 1158.1719070864372, 1160.4835848047537, 1167.1538134852333, 1168.0, 5832.708364581754, 6781.966090169518, 6798.082258895085, 6832.248452670202, 6880.174379074443, 7342.5227360221415, 7613.276738709814, 7622.31399174077, 7626.313631773056, 7657.195994420617, 6729.091572637729, 6910.516263861582, 6960.408365289007, 6965.82585435358, 6969.58182509051, 4023.0746928206677, 4074.6129117733767, 4088.754674719467, 4089.2434899543678, 4101.85643502476, 8020.398470114765, 8028.197180281925, 8031.317338026333, 8061.072976607648, 8098.51408915467, 7742.361001732583, 7812.218778122142, 7947.205279472122, 7957.880631790527, 7974.362051035903, 6598.241202261705, 7839.568823714751, 7980.600969951479, 8028.237317454832, 8050.5169689818795, 6351.920173570412, 7645.694172233048, 7721.382289416945, 7735.265149810759, 7754.534727916343, 7546.886796698163, 7570.697172113054, 7587.848243035219, 7594.696212151452, 7596.962015189889, 7751.37988960893, 7987.92163058003, 7998.160193179632, 8027.719029834161, 8041.517508949481, 4992.425871024879, 5012.523810238022, 5017.247412888096, 5022.949770502249, 5031.6729412588065, 7548.584827834302, 7639.503432276884, 7661.463697541119, 7682.884756728542, 7695.115061767969, 7330.047093877762, 7371.2677520783645, 7383.593902335423, 7393.260673932778, 7416.0729908761405, 5908.940910590839, 7058.964705176647, 7364.189939106587, 7403.703851845866, 7429.851402972018, 7191.0, 7216.494845360774, 7220.711171553079, 7230.349268565854, 7231.385332246818, 7367.889481657887, 7376.520526165782, 7379.963100184465, 7410.4442166836625, 7421.703131874665, 5857.414258574108, 7164.0, 7171.4621403395995, 7196.352328290479, 7236.38490728294], "unit": "score", "name": "prototype.html", "value": 5376.261078573824}, {"lowerIsBetter": false, "replicates": [667.8864593019186, 671.1677519875353, 720.9891851622224, 760.3802900635982, 790.2137373313553, 13640.795388069162, 14278.92860535696, 15317.38730450782, 15403.537893863175, 15577.844221557782, 12533.937330313358, 12544.184627999173, 14262.928685356585, 14783.852161478382, 14804.55586332409, 801.3949468151537, 973.4159504297436, 992.5136683025302, 999.050901643438, 1000.584757325709, 981.249344251648, 984.6602921991902, 986.3194395866828, 986.4968865878382, 989.8861630912429, 941.6327632223438, 1025.933029649164, 1034.8292531732254, 1043.3739756146326, 1044.2846650044744, 1025.2976710952985, 1028.6965345223177, 1035.8290882004499, 1037.4345981440133, 1041.713528779584, 768.6694721269852, 773.5242825662233, 773.8877862709876, 778.796759007335, 783.8510682970275, 12777.936110319482, 15226.543203703906, 15331.693366132615, 15418.306176222097, 15527.45653902108, 13496.325183740872, 13500.459981600725, 13540.661483462894, 13612.659683507893, 13616.114952528054, 10322.277440579162, 10351.792964140675, 10360.792784144274, 10384.065434110891, 10684.839727404094, 3540.7167426606065, 3548.538689970287, 3601.927961440783, 3646.1249300168033, 3806.1626442182674, 86060.56969715175, 86381.0, 87242.56378718065, 87435.56282218549, 87548.0, 9661.951690241503, 9894.703158905244, 9995.850062249072, 10118.139958103644, 10217.540210690537, 21902.45243868884, 22182.334529964126, 22371.55256894853, 22582.887085564795, 22604.660930086062, 7554.622268886534, 7665.8850117248285, 7779.988601481884, 7863.764087077397, 8019.679212831421, 4201.558836322203, 4201.663866890642, 4214.578542145761, 4278.379634952915, 4283.100548884707, 3757.6618104370737, 3779.338615742234, 3789.6778773803985, 3813.9427908581392, 3818.3127037133027, 572.8825590753886, 707.3209718670031, 708.2563308526027, 716.7634680555509, 723.8660847743184, 545.8198794397839, 615.1203778596649, 616.7810427298244, 618.3414663383483, 631.2204427531994, 8120.26917577409, 8396.454230475, 8423.663053477792, 8532.402731808837, 8566.486010839244, 568.573569822633, 598.856274494127, 600.8738164985315, 630.9053641953743, 638.1863124516279, 3801.8669346572738, 3890.747101438454, 3985.7608543486904, 4022.255882661717, 4045.9190816183514, 94855.52572237095, 97734.0, 97777.0, 98648.0, 98830.5058474703, 80453.19546804574, 81200.0, 83414.0, 84037.57981210055, 84254.0, 54483.72758136263, 73214.0, 73375.26624733792, 74185.0, 75044.87432688405, 7547.471676982558, 7662.501937374052, 7722.0, 7734.199350487134, 7788.493747906368, 50474.49525504698, 51303.486965131364, 52034.21948670848, 52693.736531317096, 52825.47174528359, 50970.74514627403, 63717.681411592646, 65243.69512609626, 68223.97664035142, 71296.0, 9968.750781230527, 9990.650327238513, 10099.293049486465, 10174.338667986558, 10192.796144077225, 10828.931530832626, 10938.179636527133, 11015.889841101807, 11045.889541104487, 11133.443327833738, 9365.203957663674, 10150.644727434505, 10239.897601023895, 10338.483075846329, 10620.734481638021, 10353.84469232947, 10398.844017339898, 10417.739556511147, 10789.56841726322, 10937.0, 10773.138148948223, 11290.548378064785, 11388.37363944991, 12056.51773929033, 12214.511419543118, 8122.0, 9825.557849896915, 9834.704958851245, 9843.655472058443, 9851.556679949275, 9140.63437462494, 9219.539023048956, 9673.854892176481, 10004.949975250078, 10130.797384052425], "unit": "score", "name": "yui.html", "value": 7036.25040449929}], "name": "dromaeo_css", "value": 7739.456817113255}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [116.0, 112.0, 116.0, 114.0, 116.0, 130.0, 95.0, 165.0, 168.0, 94.0], "unit": "score", "name": "ai-astar", "value": 122.6}, {"lowerIsBetter": true, "replicates": [175.0, 152.0, 160.0, 184.0, 149.0, 142.0, 135.0, 119.0, 193.0, 140.0], "unit": "score", "name": "audio-beat-detection", "value": 154.9}, {"lowerIsBetter": true, "replicates": [192.0, 197.0, 198.0, 201.0, 196.0, 198.0, 194.0, 195.0, 207.0, 195.0], "unit": "score", "name": "audio-dft", "value": 197.3}, {"lowerIsBetter": true, "replicates": [65.0, 70.0, 76.0, 108.0, 59.0, 56.0, 56.0, 60.0, 124.0, 57.0], "unit": "score", "name": "audio-fft", "value": 73.1}, {"lowerIsBetter": true, "replicates": [83.0, 79.0, 123.0, 81.0, 81.0, 85.0, 120.0, 80.0, 80.0, 121.0], "unit": "score", "name": "audio-oscillator", "value": 93.3}, {"lowerIsBetter": true, "replicates": [90.0, 88.0, 88.0, 91.0, 90.0, 86.0, 90.0, 102.0, 122.0, 86.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 93.3}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 94.0, 96.0, 95.0, 95.0, 95.0, 143.0, 151.0, 95.0], "unit": "score", "name": "imaging-darkroom", "value": 105.5}, {"lowerIsBetter": true, "replicates": [90.0, 98.0, 91.0, 125.0, 123.0, 90.0, 123.0, 103.0, 139.0, 142.0], "unit": "score", "name": "imaging-desaturate", "value": 112.4}, {"lowerIsBetter": true, "replicates": [57.0, 62.0, 59.0, 59.0, 62.0, 61.0, 61.0, 69.0, 56.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 60.2}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 81.0, 85.0, 70.0, 69.0, 66.0, 96.0, 100.0, 98.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 79.2}, {"lowerIsBetter": true, "replicates": [83.0, 81.0, 83.0, 83.0, 81.0, 83.0, 82.0, 83.0, 82.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.5}, {"lowerIsBetter": true, "replicates": [132.0, 105.0, 134.0, 135.0, 138.0, 138.0, 143.0, 119.0, 145.0, 107.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 129.6}, {"lowerIsBetter": true, "replicates": [168.0, 137.0, 121.0, 175.0, 177.0, 127.0, 138.0, 134.0, 135.0, 134.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 144.6}, {"lowerIsBetter": true, "replicates": [65.0, 67.0, 64.0, 65.0, 68.0, 68.0, 78.0, 89.0, 87.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 71.8}], "name": "kraken", "value": 1520.2999999999997}]} 09:40:36 INFO - Return code: 0 09:40:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:40:36 ERROR - # TBPL SUCCESS # 09:40:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:40:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:40:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:40:36 INFO - 2016-04-18 09:40:36.657 screenresolution[1950:16747] starting screenresolution argv=screenresolution get 09:40:36 INFO - 2016-04-18 09:40:36.659 screenresolution[1950:16747] Display 0: 1600x1200x32@60 09:40:36 INFO - 2016-04-18 09:40:36.665 screenresolution[1951:16750] starting screenresolution argv=screenresolution list 09:40:36 INFO - Available Modes on Display 0 09:40:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:40:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:40:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:40:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:40:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:40:36 INFO - Intel Iris: 09:40:36 INFO - Chipset Model: Intel Iris 09:40:36 INFO - Type: GPU 09:40:36 INFO - Bus: Built-In 09:40:36 INFO - VRAM (Dynamic, Max): 1536 MB 09:40:36 INFO - Vendor: Intel (0x8086) 09:40:36 INFO - Device ID: 0x0a2e 09:40:36 INFO - Revision ID: 0x0009 09:40:36 INFO - Displays: 09:40:36 INFO - Display: 09:40:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:40:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:40:36 INFO - Display Serial Number: 1600x1200 60 09:40:36 INFO - Main Display: Yes 09:40:36 INFO - Mirror: Off 09:40:36 INFO - Online: Yes 09:40:36 INFO - Rotation: Supported 09:40:36 INFO - Return code: 0 09:40:36 INFO - Running post-action listener: _resource_record_post_action 09:40:36 INFO - Running post-run listener: _resource_record_post_run 09:40:37 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 913408; Write bytes: 667546112; Read time: 12; Write time: 1872 09:40:37 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 356268544; Write bytes: 354555392; Read time: 27913; Write time: 670 09:40:37 INFO - run-tests - Wall time: 1171s; CPU: 53.0%; Read bytes: 913408; Write bytes: 301812736; Read time: 12; Write time: 1166 09:40:37 INFO - Running post-run listener: _upload_blobber_files 09:40:37 INFO - Blob upload gear active. 09:40:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:40:37 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 09:40:37 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'] 09:40:37 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 09:40:38 INFO - (blobuploader) - INFO - Open directory for files ... 09:40:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:40:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:40:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:39 INFO - (blobuploader) - INFO - Done attempting. 09:40:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:40:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:40:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:40 INFO - (blobuploader) - INFO - Done attempting. 09:40:40 INFO - (blobuploader) - INFO - Iteration through files over. 09:40:40 INFO - Return code: 0 09:40:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:40:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:40:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4307083710a830bbf4070fb70857ae26bcd69ecc7dce8bd7c76224bf6f1662523a335eab86163d4dfc641e1f5cfea3f202709e547d7c2c594a5003c1557ce2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ad0acb637215b4e6383b9fd7554d71350bbf55097a958ed37da2bf248b3777a03fd8c230326b45932395ba97126a0c4d04719d691ea77a63cbed17fff354009"} 09:40:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:40:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:40:40 INFO - Contents: 09:40:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4307083710a830bbf4070fb70857ae26bcd69ecc7dce8bd7c76224bf6f1662523a335eab86163d4dfc641e1f5cfea3f202709e547d7c2c594a5003c1557ce2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ad0acb637215b4e6383b9fd7554d71350bbf55097a958ed37da2bf248b3777a03fd8c230326b45932395ba97126a0c4d04719d691ea77a63cbed17fff354009"} 09:40:40 INFO - Running post-run listener: copy_logs_to_upload_dir 09:40:40 INFO - Copying logs to upload dir... 09:40:40 INFO - mkdir: /builds/slave/test/build/upload/logs 09:40:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.434236 ========= master_lag: 4.75 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-18 09:40:44.908548) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 09:40:44.912577) ========= 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.5FLb3VRdeo/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.H5og91mo08/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4307083710a830bbf4070fb70857ae26bcd69ecc7dce8bd7c76224bf6f1662523a335eab86163d4dfc641e1f5cfea3f202709e547d7c2c594a5003c1557ce2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ad0acb637215b4e6383b9fd7554d71350bbf55097a958ed37da2bf248b3777a03fd8c230326b45932395ba97126a0c4d04719d691ea77a63cbed17fff354009"} build_url:https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011686 build_url: 'https://queue.taskcluster.net/v1/task/Fb_F4dmcR-OzfDgmZ8jeaQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4307083710a830bbf4070fb70857ae26bcd69ecc7dce8bd7c76224bf6f1662523a335eab86163d4dfc641e1f5cfea3f202709e547d7c2c594a5003c1557ce2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ad0acb637215b4e6383b9fd7554d71350bbf55097a958ed37da2bf248b3777a03fd8c230326b45932395ba97126a0c4d04719d691ea77a63cbed17fff354009"}' ========= master_lag: 0.41 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 09:40:45.335242) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 09:40:45.335547) ========= 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.5FLb3VRdeo/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.H5og91mo08/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.005315 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 09:40:45.365317) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 09:40:45.365616) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 09:40:45.366763) ========= ========= Total master_lag: 5.39 =========