builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0163 starttime: 1460684394.24 results: success (0) buildid: 20160414165245 builduid: 0b042bda1e42440683754078110f2496 revision: e595db332894e0d6a2bacd793f792d56ce3960d1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 18:39:54.241705) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 18:39:54.242149) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-14 18:39:54.242444) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-14 18:39:54.269379) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 18:39:54.269692) ========= 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.hdBR7BKmn2/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.TUyWCihfm3/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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-14 18:39:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 133M=0s 2016-04-14 18:39:54 (133 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113386 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 18:39:54.402854) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 18:39:54.403249) ========= 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.hdBR7BKmn2/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.TUyWCihfm3/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.020744 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 18:39:54.452213) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-14 18:39:54.455579) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e595db332894e0d6a2bacd793f792d56ce3960d1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev e595db332894e0d6a2bacd793f792d56ce3960d1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hdBR7BKmn2/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.TUyWCihfm3/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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-14 18:39:54,529 truncating revision to first 12 chars 2016-04-14 18:39:54,529 Setting DEBUG logging. 2016-04-14 18:39:54,529 attempt 1/10 2016-04-14 18:39:54,529 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e595db332894?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-14 18:39:56,244 unpacking tar archive at: fx-team-e595db332894/testing/mozharness/ program finished with exit code 0 elapsedTime=1.994797 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-14 18:39:56.466543) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 18:39:56.466861) ========= script_repo_revision: e595db332894e0d6a2bacd793f792d56ce3960d1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 18:39:56.467245) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 18:39:56.470538) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 18:39:56.488456) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-04-14 18:39:56.488723) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hdBR7BKmn2/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.TUyWCihfm3/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 18:39:56 INFO - MultiFileLogger online at 20160414 18:39:56 in /builds/slave/test 18:39:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:39:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:39:56 INFO - {'append_to_log': False, 18:39:56 INFO - 'base_work_dir': '/builds/slave/test', 18:39:56 INFO - 'blob_upload_branch': 'Fx-Team', 18:39:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:39:56 INFO - 'branch': 'Fx-Team', 18:39:56 INFO - 'buildbot_json_path': 'buildprops.json', 18:39:56 INFO - 'config_files': ('talos/mac_config.py',), 18:39:56 INFO - 'default_actions': ('clobber', 18:39:56 INFO - 'read-buildbot-config', 18:39:56 INFO - 'download-and-extract', 18:39:56 INFO - 'populate-webroot', 18:39:56 INFO - 'create-virtualenv', 18:39:56 INFO - 'install', 18:39:56 INFO - 'run-tests'), 18:39:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:39:56 INFO - 'download_minidump_stackwalk': True, 18:39:56 INFO - 'download_symbols': 'ondemand', 18:39:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:39:56 INFO - 'tooltool.py': '/tools/tooltool.py', 18:39:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:39:56 INFO - '/tools/misc-python/virtualenv.py')}, 18:39:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:39:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:39:56 INFO - 'installer_path': 'installer.exe', 18:39:56 INFO - 'log_level': 'info', 18:39:56 INFO - 'log_name': 'talos', 18:39:56 INFO - 'log_to_console': True, 18:39:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:39:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:39:56 INFO - 'opt_config_files': (), 18:39:56 INFO - 'pip_index': False, 18:39:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:39:56 INFO - 'cmd': ('bash', 18:39:56 INFO - '-c', 18:39:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:39:56 INFO - 'enabled': True, 18:39:56 INFO - 'halt_on_failure': False, 18:39:56 INFO - 'name': 'check_screen_resolution'},), 18:39:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:39:56 INFO - 'cmd': ('bash', 18:39:56 INFO - '-c', 18:39:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:39:56 INFO - 'enabled': True, 18:39:56 INFO - 'halt_on_failure': False, 18:39:56 INFO - 'name': 'check_screen_resolution'},), 18:39:56 INFO - 'run_cmd_checks_enabled': True, 18:39:56 INFO - 'sps_profile': False, 18:39:56 INFO - 'sps_profile_interval': 0, 18:39:56 INFO - 'suite': 'dromaeojs-e10s', 18:39:56 INFO - 'system_bits': '32', 18:39:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:39:56 INFO - 'title': 't-yosemite-r7-0163', 18:39:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:39:56 INFO - 'use_talos_json': True, 18:39:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:39:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:39:56 INFO - 'work_dir': 'build'} 18:39:56 INFO - ##### 18:39:56 INFO - ##### Running clobber step. 18:39:56 INFO - ##### 18:39:56 INFO - Running pre-action listener: _resource_record_pre_action 18:39:56 INFO - Running main action method: clobber 18:39:56 INFO - rmtree: /builds/slave/test/build 18:39:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:39:58 INFO - Running post-action listener: _resource_record_post_action 18:39:58 INFO - ##### 18:39:58 INFO - ##### Running read-buildbot-config step. 18:39:58 INFO - ##### 18:39:58 INFO - Running pre-action listener: _resource_record_pre_action 18:39:58 INFO - Running main action method: read_buildbot_config 18:39:58 INFO - Using buildbot properties: 18:39:58 INFO - { 18:39:58 INFO - "project": "", 18:39:58 INFO - "product": "firefox", 18:39:58 INFO - "script_repo_revision": "production", 18:39:58 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 18:39:58 INFO - "repository": "", 18:39:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 18:39:58 INFO - "buildid": "20160414165245", 18:39:58 INFO - "pgo_build": "False", 18:39:58 INFO - "basedir": "/builds/slave/test", 18:39:58 INFO - "buildnumber": 690, 18:39:58 INFO - "slavename": "t-yosemite-r7-0163", 18:39:58 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:39:58 INFO - "platform": "yosemite_r7", 18:39:58 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 18:39:58 INFO - "branch": "fx-team", 18:39:58 INFO - "repo_path": "integration/fx-team", 18:39:58 INFO - "revision": "e595db332894e0d6a2bacd793f792d56ce3960d1", 18:39:58 INFO - "stage_platform": "macosx64", 18:39:58 INFO - "builduid": "0b042bda1e42440683754078110f2496", 18:39:58 INFO - "slavebuilddir": "test" 18:39:58 INFO - } 18:39:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 18:39:58 INFO - Running post-action listener: _resource_record_post_action 18:39:58 INFO - ##### 18:39:58 INFO - ##### Running download-and-extract step. 18:39:58 INFO - ##### 18:39:58 INFO - Running pre-action listener: _resource_record_pre_action 18:39:58 INFO - Running main action method: download_and_extract 18:39:58 INFO - mkdir: /builds/slave/test/build/tests 18:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:39:58 INFO - https://queue.taskcluster.net/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:39:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 18:39:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 18:39:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 18:39:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 18:39:59 INFO - Downloaded 1199 bytes. 18:39:59 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 18:39:59 INFO - Using the following test package requirements: 18:39:59 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 18:39:59 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:39:59 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 18:39:59 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:39:59 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 18:39:59 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:39:59 INFO - u'jsshell-mac.zip'], 18:39:59 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:39:59 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 18:39:59 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 18:39:59 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:39:59 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 18:39:59 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:39:59 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 18:39:59 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:39:59 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 18:39:59 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:39:59 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 18:39:59 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:39:59 INFO - https://queue.taskcluster.net/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:39:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:39:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:39:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:39:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:40:00 INFO - Downloaded 18031153 bytes. 18:40:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:40:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:40:04 INFO - Return code: 0 18:40:04 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:40: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')]} 18:40:04 INFO - https://queue.taskcluster.net/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:40:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:40:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:40:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:40:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:40:05 INFO - Downloaded 18031153 bytes. 18:40:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:40:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:40:08 INFO - Return code: 0 18:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:08 INFO - https://queue.taskcluster.net/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:40:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 18:40:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 18:40:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 18:40:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:40:10 INFO - Downloaded 10841076 bytes. 18:40:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:40:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:40:10 INFO - Return code: 0 18:40:10 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')]} 18:40:10 INFO - https://queue.taskcluster.net/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:40:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 18:40:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 18:40:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 18:40:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 18:40:14 INFO - Downloaded 109833593 bytes. 18:40:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 18:40:14 INFO - mkdir: /builds/slave/test/properties 18:40:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:40:14 INFO - Writing to file /builds/slave/test/properties/build_url 18:40:14 INFO - Contents: 18:40:14 INFO - build_url:https://queue.taskcluster.net/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 18:40:15 INFO - Running post-action listener: _resource_record_post_action 18:40:15 INFO - Running post-action listener: set_extra_try_arguments 18:40:15 INFO - ##### 18:40:15 INFO - ##### Running populate-webroot step. 18:40:15 INFO - ##### 18:40:15 INFO - Running pre-action listener: _resource_record_pre_action 18:40:15 INFO - Running main action method: populate_webroot 18:40:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:40:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:40:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:40:15 INFO - '--tppagecycles', 18:40:15 INFO - '7'], 18:40:15 INFO - 'tests': ['tsvgm']}}, 18:40:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:40:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:40:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:40:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:40:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:40:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:40:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:15 INFO - 'talos_options': ['--disable-e10s'], 18:40:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:40:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:40:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:15 INFO - 'talos_options': ['--disable-e10s'], 18:40:15 INFO - 'tests': ['damp', 'tps']}, 18:40:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:15 INFO - 'tests': ['damp', 'tps']}, 18:40:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:40:15 INFO - 'tests': ['dromaeo_dom']}, 18:40:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:40:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:40:15 INFO - 'tests': ['a11yr', 18:40:15 INFO - 'ts_paint', 18:40:15 INFO - 'tpaint', 18:40:15 INFO - 'sessionrestore', 18:40:15 INFO - 'sessionrestore_no_auto_restore', 18:40:15 INFO - 'tabpaint']}, 18:40:15 INFO - 'other-e10s': {'tests': ['a11yr', 18:40:15 INFO - 'ts_paint', 18:40:15 INFO - 'tpaint', 18:40:15 INFO - 'sessionrestore', 18:40:15 INFO - 'sessionrestore_no_auto_restore', 18:40:15 INFO - 'tabpaint']}, 18:40:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:40:15 INFO - 'ts_paint', 18:40:15 INFO - 'tpaint', 18:40:15 INFO - 'sessionrestore', 18:40:15 INFO - 'sessionrestore_no_auto_restore', 18:40:15 INFO - 'tabpaint']}, 18:40:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:40:15 INFO - 'ts_paint', 18:40:15 INFO - 'tpaint', 18:40:15 INFO - 'sessionrestore', 18:40:15 INFO - 'sessionrestore_no_auto_restore', 18:40:15 INFO - 'tabpaint']}, 18:40:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:40:15 INFO - 'tests': ['a11yr', 18:40:15 INFO - 'ts_paint', 18:40:15 INFO - 'tpaint', 18:40:15 INFO - 'sessionrestore', 18:40:15 INFO - 'sessionrestore_no_auto_restore', 18:40:15 INFO - 'tabpaint']}, 18:40:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:40:15 INFO - 'tests': ['a11yr', 18:40:15 INFO - 'ts_paint', 18:40:15 INFO - 'tpaint', 18:40:15 INFO - 'sessionrestore', 18:40:15 INFO - 'sessionrestore_no_auto_restore', 18:40:15 INFO - 'tabpaint']}, 18:40:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:40:15 INFO - 'tests': ['tsvgx', 18:40:15 INFO - 'tsvgr_opacity', 18:40:15 INFO - 'tart', 18:40:15 INFO - 'tscrollx', 18:40:15 INFO - 'cart']}, 18:40:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:40:15 INFO - 'tsvgr_opacity', 18:40:15 INFO - 'tart', 18:40:15 INFO - 'tscrollx', 18:40:15 INFO - 'cart']}, 18:40:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:15 INFO - 'talos_options': ['--disable-e10s'], 18:40:15 INFO - 'tests': ['tp5o']}, 18:40:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:15 INFO - 'tests': ['tp5o']}, 18:40:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:15 INFO - 'talos_options': ['--disable-e10s', 18:40:15 INFO - '--xperf_path', 18:40:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:40:15 INFO - 'tests': ['tp5n']}, 18:40:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:15 INFO - 'talos_options': ['--xperf_path', 18:40:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:40:15 INFO - 'tests': ['tp5n']}}, 18:40:15 INFO - 'talos.zip': {'path': '', 18:40:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:40:15 INFO - Running post-action listener: _resource_record_post_action 18:40:15 INFO - ##### 18:40:15 INFO - ##### Running create-virtualenv step. 18:40:15 INFO - ##### 18:40:15 INFO - Running pre-action listener: _resource_record_pre_action 18:40:15 INFO - Running main action method: create_virtualenv 18:40:15 INFO - Creating virtualenv /builds/slave/test/build/venv 18:40:15 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 18:40:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:40:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:40:15 INFO - Using real prefix '/tools/python27' 18:40:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:40:16 INFO - Installing distribute.............................................................................................................................................................................................done. 18:40:19 INFO - Installing pip.................done. 18:40:19 INFO - Return code: 0 18:40:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:19 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 0x10ade1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda9a5a0510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac80e00>, '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 0x10aae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac7bb20>, '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.hdBR7BKmn2/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.TUyWCihfm3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 18:40:19 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 18:40:19 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 18:40:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdBR7BKmn2/Render', 18:40:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:19 INFO - 'HOME': '/Users/cltbld', 18:40:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:19 INFO - 'LOGNAME': 'cltbld', 18:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:19 INFO - 'MOZ_NO_REMOTE': '1', 18:40:19 INFO - 'NO_EM_RESTART': '1', 18:40:19 INFO - 'PAGER': '/bin/cat', 18:40:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:19 INFO - 'PWD': '/builds/slave/test', 18:40:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:19 INFO - 'SHELL': '/bin/bash', 18:40:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TUyWCihfm3/Listeners', 18:40:19 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 18:40:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:19 INFO - 'USER': 'cltbld', 18:40:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:19 INFO - 'XPC_FLAGS': '0x0', 18:40:19 INFO - 'XPC_SERVICE_NAME': '0', 18:40:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:19 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 18:40:19 INFO - Cleaning up... 18:40:19 INFO - Return code: 0 18:40:19 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:19 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 0x10ade1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda9a5a0510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac80e00>, '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 0x10aae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac7bb20>, '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.hdBR7BKmn2/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.TUyWCihfm3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 18:40:19 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 18:40:19 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 18:40:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdBR7BKmn2/Render', 18:40:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:19 INFO - 'HOME': '/Users/cltbld', 18:40:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:19 INFO - 'LOGNAME': 'cltbld', 18:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:19 INFO - 'MOZ_NO_REMOTE': '1', 18:40:19 INFO - 'NO_EM_RESTART': '1', 18:40:19 INFO - 'PAGER': '/bin/cat', 18:40:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:19 INFO - 'PWD': '/builds/slave/test', 18:40:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:19 INFO - 'SHELL': '/bin/bash', 18:40:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TUyWCihfm3/Listeners', 18:40:19 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 18:40:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:19 INFO - 'USER': 'cltbld', 18:40:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:19 INFO - 'XPC_FLAGS': '0x0', 18:40:19 INFO - 'XPC_SERVICE_NAME': '0', 18:40:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:19 INFO - Downloading/unpacking psutil>=0.7.1 18:40:19 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 18:40:19 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 18:40:19 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 18:40:19 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 18:40:19 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 18:40:19 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 18:40:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:40:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:40:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:40:22 INFO - Installing collected packages: psutil 18:40:22 INFO - Running setup.py install for psutil 18:40:22 INFO - building 'psutil._psutil_osx' extension 18:40:22 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 18:40:22 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 18:40:22 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 18:40:22 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 18:40:22 INFO - building 'psutil._psutil_posix' extension 18:40:22 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 18:40:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:40:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:40:22 INFO - ^ 18:40:22 INFO - 1 warning generated. 18:40:22 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 18:40:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:40:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:40:22 INFO - Successfully installed psutil 18:40:22 INFO - Cleaning up... 18:40:23 INFO - Return code: 0 18:40:23 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:40:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:23 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 0x10ade1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda9a5a0510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac80e00>, '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 0x10aae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac7bb20>, '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.hdBR7BKmn2/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.TUyWCihfm3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 18:40:23 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 18:40:23 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 18:40:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdBR7BKmn2/Render', 18:40:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:23 INFO - 'HOME': '/Users/cltbld', 18:40:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:23 INFO - 'LOGNAME': 'cltbld', 18:40:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:23 INFO - 'MOZ_NO_REMOTE': '1', 18:40:23 INFO - 'NO_EM_RESTART': '1', 18:40:23 INFO - 'PAGER': '/bin/cat', 18:40:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:23 INFO - 'PWD': '/builds/slave/test', 18:40:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:23 INFO - 'SHELL': '/bin/bash', 18:40:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TUyWCihfm3/Listeners', 18:40:23 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 18:40:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:23 INFO - 'USER': 'cltbld', 18:40:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:23 INFO - 'XPC_FLAGS': '0x0', 18:40:23 INFO - 'XPC_SERVICE_NAME': '0', 18:40:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:23 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:40: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 18:40: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 18:40: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 18:40: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 18:40: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 18:40: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 18:40:25 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:40:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:40:25 INFO - Installing collected packages: mozsystemmonitor 18:40:25 INFO - Running setup.py install for mozsystemmonitor 18:40:25 INFO - Successfully installed mozsystemmonitor 18:40:25 INFO - Cleaning up... 18:40:26 INFO - Return code: 0 18:40:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:40:26 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')]} 18:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:26 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')]} 18:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:26 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 0x10ade1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda9a5a0510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac80e00>, '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 0x10aae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac7bb20>, '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.hdBR7BKmn2/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.TUyWCihfm3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 18:40:26 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 18:40:26 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 18:40:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdBR7BKmn2/Render', 18:40:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:26 INFO - 'HOME': '/Users/cltbld', 18:40:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:26 INFO - 'LOGNAME': 'cltbld', 18:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:26 INFO - 'MOZ_NO_REMOTE': '1', 18:40:26 INFO - 'NO_EM_RESTART': '1', 18:40:26 INFO - 'PAGER': '/bin/cat', 18:40:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:26 INFO - 'PWD': '/builds/slave/test', 18:40:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:26 INFO - 'SHELL': '/bin/bash', 18:40:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TUyWCihfm3/Listeners', 18:40:26 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 18:40:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:26 INFO - 'USER': 'cltbld', 18:40:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:26 INFO - 'XPC_FLAGS': '0x0', 18:40:26 INFO - 'XPC_SERVICE_NAME': '0', 18:40:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:26 INFO - Downloading/unpacking blobuploader==1.2.4 18:40:26 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 18:40:26 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 18:40:26 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 18:40:26 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 18:40:26 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 18:40:26 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 18:40:28 INFO - Downloading blobuploader-1.2.4.tar.gz 18:40:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:40:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:40:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:40:29 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 18:40:29 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 18:40:29 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 18:40:29 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 18:40:29 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 18:40:29 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 18:40:29 INFO - Downloading docopt-0.6.1.tar.gz 18:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:40:30 INFO - Installing collected packages: blobuploader, requests, docopt 18:40:30 INFO - Running setup.py install for blobuploader 18:40:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:40:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:40:30 INFO - Running setup.py install for requests 18:40:30 INFO - Running setup.py install for docopt 18:40:30 INFO - Successfully installed blobuploader requests docopt 18:40:30 INFO - Cleaning up... 18:40:30 INFO - Return code: 0 18:40:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:40: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')]} 18:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40: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')]} 18:40:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:30 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 0x10ade1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda9a5a0510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac80e00>, '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 0x10aae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac7bb20>, '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.hdBR7BKmn2/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.TUyWCihfm3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 18:40:30 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 18:40:30 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 18:40:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdBR7BKmn2/Render', 18:40:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:30 INFO - 'HOME': '/Users/cltbld', 18:40:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:30 INFO - 'LOGNAME': 'cltbld', 18:40:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:30 INFO - 'MOZ_NO_REMOTE': '1', 18:40:30 INFO - 'NO_EM_RESTART': '1', 18:40:30 INFO - 'PAGER': '/bin/cat', 18:40:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:30 INFO - 'PWD': '/builds/slave/test', 18:40:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:30 INFO - 'SHELL': '/bin/bash', 18:40:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TUyWCihfm3/Listeners', 18:40:30 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 18:40:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:30 INFO - 'USER': 'cltbld', 18:40:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:30 INFO - 'XPC_FLAGS': '0x0', 18:40:30 INFO - 'XPC_SERVICE_NAME': '0', 18:40:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:40:31 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-kc3Ohh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:40:31 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-Y2jEj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:40:31 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-6Wc1kt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:40:31 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-UUkxI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:40:31 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-NY_6eD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:40:31 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-OJ3S3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:40:31 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-In5Gv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:40:31 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-GeFGOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:40:32 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-RKtGzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:40:32 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-fJ47Lk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:40:32 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-Ku5UKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:40:32 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-tyoLCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:40:32 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-I2wUSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:40:32 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-mR5E4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:40:32 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-nQqTZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:40:32 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-M2gWNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:40:32 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-Nm4WND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:40:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:40:32 INFO - Running setup.py install for manifestparser 18:40:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:40:33 INFO - Running setup.py install for mozcrash 18:40:33 INFO - Running setup.py install for mozdebug 18:40:33 INFO - Running setup.py install for mozdevice 18:40:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:40:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:40:33 INFO - Running setup.py install for mozfile 18:40:33 INFO - Running setup.py install for mozhttpd 18:40:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:40:33 INFO - Running setup.py install for mozinfo 18:40:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Running setup.py install for mozInstall 18:40:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Running setup.py install for mozleak 18:40:34 INFO - Running setup.py install for mozlog 18:40:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Running setup.py install for moznetwork 18:40:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Running setup.py install for mozprocess 18:40:34 INFO - Running setup.py install for mozprofile 18:40:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Running setup.py install for mozrunner 18:40:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:40:35 INFO - Running setup.py install for mozscreenshot 18:40:35 INFO - Running setup.py install for moztest 18:40:35 INFO - Running setup.py install for mozversion 18:40:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:40:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:40:35 INFO - Cleaning up... 18:40:35 INFO - Return code: 0 18:40:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:40: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')]} 18:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40: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')]} 18:40:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:35 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 0x10ade1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda9a5a0510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac80e00>, '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 0x10aae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac7bb20>, '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.hdBR7BKmn2/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.TUyWCihfm3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 18:40:35 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 18:40:35 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 18:40:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdBR7BKmn2/Render', 18:40:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:35 INFO - 'HOME': '/Users/cltbld', 18:40:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:35 INFO - 'LOGNAME': 'cltbld', 18:40:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:35 INFO - 'MOZ_NO_REMOTE': '1', 18:40:35 INFO - 'NO_EM_RESTART': '1', 18:40:35 INFO - 'PAGER': '/bin/cat', 18:40:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:35 INFO - 'PWD': '/builds/slave/test', 18:40:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:35 INFO - 'SHELL': '/bin/bash', 18:40:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TUyWCihfm3/Listeners', 18:40:35 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 18:40:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:35 INFO - 'USER': 'cltbld', 18:40:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:35 INFO - 'XPC_FLAGS': '0x0', 18:40:35 INFO - 'XPC_SERVICE_NAME': '0', 18:40:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:40:35 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-mN2xMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:40:35 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)) 18:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:40:35 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-Za6faB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:40:36 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-VxoIlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:40:36 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-UefMyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:40:36 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-3H3iKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:40:36 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-uPsxuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:40:36 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-twJ63O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:40:36 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-KjwLXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:40:36 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-RiVc_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:40:36 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-h46_sX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:40:36 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-5w6Qxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:40:36 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-DcfPgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:40:37 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)) 18:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:40:37 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-qBVRCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:40:37 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)) 18:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:40:37 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-CEpeQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:40:37 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)) 18:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:40:37 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-zxQZyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:40:37 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)) 18:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:40:37 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-Gzgvee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:40:37 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)) 18:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:40:37 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-6_NARz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:40:37 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)) 18:40:37 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)) 18:40:37 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)) 18:40:37 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)) 18:40:37 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)) 18:40:37 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)) 18:40:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:40 INFO - Downloading blessings-1.6.tar.gz 18:40:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:40:40 INFO - Installing collected packages: blessings 18:40:40 INFO - Running setup.py install for blessings 18:40:40 INFO - Successfully installed blessings 18:40:40 INFO - Cleaning up... 18:40:40 INFO - Return code: 0 18:40:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:40:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:40:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:40:40 INFO - Reading from file tmpfile_stdout 18:40:40 INFO - Current package versions: 18:40:40 INFO - blessings == 1.6 18:40:40 INFO - blobuploader == 1.2.4 18:40:40 INFO - docopt == 0.6.1 18:40:40 INFO - manifestparser == 1.1 18:40:40 INFO - mozInstall == 1.12 18:40:40 INFO - mozcrash == 0.17 18:40:40 INFO - mozdebug == 0.1 18:40:40 INFO - mozdevice == 0.48 18:40:40 INFO - mozfile == 1.2 18:40:40 INFO - mozhttpd == 0.7 18:40:40 INFO - mozinfo == 0.9 18:40:40 INFO - mozleak == 0.1 18:40:40 INFO - mozlog == 3.1 18:40:40 INFO - moznetwork == 0.27 18:40:40 INFO - mozprocess == 0.22 18:40:40 INFO - mozprofile == 0.28 18:40:40 INFO - mozrunner == 6.11 18:40:40 INFO - mozscreenshot == 0.1 18:40:40 INFO - mozsystemmonitor == 0.0 18:40:40 INFO - moztest == 0.7 18:40:40 INFO - mozversion == 1.4 18:40:40 INFO - psutil == 3.1.1 18:40:40 INFO - requests == 1.2.3 18:40:40 INFO - wsgiref == 0.1.2 18:40:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:40:40 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')]} 18:40:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:40 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')]} 18:40:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:40 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 0x10ade1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda9a5a0510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac80e00>, '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 0x10aae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac7bb20>, '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.hdBR7BKmn2/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.TUyWCihfm3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 18:40:40 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 18:40:40 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 18:40:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdBR7BKmn2/Render', 18:40:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:40 INFO - 'HOME': '/Users/cltbld', 18:40:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:40 INFO - 'LOGNAME': 'cltbld', 18:40:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:40 INFO - 'MOZ_NO_REMOTE': '1', 18:40:40 INFO - 'NO_EM_RESTART': '1', 18:40:40 INFO - 'PAGER': '/bin/cat', 18:40:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:40 INFO - 'PWD': '/builds/slave/test', 18:40:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:40 INFO - 'SHELL': '/bin/bash', 18:40:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TUyWCihfm3/Listeners', 18:40:40 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 18:40:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:40 INFO - 'USER': 'cltbld', 18:40:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:40 INFO - 'XPC_FLAGS': '0x0', 18:40:40 INFO - 'XPC_SERVICE_NAME': '0', 18:40:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:40 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)) 18:40:40 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)) 18:40:40 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)) 18:40:40 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)) 18:40:40 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)) 18:40:40 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)) 18:40:40 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)) 18:40:40 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)) 18:40:40 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)) 18:40:40 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)) 18:40:40 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)) 18:40:40 INFO - Cleaning up... 18:40:40 INFO - Return code: 0 18:40:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:40:40 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')]} 18:40:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:40 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')]} 18:40:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:40 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 0x10ade1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda9a5a0510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac80e00>, '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 0x10aae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac7bb20>, '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.hdBR7BKmn2/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.TUyWCihfm3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 18:40:40 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 18:40:40 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 18:40:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdBR7BKmn2/Render', 18:40:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:40 INFO - 'HOME': '/Users/cltbld', 18:40:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:40 INFO - 'LOGNAME': 'cltbld', 18:40:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:40 INFO - 'MOZ_NO_REMOTE': '1', 18:40:40 INFO - 'NO_EM_RESTART': '1', 18:40:40 INFO - 'PAGER': '/bin/cat', 18:40:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:40 INFO - 'PWD': '/builds/slave/test', 18:40:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:40 INFO - 'SHELL': '/bin/bash', 18:40:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TUyWCihfm3/Listeners', 18:40:40 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 18:40:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:40 INFO - 'USER': 'cltbld', 18:40:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:40 INFO - 'XPC_FLAGS': '0x0', 18:40:40 INFO - 'XPC_SERVICE_NAME': '0', 18:40:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:41 INFO - Downloading/unpacking jsonschema 18:40: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 18:40: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 18:40: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 18:40: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 18:40: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 18:40: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 18:40:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:40:43 INFO - Downloading/unpacking functools32 (from jsonschema) 18:40:43 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 18:40:43 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 18:40:43 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 18:40:43 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 18:40:43 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 18:40:43 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 18:40:43 INFO - Downloading functools32-3.2.3-2.tar.gz 18:40:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:40:44 INFO - warning: no files found matching '*.txt' 18:40:44 INFO - no previously-included directories found matching 'build' 18:40:44 INFO - no previously-included directories found matching 'dist' 18:40:44 INFO - no previously-included directories found matching '.git*' 18:40:44 INFO - Installing collected packages: jsonschema, functools32 18:40:44 INFO - Running setup.py install for functools32 18:40:44 INFO - warning: no files found matching '*.txt' 18:40:44 INFO - no previously-included directories found matching 'build' 18:40:44 INFO - no previously-included directories found matching 'dist' 18:40:44 INFO - no previously-included directories found matching '.git*' 18:40:44 INFO - Successfully installed jsonschema functools32 18:40:44 INFO - Cleaning up... 18:40:44 INFO - Return code: 0 18:40:44 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ade1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10adf1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda9a5a0510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac80e00>, '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 0x10aae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac7bb20>, '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.hdBR7BKmn2/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.TUyWCihfm3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 18:40:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:40:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:40:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdBR7BKmn2/Render', 18:40:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:44 INFO - 'HOME': '/Users/cltbld', 18:40:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:44 INFO - 'LOGNAME': 'cltbld', 18:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:44 INFO - 'MOZ_NO_REMOTE': '1', 18:40:44 INFO - 'NO_EM_RESTART': '1', 18:40:44 INFO - 'PAGER': '/bin/cat', 18:40:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:44 INFO - 'PWD': '/builds/slave/test', 18:40:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:44 INFO - 'SHELL': '/bin/bash', 18:40:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TUyWCihfm3/Listeners', 18:40:44 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 18:40:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:44 INFO - 'USER': 'cltbld', 18:40:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:44 INFO - 'XPC_FLAGS': '0x0', 18:40:44 INFO - 'XPC_SERVICE_NAME': '0', 18:40:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:44 INFO - Downloading/unpacking flake8 18:40:44 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 18:40:44 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 18:40:44 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 18:40:44 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 18:40:44 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 18:40:44 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 18:40:46 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:40:46 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:40:46 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 18:40:46 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 18:40:46 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 18:40:46 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 18:40:46 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 18:40:46 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 18:40:47 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:40:47 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:40:47 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 18:40:47 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 18:40:47 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 18:40:47 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 18:40:47 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 18:40:47 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 18:40:47 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:40:47 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 18:40:47 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 18:40:47 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 18:40:47 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 18:40:47 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 18:40:47 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 18:40:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:40:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:40:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:40:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:40:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:40:48 INFO - no previously-included directories found matching 'docs/_build' 18:40:48 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:40:48 INFO - Running setup.py install for pep8 18:40:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:40:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:40:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:40:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:40:48 INFO - no previously-included directories found matching 'docs/_build' 18:40:48 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:40:48 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:40:48 INFO - Cleaning up... 18:40:48 INFO - Return code: 0 18:40:48 INFO - Running post-action listener: _resource_record_post_action 18:40:48 INFO - Running post-action listener: _start_resource_monitoring 18:40:48 INFO - Starting resource monitoring. 18:40:48 INFO - ##### 18:40:48 INFO - ##### Running install step. 18:40:48 INFO - ##### 18:40:48 INFO - Running pre-action listener: _resource_record_pre_action 18:40:48 INFO - Running main action method: install 18:40:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:40:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:40:48 INFO - Reading from file tmpfile_stdout 18:40:48 INFO - Detecting whether we're running mozinstall >=1.0... 18:40:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:40:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:40:49 INFO - Reading from file tmpfile_stdout 18:40:49 INFO - Output received: 18:40:49 INFO - Usage: mozinstall [options] installer 18:40:49 INFO - Options: 18:40:49 INFO - -h, --help show this help message and exit 18:40:49 INFO - -d DEST, --destination=DEST 18:40:49 INFO - Directory to install application into. [default: 18:40:49 INFO - "/builds/slave/test"] 18:40:49 INFO - --app=APP Application being installed. [default: firefox] 18:40:49 INFO - mkdir: /builds/slave/test/build/application 18:40:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:40:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:41:22 INFO - Reading from file tmpfile_stdout 18:41:22 INFO - Output received: 18:41:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:41:22 INFO - Running post-action listener: _resource_record_post_action 18:41:22 INFO - ##### 18:41:22 INFO - ##### Running run-tests step. 18:41:22 INFO - ##### 18:41:22 INFO - Running pre-action listener: _resource_record_pre_action 18:41:22 INFO - Running main action method: run_tests 18:41:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:41:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:41:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:41:22 INFO - 2016-04-14 18:41:22.801 screenresolution[1944:17839] starting screenresolution argv=screenresolution get 18:41:22 INFO - 2016-04-14 18:41:22.804 screenresolution[1944:17839] Display 0: 1600x1200x32@60 18:41:22 INFO - 2016-04-14 18:41:22.810 screenresolution[1945:17841] starting screenresolution argv=screenresolution list 18:41:22 INFO - Available Modes on Display 0 18:41:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:41:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:41:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:41:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:41:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:41:23 INFO - Intel Iris: 18:41:23 INFO - Chipset Model: Intel Iris 18:41:23 INFO - Type: GPU 18:41:23 INFO - Bus: Built-In 18:41:23 INFO - VRAM (Dynamic, Max): 1536 MB 18:41:23 INFO - Vendor: Intel (0x8086) 18:41:23 INFO - Device ID: 0x0a2e 18:41:23 INFO - Revision ID: 0x0009 18:41:23 INFO - Displays: 18:41:23 INFO - Display: 18:41:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:41:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:41:23 INFO - Display Serial Number: 1600x1200 60 18:41:23 INFO - Main Display: Yes 18:41:23 INFO - Mirror: Off 18:41:23 INFO - Online: Yes 18:41:23 INFO - Rotation: Supported 18:41:23 INFO - Return code: 0 18:41:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:41:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:41:23 INFO - Python 2.7.3 18:41:23 INFO - Return code: 0 18:41:23 INFO - grabbing minidump binary from tooltool 18:41:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:41:23 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 0x10ac80e00>, '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 0x10aae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ac7bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:41:23 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 18:41:23 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 18:41:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:41:23 INFO - Return code: 0 18:41:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:41:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:41:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:41:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:41:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:41:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:41:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:41:24 INFO - Return code: 0 18:41:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--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 18:41:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0163 --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 18:41:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hdBR7BKmn2/Render', 18:41:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:41:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:41:24 INFO - 'HOME': '/Users/cltbld', 18:41:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:41:24 INFO - 'LOGNAME': 'cltbld', 18:41:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:41:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:41:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:24 INFO - 'MOZ_NO_REMOTE': '1', 18:41:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:41:24 INFO - 'NO_EM_RESTART': '1', 18:41:24 INFO - 'PAGER': '/bin/cat', 18:41:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:41:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:24 INFO - 'PWD': '/builds/slave/test', 18:41:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:41:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:41:24 INFO - 'SHELL': '/bin/bash', 18:41:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TUyWCihfm3/Listeners', 18:41:24 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 18:41:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:41:24 INFO - 'USER': 'cltbld', 18:41:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:41:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:41:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:24 INFO - 'XPC_FLAGS': '0x0', 18:41:24 INFO - 'XPC_SERVICE_NAME': '0', 18:41:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:41:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--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 18:41:24 INFO - mozversion application_buildid: 20160414165245 18:41:24 INFO - mozversion application_changeset: e595db332894e0d6a2bacd793f792d56ce3960d1 18:41:24 INFO - mozversion application_display_name: Nightly 18:41:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:41:24 INFO - mozversion application_name: Firefox 18:41:24 INFO - mozversion application_remotingname: firefox 18:41:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:41:24 INFO - mozversion application_vendor: Mozilla 18:41:24 INFO - mozversion application_version: 48.0a1 18:41:24 INFO - mozversion platform_buildid: 20160414165245 18:41:24 INFO - mozversion platform_changeset: e595db332894e0d6a2bacd793f792d56ce3960d1 18:41:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:41:24 INFO - mozversion platform_version: 48.0a1 18:41:24 INFO - using testdate: 1460684484 18:41:24 INFO - actual date: 1460684484 18:41:24 INFO - starting webserver on 'localhost:56074' 18:41:24 INFO - SUITE-START | Running 2 tests 18:41:24 INFO - TEST-START | dromaeo_css 18:41:24 INFO - operating with platform_type : mac_ 18:41:24 INFO - Initialising browser for dromaeo_css test... 18:41:24 INFO - TEST-INFO | started process 1963 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpDojMAV/profile http://localhost:56074/getInfo.html) 18:41:27 INFO - PROCESS | 1963 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 18:41:27 INFO - PROCESS | 1963 | __metrics Screen width/height:1600/1200 18:41:27 INFO - PROCESS | 1963 | colorDepth:24 18:41:27 INFO - PROCESS | 1963 | Browser inner width/height: 1024/689 18:41:27 INFO - PROCESS | 1963 | __metrics 18:41:27 ERROR - PROCESS | 1963 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 18:41:28 INFO - TEST-INFO | 1963: exit 0 18:41:28 INFO - Browser initialized. 18:41:28 INFO - Running cycle 1/1 for dromaeo_css test... 18:41:28 INFO - TEST-INFO | started process 1966 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpDojMAV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:41:40 INFO - PROCESS | 1966 | RSS: Main: 247988224 18:41:40 INFO - PROCESS | 1966 | 18:42:30 INFO - PROCESS | 1966 | 1460684550011 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] 18:44:41 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:56074/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56074/tests/dromaeo/cssquery-ext.html) 18:44:42 INFO - PROCESS | 1966 | RSS: Main: 263446528 18:44:42 INFO - PROCESS | 1966 | 18:47:44 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:56074/tests/dromaeo/cssquery-ext.html (next: http://localhost:56074/tests/dromaeo/cssquery-jquery.html) 18:47:45 INFO - PROCESS | 1966 | RSS: Main: 275300352 18:47:45 INFO - PROCESS | 1966 | 18:50:47 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:56074/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56074/tests/dromaeo/cssquery-mootools.html) 18:50:47 INFO - PROCESS | 1966 | RSS: Main: 277286912 18:50:47 INFO - PROCESS | 1966 | 18:53:49 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:56074/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56074/tests/dromaeo/cssquery-prototype.html) 18:53:50 INFO - PROCESS | 1966 | RSS: Main: 278794240 18:53:50 INFO - PROCESS | 1966 | 18:56:52 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:56074/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56074/tests/dromaeo/cssquery-yui.html) 18:56:53 INFO - PROCESS | 1966 | RSS: Main: 281726976 18:56:53 INFO - PROCESS | 1966 | 18:59:56 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:56074/tests/dromaeo/cssquery-yui.html (next: http://localhost:56074/tests/dromaeo/cssquery-dojo.html) 18:59:56 INFO - PROCESS | 1966 | RSS: Main: 282910720 18:59:56 INFO - PROCESS | 1966 | 18:59:56 INFO - PROCESS | 1966 | __start_tp_report 18:59:56 INFO - PROCESS | 1966 | _x_x_mozilla_page_load 18:59:56 INFO - PROCESS | 1966 | _x_x_mozilla_page_load_details 18:59:56 INFO - PROCESS | 1966 | |i|pagename|runs| 18:59:56 INFO - PROCESS | 1966 | |0;dojo.html;3408.9147771305716;3437.6734210250047;3503.264314493956;3508.736844736644;3511.262634846683;4644.953550464498;4892.899097703015;5173.663711858727;5602.551795856332;5665.716714164286;4651.674382793206;5680.602357834953;5711.343195532505;5770.04794208955;5828.009238429467;5081.491850814908;5143.074246635604;5197.194434862602;5207.323048003773;5323.547498462635;4167.374893765929;5212.947870521284;5240.082985477546;5252.264682944391;5317.415084340739;4130.669546436308;5091.949080509222;5096.821611243588;5184.766685499161;5187.7406129693745;5142.151544995071;5145.51117643827;5167.948320516784;5172.818951336685;5179.378474583036;4224.4930608327195;5105.259737338065;5127.128388173982;5149.588032957355;5179.922301165486;4871.098846713334;5063.569596584295;5515.034868897927;5535.474129957647;5646.26598542191;6158.876822463526;6172.969135154341;6187.164732761064;6213.285472170713;6226.972549529322;5008.649394542384;6115.240341084353;6125.9693701531205;6197.568738352054;6257.78097766576;5716.628419152825;5725.341585717654;5746.281714785652;5759.222504961776;5759.22250496186;6214.378562143749;6252.218472690914;6256.499480041586;6273.341299163613;6275.592086514362;4960.47914968923;6010.939890601126;6048.546359023091;6112.327643959161;6123.203983482208;6098.268207815002;6184.226971628546;6191.040388739752;6196.349383314687;6218;6062.181605483292;6138.386161383915;6161.599496032743;6186.628802271878;6217.595856269328;6036.456718895321;6104.7926496150985;6144.78493252734;6158.353372895871;6162.907556386658;4845.345878306454;4847.903041939141;4862.82980095695;4890.339804126468;4891.779869905791;4332.090261045153;4366.847160349373;4390.05613793036;4398.978005109954;4405.964598319392;5435.701036442954;5575.916361254584;5638.379778224392;5664.235328230636;5702.401247868997;6166.81499555014;6176.104464852571;6236.095766113888;6242.469390101892;6261.123442718023;5579.246801681721;5590.776368945197;5632.211490391349;5633.183188437653;5640.182173584807;5187.19598462239;5300.6024548158275;5350.491703288181;5351.09031464652;5405.324334458192;6256.812295631047;6267.46726528232;6285.9057114143325;6301.747930082929;6315.526335524854;6111.379037751267;6221.3156552778255;6241.375862413723;6255;6268.4671802897265;6278.4035516625845;6312.43188113072;6320.209973753281;6347.682615869096;6351.618902865843;5979.910301345571;6043.848903777265;6050.183225264533;6066.7269972850445;6070;5418.4557401142065;6078.969605151946;6107.114468402146;6170.074488826533;6192.59748116371;4782.378290822171;4801.447833499193;5098.005888851639;5997.970010149921;6146.9550176471;5129.692218466904;5869.236999190078;6044.063170208625;6044.455998959938;6109.450149486567;4708.929366059443;5792.189093526994;5833.300003999378;5865.4134586541;5883.61756485827;5114.849158939209;5431.995080910045;5736.397678243891;6111.694415279129;6121.938780612137;4415.646748260067;5258.290130832442;5506.284183056177;5949.940500594938;5977.820665380045 18:59:56 INFO - PROCESS | 1966 | |1;ext.html;523.829755329518;534.041395694728;569.9544036477084;578.8234588450521;582.0628787651881;36938.70714524992;37670.62329376706;37678.43482347767;37747.62252377476;37849.81075094628;31853.203669908205;38452.61547384525;38624;39198.804005980004;39207;9399.154076133163;9585.760355991086;9596.568154433033;9596.904030959671;9621.470819104981;9491.905080949206;9576.377535460208;9637.469939153343;9661.758456038586;9669.95165024174;8269.173082691743;9617.278704097185;9697.224222062217;9704.514774261259;9757;9578.137967582916;9651.131398174162;9676.225901927828;9744.269179811543;9751.26865485088;7689.961550192269;9587;9609;9643.90356096437;9650.613975440974;31517.684823151703;37604;37809.43285850714;37956.861294160946;37958.62041379578;2995.8801647934056;3014.8341841198726;3118.09575223185;3165.778395512315;3445.207602251471;26778.50201943459;27155.321116972038;27530.724692753218;28081.57877631817;28120.859395703093;2689.3545549068276;2698.716634753362;2749.5188342040065;2884.8505417829074;2953.143588359373;281847;349260;351257;352044.4795552012;352898;35064.82467587646;61323.38676613177;61697;61909;62061.68969155216;34448.82775586106;34507.482387764205;35290.64709352925;35304.823475882455;35480.11299717476;14510.782338264935;14598.635034124232;14727.337269822883;14737.11577305365;14747.77878331826;12113.576024839089;12119.697007574881;12136.817947730791;12162.817557736642;12497.937510312571;7898.881516777253;7933.127355990837;7988.920110798817;7990.560519171385;8153.633086511121;2917.4602698500844;3349.4975753636663;3375.780574262665;3396.660333966633;3422.3839708852147;968.6367612145445;1013.7060252526685;1025.933314334566;1105.1545567640762;1134.444122380028;30208.244793880327;30258.54612180819;30462.39075218483;31195.68804311973;31606;1069.1446842525947;1085.1102096280974;1107.9474499225607;1110.1063643766845;1158.2760774515928;11377.715557110972;13820.861791382158;14036;14513.492027778977;15234.3906243749;549821;588639;589836;590274;593998;46562.53437465582;57611;58068;58460.707696462094;58617.12074318888;1428.3215472650409;1442.7691569348854;1480.985524915458;1485.472657206676;1504.50351384043;24844.627330589694;29268.56097158502;29602.703972959996;30002.399952001273;30002.699973000865;43721.12557748891;53399;53422;53520.464795351545;53643.46356536385;576170;578725;579799;580353;581035;21870.23454179096;26905;27484.725152749015;27509.587356189273;27527.724722752515;45699.31451028304;46210.30684539668;46573.3014004797;46826.53173468358;47349.7632511849;36712.449313260855;37900;38372.6162738369;38640;38754;37405;37596.6240337604;37633.81183094176;38202.23595528129;38643.613563865125;37075.887723368345;37571;37978.0505487365;38285.808570956964;38512;31190;32912.34175316528;33229;33298.5005224917;33952.320953581286;24471.87764061168;24912.750872491768;25026.74973250244;25206.97621384788;25441.872790635927 18:59:56 INFO - PROCESS | 1966 | |2;jquery.html;385.4160946166558;393.79325853926684;395.116913697885;397.3681845865075;401.34780980906027;14157;19606.62753607246;19998.90000549997;20270.18919243232;20338.186472541118;19782;19907.800921990776;20070.29753958608;21274.787252127473;21308;5875.500582450497;5938.613990090641;6025.276966763972;6109.35851735568;6121.357257487966;6026;6044.455998960093;6061.9393806062035;6064.3935606439445;6071.998120310143;5666.376698563155;5926.5258779297765;5952.4047595240345;6003.579749417542;6014.188084608588;5636.436356364372;5772.797952071657;5869.7652093916195;5914.88170236595;5954.106883967396;4913.803447862081;4919.262110683426;4923.08922849274;4927.827526036607;4977.203647416396;24892;25064.87208075641;25565.488690226277;25702.871485642638;26030.088946886768;2115.9153633854626;2121.9151233950624;2135.39141344716;2179.0194412514275;2544.440223150904;9211.539423028822;9893;9967.45179015154;10095.14191293741;14925.552233433014;1496.8959523218277;1701.7872765904262;1715.8284171582866;1736.2447335409138;1787.830156135165;113234;118890;118975;121400.39299803444;122880;9596.808063838684;11657.708557286136;11826.88173118275;11872.168948173718;11928.821067683992;15115.866310026791;15136.167510787012;15148.621284467976;15208.391664333303;15236.466723664618;7241.710331586783;8625.05124436324;8637.136286371438;8653.956730216434;8701.26039286655;8300.294474969694;8471.872921906177;8478.618462169217;8662.436941598902;8673.696420625374;4610.216263235258;4637.675362724644;4641.976790116028;4655.604273636779;4695.5069717679835;1946.7274581558593;1978.3768113044346;1985.1761518969754;1986.255154317131;1999.5001249687577;885.7121435533477;925.6945208081318;962.1629182611033;1047.1518070363031;1058.3544038136731;15243.3902643893;17035.91482042582;17512.29950801979;20132.39602811918;20229.898850505655;852.6589364254348;926.5182105305204;944.8535477001076;986.9735475105809;1024.990384471295;8236.546989915612;10201.081902628799;10566.894331056745;10620.362778233331;10641.467926603638;213223;569304;594253;614519;624210;101388;124206;125516.37241813733;126647;127585;423.40300176750634;452.60623257765957;452.86414075777134;1053.4679986606868;1073.173656284672;10239.590416383558;10363.768679406889;10771.838422423827;10787.568497260336;11159.665210043711;48406.757966209945;49924.00151997012;50417.739556511384;50541.241881371076;51695;64884.67557662181;67342.97971060876;73766.63116684383;74681.62659186669;76662;7113.57318560888;7984.283379073295;8104.149064348158;8116.350691944512;8247.917520824954;10159.695209143738;12229.082818788804;12276.631701048982;12412.81380779307;12680.23918564889;10492.370457772558;10581.576736930436;10694;11051.613193538187;11078.944605277244;10525.578976841147;10538.631147909786;10900.727481813017;10912.345259284468;11088.667339979813;10821.621243256448;11109.166812488935;11308.660740177807;11411.030062444785;11431.942840285745;7970.960145199237;8043.919560804317;8201.22088401601;8207.179282071746;8217;9982.450965196693;10961;11153.721156970816;11222.607208747655;11324.6036388726 18:59:56 INFO - PROCESS | 1966 | |3;mootools.html;1509.3358922074287;1594.210865621517;1765.6821772081028;1837.2742766607191;1921.2219051284235;7986.5607391593585;8502.787430314229;8619.956900215499;8868.645254189832;8874.023857375692;7221.205667376585;7383.556986580809;7741;8559.529225892573;8745.868811967826;2425.8787060646987;2449.155041510676;2450.3261603059123;2456.226288719057;2456.496418234258;2436.049940523197;2439.9146029888957;2462.384403899025;2467.370820440794;2477.826552141351;2303.7120359955006;2459.409741661997;2462.753724627541;2479.4917042006437;2489.9004039838383;2418.4316685578874;2434.47658753368;2441.8901149448316;2444.2300675287406;2457.3610861176135;2371.063429945162;2421.4551725861716;2429.2226487524;2445.7187423446353;2448.09420514409;8870.290376769908;8884.20042196199;8921.241694455979;8921.821563568692;8925.598348074287;5263.86840328991;5458.918116228259;5479.150731636602;5512.586556008276;5558.6942718150485;3594.4428613564937;3636.254567813592;3639.0720366306687;3647.252313275772;3678.4114541673202;1020.1736593359402;1033.1115240892825;1038.8182068138121;1050.669039252633;1066.5094056733913;11581.131415143813;11622.941885290604;11654.242474239147;11808.173427860052;11830.526778928832;4731.905361892743;4809.0862736080035;4839.661223714306;4932.802687892444;4962.727050012284;9848.704538863845;9899;9905.158061564704;9962.103410693073;10010.04904534068;4553.658475614342;5331.86670333237;5417.729113544307;5453.7818487260065;5459.808906688247;4444.444444444444;4772.689775164603;4853.80584776605;4913.287573301851;4946.579540739005;4049.6962727795535;4054.108096218827;4056.229316429928;4057.330540460791;4075.558884140811;1148.6783700563856;1346.9057165998481;1348.5347555093476;1358.1375826350338;1361.2445092973296;885.3448448148453;907.6414816147585;918.4856480371013;920.7683311197869;922.3589605224439;5853.443922827325;5872.412758724179;5927.525797936155;5953.166556682068;6003.219581454383;925.5788616179622;947.6825263536655;978.4667356290838;981.3375971219398;1014.3761586624321;3679.77921324716;3821.7706937583835;3846.673032792244;3853.6531712146043;3880.2239552089695;8665.220130188314;9258;10576.894231057591;10866.511007004703;10871.728206794893;9153.313501487415;9451.858222126673;10358.274920755475;10757.139428845674;10872.945635271932;8147.388945829087;8227.506349619161;8345;8348.165183481604;8434.873476897852;5628.212050313042;5708.57185711065;5732.885342293047;5828.329742079715;5887.293524777054;9894.901050989398;9972.451515166735;9985.201183905125;10007.249456290661;10045.347052441568;9665.855012174683;9866.457344846101;10225.130863876357;10305.74235644115;10321.896781032094;6396.296407395276;6746.359095885883;6909.928961011051;6923.238443771081;7014.2635023323855;7092.397146242627;8232.300254478436;8333.2916702081;8339.24946754795;8362.121977192308;7338.266173382833;7406.333429991327;7508.361789247975;7617.390608751398;7648.235176482307;7324.707011719472;7438.851222975619;7468.775936721907;7505.399568034435;7559.055118110236;5871.324797648325;7041.859162816612;7080.114985626797;7131.572105673677;7135.892961605477;3985.5815139409942;4607.654425918003;4736.976315118402;4822.373091498082;4944.456109827845;4820.156472617348;4846.491118432514;4872.244802055687;4890.119778439915;4915.139850526215 18:59:56 INFO - PROCESS | 1966 | |4;prototype.html;311.9610048743907;319.24976305681645;328.05520102105936;328.8733837472572;329.43337459569534;4898.363212782333;4993.825216117437;5232;5705.9144112838285;6100.542459315552;5270;5861.150133230679;6058.333583305833;6100.1154832549255;6105.419985101419;3811.7802303262965;4444.755538445384;4541.636669066484;4554.635629149677;4566.315052742082;4198.874033778991;4514.52597477265;4533.0253995390985;4583.977080114595;4592.517785632511;3517.489963955225;3850.4031875059272;4108.938365924513;4349.956010557459;4456.6657500687415;3500.387432199355;3894.53265608126;4455.952851079994;4480.641548676098;4502.816134174923;3587.856485740567;3816.255830113129;3822.522184726909;3823.273578020195;3857.1707082977296;5535.6955367454775;5707.885842283173;5794.159846822229;5807.796727114573;5853.970730146365;4770.260609605517;5143.408508021588;5208.713520756357;5212.244224587414;5215;6114.388561143895;6417.101605775195;6430.324815894311;6466.51039349849;6473.2232132144445;4016.3172260715455;4202.595897323147;4243.257429949777;4250.787460626956;4288.69979101463;6714.29499902513;6789.7623583174845;6799.762008329734;6807.353301436356;6815.079964204868;3793.336166170909;4182.53992060873;4186.372044193396;4221.817890990527;4294.141171765659;3517.4547945068557;3969.8166008695102;4377.606015458624;4388.100439409914;4389.946412860954;5168.793248270103;5170.586353091744;5189.377274727057;5197.7401129943355;5199.324087868553;6131.785387511505;6265.091561723524;6359.459445947054;6509.641969691617;6538.574992625464;1531.9004264722757;1547.8993865398713;1560.2510794818324;1560.9229631554192;1565.7807906893045;1023.3041531758475;1039.4646756920192;1056.2817284246632;1061.6284300494765;1061.8797168986794;5081;5636.577256705764;6010.699465026732;6014.458698717049;6037.154798328237;5728.073856483421;6574;6612.7354905804195;6672.666366681647;6711.664416779142;5448.591355648263;5691.203231547586;5900.616459930091;5971.134185543072;5973.581849270597;2926.1857648258215;2983.8508074596616;3107.300857307133;3497.8600855965983;3541.1678255609913;6649.800505984828;6966.651667416609;6974.907527741759;6985.485225318881;7007.194172670208;6798.558093723892;6833.658317084126;6867.660806142754;6870.20992585859;6886;6096.908546371719;6356.650384228912;6615.470762339001;7004.093301280013;7012.088428504262;4952.678075925053;5854.970725146347;6720;6770.830729231809;6784.7625333115075;5776.393478684678;6347.619142851444;6645.942866281372;6685.966570167118;6713.932860671526;6512.892808222518;7019.613921234381;7047.295270472911;7048.577085374894;7051.682674279772;3200.7677044337634;3804.94292585617;4139.77231252284;4326.978365108154;4378.934315985199;6379.840503987438;6727.553575981187;6731.629760363227;6737.326267373223;6765.3805214212125;5701.401352858056;6490;6526.967365163144;6541.280459149585;6550.901736474052;4902.7058376497525;6315.652639104894;6397.520185985976;6535.3464653534265;6558.508111891531;5299.894002119859;5417.566594672496;5916.852078698067;6384.233891932812;6393.744250230125;4882.853514394574;5730.997075511686;6308.71610777507;6559.770408035696;6562.179727534059;4818.638602104927;5400.83797486076;6339.714712838026;6373.602214471999;6417.593402474072 18:59:56 INFO - PROCESS | 1966 | |5;yui.html;661.6460193796319;670.6881300195408;719.3921136639538;721.2643104033885;767.0488594143264;15042.699146017087;15129.773053404195;15312.46406375777;15385.076895386257;15388.69222615547;14049.719005619882;14124.929375353135;14162.079464834778;14215.928920355384;14295.571132866005;885.3802338363139;889.8798662180587;901.2429559170296;901.5807649443008;902.512643172686;718.5760401363194;877.7717793373737;891.7101941868887;895.7760559860035;908.5865931001412;927.2906226736552;932.1843387036344;937.7608709778997;940.4921342475055;940.5626383731563;916.2486760856101;928.2759447630858;929.8512238041915;930.469632309577;931.2270815223383;685.6023506366346;688.6384648059759;693.3794254142514;693.4417793676089;696.4532841719226;12121.878781212252;14840.851591484163;15004.099754014685;15049.849501504954;15140.015898966532;12971.286579238138;13053.804192937114;13069;13091.934540327333;13204;8457.830843383097;10229.539670714836;10299.17606591471;10341.13172181039;10588.841167382496;3508.333416650854;3522.3659741246565;3541.7874927504176;3658.5426821647293;3658.884040367687;80942.59528702378;81624;83764;84816;85900.57049714774;7893.802654933673;9725.61097556104;9748.220142388593;9763.755906102404;9967.750806229902;22637.094516219495;22794.886025570097;22925.656115158286;22949;23114.1910033148;7335.48651594394;7464.514806537558;7645.923540764632;7805.336546393507;7901.170377110436;4072.857449989236;4092.897677558085;4093.099518106012;4102.548719640837;4106.219818234586;3601.6398360163776;3621.9818900905852;3683.060819491066;3693.261347730465;3712.5916149223563;547.261197383537;634.4575388043156;634.5970308853911;641.1440726629908;652.774792696519;547.7754120810448;563.5716855190085;591.1133300049925;599.6821684507219;600.9399060094044;7656.425768067305;7657;7668.348190403766;7752.5736084514765;8145.633446494922;555.7221389305347;571.7198572699348;584.1675612252608;591.9940800592025;593.8129489210885;3407.063057659114;3429.702536965318;3432.3135372925517;3438.3123375325035;3523.823808809549;85944;87025;88771.11228887757;89238.55380723184;89325.55337223402;67973;70558.58882822418;73857;73862;74583.62708186424;63790.68104659447;65241.67379163074;66410.66794665996;66418.33581664122;71752.12393803099;6765.797026089225;6774.491913106438;6845.14435695538;6919.20429150634;6978.197507286727;41978.790106050496;42741.78629106835;42853.78573107115;43143.35284970791;43601.56398435975;57605.71197143987;60666.39333606784;61462.38537614745;61940.69029654823;62329.688351557954;9645;9978.650747223812;10372.896271037192;10789.244752867224;11181.888181118411;9318.673846415342;11718.648440546796;11941.402929853646;11950.223235489666;11968.820467693165;11413.657590272305;11455.198136130392;11497.885021149681;11733.296002239893;11797.705057373634;11213.831792522955;11686.24039437434;11820;11883.524659013543;11964.880351196376;11304.773904522028;11328.546858125912;11340.886591133984;11350.432478376213;11610.303381797119;9447.196988256075;9554.882543983753;9683.757906052124;9888.752781180528;10278.126359259248;8038.236367545074;9255.907440925774;9697.515124243619;9812.950935245279;9963.352382095141 18:59:56 INFO - PROCESS | 1966 | __end_tp_report 18:59:56 INFO - PROCESS | 1966 | __start_cc_report 18:59:56 INFO - PROCESS | 1966 | _x_x_mozilla_cycle_collect,390 18:59:56 INFO - PROCESS | 1966 | __end_cc_report 18:59:56 INFO - PROCESS | 1966 | __startTimestamp1460685596304__endTimestamp 18:59:56 INFO - PROCESS | 1966 | 18:59:56 INFO - PROCESS | 1966 | ------- Summary: start ------- 18:59:56 INFO - PROCESS | 1966 | Number of tests: 6 18:59:56 INFO - PROCESS | 1966 | 18:59:56 INFO - PROCESS | 1966 | [#0] dojo.html Cycles:165 Average:5577.33 Median:5725.34 stddev:680.15 (11.9%) stddev-sans-first:660.62 18:59:56 INFO - PROCESS | 1966 | Values: 3408.9 3437.7 3503.3 3508.7 3511.3 4645.0 4892.9 5173.7 5602.6 5665.7 4651.7 5680.6 5711.3 5770.0 5828.0 5081.5 5143.1 5197.2 5207.3 5323.5 4167.4 5212.9 5240.1 5252.3 5317.4 4130.7 5091.9 5096.8 5184.8 5187.7 5142.2 5145.5 5167.9 5172.8 5179.4 4224.5 5105.3 5127.1 5149.6 5179.9 4871.1 5063.6 5515.0 5535.5 5646.3 6158.9 6173.0 6187.2 6213.3 6227.0 5008.6 6115.2 6126.0 6197.6 6257.8 5716.6 5725.3 5746.3 5759.2 5759.2 6214.4 6252.2 6256.5 6273.3 6275.6 4960.5 6010.9 6048.5 6112.3 6123.2 6098.3 6184.2 6191.0 6196.3 6218.0 6062.2 6138.4 6161.6 6186.6 6217.6 6036.5 6104.8 6144.8 6158.4 6162.9 4845.3 4847.9 4862.8 4890.3 4891.8 4332.1 4366.8 4390.1 4399.0 4406.0 5435.7 5575.9 5638.4 5664.2 5702.4 6166.8 6176.1 6236.1 6242.5 6261.1 5579.2 5590.8 5632.2 5633.2 5640.2 5187.2 5300.6 5350.5 5351.1 5405.3 6256.8 6267.5 6285.9 6301.7 6315.5 6111.4 6221.3 6241.4 6255.0 6268.5 6278.4 6312.4 6320.2 6347.7 6351.6 5979.9 6043.8 6050.2 6066.7 6070.0 5418.5 6079.0 6107.1 6170.1 6192.6 4782.4 4801.4 5098.0 5998.0 6147.0 5129.7 5869.2 6044.1 6044.5 6109.5 4708.9 5792.2 5833.3 5865.4 5883.6 5114.8 5432.0 5736.4 6111.7 6121.9 4415.6 5258.3 5506.3 5949.9 5977.8 18:59:56 INFO - PROCESS | 1966 | 18:59:56 INFO - PROCESS | 1966 | [#1] ext.html Cycles:180 Average:62522.16 Median:37488.00 stddev:137419.63 (366.6%) stddev-sans-first:137726.29 18:59:56 INFO - PROCESS | 1966 | Values: 523.8 534.0 570.0 578.8 582.1 36938.7 37670.6 37678.4 37747.6 37849.8 31853.2 38452.6 38624.0 39198.8 39207.0 9399.2 9585.8 9596.6 9596.9 9621.5 9491.9 9576.4 9637.5 9661.8 9670.0 8269.2 9617.3 9697.2 9704.5 9757.0 9578.1 9651.1 9676.2 9744.3 9751.3 7690.0 9587.0 9609.0 9643.9 9650.6 31517.7 37604.0 37809.4 37956.9 37958.6 2995.9 3014.8 3118.1 3165.8 3445.2 26778.5 27155.3 27530.7 28081.6 28120.9 2689.4 2698.7 2749.5 2884.9 2953.1 281847.0 349260.0 351257.0 352044.5 352898.0 35064.8 61323.4 61697.0 61909.0 62061.7 34448.8 34507.5 35290.6 35304.8 35480.1 14510.8 14598.6 14727.3 14737.1 14747.8 12113.6 12119.7 12136.8 12162.8 12497.9 7898.9 7933.1 7988.9 7990.6 8153.6 2917.5 3349.5 3375.8 3396.7 3422.4 968.6 1013.7 1025.9 1105.2 1134.4 30208.2 30258.5 30462.4 31195.7 31606.0 1069.1 1085.1 1107.9 1110.1 1158.3 11377.7 13820.9 14036.0 14513.5 15234.4 549821.0 588639.0 589836.0 590274.0 593998.0 46562.5 57611.0 58068.0 58460.7 58617.1 1428.3 1442.8 1481.0 1485.5 1504.5 24844.6 29268.6 29602.7 30002.4 30002.7 43721.1 53399.0 53422.0 53520.5 53643.5 576170.0 578725.0 579799.0 580353.0 581035.0 21870.2 26905.0 27484.7 27509.6 27527.7 45699.3 46210.3 46573.3 46826.5 47349.8 36712.4 37900.0 38372.6 38640.0 38754.0 37405.0 37596.6 37633.8 38202.2 38643.6 37075.9 37571.0 37978.1 38285.8 38512.0 31190.0 32912.3 33229.0 33298.5 33952.3 24471.9 24912.8 25026.7 25207.0 25441.9 18:59:56 INFO - PROCESS | 1966 | 18:59:56 INFO - PROCESS | 1966 | [#2] jquery.html Cycles:180 Average:32130.64 Median:13804.66 stddev:91690.55 (664.2%) stddev-sans-first:91916.78 18:59:56 INFO - PROCESS | 1966 | Values: 385.4 393.8 395.1 397.4 401.3 14157.0 19606.6 19998.9 20270.2 20338.2 19782.0 19907.8 20070.3 21274.8 21308.0 5875.5 5938.6 6025.3 6109.4 6121.4 6026.0 6044.5 6061.9 6064.4 6072.0 5666.4 5926.5 5952.4 6003.6 6014.2 5636.4 5772.8 5869.8 5914.9 5954.1 4913.8 4919.3 4923.1 4927.8 4977.2 24892.0 25064.9 25565.5 25702.9 26030.1 2115.9 2121.9 2135.4 2179.0 2544.4 9211.5 9893.0 9967.5 10095.1 14925.6 1496.9 1701.8 1715.8 1736.2 1787.8 113234.0 118890.0 118975.0 121400.4 122880.0 9596.8 11657.7 11826.9 11872.2 11928.8 15115.9 15136.2 15148.6 15208.4 15236.5 7241.7 8625.1 8637.1 8654.0 8701.3 8300.3 8471.9 8478.6 8662.4 8673.7 4610.2 4637.7 4642.0 4655.6 4695.5 1946.7 1978.4 1985.2 1986.3 1999.5 885.7 925.7 962.2 1047.2 1058.4 15243.4 17035.9 17512.3 20132.4 20229.9 852.7 926.5 944.9 987.0 1025.0 8236.5 10201.1 10566.9 10620.4 10641.5 213223.0 569304.0 594253.0 614519.0 624210.0 101388.0 124206.0 125516.4 126647.0 127585.0 423.4 452.6 452.9 1053.5 1073.2 10239.6 10363.8 10771.8 10787.6 11159.7 48406.8 49924.0 50417.7 50541.2 51695.0 64884.7 67343.0 73766.6 74681.6 76662.0 7113.6 7984.3 8104.1 8116.4 8247.9 10159.7 12229.1 12276.6 12412.8 12680.2 10492.4 10581.6 10694.0 11051.6 11078.9 10525.6 10538.6 10900.7 10912.3 11088.7 10821.6 11109.2 11308.7 11411.0 11431.9 7971.0 8043.9 8201.2 8207.2 8217.0 9982.5 10961.0 11153.7 11222.6 11324.6 18:59:56 INFO - PROCESS | 1966 | 18:59:56 INFO - PROCESS | 1966 | [#3] mootools.html Cycles:180 Average:5650.75 Median:4938.63 stddev:3111.45 (63.0%) stddev-sans-first:3104.61 18:59:56 INFO - PROCESS | 1966 | Values: 1509.3 1594.2 1765.7 1837.3 1921.2 7986.6 8502.8 8620.0 8868.6 8874.0 7221.2 7383.6 7741.0 8559.5 8745.9 2425.9 2449.2 2450.3 2456.2 2456.5 2436.0 2439.9 2462.4 2467.4 2477.8 2303.7 2459.4 2462.8 2479.5 2489.9 2418.4 2434.5 2441.9 2444.2 2457.4 2371.1 2421.5 2429.2 2445.7 2448.1 8870.3 8884.2 8921.2 8921.8 8925.6 5263.9 5458.9 5479.2 5512.6 5558.7 3594.4 3636.3 3639.1 3647.3 3678.4 1020.2 1033.1 1038.8 1050.7 1066.5 11581.1 11622.9 11654.2 11808.2 11830.5 4731.9 4809.1 4839.7 4932.8 4962.7 9848.7 9899.0 9905.2 9962.1 10010.0 4553.7 5331.9 5417.7 5453.8 5459.8 4444.4 4772.7 4853.8 4913.3 4946.6 4049.7 4054.1 4056.2 4057.3 4075.6 1148.7 1346.9 1348.5 1358.1 1361.2 885.3 907.6 918.5 920.8 922.4 5853.4 5872.4 5927.5 5953.2 6003.2 925.6 947.7 978.5 981.3 1014.4 3679.8 3821.8 3846.7 3853.7 3880.2 8665.2 9258.0 10576.9 10866.5 10871.7 9153.3 9451.9 10358.3 10757.1 10872.9 8147.4 8227.5 8345.0 8348.2 8434.9 5628.2 5708.6 5732.9 5828.3 5887.3 9894.9 9972.5 9985.2 10007.2 10045.3 9665.9 9866.5 10225.1 10305.7 10321.9 6396.3 6746.4 6909.9 6923.2 7014.3 7092.4 8232.3 8333.3 8339.2 8362.1 7338.3 7406.3 7508.4 7617.4 7648.2 7324.7 7438.9 7468.8 7505.4 7559.1 5871.3 7041.9 7080.1 7131.6 7135.9 3985.6 4607.7 4737.0 4822.4 4944.5 4820.2 4846.5 4872.2 4890.1 4915.1 18:59:56 INFO - PROCESS | 1966 | 18:59:56 INFO - PROCESS | 1966 | [#4] prototype.html Cycles:180 Average:5144.16 Median:5696.30 stddev:1670.89 (29.3%) stddev-sans-first:1635.74 18:59:56 INFO - PROCESS | 1966 | Values: 312.0 319.2 328.1 328.9 329.4 4898.4 4993.8 5232.0 5705.9 6100.5 5270.0 5861.2 6058.3 6100.1 6105.4 3811.8 4444.8 4541.6 4554.6 4566.3 4198.9 4514.5 4533.0 4584.0 4592.5 3517.5 3850.4 4108.9 4350.0 4456.7 3500.4 3894.5 4456.0 4480.6 4502.8 3587.9 3816.3 3822.5 3823.3 3857.2 5535.7 5707.9 5794.2 5807.8 5854.0 4770.3 5143.4 5208.7 5212.2 5215.0 6114.4 6417.1 6430.3 6466.5 6473.2 4016.3 4202.6 4243.3 4250.8 4288.7 6714.3 6789.8 6799.8 6807.4 6815.1 3793.3 4182.5 4186.4 4221.8 4294.1 3517.5 3969.8 4377.6 4388.1 4389.9 5168.8 5170.6 5189.4 5197.7 5199.3 6131.8 6265.1 6359.5 6509.6 6538.6 1531.9 1547.9 1560.3 1560.9 1565.8 1023.3 1039.5 1056.3 1061.6 1061.9 5081.0 5636.6 6010.7 6014.5 6037.2 5728.1 6574.0 6612.7 6672.7 6711.7 5448.6 5691.2 5900.6 5971.1 5973.6 2926.2 2983.9 3107.3 3497.9 3541.2 6649.8 6966.7 6974.9 6985.5 7007.2 6798.6 6833.7 6867.7 6870.2 6886.0 6096.9 6356.7 6615.5 7004.1 7012.1 4952.7 5855.0 6720.0 6770.8 6784.8 5776.4 6347.6 6645.9 6686.0 6713.9 6512.9 7019.6 7047.3 7048.6 7051.7 3200.8 3804.9 4139.8 4327.0 4378.9 6379.8 6727.6 6731.6 6737.3 6765.4 5701.4 6490.0 6527.0 6541.3 6550.9 4902.7 6315.7 6397.5 6535.3 6558.5 5299.9 5417.6 5916.9 6384.2 6393.7 4882.9 5731.0 6308.7 6559.8 6562.2 4818.6 5400.8 6339.7 6373.6 6417.6 18:59:56 INFO - PROCESS | 1966 | 18:59:56 INFO - PROCESS | 1966 | [#5] yui.html Cycles:180 Average:17573.96 Median:61701.54 stddev:24441.47 (39.6%) stddev-sans-first:24477.04 18:59:56 INFO - PROCESS | 1966 | Values: 661.6 670.7 719.4 721.3 767.0 15042.7 15129.8 15312.5 15385.1 15388.7 14049.7 14124.9 14162.1 14215.9 14295.6 885.4 889.9 901.2 901.6 902.5 718.6 877.8 891.7 895.8 908.6 927.3 932.2 937.8 940.5 940.6 916.2 928.3 929.9 930.5 931.2 685.6 688.6 693.4 693.4 696.5 12121.9 14840.9 15004.1 15049.8 15140.0 12971.3 13053.8 13069.0 13091.9 13204.0 8457.8 10229.5 10299.2 10341.1 10588.8 3508.3 3522.4 3541.8 3658.5 3658.9 80942.6 81624.0 83764.0 84816.0 85900.6 7893.8 9725.6 9748.2 9763.8 9967.8 22637.1 22794.9 22925.7 22949.0 23114.2 7335.5 7464.5 7645.9 7805.3 7901.2 4072.9 4092.9 4093.1 4102.5 4106.2 3601.6 3622.0 3683.1 3693.3 3712.6 547.3 634.5 634.6 641.1 652.8 547.8 563.6 591.1 599.7 600.9 7656.4 7657.0 7668.3 7752.6 8145.6 555.7 571.7 584.2 592.0 593.8 3407.1 3429.7 3432.3 3438.3 3523.8 85944.0 87025.0 88771.1 89238.6 89325.6 67973.0 70558.6 73857.0 73862.0 74583.6 63790.7 65241.7 66410.7 66418.3 71752.1 6765.8 6774.5 6845.1 6919.2 6978.2 41978.8 42741.8 42853.8 43143.4 43601.6 57605.7 60666.4 61462.4 61940.7 62329.7 9645.0 9978.7 10372.9 10789.2 11181.9 9318.7 11718.6 11941.4 11950.2 11968.8 11413.7 11455.2 11497.9 11733.3 11797.7 11213.8 11686.2 11820.0 11883.5 11964.9 11304.8 11328.5 11340.9 11350.4 11610.3 9447.2 9554.9 9683.8 9888.8 10278.1 8038.2 9255.9 9697.5 9813.0 9963.4 18:59:56 INFO - PROCESS | 1966 | -------- Summary: end -------- 18:59:56 INFO - PROCESS | 1966 | 18:59:56 INFO - TEST-INFO | 1966: exit 0 18:59:56 INFO - TEST-OK | dromaeo_css | took 1112275ms 18:59:56 INFO - TEST-START | kraken 18:59:56 INFO - operating with platform_type : mac_ 18:59:56 INFO - Initialising browser for kraken test... 18:59:56 INFO - TEST-INFO | started process 1970 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmp8Vz_Po/profile http://localhost:56074/getInfo.html) 18:59:59 INFO - PROCESS | 1970 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 18:59:59 INFO - PROCESS | 1970 | __metrics Screen width/height:1600/1200 18:59:59 INFO - PROCESS | 1970 | colorDepth:24 18:59:59 INFO - PROCESS | 1970 | Browser inner width/height: 1024/689 18:59:59 INFO - PROCESS | 1970 | __metrics 18:59:59 ERROR - PROCESS | 1970 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 19:00:00 INFO - TEST-INFO | 1970: exit 0 19:00:00 INFO - Browser initialized. 19:00:00 INFO - Running cycle 1/1 for kraken test... 19:00:00 INFO - TEST-INFO | started process 1973 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmp8Vz_Po/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:00:12 INFO - PROCESS | 1973 | RSS: Main: 247906304 19:00:12 INFO - PROCESS | 1973 | 19:00:14 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:56074/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56074/tests/kraken/driver.html?testName=audio-beat-detection) 19:00:15 INFO - PROCESS | 1973 | RSS: Main: 259993600 19:00:15 INFO - PROCESS | 1973 | 19:00:18 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:56074/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56074/tests/kraken/driver.html?testName=audio-dft) 19:00:19 INFO - PROCESS | 1973 | RSS: Main: 268935168 19:00:19 INFO - PROCESS | 1973 | 19:00:22 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:56074/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56074/tests/kraken/driver.html?testName=audio-fft) 19:00:23 INFO - PROCESS | 1973 | RSS: Main: 272068608 19:00:23 INFO - PROCESS | 1973 | 19:00:25 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:56074/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56074/tests/kraken/driver.html?testName=audio-oscillator) 19:00:25 INFO - PROCESS | 1973 | RSS: Main: 273088512 19:00:25 INFO - PROCESS | 1973 | 19:00:27 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:56074/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56074/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:00:28 INFO - PROCESS | 1973 | RSS: Main: 273592320 19:00:28 INFO - PROCESS | 1973 | 19:00:31 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:56074/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56074/tests/kraken/driver.html?testName=imaging-darkroom) 19:00:32 INFO - PROCESS | 1973 | RSS: Main: 275075072 19:00:32 INFO - PROCESS | 1973 | 19:00:34 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:56074/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56074/tests/kraken/driver.html?testName=imaging-desaturate) 19:00:35 INFO - PROCESS | 1973 | RSS: Main: 275156992 19:00:35 INFO - PROCESS | 1973 | 19:00:38 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:56074/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56074/tests/kraken/driver.html?testName=json-parse-financial) 19:00:39 INFO - PROCESS | 1973 | RSS: Main: 275824640 19:00:39 INFO - PROCESS | 1973 | 19:00:40 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:56074/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56074/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:00:41 INFO - PROCESS | 1973 | RSS: Main: 275107840 19:00:41 INFO - PROCESS | 1973 | 19:00:43 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:56074/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56074/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:00:44 INFO - PROCESS | 1973 | RSS: Main: 275111936 19:00:44 INFO - PROCESS | 1973 | 19:00:46 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:56074/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56074/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:00:46 INFO - PROCESS | 1973 | RSS: Main: 275398656 19:00:46 INFO - PROCESS | 1973 | 19:00:49 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:56074/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56074/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:00:50 INFO - PROCESS | 1973 | RSS: Main: 275570688 19:00:50 INFO - PROCESS | 1973 | 19:00:53 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:56074/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56074/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:00:54 INFO - PROCESS | 1973 | RSS: Main: 275460096 19:00:54 INFO - PROCESS | 1973 | 19:00:55 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:56074/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56074/tests/kraken/driver.html?testName=ai-astar) 19:00:56 INFO - PROCESS | 1973 | RSS: Main: 277086208 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | __start_tp_report 19:00:56 INFO - PROCESS | 1973 | _x_x_mozilla_page_load 19:00:56 INFO - PROCESS | 1973 | _x_x_mozilla_page_load_details 19:00:56 INFO - PROCESS | 1973 | |i|pagename|runs| 19:00:56 INFO - PROCESS | 1973 | |0;ai-astar;116;116;163;117;118;129;113;117;116;120 19:00:56 INFO - PROCESS | 1973 | |1;audio-beat-detection;152;141;191;206;154;139;141;125;170;121 19:00:56 INFO - PROCESS | 1973 | |2;audio-dft;197;201;206;238;259;282;283;252;205;194 19:00:56 INFO - PROCESS | 1973 | |3;audio-fft;70;79;74;142;54;56;57;65;130;58 19:00:56 INFO - PROCESS | 1973 | |4;audio-oscillator;76;83;127;79;81;86;117;84;82;121 19:00:56 INFO - PROCESS | 1973 | |5;imaging-gaussian-blur;137;125;91;87;89;90;90;87;90;107 19:00:56 INFO - PROCESS | 1973 | |6;imaging-darkroom;95;96;104;95;110;123;97;96;96;96 19:00:56 INFO - PROCESS | 1973 | |7;imaging-desaturate;110;113;102;103;102;101;83;146;147;91 19:00:56 INFO - PROCESS | 1973 | |8;json-parse-financial;62;62;61;62;60;63;61;79;62;66 19:00:56 INFO - PROCESS | 1973 | |9;json-stringify-tinderbox;65;68;67;63;71;83;74;70;69;74 19:00:56 INFO - PROCESS | 1973 | |10;stanford-crypto-aes;88;85;92;89;89;90;88;87;89;86 19:00:56 INFO - PROCESS | 1973 | |11;stanford-crypto-ccm;154;149;148;145;148;164;163;147;176;164 19:00:56 INFO - PROCESS | 1973 | |12;stanford-crypto-pbkdf2;168;160;191;192;232;224;191;193;243;171 19:00:56 INFO - PROCESS | 1973 | |13;stanford-crypto-sha256-iterative;69;72;70;67;67;79;85;71;69;71 19:00:56 INFO - PROCESS | 1973 | __end_tp_report 19:00:56 INFO - PROCESS | 1973 | __start_cc_report 19:00:56 INFO - PROCESS | 1973 | _x_x_mozilla_cycle_collect,1000 19:00:56 INFO - PROCESS | 1973 | __end_cc_report 19:00:56 INFO - PROCESS | 1973 | __startTimestamp1460685656204__endTimestamp 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | ------- Summary: start ------- 19:00:56 INFO - PROCESS | 1973 | Number of tests: 14 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | [#0] ai-astar Cycles:10 Average:122.50 Median:117.50 stddev:14.86 (12.6%) stddev-sans-first:15.57 19:00:56 INFO - PROCESS | 1973 | Values: 116.0 116.0 163.0 117.0 118.0 129.0 113.0 117.0 116.0 120.0 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | [#1] audio-beat-detection Cycles:10 Average:154.00 Median:153.00 stddev:27.54 (18.0%) stddev-sans-first:29.20 19:00:56 INFO - PROCESS | 1973 | Values: 152.0 141.0 191.0 206.0 154.0 139.0 141.0 125.0 170.0 121.0 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | [#2] audio-dft Cycles:10 Average:231.70 Median:245.00 stddev:35.43 (14.5%) stddev-sans-first:35.29 19:00:56 INFO - PROCESS | 1973 | Values: 197.0 201.0 206.0 238.0 259.0 282.0 283.0 252.0 205.0 194.0 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | [#3] audio-fft Cycles:10 Average:78.50 Median:61.50 stddev:31.53 (51.3%) stddev-sans-first:33.29 19:00:56 INFO - PROCESS | 1973 | Values: 70.0 79.0 74.0 142.0 54.0 56.0 57.0 65.0 130.0 58.0 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | [#4] audio-oscillator Cycles:10 Average:93.60 Median:81.50 stddev:19.70 (24.2%) stddev-sans-first:19.84 19:00:56 INFO - PROCESS | 1973 | Values: 76.0 83.0 127.0 79.0 81.0 86.0 117.0 84.0 82.0 121.0 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | [#5] imaging-gaussian-blur Cycles:10 Average:99.30 Median:89.50 stddev:17.88 (20.0%) stddev-sans-first:12.74 19:00:56 INFO - PROCESS | 1973 | Values: 137.0 125.0 91.0 87.0 89.0 90.0 90.0 87.0 90.0 107.0 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | [#6] imaging-darkroom Cycles:10 Average:100.80 Median:96.00 stddev:9.20 (9.6%) stddev-sans-first:9.51 19:00:56 INFO - PROCESS | 1973 | Values: 95.0 96.0 104.0 95.0 110.0 123.0 97.0 96.0 96.0 96.0 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | [#7] imaging-desaturate Cycles:10 Average:109.80 Median:129.50 stddev:21.14 (16.3%) stddev-sans-first:22.42 19:00:56 INFO - PROCESS | 1973 | Values: 110.0 113.0 102.0 103.0 102.0 101.0 83.0 146.0 147.0 91.0 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:62.00 stddev:5.57 (9.0%) stddev-sans-first:5.87 19:00:56 INFO - PROCESS | 1973 | Values: 62.0 62.0 61.0 62.0 60.0 63.0 61.0 79.0 62.0 66.0 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:70.50 stddev:5.66 (8.0%) stddev-sans-first:5.66 19:00:56 INFO - PROCESS | 1973 | Values: 65.0 68.0 67.0 63.0 71.0 83.0 74.0 70.0 69.0 74.0 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:89.00 stddev:2.00 (2.3%) stddev-sans-first:2.12 19:00:56 INFO - PROCESS | 1973 | Values: 88.0 85.0 92.0 89.0 89.0 90.0 88.0 87.0 89.0 86.0 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | [#11] stanford-crypto-ccm Cycles:10 Average:155.80 Median:158.50 stddev:10.33 (6.5%) stddev-sans-first:10.93 19:00:56 INFO - PROCESS | 1973 | Values: 154.0 149.0 148.0 145.0 148.0 164.0 163.0 147.0 176.0 164.0 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.50 Median:192.50 stddev:28.02 (14.6%) stddev-sans-first:27.76 19:00:56 INFO - PROCESS | 1973 | Values: 168.0 160.0 191.0 192.0 232.0 224.0 191.0 193.0 243.0 171.0 19:00:56 INFO - PROCESS | 1973 | 19:00:56 INFO - PROCESS | 1973 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.00 Median:71.00 stddev:5.70 (8.0%) stddev-sans-first:5.94 19:00:56 INFO - PROCESS | 1973 | Values: 69.0 72.0 70.0 67.0 67.0 79.0 85.0 71.0 69.0 71.0 19:00:56 INFO - PROCESS | 1973 | -------- Summary: end -------- 19:00:56 INFO - PROCESS | 1973 | 19:00:57 INFO - TEST-INFO | 1973: exit 0 19:00:57 INFO - TEST-OK | kraken | took 60297ms 19:00:57 INFO - SUITE-END | took 1172s 19:00:57 INFO - Completed test suite (00:19:32) 19:00:57 INFO - javascript benchmark 19:00:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3408.9147771305716, 3437.6734210250047, 3503.264314493956, 3508.736844736644, 3511.262634846683, 4644.953550464498, 4892.899097703015, 5173.663711858727, 5602.551795856332, 5665.716714164286, 4651.674382793206, 5680.602357834953, 5711.343195532505, 5770.04794208955, 5828.009238429467, 5081.491850814908, 5143.074246635604, 5197.194434862602, 5207.323048003773, 5323.547498462635, 4167.374893765929, 5212.947870521284, 5240.082985477546, 5252.264682944391, 5317.415084340739, 4130.669546436308, 5091.949080509222, 5096.821611243588, 5184.766685499161, 5187.7406129693745, 5142.151544995071, 5145.51117643827, 5167.948320516784, 5172.818951336685, 5179.378474583036, 4224.4930608327195, 5105.259737338065, 5127.128388173982, 5149.588032957355, 5179.922301165486, 4871.098846713334, 5063.569596584295, 5515.034868897927, 5535.474129957647, 5646.26598542191, 6158.876822463526, 6172.969135154341, 6187.164732761064, 6213.285472170713, 6226.972549529322, 5008.649394542384, 6115.240341084353, 6125.9693701531205, 6197.568738352054, 6257.78097766576, 5716.628419152825, 5725.341585717654, 5746.281714785652, 5759.222504961776, 5759.22250496186, 6214.378562143749, 6252.218472690914, 6256.499480041586, 6273.341299163613, 6275.592086514362, 4960.47914968923, 6010.939890601126, 6048.546359023091, 6112.327643959161, 6123.203983482208, 6098.268207815002, 6184.226971628546, 6191.040388739752, 6196.349383314687, 6218.0, 6062.181605483292, 6138.386161383915, 6161.599496032743, 6186.628802271878, 6217.595856269328, 6036.456718895321, 6104.7926496150985, 6144.78493252734, 6158.353372895871, 6162.907556386658, 4845.345878306454, 4847.903041939141, 4862.82980095695, 4890.339804126468, 4891.779869905791, 4332.090261045153, 4366.847160349373, 4390.05613793036, 4398.978005109954, 4405.964598319392, 5435.701036442954, 5575.916361254584, 5638.379778224392, 5664.235328230636, 5702.401247868997, 6166.81499555014, 6176.104464852571, 6236.095766113888, 6242.469390101892, 6261.123442718023, 5579.246801681721, 5590.776368945197, 5632.211490391349, 5633.183188437653, 5640.182173584807, 5187.19598462239, 5300.6024548158275, 5350.491703288181, 5351.09031464652, 5405.324334458192, 6256.812295631047, 6267.46726528232, 6285.9057114143325, 6301.747930082929, 6315.526335524854, 6111.379037751267, 6221.3156552778255, 6241.375862413723, 6255.0, 6268.4671802897265, 6278.4035516625845, 6312.43188113072, 6320.209973753281, 6347.682615869096, 6351.618902865843, 5979.910301345571, 6043.848903777265, 6050.183225264533, 6066.7269972850445, 6070.0, 5418.4557401142065, 6078.969605151946, 6107.114468402146, 6170.074488826533, 6192.59748116371, 4782.378290822171, 4801.447833499193, 5098.005888851639, 5997.970010149921, 6146.9550176471, 5129.692218466904, 5869.236999190078, 6044.063170208625, 6044.455998959938, 6109.450149486567, 4708.929366059443, 5792.189093526994, 5833.300003999378, 5865.4134586541, 5883.61756485827, 5114.849158939209, 5431.995080910045, 5736.397678243891, 6111.694415279129, 6121.938780612137, 4415.646748260067, 5258.290130832442, 5506.284183056177, 5949.940500594938, 5977.820665380045], "value": 5538.865700870448, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.829755329518, 534.041395694728, 569.9544036477084, 578.8234588450521, 582.0628787651881, 36938.70714524992, 37670.62329376706, 37678.43482347767, 37747.62252377476, 37849.81075094628, 31853.203669908205, 38452.61547384525, 38624.0, 39198.804005980004, 39207.0, 9399.154076133163, 9585.760355991086, 9596.568154433033, 9596.904030959671, 9621.470819104981, 9491.905080949206, 9576.377535460208, 9637.469939153343, 9661.758456038586, 9669.95165024174, 8269.173082691743, 9617.278704097185, 9697.224222062217, 9704.514774261259, 9757.0, 9578.137967582916, 9651.131398174162, 9676.225901927828, 9744.269179811543, 9751.26865485088, 7689.961550192269, 9587.0, 9609.0, 9643.90356096437, 9650.613975440974, 31517.684823151703, 37604.0, 37809.43285850714, 37956.861294160946, 37958.62041379578, 2995.8801647934056, 3014.8341841198726, 3118.09575223185, 3165.778395512315, 3445.207602251471, 26778.50201943459, 27155.321116972038, 27530.724692753218, 28081.57877631817, 28120.859395703093, 2689.3545549068276, 2698.716634753362, 2749.5188342040065, 2884.8505417829074, 2953.143588359373, 281847.0, 349260.0, 351257.0, 352044.4795552012, 352898.0, 35064.82467587646, 61323.38676613177, 61697.0, 61909.0, 62061.68969155216, 34448.82775586106, 34507.482387764205, 35290.64709352925, 35304.823475882455, 35480.11299717476, 14510.782338264935, 14598.635034124232, 14727.337269822883, 14737.11577305365, 14747.77878331826, 12113.576024839089, 12119.697007574881, 12136.817947730791, 12162.817557736642, 12497.937510312571, 7898.881516777253, 7933.127355990837, 7988.920110798817, 7990.560519171385, 8153.633086511121, 2917.4602698500844, 3349.4975753636663, 3375.780574262665, 3396.660333966633, 3422.3839708852147, 968.6367612145445, 1013.7060252526685, 1025.933314334566, 1105.1545567640762, 1134.444122380028, 30208.244793880327, 30258.54612180819, 30462.39075218483, 31195.68804311973, 31606.0, 1069.1446842525947, 1085.1102096280974, 1107.9474499225607, 1110.1063643766845, 1158.2760774515928, 11377.715557110972, 13820.861791382158, 14036.0, 14513.492027778977, 15234.3906243749, 549821.0, 588639.0, 589836.0, 590274.0, 593998.0, 46562.53437465582, 57611.0, 58068.0, 58460.707696462094, 58617.12074318888, 1428.3215472650409, 1442.7691569348854, 1480.985524915458, 1485.472657206676, 1504.50351384043, 24844.627330589694, 29268.56097158502, 29602.703972959996, 30002.399952001273, 30002.699973000865, 43721.12557748891, 53399.0, 53422.0, 53520.464795351545, 53643.46356536385, 576170.0, 578725.0, 579799.0, 580353.0, 581035.0, 21870.23454179096, 26905.0, 27484.725152749015, 27509.587356189273, 27527.724722752515, 45699.31451028304, 46210.30684539668, 46573.3014004797, 46826.53173468358, 47349.7632511849, 36712.449313260855, 37900.0, 38372.6162738369, 38640.0, 38754.0, 37405.0, 37596.6240337604, 37633.81183094176, 38202.23595528129, 38643.613563865125, 37075.887723368345, 37571.0, 37978.0505487365, 38285.808570956964, 38512.0, 31190.0, 32912.34175316528, 33229.0, 33298.5005224917, 33952.320953581286, 24471.87764061168, 24912.750872491768, 25026.74973250244, 25206.97621384788, 25441.872790635927], "value": 18098.03795313445, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.4160946166558, 393.79325853926684, 395.116913697885, 397.3681845865075, 401.34780980906027, 14157.0, 19606.62753607246, 19998.90000549997, 20270.18919243232, 20338.186472541118, 19782.0, 19907.800921990776, 20070.29753958608, 21274.787252127473, 21308.0, 5875.500582450497, 5938.613990090641, 6025.276966763972, 6109.35851735568, 6121.357257487966, 6026.0, 6044.455998960093, 6061.9393806062035, 6064.3935606439445, 6071.998120310143, 5666.376698563155, 5926.5258779297765, 5952.4047595240345, 6003.579749417542, 6014.188084608588, 5636.436356364372, 5772.797952071657, 5869.7652093916195, 5914.88170236595, 5954.106883967396, 4913.803447862081, 4919.262110683426, 4923.08922849274, 4927.827526036607, 4977.203647416396, 24892.0, 25064.87208075641, 25565.488690226277, 25702.871485642638, 26030.088946886768, 2115.9153633854626, 2121.9151233950624, 2135.39141344716, 2179.0194412514275, 2544.440223150904, 9211.539423028822, 9893.0, 9967.45179015154, 10095.14191293741, 14925.552233433014, 1496.8959523218277, 1701.7872765904262, 1715.8284171582866, 1736.2447335409138, 1787.830156135165, 113234.0, 118890.0, 118975.0, 121400.39299803444, 122880.0, 9596.808063838684, 11657.708557286136, 11826.88173118275, 11872.168948173718, 11928.821067683992, 15115.866310026791, 15136.167510787012, 15148.621284467976, 15208.391664333303, 15236.466723664618, 7241.710331586783, 8625.05124436324, 8637.136286371438, 8653.956730216434, 8701.26039286655, 8300.294474969694, 8471.872921906177, 8478.618462169217, 8662.436941598902, 8673.696420625374, 4610.216263235258, 4637.675362724644, 4641.976790116028, 4655.604273636779, 4695.5069717679835, 1946.7274581558593, 1978.3768113044346, 1985.1761518969754, 1986.255154317131, 1999.5001249687577, 885.7121435533477, 925.6945208081318, 962.1629182611033, 1047.1518070363031, 1058.3544038136731, 15243.3902643893, 17035.91482042582, 17512.29950801979, 20132.39602811918, 20229.898850505655, 852.6589364254348, 926.5182105305204, 944.8535477001076, 986.9735475105809, 1024.990384471295, 8236.546989915612, 10201.081902628799, 10566.894331056745, 10620.362778233331, 10641.467926603638, 213223.0, 569304.0, 594253.0, 614519.0, 624210.0, 101388.0, 124206.0, 125516.37241813733, 126647.0, 127585.0, 423.40300176750634, 452.60623257765957, 452.86414075777134, 1053.4679986606868, 1073.173656284672, 10239.590416383558, 10363.768679406889, 10771.838422423827, 10787.568497260336, 11159.665210043711, 48406.757966209945, 49924.00151997012, 50417.739556511384, 50541.241881371076, 51695.0, 64884.67557662181, 67342.97971060876, 73766.63116684383, 74681.62659186669, 76662.0, 7113.57318560888, 7984.283379073295, 8104.149064348158, 8116.350691944512, 8247.917520824954, 10159.695209143738, 12229.082818788804, 12276.631701048982, 12412.81380779307, 12680.23918564889, 10492.370457772558, 10581.576736930436, 10694.0, 11051.613193538187, 11078.944605277244, 10525.578976841147, 10538.631147909786, 10900.727481813017, 10912.345259284468, 11088.667339979813, 10821.621243256448, 11109.166812488935, 11308.660740177807, 11411.030062444785, 11431.942840285745, 7970.960145199237, 8043.919560804317, 8201.22088401601, 8207.179282071746, 8217.0, 9982.450965196693, 10961.0, 11153.721156970816, 11222.607208747655, 11324.6036388726], "value": 9148.510807715062, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1509.3358922074287, 1594.210865621517, 1765.6821772081028, 1837.2742766607191, 1921.2219051284235, 7986.5607391593585, 8502.787430314229, 8619.956900215499, 8868.645254189832, 8874.023857375692, 7221.205667376585, 7383.556986580809, 7741.0, 8559.529225892573, 8745.868811967826, 2425.8787060646987, 2449.155041510676, 2450.3261603059123, 2456.226288719057, 2456.496418234258, 2436.049940523197, 2439.9146029888957, 2462.384403899025, 2467.370820440794, 2477.826552141351, 2303.7120359955006, 2459.409741661997, 2462.753724627541, 2479.4917042006437, 2489.9004039838383, 2418.4316685578874, 2434.47658753368, 2441.8901149448316, 2444.2300675287406, 2457.3610861176135, 2371.063429945162, 2421.4551725861716, 2429.2226487524, 2445.7187423446353, 2448.09420514409, 8870.290376769908, 8884.20042196199, 8921.241694455979, 8921.821563568692, 8925.598348074287, 5263.86840328991, 5458.918116228259, 5479.150731636602, 5512.586556008276, 5558.6942718150485, 3594.4428613564937, 3636.254567813592, 3639.0720366306687, 3647.252313275772, 3678.4114541673202, 1020.1736593359402, 1033.1115240892825, 1038.8182068138121, 1050.669039252633, 1066.5094056733913, 11581.131415143813, 11622.941885290604, 11654.242474239147, 11808.173427860052, 11830.526778928832, 4731.905361892743, 4809.0862736080035, 4839.661223714306, 4932.802687892444, 4962.727050012284, 9848.704538863845, 9899.0, 9905.158061564704, 9962.103410693073, 10010.04904534068, 4553.658475614342, 5331.86670333237, 5417.729113544307, 5453.7818487260065, 5459.808906688247, 4444.444444444444, 4772.689775164603, 4853.80584776605, 4913.287573301851, 4946.579540739005, 4049.6962727795535, 4054.108096218827, 4056.229316429928, 4057.330540460791, 4075.558884140811, 1148.6783700563856, 1346.9057165998481, 1348.5347555093476, 1358.1375826350338, 1361.2445092973296, 885.3448448148453, 907.6414816147585, 918.4856480371013, 920.7683311197869, 922.3589605224439, 5853.443922827325, 5872.412758724179, 5927.525797936155, 5953.166556682068, 6003.219581454383, 925.5788616179622, 947.6825263536655, 978.4667356290838, 981.3375971219398, 1014.3761586624321, 3679.77921324716, 3821.7706937583835, 3846.673032792244, 3853.6531712146043, 3880.2239552089695, 8665.220130188314, 9258.0, 10576.894231057591, 10866.511007004703, 10871.728206794893, 9153.313501487415, 9451.858222126673, 10358.274920755475, 10757.139428845674, 10872.945635271932, 8147.388945829087, 8227.506349619161, 8345.0, 8348.165183481604, 8434.873476897852, 5628.212050313042, 5708.57185711065, 5732.885342293047, 5828.329742079715, 5887.293524777054, 9894.901050989398, 9972.451515166735, 9985.201183905125, 10007.249456290661, 10045.347052441568, 9665.855012174683, 9866.457344846101, 10225.130863876357, 10305.74235644115, 10321.896781032094, 6396.296407395276, 6746.359095885883, 6909.928961011051, 6923.238443771081, 7014.2635023323855, 7092.397146242627, 8232.300254478436, 8333.2916702081, 8339.24946754795, 8362.121977192308, 7338.266173382833, 7406.333429991327, 7508.361789247975, 7617.390608751398, 7648.235176482307, 7324.707011719472, 7438.851222975619, 7468.775936721907, 7505.399568034435, 7559.055118110236, 5871.324797648325, 7041.859162816612, 7080.114985626797, 7131.572105673677, 7135.892961605477, 3985.5815139409942, 4607.654425918003, 4736.976315118402, 4822.373091498082, 4944.456109827845, 4820.156472617348, 4846.491118432514, 4872.244802055687, 4890.119778439915, 4915.139850526215], "value": 4587.016615184898, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.9610048743907, 319.24976305681645, 328.05520102105936, 328.8733837472572, 329.43337459569534, 4898.363212782333, 4993.825216117437, 5232.0, 5705.9144112838285, 6100.542459315552, 5270.0, 5861.150133230679, 6058.333583305833, 6100.1154832549255, 6105.419985101419, 3811.7802303262965, 4444.755538445384, 4541.636669066484, 4554.635629149677, 4566.315052742082, 4198.874033778991, 4514.52597477265, 4533.0253995390985, 4583.977080114595, 4592.517785632511, 3517.489963955225, 3850.4031875059272, 4108.938365924513, 4349.956010557459, 4456.6657500687415, 3500.387432199355, 3894.53265608126, 4455.952851079994, 4480.641548676098, 4502.816134174923, 3587.856485740567, 3816.255830113129, 3822.522184726909, 3823.273578020195, 3857.1707082977296, 5535.6955367454775, 5707.885842283173, 5794.159846822229, 5807.796727114573, 5853.970730146365, 4770.260609605517, 5143.408508021588, 5208.713520756357, 5212.244224587414, 5215.0, 6114.388561143895, 6417.101605775195, 6430.324815894311, 6466.51039349849, 6473.2232132144445, 4016.3172260715455, 4202.595897323147, 4243.257429949777, 4250.787460626956, 4288.69979101463, 6714.29499902513, 6789.7623583174845, 6799.762008329734, 6807.353301436356, 6815.079964204868, 3793.336166170909, 4182.53992060873, 4186.372044193396, 4221.817890990527, 4294.141171765659, 3517.4547945068557, 3969.8166008695102, 4377.606015458624, 4388.100439409914, 4389.946412860954, 5168.793248270103, 5170.586353091744, 5189.377274727057, 5197.7401129943355, 5199.324087868553, 6131.785387511505, 6265.091561723524, 6359.459445947054, 6509.641969691617, 6538.574992625464, 1531.9004264722757, 1547.8993865398713, 1560.2510794818324, 1560.9229631554192, 1565.7807906893045, 1023.3041531758475, 1039.4646756920192, 1056.2817284246632, 1061.6284300494765, 1061.8797168986794, 5081.0, 5636.577256705764, 6010.699465026732, 6014.458698717049, 6037.154798328237, 5728.073856483421, 6574.0, 6612.7354905804195, 6672.666366681647, 6711.664416779142, 5448.591355648263, 5691.203231547586, 5900.616459930091, 5971.134185543072, 5973.581849270597, 2926.1857648258215, 2983.8508074596616, 3107.300857307133, 3497.8600855965983, 3541.1678255609913, 6649.800505984828, 6966.651667416609, 6974.907527741759, 6985.485225318881, 7007.194172670208, 6798.558093723892, 6833.658317084126, 6867.660806142754, 6870.20992585859, 6886.0, 6096.908546371719, 6356.650384228912, 6615.470762339001, 7004.093301280013, 7012.088428504262, 4952.678075925053, 5854.970725146347, 6720.0, 6770.830729231809, 6784.7625333115075, 5776.393478684678, 6347.619142851444, 6645.942866281372, 6685.966570167118, 6713.932860671526, 6512.892808222518, 7019.613921234381, 7047.295270472911, 7048.577085374894, 7051.682674279772, 3200.7677044337634, 3804.94292585617, 4139.77231252284, 4326.978365108154, 4378.934315985199, 6379.840503987438, 6727.553575981187, 6731.629760363227, 6737.326267373223, 6765.3805214212125, 5701.401352858056, 6490.0, 6526.967365163144, 6541.280459149585, 6550.901736474052, 4902.7058376497525, 6315.652639104894, 6397.520185985976, 6535.3464653534265, 6558.508111891531, 5299.894002119859, 5417.566594672496, 5916.852078698067, 6384.233891932812, 6393.744250230125, 4882.853514394574, 5730.997075511686, 6308.71610777507, 6559.770408035696, 6562.179727534059, 4818.638602104927, 5400.83797486076, 6339.714712838026, 6373.602214471999, 6417.593402474072], "value": 4615.529068065306, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.6460193796319, 670.6881300195408, 719.3921136639538, 721.2643104033885, 767.0488594143264, 15042.699146017087, 15129.773053404195, 15312.46406375777, 15385.076895386257, 15388.69222615547, 14049.719005619882, 14124.929375353135, 14162.079464834778, 14215.928920355384, 14295.571132866005, 885.3802338363139, 889.8798662180587, 901.2429559170296, 901.5807649443008, 902.512643172686, 718.5760401363194, 877.7717793373737, 891.7101941868887, 895.7760559860035, 908.5865931001412, 927.2906226736552, 932.1843387036344, 937.7608709778997, 940.4921342475055, 940.5626383731563, 916.2486760856101, 928.2759447630858, 929.8512238041915, 930.469632309577, 931.2270815223383, 685.6023506366346, 688.6384648059759, 693.3794254142514, 693.4417793676089, 696.4532841719226, 12121.878781212252, 14840.851591484163, 15004.099754014685, 15049.849501504954, 15140.015898966532, 12971.286579238138, 13053.804192937114, 13069.0, 13091.934540327333, 13204.0, 8457.830843383097, 10229.539670714836, 10299.17606591471, 10341.13172181039, 10588.841167382496, 3508.333416650854, 3522.3659741246565, 3541.7874927504176, 3658.5426821647293, 3658.884040367687, 80942.59528702378, 81624.0, 83764.0, 84816.0, 85900.57049714774, 7893.802654933673, 9725.61097556104, 9748.220142388593, 9763.755906102404, 9967.750806229902, 22637.094516219495, 22794.886025570097, 22925.656115158286, 22949.0, 23114.1910033148, 7335.48651594394, 7464.514806537558, 7645.923540764632, 7805.336546393507, 7901.170377110436, 4072.857449989236, 4092.897677558085, 4093.099518106012, 4102.548719640837, 4106.219818234586, 3601.6398360163776, 3621.9818900905852, 3683.060819491066, 3693.261347730465, 3712.5916149223563, 547.261197383537, 634.4575388043156, 634.5970308853911, 641.1440726629908, 652.774792696519, 547.7754120810448, 563.5716855190085, 591.1133300049925, 599.6821684507219, 600.9399060094044, 7656.425768067305, 7657.0, 7668.348190403766, 7752.5736084514765, 8145.633446494922, 555.7221389305347, 571.7198572699348, 584.1675612252608, 591.9940800592025, 593.8129489210885, 3407.063057659114, 3429.702536965318, 3432.3135372925517, 3438.3123375325035, 3523.823808809549, 85944.0, 87025.0, 88771.11228887757, 89238.55380723184, 89325.55337223402, 67973.0, 70558.58882822418, 73857.0, 73862.0, 74583.62708186424, 63790.68104659447, 65241.67379163074, 66410.66794665996, 66418.33581664122, 71752.12393803099, 6765.797026089225, 6774.491913106438, 6845.14435695538, 6919.20429150634, 6978.197507286727, 41978.790106050496, 42741.78629106835, 42853.78573107115, 43143.35284970791, 43601.56398435975, 57605.71197143987, 60666.39333606784, 61462.38537614745, 61940.69029654823, 62329.688351557954, 9645.0, 9978.650747223812, 10372.896271037192, 10789.244752867224, 11181.888181118411, 9318.673846415342, 11718.648440546796, 11941.402929853646, 11950.223235489666, 11968.820467693165, 11413.657590272305, 11455.198136130392, 11497.885021149681, 11733.296002239893, 11797.705057373634, 11213.831792522955, 11686.24039437434, 11820.0, 11883.524659013543, 11964.880351196376, 11304.773904522028, 11328.546858125912, 11340.886591133984, 11350.432478376213, 11610.303381797119, 9447.196988256075, 9554.882543983753, 9683.757906052124, 9888.752781180528, 10278.126359259248, 8038.236367545074, 9255.907440925774, 9697.515124243619, 9812.950935245279, 9963.352382095141], "value": 6746.172468886943, "unit": "score"}], "name": "dromaeo_css", "value": 7126.477061765747, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 163.0, 117.0, 118.0, 129.0, 113.0, 117.0, 116.0, 120.0], "value": 122.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 141.0, 191.0, 206.0, 154.0, 139.0, 141.0, 125.0, 170.0, 121.0], "value": 154.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 201.0, 206.0, 238.0, 259.0, 282.0, 283.0, 252.0, 205.0, 194.0], "value": 231.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 79.0, 74.0, 142.0, 54.0, 56.0, 57.0, 65.0, 130.0, 58.0], "value": 78.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 83.0, 127.0, 79.0, 81.0, 86.0, 117.0, 84.0, 82.0, 121.0], "value": 93.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 125.0, 91.0, 87.0, 89.0, 90.0, 90.0, 87.0, 90.0, 107.0], "value": 99.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 104.0, 95.0, 110.0, 123.0, 97.0, 96.0, 96.0, 96.0], "value": 100.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 102.0, 103.0, 102.0, 101.0, 83.0, 146.0, 147.0, 91.0], "value": 109.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 62.0, 60.0, 63.0, 61.0, 79.0, 62.0, 66.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 67.0, 63.0, 71.0, 83.0, 74.0, 70.0, 69.0, 74.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 92.0, 89.0, 89.0, 90.0, 88.0, 87.0, 89.0, 86.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 149.0, 148.0, 145.0, 148.0, 164.0, 163.0, 147.0, 176.0, 164.0], "value": 155.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 160.0, 191.0, 192.0, 232.0, 224.0, 191.0, 193.0, 243.0, 171.0], "value": 196.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 70.0, 67.0, 67.0, 79.0, 85.0, 71.0, 69.0, 71.0], "value": 72.0, "unit": "score"}], "name": "kraken", "value": 1637.0, "alertThreshold": 2.0}]} 19:00:57 INFO - Return code: 0 19:00:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:00:57 ERROR - # TBPL SUCCESS # 19:00:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:00:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:00:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:00:57 INFO - 2016-04-14 19:00:57.527 screenresolution[1978:20276] starting screenresolution argv=screenresolution get 19:00:57 INFO - 2016-04-14 19:00:57.530 screenresolution[1978:20276] Display 0: 1600x1200x32@60 19:00:57 INFO - 2016-04-14 19:00:57.536 screenresolution[1979:20279] starting screenresolution argv=screenresolution list 19:00:57 INFO - Available Modes on Display 0 19:00:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:00:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:00:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:00:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:00:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:00:57 INFO - Intel Iris: 19:00:57 INFO - Chipset Model: Intel Iris 19:00:57 INFO - Type: GPU 19:00:57 INFO - Bus: Built-In 19:00:57 INFO - VRAM (Dynamic, Max): 1536 MB 19:00:57 INFO - Vendor: Intel (0x8086) 19:00:57 INFO - Device ID: 0x0a2e 19:00:57 INFO - Revision ID: 0x0009 19:00:57 INFO - Displays: 19:00:57 INFO - Display: 19:00:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:00:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:00:57 INFO - Display Serial Number: 1600x1200 60 19:00:57 INFO - Main Display: Yes 19:00:57 INFO - Mirror: Off 19:00:57 INFO - Online: Yes 19:00:57 INFO - Rotation: Supported 19:00:57 INFO - Return code: 0 19:00:57 INFO - Running post-action listener: _resource_record_post_action 19:00:57 INFO - Running post-run listener: _resource_record_post_run 19:00:58 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 0; Write bytes: 694538240; Read time: 0; Write time: 1976 19:00:58 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 394803712; Write bytes: 396183040; Read time: 29968; Write time: 773 19:00:58 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 0; Write bytes: 297507328; Read time: 0; Write time: 1201 19:00:58 INFO - Running post-run listener: _upload_blobber_files 19:00:58 INFO - Blob upload gear active. 19:00:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:00:58 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 19:00:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:00:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:00:59 INFO - (blobuploader) - INFO - Open directory for files ... 19:00:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:00:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:00:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:01:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:01:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:01:00 INFO - (blobuploader) - INFO - Done attempting. 19:01:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:01:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:01:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:01:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:01:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:01:00 INFO - (blobuploader) - INFO - Done attempting. 19:01:00 INFO - (blobuploader) - INFO - Iteration through files over. 19:01:00 INFO - Return code: 0 19:01:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:01:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:01:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9b96f23dbed99b849caccd6352a4d2e9d3f8fa6c10b72278c17b872a03472355b4f5de96003c8b733bcf8f5eb7fc28fe9654f650bc831791db71c650e129d8fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c5c45bf68559820e82afcb9b7a9df288524e59806bde972ec61281d1bc9c02655ad11e670c373cf343a25cbf68c30e2a32772c3158e4e3ff07abb75ebd7f36da"} 19:01:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:01:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:01:00 INFO - Contents: 19:01:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9b96f23dbed99b849caccd6352a4d2e9d3f8fa6c10b72278c17b872a03472355b4f5de96003c8b733bcf8f5eb7fc28fe9654f650bc831791db71c650e129d8fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c5c45bf68559820e82afcb9b7a9df288524e59806bde972ec61281d1bc9c02655ad11e670c373cf343a25cbf68c30e2a32772c3158e4e3ff07abb75ebd7f36da"} 19:01:00 INFO - Running post-run listener: copy_logs_to_upload_dir 19:01:00 INFO - Copying logs to upload dir... 19:01:00 INFO - mkdir: /builds/slave/test/build/upload/logs 19:01:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.514790 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-04-14 19:01:01.054939) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 19:01:01.059782) ========= 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.hdBR7BKmn2/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.TUyWCihfm3/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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/Fx-Team/sha512/9b96f23dbed99b849caccd6352a4d2e9d3f8fa6c10b72278c17b872a03472355b4f5de96003c8b733bcf8f5eb7fc28fe9654f650bc831791db71c650e129d8fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c5c45bf68559820e82afcb9b7a9df288524e59806bde972ec61281d1bc9c02655ad11e670c373cf343a25cbf68c30e2a32772c3158e4e3ff07abb75ebd7f36da"} build_url:https://queue.taskcluster.net/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011165 build_url: 'https://queue.taskcluster.net/v1/task/WLn3bi1USYSih5SLORnpFQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9b96f23dbed99b849caccd6352a4d2e9d3f8fa6c10b72278c17b872a03472355b4f5de96003c8b733bcf8f5eb7fc28fe9654f650bc831791db71c650e129d8fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c5c45bf68559820e82afcb9b7a9df288524e59806bde972ec61281d1bc9c02655ad11e670c373cf343a25cbf68c30e2a32772c3158e4e3ff07abb75ebd7f36da"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 19:01:01.098895) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 19:01:01.099313) ========= 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.hdBR7BKmn2/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.TUyWCihfm3/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.005292 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 19:01:01.147904) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 19:01:01.148225) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 19:01:01.151600) ========= ========= Total master_lag: 0.19 =========