builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0382 starttime: 1467979992.9 results: success (0) buildid: 20160706215757 builduid: c7f528964bfb470d879baf9a01f739df revision: d142c49033c015f67272562b37dbe2912cfc7f14 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 05:13:12.897181) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 05:13:12.908760) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 05:13:12.909058) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 05:13:12.932372) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:13:12.932705) ========= 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.GiCMZzyyfz/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.sVoePtV9CF/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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-07-08 05:13:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.1M=0.001s 2016-07-08 05:13:13 (13.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119266 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:13:13.099743) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:13:13.100236) ========= 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.GiCMZzyyfz/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.sVoePtV9CF/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.021055 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:13:13.152330) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:13:13.152949) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d142c49033c015f67272562b37dbe2912cfc7f14 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d142c49033c015f67272562b37dbe2912cfc7f14 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GiCMZzyyfz/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.sVoePtV9CF/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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-07-08 05:13:13,233 truncating revision to first 12 chars 2016-07-08 05:13:13,233 Setting DEBUG logging. 2016-07-08 05:13:13,233 attempt 1/10 2016-07-08 05:13:13,233 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d142c49033c0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-08 05:13:13,910 unpacking tar archive at: mozilla-beta-d142c49033c0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.958675 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:13:14.134756) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 05:13:14.135097) ========= script_repo_revision: d142c49033c015f67272562b37dbe2912cfc7f14 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 05:13:14.135598) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 05:13:14.135899) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 05:13:14.154191) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-08 05:13:14.154485) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GiCMZzyyfz/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.sVoePtV9CF/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 05:13:14 INFO - MultiFileLogger online at 20160708 05:13:14 in /builds/slave/test 05:13:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:13:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:13:14 INFO - {'append_to_log': False, 05:13:14 INFO - 'base_work_dir': '/builds/slave/test', 05:13:14 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:13:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:13:14 INFO - 'branch': 'Mozilla-Beta', 05:13:14 INFO - 'buildbot_json_path': 'buildprops.json', 05:13:14 INFO - 'config_files': ('talos/mac_config.py',), 05:13:14 INFO - 'default_actions': ('clobber', 05:13:14 INFO - 'read-buildbot-config', 05:13:14 INFO - 'download-and-extract', 05:13:14 INFO - 'populate-webroot', 05:13:14 INFO - 'create-virtualenv', 05:13:14 INFO - 'install', 05:13:14 INFO - 'run-tests'), 05:13:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:13:14 INFO - 'download_minidump_stackwalk': True, 05:13:14 INFO - 'download_symbols': 'ondemand', 05:13:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:13:14 INFO - 'tooltool.py': '/tools/tooltool.py', 05:13:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:13:14 INFO - '/tools/misc-python/virtualenv.py')}, 05:13:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:13:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:13:14 INFO - 'installer_path': 'installer.exe', 05:13:14 INFO - 'log_level': 'info', 05:13:14 INFO - 'log_name': 'talos', 05:13:14 INFO - 'log_to_console': True, 05:13:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:13:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:13:14 INFO - 'opt_config_files': (), 05:13:14 INFO - 'pip_index': False, 05:13:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:13:14 INFO - 'cmd': ('bash', 05:13:14 INFO - '-c', 05:13:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:13:14 INFO - 'enabled': True, 05:13:14 INFO - 'halt_on_failure': False, 05:13:14 INFO - 'name': 'check_screen_resolution'},), 05:13:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:13:14 INFO - 'cmd': ('bash', 05:13:14 INFO - '-c', 05:13:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:13:14 INFO - 'enabled': True, 05:13:14 INFO - 'halt_on_failure': False, 05:13:14 INFO - 'name': 'check_screen_resolution'},), 05:13:14 INFO - 'run_cmd_checks_enabled': True, 05:13:14 INFO - 'sps_profile': False, 05:13:14 INFO - 'sps_profile_interval': 0, 05:13:14 INFO - 'suite': 'dromaeojs', 05:13:14 INFO - 'system_bits': '32', 05:13:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:13:14 INFO - 'title': 't-yosemite-r7-0382', 05:13:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:13:14 INFO - 'use_talos_json': True, 05:13:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:13:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:13:14 INFO - 'work_dir': 'build'} 05:13:14 INFO - ##### 05:13:14 INFO - ##### Running clobber step. 05:13:14 INFO - ##### 05:13:14 INFO - Running pre-action listener: _resource_record_pre_action 05:13:14 INFO - Running main action method: clobber 05:13:14 INFO - rmtree: /builds/slave/test/build 05:13:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:13:15 INFO - Running post-action listener: _resource_record_post_action 05:13:15 INFO - ##### 05:13:15 INFO - ##### Running read-buildbot-config step. 05:13:15 INFO - ##### 05:13:15 INFO - Running pre-action listener: _resource_record_pre_action 05:13:15 INFO - Running main action method: read_buildbot_config 05:13:15 INFO - Using buildbot properties: 05:13:15 INFO - { 05:13:15 INFO - "project": "", 05:13:15 INFO - "product": "firefox", 05:13:15 INFO - "script_repo_revision": "production", 05:13:15 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 05:13:15 INFO - "repository": "", 05:13:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 05:13:15 INFO - "buildid": "20160706215757", 05:13:15 INFO - "pgo_build": "False", 05:13:15 INFO - "basedir": "/builds/slave/test", 05:13:15 INFO - "buildnumber": 30, 05:13:15 INFO - "slavename": "t-yosemite-r7-0382", 05:13:15 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:13:15 INFO - "platform": "yosemite_r7", 05:13:15 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:13:15 INFO - "branch": "mozilla-beta", 05:13:15 INFO - "repo_path": "releases/mozilla-beta", 05:13:15 INFO - "revision": "d142c49033c015f67272562b37dbe2912cfc7f14", 05:13:15 INFO - "stage_platform": "macosx64", 05:13:15 INFO - "builduid": "c7f528964bfb470d879baf9a01f739df", 05:13:15 INFO - "slavebuilddir": "test" 05:13:15 INFO - } 05:13:15 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.dmg. 05:13:15 INFO - Running post-action listener: _resource_record_post_action 05:13:15 INFO - ##### 05:13:15 INFO - ##### Running download-and-extract step. 05:13:15 INFO - ##### 05:13:15 INFO - Running pre-action listener: _resource_record_pre_action 05:13:15 INFO - Running main action method: download_and_extract 05:13:15 INFO - mkdir: /builds/slave/test/build/tests 05:13:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:15 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.test_packages.json 05:13:15 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 05:13:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 05:13:16 INFO - Downloaded 1165 bytes. 05:13:16 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 05:13:16 INFO - Using the following test package requirements: 05:13:16 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 05:13:16 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:13:16 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 05:13:16 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:13:16 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 05:13:16 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:13:16 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:13:16 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 05:13:16 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 05:13:16 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:13:16 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 05:13:16 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:13:16 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 05:13:16 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:13:16 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 05:13:16 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:13:16 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 05:13:16 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 05:13:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:16 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.common.tests.zip 05:13:16 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 05:13:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 05:13:24 INFO - Downloaded 18351409 bytes. 05:13:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:13:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:13:27 INFO - Return code: 0 05:13:27 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 05:13:27 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')]} 05:13:27 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.common.tests.zip 05:13:27 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 05:13:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 05:13:27 INFO - Downloaded 18351409 bytes. 05:13:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:13:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:13:31 INFO - Return code: 0 05:13:31 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')]} 05:13:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.talos.tests.zip 05:13:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 05:13:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 05:13:35 INFO - Downloaded 10840145 bytes. 05:13:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:13:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:13:35 INFO - Return code: 0 05:13: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')]} 05:13:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.dmg 05:13:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 05:13:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 05:13:47 INFO - Downloaded 86190829 bytes. 05:13:47 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.dmg 05:13:47 INFO - mkdir: /builds/slave/test/properties 05:13:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:13:47 INFO - Writing to file /builds/slave/test/properties/build_url 05:13:47 INFO - Contents: 05:13:47 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.dmg 05:13:47 INFO - Running post-action listener: _resource_record_post_action 05:13:47 INFO - Running post-action listener: set_extra_try_arguments 05:13:47 INFO - ##### 05:13:47 INFO - ##### Running populate-webroot step. 05:13:47 INFO - ##### 05:13:47 INFO - Running pre-action listener: _resource_record_pre_action 05:13:47 INFO - Running main action method: populate_webroot 05:13:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:13:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:13:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:13:47 INFO - '--tppagecycles', 05:13:47 INFO - '7'], 05:13:47 INFO - 'tests': ['tsvgm']}}, 05:13:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:13:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:13:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:13:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:13:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:13:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:13:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:47 INFO - 'talos_options': ['--disable-e10s'], 05:13:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:13:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:13:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:47 INFO - 'talos_options': ['--disable-e10s'], 05:13:47 INFO - 'tests': ['damp', 'tps']}, 05:13:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:47 INFO - 'tests': ['damp', 'tps']}, 05:13:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:13:47 INFO - 'tests': ['dromaeo_dom']}, 05:13:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:13:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:13:47 INFO - 'tests': ['a11yr', 05:13:47 INFO - 'ts_paint', 05:13:47 INFO - 'tpaint', 05:13:47 INFO - 'sessionrestore', 05:13:47 INFO - 'sessionrestore_no_auto_restore', 05:13:47 INFO - 'tabpaint']}, 05:13:47 INFO - 'other-e10s': {'tests': ['a11yr', 05:13:47 INFO - 'ts_paint', 05:13:47 INFO - 'tpaint', 05:13:47 INFO - 'sessionrestore', 05:13:47 INFO - 'sessionrestore_no_auto_restore', 05:13:47 INFO - 'tabpaint']}, 05:13:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:13:47 INFO - 'ts_paint', 05:13:47 INFO - 'tpaint', 05:13:47 INFO - 'sessionrestore', 05:13:47 INFO - 'sessionrestore_no_auto_restore', 05:13:47 INFO - 'tabpaint']}, 05:13:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:13:47 INFO - 'ts_paint', 05:13:47 INFO - 'tpaint', 05:13:47 INFO - 'sessionrestore', 05:13:47 INFO - 'sessionrestore_no_auto_restore', 05:13:47 INFO - 'tabpaint']}, 05:13:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:13:47 INFO - 'tests': ['a11yr', 05:13:47 INFO - 'ts_paint', 05:13:47 INFO - 'tpaint', 05:13:47 INFO - 'sessionrestore', 05:13:47 INFO - 'sessionrestore_no_auto_restore', 05:13:47 INFO - 'tabpaint']}, 05:13:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:13:47 INFO - 'tests': ['a11yr', 05:13:47 INFO - 'ts_paint', 05:13:47 INFO - 'tpaint', 05:13:47 INFO - 'sessionrestore', 05:13:47 INFO - 'sessionrestore_no_auto_restore', 05:13:47 INFO - 'tabpaint']}, 05:13:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:13:47 INFO - 'tests': ['tsvgx', 05:13:47 INFO - 'tsvgr_opacity', 05:13:47 INFO - 'tart', 05:13:47 INFO - 'tscrollx', 05:13:47 INFO - 'cart']}, 05:13:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:13:47 INFO - 'tsvgr_opacity', 05:13:47 INFO - 'tart', 05:13:47 INFO - 'tscrollx', 05:13:47 INFO - 'cart']}, 05:13:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:47 INFO - 'talos_options': ['--disable-e10s'], 05:13:47 INFO - 'tests': ['tp5o']}, 05:13:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:47 INFO - 'tests': ['tp5o']}, 05:13:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:47 INFO - 'talos_options': ['--disable-e10s', 05:13:47 INFO - '--xperf_path', 05:13:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:13:47 INFO - 'tests': ['tp5n']}, 05:13:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:47 INFO - 'talos_options': ['--xperf_path', 05:13:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:13:47 INFO - 'tests': ['tp5n']}}, 05:13:47 INFO - 'talos.zip': {'path': '', 05:13:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:13:47 INFO - Running post-action listener: _resource_record_post_action 05:13:47 INFO - ##### 05:13:47 INFO - ##### Running create-virtualenv step. 05:13:47 INFO - ##### 05:13:47 INFO - Running pre-action listener: _resource_record_pre_action 05:13:47 INFO - Running main action method: create_virtualenv 05:13:47 INFO - Creating virtualenv /builds/slave/test/build/venv 05:13:47 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 05:13:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:13:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:13:47 INFO - Using real prefix '/tools/python27' 05:13:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:13:48 INFO - Installing distribute.............................................................................................................................................................................................done. 05:13:50 INFO - Installing pip.................done. 05:13:50 INFO - Return code: 0 05:13:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:13:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:51 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')]} 05:13:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:51 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 0x10f9e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779c5c930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f87fe00>, '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 0x10f6e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87ab20>, '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.GiCMZzyyfz/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.sVoePtV9CF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 05:13:51 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 05:13:51 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 05:13:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GiCMZzyyfz/Render', 05:13:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:51 INFO - 'HOME': '/Users/cltbld', 05:13:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:51 INFO - 'LOGNAME': 'cltbld', 05:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:51 INFO - 'MOZ_NO_REMOTE': '1', 05:13:51 INFO - 'NO_EM_RESTART': '1', 05:13:51 INFO - 'PAGER': '/bin/cat', 05:13:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:51 INFO - 'PWD': '/builds/slave/test', 05:13:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:51 INFO - 'SHELL': '/bin/bash', 05:13:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sVoePtV9CF/Listeners', 05:13:51 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 05:13:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:51 INFO - 'USER': 'cltbld', 05:13:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:51 INFO - 'XPC_FLAGS': '0x0', 05:13:51 INFO - 'XPC_SERVICE_NAME': '0', 05:13:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:51 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 05:13:51 INFO - Cleaning up... 05:13:51 INFO - Return code: 0 05:13:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:13:51 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')]} 05:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:51 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')]} 05:13:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:51 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 0x10f9e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779c5c930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f87fe00>, '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 0x10f6e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87ab20>, '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.GiCMZzyyfz/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.sVoePtV9CF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 05:13:51 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 05:13:51 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 05:13:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GiCMZzyyfz/Render', 05:13:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:51 INFO - 'HOME': '/Users/cltbld', 05:13:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:51 INFO - 'LOGNAME': 'cltbld', 05:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:51 INFO - 'MOZ_NO_REMOTE': '1', 05:13:51 INFO - 'NO_EM_RESTART': '1', 05:13:51 INFO - 'PAGER': '/bin/cat', 05:13:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:51 INFO - 'PWD': '/builds/slave/test', 05:13:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:51 INFO - 'SHELL': '/bin/bash', 05:13:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sVoePtV9CF/Listeners', 05:13:51 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 05:13:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:51 INFO - 'USER': 'cltbld', 05:13:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:51 INFO - 'XPC_FLAGS': '0x0', 05:13:51 INFO - 'XPC_SERVICE_NAME': '0', 05:13:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:51 INFO - Downloading/unpacking psutil>=0.7.1 05:13:51 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 05:13:51 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 05:13:51 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 05:13:51 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 05:13:51 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 05:13:51 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 05:13:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:13:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:13:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:13:54 INFO - Installing collected packages: psutil 05:13:54 INFO - Running setup.py install for psutil 05:13:54 INFO - building 'psutil._psutil_osx' extension 05:13:54 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 05:13:55 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 05:13:55 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 05:13:55 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 05:13:55 INFO - building 'psutil._psutil_posix' extension 05:13:55 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 05:13:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:13:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:13:55 INFO - ^ 05:13:55 INFO - 1 warning generated. 05:13:55 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 05:13:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:13:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:13:55 INFO - Successfully installed psutil 05:13:55 INFO - Cleaning up... 05:13:55 INFO - Return code: 0 05:13:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:13:55 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')]} 05:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:55 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')]} 05:13:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:55 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 0x10f9e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779c5c930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f87fe00>, '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 0x10f6e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87ab20>, '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.GiCMZzyyfz/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.sVoePtV9CF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 05:13:55 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 05:13:55 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 05:13:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GiCMZzyyfz/Render', 05:13:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:55 INFO - 'HOME': '/Users/cltbld', 05:13:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:55 INFO - 'LOGNAME': 'cltbld', 05:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:55 INFO - 'MOZ_NO_REMOTE': '1', 05:13:55 INFO - 'NO_EM_RESTART': '1', 05:13:55 INFO - 'PAGER': '/bin/cat', 05:13:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:55 INFO - 'PWD': '/builds/slave/test', 05:13:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:55 INFO - 'SHELL': '/bin/bash', 05:13:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sVoePtV9CF/Listeners', 05:13:55 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 05:13:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:55 INFO - 'USER': 'cltbld', 05:13:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:55 INFO - 'XPC_FLAGS': '0x0', 05:13:55 INFO - 'XPC_SERVICE_NAME': '0', 05:13:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:13:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:13:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:13:58 INFO - Installing collected packages: mozsystemmonitor 05:13:58 INFO - Running setup.py install for mozsystemmonitor 05:13:58 INFO - Successfully installed mozsystemmonitor 05:13:58 INFO - Cleaning up... 05:13:58 INFO - Return code: 0 05:13:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:58 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 0x10f9e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779c5c930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f87fe00>, '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 0x10f6e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87ab20>, '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.GiCMZzyyfz/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.sVoePtV9CF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 05:13:58 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 05:13:58 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 05:13:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GiCMZzyyfz/Render', 05:13:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:58 INFO - 'HOME': '/Users/cltbld', 05:13:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:58 INFO - 'LOGNAME': 'cltbld', 05:13:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:58 INFO - 'MOZ_NO_REMOTE': '1', 05:13:58 INFO - 'NO_EM_RESTART': '1', 05:13:58 INFO - 'PAGER': '/bin/cat', 05:13:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:58 INFO - 'PWD': '/builds/slave/test', 05:13:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:58 INFO - 'SHELL': '/bin/bash', 05:13:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sVoePtV9CF/Listeners', 05:13:58 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 05:13:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:58 INFO - 'USER': 'cltbld', 05:13:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:58 INFO - 'XPC_FLAGS': '0x0', 05:13:58 INFO - 'XPC_SERVICE_NAME': '0', 05:13:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:59 INFO - Downloading/unpacking blobuploader==1.2.4 05:13:59 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 05:13:59 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 05:13:59 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 05:13:59 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 05:13:59 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 05:13:59 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 05:14:01 INFO - Downloading blobuploader-1.2.4.tar.gz 05:14:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:14:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:14:01 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 05:14:01 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 05:14:01 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 05:14:01 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 05:14:01 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 05:14:01 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 05:14:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:14:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:02 INFO - Downloading docopt-0.6.1.tar.gz 05:14:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:14:02 INFO - Installing collected packages: blobuploader, requests, docopt 05:14:02 INFO - Running setup.py install for blobuploader 05:14:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:14:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:14:02 INFO - Running setup.py install for requests 05:14:03 INFO - Running setup.py install for docopt 05:14:03 INFO - Successfully installed blobuploader requests docopt 05:14:03 INFO - Cleaning up... 05:14:03 INFO - Return code: 0 05:14:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:03 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 0x10f9e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779c5c930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f87fe00>, '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 0x10f6e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87ab20>, '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.GiCMZzyyfz/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.sVoePtV9CF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 05:14:03 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 05:14:03 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 05:14:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GiCMZzyyfz/Render', 05:14:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:03 INFO - 'HOME': '/Users/cltbld', 05:14:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:03 INFO - 'LOGNAME': 'cltbld', 05:14:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:03 INFO - 'MOZ_NO_REMOTE': '1', 05:14:03 INFO - 'NO_EM_RESTART': '1', 05:14:03 INFO - 'PAGER': '/bin/cat', 05:14:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:03 INFO - 'PWD': '/builds/slave/test', 05:14:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:03 INFO - 'SHELL': '/bin/bash', 05:14:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sVoePtV9CF/Listeners', 05:14:03 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 05:14:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:03 INFO - 'USER': 'cltbld', 05:14:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:03 INFO - 'XPC_FLAGS': '0x0', 05:14:03 INFO - 'XPC_SERVICE_NAME': '0', 05:14:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:03 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-8gM2qJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:04 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-4tY61j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:04 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-5UkGb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:04 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-SKdSoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:04 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-xaaSCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:04 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Y4RGvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:04 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-kDRFTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:04 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-ABrf_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:04 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-gnHZKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:04 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-3XpQ0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:04 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-tlQgOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:05 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-NaS2Bl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:05 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-30c0PE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:05 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-85DDzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:05 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-1Z8h1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:05 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-1FeAjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:05 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-t8izYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:14:05 INFO - Running setup.py install for manifestparser 05:14:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:14:05 INFO - Running setup.py install for mozcrash 05:14:05 INFO - Running setup.py install for mozdebug 05:14:06 INFO - Running setup.py install for mozdevice 05:14:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:14:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:14:06 INFO - Running setup.py install for mozfile 05:14:06 INFO - Running setup.py install for mozhttpd 05:14:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:14:06 INFO - Running setup.py install for mozinfo 05:14:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:14:06 INFO - Running setup.py install for mozInstall 05:14:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:14:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:14:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:14:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:14:06 INFO - Running setup.py install for mozleak 05:14:06 INFO - Running setup.py install for mozlog 05:14:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:14:07 INFO - Running setup.py install for moznetwork 05:14:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:14:07 INFO - Running setup.py install for mozprocess 05:14:07 INFO - Running setup.py install for mozprofile 05:14:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:14:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:14:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:14:07 INFO - Running setup.py install for mozrunner 05:14:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:14:07 INFO - Running setup.py install for mozscreenshot 05:14:07 INFO - Running setup.py install for moztest 05:14:08 INFO - Running setup.py install for mozversion 05:14:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:14:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:14:08 INFO - Cleaning up... 05:14:08 INFO - Return code: 0 05:14:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14: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')]} 05:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14: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')]} 05:14:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:08 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 0x10f9e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779c5c930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f87fe00>, '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 0x10f6e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87ab20>, '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.GiCMZzyyfz/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.sVoePtV9CF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 05:14:08 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 05:14:08 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 05:14:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GiCMZzyyfz/Render', 05:14:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:08 INFO - 'HOME': '/Users/cltbld', 05:14:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:08 INFO - 'LOGNAME': 'cltbld', 05:14:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:08 INFO - 'MOZ_NO_REMOTE': '1', 05:14:08 INFO - 'NO_EM_RESTART': '1', 05:14:08 INFO - 'PAGER': '/bin/cat', 05:14:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:08 INFO - 'PWD': '/builds/slave/test', 05:14:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:08 INFO - 'SHELL': '/bin/bash', 05:14:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sVoePtV9CF/Listeners', 05:14:08 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 05:14:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:08 INFO - 'USER': 'cltbld', 05:14:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:08 INFO - 'XPC_FLAGS': '0x0', 05:14:08 INFO - 'XPC_SERVICE_NAME': '0', 05:14:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:08 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-aKKIGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:08 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)) 05:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:08 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-CECnKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:08 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)) 05:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:08 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-lTfRqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:08 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)) 05:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:08 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-6STK6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:08 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)) 05:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:08 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-okx96f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:09 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)) 05:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:09 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-X2C7FD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:09 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)) 05:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:09 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-QwfhOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:09 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)) 05:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:09 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-nOCyCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:09 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)) 05:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:09 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-HQQCuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:09 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)) 05:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:09 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-5ZeNHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:09 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)) 05:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:09 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-MKE1BY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:09 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)) 05:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:09 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-7rCq97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:09 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)) 05:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:09 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-zi4kf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:09 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)) 05:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:09 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-0I25wW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:10 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)) 05:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:10 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-eZj054-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:10 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)) 05:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:10 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-YvtlHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:10 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)) 05:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:10 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-LWhk9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:10 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)) 05:14:10 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)) 05:14:10 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)) 05:14:10 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)) 05:14:10 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)) 05:14:10 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)) 05:14:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:12 INFO - Downloading blessings-1.6.tar.gz 05:14:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:14:12 INFO - Installing collected packages: blessings 05:14:12 INFO - Running setup.py install for blessings 05:14:13 INFO - Successfully installed blessings 05:14:13 INFO - Cleaning up... 05:14:13 INFO - Return code: 0 05:14:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:14:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:13 INFO - Reading from file tmpfile_stdout 05:14:13 INFO - Current package versions: 05:14:13 INFO - blessings == 1.6 05:14:13 INFO - blobuploader == 1.2.4 05:14:13 INFO - docopt == 0.6.1 05:14:13 INFO - manifestparser == 1.1 05:14:13 INFO - mozInstall == 1.12 05:14:13 INFO - mozcrash == 0.17 05:14:13 INFO - mozdebug == 0.1 05:14:13 INFO - mozdevice == 0.48 05:14:13 INFO - mozfile == 1.2 05:14:13 INFO - mozhttpd == 0.7 05:14:13 INFO - mozinfo == 0.9 05:14:13 INFO - mozleak == 0.1 05:14:13 INFO - mozlog == 3.1 05:14:13 INFO - moznetwork == 0.27 05:14:13 INFO - mozprocess == 0.22 05:14:13 INFO - mozprofile == 0.28 05:14:13 INFO - mozrunner == 6.11 05:14:13 INFO - mozscreenshot == 0.1 05:14:13 INFO - mozsystemmonitor == 0.0 05:14:13 INFO - moztest == 0.7 05:14:13 INFO - mozversion == 1.4 05:14:13 INFO - psutil == 3.1.1 05:14:13 INFO - requests == 1.2.3 05:14:13 INFO - wsgiref == 0.1.2 05:14:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:13 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 0x10f9e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779c5c930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f87fe00>, '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 0x10f6e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87ab20>, '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.GiCMZzyyfz/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.sVoePtV9CF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 05:14:13 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 05:14:13 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 05:14:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GiCMZzyyfz/Render', 05:14:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:13 INFO - 'HOME': '/Users/cltbld', 05:14:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:13 INFO - 'LOGNAME': 'cltbld', 05:14:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:13 INFO - 'MOZ_NO_REMOTE': '1', 05:14:13 INFO - 'NO_EM_RESTART': '1', 05:14:13 INFO - 'PAGER': '/bin/cat', 05:14:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:13 INFO - 'PWD': '/builds/slave/test', 05:14:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:13 INFO - 'SHELL': '/bin/bash', 05:14:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sVoePtV9CF/Listeners', 05:14:13 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 05:14:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:13 INFO - 'USER': 'cltbld', 05:14:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:13 INFO - 'XPC_FLAGS': '0x0', 05:14:13 INFO - 'XPC_SERVICE_NAME': '0', 05:14:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:13 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)) 05:14:13 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)) 05:14:13 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)) 05:14:13 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)) 05:14:13 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)) 05:14:13 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)) 05:14:13 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)) 05:14:13 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)) 05:14:13 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)) 05:14:13 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)) 05:14:13 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)) 05:14:13 INFO - Cleaning up... 05:14:13 INFO - Return code: 0 05:14:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779c5c930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f87fe00>, '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 0x10f6e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87ab20>, '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.GiCMZzyyfz/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.sVoePtV9CF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 05:14:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:14:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:14:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GiCMZzyyfz/Render', 05:14:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:13 INFO - 'HOME': '/Users/cltbld', 05:14:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:13 INFO - 'LOGNAME': 'cltbld', 05:14:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:13 INFO - 'MOZ_NO_REMOTE': '1', 05:14:13 INFO - 'NO_EM_RESTART': '1', 05:14:13 INFO - 'PAGER': '/bin/cat', 05:14:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:13 INFO - 'PWD': '/builds/slave/test', 05:14:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:13 INFO - 'SHELL': '/bin/bash', 05:14:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sVoePtV9CF/Listeners', 05:14:13 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 05:14:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:13 INFO - 'USER': 'cltbld', 05:14:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:13 INFO - 'XPC_FLAGS': '0x0', 05:14:13 INFO - 'XPC_SERVICE_NAME': '0', 05:14:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:13 INFO - Downloading/unpacking jsonschema 05:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:14:17 INFO - Downloading/unpacking functools32 (from jsonschema) 05:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:17 INFO - Downloading functools32-3.2.3-2.tar.gz 05:14:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:14:17 INFO - warning: no files found matching '*.txt' 05:14:17 INFO - no previously-included directories found matching 'build' 05:14:17 INFO - no previously-included directories found matching 'dist' 05:14:17 INFO - no previously-included directories found matching '.git*' 05:14:17 INFO - Installing collected packages: jsonschema, functools32 05:14:17 INFO - Running setup.py install for functools32 05:14:17 INFO - warning: no files found matching '*.txt' 05:14:17 INFO - no previously-included directories found matching 'build' 05:14:17 INFO - no previously-included directories found matching 'dist' 05:14:17 INFO - no previously-included directories found matching '.git*' 05:14:18 INFO - Successfully installed jsonschema functools32 05:14:18 INFO - Cleaning up... 05:14:18 INFO - Return code: 0 05:14:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:18 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 0x10f9e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779c5c930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f87fe00>, '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 0x10f6e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87ab20>, '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.GiCMZzyyfz/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.sVoePtV9CF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 05:14:18 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 05:14:18 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 05:14:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GiCMZzyyfz/Render', 05:14:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:18 INFO - 'HOME': '/Users/cltbld', 05:14:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:18 INFO - 'LOGNAME': 'cltbld', 05:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:18 INFO - 'MOZ_NO_REMOTE': '1', 05:14:18 INFO - 'NO_EM_RESTART': '1', 05:14:18 INFO - 'PAGER': '/bin/cat', 05:14:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:18 INFO - 'PWD': '/builds/slave/test', 05:14:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:18 INFO - 'SHELL': '/bin/bash', 05:14:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sVoePtV9CF/Listeners', 05:14:18 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 05:14:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:18 INFO - 'USER': 'cltbld', 05:14:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:18 INFO - 'XPC_FLAGS': '0x0', 05:14:18 INFO - 'XPC_SERVICE_NAME': '0', 05:14:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:18 INFO - Downloading/unpacking flake8 05:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:14:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:14:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:14:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:14:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:14:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:14:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:14:22 INFO - no previously-included directories found matching 'docs/_build' 05:14:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:14:22 INFO - Running setup.py install for pep8 05:14:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:14:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:14:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:14:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:14:22 INFO - no previously-included directories found matching 'docs/_build' 05:14:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:14:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:14:22 INFO - Cleaning up... 05:14:22 INFO - Return code: 0 05:14:22 INFO - Running post-action listener: _resource_record_post_action 05:14:22 INFO - Running post-action listener: _start_resource_monitoring 05:14:22 INFO - Starting resource monitoring. 05:14:22 INFO - ##### 05:14:22 INFO - ##### Running install step. 05:14:22 INFO - ##### 05:14:22 INFO - Running pre-action listener: _resource_record_pre_action 05:14:22 INFO - Running main action method: install 05:14:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:22 INFO - Reading from file tmpfile_stdout 05:14:22 INFO - Detecting whether we're running mozinstall >=1.0... 05:14:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:14:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:14:22 INFO - Reading from file tmpfile_stdout 05:14:22 INFO - Output received: 05:14:22 INFO - Usage: mozinstall [options] installer 05:14:22 INFO - Options: 05:14:22 INFO - -h, --help show this help message and exit 05:14:22 INFO - -d DEST, --destination=DEST 05:14:22 INFO - Directory to install application into. [default: 05:14:22 INFO - "/builds/slave/test"] 05:14:22 INFO - --app=APP Application being installed. [default: firefox] 05:14:22 INFO - mkdir: /builds/slave/test/build/application 05:14:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:14:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 05:14:44 INFO - Reading from file tmpfile_stdout 05:14:44 INFO - Output received: 05:14:44 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 05:14:44 INFO - Running post-action listener: _resource_record_post_action 05:14:44 INFO - ##### 05:14:44 INFO - ##### Running run-tests step. 05:14:44 INFO - ##### 05:14:44 INFO - Running pre-action listener: _resource_record_pre_action 05:14:44 INFO - Running main action method: run_tests 05:14:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:14:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:14:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:14:44 INFO - 2016-07-08 05:14:44.452 screenresolution[2258:23975] starting screenresolution argv=screenresolution get 05:14:44 INFO - 2016-07-08 05:14:44.454 screenresolution[2258:23975] Display 0: 1600x1200x32@60 05:14:44 INFO - 2016-07-08 05:14:44.460 screenresolution[2259:23978] starting screenresolution argv=screenresolution list 05:14:44 INFO - Available Modes on Display 0 05:14:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:14:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:14:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:14:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:14:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:14:44 INFO - Intel Iris: 05:14:44 INFO - Chipset Model: Intel Iris 05:14:44 INFO - Type: GPU 05:14:44 INFO - Bus: Built-In 05:14:44 INFO - VRAM (Dynamic, Max): 1536 MB 05:14:44 INFO - Vendor: Intel (0x8086) 05:14:44 INFO - Device ID: 0x0a2e 05:14:44 INFO - Revision ID: 0x0009 05:14:44 INFO - Displays: 05:14:44 INFO - Display: 05:14:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:14:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:14:44 INFO - Display Serial Number: 1600x1200 60 05:14:44 INFO - Main Display: Yes 05:14:44 INFO - Mirror: Off 05:14:44 INFO - Online: Yes 05:14:44 INFO - Rotation: Supported 05:14:44 INFO - Return code: 0 05:14:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:14:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:14:44 INFO - Python 2.7.3 05:14:44 INFO - Return code: 0 05:14:44 INFO - grabbing minidump binary from tooltool 05:14: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')]} 05:14:44 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 0x10f87fe00>, '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 0x10f6e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:14:44 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 05:14:44 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 05:14:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:14:44 INFO - Return code: 0 05:14:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:14:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:14:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:14:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:14:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:14:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:14:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:14:45 INFO - Return code: 0 05:14:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:14:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0382 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:14:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GiCMZzyyfz/Render', 05:14:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:45 INFO - 'HOME': '/Users/cltbld', 05:14:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:45 INFO - 'LOGNAME': 'cltbld', 05:14:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:14:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:14:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:45 INFO - 'MOZ_NO_REMOTE': '1', 05:14:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:14:45 INFO - 'NO_EM_RESTART': '1', 05:14:45 INFO - 'PAGER': '/bin/cat', 05:14:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:45 INFO - 'PWD': '/builds/slave/test', 05:14:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:14:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:45 INFO - 'SHELL': '/bin/bash', 05:14:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sVoePtV9CF/Listeners', 05:14:45 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 05:14:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:45 INFO - 'USER': 'cltbld', 05:14:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:45 INFO - 'XPC_FLAGS': '0x0', 05:14:45 INFO - 'XPC_SERVICE_NAME': '0', 05:14:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:14:46 INFO - mozversion application_buildid: 20160706215757 05:14:46 INFO - mozversion application_changeset: d142c49033c015f67272562b37dbe2912cfc7f14 05:14:46 INFO - mozversion application_display_name: Firefox 05:14:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:14:46 INFO - mozversion application_name: Firefox 05:14:46 INFO - mozversion application_remotingname: firefox 05:14:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:14:46 INFO - mozversion application_vendor: Mozilla 05:14:46 INFO - mozversion application_version: 48.0 05:14:46 INFO - mozversion platform_buildid: 20160706215757 05:14:46 INFO - mozversion platform_changeset: d142c49033c015f67272562b37dbe2912cfc7f14 05:14:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:14:46 INFO - mozversion platform_version: 48.0 05:14:46 INFO - using testdate: 1467980086 05:14:46 INFO - actual date: 1467980086 05:14:46 INFO - starting webserver on 'localhost:54730' 05:14:46 INFO - SUITE-START | Running 2 tests 05:14:46 INFO - TEST-START | dromaeo_css 05:14:46 INFO - operating with platform_type : mac_ 05:14:46 INFO - Initialising browser for dromaeo_css test... 05:14:46 INFO - TEST-INFO | started process 2277 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpDzkOI4/profile http://localhost:54730/getInfo.html) 05:14:48 INFO - PROCESS | 2277 | __metrics Screen width/height:1600/1200 05:14:48 INFO - PROCESS | 2277 | colorDepth:24 05:14:48 INFO - PROCESS | 2277 | Browser inner width/height: 1024/689 05:14:48 INFO - PROCESS | 2277 | __metrics 05:14:48 INFO - PROCESS | 2277 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:14:48 ERROR - PROCESS | 2277 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:14:49 INFO - TEST-INFO | 2277: exit 0 05:14:49 INFO - Browser initialized. 05:14:49 INFO - Running cycle 1/1 for dromaeo_css test... 05:14:49 INFO - TEST-INFO | started process 2280 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpDzkOI4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:15:01 INFO - PROCESS | 2280 | RSS: Main: 161935360 05:15:01 INFO - PROCESS | 2280 | 05:15:52 INFO - PROCESS | 2280 | 1467980152170 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] 05:18:01 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:54730/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54730/tests/dromaeo/cssquery-ext.html) 05:18:03 INFO - PROCESS | 2280 | RSS: Main: 250007552 05:18:03 INFO - PROCESS | 2280 | 05:21:05 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:54730/tests/dromaeo/cssquery-ext.html (next: http://localhost:54730/tests/dromaeo/cssquery-jquery.html) 05:21:06 INFO - PROCESS | 2280 | RSS: Main: 309542912 05:21:06 INFO - PROCESS | 2280 | 05:24:08 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:54730/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54730/tests/dromaeo/cssquery-mootools.html) 05:24:09 INFO - PROCESS | 2280 | RSS: Main: 308297728 05:24:09 INFO - PROCESS | 2280 | 05:27:11 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:54730/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54730/tests/dromaeo/cssquery-prototype.html) 05:27:11 INFO - PROCESS | 2280 | RSS: Main: 305655808 05:27:11 INFO - PROCESS | 2280 | 05:30:14 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:54730/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54730/tests/dromaeo/cssquery-yui.html) 05:30:15 INFO - PROCESS | 2280 | RSS: Main: 273125376 05:30:15 INFO - PROCESS | 2280 | 05:33:17 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:54730/tests/dromaeo/cssquery-yui.html (next: http://localhost:54730/tests/dromaeo/cssquery-dojo.html) 05:33:18 INFO - PROCESS | 2280 | RSS: Main: 316461056 05:33:18 INFO - PROCESS | 2280 | 05:33:18 INFO - PROCESS | 2280 | __start_tp_report 05:33:18 INFO - PROCESS | 2280 | _x_x_mozilla_page_load 05:33:18 INFO - PROCESS | 2280 | _x_x_mozilla_page_load_details 05:33:18 INFO - PROCESS | 2280 | |i|pagename|runs| 05:33:18 INFO - PROCESS | 2280 | |0;dojo.html;2976.8958086466973;3491.1446695559594;3524.5946716127655;3533.34633092878;3613.789380557513;5698.943010569893;5733.655980641154;5734.455226753462;5734.856628584287;5767.942320576798;4743.932615161587;5737.053386191284;5785.3925337839555;5785.652860828353;5796.275465566804;5134;5159.509846564589;5172.948270517303;5242.7378631068295;5280.683159010472;5226.425093239741;5260;5279.287296215;5292.603054770889;5299.258103865462;5191.195364718475;5239.161734122541;5267.3679158500845;5286.365636123671;5299.54953828925;5253.973730131344;5303.8408847734245;5310.654807437504;5321.780322904626;5334.759935802898;5142.665726727788;5148.871278218037;5165.690058596496;5243.606729495303;5270;4674.555917187896;5584.329880414336;5658.717064146817;5677.120046392815;5685.1756495307945;6275.560710750249;6307.0539419087045;6313.873722525524;6328.208973878265;6343.365663433666;6153.876922461525;6193.411625895533;6290.716917738667;6302.401271879165;6305.306416294204;5873;5890.175375447441;5907.232059832194;5930.169776231331;5941.732622032019;6300.873982520324;6307.463865571478;6317.6209427434505;6319.747210111636;6331.778387756406;6103.511719062464;6145.494353883327;6226;6247.781327653601;6261.843453913689;6289.685515724195;6316.778912738032;6326.114343991845;6329.841753956188;6331.715072821734;6224.782132625336;6295.464885484785;6317.936820631735;6326.177596912464;6334.0182271747035;4836.492168322276;6188.257409110845;6289.182406287154;6347.841303967345;6363;4945.431275403303;4989.451160372356;4992.700437973734;4998.475160108209;5001.124803159505;4474.7762611869275;4487.775611219426;4502.099580083996;4509.909801803946;4531.77341132942;5808.157817116579;5879.441453061952;5951.851203719942;5985.491233245136;6018.548608854353;5150.948490515047;5195.922061169085;6260.092286618506;6281.96859015702;6284.057391391328;5876.942150412881;5877.853053673693;5900.557458190653;5901.468867801918;5914.467697907209;5357;5376.193570964387;5431.131019036935;5431.321084864392;5467.535259502987;6351.142595749607;6401.807945761635;6421.935780642228;6432.646204458612;6446.194225721785;6194.81415557534;6201.859958967031;6299.6535190564955;6300.9684951574945;6315.305316415099;5403.378611459732;6426.485881129405;6492.220933488012;6492.285848556749;6497.577657452251;6312.337204593451;6316.989281714904;6325.588836725598;6326.810195694136;6329.620222786648;6183.96908015457;6214;6314.179156709598;6317.778877739257;6321.304656487875;6134.803982731443;6201.782937597162;6215.254169499689;6227.19046523949;6236.937630623635;6152.78465253714;6176.073588961692;6181.258249010148;6201.875962480815;6227.750889964351;5716.228309178207;6000.219971403864;6078.149059131813;6089.847753806191;6090.086487026982;6012.819615411545;6131.54013449002;6169.321374648695;6175.104609831639;6217.160683307696;6010.2186715726675;6032.125341825323;6053.485453736482;6061.7575296987625;6074 05:33:18 INFO - PROCESS | 2280 | |1;ext.html;510.7573902396361;516.8526969813604;544.9536889172788;546.8168163665172;596.7911231069129;31669.683303166963;32464.513032304505;37955.62044379559;38820.41769373462;38889.02777430566;42705.35941960873;43010.35484467736;43093.78453107738;43139.56860431395;43386;7648.235176482335;9658.903410965888;9881.851772223423;9888.208943284555;9891.208703303755;11887.940560297186;12132.45403956824;12143.939280303586;12153.513859445611;12172.269663820154;11765.294082355043;11885.762284754299;11886.940565297162;11890.40547972598;11891.464884080237;11845;11939.880601193963;11940.641780746548;11942.761144777056;11960.820587691192;11720.531178752839;11846.88153118475;11860.644180674593;11862.22895511789;11884.58403955866;40886;41313;41321.38017929733;41348.37977430341;41456.79271603653;3479.6346383629607;3501.9474707879403;3616.819159042064;3715.498407714951;3899.2201559688174;28583.428331433253;29312.853435732897;29583.556246656317;29658.406831863456;29782;2765;2773.459175460786;2790.8185967912023;2815.4369126174847;2821.830690158597;497461;697085;706556;707668;708064;37043.62956370402;63465.68267158635;63672.04491932625;64240;64394;35882.8205858969;35996;36016.819915900254;36020.459693104625;36051.4592281116;15511.069335839886;15623.218839058001;15682.921585392229;15704.371825126867;15707.528774136794;12427.37863106841;13294.069415140848;13306;13362.33188340579;13604.727905441836;8414.789630259293;8440.071592124861;8465.449745766506;8523.829523409497;8531.74404767858;3181.316017056344;3423.8288085595623;3548.1129717570607;3601.405768048295;3603.5135256740004;988.7124469890798;1062.3519653011656;1083.230906056693;1095.4960718069776;1118.3960661243;30587;30683.539746903814;31054.844725776227;31120;31291.53062704061;1131.688785583971;1141.6346769033996;1161.8257261410856;1187.6971372300009;1277.7444511097817;12195.326821121616;14870.330835112445;16369.918150409172;16583.91708041452;16949.576260593338;611109;613073;616852;618514;621679;61935.69032154811;62156.44608884814;62454;62463.6876815613;62660.746785064046;1420.5028240116083;1551.247644892249;1604.4304271983744;1607.6784643071198;1619.7165496038147;29833;30118.69881301159;30171.396572068876;30214.9424770132;30955;47347.52652473569;47814.56556303317;48041.27938080861;48302.27546586734;48306.758466207444;390887;393670.0316498399;393809;393878;394323;24367.878160609085;27243.455130897666;27387;27673.308167295978;27679;41912.58087419087;42307.36538951856;42476.93807654834;42577.361339579315;43262.91842703958;33456.367648451465;35302.82348588241;35374.29251415009;35768;35986.64013359833;34981.47527787136;35121.121971949884;35833.46249806203;35903.28193436169;36971.630283697894;36477.45283820798;36977.260454790216;37452.06369840776;37541.4368784463;37631.247375052895;32126;32126.03621891347;32307.030789076365;32362.676373235965;32553.67446325601;24731.62902556427;24828;24899.52665892624;24920.875395623632;24962.001519939 05:33:18 INFO - PROCESS | 2280 | |2;jquery.html;421.9936700949485;422.2631508018508;422.4698004004973;423.5468049187369;436.1582146457338;20216.8989155054;20221.393358199242;20749.688754668692;20996;21417.464563385885;21095.68356474654;21134.471638209052;21345.35963921081;21411.14355425781;21424.03591838367;5641.943580564182;6193.234928045508;6199.318075011745;6201.751929922798;6209.875802483948;6011.789587364443;6151.815445536641;6156.876862462748;6161.815145545641;6165.013597824349;5705.515031222351;6011.969940150293;6014.488768454665;6020.9096863546865;6023.096535519663;5851.502622277111;5945.137954996523;5957.791477298317;6123.724432400552;6124.755009799603;5874.853128671774;5883.764649414018;5903.822885313448;5914.645121292736;5920.9638313295;14620.561383158521;23514.412139696473;24909.128180513777;24922.252332429875;24942.87528562364;2173.152470536492;2179.836512261571;2319.744828068911;2396.484755777499;2400.59189937711;9810.90189098107;10063.5471403787;10221.9488902555;11652.94173529127;15336.079835209923;1722.4402069327518;1806.9096545172688;1807.1325763633529;1822.1618055694396;1859.7489338939206;109510;111477.44261278641;116842;119711.60576788416;121504;11577.884221157849;11581;11760;11773.470193841298;11906.583269585697;15061;15170.469033583771;15213.695726085416;15270.236488175547;15288.847111528965;8880.245179159714;8897.377183597215;8911.598978046004;8916.777080573038;9002.909970900208;7486.401087912954;8687.435316704521;8920;9009.684661036832;9012.81974360509;4310.978445107754;4338.110687309094;4621.792019359137;4651.697639653412;4671.649626278043;1568.968620627581;1737.8870373425686;1997.211101614883;2024.5343570978757;2096.2139197800825;922.6678395777515;963.3786207895868;965.165132160676;1018.4602160854758;1044.806710758512;19013.33453329127;20102.89948550277;20123.899380503004;20238.392848214284;20502.487437813874;1012.3217444312328;1018.180364806338;1026.1893104447552;1028.8508166315842;1071.6302875507938;10259.384436933808;10712.839307410395;10921.92483428146;11412.144089193343;11459.197856150156;668872;673762;674835;688302.1169788238;692433;87164.5641771787;109736;135769;142210;148235.51764482431;1042.6559235452282;1106.2311693373028;1114.626600088963;1115.6485707002269;1117.357519426333;18038.819611803712;18077.909610451865;18131.546711331794;18181.272749089887;19006;53520.464795351545;53636.73181634223;53755;54762;56122;80752;82859.56936603838;83378.16621833704;83695.90760231043;84798.57600711957;8215.671373145007;8295.751127466187;8500.957495212484;8519.062903080532;8724;11972.93128327604;12285.324307163191;12527;12529.248245105322;12886.613401597968;10111.797764044824;10717.196210284104;11057.61298354586;11085.27945683528;11436.256643318158;10705.732356690833;10881.727956801144;10997.780044399227;11059.115270778479;11228.719282018015;11061.502232399722;11115.833262500908;11222.55109795599;11420.143489238506;11513.654590362303;7140.928860670941;8308.210719981593;8315.916840831514;8339.749807505785;8589.570521474026;9682.128608425275;11046.944765276123;11082.889171108509;11145.442727863736;11484.770304594029 05:33:18 INFO - PROCESS | 2280 | |3;mootools.html;1475.011742132771;1610.9677806443872;1622.6267958369574;1889.763779527559;1998.5011241568823;8371.414001019932;9027.729168124959;9241.537923103851;9246.445213287207;9271.860922086165;8829.911700882989;9062.320325975545;9085.36402451827;9092.727218183465;9099.135582119688;2563.026050100959;2589.04205443986;2589.974100258992;2590.080521414899;2591.0931174089014;2515.3208633668896;2576.716561178269;2577.9484410311784;2581.2514370832437;2583.9793281653738;2122.2147805312056;2542.5296320180732;2566.461043180934;2571.151519998396;2582.987085064572;2533.74662533747;2556.003158768082;2566.2301309607137;2576.793856491476;2581.5740402833508;2526.5199612073648;2556.527042497144;2570.383108054072;2570.4730530241254;2574.871256437171;8855.557222138932;9075.773105672344;9163.81672366549;9225.67710130149;9229.538523073887;5609.3268807742925;5719.59962802604;5766.769329226826;5767.0772676372;5773.422657734235;3671.4859919611276;3721.5162028936334;3794.1083845296334;3805.2389522095414;3825.44531043;1066.296244478648;1079.4279032112993;1084.8426978088212;1119.7592517608753;1124.0670243697712;12231.816522752166;12398.256104633841;12535.18521296122;12568.120231583793;12575.931045861113;4819.204831202812;4840.685355451885;4841.080194762983;4900.289458028566;4907.411110666742;9782.119609235204;9783.266255030901;9788;9799.804003919882;9803.656872009446;5613.101903695389;5635.690037048002;5637.210790489335;5641.689707066151;5648.124540696116;4647.535246475325;4757.452892917358;5079.822206222764;5210.452902445189;5254.15933450646;4015.738976966488;4077.4903137107863;4135.91728165435;4141.855035073758;4191.1827193697545;1250.2123662092824;1271.053065466215;1280.929548874802;1289.9290539020255;1290.9096363254482;925.9027802080684;948.720127562357;959.3332633819429;965.7247684409909;997.3018886779283;6100.115483254903;6134.478569321568;6170.321264660884;6260.24877014752;6279.403456671609;480.495479746265;481.22042291488015;481.2949029671548;483.3595485981089;488.64329039801146;4017.5781542938157;4071.6946229032346;4087.1621317629815;4094.7338423003007;4163.208990291835;11044.723881903017;11389.974902258837;11547.307161570332;11553.653390398302;11590.362530060758;11349.262297950607;11378.715532111764;11382.089432845352;11399.772004559862;11403.315801051964;8487.660493580188;8806.91193088061;8842.602082906285;8992.640294388151;8994.965578958503;4892.31507588945;6010.7295171716505;6060.060690592965;6083.69581520931;6103.481204097701;9776.21790256792;10151.543180556744;10244.897551024394;10366.378017318984;10372.17022638172;10147.84778228342;10497.055265026183;10514.947425262824;10535.42055186972;10680.519376627915;6709.530332876847;6881.931180688129;6893.069435626327;7451.627418629156;7527;8084.474509156886;8167.224113709188;8491.915080849112;8699.173578510032;8738.25724813398;6596.538242322991;7959.920400795918;8012.078610959814;8119.350451963947;8134.877976830234;7561.7731468056045;7633.732819351519;7836.333911617348;7891.565963872042;8036.397270204641;7231.4214862811905;7267.30960558746;7288.599127048064;7308.269173082862;7309.634518274172;5099.974500127624;5209.765560549708;5263.973680131575;5363.812266570651;5375.247465354932;5292.692067457839;5305.681659100466;5340.439253878287;5402.243685883901;5495.395506494203 05:33:18 INFO - PROCESS | 2280 | |4;prototype.html;333.92809082844076;338.73982926341165;342.66247745970213;352.1038955857343;353.6286898756305;6506.3818937200895;6613.041109039186;6653.401193892549;6690.197176338834;6700.396964273221;6435.259945106314;6641.5682980606225;6657;6663.933360666392;6708.228553716324;4907.386576677915;4943.703377797326;4944.283078953549;4946.233333833262;4971.726555039471;4684.836030738925;4756.881077973044;4903.141950158726;4935.555799978001;4942.53045960635;4851.272309153637;4862.781174847123;4869.659123861331;4879.170541008037;4885.926711099336;4887.0714564232685;4901.485344038878;4903.362562866841;4919.950800492021;4931.926021109686;3809.257194847006;3912.823922923447;4220.2192594370135;4233.597808208216;4246.2356775780345;6371.808845734636;6377.8405539861415;6384.744610215585;6397.488200943913;6411.4229719325485;5657.462541058593;5842.824715258549;5851.619644723122;5865.20819689345;5876.236089308362;7009.859802803966;7029.402500787439;7055.4002909752735;7059.752908648224;7074.115735533059;4749.240121580565;4751.857444276678;4754.500777418356;4758.881027974294;4765.309030190638;7214;7481.476296659236;7520.586367749772;7524.134724506697;7524.914784938203;4640.1879671058105;4668.533146685304;4676.883077923011;4681.836135735233;4687;4760.880977975509;4772.212584923518;4774.618030557547;4775.403074615673;4779.737114458669;5689.9146512802345;5693.03218452864;5700.344460387026;5711.514521265739;5729.742161602738;6896.176538954231;7095.680694368766;7120.35916767483;7121.679524421414;7129.857402852018;1615.6122530592572;1659.9834001659829;1664.25108701085;1664.6171380582534;1667.641457086732;1029.2023681646783;1044.4430211992333;1058.3015209961388;1060.0247772049734;1060.607575197182;5516;6773.491988100912;6777.593344399352;6795.286494918061;6803.421709154678;7306.707731690779;7326.780196594111;7334.3765779908235;7337.119545654449;7362.631868406558;6783.457323414115;6784.728610855609;6784.8303792404595;6791.049253104569;6808.931910680929;3825.9426108608395;3860.285847118292;3862.4206369044305;3863.1114843586133;3865.207632435344;7446.25537446251;7684.654190561439;7709.5374277542405;7757.612119394008;7814.55430745314;7324.743633972892;7446.925530744623;7627.809304767426;7631.3513351365755;7647.197044310378;7778.261065198797;7784.883226751716;7800;7805.180456052146;7805.492642978302;7080.822979425556;7327.413806895329;7336.596487193256;7361.889571656322;7363.711350513746;6341.365863413622;6759.9522074078595;7190.460715446257;7218.602976836324;7232.132144142736;7729;7732.38140948734;7766.339861111869;7778.6888524460655;7797.532148071134;4396.648268138477;4477.306017567283;4582.427196600425;4637.513061128533;4697.389339385994;6373.267074286517;6772.898406523807;7265.891011634935;7266.600336981517;7269.5274807139485;7027.15674119109;7042.823929401806;7043;7047.40097091753;7056.823579410556;7003.054587630809;7003.614801185984;7007.334303241184;7007.544509606859;7010.684519196547;6644.734210631714;6859.965700171467;6874.072000279898;6902.240753517009;6906.37842594169;6950.3049695030095;6999.51517211403;7025.051618031501;7027.402670772837;7043.577385356895;5794.01501744696;6723.731050757915;6900.03399524057;6912.792616221522;6913.204981426999 05:33:18 INFO - PROCESS | 2280 | |5;yui.html;657.126022390221;680.4420375292259;758.9544627322362;771.2634434115421;806.2622700229291;15313.081215127086;15525.534233972985;15573.688526229469;15583.065016099014;15634;14322.570322890304;14397.424103035892;15878.761818572731;16380;16396.18019099046;805.0420000199744;974.2303580171656;983.4443539400216;993.8260804359244;995.5768798260747;818.4107442641289;971.1017308989192;993.5479356892597;994.9154321882648;996.6710985374846;1035.5702383510834;1046.304207701881;1048.6906362623006;1050.3540322701522;1050.9894901051007;829.8049958259804;845.5180547088162;1028.0387837372043;1041.052642095697;1044.0499145777344;629.2574761781096;631.516889579472;707.4481904114799;777.7161336112334;782.1474592693934;12748;15169.469068582657;15522.223888805514;15525.301361438762;15560;12514.060320650222;12669.68776951877;12803.039772017137;12927.35363231844;13082.149660272053;9920.561518579765;10172.643957461454;10213.642522511676;10352.171826253882;10888.891111088868;3492.8571072157497;3576.8211589420425;3586.5337506124297;3620.25784714133;3630.110622897407;75685.62157189233;79731.6013419935;86369.56815215947;86448;86913.56543217243;9483.33616646842;9598.280128990353;9633.566489507848;9804.117629413244;10369.377837329634;21232.36302910915;21316.04077816502;21320.36038918835;21347.46631334198;21365.89317053436;6092.847678808065;7425;7449.739259125905;7685.116211635622;7905.683772649145;4173.853915112956;4209;4281.036766727461;4348.695591308641;4508.391367165391;3812.6568608825337;3881.9611803882162;3899.8440062397754;3903.902402439905;3917.040325120364;693.7710555516668;704.3695892176576;707.6603230449413;733.3912852332551;736.0615215600153;523.3379774585153;547.6823442403396;592.8103007037706;619.0992106485054;623.4856243599049;6450.387213214737;8118.309943654737;8211.137830527709;8260.421770476009;8559.914400855912;527.2776296473779;551.7654996626418;595.3421469276387;622.8816524860353;635.9300476947532;3742.214135031619;3857;3890.280298144852;3904.843806247775;3929.6070392961046;68897;89262;90769;90978.54510727536;91400.08599914049;78865.60567197128;79370.60314698389;79475.60262198768;80129.79805302926;81876;68952;69237;69902.95145572821;70028;70468.2953170472;6981.476389270723;7596.582187979714;7831.216878312123;7840;7911.367090632621;47799.76100119477;48215.75892120517;48479.03041939071;48553.514464854896;49898.50101499084;62247.688761557714;62380.44048898814;62789.74420511473;63148;66334;9715.22278217755;9725.4650994196;9878.654247101316;10008.699739007841;10605.310654807412;10584.629537966135;10741.570337186738;10878.673639790819;11019.063379612822;11635.243709158878;9036.90963090387;10319.793604127724;10428.322159059637;10634.946825265824;10703.892961070602;11122.833157502806;11350;11548.59579914699;11664.416779161178;11753.294802311888;11328.94335528317;11457.598984035852;11464.25482343645;11671.941640291745;11768.470418831002;9359.698868771582;9659.227261818896;9702.223822094074;9914.702558923243;9943.353682010646;9758.072983066597;10026.799464010825;10036.197104231498;10081.848772268275;10083.344582602107 05:33:18 INFO - PROCESS | 2280 | __end_tp_report 05:33:18 INFO - PROCESS | 2280 | __start_cc_report 05:33:18 INFO - PROCESS | 2280 | _x_x_mozilla_cycle_collect,1290 05:33:18 INFO - PROCESS | 2280 | __end_cc_report 05:33:18 INFO - PROCESS | 2280 | __startTimestamp1467981198115__endTimestamp 05:33:18 INFO - PROCESS | 2280 | 05:33:18 INFO - PROCESS | 2280 | ------- Summary: start ------- 05:33:18 INFO - PROCESS | 2280 | Number of tests: 6 05:33:18 INFO - PROCESS | 2280 | 05:33:18 INFO - PROCESS | 2280 | [#0] dojo.html Cycles:165 Average:5778.14 Median:6018.55 stddev:662.81 (11.0%) stddev-sans-first:627.36 05:33:18 INFO - PROCESS | 2280 | Values: 2976.9 3491.1 3524.6 3533.3 3613.8 5698.9 5733.7 5734.5 5734.9 5767.9 4743.9 5737.1 5785.4 5785.7 5796.3 5134.0 5159.5 5172.9 5242.7 5280.7 5226.4 5260.0 5279.3 5292.6 5299.3 5191.2 5239.2 5267.4 5286.4 5299.5 5254.0 5303.8 5310.7 5321.8 5334.8 5142.7 5148.9 5165.7 5243.6 5270.0 4674.6 5584.3 5658.7 5677.1 5685.2 6275.6 6307.1 6313.9 6328.2 6343.4 6153.9 6193.4 6290.7 6302.4 6305.3 5873.0 5890.2 5907.2 5930.2 5941.7 6300.9 6307.5 6317.6 6319.7 6331.8 6103.5 6145.5 6226.0 6247.8 6261.8 6289.7 6316.8 6326.1 6329.8 6331.7 6224.8 6295.5 6317.9 6326.2 6334.0 4836.5 6188.3 6289.2 6347.8 6363.0 4945.4 4989.5 4992.7 4998.5 5001.1 4474.8 4487.8 4502.1 4509.9 4531.8 5808.2 5879.4 5951.9 5985.5 6018.5 5150.9 5195.9 6260.1 6282.0 6284.1 5876.9 5877.9 5900.6 5901.5 5914.5 5357.0 5376.2 5431.1 5431.3 5467.5 6351.1 6401.8 6421.9 6432.6 6446.2 6194.8 6201.9 6299.7 6301.0 6315.3 5403.4 6426.5 6492.2 6492.3 6497.6 6312.3 6317.0 6325.6 6326.8 6329.6 6184.0 6214.0 6314.2 6317.8 6321.3 6134.8 6201.8 6215.3 6227.2 6236.9 6152.8 6176.1 6181.3 6201.9 6227.8 5716.2 6000.2 6078.1 6089.8 6090.1 6012.8 6131.5 6169.3 6175.1 6217.2 6010.2 6032.1 6053.5 6061.8 6074.0 05:33:18 INFO - PROCESS | 2280 | 05:33:18 INFO - PROCESS | 2280 | [#1] ext.html Cycles:180 Average:67984.70 Median:19241.71 stddev:153468.46 (797.6%) stddev-sans-first:153815.36 05:33:18 INFO - PROCESS | 2280 | Values: 510.8 516.9 545.0 546.8 596.8 31669.7 32464.5 37955.6 38820.4 38889.0 42705.4 43010.4 43093.8 43139.6 43386.0 7648.2 9658.9 9881.9 9888.2 9891.2 11887.9 12132.5 12143.9 12153.5 12172.3 11765.3 11885.8 11886.9 11890.4 11891.5 11845.0 11939.9 11940.6 11942.8 11960.8 11720.5 11846.9 11860.6 11862.2 11884.6 40886.0 41313.0 41321.4 41348.4 41456.8 3479.6 3501.9 3616.8 3715.5 3899.2 28583.4 29312.9 29583.6 29658.4 29782.0 2765.0 2773.5 2790.8 2815.4 2821.8 497461.0 697085.0 706556.0 707668.0 708064.0 37043.6 63465.7 63672.0 64240.0 64394.0 35882.8 35996.0 36016.8 36020.5 36051.5 15511.1 15623.2 15682.9 15704.4 15707.5 12427.4 13294.1 13306.0 13362.3 13604.7 8414.8 8440.1 8465.4 8523.8 8531.7 3181.3 3423.8 3548.1 3601.4 3603.5 988.7 1062.4 1083.2 1095.5 1118.4 30587.0 30683.5 31054.8 31120.0 31291.5 1131.7 1141.6 1161.8 1187.7 1277.7 12195.3 14870.3 16369.9 16583.9 16949.6 611109.0 613073.0 616852.0 618514.0 621679.0 61935.7 62156.4 62454.0 62463.7 62660.7 1420.5 1551.2 1604.4 1607.7 1619.7 29833.0 30118.7 30171.4 30214.9 30955.0 47347.5 47814.6 48041.3 48302.3 48306.8 390887.0 393670.0 393809.0 393878.0 394323.0 24367.9 27243.5 27387.0 27673.3 27679.0 41912.6 42307.4 42476.9 42577.4 43262.9 33456.4 35302.8 35374.3 35768.0 35986.6 34981.5 35121.1 35833.5 35903.3 36971.6 36477.5 36977.3 37452.1 37541.4 37631.2 32126.0 32126.0 32307.0 32362.7 32553.7 24731.6 24828.0 24899.5 24920.9 24962.0 05:33:18 INFO - PROCESS | 2280 | 05:33:18 INFO - PROCESS | 2280 | [#2] jquery.html Cycles:180 Average:37344.12 Median:21239.92 stddev:112743.17 (530.8%) stddev-sans-first:113025.36 05:33:18 INFO - PROCESS | 2280 | Values: 422.0 422.3 422.5 423.5 436.2 20216.9 20221.4 20749.7 20996.0 21417.5 21095.7 21134.5 21345.4 21411.1 21424.0 5641.9 6193.2 6199.3 6201.8 6209.9 6011.8 6151.8 6156.9 6161.8 6165.0 5705.5 6012.0 6014.5 6020.9 6023.1 5851.5 5945.1 5957.8 6123.7 6124.8 5874.9 5883.8 5903.8 5914.6 5921.0 14620.6 23514.4 24909.1 24922.3 24942.9 2173.2 2179.8 2319.7 2396.5 2400.6 9810.9 10063.5 10221.9 11652.9 15336.1 1722.4 1806.9 1807.1 1822.2 1859.7 109510.0 111477.4 116842.0 119711.6 121504.0 11577.9 11581.0 11760.0 11773.5 11906.6 15061.0 15170.5 15213.7 15270.2 15288.8 8880.2 8897.4 8911.6 8916.8 9002.9 7486.4 8687.4 8920.0 9009.7 9012.8 4311.0 4338.1 4621.8 4651.7 4671.6 1569.0 1737.9 1997.2 2024.5 2096.2 922.7 963.4 965.2 1018.5 1044.8 19013.3 20102.9 20123.9 20238.4 20502.5 1012.3 1018.2 1026.2 1028.9 1071.6 10259.4 10712.8 10921.9 11412.1 11459.2 668872.0 673762.0 674835.0 688302.1 692433.0 87164.6 109736.0 135769.0 142210.0 148235.5 1042.7 1106.2 1114.6 1115.6 1117.4 18038.8 18077.9 18131.5 18181.3 19006.0 53520.5 53636.7 53755.0 54762.0 56122.0 80752.0 82859.6 83378.2 83695.9 84798.6 8215.7 8295.8 8501.0 8519.1 8724.0 11972.9 12285.3 12527.0 12529.2 12886.6 10111.8 10717.2 11057.6 11085.3 11436.3 10705.7 10881.7 10997.8 11059.1 11228.7 11061.5 11115.8 11222.6 11420.1 11513.7 7140.9 8308.2 8315.9 8339.7 8589.6 9682.1 11046.9 11082.9 11145.4 11484.8 05:33:18 INFO - PROCESS | 2280 | 05:33:18 INFO - PROCESS | 2280 | [#3] mootools.html Cycles:180 Average:5949.89 Median:4903.85 stddev:3343.37 (68.2%) stddev-sans-first:3335.84 05:33:18 INFO - PROCESS | 2280 | Values: 1475.0 1611.0 1622.6 1889.8 1998.5 8371.4 9027.7 9241.5 9246.4 9271.9 8829.9 9062.3 9085.4 9092.7 9099.1 2563.0 2589.0 2590.0 2590.1 2591.1 2515.3 2576.7 2577.9 2581.3 2584.0 2122.2 2542.5 2566.5 2571.2 2583.0 2533.7 2556.0 2566.2 2576.8 2581.6 2526.5 2556.5 2570.4 2570.5 2574.9 8855.6 9075.8 9163.8 9225.7 9229.5 5609.3 5719.6 5766.8 5767.1 5773.4 3671.5 3721.5 3794.1 3805.2 3825.4 1066.3 1079.4 1084.8 1119.8 1124.1 12231.8 12398.3 12535.2 12568.1 12575.9 4819.2 4840.7 4841.1 4900.3 4907.4 9782.1 9783.3 9788.0 9799.8 9803.7 5613.1 5635.7 5637.2 5641.7 5648.1 4647.5 4757.5 5079.8 5210.5 5254.2 4015.7 4077.5 4135.9 4141.9 4191.2 1250.2 1271.1 1280.9 1289.9 1290.9 925.9 948.7 959.3 965.7 997.3 6100.1 6134.5 6170.3 6260.2 6279.4 480.5 481.2 481.3 483.4 488.6 4017.6 4071.7 4087.2 4094.7 4163.2 11044.7 11390.0 11547.3 11553.7 11590.4 11349.3 11378.7 11382.1 11399.8 11403.3 8487.7 8806.9 8842.6 8992.6 8995.0 4892.3 6010.7 6060.1 6083.7 6103.5 9776.2 10151.5 10244.9 10366.4 10372.2 10147.8 10497.1 10514.9 10535.4 10680.5 6709.5 6881.9 6893.1 7451.6 7527.0 8084.5 8167.2 8491.9 8699.2 8738.3 6596.5 7959.9 8012.1 8119.4 8134.9 7561.8 7633.7 7836.3 7891.6 8036.4 7231.4 7267.3 7288.6 7308.3 7309.6 5100.0 5209.8 5264.0 5363.8 5375.2 5292.7 5305.7 5340.4 5402.2 5495.4 05:33:18 INFO - PROCESS | 2280 | 05:33:18 INFO - PROCESS | 2280 | [#4] prototype.html Cycles:180 Average:5812.74 Median:6660.47 stddev:1849.53 (27.8%) stddev-sans-first:1808.43 05:33:18 INFO - PROCESS | 2280 | Values: 333.9 338.7 342.7 352.1 353.6 6506.4 6613.0 6653.4 6690.2 6700.4 6435.3 6641.6 6657.0 6663.9 6708.2 4907.4 4943.7 4944.3 4946.2 4971.7 4684.8 4756.9 4903.1 4935.6 4942.5 4851.3 4862.8 4869.7 4879.2 4885.9 4887.1 4901.5 4903.4 4920.0 4931.9 3809.3 3912.8 4220.2 4233.6 4246.2 6371.8 6377.8 6384.7 6397.5 6411.4 5657.5 5842.8 5851.6 5865.2 5876.2 7009.9 7029.4 7055.4 7059.8 7074.1 4749.2 4751.9 4754.5 4758.9 4765.3 7214.0 7481.5 7520.6 7524.1 7524.9 4640.2 4668.5 4676.9 4681.8 4687.0 4760.9 4772.2 4774.6 4775.4 4779.7 5689.9 5693.0 5700.3 5711.5 5729.7 6896.2 7095.7 7120.4 7121.7 7129.9 1615.6 1660.0 1664.3 1664.6 1667.6 1029.2 1044.4 1058.3 1060.0 1060.6 5516.0 6773.5 6777.6 6795.3 6803.4 7306.7 7326.8 7334.4 7337.1 7362.6 6783.5 6784.7 6784.8 6791.0 6808.9 3825.9 3860.3 3862.4 3863.1 3865.2 7446.3 7684.7 7709.5 7757.6 7814.6 7324.7 7446.9 7627.8 7631.4 7647.2 7778.3 7784.9 7800.0 7805.2 7805.5 7080.8 7327.4 7336.6 7361.9 7363.7 6341.4 6760.0 7190.5 7218.6 7232.1 7729.0 7732.4 7766.3 7778.7 7797.5 4396.6 4477.3 4582.4 4637.5 4697.4 6373.3 6772.9 7265.9 7266.6 7269.5 7027.2 7042.8 7043.0 7047.4 7056.8 7003.1 7003.6 7007.3 7007.5 7010.7 6644.7 6860.0 6874.1 6902.2 6906.4 6950.3 6999.5 7025.1 7027.4 7043.6 5794.0 6723.7 6900.0 6912.8 6913.2 05:33:18 INFO - PROCESS | 2280 | 05:33:18 INFO - PROCESS | 2280 | [#5] yui.html Cycles:180 Average:18074.52 Median:549.72 stddev:25267.94 (4596.5%) stddev-sans-first:25304.98 05:33:18 INFO - PROCESS | 2280 | Values: 657.1 680.4 759.0 771.3 806.3 15313.1 15525.5 15573.7 15583.1 15634.0 14322.6 14397.4 15878.8 16380.0 16396.2 805.0 974.2 983.4 993.8 995.6 818.4 971.1 993.5 994.9 996.7 1035.6 1046.3 1048.7 1050.4 1051.0 829.8 845.5 1028.0 1041.1 1044.0 629.3 631.5 707.4 777.7 782.1 12748.0 15169.5 15522.2 15525.3 15560.0 12514.1 12669.7 12803.0 12927.4 13082.1 9920.6 10172.6 10213.6 10352.2 10888.9 3492.9 3576.8 3586.5 3620.3 3630.1 75685.6 79731.6 86369.6 86448.0 86913.6 9483.3 9598.3 9633.6 9804.1 10369.4 21232.4 21316.0 21320.4 21347.5 21365.9 6092.8 7425.0 7449.7 7685.1 7905.7 4173.9 4209.0 4281.0 4348.7 4508.4 3812.7 3882.0 3899.8 3903.9 3917.0 693.8 704.4 707.7 733.4 736.1 523.3 547.7 592.8 619.1 623.5 6450.4 8118.3 8211.1 8260.4 8559.9 527.3 551.8 595.3 622.9 635.9 3742.2 3857.0 3890.3 3904.8 3929.6 68897.0 89262.0 90769.0 90978.5 91400.1 78865.6 79370.6 79475.6 80129.8 81876.0 68952.0 69237.0 69903.0 70028.0 70468.3 6981.5 7596.6 7831.2 7840.0 7911.4 47799.8 48215.8 48479.0 48553.5 49898.5 62247.7 62380.4 62789.7 63148.0 66334.0 9715.2 9725.5 9878.7 10008.7 10605.3 10584.6 10741.6 10878.7 11019.1 11635.2 9036.9 10319.8 10428.3 10634.9 10703.9 11122.8 11350.0 11548.6 11664.4 11753.3 11328.9 11457.6 11464.3 11671.9 11768.5 9359.7 9659.2 9702.2 9914.7 9943.4 9758.1 10026.8 10036.2 10081.8 10083.3 05:33:18 INFO - PROCESS | 2280 | -------- Summary: end -------- 05:33:18 INFO - PROCESS | 2280 | 05:33:18 INFO - TEST-INFO | 2280: exit 0 05:33:18 INFO - TEST-OK | dromaeo_css | took 1112595ms 05:33:18 INFO - TEST-START | kraken 05:33:18 INFO - operating with platform_type : mac_ 05:33:18 INFO - Initialising browser for kraken test... 05:33:18 INFO - TEST-INFO | started process 2283 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmp8HYqBv/profile http://localhost:54730/getInfo.html) 05:33:21 INFO - PROCESS | 2283 | __metrics Screen width/height:1600/1200 05:33:21 INFO - PROCESS | 2283 | colorDepth:24 05:33:21 INFO - PROCESS | 2283 | Browser inner width/height: 1024/689 05:33:21 INFO - PROCESS | 2283 | __metrics 05:33:21 INFO - PROCESS | 2283 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:33:21 ERROR - PROCESS | 2283 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:33:21 INFO - TEST-INFO | 2283: exit 0 05:33:21 INFO - Browser initialized. 05:33:21 INFO - Running cycle 1/1 for kraken test... 05:33:21 INFO - TEST-INFO | started process 2286 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmp8HYqBv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:33 INFO - PROCESS | 2286 | RSS: Main: 161382400 05:33:33 INFO - PROCESS | 2286 | 05:33:36 INFO - PROCESS | 2286 | Cycle 1(1): loaded http://localhost:54730/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54730/tests/kraken/driver.html?testName=audio-beat-detection) 05:33:37 INFO - PROCESS | 2286 | RSS: Main: 224403456 05:33:37 INFO - PROCESS | 2286 | 05:33:39 INFO - PROCESS | 2286 | Cycle 1(1): loaded http://localhost:54730/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54730/tests/kraken/driver.html?testName=audio-dft) 05:33:40 INFO - PROCESS | 2286 | RSS: Main: 239927296 05:33:40 INFO - PROCESS | 2286 | 05:33:43 INFO - PROCESS | 2286 | Cycle 1(1): loaded http://localhost:54730/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54730/tests/kraken/driver.html?testName=audio-fft) 05:33:44 INFO - PROCESS | 2286 | RSS: Main: 326430720 05:33:44 INFO - PROCESS | 2286 | 05:33:46 INFO - PROCESS | 2286 | Cycle 1(1): loaded http://localhost:54730/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54730/tests/kraken/driver.html?testName=audio-oscillator) 05:33:46 INFO - PROCESS | 2286 | RSS: Main: 230457344 05:33:46 INFO - PROCESS | 2286 | 05:33:48 INFO - PROCESS | 2286 | Cycle 1(1): loaded http://localhost:54730/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54730/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:33:49 INFO - PROCESS | 2286 | RSS: Main: 230821888 05:33:49 INFO - PROCESS | 2286 | 05:33:52 INFO - PROCESS | 2286 | Cycle 1(1): loaded http://localhost:54730/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54730/tests/kraken/driver.html?testName=imaging-darkroom) 05:33:52 INFO - PROCESS | 2286 | RSS: Main: 270557184 05:33:52 INFO - PROCESS | 2286 | 05:33:55 INFO - PROCESS | 2286 | Cycle 1(1): loaded http://localhost:54730/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54730/tests/kraken/driver.html?testName=imaging-desaturate) 05:33:56 INFO - PROCESS | 2286 | RSS: Main: 271269888 05:33:56 INFO - PROCESS | 2286 | 05:33:58 INFO - PROCESS | 2286 | Cycle 1(1): loaded http://localhost:54730/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54730/tests/kraken/driver.html?testName=json-parse-financial) 05:33:59 INFO - PROCESS | 2286 | RSS: Main: 268062720 05:33:59 INFO - PROCESS | 2286 | 05:34:01 INFO - PROCESS | 2286 | Cycle 1(1): loaded http://localhost:54730/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54730/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:34:01 INFO - PROCESS | 2286 | RSS: Main: 260726784 05:34:01 INFO - PROCESS | 2286 | 05:34:03 INFO - PROCESS | 2286 | Cycle 1(1): loaded http://localhost:54730/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54730/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:34:04 INFO - PROCESS | 2286 | RSS: Main: 267870208 05:34:04 INFO - PROCESS | 2286 | 05:34:06 INFO - PROCESS | 2286 | Cycle 1(1): loaded http://localhost:54730/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54730/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:34:06 INFO - PROCESS | 2286 | RSS: Main: 271212544 05:34:06 INFO - PROCESS | 2286 | 05:34:09 INFO - PROCESS | 2286 | Cycle 1(1): loaded http://localhost:54730/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54730/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:34:10 INFO - PROCESS | 2286 | RSS: Main: 278208512 05:34:10 INFO - PROCESS | 2286 | 05:34:13 INFO - PROCESS | 2286 | Cycle 1(1): loaded http://localhost:54730/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54730/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:34:13 INFO - PROCESS | 2286 | RSS: Main: 275595264 05:34:13 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | Cycle 1(1): loaded http://localhost:54730/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54730/tests/kraken/driver.html?testName=ai-astar) 05:34:15 INFO - PROCESS | 2286 | RSS: Main: 337473536 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | __start_tp_report 05:34:15 INFO - PROCESS | 2286 | _x_x_mozilla_page_load 05:34:15 INFO - PROCESS | 2286 | _x_x_mozilla_page_load_details 05:34:15 INFO - PROCESS | 2286 | |i|pagename|runs| 05:34:15 INFO - PROCESS | 2286 | |0;ai-astar;113;114;120;112;113;112;114;113;113;135 05:34:15 INFO - PROCESS | 2286 | |1;audio-beat-detection;161;144;146;166;116;139;117;150;152;112 05:34:15 INFO - PROCESS | 2286 | |2;audio-dft;184;186;185;183;182;186;187;189;256;187 05:34:15 INFO - PROCESS | 2286 | |3;audio-fft;72;78;74;157;56;55;57;63;138;59 05:34:15 INFO - PROCESS | 2286 | |4;audio-oscillator;93;94;144;79;78;87;126;79;76;125 05:34:15 INFO - PROCESS | 2286 | |5;imaging-gaussian-blur;88;131;88;86;87;83;88;83;90;85 05:34:15 INFO - PROCESS | 2286 | |6;imaging-darkroom;92;93;93;92;91;93;117;150;102;91 05:34:15 INFO - PROCESS | 2286 | |7;imaging-desaturate;85;84;83;86;85;84;84;86;85;86 05:34:15 INFO - PROCESS | 2286 | |8;json-parse-financial;57;64;63;62;63;64;64;80;58;55 05:34:15 INFO - PROCESS | 2286 | |9;json-stringify-tinderbox;61;66;62;68;76;72;72;67;66;68 05:34:15 INFO - PROCESS | 2286 | |10;stanford-crypto-aes;81;80;79;80;78;78;79;77;80;84 05:34:15 INFO - PROCESS | 2286 | |11;stanford-crypto-ccm;138;144;148;117;123;125;123;125;145;144 05:34:15 INFO - PROCESS | 2286 | |12;stanford-crypto-pbkdf2;162;163;160;162;159;161;159;162;161;158 05:34:15 INFO - PROCESS | 2286 | |13;stanford-crypto-sha256-iterative;66;67;68;65;65;66;65;68;66;66 05:34:15 INFO - PROCESS | 2286 | __end_tp_report 05:34:15 INFO - PROCESS | 2286 | __start_cc_report 05:34:15 INFO - PROCESS | 2286 | _x_x_mozilla_cycle_collect,1937 05:34:15 INFO - PROCESS | 2286 | __end_cc_report 05:34:15 INFO - PROCESS | 2286 | __startTimestamp1467981255572__endTimestamp 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | ------- Summary: start ------- 05:34:15 INFO - PROCESS | 2286 | Number of tests: 14 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | [#0] ai-astar Cycles:10 Average:115.90 Median:113.50 stddev:7.09 (6.3%) stddev-sans-first:7.45 05:34:15 INFO - PROCESS | 2286 | Values: 113.0 114.0 120.0 112.0 113.0 112.0 114.0 113.0 113.0 135.0 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:148.00 stddev:19.15 (12.9%) stddev-sans-first:18.79 05:34:15 INFO - PROCESS | 2286 | Values: 161.0 144.0 146.0 166.0 116.0 139.0 117.0 150.0 152.0 112.0 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | [#2] audio-dft Cycles:10 Average:192.50 Median:186.50 stddev:22.41 (12.0%) stddev-sans-first:23.55 05:34:15 INFO - PROCESS | 2286 | Values: 184.0 186.0 185.0 183.0 182.0 186.0 187.0 189.0 256.0 187.0 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | [#3] audio-fft Cycles:10 Average:80.90 Median:61.00 stddev:36.28 (59.5%) stddev-sans-first:38.34 05:34:15 INFO - PROCESS | 2286 | Values: 72.0 78.0 74.0 157.0 56.0 55.0 57.0 63.0 138.0 59.0 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | [#4] audio-oscillator Cycles:10 Average:98.10 Median:79.00 stddev:24.49 (31.0%) stddev-sans-first:25.90 05:34:15 INFO - PROCESS | 2286 | Values: 93.0 94.0 144.0 79.0 78.0 87.0 126.0 79.0 76.0 125.0 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | [#5] imaging-gaussian-blur Cycles:10 Average:90.90 Median:87.50 stddev:14.27 (16.3%) stddev-sans-first:15.10 05:34:15 INFO - PROCESS | 2286 | Values: 88.0 131.0 88.0 86.0 87.0 83.0 88.0 83.0 90.0 85.0 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:92.00 stddev:18.89 (20.5%) stddev-sans-first:19.72 05:34:15 INFO - PROCESS | 2286 | Values: 92.0 93.0 93.0 92.0 91.0 93.0 117.0 150.0 102.0 91.0 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.03 (1.2%) stddev-sans-first:1.09 05:34:15 INFO - PROCESS | 2286 | Values: 85.0 84.0 83.0 86.0 85.0 84.0 84.0 86.0 85.0 86.0 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | [#8] json-parse-financial Cycles:10 Average:63.00 Median:63.50 stddev:6.82 (10.7%) stddev-sans-first:6.87 05:34:15 INFO - PROCESS | 2286 | Values: 57.0 64.0 63.0 62.0 63.0 64.0 64.0 80.0 58.0 55.0 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:4.59 (6.7%) stddev-sans-first:4.16 05:34:15 INFO - PROCESS | 2286 | Values: 61.0 66.0 62.0 68.0 76.0 72.0 72.0 67.0 66.0 68.0 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:80.00 stddev:1.96 (2.4%) stddev-sans-first:2.01 05:34:15 INFO - PROCESS | 2286 | Values: 81.0 80.0 79.0 80.0 78.0 78.0 79.0 77.0 80.0 84.0 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | [#11] stanford-crypto-ccm Cycles:10 Average:133.20 Median:141.00 stddev:11.64 (8.3%) stddev-sans-first:12.22 05:34:15 INFO - PROCESS | 2286 | Values: 138.0 144.0 148.0 117.0 123.0 125.0 123.0 125.0 145.0 144.0 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.70 Median:161.50 stddev:1.64 (1.0%) stddev-sans-first:1.67 05:34:15 INFO - PROCESS | 2286 | Values: 162.0 163.0 160.0 162.0 159.0 161.0 159.0 162.0 161.0 158.0 05:34:15 INFO - PROCESS | 2286 | 05:34:15 INFO - PROCESS | 2286 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 05:34:15 INFO - PROCESS | 2286 | Values: 66.0 67.0 68.0 65.0 65.0 66.0 65.0 68.0 66.0 66.0 05:34:15 INFO - PROCESS | 2286 | -------- Summary: end -------- 05:34:15 INFO - PROCESS | 2286 | 05:34:16 INFO - TEST-INFO | 2286: exit 0 05:34:16 INFO - TEST-OK | kraken | took 57629ms 05:34:16 INFO - SUITE-END | took 1170s 05:34:16 INFO - Completed test suite (00:19:30) 05:34:16 INFO - javascript benchmark 05:34:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2976.8958086466973, 3491.1446695559594, 3524.5946716127655, 3533.34633092878, 3613.789380557513, 5698.943010569893, 5733.655980641154, 5734.455226753462, 5734.856628584287, 5767.942320576798, 4743.932615161587, 5737.053386191284, 5785.3925337839555, 5785.652860828353, 5796.275465566804, 5134.0, 5159.509846564589, 5172.948270517303, 5242.7378631068295, 5280.683159010472, 5226.425093239741, 5260.0, 5279.287296215, 5292.603054770889, 5299.258103865462, 5191.195364718475, 5239.161734122541, 5267.3679158500845, 5286.365636123671, 5299.54953828925, 5253.973730131344, 5303.8408847734245, 5310.654807437504, 5321.780322904626, 5334.759935802898, 5142.665726727788, 5148.871278218037, 5165.690058596496, 5243.606729495303, 5270.0, 4674.555917187896, 5584.329880414336, 5658.717064146817, 5677.120046392815, 5685.1756495307945, 6275.560710750249, 6307.0539419087045, 6313.873722525524, 6328.208973878265, 6343.365663433666, 6153.876922461525, 6193.411625895533, 6290.716917738667, 6302.401271879165, 6305.306416294204, 5873.0, 5890.175375447441, 5907.232059832194, 5930.169776231331, 5941.732622032019, 6300.873982520324, 6307.463865571478, 6317.6209427434505, 6319.747210111636, 6331.778387756406, 6103.511719062464, 6145.494353883327, 6226.0, 6247.781327653601, 6261.843453913689, 6289.685515724195, 6316.778912738032, 6326.114343991845, 6329.841753956188, 6331.715072821734, 6224.782132625336, 6295.464885484785, 6317.936820631735, 6326.177596912464, 6334.0182271747035, 4836.492168322276, 6188.257409110845, 6289.182406287154, 6347.841303967345, 6363.0, 4945.431275403303, 4989.451160372356, 4992.700437973734, 4998.475160108209, 5001.124803159505, 4474.7762611869275, 4487.775611219426, 4502.099580083996, 4509.909801803946, 4531.77341132942, 5808.157817116579, 5879.441453061952, 5951.851203719942, 5985.491233245136, 6018.548608854353, 5150.948490515047, 5195.922061169085, 6260.092286618506, 6281.96859015702, 6284.057391391328, 5876.942150412881, 5877.853053673693, 5900.557458190653, 5901.468867801918, 5914.467697907209, 5357.0, 5376.193570964387, 5431.131019036935, 5431.321084864392, 5467.535259502987, 6351.142595749607, 6401.807945761635, 6421.935780642228, 6432.646204458612, 6446.194225721785, 6194.81415557534, 6201.859958967031, 6299.6535190564955, 6300.9684951574945, 6315.305316415099, 5403.378611459732, 6426.485881129405, 6492.220933488012, 6492.285848556749, 6497.577657452251, 6312.337204593451, 6316.989281714904, 6325.588836725598, 6326.810195694136, 6329.620222786648, 6183.96908015457, 6214.0, 6314.179156709598, 6317.778877739257, 6321.304656487875, 6134.803982731443, 6201.782937597162, 6215.254169499689, 6227.19046523949, 6236.937630623635, 6152.78465253714, 6176.073588961692, 6181.258249010148, 6201.875962480815, 6227.750889964351, 5716.228309178207, 6000.219971403864, 6078.149059131813, 6089.847753806191, 6090.086487026982, 6012.819615411545, 6131.54013449002, 6169.321374648695, 6175.104609831639, 6217.160683307696, 6010.2186715726675, 6032.125341825323, 6053.485453736482, 6061.7575296987625, 6074.0], "value": 5737.404547902705, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.7573902396361, 516.8526969813604, 544.9536889172788, 546.8168163665172, 596.7911231069129, 31669.683303166963, 32464.513032304505, 37955.62044379559, 38820.41769373462, 38889.02777430566, 42705.35941960873, 43010.35484467736, 43093.78453107738, 43139.56860431395, 43386.0, 7648.235176482335, 9658.903410965888, 9881.851772223423, 9888.208943284555, 9891.208703303755, 11887.940560297186, 12132.45403956824, 12143.939280303586, 12153.513859445611, 12172.269663820154, 11765.294082355043, 11885.762284754299, 11886.940565297162, 11890.40547972598, 11891.464884080237, 11845.0, 11939.880601193963, 11940.641780746548, 11942.761144777056, 11960.820587691192, 11720.531178752839, 11846.88153118475, 11860.644180674593, 11862.22895511789, 11884.58403955866, 40886.0, 41313.0, 41321.38017929733, 41348.37977430341, 41456.79271603653, 3479.6346383629607, 3501.9474707879403, 3616.819159042064, 3715.498407714951, 3899.2201559688174, 28583.428331433253, 29312.853435732897, 29583.556246656317, 29658.406831863456, 29782.0, 2765.0, 2773.459175460786, 2790.8185967912023, 2815.4369126174847, 2821.830690158597, 497461.0, 697085.0, 706556.0, 707668.0, 708064.0, 37043.62956370402, 63465.68267158635, 63672.04491932625, 64240.0, 64394.0, 35882.8205858969, 35996.0, 36016.819915900254, 36020.459693104625, 36051.4592281116, 15511.069335839886, 15623.218839058001, 15682.921585392229, 15704.371825126867, 15707.528774136794, 12427.37863106841, 13294.069415140848, 13306.0, 13362.33188340579, 13604.727905441836, 8414.789630259293, 8440.071592124861, 8465.449745766506, 8523.829523409497, 8531.74404767858, 3181.316017056344, 3423.8288085595623, 3548.1129717570607, 3601.405768048295, 3603.5135256740004, 988.7124469890798, 1062.3519653011656, 1083.230906056693, 1095.4960718069776, 1118.3960661243, 30587.0, 30683.539746903814, 31054.844725776227, 31120.0, 31291.53062704061, 1131.688785583971, 1141.6346769033996, 1161.8257261410856, 1187.6971372300009, 1277.7444511097817, 12195.326821121616, 14870.330835112445, 16369.918150409172, 16583.91708041452, 16949.576260593338, 611109.0, 613073.0, 616852.0, 618514.0, 621679.0, 61935.69032154811, 62156.44608884814, 62454.0, 62463.6876815613, 62660.746785064046, 1420.5028240116083, 1551.247644892249, 1604.4304271983744, 1607.6784643071198, 1619.7165496038147, 29833.0, 30118.69881301159, 30171.396572068876, 30214.9424770132, 30955.0, 47347.52652473569, 47814.56556303317, 48041.27938080861, 48302.27546586734, 48306.758466207444, 390887.0, 393670.0316498399, 393809.0, 393878.0, 394323.0, 24367.878160609085, 27243.455130897666, 27387.0, 27673.308167295978, 27679.0, 41912.58087419087, 42307.36538951856, 42476.93807654834, 42577.361339579315, 43262.91842703958, 33456.367648451465, 35302.82348588241, 35374.29251415009, 35768.0, 35986.64013359833, 34981.47527787136, 35121.121971949884, 35833.46249806203, 35903.28193436169, 36971.630283697894, 36477.45283820798, 36977.260454790216, 37452.06369840776, 37541.4368784463, 37631.247375052895, 32126.0, 32126.03621891347, 32307.030789076365, 32362.676373235965, 32553.67446325601, 24731.62902556427, 24828.0, 24899.52665892624, 24920.875395623632, 24962.001519939], "value": 19168.498575030786, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.9936700949485, 422.2631508018508, 422.4698004004973, 423.5468049187369, 436.1582146457338, 20216.8989155054, 20221.393358199242, 20749.688754668692, 20996.0, 21417.464563385885, 21095.68356474654, 21134.471638209052, 21345.35963921081, 21411.14355425781, 21424.03591838367, 5641.943580564182, 6193.234928045508, 6199.318075011745, 6201.751929922798, 6209.875802483948, 6011.789587364443, 6151.815445536641, 6156.876862462748, 6161.815145545641, 6165.013597824349, 5705.515031222351, 6011.969940150293, 6014.488768454665, 6020.9096863546865, 6023.096535519663, 5851.502622277111, 5945.137954996523, 5957.791477298317, 6123.724432400552, 6124.755009799603, 5874.853128671774, 5883.764649414018, 5903.822885313448, 5914.645121292736, 5920.9638313295, 14620.561383158521, 23514.412139696473, 24909.128180513777, 24922.252332429875, 24942.87528562364, 2173.152470536492, 2179.836512261571, 2319.744828068911, 2396.484755777499, 2400.59189937711, 9810.90189098107, 10063.5471403787, 10221.9488902555, 11652.94173529127, 15336.079835209923, 1722.4402069327518, 1806.9096545172688, 1807.1325763633529, 1822.1618055694396, 1859.7489338939206, 109510.0, 111477.44261278641, 116842.0, 119711.60576788416, 121504.0, 11577.884221157849, 11581.0, 11760.0, 11773.470193841298, 11906.583269585697, 15061.0, 15170.469033583771, 15213.695726085416, 15270.236488175547, 15288.847111528965, 8880.245179159714, 8897.377183597215, 8911.598978046004, 8916.777080573038, 9002.909970900208, 7486.401087912954, 8687.435316704521, 8920.0, 9009.684661036832, 9012.81974360509, 4310.978445107754, 4338.110687309094, 4621.792019359137, 4651.697639653412, 4671.649626278043, 1568.968620627581, 1737.8870373425686, 1997.211101614883, 2024.5343570978757, 2096.2139197800825, 922.6678395777515, 963.3786207895868, 965.165132160676, 1018.4602160854758, 1044.806710758512, 19013.33453329127, 20102.89948550277, 20123.899380503004, 20238.392848214284, 20502.487437813874, 1012.3217444312328, 1018.180364806338, 1026.1893104447552, 1028.8508166315842, 1071.6302875507938, 10259.384436933808, 10712.839307410395, 10921.92483428146, 11412.144089193343, 11459.197856150156, 668872.0, 673762.0, 674835.0, 688302.1169788238, 692433.0, 87164.5641771787, 109736.0, 135769.0, 142210.0, 148235.51764482431, 1042.6559235452282, 1106.2311693373028, 1114.626600088963, 1115.6485707002269, 1117.357519426333, 18038.819611803712, 18077.909610451865, 18131.546711331794, 18181.272749089887, 19006.0, 53520.464795351545, 53636.73181634223, 53755.0, 54762.0, 56122.0, 80752.0, 82859.56936603838, 83378.16621833704, 83695.90760231043, 84798.57600711957, 8215.671373145007, 8295.751127466187, 8500.957495212484, 8519.062903080532, 8724.0, 11972.93128327604, 12285.324307163191, 12527.0, 12529.248245105322, 12886.613401597968, 10111.797764044824, 10717.196210284104, 11057.61298354586, 11085.27945683528, 11436.256643318158, 10705.732356690833, 10881.727956801144, 10997.780044399227, 11059.115270778479, 11228.719282018015, 11061.502232399722, 11115.833262500908, 11222.55109795599, 11420.143489238506, 11513.654590362303, 7140.928860670941, 8308.210719981593, 8315.916840831514, 8339.749807505785, 8589.570521474026, 9682.128608425275, 11046.944765276123, 11082.889171108509, 11145.442727863736, 11484.770304594029], "value": 9785.558246131051, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1475.011742132771, 1610.9677806443872, 1622.6267958369574, 1889.763779527559, 1998.5011241568823, 8371.414001019932, 9027.729168124959, 9241.537923103851, 9246.445213287207, 9271.860922086165, 8829.911700882989, 9062.320325975545, 9085.36402451827, 9092.727218183465, 9099.135582119688, 2563.026050100959, 2589.04205443986, 2589.974100258992, 2590.080521414899, 2591.0931174089014, 2515.3208633668896, 2576.716561178269, 2577.9484410311784, 2581.2514370832437, 2583.9793281653738, 2122.2147805312056, 2542.5296320180732, 2566.461043180934, 2571.151519998396, 2582.987085064572, 2533.74662533747, 2556.003158768082, 2566.2301309607137, 2576.793856491476, 2581.5740402833508, 2526.5199612073648, 2556.527042497144, 2570.383108054072, 2570.4730530241254, 2574.871256437171, 8855.557222138932, 9075.773105672344, 9163.81672366549, 9225.67710130149, 9229.538523073887, 5609.3268807742925, 5719.59962802604, 5766.769329226826, 5767.0772676372, 5773.422657734235, 3671.4859919611276, 3721.5162028936334, 3794.1083845296334, 3805.2389522095414, 3825.44531043, 1066.296244478648, 1079.4279032112993, 1084.8426978088212, 1119.7592517608753, 1124.0670243697712, 12231.816522752166, 12398.256104633841, 12535.18521296122, 12568.120231583793, 12575.931045861113, 4819.204831202812, 4840.685355451885, 4841.080194762983, 4900.289458028566, 4907.411110666742, 9782.119609235204, 9783.266255030901, 9788.0, 9799.804003919882, 9803.656872009446, 5613.101903695389, 5635.690037048002, 5637.210790489335, 5641.689707066151, 5648.124540696116, 4647.535246475325, 4757.452892917358, 5079.822206222764, 5210.452902445189, 5254.15933450646, 4015.738976966488, 4077.4903137107863, 4135.91728165435, 4141.855035073758, 4191.1827193697545, 1250.2123662092824, 1271.053065466215, 1280.929548874802, 1289.9290539020255, 1290.9096363254482, 925.9027802080684, 948.720127562357, 959.3332633819429, 965.7247684409909, 997.3018886779283, 6100.115483254903, 6134.478569321568, 6170.321264660884, 6260.24877014752, 6279.403456671609, 480.495479746265, 481.22042291488015, 481.2949029671548, 483.3595485981089, 488.64329039801146, 4017.5781542938157, 4071.6946229032346, 4087.1621317629815, 4094.7338423003007, 4163.208990291835, 11044.723881903017, 11389.974902258837, 11547.307161570332, 11553.653390398302, 11590.362530060758, 11349.262297950607, 11378.715532111764, 11382.089432845352, 11399.772004559862, 11403.315801051964, 8487.660493580188, 8806.91193088061, 8842.602082906285, 8992.640294388151, 8994.965578958503, 4892.31507588945, 6010.7295171716505, 6060.060690592965, 6083.69581520931, 6103.481204097701, 9776.21790256792, 10151.543180556744, 10244.897551024394, 10366.378017318984, 10372.17022638172, 10147.84778228342, 10497.055265026183, 10514.947425262824, 10535.42055186972, 10680.519376627915, 6709.530332876847, 6881.931180688129, 6893.069435626327, 7451.627418629156, 7527.0, 8084.474509156886, 8167.224113709188, 8491.915080849112, 8699.173578510032, 8738.25724813398, 6596.538242322991, 7959.920400795918, 8012.078610959814, 8119.350451963947, 8134.877976830234, 7561.7731468056045, 7633.732819351519, 7836.333911617348, 7891.565963872042, 8036.397270204641, 7231.4214862811905, 7267.30960558746, 7288.599127048064, 7308.269173082862, 7309.634518274172, 5099.974500127624, 5209.765560549708, 5263.973680131575, 5363.812266570651, 5375.247465354932, 5292.692067457839, 5305.681659100466, 5340.439253878287, 5402.243685883901, 5495.395506494203], "value": 4716.522801174347, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.92809082844076, 338.73982926341165, 342.66247745970213, 352.1038955857343, 353.6286898756305, 6506.3818937200895, 6613.041109039186, 6653.401193892549, 6690.197176338834, 6700.396964273221, 6435.259945106314, 6641.5682980606225, 6657.0, 6663.933360666392, 6708.228553716324, 4907.386576677915, 4943.703377797326, 4944.283078953549, 4946.233333833262, 4971.726555039471, 4684.836030738925, 4756.881077973044, 4903.141950158726, 4935.555799978001, 4942.53045960635, 4851.272309153637, 4862.781174847123, 4869.659123861331, 4879.170541008037, 4885.926711099336, 4887.0714564232685, 4901.485344038878, 4903.362562866841, 4919.950800492021, 4931.926021109686, 3809.257194847006, 3912.823922923447, 4220.2192594370135, 4233.597808208216, 4246.2356775780345, 6371.808845734636, 6377.8405539861415, 6384.744610215585, 6397.488200943913, 6411.4229719325485, 5657.462541058593, 5842.824715258549, 5851.619644723122, 5865.20819689345, 5876.236089308362, 7009.859802803966, 7029.402500787439, 7055.4002909752735, 7059.752908648224, 7074.115735533059, 4749.240121580565, 4751.857444276678, 4754.500777418356, 4758.881027974294, 4765.309030190638, 7214.0, 7481.476296659236, 7520.586367749772, 7524.134724506697, 7524.914784938203, 4640.1879671058105, 4668.533146685304, 4676.883077923011, 4681.836135735233, 4687.0, 4760.880977975509, 4772.212584923518, 4774.618030557547, 4775.403074615673, 4779.737114458669, 5689.9146512802345, 5693.03218452864, 5700.344460387026, 5711.514521265739, 5729.742161602738, 6896.176538954231, 7095.680694368766, 7120.35916767483, 7121.679524421414, 7129.857402852018, 1615.6122530592572, 1659.9834001659829, 1664.25108701085, 1664.6171380582534, 1667.641457086732, 1029.2023681646783, 1044.4430211992333, 1058.3015209961388, 1060.0247772049734, 1060.607575197182, 5516.0, 6773.491988100912, 6777.593344399352, 6795.286494918061, 6803.421709154678, 7306.707731690779, 7326.780196594111, 7334.3765779908235, 7337.119545654449, 7362.631868406558, 6783.457323414115, 6784.728610855609, 6784.8303792404595, 6791.049253104569, 6808.931910680929, 3825.9426108608395, 3860.285847118292, 3862.4206369044305, 3863.1114843586133, 3865.207632435344, 7446.25537446251, 7684.654190561439, 7709.5374277542405, 7757.612119394008, 7814.55430745314, 7324.743633972892, 7446.925530744623, 7627.809304767426, 7631.3513351365755, 7647.197044310378, 7778.261065198797, 7784.883226751716, 7800.0, 7805.180456052146, 7805.492642978302, 7080.822979425556, 7327.413806895329, 7336.596487193256, 7361.889571656322, 7363.711350513746, 6341.365863413622, 6759.9522074078595, 7190.460715446257, 7218.602976836324, 7232.132144142736, 7729.0, 7732.38140948734, 7766.339861111869, 7778.6888524460655, 7797.532148071134, 4396.648268138477, 4477.306017567283, 4582.427196600425, 4637.513061128533, 4697.389339385994, 6373.267074286517, 6772.898406523807, 7265.891011634935, 7266.600336981517, 7269.5274807139485, 7027.15674119109, 7042.823929401806, 7043.0, 7047.40097091753, 7056.823579410556, 7003.054587630809, 7003.614801185984, 7007.334303241184, 7007.544509606859, 7010.684519196547, 6644.734210631714, 6859.965700171467, 6874.072000279898, 6902.240753517009, 6906.37842594169, 6950.3049695030095, 6999.51517211403, 7025.051618031501, 7027.402670772837, 7043.577385356895, 5794.01501744696, 6723.731050757915, 6900.03399524057, 6912.792616221522, 6913.204981426999], "value": 5194.245112830477, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.126022390221, 680.4420375292259, 758.9544627322362, 771.2634434115421, 806.2622700229291, 15313.081215127086, 15525.534233972985, 15573.688526229469, 15583.065016099014, 15634.0, 14322.570322890304, 14397.424103035892, 15878.761818572731, 16380.0, 16396.18019099046, 805.0420000199744, 974.2303580171656, 983.4443539400216, 993.8260804359244, 995.5768798260747, 818.4107442641289, 971.1017308989192, 993.5479356892597, 994.9154321882648, 996.6710985374846, 1035.5702383510834, 1046.304207701881, 1048.6906362623006, 1050.3540322701522, 1050.9894901051007, 829.8049958259804, 845.5180547088162, 1028.0387837372043, 1041.052642095697, 1044.0499145777344, 629.2574761781096, 631.516889579472, 707.4481904114799, 777.7161336112334, 782.1474592693934, 12748.0, 15169.469068582657, 15522.223888805514, 15525.301361438762, 15560.0, 12514.060320650222, 12669.68776951877, 12803.039772017137, 12927.35363231844, 13082.149660272053, 9920.561518579765, 10172.643957461454, 10213.642522511676, 10352.171826253882, 10888.891111088868, 3492.8571072157497, 3576.8211589420425, 3586.5337506124297, 3620.25784714133, 3630.110622897407, 75685.62157189233, 79731.6013419935, 86369.56815215947, 86448.0, 86913.56543217243, 9483.33616646842, 9598.280128990353, 9633.566489507848, 9804.117629413244, 10369.377837329634, 21232.36302910915, 21316.04077816502, 21320.36038918835, 21347.46631334198, 21365.89317053436, 6092.847678808065, 7425.0, 7449.739259125905, 7685.116211635622, 7905.683772649145, 4173.853915112956, 4209.0, 4281.036766727461, 4348.695591308641, 4508.391367165391, 3812.6568608825337, 3881.9611803882162, 3899.8440062397754, 3903.902402439905, 3917.040325120364, 693.7710555516668, 704.3695892176576, 707.6603230449413, 733.3912852332551, 736.0615215600153, 523.3379774585153, 547.6823442403396, 592.8103007037706, 619.0992106485054, 623.4856243599049, 6450.387213214737, 8118.309943654737, 8211.137830527709, 8260.421770476009, 8559.914400855912, 527.2776296473779, 551.7654996626418, 595.3421469276387, 622.8816524860353, 635.9300476947532, 3742.214135031619, 3857.0, 3890.280298144852, 3904.843806247775, 3929.6070392961046, 68897.0, 89262.0, 90769.0, 90978.54510727536, 91400.08599914049, 78865.60567197128, 79370.60314698389, 79475.60262198768, 80129.79805302926, 81876.0, 68952.0, 69237.0, 69902.95145572821, 70028.0, 70468.2953170472, 6981.476389270723, 7596.582187979714, 7831.216878312123, 7840.0, 7911.367090632621, 47799.76100119477, 48215.75892120517, 48479.03041939071, 48553.514464854896, 49898.50101499084, 62247.688761557714, 62380.44048898814, 62789.74420511473, 63148.0, 66334.0, 9715.22278217755, 9725.4650994196, 9878.654247101316, 10008.699739007841, 10605.310654807412, 10584.629537966135, 10741.570337186738, 10878.673639790819, 11019.063379612822, 11635.243709158878, 9036.90963090387, 10319.793604127724, 10428.322159059637, 10634.946825265824, 10703.892961070602, 11122.833157502806, 11350.0, 11548.59579914699, 11664.416779161178, 11753.294802311888, 11328.94335528317, 11457.598984035852, 11464.25482343645, 11671.941640291745, 11768.470418831002, 9359.698868771582, 9659.227261818896, 9702.223822094074, 9914.702558923243, 9943.353682010646, 9758.072983066597, 10026.799464010825, 10036.197104231498, 10081.848772268275, 10083.344582602107], "value": 6952.212178890889, "unit": "score"}], "name": "dromaeo_css", "value": 7537.0018344416385, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 120.0, 112.0, 113.0, 112.0, 114.0, 113.0, 113.0, 135.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 144.0, 146.0, 166.0, 116.0, 139.0, 117.0, 150.0, 152.0, 112.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 185.0, 183.0, 182.0, 186.0, 187.0, 189.0, 256.0, 187.0], "value": 192.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 78.0, 74.0, 157.0, 56.0, 55.0, 57.0, 63.0, 138.0, 59.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 144.0, 79.0, 78.0, 87.0, 126.0, 79.0, 76.0, 125.0], "value": 98.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 131.0, 88.0, 86.0, 87.0, 83.0, 88.0, 83.0, 90.0, 85.0], "value": 90.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 92.0, 91.0, 93.0, 117.0, 150.0, 102.0, 91.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 83.0, 86.0, 85.0, 84.0, 84.0, 86.0, 85.0, 86.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 64.0, 63.0, 62.0, 63.0, 64.0, 64.0, 80.0, 58.0, 55.0], "value": 63.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 66.0, 62.0, 68.0, 76.0, 72.0, 72.0, 67.0, 66.0, 68.0], "value": 67.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 79.0, 80.0, 78.0, 78.0, 79.0, 77.0, 80.0, 84.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 144.0, 148.0, 117.0, 123.0, 125.0, 123.0, 125.0, 145.0, 144.0], "value": 133.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 160.0, 162.0, 159.0, 161.0, 159.0, 162.0, 161.0, 158.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 65.0, 65.0, 66.0, 65.0, 68.0, 66.0, 66.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1475.3, "alertThreshold": 2.0}]} 05:34:16 INFO - Return code: 0 05:34:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:34:16 ERROR - # TBPL SUCCESS # 05:34:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:34:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:34:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:34:16 INFO - 2016-07-08 05:34:16.879 screenresolution[2290:25900] starting screenresolution argv=screenresolution get 05:34:16 INFO - 2016-07-08 05:34:16.882 screenresolution[2290:25900] Display 0: 1600x1200x32@60 05:34:16 INFO - 2016-07-08 05:34:16.888 screenresolution[2291:25904] starting screenresolution argv=screenresolution list 05:34:16 INFO - Available Modes on Display 0 05:34:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:34:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:34:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:34:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:34:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:34:17 INFO - Intel Iris: 05:34:17 INFO - Chipset Model: Intel Iris 05:34:17 INFO - Type: GPU 05:34:17 INFO - Bus: Built-In 05:34:17 INFO - VRAM (Dynamic, Max): 1536 MB 05:34:17 INFO - Vendor: Intel (0x8086) 05:34:17 INFO - Device ID: 0x0a2e 05:34:17 INFO - Revision ID: 0x0009 05:34:17 INFO - Displays: 05:34:17 INFO - Display: 05:34:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:34:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:34:17 INFO - Display Serial Number: 1600x1200 60 05:34:17 INFO - Main Display: Yes 05:34:17 INFO - Mirror: Off 05:34:17 INFO - Online: Yes 05:34:17 INFO - Rotation: Supported 05:34:17 INFO - Return code: 0 05:34:17 INFO - Running post-action listener: _resource_record_post_action 05:34:17 INFO - Running post-run listener: _resource_record_post_run 05:34:18 INFO - Total resource usage - Wall time: 1194s; CPU: 53.0%; Read bytes: 794624; Write bytes: 491392512; Read time: 7; Write time: 1532 05:34:18 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 185340416; Write bytes: 184713216; Read time: 16552; Write time: 407 05:34:18 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 794624; Write bytes: 295532544; Read time: 7; Write time: 1084 05:34:18 INFO - Running post-run listener: _upload_blobber_files 05:34:18 INFO - Blob upload gear active. 05:34:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:34:18 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 05:34:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:34:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:34:18 INFO - (blobuploader) - INFO - Open directory for files ... 05:34:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:34:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:34:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:34:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:34:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:34:19 INFO - (blobuploader) - INFO - Done attempting. 05:34:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:34:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:34:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:34:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:34:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:34:20 INFO - (blobuploader) - INFO - Done attempting. 05:34:20 INFO - (blobuploader) - INFO - Iteration through files over. 05:34:20 INFO - Return code: 0 05:34:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:34:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:34:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c76f6258038a6db4e1dd6c016d4bfa69f20925bb49a2860d52a1220e9b77f9d5fda8b29ac50474bcceaec660117b2e43758920f2b86b84a6edd4ad565f6cbc2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fd69b6244730474afd9978c62a248de1fc28e74c83b38426adf11e1b9a45b74fc2b3340a9bacd8da99c8e21871cb589f4867f3712828c697764c4ce3e89457bb"} 05:34:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:34:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:34:20 INFO - Contents: 05:34:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c76f6258038a6db4e1dd6c016d4bfa69f20925bb49a2860d52a1220e9b77f9d5fda8b29ac50474bcceaec660117b2e43758920f2b86b84a6edd4ad565f6cbc2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fd69b6244730474afd9978c62a248de1fc28e74c83b38426adf11e1b9a45b74fc2b3340a9bacd8da99c8e21871cb589f4867f3712828c697764c4ce3e89457bb"} 05:34:20 INFO - Running post-run listener: copy_logs_to_upload_dir 05:34:20 INFO - Copying logs to upload dir... 05:34:20 INFO - mkdir: /builds/slave/test/build/upload/logs 05:34:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.050016 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-08 05:34:20.245847) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 05:34:20.249151) ========= 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.GiCMZzyyfz/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.sVoePtV9CF/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c76f6258038a6db4e1dd6c016d4bfa69f20925bb49a2860d52a1220e9b77f9d5fda8b29ac50474bcceaec660117b2e43758920f2b86b84a6edd4ad565f6cbc2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fd69b6244730474afd9978c62a248de1fc28e74c83b38426adf11e1b9a45b74fc2b3340a9bacd8da99c8e21871cb589f4867f3712828c697764c4ce3e89457bb"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011383 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-macosx64/1467867477/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c76f6258038a6db4e1dd6c016d4bfa69f20925bb49a2860d52a1220e9b77f9d5fda8b29ac50474bcceaec660117b2e43758920f2b86b84a6edd4ad565f6cbc2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fd69b6244730474afd9978c62a248de1fc28e74c83b38426adf11e1b9a45b74fc2b3340a9bacd8da99c8e21871cb589f4867f3712828c697764c4ce3e89457bb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 05:34:20.282813) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:34:20.283186) ========= 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.GiCMZzyyfz/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.sVoePtV9CF/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.005251 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 05:34:20.331984) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 05:34:20.332305) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 05:34:20.332637) ========= ========= Total master_lag: 0.21 =========