builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0161 starttime: 1461701424.23 results: success (0) buildid: 20160426062558 builduid: 18458ff6a626441ea2f2e0a4fd3bd5e1 revision: cbeae0c4410bef3864b3d3f4ad939321c61cb15b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:24.235042) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:24.235496) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-04-26 13:10:24.235835) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-04-26 13:10:27.188287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:10:27.188585) ========= 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.bF6HU3wzmT/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.MGPO4Gwepn/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-26 13:10:27-- 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% 6.53M=0.002s 2016-04-26 13:10:27 (6.53 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.464217 ========= master_lag: 1.18 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:10:28.827961) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:28.828245) ========= 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.bF6HU3wzmT/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.MGPO4Gwepn/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.037493 ========= master_lag: 0.28 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:29.141183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:10:29.141724) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cbeae0c4410bef3864b3d3f4ad939321c61cb15b --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 cbeae0c4410bef3864b3d3f4ad939321c61cb15b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bF6HU3wzmT/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.MGPO4Gwepn/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-26 13:10:29,220 truncating revision to first 12 chars 2016-04-26 13:10:29,220 Setting DEBUG logging. 2016-04-26 13:10:29,220 attempt 1/10 2016-04-26 13:10:29,220 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cbeae0c4410b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 13:10:30,496 unpacking tar archive at: mozilla-aurora-cbeae0c4410b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.518006 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:10:30.788658) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:30.788939) ========= script_repo_revision: cbeae0c4410bef3864b3d3f4ad939321c61cb15b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:30.789347) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:30.789612) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:31.145904) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 23 secs) (at 2016-04-26 13:10:31.146186) ========= /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.bF6HU3wzmT/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.MGPO4Gwepn/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 13:10:31 INFO - MultiFileLogger online at 20160426 13:10:31 in /builds/slave/test 13:10:31 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 13:10:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:10:31 INFO - {'append_to_log': False, 13:10:31 INFO - 'base_work_dir': '/builds/slave/test', 13:10:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:10:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:10:31 INFO - 'branch': 'Mozilla-Aurora', 13:10:31 INFO - 'buildbot_json_path': 'buildprops.json', 13:10:31 INFO - 'config_files': ('talos/mac_config.py',), 13:10:31 INFO - 'default_actions': ('clobber', 13:10:31 INFO - 'read-buildbot-config', 13:10:31 INFO - 'download-and-extract', 13:10:31 INFO - 'populate-webroot', 13:10:31 INFO - 'create-virtualenv', 13:10:31 INFO - 'install', 13:10:31 INFO - 'run-tests'), 13:10:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:10:31 INFO - 'download_minidump_stackwalk': True, 13:10:31 INFO - 'download_symbols': 'ondemand', 13:10:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:10:31 INFO - 'tooltool.py': '/tools/tooltool.py', 13:10:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:10:31 INFO - '/tools/misc-python/virtualenv.py')}, 13:10:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:10:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:10:31 INFO - 'installer_path': 'installer.exe', 13:10:31 INFO - 'log_level': 'info', 13:10:31 INFO - 'log_name': 'talos', 13:10:31 INFO - 'log_to_console': True, 13:10:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:10:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:10:31 INFO - 'opt_config_files': (), 13:10:31 INFO - 'pip_index': False, 13:10:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:10:31 INFO - 'cmd': ('bash', 13:10:31 INFO - '-c', 13:10:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:10:31 INFO - 'enabled': True, 13:10:31 INFO - 'halt_on_failure': False, 13:10:31 INFO - 'name': 'check_screen_resolution'},), 13:10:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:10:31 INFO - 'cmd': ('bash', 13:10:31 INFO - '-c', 13:10:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:10:31 INFO - 'enabled': True, 13:10:31 INFO - 'halt_on_failure': False, 13:10:31 INFO - 'name': 'check_screen_resolution'},), 13:10:31 INFO - 'run_cmd_checks_enabled': True, 13:10:31 INFO - 'sps_profile': False, 13:10:31 INFO - 'sps_profile_interval': 0, 13:10:31 INFO - 'suite': 'dromaeojs-e10s', 13:10:31 INFO - 'system_bits': '32', 13:10:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:10:31 INFO - 'title': 't-yosemite-r7-0161', 13:10:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:10:31 INFO - 'use_talos_json': True, 13:10:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:10:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:10:31 INFO - 'work_dir': 'build'} 13:10:31 INFO - ##### 13:10:31 INFO - ##### Running clobber step. 13:10:31 INFO - ##### 13:10:31 INFO - Running pre-action listener: _resource_record_pre_action 13:10:31 INFO - Running main action method: clobber 13:10:31 INFO - rmtree: /builds/slave/test/build 13:10:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:10:32 INFO - Running post-action listener: _resource_record_post_action 13:10:32 INFO - ##### 13:10:32 INFO - ##### Running read-buildbot-config step. 13:10:32 INFO - ##### 13:10:32 INFO - Running pre-action listener: _resource_record_pre_action 13:10:32 INFO - Running main action method: read_buildbot_config 13:10:32 INFO - Using buildbot properties: 13:10:32 INFO - { 13:10:32 INFO - "project": "", 13:10:32 INFO - "product": "firefox", 13:10:32 INFO - "script_repo_revision": "production", 13:10:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 13:10:32 INFO - "repository": "", 13:10:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 13:10:32 INFO - "buildid": "20160426062558", 13:10:32 INFO - "pgo_build": "False", 13:10:32 INFO - "basedir": "/builds/slave/test", 13:10:32 INFO - "buildnumber": 265, 13:10:32 INFO - "slavename": "t-yosemite-r7-0161", 13:10:32 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:10:32 INFO - "platform": "yosemite_r7", 13:10:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:10:32 INFO - "branch": "mozilla-aurora", 13:10:32 INFO - "repo_path": "releases/mozilla-aurora", 13:10:32 INFO - "revision": "cbeae0c4410bef3864b3d3f4ad939321c61cb15b", 13:10:32 INFO - "stage_platform": "macosx64", 13:10:32 INFO - "builduid": "18458ff6a626441ea2f2e0a4fd3bd5e1", 13:10:32 INFO - "slavebuilddir": "test" 13:10:32 INFO - } 13:10:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 13:10:32 INFO - Running post-action listener: _resource_record_post_action 13:10:32 INFO - ##### 13:10:32 INFO - ##### Running download-and-extract step. 13:10:32 INFO - ##### 13:10:32 INFO - Running pre-action listener: _resource_record_pre_action 13:10:32 INFO - Running main action method: download_and_extract 13:10:32 INFO - mkdir: /builds/slave/test/build/tests 13:10:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:10:32 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:10:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:10:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:10:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:10:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 13:10:43 INFO - Downloaded 1199 bytes. 13:10:43 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:10:43 INFO - Using the following test package requirements: 13:10:43 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:10:43 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:43 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 13:10:43 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:43 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 13:10:43 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:43 INFO - u'jsshell-mac.zip'], 13:10:43 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:43 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 13:10:43 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:10:43 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:43 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 13:10:43 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:43 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 13:10:43 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:43 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 13:10:43 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:43 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 13:10:43 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:10:43 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')]} 13:10:43 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:10:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:10:44 INFO - Downloaded 18110203 bytes. 13:10:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:10:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:10:47 INFO - Return code: 0 13:10:47 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:10: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')]} 13:10:47 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:10:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:10:48 INFO - Downloaded 18110203 bytes. 13:10:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:10:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:10:52 INFO - Return code: 0 13:10:52 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')]} 13:10:52 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:10:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:10:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:10:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:10:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:12:04 INFO - Downloaded 10840145 bytes. 13:12:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:12:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:12:04 INFO - Return code: 0 13:12:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:04 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 13:12:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:12:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:12:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 13:12:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 13:12:08 INFO - Downloaded 114070774 bytes. 13:12:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:12:08 INFO - mkdir: /builds/slave/test/properties 13:12:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:12:08 INFO - Writing to file /builds/slave/test/properties/build_url 13:12:08 INFO - Contents: 13:12:08 INFO - build_url:https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:12:08 INFO - Running post-action listener: _resource_record_post_action 13:12:08 INFO - Running post-action listener: set_extra_try_arguments 13:12:08 INFO - ##### 13:12:08 INFO - ##### Running populate-webroot step. 13:12:08 INFO - ##### 13:12:08 INFO - Running pre-action listener: _resource_record_pre_action 13:12:08 INFO - Running main action method: populate_webroot 13:12:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:12:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:12:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:12:08 INFO - '--tppagecycles', 13:12:08 INFO - '7'], 13:12:08 INFO - 'tests': ['tsvgm']}}, 13:12:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:12:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:12:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:12:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:12:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:12:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:12:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:08 INFO - 'talos_options': ['--disable-e10s'], 13:12:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:12:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:12:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:08 INFO - 'talos_options': ['--disable-e10s'], 13:12:08 INFO - 'tests': ['damp', 'tps']}, 13:12:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:08 INFO - 'tests': ['damp', 'tps']}, 13:12:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:12:08 INFO - 'tests': ['dromaeo_dom']}, 13:12:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:12:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:12:08 INFO - 'tests': ['a11yr', 13:12:08 INFO - 'ts_paint', 13:12:08 INFO - 'tpaint', 13:12:09 INFO - 'sessionrestore', 13:12:09 INFO - 'sessionrestore_no_auto_restore', 13:12:09 INFO - 'tabpaint']}, 13:12:09 INFO - 'other-e10s': {'tests': ['a11yr', 13:12:09 INFO - 'ts_paint', 13:12:09 INFO - 'tpaint', 13:12:09 INFO - 'sessionrestore', 13:12:09 INFO - 'sessionrestore_no_auto_restore', 13:12:09 INFO - 'tabpaint']}, 13:12:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:12:09 INFO - 'ts_paint', 13:12:09 INFO - 'tpaint', 13:12:09 INFO - 'sessionrestore', 13:12:09 INFO - 'sessionrestore_no_auto_restore', 13:12:09 INFO - 'tabpaint']}, 13:12:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:12:09 INFO - 'ts_paint', 13:12:09 INFO - 'tpaint', 13:12:09 INFO - 'sessionrestore', 13:12:09 INFO - 'sessionrestore_no_auto_restore', 13:12:09 INFO - 'tabpaint']}, 13:12:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:12:09 INFO - 'tests': ['a11yr', 13:12:09 INFO - 'ts_paint', 13:12:09 INFO - 'tpaint', 13:12:09 INFO - 'sessionrestore', 13:12:09 INFO - 'sessionrestore_no_auto_restore', 13:12:09 INFO - 'tabpaint']}, 13:12:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:12:09 INFO - 'tests': ['a11yr', 13:12:09 INFO - 'ts_paint', 13:12:09 INFO - 'tpaint', 13:12:09 INFO - 'sessionrestore', 13:12:09 INFO - 'sessionrestore_no_auto_restore', 13:12:09 INFO - 'tabpaint']}, 13:12:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:12:09 INFO - 'tests': ['tsvgx', 13:12:09 INFO - 'tsvgr_opacity', 13:12:09 INFO - 'tart', 13:12:09 INFO - 'tscrollx', 13:12:09 INFO - 'cart']}, 13:12:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:12:09 INFO - 'tsvgr_opacity', 13:12:09 INFO - 'tart', 13:12:09 INFO - 'tscrollx', 13:12:09 INFO - 'cart']}, 13:12:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:09 INFO - 'talos_options': ['--disable-e10s'], 13:12:09 INFO - 'tests': ['tp5o']}, 13:12:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:09 INFO - 'tests': ['tp5o']}, 13:12:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:09 INFO - 'talos_options': ['--disable-e10s', 13:12:09 INFO - '--xperf_path', 13:12:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:12:09 INFO - 'tests': ['tp5n']}, 13:12:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:09 INFO - 'talos_options': ['--xperf_path', 13:12:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:12:09 INFO - 'tests': ['tp5n']}}, 13:12:09 INFO - 'talos.zip': {'path': '', 13:12:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:12:09 INFO - Running post-action listener: _resource_record_post_action 13:12:09 INFO - ##### 13:12:09 INFO - ##### Running create-virtualenv step. 13:12:09 INFO - ##### 13:12:09 INFO - Running pre-action listener: _resource_record_pre_action 13:12:09 INFO - Running main action method: create_virtualenv 13:12:09 INFO - Creating virtualenv /builds/slave/test/build/venv 13:12:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:12:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:12:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:12:09 INFO - Using real prefix '/tools/python27' 13:12:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:12:09 INFO - Installing distribute.............................................................................................................................................................................................done. 13:12:12 INFO - Installing pip.................done. 13:12:12 INFO - Return code: 0 13:12:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc13420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faa2e00>, '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 0x10f90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9db20>, '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.bF6HU3wzmT/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.MGPO4Gwepn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 13:12:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:12:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bF6HU3wzmT/Render', 13:12:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:12 INFO - 'HOME': '/Users/cltbld', 13:12:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:12 INFO - 'LOGNAME': 'cltbld', 13:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:12 INFO - 'MOZ_NO_REMOTE': '1', 13:12:12 INFO - 'NO_EM_RESTART': '1', 13:12:12 INFO - 'PAGER': '/bin/cat', 13:12:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:12 INFO - 'PWD': '/builds/slave/test', 13:12:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:12 INFO - 'SHELL': '/bin/bash', 13:12:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MGPO4Gwepn/Listeners', 13:12:12 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 13:12:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:12 INFO - 'USER': 'cltbld', 13:12:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:12 INFO - 'XPC_FLAGS': '0x0', 13:12:12 INFO - 'XPC_SERVICE_NAME': '0', 13:12:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:12:13 INFO - Cleaning up... 13:12:13 INFO - Return code: 0 13:12:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc13420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faa2e00>, '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 0x10f90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9db20>, '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.bF6HU3wzmT/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.MGPO4Gwepn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 13:12:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:12:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bF6HU3wzmT/Render', 13:12:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:13 INFO - 'HOME': '/Users/cltbld', 13:12:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:13 INFO - 'LOGNAME': 'cltbld', 13:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:13 INFO - 'MOZ_NO_REMOTE': '1', 13:12:13 INFO - 'NO_EM_RESTART': '1', 13:12:13 INFO - 'PAGER': '/bin/cat', 13:12:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:13 INFO - 'PWD': '/builds/slave/test', 13:12:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:13 INFO - 'SHELL': '/bin/bash', 13:12:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MGPO4Gwepn/Listeners', 13:12:13 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 13:12:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:13 INFO - 'USER': 'cltbld', 13:12:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:13 INFO - 'XPC_FLAGS': '0x0', 13:12:13 INFO - 'XPC_SERVICE_NAME': '0', 13:12:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:13 INFO - Downloading/unpacking psutil>=0.7.1 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:12:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:12:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:12:16 INFO - Installing collected packages: psutil 13:12:16 INFO - Running setup.py install for psutil 13:12:16 INFO - building 'psutil._psutil_osx' extension 13:12:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:12:17 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 13:12:17 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 13:12:17 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 13:12:17 INFO - building 'psutil._psutil_posix' extension 13:12:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:12:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:12:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:12:17 INFO - ^ 13:12:17 INFO - 1 warning generated. 13:12:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:12:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:12:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:12:17 INFO - Successfully installed psutil 13:12:17 INFO - Cleaning up... 13:12:17 INFO - Return code: 0 13:12:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc13420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faa2e00>, '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 0x10f90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9db20>, '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.bF6HU3wzmT/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.MGPO4Gwepn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 13:12:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 13:12:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 13:12:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bF6HU3wzmT/Render', 13:12:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:17 INFO - 'HOME': '/Users/cltbld', 13:12:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:17 INFO - 'LOGNAME': 'cltbld', 13:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:17 INFO - 'MOZ_NO_REMOTE': '1', 13:12:17 INFO - 'NO_EM_RESTART': '1', 13:12:17 INFO - 'PAGER': '/bin/cat', 13:12:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:17 INFO - 'PWD': '/builds/slave/test', 13:12:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:17 INFO - 'SHELL': '/bin/bash', 13:12:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MGPO4Gwepn/Listeners', 13:12:17 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 13:12:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:17 INFO - 'USER': 'cltbld', 13:12:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:17 INFO - 'XPC_FLAGS': '0x0', 13:12:17 INFO - 'XPC_SERVICE_NAME': '0', 13:12:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:18 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:12:18 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 13:12:18 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 13:12:18 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 13:12:18 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 13:12:18 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 13:12:18 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 13:12:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:12:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:12:20 INFO - Installing collected packages: mozsystemmonitor 13:12:20 INFO - Running setup.py install for mozsystemmonitor 13:12:20 INFO - Successfully installed mozsystemmonitor 13:12:20 INFO - Cleaning up... 13:12:20 INFO - Return code: 0 13:12:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc13420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faa2e00>, '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 0x10f90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9db20>, '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.bF6HU3wzmT/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.MGPO4Gwepn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 13:12:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:12:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:12:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bF6HU3wzmT/Render', 13:12:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:20 INFO - 'HOME': '/Users/cltbld', 13:12:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:20 INFO - 'LOGNAME': 'cltbld', 13:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:20 INFO - 'MOZ_NO_REMOTE': '1', 13:12:20 INFO - 'NO_EM_RESTART': '1', 13:12:20 INFO - 'PAGER': '/bin/cat', 13:12:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:20 INFO - 'PWD': '/builds/slave/test', 13:12:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:20 INFO - 'SHELL': '/bin/bash', 13:12:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MGPO4Gwepn/Listeners', 13:12:20 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 13:12:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:20 INFO - 'USER': 'cltbld', 13:12:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:20 INFO - 'XPC_FLAGS': '0x0', 13:12:20 INFO - 'XPC_SERVICE_NAME': '0', 13:12:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:21 INFO - Downloading/unpacking blobuploader==1.2.4 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12:23 INFO - Downloading blobuploader-1.2.4.tar.gz 13:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:12:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:12:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:24 INFO - Downloading docopt-0.6.1.tar.gz 13:12:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:12:24 INFO - Installing collected packages: blobuploader, requests, docopt 13:12:24 INFO - Running setup.py install for blobuploader 13:12:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:12:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:12:24 INFO - Running setup.py install for requests 13:12:25 INFO - Running setup.py install for docopt 13:12:25 INFO - Successfully installed blobuploader requests docopt 13:12:25 INFO - Cleaning up... 13:12:25 INFO - Return code: 0 13:12:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc13420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faa2e00>, '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 0x10f90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9db20>, '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.bF6HU3wzmT/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.MGPO4Gwepn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 13:12:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:12:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:12:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bF6HU3wzmT/Render', 13:12:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:25 INFO - 'HOME': '/Users/cltbld', 13:12:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:25 INFO - 'LOGNAME': 'cltbld', 13:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:25 INFO - 'MOZ_NO_REMOTE': '1', 13:12:25 INFO - 'NO_EM_RESTART': '1', 13:12:25 INFO - 'PAGER': '/bin/cat', 13:12:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:25 INFO - 'PWD': '/builds/slave/test', 13:12:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:25 INFO - 'SHELL': '/bin/bash', 13:12:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MGPO4Gwepn/Listeners', 13:12:25 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 13:12:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:25 INFO - 'USER': 'cltbld', 13:12:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:25 INFO - 'XPC_FLAGS': '0x0', 13:12:25 INFO - 'XPC_SERVICE_NAME': '0', 13:12:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:12:25 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-6dZUSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:12:25 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Hauoiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:12:26 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-7z184h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:12:26 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-H4MP7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:12:26 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-sQyWA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:12:26 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-9EHGNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:12:26 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-kzO3UR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:12:26 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-1cSAnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:12:26 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-aA4gS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:12:26 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-I5Jtey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:12:26 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-KCp_0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:12:26 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-cSV7UI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:12:27 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-4sJBSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:12:27 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-lfhinU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:27 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-QfwMN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:12:27 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Hkr4ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:12:27 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-G3In3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:12:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:12:27 INFO - Running setup.py install for manifestparser 13:12:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:12:27 INFO - Running setup.py install for mozcrash 13:12:27 INFO - Running setup.py install for mozdebug 13:12:28 INFO - Running setup.py install for mozdevice 13:12:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:12:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:12:28 INFO - Running setup.py install for mozfile 13:12:28 INFO - Running setup.py install for mozhttpd 13:12:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:12:28 INFO - Running setup.py install for mozinfo 13:12:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:12:28 INFO - Running setup.py install for mozInstall 13:12:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:12:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:12:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:12:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:12:28 INFO - Running setup.py install for mozleak 13:12:28 INFO - Running setup.py install for mozlog 13:12:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Running setup.py install for moznetwork 13:12:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Running setup.py install for mozprocess 13:12:29 INFO - Running setup.py install for mozprofile 13:12:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Running setup.py install for mozrunner 13:12:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Running setup.py install for mozscreenshot 13:12:29 INFO - Running setup.py install for moztest 13:12:30 INFO - Running setup.py install for mozversion 13:12:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:12:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:12:30 INFO - Cleaning up... 13:12:30 INFO - Return code: 0 13:12:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:12:30 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')]} 13:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:30 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')]} 13:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:30 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 0x10fc03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc13420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faa2e00>, '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 0x10f90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9db20>, '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.bF6HU3wzmT/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.MGPO4Gwepn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 13:12:30 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 13:12:30 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 13:12:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bF6HU3wzmT/Render', 13:12:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:30 INFO - 'HOME': '/Users/cltbld', 13:12:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:30 INFO - 'LOGNAME': 'cltbld', 13:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:30 INFO - 'MOZ_NO_REMOTE': '1', 13:12:30 INFO - 'NO_EM_RESTART': '1', 13:12:30 INFO - 'PAGER': '/bin/cat', 13:12:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:30 INFO - 'PWD': '/builds/slave/test', 13:12:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:30 INFO - 'SHELL': '/bin/bash', 13:12:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MGPO4Gwepn/Listeners', 13:12:30 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 13:12:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:30 INFO - 'USER': 'cltbld', 13:12:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:30 INFO - 'XPC_FLAGS': '0x0', 13:12:30 INFO - 'XPC_SERVICE_NAME': '0', 13:12:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:12:30 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-SgSUq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:12:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:12:30 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-zwdF7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:12:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:12:30 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-cAenCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:12:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:12:30 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Vxvckk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:12:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:12:30 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-4wm1VC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:12:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:12:30 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-2kE58U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:12:31 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-4vuw2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:12:31 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-B9FrOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:12:31 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-8XMNDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:12:31 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-lauoOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:12:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:12:31 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-x7zwRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:12:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:12:31 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-VJSL4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:12:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:12:31 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-4OUjP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:12:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:12:31 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-90KE0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:12:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:31 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-aQha0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:32 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)) 13:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:12:32 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-e2dLxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:12:32 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)) 13:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:12:32 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-go6VY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:12:32 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)) 13:12:32 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)) 13:12:32 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)) 13:12:32 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)) 13:12:32 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)) 13:12:32 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)) 13:12:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12:34 INFO - Downloading blessings-1.6.tar.gz 13:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:12:34 INFO - Installing collected packages: blessings 13:12:34 INFO - Running setup.py install for blessings 13:12:34 INFO - Successfully installed blessings 13:12:34 INFO - Cleaning up... 13:12:35 INFO - Return code: 0 13:12:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:12:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:12:35 INFO - Reading from file tmpfile_stdout 13:12:35 INFO - Current package versions: 13:12:35 INFO - blessings == 1.6 13:12:35 INFO - blobuploader == 1.2.4 13:12:35 INFO - docopt == 0.6.1 13:12:35 INFO - manifestparser == 1.1 13:12:35 INFO - mozInstall == 1.12 13:12:35 INFO - mozcrash == 0.17 13:12:35 INFO - mozdebug == 0.1 13:12:35 INFO - mozdevice == 0.48 13:12:35 INFO - mozfile == 1.2 13:12:35 INFO - mozhttpd == 0.7 13:12:35 INFO - mozinfo == 0.9 13:12:35 INFO - mozleak == 0.1 13:12:35 INFO - mozlog == 3.1 13:12:35 INFO - moznetwork == 0.27 13:12:35 INFO - mozprocess == 0.22 13:12:35 INFO - mozprofile == 0.28 13:12:35 INFO - mozrunner == 6.11 13:12:35 INFO - mozscreenshot == 0.1 13:12:35 INFO - mozsystemmonitor == 0.0 13:12:35 INFO - moztest == 0.7 13:12:35 INFO - mozversion == 1.4 13:12:35 INFO - psutil == 3.1.1 13:12:35 INFO - requests == 1.2.3 13:12:35 INFO - wsgiref == 0.1.2 13:12:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:35 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 0x10fc03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc13420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faa2e00>, '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 0x10f90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9db20>, '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.bF6HU3wzmT/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.MGPO4Gwepn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 13:12:35 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 13:12:35 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 13:12:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bF6HU3wzmT/Render', 13:12:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:35 INFO - 'HOME': '/Users/cltbld', 13:12:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:35 INFO - 'LOGNAME': 'cltbld', 13:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:35 INFO - 'MOZ_NO_REMOTE': '1', 13:12:35 INFO - 'NO_EM_RESTART': '1', 13:12:35 INFO - 'PAGER': '/bin/cat', 13:12:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:35 INFO - 'PWD': '/builds/slave/test', 13:12:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:35 INFO - 'SHELL': '/bin/bash', 13:12:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MGPO4Gwepn/Listeners', 13:12:35 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 13:12:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:35 INFO - 'USER': 'cltbld', 13:12:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:35 INFO - 'XPC_FLAGS': '0x0', 13:12:35 INFO - 'XPC_SERVICE_NAME': '0', 13:12:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:12:35 INFO - Cleaning up... 13:12:35 INFO - Return code: 0 13:12:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc13420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faa2e00>, '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 0x10f90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9db20>, '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.bF6HU3wzmT/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.MGPO4Gwepn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 13:12:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:12:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bF6HU3wzmT/Render', 13:12:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:35 INFO - 'HOME': '/Users/cltbld', 13:12:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:35 INFO - 'LOGNAME': 'cltbld', 13:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:35 INFO - 'MOZ_NO_REMOTE': '1', 13:12:35 INFO - 'NO_EM_RESTART': '1', 13:12:35 INFO - 'PAGER': '/bin/cat', 13:12:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:35 INFO - 'PWD': '/builds/slave/test', 13:12:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:35 INFO - 'SHELL': '/bin/bash', 13:12:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MGPO4Gwepn/Listeners', 13:12:35 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 13:12:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:35 INFO - 'USER': 'cltbld', 13:12:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:35 INFO - 'XPC_FLAGS': '0x0', 13:12:35 INFO - 'XPC_SERVICE_NAME': '0', 13:12:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:35 INFO - Downloading/unpacking jsonschema 13:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:12:38 INFO - Downloading/unpacking functools32 (from jsonschema) 13:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:38 INFO - Downloading functools32-3.2.3-2.tar.gz 13:12:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:12:38 INFO - warning: no files found matching '*.txt' 13:12:38 INFO - no previously-included directories found matching 'build' 13:12:38 INFO - no previously-included directories found matching 'dist' 13:12:38 INFO - no previously-included directories found matching '.git*' 13:12:38 INFO - Installing collected packages: jsonschema, functools32 13:12:38 INFO - Running setup.py install for functools32 13:12:38 INFO - warning: no files found matching '*.txt' 13:12:38 INFO - no previously-included directories found matching 'build' 13:12:38 INFO - no previously-included directories found matching 'dist' 13:12:38 INFO - no previously-included directories found matching '.git*' 13:12:38 INFO - Successfully installed jsonschema functools32 13:12:38 INFO - Cleaning up... 13:12:39 INFO - Return code: 0 13:12:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:12: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')]} 13:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12: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')]} 13:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:39 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 0x10fc03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc13420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f0d90270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10faa2e00>, '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 0x10f90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9db20>, '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.bF6HU3wzmT/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.MGPO4Gwepn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 13:12:39 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 13:12:39 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 13:12:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bF6HU3wzmT/Render', 13:12:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:39 INFO - 'HOME': '/Users/cltbld', 13:12:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:39 INFO - 'LOGNAME': 'cltbld', 13:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:39 INFO - 'MOZ_NO_REMOTE': '1', 13:12:39 INFO - 'NO_EM_RESTART': '1', 13:12:39 INFO - 'PAGER': '/bin/cat', 13:12:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:39 INFO - 'PWD': '/builds/slave/test', 13:12:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:39 INFO - 'SHELL': '/bin/bash', 13:12:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MGPO4Gwepn/Listeners', 13:12:39 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 13:12:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:39 INFO - 'USER': 'cltbld', 13:12:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:39 INFO - 'XPC_FLAGS': '0x0', 13:12:39 INFO - 'XPC_SERVICE_NAME': '0', 13:12:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:39 INFO - Downloading/unpacking flake8 13:12:39 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 13:12:39 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 13:12:39 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 13:12:39 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 13:12:39 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 13:12:39 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 13:12:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:12:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:42 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:12:42 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:12:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:12:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:12:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:12:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:12:43 INFO - no previously-included directories found matching 'docs/_build' 13:12:43 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:12:43 INFO - Running setup.py install for pep8 13:12:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:12:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:12:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:12:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:12:43 INFO - no previously-included directories found matching 'docs/_build' 13:12:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:12:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:12:43 INFO - Cleaning up... 13:12:43 INFO - Return code: 0 13:12:43 INFO - Running post-action listener: _resource_record_post_action 13:12:43 INFO - Running post-action listener: _start_resource_monitoring 13:12:43 INFO - Starting resource monitoring. 13:12:43 INFO - ##### 13:12:43 INFO - ##### Running install step. 13:12:43 INFO - ##### 13:12:43 INFO - Running pre-action listener: _resource_record_pre_action 13:12:43 INFO - Running main action method: install 13:12:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:12:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:12:43 INFO - Reading from file tmpfile_stdout 13:12:43 INFO - Detecting whether we're running mozinstall >=1.0... 13:12:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:12:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:12:43 INFO - Reading from file tmpfile_stdout 13:12:43 INFO - Output received: 13:12:43 INFO - Usage: mozinstall [options] installer 13:12:43 INFO - Options: 13:12:43 INFO - -h, --help show this help message and exit 13:12:43 INFO - -d DEST, --destination=DEST 13:12:43 INFO - Directory to install application into. [default: 13:12:43 INFO - "/builds/slave/test"] 13:12:43 INFO - --app=APP Application being installed. [default: firefox] 13:12:43 INFO - mkdir: /builds/slave/test/build/application 13:12:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:12:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:13:15 INFO - Reading from file tmpfile_stdout 13:13:15 INFO - Output received: 13:13:15 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:13:15 INFO - Running post-action listener: _resource_record_post_action 13:13:15 INFO - ##### 13:13:15 INFO - ##### Running run-tests step. 13:13:15 INFO - ##### 13:13:15 INFO - Running pre-action listener: _resource_record_pre_action 13:13:15 INFO - Running main action method: run_tests 13:13:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:13:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:13:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:13:15 INFO - 2016-04-26 13:13:15.857 screenresolution[1641:8358] starting screenresolution argv=screenresolution get 13:13:15 INFO - 2016-04-26 13:13:15.859 screenresolution[1641:8358] Display 0: 1600x1200x32@60 13:13:15 INFO - 2016-04-26 13:13:15.865 screenresolution[1642:8360] starting screenresolution argv=screenresolution list 13:13:15 INFO - Available Modes on Display 0 13:13:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:13:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:13:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:13:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:13:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:13:16 INFO - Intel Iris: 13:13:16 INFO - Chipset Model: Intel Iris 13:13:16 INFO - Type: GPU 13:13:16 INFO - Bus: Built-In 13:13:16 INFO - VRAM (Dynamic, Max): 1536 MB 13:13:16 INFO - Vendor: Intel (0x8086) 13:13:16 INFO - Device ID: 0x0a2e 13:13:16 INFO - Revision ID: 0x0009 13:13:16 INFO - Displays: 13:13:16 INFO - Display: 13:13:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:13:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:13:16 INFO - Display Serial Number: 1600x1200 60 13:13:16 INFO - Main Display: Yes 13:13:16 INFO - Mirror: Off 13:13:16 INFO - Online: Yes 13:13:16 INFO - Rotation: Supported 13:13:16 INFO - Return code: 0 13:13:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:13:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:13:16 INFO - Python 2.7.3 13:13:16 INFO - Return code: 0 13:13:16 INFO - grabbing minidump binary from tooltool 13:13:16 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')]} 13:13:16 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 0x10faa2e00>, '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 0x10f90bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:13:16 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 13:13:16 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 13:13:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:13:16 INFO - Return code: 0 13:13:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:13:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:13:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:13:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:13:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:13:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:13:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:13:17 INFO - Return code: 0 13:13:17 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/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0161', '--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 13:13:17 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/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0161 --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 13:13:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bF6HU3wzmT/Render', 13:13:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:13:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:13:17 INFO - 'HOME': '/Users/cltbld', 13:13:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:13:17 INFO - 'LOGNAME': 'cltbld', 13:13:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:13:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:13:17 INFO - 'MOZ_NO_REMOTE': '1', 13:13:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:13:17 INFO - 'NO_EM_RESTART': '1', 13:13:17 INFO - 'PAGER': '/bin/cat', 13:13:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:13:17 INFO - 'PWD': '/builds/slave/test', 13:13:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:13:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:13:17 INFO - 'SHELL': '/bin/bash', 13:13:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MGPO4Gwepn/Listeners', 13:13:17 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 13:13:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:13:17 INFO - 'USER': 'cltbld', 13:13:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:13:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:13:17 INFO - 'XPC_FLAGS': '0x0', 13:13:17 INFO - 'XPC_SERVICE_NAME': '0', 13:13:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:13:17 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/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0161', '--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 13:13:17 INFO - mozversion application_buildid: 20160426062558 13:13:17 INFO - mozversion application_changeset: cbeae0c4410bef3864b3d3f4ad939321c61cb15b 13:13:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:13:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:13:17 INFO - mozversion application_name: Firefox 13:13:17 INFO - mozversion application_remotingname: firefox-dev 13:13:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:13:17 INFO - mozversion application_vendor: Mozilla 13:13:17 INFO - mozversion application_version: 48.0a2 13:13:17 INFO - mozversion platform_buildid: 20160426062558 13:13:17 INFO - mozversion platform_changeset: cbeae0c4410bef3864b3d3f4ad939321c61cb15b 13:13:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:13:17 INFO - mozversion platform_version: 48.0a2 13:13:17 INFO - using testdate: 1461701597 13:13:17 INFO - actual date: 1461701597 13:13:17 INFO - starting webserver on 'localhost:49275' 13:13:17 INFO - SUITE-START | Running 2 tests 13:13:17 INFO - TEST-START | dromaeo_css 13:13:17 INFO - operating with platform_type : mac_ 13:13:17 INFO - Initialising browser for dromaeo_css test... 13:13:17 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpcvDFUd/profile http://localhost:49275/getInfo.html) 13:13:20 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 13:13:20 INFO - PROCESS | 1660 | colorDepth:24 13:13:20 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 13:13:20 INFO - PROCESS | 1660 | __metrics 13:13:20 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 13:13:21 INFO - TEST-INFO | 1660: exit 0 13:13:21 INFO - Browser initialized. 13:13:21 INFO - Running cycle 1/1 for dromaeo_css test... 13:13:21 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpcvDFUd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:13:33 INFO - PROCESS | 1664 | RSS: Main: 221704192 13:13:33 INFO - PROCESS | 1664 | 13:14:22 INFO - PROCESS | 1664 | 1461701662875 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] 13:16:34 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 13:16:36 INFO - PROCESS | 1664 | RSS: Main: 231927808 13:16:36 INFO - PROCESS | 1664 | 13:19:38 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 13:19:38 INFO - PROCESS | 1664 | RSS: Main: 244244480 13:19:38 INFO - PROCESS | 1664 | 13:22:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 13:22:41 INFO - PROCESS | 1664 | RSS: Main: 245665792 13:22:41 INFO - PROCESS | 1664 | 13:25:42 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 13:25:43 INFO - PROCESS | 1664 | RSS: Main: 247947264 13:25:43 INFO - PROCESS | 1664 | 13:28:46 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 13:28:46 INFO - PROCESS | 1664 | RSS: Main: 250191872 13:28:46 INFO - PROCESS | 1664 | 13:31:48 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 13:31:49 INFO - PROCESS | 1664 | RSS: Main: 251179008 13:31:49 INFO - PROCESS | 1664 | 13:31:49 INFO - PROCESS | 1664 | __start_tp_report 13:31:49 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 13:31:49 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 13:31:49 INFO - PROCESS | 1664 | |i|pagename|runs| 13:31:49 INFO - PROCESS | 1664 | |0;dojo.html;3474.982625086874;3481.4777783332497;3494.2662040971395;3504.3516949364353;3547.113221694576;5440.047991601474;5809.796657117002;5853.531717462603;5872.73572689229;5880.764769409224;5869.941300586993;5870.442307980746;5884.705764711758;5887.735051922663;5915.615484993483;5216.24364467153;5295.4969277918535;5321.015612111772;5321.73391330432;5352.598555108363;5167.431582525919;5333.57331413486;5347.144456886879;5348.812791552296;5365.490278423563;5289.656172348804;5322.0686379883555;5322.787088516455;5323.893522129555;5333.146696528556;5273;5292.073887069767;5298.841034768963;5316.787328506855;5322.600804939606;5156.04613146569;5248.921266181011;5282.418933917266;5396.055690254189;5397.298351214317;5911.438413350725;5926.496247818941;5930.851728706773;5942.643441393487;5947.762089516415;6205.503559715212;6207.099970504251;6223.906641400383;6232.9376706232815;6242.313345531988;6186.133941248228;6217.036359364306;6247.437730604267;6298.527610429236;6299.811005669837;5953.238750714322;5987;6020.879582408327;6046.123312119806;6083.6349819011;6442.226932768098;6458.935410645833;6480.2547707013355;6491.480681545465;6507.804765857032;6081.817545473643;6138.664296166039;6150.477209437248;6195.659238741912;6202.81391558254;6303.369663033752;6313.05304204373;6338.112664227012;6342.238931328178;6344.746210151635;6145.600535965148;6250.593711408744;6262.029385445263;6277.466415354655;6286.591371560834;6166.321704612489;6171.691415429301;6206.0690896365;6219.315875253718;6250.3749625037135;5029.723365214948;5030.0945829751;5034.748262586856;5037.395512538519;5037.974810125926;4336.241157797373;4426.070525189746;4515.074409745994;4519.887002824956;4524.117797029548;5979.790707325223;5984.072468767348;5990.161377407167;6015.398460154037;6025.548083893726;5430.456954304538;5539.667619942736;6133.44798968095;6357.586756860882;6379.489640828722;5766.106253530709;5883.676397798162;5928.466438020512;5957.6127551709;5976.5218782497295;4606.493285738566;5511.366192887789;5532.5020748132865;5540.252065971123;5544.69504177266;6535.444487218545;6550.443212327005;6557.573757705733;6559.147310849559;6581.2102547694585;5451.509364157245;6155.261368635793;6286.40279173496;6288.685565721604;6316.210473690789;5422.403535611158;6464.0627109069865;6498.9329663535445;6535.379138981789;6563.442107420923;6228.4705800007505;6240.719167637558;6242.719077641426;6262.34245404226;6272.121902933684;5218.269442278008;6157.1072194532435;6269;6319.399657032574;6323.399277068671;6149.661768602769;6150.7847225346895;6175.752969881334;6196.411340922605;6200.627962322275;6021.87956240864;6150.354212807591;6155.1075094112;6208.720607572761;6209.913265178486;5806.071028635398;5891.941080589139;5918.881622367615;5958.851028724143;5993.220881285405;6193.38066193377;6214.1921550198185;6222.222222222223;6243;6267.4986001120715;5963.880722385615;6087.421694938974;6130.50955923534;6141.508679305734;6152.630842149485 13:31:49 INFO - PROCESS | 1664 | |1;ext.html;522.9058769421505;531.5163201486647;568.8963410982695;569.2969183058922;623.9407256310649;34913.650863491355;40549.18901621973;40554.594454055485;40582.59417405825;40688;41321.586784132225;41893.79053104738;41912.37131443031;41913;42039.94900127498;8432.53621050845;9385.57764900581;9517.428954262732;9605.18355939746;9666.806663866719;9609.759756006086;9747.707568772948;9773.951130244339;9816.754581135458;9846.261530385249;9742.610295588203;9743.317967742261;9781.804363912717;9786.217102631772;9812.165965892907;9734.51327433629;9778.608855645802;9778.657746978894;9796.95101524495;9810.46042467664;9549.713508594754;9600.087991640783;9612.615495380176;9674.419534827934;9684.03159684026;39959;39988.00029999273;40297.59702402968;40500.18999619991;40611;3183.6179658441133;3216.308493673848;3230.91922701932;3369.241920567877;3696.1868388954385;28830.855845720846;28864.42271154586;29177.70822291771;29737.405251895056;29747.553786693217;2771.473420050184;2790.9162725118076;2820.49231138395;2858.9285267868468;2865.8137221080565;463873;470825;473368.63315683545;474827;475818;34187.487187692204;66804;67413.3258667407;67424;67425;35867.28265434677;35969.820150899075;36141.819290903906;36287.4556881647;36347.72782952584;15654;16059.839401605834;16077.839221607868;16108.19459027044;16114.597135071483;10941.070009049161;13197.73604527904;13390.263535505446;13464.663383415493;13504.054716169774;7969.840603187904;8201.589920503951;8314.501129932223;8330.708425205088;8350.164983501601;3375.7130643895052;3420.1791570023015;3420.8973730788116;3424.5719285089363;3426.5202871597994;962.6582563190107;980.632262901412;1014.7716763728102;1098.2257508456519;1105.9723506912392;30806.92175773837;30976.225594359872;30993.007270258386;31154;31467.685323146932;1005.1657124586576;1041.9528373984097;1075.2795626929976;1095.4358505369742;1160.5879912631062;15810.367585296459;16040.67918641644;16119;16317.836821631632;16349.264283107288;654400;672861;679615;680561;681922;61820;62421;62423.687881560305;62424.37575624276;62556;1487.6355292953297;1501.129344979909;1525.5270866031565;1543.1975372806078;1619.1256721370328;30138.09585712432;30484.628191730983;30607.54088688627;30622.928197513873;30736.69263307428;55121.44878551324;55206;55358.61603459946;55398.89202216014;55632.887342253736;521410;522284;522509;523689.38155308977;524548;25780.742192577833;26842.597361039992;26938.59592106159;27016.32459188536;27382.726172738814;46862;47258.76370618125;48470.54588362355;48587.75706121447;48827;37592.496300148785;37621.05947351338;39019;39448;40209;38172;38500.844974650805;38984.220315594095;39052.2189556213;39533.80233098816;36985.260294793414;38233.617663824116;38930;39209.803950980066;39371.212575747755;32772.67227327696;33680.83159584186;33841;33909.66090339164;34180.658193417745;25388.984440622167;25612.871935640203;25727.871360643076;25729.099481518057;25971 13:31:49 INFO - PROCESS | 1664 | |2;jquery.html;399.4088748651995;403.4593644516348;404.9655779258763;406.35998302673295;410.2267226278466;13727.931360343184;18213.81786182138;20607.175712971482;20737.65205261657;21337.786622133775;20231.49421264469;20701.68947465789;21443.463913402134;21459;21558.568828623418;5697.344805347397;6179.443850053494;6182.474489668383;6183.6598987055695;6241.344658810827;5323.946760532383;5666.801661941833;6057.091436284549;6065.878682426349;6120.63276203427;5134.64057515974;5925.318588362329;5939.198208241897;6018.518518518508;6056.485198758089;5763.366029736726;6063.878722425548;6181.134641150243;6203.658798766066;6210.1616281801835;5846.473817356458;5907.350191478977;5938.079597662326;5965.20463605568;6018.6990650467305;25728.74271257282;26248.343791405176;26343.604845927326;26830;26848.59727104095;1951.9024048797503;2045.3454894433787;2099.1078791513546;2223.4774827915426;2267.671187677794;8235.711750088778;9940.055694708992;10061.949690251502;10077.848832267522;10235.385876847338;1660.96678066438;1672.503109716809;1687.9409220677217;1753.1409609291484;1768.4252617899235;85952;132573;134264;136854.63145368517;136904;11714.648560543026;11888.465019074163;11924.10569207313;11933.105017123577;11954.163208575315;15344.693106137815;15621.297041633154;15662.843371566367;15689.607759806097;15700;7512.887306690404;8976.551172441352;9023.909760902437;9124.406913550598;9198.724038278862;7271.381932535688;8960;9058.54707264634;9096.272298216127;9109.498977556166;4650.348951146842;4685.297205419146;4737.099951009297;4739.786709598076;4802.783874725646;1955.257002339102;2009.2867032203358;2022.848286378498;2049.6720524715975;2055.1676570988775;808.7816289601775;816.7059858450953;824.2746383182881;877.6270085213758;890.5235698901057;16987.01891359658;20356.898215508827;21246.681299780517;21288;21406.678899816514;863.5250612163285;896.8296023755596;898.3531857062904;915.8992510823804;958.3531116496337;8873.73378798637;11446.313221206754;11497.655070347735;11501.482433290523;11503.424828758696;558113;617244;630864;631122;631971;130022.34988824996;130561;131628.68371316357;131856.68143318637;136184;1000.9040101089296;1010.5907107621425;1019.2304809868501;1022.1771473963531;1049.061090324171;18469.076546172368;18610.534736631693;18941;19107;19345.61308773845;50634;51876.481235187166;55643.721781390836;56523.43476565346;56596.15105773335;56596.71701641465;71513.6424317875;76519.61740191263;78963.81554276576;81277;8248.13394593559;8319.667213311643;8349.874751878848;8392.916070839214;8537.402381833212;11642.825357619811;12367.319797411228;12513.93743031279;12562.246265224116;12575.308358040396;10612.787744245226;10826.56693732242;11077.335359878432;11186.32882027081;11333.716657083309;8568.57157142153;9378.5779639915;10004.049615286536;10759.731006724895;11127.22109452363;11029.779404412027;11168.162387820783;11181.496832642713;11391.772164556829;11560.826587601025;8152.755417337489;8165.755027349189;8185.935828342277;8331.375146864131;8523.91476085256;8957.372983891066;10634.946825266134;11075.889241107487;11122.44387780624;11358.886411135783 13:31:49 INFO - PROCESS | 1664 | |3;mootools.html;1583.7149313123637;1606.6786642671466;1848.9260429582816;1933.2460340467212;1952.0825212150285;8194.344452443805;8684.522351270678;8755.737327880157;9078.50068246247;9106;8708.172723591264;8744.344174186945;8800.16398442147;8865.556722163898;8926.41978271412;2429.344077099182;2445.449773800894;2463.2487091437106;2468.0991438125043;2478.5662509060844;2132.4242454537266;2457.2382561405934;2467.9136230231948;2490.6139548370033;2506.410993416546;2035.7251771010872;2452.3378687754293;2494.1769216158623;2498.7876030537427;2505.473850491399;2447.8286519943613;2453.116877923946;2466.334089795754;2467.4571594249237;2481.528509583173;2446.156076153742;2455.5457240410583;2473.4558397152596;2474.851508909453;2482.3173627252468;8970.82058358829;8975.506347150904;8982.820343593092;9101.271898248124;9112;5377;5571.804986825442;5582.441755824426;5629.549636029108;5648.181013753024;3598.5501812273465;3648.087978005499;3704.5554533456157;3739.046543131484;3766.303233901737;1051.3901936876593;1056.4084112896803;1058.7564860082148;1063.5479921033607;1087.2009073331096;11887.94056029723;11917.761644767143;11989.880101198964;12071.155019148664;12094.971927386096;4899.363082799285;4911;4984.925226121611;5001.574866136347;5018.272350509155;10219;10248.23138264633;10294.661693979884;10303.690889273334;10317.638882639072;5478.561715062785;5485.341758988867;5545.833624991256;5547.7780888764;5579.860503487364;4501.257292546758;4893.7063776173545;4991.500849915052;5043.52086551777;5048.217526283432;3513.5432393789156;3572.8570857165423;3614.674679278825;4165.75005499671;4173.97913010433;1368.0560413314781;1373.979390309146;1382.0878220374505;1382.1154461144874;1392.1647011793045;918.4856480371013;918.9678361257323;934.9859752103724;942.4015749998663;945.5697657565792;5872;5880.529557635465;5897.882042359214;6036.758529658852;6148.8770224595255;454.4887002122612;464.20852446578505;473.39405560882125;475.2657144711429;1009.6974902375999;3907.257621051991;3942.6254505822476;3980.920381592352;3984.203159368138;4033.3344998075577;10525.9473702631;10650.147988160928;11056.778864422828;11070.059044981106;11111.83332250017;10704.571817127227;10731.731706707395;10925.344479331265;10948.616798412017;10968.725781855517;7865.488743231672;8100.959495202486;8303.04514980773;8335.791605209797;8355.582220888931;4719.834805781781;5901.232839730807;6025.6761809796835;6127.938720612737;6160.414760597736;9543.093406126407;9760.658376956773;10059.49702514886;10112.898871011195;10120.898791011996;10172.491375431347;10192.541335639766;10294.124999375137;10301.484925753832;10399.94800025995;7674.884876726966;7751.6511756972195;7761.068671759425;7800.102988156435;7871.645775940211;8362.289205417608;8515.19105684959;8530.189631984951;8567.100454452193;8594.82810343777;6500.284968653539;7547.16981132064;7641.235876412315;7674.079110506775;7696;6848.041274221513;6899.20659124187;7444.994925684963;7445.851082978418;7627.084749830056;7153.785386438415;7226.313500217471;7238.167610724834;7242.782716518513;7246.782596522113;4855.150348689036;4899.534544218293;5044.293798868235;5064.18972964324;5073.923891141562;4949.183384741477;4951.876203094951;4988.825391111293;5015.22264049073;5179 13:31:49 INFO - PROCESS | 1664 | |4;prototype.html;306.90179142674356;307.2671678047855;307.6446704056812;308.487910069285;315.48260852202384;5511.393746687866;5552.41699621539;5561.555075593953;5605.299337582802;5607.551395888329;4294.368140106755;5442.102053161224;5516.9448305516935;5545.306836645419;5556.9444305556935;3728.049347416405;3975.3838155085937;4111.465509483778;4211.010412553048;4220.7256528325715;3290.243244053869;4010.5588385277742;4078.8572399966006;4169.854055108071;4242;3860.8455661773637;4201.957980420188;4230.725002874803;4231.407602935591;4243;3878.9806050969846;4056.2090392373498;4124.118978657795;4190.91618167638;4212.262854000568;3204.198950262434;3490.6509349065145;3634.6365363463706;3673;3683;4572.993941332901;4756.096341695067;5346.572274218053;5366.6779993200535;5383.300170977787;3675.1730860556427;4657.115148121846;4828.975855120702;4864.343313652647;4894.779734911937;4746.742113339968;5347.572194224492;5468.945310546884;5746.195532625436;5873.913326034654;3700.481932529448;4051.9392209116886;4064.857729979466;4073.6333729964445;4073.7555746655294;5879.970600147015;6084.269887613515;6130.816075517741;6145.661988590626;6219.937800621981;4112.444819949328;4116.567760385176;4131.008557946131;4137.648299894482;4240.5123410808155;3662.377395842713;4181.393697913846;4198.6011328923705;4217.785662527198;4237.724547904377;4085.912497266784;4849.75751212445;4854;4860.513948605182;4864.610831133501;5334.093204155303;5585.972070139624;5765.279340082489;5871.911921321183;5893.44012318829;1446.5081872163514;1460.3136525832842;1508.1629695519093;1520.125927591617;1523.1394262241797;880.6389380354024;926.7312479381039;969.1568335548118;980.7646164920368;1014.0721240065258;4994.275830004629;5634.3802181759975;5924.992751232301;5926.614770039933;5995.890760209375;6470.54706170573;6501.252355979028;6512.739490420425;6549.299645700165;6552.180977377828;4773.451053128934;5288.338957630296;5664.915026274609;5699.153071676602;5960.5827592068135;2915.99398207618;3062.8162310261455;3464.705500032475;3486.3550243205086;3503.737219708532;6601.504887133484;6854.143232095988;6879.690413931385;6901.896471552931;6907.758228461979;6741.898871516931;6750.189977202669;6786.8303292417095;6801.285864984205;6834.760783372558;5530.474604912527;6561.835954101186;6897.448204143657;6987.336203060701;6998.545094568837;6202.883480973468;6394.200724909387;6514.892468280498;6569.277379488348;6630.635315057525;5732.828015159552;6256.468200203034;6508.015055408146;6514.51141164424;6525.412712855866;6209.161763162096;6566.868662626625;6778;6826.249112597709;6929.203141638776;3520.4895290182267;3836.808159592065;4041.91916161681;4137.77242251679;4224.091820258659;6073.696315184311;6345.587536810092;6600.801975940537;6618.834529136618;6661.635873883658;5564.415736347787;5961.910571341346;6004.078694817791;6246.6876656167915;6400.935990640221;6109.450149486567;6353.110564520878;6374.29882712892;6395.392437718409;6449.7420103195345;5476.945230547643;5657.603967722384;5738.770449182153;6174.506039516738;6207.689615519296;4891.828785992473;5556.999740046669;5836.795712150054;5961.344252132349;5972.671503067373;4506.053728717006;4837.274408838703;5094.847154585368;5256.896051829159;5295.417504074626 13:31:49 INFO - PROCESS | 1664 | |5;yui.html;660.3792435110995;689.5138927056425;692.3111504053468;739.3641468337232;760.8250102476434;13172.143810652313;14397.92801035996;14740.484083057081;14937.551873443814;14991.400343986228;13771.93114034431;13825.654358641039;13840.9619278554;13879.791803122962;13943.442262309522;726.6221564786308;735.8307589254474;913.4154141349525;923.6767131503954;923.8013827027188;908.0873721909504;918.9172974432299;919.6321471411455;922.7462447826867;923.4367036107968;980.0493521284343;980.2011360741009;981.7889153831925;982.4989255479685;983.306768728045;938.6714649872558;939.4363381970832;952.4333021851987;962.2927148545813;965.7971825916566;696.4985210648325;697.6407150317591;697.8848489999144;712.1098626716604;721.0050130819436;13011.804822927663;14109.78835317471;14590.270486475634;14808.963372563925;14821.407143714347;12619.74760504794;12744.87255127446;12822.679433014157;12869.09916305859;13114;9410.776599042149;9609.228035518427;10084.899151008469;10215.438150901697;10304.278700490968;3032.348045170299;3430.7598468107244;3449.6032956210106;3466.60134084581;3552.147484603702;77463.6126819368;81274.59362703208;81790.18209817885;84800;84881;9577.760555986157;9663.178629816544;9730.41617502952;9778.804423911624;9816.803663926681;19076.61846763057;19598.70601940972;21293.574128517343;21325.573488530452;21338.35984920455;7454.552726836408;7460.328570428687;7460.626968651546;7562.243775622504;7580.279873412017;3993.1614360983936;4037.4549435826375;4104.671626269951;4115.1975364803575;4177.352510360899;3642.6721595056574;3713.870014549532;3720.330340538675;3722.4230244312175;3739.2521495700967;652.9085927970089;660.7390080918107;664.837114906851;669.5514005616249;675.6520391998125;502.36952624456075;594.5095296380504;606.7451670298396;610.2372034956304;613.2120225510198;7347.669354879044;7672.884906726404;7693.346065584376;7697.653605587762;7770.8057298566855;508.3493128795148;518.1010946008672;547.2693953572026;614.0942110387143;625.893598088326;3824.29250588642;3827.3876179811095;3855.9421608675893;3902.863399780994;4120.381942708557;91390.54304728567;92638;93081;93898.0610193903;96203.03796962081;81467.59266203632;83392.58303708442;83645.58177209075;83777.58111209527;84356;72267.6386618074;72441;72474.63762681153;72714.63642681752;72750.63624681949;7736.110347310131;7903.091144518454;7926.286634202949;7942.880856787037;8084.9595752023215;46279;46318.53681463142;46457.76771116123;46917.765411172724;47985.80035499141;65688.67155664382;67887.66056169687;68312.29219269558;68615.65692171507;68622;9819.803603928025;10159;10159.136473399843;10278.794424111626;10316.742081447723;11033.613823516138;11099.833502497631;11297.26567773092;11457.885421145682;11498.367589782642;10228;10294.691159265234;10471.371717696962;10528.473576321;10682.198835087243;10566.524506397076;10618.362898225821;10619.097376723064;10631.468426578795;10644.680659580225;10407;10473.21450891171;10480.68557943263;10489.213309001703;10501.632442864464;8913.420627659441;9136.6802161924;9163;9198.402103863227;9283.675071372469;8849.646014159362;9077.364584479023;9110.863337050083;9168.954155229449;9304.58129384193 13:31:49 INFO - PROCESS | 1664 | __end_tp_report 13:31:49 INFO - PROCESS | 1664 | __start_cc_report 13:31:49 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,423 13:31:49 INFO - PROCESS | 1664 | __end_cc_report 13:31:49 INFO - PROCESS | 1664 | __startTimestamp1461702709206__endTimestamp 13:31:49 INFO - PROCESS | 1664 | 13:31:49 INFO - PROCESS | 1664 | ------- Summary: start ------- 13:31:49 INFO - PROCESS | 1664 | Number of tests: 6 13:31:49 INFO - PROCESS | 1664 | 13:31:49 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5819.70 Median:6020.88 stddev:639.49 (10.6%) stddev-sans-first:614.43 13:31:49 INFO - PROCESS | 1664 | Values: 3475.0 3481.5 3494.3 3504.4 3547.1 5440.0 5809.8 5853.5 5872.7 5880.8 5869.9 5870.4 5884.7 5887.7 5915.6 5216.2 5295.5 5321.0 5321.7 5352.6 5167.4 5333.6 5347.1 5348.8 5365.5 5289.7 5322.1 5322.8 5323.9 5333.1 5273.0 5292.1 5298.8 5316.8 5322.6 5156.0 5248.9 5282.4 5396.1 5397.3 5911.4 5926.5 5930.9 5942.6 5947.8 6205.5 6207.1 6223.9 6232.9 6242.3 6186.1 6217.0 6247.4 6298.5 6299.8 5953.2 5987.0 6020.9 6046.1 6083.6 6442.2 6458.9 6480.3 6491.5 6507.8 6081.8 6138.7 6150.5 6195.7 6202.8 6303.4 6313.1 6338.1 6342.2 6344.7 6145.6 6250.6 6262.0 6277.5 6286.6 6166.3 6171.7 6206.1 6219.3 6250.4 5029.7 5030.1 5034.7 5037.4 5038.0 4336.2 4426.1 4515.1 4519.9 4524.1 5979.8 5984.1 5990.2 6015.4 6025.5 5430.5 5539.7 6133.4 6357.6 6379.5 5766.1 5883.7 5928.5 5957.6 5976.5 4606.5 5511.4 5532.5 5540.3 5544.7 6535.4 6550.4 6557.6 6559.1 6581.2 5451.5 6155.3 6286.4 6288.7 6316.2 5422.4 6464.1 6498.9 6535.4 6563.4 6228.5 6240.7 6242.7 6262.3 6272.1 5218.3 6157.1 6269.0 6319.4 6323.4 6149.7 6150.8 6175.8 6196.4 6200.6 6021.9 6150.4 6155.1 6208.7 6209.9 5806.1 5891.9 5918.9 5958.9 5993.2 6193.4 6214.2 6222.2 6243.0 6267.5 5963.9 6087.4 6130.5 6141.5 6152.6 13:31:49 INFO - PROCESS | 1664 | 13:31:49 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:68360.92 Median:39290.51 stddev:150632.62 (383.4%) stddev-sans-first:150969.08 13:31:49 INFO - PROCESS | 1664 | Values: 522.9 531.5 568.9 569.3 623.9 34913.7 40549.2 40554.6 40582.6 40688.0 41321.6 41893.8 41912.4 41913.0 42039.9 8432.5 9385.6 9517.4 9605.2 9666.8 9609.8 9747.7 9774.0 9816.8 9846.3 9742.6 9743.3 9781.8 9786.2 9812.2 9734.5 9778.6 9778.7 9797.0 9810.5 9549.7 9600.1 9612.6 9674.4 9684.0 39959.0 39988.0 40297.6 40500.2 40611.0 3183.6 3216.3 3230.9 3369.2 3696.2 28830.9 28864.4 29177.7 29737.4 29747.6 2771.5 2790.9 2820.5 2858.9 2865.8 463873.0 470825.0 473368.6 474827.0 475818.0 34187.5 66804.0 67413.3 67424.0 67425.0 35867.3 35969.8 36141.8 36287.5 36347.7 15654.0 16059.8 16077.8 16108.2 16114.6 10941.1 13197.7 13390.3 13464.7 13504.1 7969.8 8201.6 8314.5 8330.7 8350.2 3375.7 3420.2 3420.9 3424.6 3426.5 962.7 980.6 1014.8 1098.2 1106.0 30806.9 30976.2 30993.0 31154.0 31467.7 1005.2 1042.0 1075.3 1095.4 1160.6 15810.4 16040.7 16119.0 16317.8 16349.3 654400.0 672861.0 679615.0 680561.0 681922.0 61820.0 62421.0 62423.7 62424.4 62556.0 1487.6 1501.1 1525.5 1543.2 1619.1 30138.1 30484.6 30607.5 30622.9 30736.7 55121.4 55206.0 55358.6 55398.9 55632.9 521410.0 522284.0 522509.0 523689.4 524548.0 25780.7 26842.6 26938.6 27016.3 27382.7 46862.0 47258.8 48470.5 48587.8 48827.0 37592.5 37621.1 39019.0 39448.0 40209.0 38172.0 38500.8 38984.2 39052.2 39533.8 36985.3 38233.6 38930.0 39209.8 39371.2 32772.7 33680.8 33841.0 33909.7 34180.7 25389.0 25612.9 25727.9 25729.1 25971.0 13:31:49 INFO - PROCESS | 1664 | 13:31:49 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:35694.48 Median:26586.80 stddev:102708.23 (386.3%) stddev-sans-first:102962.16 13:31:49 INFO - PROCESS | 1664 | Values: 399.4 403.5 405.0 406.4 410.2 13727.9 18213.8 20607.2 20737.7 21337.8 20231.5 20701.7 21443.5 21459.0 21558.6 5697.3 6179.4 6182.5 6183.7 6241.3 5323.9 5666.8 6057.1 6065.9 6120.6 5134.6 5925.3 5939.2 6018.5 6056.5 5763.4 6063.9 6181.1 6203.7 6210.2 5846.5 5907.4 5938.1 5965.2 6018.7 25728.7 26248.3 26343.6 26830.0 26848.6 1951.9 2045.3 2099.1 2223.5 2267.7 8235.7 9940.1 10061.9 10077.8 10235.4 1661.0 1672.5 1687.9 1753.1 1768.4 85952.0 132573.0 134264.0 136854.6 136904.0 11714.6 11888.5 11924.1 11933.1 11954.2 15344.7 15621.3 15662.8 15689.6 15700.0 7512.9 8976.6 9023.9 9124.4 9198.7 7271.4 8960.0 9058.5 9096.3 9109.5 4650.3 4685.3 4737.1 4739.8 4802.8 1955.3 2009.3 2022.8 2049.7 2055.2 808.8 816.7 824.3 877.6 890.5 16987.0 20356.9 21246.7 21288.0 21406.7 863.5 896.8 898.4 915.9 958.4 8873.7 11446.3 11497.7 11501.5 11503.4 558113.0 617244.0 630864.0 631122.0 631971.0 130022.3 130561.0 131628.7 131856.7 136184.0 1000.9 1010.6 1019.2 1022.2 1049.1 18469.1 18610.5 18941.0 19107.0 19345.6 50634.0 51876.5 55643.7 56523.4 56596.2 56596.7 71513.6 76519.6 78963.8 81277.0 8248.1 8319.7 8349.9 8392.9 8537.4 11642.8 12367.3 12513.9 12562.2 12575.3 10612.8 10826.6 11077.3 11186.3 11333.7 8568.6 9378.6 10004.0 10759.7 11127.2 11029.8 11168.2 11181.5 11391.8 11560.8 8152.8 8165.8 8185.9 8331.4 8523.9 8957.4 10634.9 11075.9 11122.4 11358.9 13:31:49 INFO - PROCESS | 1664 | 13:31:49 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5813.08 Median:4874.43 stddev:3258.92 (66.9%) stddev-sans-first:3252.56 13:31:49 INFO - PROCESS | 1664 | Values: 1583.7 1606.7 1848.9 1933.2 1952.1 8194.3 8684.5 8755.7 9078.5 9106.0 8708.2 8744.3 8800.2 8865.6 8926.4 2429.3 2445.4 2463.2 2468.1 2478.6 2132.4 2457.2 2467.9 2490.6 2506.4 2035.7 2452.3 2494.2 2498.8 2505.5 2447.8 2453.1 2466.3 2467.5 2481.5 2446.2 2455.5 2473.5 2474.9 2482.3 8970.8 8975.5 8982.8 9101.3 9112.0 5377.0 5571.8 5582.4 5629.5 5648.2 3598.6 3648.1 3704.6 3739.0 3766.3 1051.4 1056.4 1058.8 1063.5 1087.2 11887.9 11917.8 11989.9 12071.2 12095.0 4899.4 4911.0 4984.9 5001.6 5018.3 10219.0 10248.2 10294.7 10303.7 10317.6 5478.6 5485.3 5545.8 5547.8 5579.9 4501.3 4893.7 4991.5 5043.5 5048.2 3513.5 3572.9 3614.7 4165.8 4174.0 1368.1 1374.0 1382.1 1382.1 1392.2 918.5 919.0 935.0 942.4 945.6 5872.0 5880.5 5897.9 6036.8 6148.9 454.5 464.2 473.4 475.3 1009.7 3907.3 3942.6 3980.9 3984.2 4033.3 10525.9 10650.1 11056.8 11070.1 11111.8 10704.6 10731.7 10925.3 10948.6 10968.7 7865.5 8101.0 8303.0 8335.8 8355.6 4719.8 5901.2 6025.7 6127.9 6160.4 9543.1 9760.7 10059.5 10112.9 10120.9 10172.5 10192.5 10294.1 10301.5 10399.9 7674.9 7751.7 7761.1 7800.1 7871.6 8362.3 8515.2 8530.2 8567.1 8594.8 6500.3 7547.2 7641.2 7674.1 7696.0 6848.0 6899.2 7445.0 7445.9 7627.1 7153.8 7226.3 7238.2 7242.8 7246.8 4855.2 4899.5 5044.3 5064.2 5073.9 4949.2 4951.9 4988.8 5015.2 5179.0 13:31:49 INFO - PROCESS | 1664 | 13:31:49 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4919.69 Median:5472.95 stddev:1608.64 (29.4%) stddev-sans-first:1575.46 13:31:49 INFO - PROCESS | 1664 | Values: 306.9 307.3 307.6 308.5 315.5 5511.4 5552.4 5561.6 5605.3 5607.6 4294.4 5442.1 5516.9 5545.3 5556.9 3728.0 3975.4 4111.5 4211.0 4220.7 3290.2 4010.6 4078.9 4169.9 4242.0 3860.8 4202.0 4230.7 4231.4 4243.0 3879.0 4056.2 4124.1 4190.9 4212.3 3204.2 3490.7 3634.6 3673.0 3683.0 4573.0 4756.1 5346.6 5366.7 5383.3 3675.2 4657.1 4829.0 4864.3 4894.8 4746.7 5347.6 5468.9 5746.2 5873.9 3700.5 4051.9 4064.9 4073.6 4073.8 5880.0 6084.3 6130.8 6145.7 6219.9 4112.4 4116.6 4131.0 4137.6 4240.5 3662.4 4181.4 4198.6 4217.8 4237.7 4085.9 4849.8 4854.0 4860.5 4864.6 5334.1 5586.0 5765.3 5871.9 5893.4 1446.5 1460.3 1508.2 1520.1 1523.1 880.6 926.7 969.2 980.8 1014.1 4994.3 5634.4 5925.0 5926.6 5995.9 6470.5 6501.3 6512.7 6549.3 6552.2 4773.5 5288.3 5664.9 5699.2 5960.6 2916.0 3062.8 3464.7 3486.4 3503.7 6601.5 6854.1 6879.7 6901.9 6907.8 6741.9 6750.2 6786.8 6801.3 6834.8 5530.5 6561.8 6897.4 6987.3 6998.5 6202.9 6394.2 6514.9 6569.3 6630.6 5732.8 6256.5 6508.0 6514.5 6525.4 6209.2 6566.9 6778.0 6826.2 6929.2 3520.5 3836.8 4041.9 4137.8 4224.1 6073.7 6345.6 6600.8 6618.8 6661.6 5564.4 5961.9 6004.1 6246.7 6400.9 6109.5 6353.1 6374.3 6395.4 6449.7 5476.9 5657.6 5738.8 6174.5 6207.7 4891.8 5557.0 5836.8 5961.3 5972.7 4506.1 4837.3 5094.8 5256.9 5295.4 13:31:49 INFO - PROCESS | 1664 | 13:31:49 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18271.85 Median:662.79 stddev:26321.53 (3971.3%) stddev-sans-first:26362.15 13:31:49 INFO - PROCESS | 1664 | Values: 660.4 689.5 692.3 739.4 760.8 13172.1 14397.9 14740.5 14937.6 14991.4 13771.9 13825.7 13841.0 13879.8 13943.4 726.6 735.8 913.4 923.7 923.8 908.1 918.9 919.6 922.7 923.4 980.0 980.2 981.8 982.5 983.3 938.7 939.4 952.4 962.3 965.8 696.5 697.6 697.9 712.1 721.0 13011.8 14109.8 14590.3 14809.0 14821.4 12619.7 12744.9 12822.7 12869.1 13114.0 9410.8 9609.2 10084.9 10215.4 10304.3 3032.3 3430.8 3449.6 3466.6 3552.1 77463.6 81274.6 81790.2 84800.0 84881.0 9577.8 9663.2 9730.4 9778.8 9816.8 19076.6 19598.7 21293.6 21325.6 21338.4 7454.6 7460.3 7460.6 7562.2 7580.3 3993.2 4037.5 4104.7 4115.2 4177.4 3642.7 3713.9 3720.3 3722.4 3739.3 652.9 660.7 664.8 669.6 675.7 502.4 594.5 606.7 610.2 613.2 7347.7 7672.9 7693.3 7697.7 7770.8 508.3 518.1 547.3 614.1 625.9 3824.3 3827.4 3855.9 3902.9 4120.4 91390.5 92638.0 93081.0 93898.1 96203.0 81467.6 83392.6 83645.6 83777.6 84356.0 72267.6 72441.0 72474.6 72714.6 72750.6 7736.1 7903.1 7926.3 7942.9 8085.0 46279.0 46318.5 46457.8 46917.8 47985.8 65688.7 67887.7 68312.3 68615.7 68622.0 9819.8 10159.0 10159.1 10278.8 10316.7 11033.6 11099.8 11297.3 11457.9 11498.4 10228.0 10294.7 10471.4 10528.5 10682.2 10566.5 10618.4 10619.1 10631.5 10644.7 10407.0 10473.2 10480.7 10489.2 10501.6 8913.4 9136.7 9163.0 9198.4 9283.7 8849.6 9077.4 9110.9 9169.0 9304.6 13:31:49 INFO - PROCESS | 1664 | -------- Summary: end -------- 13:31:49 INFO - PROCESS | 1664 | 13:31:49 INFO - TEST-INFO | 1664: exit 0 13:31:49 INFO - TEST-OK | dromaeo_css | took 1112222ms 13:31:49 INFO - TEST-START | kraken 13:31:49 INFO - operating with platform_type : mac_ 13:31:49 INFO - Initialising browser for kraken test... 13:31:49 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpjslgAP/profile http://localhost:49275/getInfo.html) 13:31:52 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 13:31:52 INFO - PROCESS | 1672 | colorDepth:24 13:31:52 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 13:31:52 INFO - PROCESS | 1672 | __metrics 13:31:52 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 13:31:53 INFO - TEST-INFO | 1672: exit 0 13:31:53 INFO - Browser initialized. 13:31:53 INFO - Running cycle 1/1 for kraken test... 13:31:53 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpjslgAP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:32:05 INFO - PROCESS | 1675 | RSS: Main: 221753344 13:32:05 INFO - PROCESS | 1675 | 13:32:08 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 13:32:09 INFO - PROCESS | 1675 | RSS: Main: 233771008 13:32:09 INFO - PROCESS | 1675 | 13:32:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 13:32:12 INFO - PROCESS | 1675 | RSS: Main: 243134464 13:32:12 INFO - PROCESS | 1675 | 13:32:15 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 13:32:16 INFO - PROCESS | 1675 | RSS: Main: 246251520 13:32:16 INFO - PROCESS | 1675 | 13:32:18 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 13:32:19 INFO - PROCESS | 1675 | RSS: Main: 246951936 13:32:19 INFO - PROCESS | 1675 | 13:32:21 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:32:21 INFO - PROCESS | 1675 | RSS: Main: 248598528 13:32:21 INFO - PROCESS | 1675 | 13:32:24 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 13:32:25 INFO - PROCESS | 1675 | RSS: Main: 248971264 13:32:25 INFO - PROCESS | 1675 | 13:32:28 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 13:32:28 INFO - PROCESS | 1675 | RSS: Main: 249012224 13:32:28 INFO - PROCESS | 1675 | 13:32:31 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 13:32:32 INFO - PROCESS | 1675 | RSS: Main: 249126912 13:32:32 INFO - PROCESS | 1675 | 13:32:33 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:32:34 INFO - PROCESS | 1675 | RSS: Main: 248070144 13:32:34 INFO - PROCESS | 1675 | 13:32:36 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:32:37 INFO - PROCESS | 1675 | RSS: Main: 248180736 13:32:37 INFO - PROCESS | 1675 | 13:32:39 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:32:40 INFO - PROCESS | 1675 | RSS: Main: 248483840 13:32:40 INFO - PROCESS | 1675 | 13:32:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:32:43 INFO - PROCESS | 1675 | RSS: Main: 248500224 13:32:43 INFO - PROCESS | 1675 | 13:32:46 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:32:46 INFO - PROCESS | 1675 | RSS: Main: 248557568 13:32:46 INFO - PROCESS | 1675 | 13:32:48 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 13:32:49 INFO - PROCESS | 1675 | RSS: Main: 250257408 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | __start_tp_report 13:32:49 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 13:32:49 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 13:32:49 INFO - PROCESS | 1675 | |i|pagename|runs| 13:32:49 INFO - PROCESS | 1675 | |0;ai-astar;135;168;176;124;110;156;113;173;172;126 13:32:49 INFO - PROCESS | 1675 | |1;audio-beat-detection;152;149;145;186;151;156;121;154;157;120 13:32:49 INFO - PROCESS | 1675 | |2;audio-dft;193;200;199;199;198;197;194;193;205;196 13:32:49 INFO - PROCESS | 1675 | |3;audio-fft;65;78;105;183;82;81;85;57;124;57 13:32:49 INFO - PROCESS | 1675 | |4;audio-oscillator;83;122;154;76;77;98;121;63;105;106 13:32:49 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;88;86;88;86;105;110;90;102;113;85 13:32:49 INFO - PROCESS | 1675 | |6;imaging-darkroom;96;95;94;96;94;95;94;116;96;94 13:32:49 INFO - PROCESS | 1675 | |7;imaging-desaturate;91;136;144;140;87;90;95;89;90;90 13:32:49 INFO - PROCESS | 1675 | |8;json-parse-financial;62;65;66;65;69;66;67;86;64;61 13:32:49 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;63;65;71;65;80;79;70;68;67;69 13:32:49 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;82;85;71;85;106;107;106;107;85;85 13:32:49 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;141;139;148;136;135;136;137;135;173;136 13:32:49 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;173;171;169;172;179;172;171;169;170;166 13:32:49 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;68;71;69;70;89;101;61;97;102;100 13:32:49 INFO - PROCESS | 1675 | __end_tp_report 13:32:49 INFO - PROCESS | 1675 | __start_cc_report 13:32:49 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1088 13:32:49 INFO - PROCESS | 1675 | __end_cc_report 13:32:49 INFO - PROCESS | 1675 | __startTimestamp1461702769044__endTimestamp 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | ------- Summary: start ------- 13:32:49 INFO - PROCESS | 1675 | Number of tests: 14 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:145.30 Median:162.00 stddev:26.40 (16.3%) stddev-sans-first:27.74 13:32:49 INFO - PROCESS | 1675 | Values: 135.0 168.0 176.0 124.0 110.0 156.0 113.0 173.0 172.0 126.0 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:149.10 Median:153.00 stddev:18.74 (12.2%) stddev-sans-first:19.85 13:32:49 INFO - PROCESS | 1675 | Values: 152.0 149.0 145.0 186.0 151.0 156.0 121.0 154.0 157.0 120.0 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:197.40 Median:198.50 stddev:3.69 (1.9%) stddev-sans-first:3.55 13:32:49 INFO - PROCESS | 1675 | Values: 193.0 200.0 199.0 199.0 198.0 197.0 194.0 193.0 205.0 196.0 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:91.70 Median:71.50 stddev:38.18 (53.4%) stddev-sans-first:39.25 13:32:49 INFO - PROCESS | 1675 | Values: 65.0 78.0 105.0 183.0 82.0 81.0 85.0 57.0 124.0 57.0 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:100.50 Median:69.50 stddev:27.22 (39.2%) stddev-sans-first:28.12 13:32:49 INFO - PROCESS | 1675 | Values: 83.0 122.0 154.0 76.0 77.0 98.0 121.0 63.0 105.0 106.0 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:95.30 Median:86.00 stddev:10.97 (12.8%) stddev-sans-first:11.31 13:32:49 INFO - PROCESS | 1675 | Values: 88.0 86.0 88.0 86.0 105.0 110.0 90.0 102.0 113.0 85.0 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:97.00 Median:95.00 stddev:6.73 (7.1%) stddev-sans-first:7.13 13:32:49 INFO - PROCESS | 1675 | Values: 96.0 95.0 94.0 96.0 94.0 95.0 94.0 116.0 96.0 94.0 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:105.20 Median:90.00 stddev:24.17 (26.9%) stddev-sans-first:25.08 13:32:49 INFO - PROCESS | 1675 | Values: 91.0 136.0 144.0 140.0 87.0 90.0 95.0 89.0 90.0 90.0 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:67.10 Median:66.00 stddev:7.03 (10.7%) stddev-sans-first:7.21 13:32:49 INFO - PROCESS | 1675 | Values: 62.0 65.0 66.0 65.0 69.0 66.0 67.0 86.0 64.0 61.0 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:69.50 stddev:5.72 (8.2%) stddev-sans-first:5.53 13:32:49 INFO - PROCESS | 1675 | Values: 63.0 65.0 71.0 65.0 80.0 79.0 70.0 68.0 67.0 69.0 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:91.90 Median:83.50 stddev:13.25 (15.9%) stddev-sans-first:13.56 13:32:49 INFO - PROCESS | 1675 | Values: 82.0 85.0 71.0 85.0 106.0 107.0 106.0 107.0 85.0 85.0 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:141.60 Median:138.00 stddev:11.72 (8.5%) stddev-sans-first:12.43 13:32:49 INFO - PROCESS | 1675 | Values: 141.0 139.0 148.0 136.0 135.0 136.0 137.0 135.0 173.0 136.0 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.20 Median:171.50 stddev:3.39 (2.0%) stddev-sans-first:3.54 13:32:49 INFO - PROCESS | 1675 | Values: 173.0 171.0 169.0 172.0 179.0 172.0 171.0 169.0 170.0 166.0 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.80 Median:69.50 stddev:16.41 (23.6%) stddev-sans-first:16.51 13:32:49 INFO - PROCESS | 1675 | Values: 68.0 71.0 69.0 70.0 89.0 101.0 61.0 97.0 102.0 100.0 13:32:49 INFO - PROCESS | 1675 | -------- Summary: end -------- 13:32:49 INFO - PROCESS | 1675 | 13:32:49 INFO - TEST-INFO | 1675: exit 0 13:32:49 INFO - TEST-OK | kraken | took 60179ms 13:32:49 INFO - SUITE-END | took 1172s 13:32:50 INFO - Completed test suite (00:19:32) 13:32:50 INFO - javascript benchmark 13:32:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3474.982625086874, 3481.4777783332497, 3494.2662040971395, 3504.3516949364353, 3547.113221694576, 5440.047991601474, 5809.796657117002, 5853.531717462603, 5872.73572689229, 5880.764769409224, 5869.941300586993, 5870.442307980746, 5884.705764711758, 5887.735051922663, 5915.615484993483, 5216.24364467153, 5295.4969277918535, 5321.015612111772, 5321.73391330432, 5352.598555108363, 5167.431582525919, 5333.57331413486, 5347.144456886879, 5348.812791552296, 5365.490278423563, 5289.656172348804, 5322.0686379883555, 5322.787088516455, 5323.893522129555, 5333.146696528556, 5273.0, 5292.073887069767, 5298.841034768963, 5316.787328506855, 5322.600804939606, 5156.04613146569, 5248.921266181011, 5282.418933917266, 5396.055690254189, 5397.298351214317, 5911.438413350725, 5926.496247818941, 5930.851728706773, 5942.643441393487, 5947.762089516415, 6205.503559715212, 6207.099970504251, 6223.906641400383, 6232.9376706232815, 6242.313345531988, 6186.133941248228, 6217.036359364306, 6247.437730604267, 6298.527610429236, 6299.811005669837, 5953.238750714322, 5987.0, 6020.879582408327, 6046.123312119806, 6083.6349819011, 6442.226932768098, 6458.935410645833, 6480.2547707013355, 6491.480681545465, 6507.804765857032, 6081.817545473643, 6138.664296166039, 6150.477209437248, 6195.659238741912, 6202.81391558254, 6303.369663033752, 6313.05304204373, 6338.112664227012, 6342.238931328178, 6344.746210151635, 6145.600535965148, 6250.593711408744, 6262.029385445263, 6277.466415354655, 6286.591371560834, 6166.321704612489, 6171.691415429301, 6206.0690896365, 6219.315875253718, 6250.3749625037135, 5029.723365214948, 5030.0945829751, 5034.748262586856, 5037.395512538519, 5037.974810125926, 4336.241157797373, 4426.070525189746, 4515.074409745994, 4519.887002824956, 4524.117797029548, 5979.790707325223, 5984.072468767348, 5990.161377407167, 6015.398460154037, 6025.548083893726, 5430.456954304538, 5539.667619942736, 6133.44798968095, 6357.586756860882, 6379.489640828722, 5766.106253530709, 5883.676397798162, 5928.466438020512, 5957.6127551709, 5976.5218782497295, 4606.493285738566, 5511.366192887789, 5532.5020748132865, 5540.252065971123, 5544.69504177266, 6535.444487218545, 6550.443212327005, 6557.573757705733, 6559.147310849559, 6581.2102547694585, 5451.509364157245, 6155.261368635793, 6286.40279173496, 6288.685565721604, 6316.210473690789, 5422.403535611158, 6464.0627109069865, 6498.9329663535445, 6535.379138981789, 6563.442107420923, 6228.4705800007505, 6240.719167637558, 6242.719077641426, 6262.34245404226, 6272.121902933684, 5218.269442278008, 6157.1072194532435, 6269.0, 6319.399657032574, 6323.399277068671, 6149.661768602769, 6150.7847225346895, 6175.752969881334, 6196.411340922605, 6200.627962322275, 6021.87956240864, 6150.354212807591, 6155.1075094112, 6208.720607572761, 6209.913265178486, 5806.071028635398, 5891.941080589139, 5918.881622367615, 5958.851028724143, 5993.220881285405, 6193.38066193377, 6214.1921550198185, 6222.222222222223, 6243.0, 6267.4986001120715, 5963.880722385615, 6087.421694938974, 6130.50955923534, 6141.508679305734, 6152.630842149485], "value": 5780.911634153676, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.9058769421505, 531.5163201486647, 568.8963410982695, 569.2969183058922, 623.9407256310649, 34913.650863491355, 40549.18901621973, 40554.594454055485, 40582.59417405825, 40688.0, 41321.586784132225, 41893.79053104738, 41912.37131443031, 41913.0, 42039.94900127498, 8432.53621050845, 9385.57764900581, 9517.428954262732, 9605.18355939746, 9666.806663866719, 9609.759756006086, 9747.707568772948, 9773.951130244339, 9816.754581135458, 9846.261530385249, 9742.610295588203, 9743.317967742261, 9781.804363912717, 9786.217102631772, 9812.165965892907, 9734.51327433629, 9778.608855645802, 9778.657746978894, 9796.95101524495, 9810.46042467664, 9549.713508594754, 9600.087991640783, 9612.615495380176, 9674.419534827934, 9684.03159684026, 39959.0, 39988.00029999273, 40297.59702402968, 40500.18999619991, 40611.0, 3183.6179658441133, 3216.308493673848, 3230.91922701932, 3369.241920567877, 3696.1868388954385, 28830.855845720846, 28864.42271154586, 29177.70822291771, 29737.405251895056, 29747.553786693217, 2771.473420050184, 2790.9162725118076, 2820.49231138395, 2858.9285267868468, 2865.8137221080565, 463873.0, 470825.0, 473368.63315683545, 474827.0, 475818.0, 34187.487187692204, 66804.0, 67413.3258667407, 67424.0, 67425.0, 35867.28265434677, 35969.820150899075, 36141.819290903906, 36287.4556881647, 36347.72782952584, 15654.0, 16059.839401605834, 16077.839221607868, 16108.19459027044, 16114.597135071483, 10941.070009049161, 13197.73604527904, 13390.263535505446, 13464.663383415493, 13504.054716169774, 7969.840603187904, 8201.589920503951, 8314.501129932223, 8330.708425205088, 8350.164983501601, 3375.7130643895052, 3420.1791570023015, 3420.8973730788116, 3424.5719285089363, 3426.5202871597994, 962.6582563190107, 980.632262901412, 1014.7716763728102, 1098.2257508456519, 1105.9723506912392, 30806.92175773837, 30976.225594359872, 30993.007270258386, 31154.0, 31467.685323146932, 1005.1657124586576, 1041.9528373984097, 1075.2795626929976, 1095.4358505369742, 1160.5879912631062, 15810.367585296459, 16040.67918641644, 16119.0, 16317.836821631632, 16349.264283107288, 654400.0, 672861.0, 679615.0, 680561.0, 681922.0, 61820.0, 62421.0, 62423.687881560305, 62424.37575624276, 62556.0, 1487.6355292953297, 1501.129344979909, 1525.5270866031565, 1543.1975372806078, 1619.1256721370328, 30138.09585712432, 30484.628191730983, 30607.54088688627, 30622.928197513873, 30736.69263307428, 55121.44878551324, 55206.0, 55358.61603459946, 55398.89202216014, 55632.887342253736, 521410.0, 522284.0, 522509.0, 523689.38155308977, 524548.0, 25780.742192577833, 26842.597361039992, 26938.59592106159, 27016.32459188536, 27382.726172738814, 46862.0, 47258.76370618125, 48470.54588362355, 48587.75706121447, 48827.0, 37592.496300148785, 37621.05947351338, 39019.0, 39448.0, 40209.0, 38172.0, 38500.844974650805, 38984.220315594095, 39052.2189556213, 39533.80233098816, 36985.260294793414, 38233.617663824116, 38930.0, 39209.803950980066, 39371.212575747755, 32772.67227327696, 33680.83159584186, 33841.0, 33909.66090339164, 34180.658193417745, 25388.984440622167, 25612.871935640203, 25727.871360643076, 25729.099481518057, 25971.0], "value": 18990.915229057322, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.4088748651995, 403.4593644516348, 404.9655779258763, 406.35998302673295, 410.2267226278466, 13727.931360343184, 18213.81786182138, 20607.175712971482, 20737.65205261657, 21337.786622133775, 20231.49421264469, 20701.68947465789, 21443.463913402134, 21459.0, 21558.568828623418, 5697.344805347397, 6179.443850053494, 6182.474489668383, 6183.6598987055695, 6241.344658810827, 5323.946760532383, 5666.801661941833, 6057.091436284549, 6065.878682426349, 6120.63276203427, 5134.64057515974, 5925.318588362329, 5939.198208241897, 6018.518518518508, 6056.485198758089, 5763.366029736726, 6063.878722425548, 6181.134641150243, 6203.658798766066, 6210.1616281801835, 5846.473817356458, 5907.350191478977, 5938.079597662326, 5965.20463605568, 6018.6990650467305, 25728.74271257282, 26248.343791405176, 26343.604845927326, 26830.0, 26848.59727104095, 1951.9024048797503, 2045.3454894433787, 2099.1078791513546, 2223.4774827915426, 2267.671187677794, 8235.711750088778, 9940.055694708992, 10061.949690251502, 10077.848832267522, 10235.385876847338, 1660.96678066438, 1672.503109716809, 1687.9409220677217, 1753.1409609291484, 1768.4252617899235, 85952.0, 132573.0, 134264.0, 136854.63145368517, 136904.0, 11714.648560543026, 11888.465019074163, 11924.10569207313, 11933.105017123577, 11954.163208575315, 15344.693106137815, 15621.297041633154, 15662.843371566367, 15689.607759806097, 15700.0, 7512.887306690404, 8976.551172441352, 9023.909760902437, 9124.406913550598, 9198.724038278862, 7271.381932535688, 8960.0, 9058.54707264634, 9096.272298216127, 9109.498977556166, 4650.348951146842, 4685.297205419146, 4737.099951009297, 4739.786709598076, 4802.783874725646, 1955.257002339102, 2009.2867032203358, 2022.848286378498, 2049.6720524715975, 2055.1676570988775, 808.7816289601775, 816.7059858450953, 824.2746383182881, 877.6270085213758, 890.5235698901057, 16987.01891359658, 20356.898215508827, 21246.681299780517, 21288.0, 21406.678899816514, 863.5250612163285, 896.8296023755596, 898.3531857062904, 915.8992510823804, 958.3531116496337, 8873.73378798637, 11446.313221206754, 11497.655070347735, 11501.482433290523, 11503.424828758696, 558113.0, 617244.0, 630864.0, 631122.0, 631971.0, 130022.34988824996, 130561.0, 131628.68371316357, 131856.68143318637, 136184.0, 1000.9040101089296, 1010.5907107621425, 1019.2304809868501, 1022.1771473963531, 1049.061090324171, 18469.076546172368, 18610.534736631693, 18941.0, 19107.0, 19345.61308773845, 50634.0, 51876.481235187166, 55643.721781390836, 56523.43476565346, 56596.15105773335, 56596.71701641465, 71513.6424317875, 76519.61740191263, 78963.81554276576, 81277.0, 8248.13394593559, 8319.667213311643, 8349.874751878848, 8392.916070839214, 8537.402381833212, 11642.825357619811, 12367.319797411228, 12513.93743031279, 12562.246265224116, 12575.308358040396, 10612.787744245226, 10826.56693732242, 11077.335359878432, 11186.32882027081, 11333.716657083309, 8568.57157142153, 9378.5779639915, 10004.049615286536, 10759.731006724895, 11127.22109452363, 11029.779404412027, 11168.162387820783, 11181.496832642713, 11391.772164556829, 11560.826587601025, 8152.755417337489, 8165.755027349189, 8185.935828342277, 8331.375146864131, 8523.91476085256, 8957.372983891066, 10634.946825266134, 11075.889241107487, 11122.44387780624, 11358.886411135783], "value": 9593.811952268294, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1583.7149313123637, 1606.6786642671466, 1848.9260429582816, 1933.2460340467212, 1952.0825212150285, 8194.344452443805, 8684.522351270678, 8755.737327880157, 9078.50068246247, 9106.0, 8708.172723591264, 8744.344174186945, 8800.16398442147, 8865.556722163898, 8926.41978271412, 2429.344077099182, 2445.449773800894, 2463.2487091437106, 2468.0991438125043, 2478.5662509060844, 2132.4242454537266, 2457.2382561405934, 2467.9136230231948, 2490.6139548370033, 2506.410993416546, 2035.7251771010872, 2452.3378687754293, 2494.1769216158623, 2498.7876030537427, 2505.473850491399, 2447.8286519943613, 2453.116877923946, 2466.334089795754, 2467.4571594249237, 2481.528509583173, 2446.156076153742, 2455.5457240410583, 2473.4558397152596, 2474.851508909453, 2482.3173627252468, 8970.82058358829, 8975.506347150904, 8982.820343593092, 9101.271898248124, 9112.0, 5377.0, 5571.804986825442, 5582.441755824426, 5629.549636029108, 5648.181013753024, 3598.5501812273465, 3648.087978005499, 3704.5554533456157, 3739.046543131484, 3766.303233901737, 1051.3901936876593, 1056.4084112896803, 1058.7564860082148, 1063.5479921033607, 1087.2009073331096, 11887.94056029723, 11917.761644767143, 11989.880101198964, 12071.155019148664, 12094.971927386096, 4899.363082799285, 4911.0, 4984.925226121611, 5001.574866136347, 5018.272350509155, 10219.0, 10248.23138264633, 10294.661693979884, 10303.690889273334, 10317.638882639072, 5478.561715062785, 5485.341758988867, 5545.833624991256, 5547.7780888764, 5579.860503487364, 4501.257292546758, 4893.7063776173545, 4991.500849915052, 5043.52086551777, 5048.217526283432, 3513.5432393789156, 3572.8570857165423, 3614.674679278825, 4165.75005499671, 4173.97913010433, 1368.0560413314781, 1373.979390309146, 1382.0878220374505, 1382.1154461144874, 1392.1647011793045, 918.4856480371013, 918.9678361257323, 934.9859752103724, 942.4015749998663, 945.5697657565792, 5872.0, 5880.529557635465, 5897.882042359214, 6036.758529658852, 6148.8770224595255, 454.4887002122612, 464.20852446578505, 473.39405560882125, 475.2657144711429, 1009.6974902375999, 3907.257621051991, 3942.6254505822476, 3980.920381592352, 3984.203159368138, 4033.3344998075577, 10525.9473702631, 10650.147988160928, 11056.778864422828, 11070.059044981106, 11111.83332250017, 10704.571817127227, 10731.731706707395, 10925.344479331265, 10948.616798412017, 10968.725781855517, 7865.488743231672, 8100.959495202486, 8303.04514980773, 8335.791605209797, 8355.582220888931, 4719.834805781781, 5901.232839730807, 6025.6761809796835, 6127.938720612737, 6160.414760597736, 9543.093406126407, 9760.658376956773, 10059.49702514886, 10112.898871011195, 10120.898791011996, 10172.491375431347, 10192.541335639766, 10294.124999375137, 10301.484925753832, 10399.94800025995, 7674.884876726966, 7751.6511756972195, 7761.068671759425, 7800.102988156435, 7871.645775940211, 8362.289205417608, 8515.19105684959, 8530.189631984951, 8567.100454452193, 8594.82810343777, 6500.284968653539, 7547.16981132064, 7641.235876412315, 7674.079110506775, 7696.0, 6848.041274221513, 6899.20659124187, 7444.994925684963, 7445.851082978418, 7627.084749830056, 7153.785386438415, 7226.313500217471, 7238.167610724834, 7242.782716518513, 7246.782596522113, 4855.150348689036, 4899.534544218293, 5044.293798868235, 5064.18972964324, 5073.923891141562, 4949.183384741477, 4951.876203094951, 4988.825391111293, 5015.22264049073, 5179.0], "value": 4635.362306705054, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.90179142674356, 307.2671678047855, 307.6446704056812, 308.487910069285, 315.48260852202384, 5511.393746687866, 5552.41699621539, 5561.555075593953, 5605.299337582802, 5607.551395888329, 4294.368140106755, 5442.102053161224, 5516.9448305516935, 5545.306836645419, 5556.9444305556935, 3728.049347416405, 3975.3838155085937, 4111.465509483778, 4211.010412553048, 4220.7256528325715, 3290.243244053869, 4010.5588385277742, 4078.8572399966006, 4169.854055108071, 4242.0, 3860.8455661773637, 4201.957980420188, 4230.725002874803, 4231.407602935591, 4243.0, 3878.9806050969846, 4056.2090392373498, 4124.118978657795, 4190.91618167638, 4212.262854000568, 3204.198950262434, 3490.6509349065145, 3634.6365363463706, 3673.0, 3683.0, 4572.993941332901, 4756.096341695067, 5346.572274218053, 5366.6779993200535, 5383.300170977787, 3675.1730860556427, 4657.115148121846, 4828.975855120702, 4864.343313652647, 4894.779734911937, 4746.742113339968, 5347.572194224492, 5468.945310546884, 5746.195532625436, 5873.913326034654, 3700.481932529448, 4051.9392209116886, 4064.857729979466, 4073.6333729964445, 4073.7555746655294, 5879.970600147015, 6084.269887613515, 6130.816075517741, 6145.661988590626, 6219.937800621981, 4112.444819949328, 4116.567760385176, 4131.008557946131, 4137.648299894482, 4240.5123410808155, 3662.377395842713, 4181.393697913846, 4198.6011328923705, 4217.785662527198, 4237.724547904377, 4085.912497266784, 4849.75751212445, 4854.0, 4860.513948605182, 4864.610831133501, 5334.093204155303, 5585.972070139624, 5765.279340082489, 5871.911921321183, 5893.44012318829, 1446.5081872163514, 1460.3136525832842, 1508.1629695519093, 1520.125927591617, 1523.1394262241797, 880.6389380354024, 926.7312479381039, 969.1568335548118, 980.7646164920368, 1014.0721240065258, 4994.275830004629, 5634.3802181759975, 5924.992751232301, 5926.614770039933, 5995.890760209375, 6470.54706170573, 6501.252355979028, 6512.739490420425, 6549.299645700165, 6552.180977377828, 4773.451053128934, 5288.338957630296, 5664.915026274609, 5699.153071676602, 5960.5827592068135, 2915.99398207618, 3062.8162310261455, 3464.705500032475, 3486.3550243205086, 3503.737219708532, 6601.504887133484, 6854.143232095988, 6879.690413931385, 6901.896471552931, 6907.758228461979, 6741.898871516931, 6750.189977202669, 6786.8303292417095, 6801.285864984205, 6834.760783372558, 5530.474604912527, 6561.835954101186, 6897.448204143657, 6987.336203060701, 6998.545094568837, 6202.883480973468, 6394.200724909387, 6514.892468280498, 6569.277379488348, 6630.635315057525, 5732.828015159552, 6256.468200203034, 6508.015055408146, 6514.51141164424, 6525.412712855866, 6209.161763162096, 6566.868662626625, 6778.0, 6826.249112597709, 6929.203141638776, 3520.4895290182267, 3836.808159592065, 4041.91916161681, 4137.77242251679, 4224.091820258659, 6073.696315184311, 6345.587536810092, 6600.801975940537, 6618.834529136618, 6661.635873883658, 5564.415736347787, 5961.910571341346, 6004.078694817791, 6246.6876656167915, 6400.935990640221, 6109.450149486567, 6353.110564520878, 6374.29882712892, 6395.392437718409, 6449.7420103195345, 5476.945230547643, 5657.603967722384, 5738.770449182153, 6174.506039516738, 6207.689615519296, 4891.828785992473, 5556.999740046669, 5836.795712150054, 5961.344252132349, 5972.671503067373, 4506.053728717006, 4837.274408838703, 5094.847154585368, 5256.896051829159, 5295.417504074626], "value": 4408.502187184211, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.3792435110995, 689.5138927056425, 692.3111504053468, 739.3641468337232, 760.8250102476434, 13172.143810652313, 14397.92801035996, 14740.484083057081, 14937.551873443814, 14991.400343986228, 13771.93114034431, 13825.654358641039, 13840.9619278554, 13879.791803122962, 13943.442262309522, 726.6221564786308, 735.8307589254474, 913.4154141349525, 923.6767131503954, 923.8013827027188, 908.0873721909504, 918.9172974432299, 919.6321471411455, 922.7462447826867, 923.4367036107968, 980.0493521284343, 980.2011360741009, 981.7889153831925, 982.4989255479685, 983.306768728045, 938.6714649872558, 939.4363381970832, 952.4333021851987, 962.2927148545813, 965.7971825916566, 696.4985210648325, 697.6407150317591, 697.8848489999144, 712.1098626716604, 721.0050130819436, 13011.804822927663, 14109.78835317471, 14590.270486475634, 14808.963372563925, 14821.407143714347, 12619.74760504794, 12744.87255127446, 12822.679433014157, 12869.09916305859, 13114.0, 9410.776599042149, 9609.228035518427, 10084.899151008469, 10215.438150901697, 10304.278700490968, 3032.348045170299, 3430.7598468107244, 3449.6032956210106, 3466.60134084581, 3552.147484603702, 77463.6126819368, 81274.59362703208, 81790.18209817885, 84800.0, 84881.0, 9577.760555986157, 9663.178629816544, 9730.41617502952, 9778.804423911624, 9816.803663926681, 19076.61846763057, 19598.70601940972, 21293.574128517343, 21325.573488530452, 21338.35984920455, 7454.552726836408, 7460.328570428687, 7460.626968651546, 7562.243775622504, 7580.279873412017, 3993.1614360983936, 4037.4549435826375, 4104.671626269951, 4115.1975364803575, 4177.352510360899, 3642.6721595056574, 3713.870014549532, 3720.330340538675, 3722.4230244312175, 3739.2521495700967, 652.9085927970089, 660.7390080918107, 664.837114906851, 669.5514005616249, 675.6520391998125, 502.36952624456075, 594.5095296380504, 606.7451670298396, 610.2372034956304, 613.2120225510198, 7347.669354879044, 7672.884906726404, 7693.346065584376, 7697.653605587762, 7770.8057298566855, 508.3493128795148, 518.1010946008672, 547.2693953572026, 614.0942110387143, 625.893598088326, 3824.29250588642, 3827.3876179811095, 3855.9421608675893, 3902.863399780994, 4120.381942708557, 91390.54304728567, 92638.0, 93081.0, 93898.0610193903, 96203.03796962081, 81467.59266203632, 83392.58303708442, 83645.58177209075, 83777.58111209527, 84356.0, 72267.6386618074, 72441.0, 72474.63762681153, 72714.63642681752, 72750.63624681949, 7736.110347310131, 7903.091144518454, 7926.286634202949, 7942.880856787037, 8084.9595752023215, 46279.0, 46318.53681463142, 46457.76771116123, 46917.765411172724, 47985.80035499141, 65688.67155664382, 67887.66056169687, 68312.29219269558, 68615.65692171507, 68622.0, 9819.803603928025, 10159.0, 10159.136473399843, 10278.794424111626, 10316.742081447723, 11033.613823516138, 11099.833502497631, 11297.26567773092, 11457.885421145682, 11498.367589782642, 10228.0, 10294.691159265234, 10471.371717696962, 10528.473576321, 10682.198835087243, 10566.524506397076, 10618.362898225821, 10619.097376723064, 10631.468426578795, 10644.680659580225, 10407.0, 10473.21450891171, 10480.68557943263, 10489.213309001703, 10501.632442864464, 8913.420627659441, 9136.6802161924, 9163.0, 9198.402103863227, 9283.675071372469, 8849.646014159362, 9077.364584479023, 9110.863337050083, 9168.954155229449, 9304.58129384193], "value": 6783.869312425426, "unit": "score"}], "name": "dromaeo_css", "value": 7256.674991940871, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 168.0, 176.0, 124.0, 110.0, 156.0, 113.0, 173.0, 172.0, 126.0], "value": 145.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 149.0, 145.0, 186.0, 151.0, 156.0, 121.0, 154.0, 157.0, 120.0], "value": 149.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 200.0, 199.0, 199.0, 198.0, 197.0, 194.0, 193.0, 205.0, 196.0], "value": 197.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 78.0, 105.0, 183.0, 82.0, 81.0, 85.0, 57.0, 124.0, 57.0], "value": 91.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 122.0, 154.0, 76.0, 77.0, 98.0, 121.0, 63.0, 105.0, 106.0], "value": 100.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 86.0, 105.0, 110.0, 90.0, 102.0, 113.0, 85.0], "value": 95.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 94.0, 96.0, 94.0, 95.0, 94.0, 116.0, 96.0, 94.0], "value": 97.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 136.0, 144.0, 140.0, 87.0, 90.0, 95.0, 89.0, 90.0, 90.0], "value": 105.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 66.0, 65.0, 69.0, 66.0, 67.0, 86.0, 64.0, 61.0], "value": 67.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 71.0, 65.0, 80.0, 79.0, 70.0, 68.0, 67.0, 69.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 71.0, 85.0, 106.0, 107.0, 106.0, 107.0, 85.0, 85.0], "value": 91.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 148.0, 136.0, 135.0, 136.0, 137.0, 135.0, 173.0, 136.0], "value": 141.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 171.0, 169.0, 172.0, 179.0, 172.0, 171.0, 169.0, 170.0, 166.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 69.0, 70.0, 89.0, 101.0, 61.0, 97.0, 102.0, 100.0], "value": 82.8, "unit": "score"}], "name": "kraken", "value": 1605.8, "alertThreshold": 2.0}]} 13:32:50 INFO - Return code: 0 13:32:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:32:50 ERROR - # TBPL SUCCESS # 13:32:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:32:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:32:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:32:50 INFO - 2016-04-26 13:32:50.358 screenresolution[1680:11198] starting screenresolution argv=screenresolution get 13:32:50 INFO - 2016-04-26 13:32:50.360 screenresolution[1680:11198] Display 0: 1600x1200x32@60 13:32:50 INFO - 2016-04-26 13:32:50.366 screenresolution[1681:11201] starting screenresolution argv=screenresolution list 13:32:50 INFO - Available Modes on Display 0 13:32:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:32:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:32:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:32:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:32:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:32:50 INFO - Intel Iris: 13:32:50 INFO - Chipset Model: Intel Iris 13:32:50 INFO - Type: GPU 13:32:50 INFO - Bus: Built-In 13:32:50 INFO - VRAM (Dynamic, Max): 1536 MB 13:32:50 INFO - Vendor: Intel (0x8086) 13:32:50 INFO - Device ID: 0x0a2e 13:32:50 INFO - Revision ID: 0x0009 13:32:50 INFO - Displays: 13:32:50 INFO - Display: 13:32:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:32:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:32:50 INFO - Display Serial Number: 1600x1200 60 13:32:50 INFO - Main Display: Yes 13:32:50 INFO - Mirror: Off 13:32:50 INFO - Online: Yes 13:32:50 INFO - Rotation: Supported 13:32:50 INFO - Return code: 0 13:32:50 INFO - Running post-action listener: _resource_record_post_action 13:32:50 INFO - Running post-run listener: _resource_record_post_run 13:32:51 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 32161280; Write bytes: 656612864; Read time: 323; Write time: 2307 13:32:51 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 351035904; Write bytes: 345569280; Read time: 27322; Write time: 942 13:32:51 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 30367232; Write bytes: 300254720; Read time: 303; Write time: 1329 13:32:51 INFO - Running post-run listener: _upload_blobber_files 13:32:51 INFO - Blob upload gear active. 13:32:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:32:51 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:32:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:32:52 INFO - (blobuploader) - INFO - Open directory for files ... 13:32:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:32:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:32:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:32:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:32:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:32:53 INFO - (blobuploader) - INFO - Done attempting. 13:32:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:32:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:32:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:32:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:32:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:32:54 INFO - (blobuploader) - INFO - Done attempting. 13:32:54 INFO - (blobuploader) - INFO - Iteration through files over. 13:32:54 INFO - Return code: 0 13:32:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:32:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:32:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0aad31a722ae3971ffacbd893040dba7d859c5c46073908de98eb841416c1ae88d72d4bf969a737092d516eccd71e461f7abb6721c57b9851de8328be02604e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b8f4006c8c8e1629da7a69957be9217dd292c9e2d54c42f32ccc4b5c214854a6a093b3285c238e59ee95b6018c4c5d8b3b2a4a4fd32c6a6244569c2575d2cae9"} 13:32:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:32:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:32:54 INFO - Contents: 13:32:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0aad31a722ae3971ffacbd893040dba7d859c5c46073908de98eb841416c1ae88d72d4bf969a737092d516eccd71e461f7abb6721c57b9851de8328be02604e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b8f4006c8c8e1629da7a69957be9217dd292c9e2d54c42f32ccc4b5c214854a6a093b3285c238e59ee95b6018c4c5d8b3b2a4a4fd32c6a6244569c2575d2cae9"} 13:32:54 INFO - Running post-run listener: copy_logs_to_upload_dir 13:32:54 INFO - Copying logs to upload dir... 13:32:54 INFO - mkdir: /builds/slave/test/build/upload/logs 13:32:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1343.418949 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 23 secs) (at 2016-04-26 13:32:54.618227) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 13:32:54.625538) ========= 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.bF6HU3wzmT/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.MGPO4Gwepn/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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/c0aad31a722ae3971ffacbd893040dba7d859c5c46073908de98eb841416c1ae88d72d4bf969a737092d516eccd71e461f7abb6721c57b9851de8328be02604e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b8f4006c8c8e1629da7a69957be9217dd292c9e2d54c42f32ccc4b5c214854a6a093b3285c238e59ee95b6018c4c5d8b3b2a4a4fd32c6a6244569c2575d2cae9"} build_url:https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010590 build_url: 'https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0aad31a722ae3971ffacbd893040dba7d859c5c46073908de98eb841416c1ae88d72d4bf969a737092d516eccd71e461f7abb6721c57b9851de8328be02604e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b8f4006c8c8e1629da7a69957be9217dd292c9e2d54c42f32ccc4b5c214854a6a093b3285c238e59ee95b6018c4c5d8b3b2a4a4fd32c6a6244569c2575d2cae9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 13:32:54.655744) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:32:54.656077) ========= 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.bF6HU3wzmT/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.MGPO4Gwepn/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.005167 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:32:54.705296) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 13:32:54.705621) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 13:32:54.706064) ========= ========= Total master_lag: 1.70 =========