builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0390 starttime: 1464625818.83 results: success (0) buildid: 20160530073151 builduid: 931f49bf727d468790e98b2743145d93 revision: ed1c3aae460813241ce2c3480df30fae2268ab1f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 09:30:18.827098) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 09:30:18.827539) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 09:30:18.827858) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 09:30:18.880562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:30:18.880990) ========= 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.qWCHMelAoj/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.0YwVff4ZYj/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-05-30 09:30:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 65.8M=0s 2016-05-30 09:30:19 (65.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123108 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:30:19.028641) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:30:19.029259) ========= 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.qWCHMelAoj/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.0YwVff4ZYj/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.019907 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:30:19.079835) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 09:30:19.080338) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ed1c3aae460813241ce2c3480df30fae2268ab1f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ed1c3aae460813241ce2c3480df30fae2268ab1f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qWCHMelAoj/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.0YwVff4ZYj/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-05-30 09:30:19,151 truncating revision to first 12 chars 2016-05-30 09:30:19,152 Setting DEBUG logging. 2016-05-30 09:30:19,152 attempt 1/10 2016-05-30 09:30:19,152 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ed1c3aae4608?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 09:30:20,170 unpacking tar archive at: mozilla-aurora-ed1c3aae4608/testing/mozharness/ program finished with exit code 0 elapsedTime=1.282173 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 09:30:20.380907) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 09:30:20.384315) ========= script_repo_revision: ed1c3aae460813241ce2c3480df30fae2268ab1f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 09:30:20.384756) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 09:30:20.385049) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 09:30:20.400194) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2016-05-30 09:30:20.400592) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qWCHMelAoj/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.0YwVff4ZYj/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:30:20 INFO - MultiFileLogger online at 20160530 09:30:20 in /builds/slave/test 09:30:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:30:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:30:20 INFO - {'append_to_log': False, 09:30:20 INFO - 'base_work_dir': '/builds/slave/test', 09:30:20 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:30:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:30:20 INFO - 'branch': 'Mozilla-Aurora', 09:30:20 INFO - 'buildbot_json_path': 'buildprops.json', 09:30:20 INFO - 'config_files': ('talos/mac_config.py',), 09:30:20 INFO - 'default_actions': ('clobber', 09:30:20 INFO - 'read-buildbot-config', 09:30:20 INFO - 'download-and-extract', 09:30:20 INFO - 'populate-webroot', 09:30:20 INFO - 'create-virtualenv', 09:30:20 INFO - 'install', 09:30:20 INFO - 'run-tests'), 09:30:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:30:20 INFO - 'download_minidump_stackwalk': True, 09:30:20 INFO - 'download_symbols': 'ondemand', 09:30:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:30:20 INFO - 'tooltool.py': '/tools/tooltool.py', 09:30:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:30:20 INFO - '/tools/misc-python/virtualenv.py')}, 09:30:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:30:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:30:20 INFO - 'installer_path': 'installer.exe', 09:30:20 INFO - 'log_level': 'info', 09:30:20 INFO - 'log_name': 'talos', 09:30:20 INFO - 'log_to_console': True, 09:30:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:30:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:30:20 INFO - 'opt_config_files': (), 09:30:20 INFO - 'pip_index': False, 09:30:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:30:20 INFO - 'cmd': ('bash', 09:30:20 INFO - '-c', 09:30:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:30:20 INFO - 'enabled': True, 09:30:20 INFO - 'halt_on_failure': False, 09:30:20 INFO - 'name': 'check_screen_resolution'},), 09:30:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:30:20 INFO - 'cmd': ('bash', 09:30:20 INFO - '-c', 09:30:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:30:20 INFO - 'enabled': True, 09:30:20 INFO - 'halt_on_failure': False, 09:30:20 INFO - 'name': 'check_screen_resolution'},), 09:30:20 INFO - 'run_cmd_checks_enabled': True, 09:30:20 INFO - 'sps_profile': False, 09:30:20 INFO - 'sps_profile_interval': 0, 09:30:20 INFO - 'suite': 'dromaeojs-e10s', 09:30:20 INFO - 'system_bits': '32', 09:30:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:30:20 INFO - 'title': 't-yosemite-r7-0390', 09:30:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:30:20 INFO - 'use_talos_json': True, 09:30:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:30:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:30:20 INFO - 'work_dir': 'build'} 09:30:20 INFO - ##### 09:30:20 INFO - ##### Running clobber step. 09:30:20 INFO - ##### 09:30:20 INFO - Running pre-action listener: _resource_record_pre_action 09:30:20 INFO - Running main action method: clobber 09:30:20 INFO - rmtree: /builds/slave/test/build 09:30:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:30:21 INFO - Running post-action listener: _resource_record_post_action 09:30:21 INFO - ##### 09:30:21 INFO - ##### Running read-buildbot-config step. 09:30:21 INFO - ##### 09:30:21 INFO - Running pre-action listener: _resource_record_pre_action 09:30:21 INFO - Running main action method: read_buildbot_config 09:30:21 INFO - Using buildbot properties: 09:30:21 INFO - { 09:30:21 INFO - "project": "", 09:30:21 INFO - "product": "firefox", 09:30:21 INFO - "script_repo_revision": "production", 09:30:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:30:21 INFO - "repository": "", 09:30:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:30:21 INFO - "buildid": "20160530073151", 09:30:21 INFO - "pgo_build": "False", 09:30:21 INFO - "basedir": "/builds/slave/test", 09:30:21 INFO - "buildnumber": 26, 09:30:21 INFO - "slavename": "t-yosemite-r7-0390", 09:30:21 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:30:21 INFO - "platform": "yosemite_r7", 09:30:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:30:21 INFO - "branch": "mozilla-aurora", 09:30:21 INFO - "repo_path": "releases/mozilla-aurora", 09:30:21 INFO - "revision": "ed1c3aae460813241ce2c3480df30fae2268ab1f", 09:30:21 INFO - "stage_platform": "macosx64", 09:30:21 INFO - "builduid": "931f49bf727d468790e98b2743145d93", 09:30:21 INFO - "slavebuilddir": "test" 09:30:21 INFO - } 09:30:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 09:30:21 INFO - Running post-action listener: _resource_record_post_action 09:30:21 INFO - ##### 09:30:21 INFO - ##### Running download-and-extract step. 09:30:21 INFO - ##### 09:30:21 INFO - Running pre-action listener: _resource_record_pre_action 09:30:21 INFO - Running main action method: download_and_extract 09:30:21 INFO - mkdir: /builds/slave/test/build/tests 09:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:21 INFO - https://queue.taskcluster.net/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:30:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 09:30:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 09:30:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 09:30:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 09:30:24 INFO - Downloaded 1199 bytes. 09:30:24 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 09:30:24 INFO - Using the following test package requirements: 09:30:24 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 09:30:24 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:30:24 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 09:30:24 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:30:24 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 09:30:24 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:30:24 INFO - u'jsshell-mac.zip'], 09:30:24 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:30:24 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 09:30:24 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 09:30:24 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:30:24 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 09:30:24 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:30:24 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 09:30:24 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:30:24 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 09:30:24 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:30:24 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 09:30:24 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:24 INFO - https://queue.taskcluster.net/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:30:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:30:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:30:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:30:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:30:35 INFO - Downloaded 18425601 bytes. 09:30:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:30:38 INFO - Return code: 0 09:30:38 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:38 INFO - https://queue.taskcluster.net/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:30:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:30:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:30:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:30:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:30:44 INFO - Downloaded 18425601 bytes. 09:30:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:30:47 INFO - Return code: 0 09:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:47 INFO - https://queue.taskcluster.net/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:30:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:30:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:30:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:30:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:30:49 INFO - Downloaded 10840145 bytes. 09:30:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:30:49 INFO - Return code: 0 09:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:49 INFO - https://queue.taskcluster.net/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 09:30:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:30:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:30:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 09:30:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 09:30:58 INFO - Downloaded 114125886 bytes. 09:30:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:30:58 INFO - mkdir: /builds/slave/test/properties 09:30:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:30:58 INFO - Writing to file /builds/slave/test/properties/build_url 09:30:58 INFO - Contents: 09:30:58 INFO - build_url:https://queue.taskcluster.net/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:30:59 INFO - Running post-action listener: _resource_record_post_action 09:30:59 INFO - Running post-action listener: set_extra_try_arguments 09:30:59 INFO - ##### 09:30:59 INFO - ##### Running populate-webroot step. 09:30:59 INFO - ##### 09:30:59 INFO - Running pre-action listener: _resource_record_pre_action 09:30:59 INFO - Running main action method: populate_webroot 09:30:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:30:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:30:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:30:59 INFO - '--tppagecycles', 09:30:59 INFO - '7'], 09:30:59 INFO - 'tests': ['tsvgm']}}, 09:30:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:30:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:30:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:30:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:30:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:30:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:30:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:59 INFO - 'talos_options': ['--disable-e10s'], 09:30:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:59 INFO - 'talos_options': ['--disable-e10s'], 09:30:59 INFO - 'tests': ['damp', 'tps']}, 09:30:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:59 INFO - 'tests': ['damp', 'tps']}, 09:30:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:30:59 INFO - 'tests': ['dromaeo_dom']}, 09:30:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:30:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:30:59 INFO - 'tests': ['a11yr', 09:30:59 INFO - 'ts_paint', 09:30:59 INFO - 'tpaint', 09:30:59 INFO - 'sessionrestore', 09:30:59 INFO - 'sessionrestore_no_auto_restore', 09:30:59 INFO - 'tabpaint']}, 09:30:59 INFO - 'other-e10s': {'tests': ['a11yr', 09:30:59 INFO - 'ts_paint', 09:30:59 INFO - 'tpaint', 09:30:59 INFO - 'sessionrestore', 09:30:59 INFO - 'sessionrestore_no_auto_restore', 09:30:59 INFO - 'tabpaint']}, 09:30:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:30:59 INFO - 'ts_paint', 09:30:59 INFO - 'tpaint', 09:30:59 INFO - 'sessionrestore', 09:30:59 INFO - 'sessionrestore_no_auto_restore', 09:30:59 INFO - 'tabpaint']}, 09:30:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:30:59 INFO - 'ts_paint', 09:30:59 INFO - 'tpaint', 09:30:59 INFO - 'sessionrestore', 09:30:59 INFO - 'sessionrestore_no_auto_restore', 09:30:59 INFO - 'tabpaint']}, 09:30:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:30:59 INFO - 'tests': ['a11yr', 09:30:59 INFO - 'ts_paint', 09:30:59 INFO - 'tpaint', 09:30:59 INFO - 'sessionrestore', 09:30:59 INFO - 'sessionrestore_no_auto_restore', 09:30:59 INFO - 'tabpaint']}, 09:30:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:30:59 INFO - 'tests': ['a11yr', 09:30:59 INFO - 'ts_paint', 09:30:59 INFO - 'tpaint', 09:30:59 INFO - 'sessionrestore', 09:30:59 INFO - 'sessionrestore_no_auto_restore', 09:30:59 INFO - 'tabpaint']}, 09:30:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:30:59 INFO - 'tests': ['tsvgx', 09:30:59 INFO - 'tsvgr_opacity', 09:30:59 INFO - 'tart', 09:30:59 INFO - 'tscrollx', 09:30:59 INFO - 'cart']}, 09:30:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:30:59 INFO - 'tsvgr_opacity', 09:30:59 INFO - 'tart', 09:30:59 INFO - 'tscrollx', 09:30:59 INFO - 'cart']}, 09:30:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:59 INFO - 'talos_options': ['--disable-e10s'], 09:30:59 INFO - 'tests': ['tp5o']}, 09:30:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:59 INFO - 'tests': ['tp5o']}, 09:30:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:59 INFO - 'talos_options': ['--disable-e10s', 09:30:59 INFO - '--xperf_path', 09:30:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:59 INFO - 'tests': ['tp5n']}, 09:30:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:59 INFO - 'talos_options': ['--xperf_path', 09:30:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:59 INFO - 'tests': ['tp5n']}}, 09:30:59 INFO - 'talos.zip': {'path': '', 09:30:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:30:59 INFO - Running post-action listener: _resource_record_post_action 09:30:59 INFO - ##### 09:30:59 INFO - ##### Running create-virtualenv step. 09:30:59 INFO - ##### 09:30:59 INFO - Running pre-action listener: _resource_record_pre_action 09:30:59 INFO - Running main action method: create_virtualenv 09:30:59 INFO - Creating virtualenv /builds/slave/test/build/venv 09:30:59 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:30:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:30:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:30:59 INFO - Using real prefix '/tools/python27' 09:30:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:31:00 INFO - Installing distribute.............................................................................................................................................................................................done. 09:31:03 INFO - Installing pip.................done. 09:31:03 INFO - Return code: 0 09:31:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:31: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')]} 09:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31: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')]} 09:31:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:03 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 0x10bb88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff270ccbe50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba27e00>, '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 0x10b890db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba22b20>, '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.qWCHMelAoj/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.0YwVff4ZYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:31:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:31:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWCHMelAoj/Render', 09:31:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:03 INFO - 'HOME': '/Users/cltbld', 09:31:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:03 INFO - 'LOGNAME': 'cltbld', 09:31:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:03 INFO - 'MOZ_NO_REMOTE': '1', 09:31:03 INFO - 'NO_EM_RESTART': '1', 09:31:03 INFO - 'PAGER': '/bin/cat', 09:31:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:03 INFO - 'PWD': '/builds/slave/test', 09:31:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:03 INFO - 'SHELL': '/bin/bash', 09:31:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YwVff4ZYj/Listeners', 09:31:03 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:31:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:03 INFO - 'USER': 'cltbld', 09:31:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:03 INFO - 'XPC_FLAGS': '0x0', 09:31:03 INFO - 'XPC_SERVICE_NAME': '0', 09:31:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:31:03 INFO - Cleaning up... 09:31:03 INFO - Return code: 0 09:31:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:31: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')]} 09:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31: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')]} 09:31:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:03 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 0x10bb88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff270ccbe50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba27e00>, '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 0x10b890db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba22b20>, '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.qWCHMelAoj/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.0YwVff4ZYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:31:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:31:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWCHMelAoj/Render', 09:31:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:03 INFO - 'HOME': '/Users/cltbld', 09:31:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:03 INFO - 'LOGNAME': 'cltbld', 09:31:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:03 INFO - 'MOZ_NO_REMOTE': '1', 09:31:03 INFO - 'NO_EM_RESTART': '1', 09:31:03 INFO - 'PAGER': '/bin/cat', 09:31:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:03 INFO - 'PWD': '/builds/slave/test', 09:31:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:03 INFO - 'SHELL': '/bin/bash', 09:31:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YwVff4ZYj/Listeners', 09:31:03 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:31:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:03 INFO - 'USER': 'cltbld', 09:31:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:03 INFO - 'XPC_FLAGS': '0x0', 09:31:03 INFO - 'XPC_SERVICE_NAME': '0', 09:31:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:03 INFO - Downloading/unpacking psutil>=0.7.1 09:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:31:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:06 INFO - Installing collected packages: psutil 09:31:06 INFO - Running setup.py install for psutil 09:31:07 INFO - building 'psutil._psutil_osx' extension 09:31:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:31:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:31:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:31:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:31:07 INFO - building 'psutil._psutil_posix' extension 09:31:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:31:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:31:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:31:07 INFO - ^ 09:31:07 INFO - 1 warning generated. 09:31:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:31:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:07 INFO - Successfully installed psutil 09:31:07 INFO - Cleaning up... 09:31:07 INFO - Return code: 0 09:31:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31: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')]} 09:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:08 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 0x10bb88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff270ccbe50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba27e00>, '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 0x10b890db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba22b20>, '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.qWCHMelAoj/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.0YwVff4ZYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:31:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWCHMelAoj/Render', 09:31:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:08 INFO - 'HOME': '/Users/cltbld', 09:31:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:08 INFO - 'LOGNAME': 'cltbld', 09:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:08 INFO - 'MOZ_NO_REMOTE': '1', 09:31:08 INFO - 'NO_EM_RESTART': '1', 09:31:08 INFO - 'PAGER': '/bin/cat', 09:31:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:08 INFO - 'PWD': '/builds/slave/test', 09:31:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:08 INFO - 'SHELL': '/bin/bash', 09:31:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YwVff4ZYj/Listeners', 09:31:08 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:31:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:08 INFO - 'USER': 'cltbld', 09:31:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:08 INFO - 'XPC_FLAGS': '0x0', 09:31:08 INFO - 'XPC_SERVICE_NAME': '0', 09:31:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:31:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:31:11 INFO - Installing collected packages: mozsystemmonitor 09:31:11 INFO - Running setup.py install for mozsystemmonitor 09:31:11 INFO - Successfully installed mozsystemmonitor 09:31:11 INFO - Cleaning up... 09:31:11 INFO - Return code: 0 09:31:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:11 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 0x10bb88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff270ccbe50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba27e00>, '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 0x10b890db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba22b20>, '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.qWCHMelAoj/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.0YwVff4ZYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:31:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWCHMelAoj/Render', 09:31:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:11 INFO - 'HOME': '/Users/cltbld', 09:31:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:11 INFO - 'LOGNAME': 'cltbld', 09:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:11 INFO - 'MOZ_NO_REMOTE': '1', 09:31:11 INFO - 'NO_EM_RESTART': '1', 09:31:11 INFO - 'PAGER': '/bin/cat', 09:31:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:11 INFO - 'PWD': '/builds/slave/test', 09:31:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:11 INFO - 'SHELL': '/bin/bash', 09:31:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YwVff4ZYj/Listeners', 09:31:11 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:31:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:11 INFO - 'USER': 'cltbld', 09:31:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:11 INFO - 'XPC_FLAGS': '0x0', 09:31:11 INFO - 'XPC_SERVICE_NAME': '0', 09:31:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:11 INFO - Downloading/unpacking blobuploader==1.2.4 09:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:14 INFO - Downloading blobuploader-1.2.4.tar.gz 09:31:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:31:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:31:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:15 INFO - Downloading docopt-0.6.1.tar.gz 09:31:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:31:15 INFO - Installing collected packages: blobuploader, requests, docopt 09:31:15 INFO - Running setup.py install for blobuploader 09:31:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:31:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:31:15 INFO - Running setup.py install for requests 09:31:16 INFO - Running setup.py install for docopt 09:31:16 INFO - Successfully installed blobuploader requests docopt 09:31:16 INFO - Cleaning up... 09:31:16 INFO - Return code: 0 09:31:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31: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')]} 09:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31: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')]} 09:31:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:16 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 0x10bb88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff270ccbe50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba27e00>, '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 0x10b890db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba22b20>, '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.qWCHMelAoj/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.0YwVff4ZYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:31:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:31:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWCHMelAoj/Render', 09:31:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:16 INFO - 'HOME': '/Users/cltbld', 09:31:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:16 INFO - 'LOGNAME': 'cltbld', 09:31:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:16 INFO - 'MOZ_NO_REMOTE': '1', 09:31:16 INFO - 'NO_EM_RESTART': '1', 09:31:16 INFO - 'PAGER': '/bin/cat', 09:31:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:16 INFO - 'PWD': '/builds/slave/test', 09:31:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:16 INFO - 'SHELL': '/bin/bash', 09:31:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YwVff4ZYj/Listeners', 09:31:16 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:31:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:16 INFO - 'USER': 'cltbld', 09:31:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:16 INFO - 'XPC_FLAGS': '0x0', 09:31:16 INFO - 'XPC_SERVICE_NAME': '0', 09:31:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:16 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-DZbRaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:16 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-khU6tt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:16 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-S6sGtl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:17 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-yL6EzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:17 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-hDzO2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:17 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-C3sOHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:17 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-A_JL64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:17 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Uw3QIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:17 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-NgGiLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:17 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ulQGyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:17 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-VT9Pya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:17 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-yRBgIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:17 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-5xZDAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:18 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-aMZaV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:18 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Semhyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:18 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Bqgbh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:18 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-CpsHfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:31:18 INFO - Running setup.py install for manifestparser 09:31:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:31:18 INFO - Running setup.py install for mozcrash 09:31:18 INFO - Running setup.py install for mozdebug 09:31:18 INFO - Running setup.py install for mozdevice 09:31:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:31:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:31:19 INFO - Running setup.py install for mozfile 09:31:19 INFO - Running setup.py install for mozhttpd 09:31:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:31:19 INFO - Running setup.py install for mozinfo 09:31:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:31:19 INFO - Running setup.py install for mozInstall 09:31:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:31:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:31:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:31:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:31:19 INFO - Running setup.py install for mozleak 09:31:19 INFO - Running setup.py install for mozlog 09:31:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:31:19 INFO - Running setup.py install for moznetwork 09:31:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Running setup.py install for mozprocess 09:31:20 INFO - Running setup.py install for mozprofile 09:31:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Running setup.py install for mozrunner 09:31:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Running setup.py install for mozscreenshot 09:31:20 INFO - Running setup.py install for moztest 09:31:20 INFO - Running setup.py install for mozversion 09:31:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:31:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:31:21 INFO - Cleaning up... 09:31:21 INFO - Return code: 0 09:31:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:21 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 0x10bb88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff270ccbe50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba27e00>, '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 0x10b890db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba22b20>, '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.qWCHMelAoj/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.0YwVff4ZYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:31:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:31:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWCHMelAoj/Render', 09:31:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:21 INFO - 'HOME': '/Users/cltbld', 09:31:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:21 INFO - 'LOGNAME': 'cltbld', 09:31:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:21 INFO - 'MOZ_NO_REMOTE': '1', 09:31:21 INFO - 'NO_EM_RESTART': '1', 09:31:21 INFO - 'PAGER': '/bin/cat', 09:31:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:21 INFO - 'PWD': '/builds/slave/test', 09:31:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:21 INFO - 'SHELL': '/bin/bash', 09:31:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YwVff4ZYj/Listeners', 09:31:21 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:31:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:21 INFO - 'USER': 'cltbld', 09:31:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:21 INFO - 'XPC_FLAGS': '0x0', 09:31:21 INFO - 'XPC_SERVICE_NAME': '0', 09:31:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:21 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-t5xagV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:21 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-QAUHeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:21 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-xLgXD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:21 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-WOd9EY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:21 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-tNSZ9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:21 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-psF1ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:21 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-lPPjfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-fnc9jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-GdieW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-WRipSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-gC9EhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-UjG3Zb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-bjaW7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-PCLmb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-RU3Njm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-3Y9TwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-nwSum2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:31:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:31:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:25 INFO - Downloading blessings-1.6.tar.gz 09:31:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:31:26 INFO - Installing collected packages: blessings 09:31:26 INFO - Running setup.py install for blessings 09:31:26 INFO - Successfully installed blessings 09:31:26 INFO - Cleaning up... 09:31:26 INFO - Return code: 0 09:31:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:31:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:31:26 INFO - Reading from file tmpfile_stdout 09:31:26 INFO - Current package versions: 09:31:26 INFO - blessings == 1.6 09:31:26 INFO - blobuploader == 1.2.4 09:31:26 INFO - docopt == 0.6.1 09:31:26 INFO - manifestparser == 1.1 09:31:26 INFO - mozInstall == 1.12 09:31:26 INFO - mozcrash == 0.17 09:31:26 INFO - mozdebug == 0.1 09:31:26 INFO - mozdevice == 0.48 09:31:26 INFO - mozfile == 1.2 09:31:26 INFO - mozhttpd == 0.7 09:31:26 INFO - mozinfo == 0.9 09:31:26 INFO - mozleak == 0.1 09:31:26 INFO - mozlog == 3.1 09:31:26 INFO - moznetwork == 0.27 09:31:26 INFO - mozprocess == 0.22 09:31:26 INFO - mozprofile == 0.28 09:31:26 INFO - mozrunner == 6.11 09:31:26 INFO - mozscreenshot == 0.1 09:31:26 INFO - mozsystemmonitor == 0.0 09:31:26 INFO - moztest == 0.7 09:31:26 INFO - mozversion == 1.4 09:31:26 INFO - psutil == 3.1.1 09:31:26 INFO - requests == 1.2.3 09:31:26 INFO - wsgiref == 0.1.2 09:31:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:26 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 0x10bb88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff270ccbe50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba27e00>, '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 0x10b890db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba22b20>, '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.qWCHMelAoj/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.0YwVff4ZYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:31:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWCHMelAoj/Render', 09:31:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:26 INFO - 'HOME': '/Users/cltbld', 09:31:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:26 INFO - 'LOGNAME': 'cltbld', 09:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:26 INFO - 'MOZ_NO_REMOTE': '1', 09:31:26 INFO - 'NO_EM_RESTART': '1', 09:31:26 INFO - 'PAGER': '/bin/cat', 09:31:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:26 INFO - 'PWD': '/builds/slave/test', 09:31:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:26 INFO - 'SHELL': '/bin/bash', 09:31:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YwVff4ZYj/Listeners', 09:31:26 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:31:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:26 INFO - 'USER': 'cltbld', 09:31:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:26 INFO - 'XPC_FLAGS': '0x0', 09:31:26 INFO - 'XPC_SERVICE_NAME': '0', 09:31:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:31:26 INFO - Cleaning up... 09:31:26 INFO - Return code: 0 09:31:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bb88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff270ccbe50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba27e00>, '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 0x10b890db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba22b20>, '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.qWCHMelAoj/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.0YwVff4ZYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:31:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWCHMelAoj/Render', 09:31:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:26 INFO - 'HOME': '/Users/cltbld', 09:31:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:26 INFO - 'LOGNAME': 'cltbld', 09:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:26 INFO - 'MOZ_NO_REMOTE': '1', 09:31:26 INFO - 'NO_EM_RESTART': '1', 09:31:26 INFO - 'PAGER': '/bin/cat', 09:31:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:26 INFO - 'PWD': '/builds/slave/test', 09:31:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:26 INFO - 'SHELL': '/bin/bash', 09:31:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YwVff4ZYj/Listeners', 09:31:26 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:31:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:26 INFO - 'USER': 'cltbld', 09:31:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:26 INFO - 'XPC_FLAGS': '0x0', 09:31:26 INFO - 'XPC_SERVICE_NAME': '0', 09:31:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:27 INFO - Downloading/unpacking jsonschema 09:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:31:29 INFO - Downloading/unpacking functools32 (from jsonschema) 09:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:29 INFO - Downloading functools32-3.2.3-2.tar.gz 09:31:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:31:29 INFO - warning: no files found matching '*.txt' 09:31:29 INFO - no previously-included directories found matching 'build' 09:31:29 INFO - no previously-included directories found matching 'dist' 09:31:29 INFO - no previously-included directories found matching '.git*' 09:31:29 INFO - Installing collected packages: jsonschema, functools32 09:31:30 INFO - Running setup.py install for functools32 09:31:30 INFO - warning: no files found matching '*.txt' 09:31:30 INFO - no previously-included directories found matching 'build' 09:31:30 INFO - no previously-included directories found matching 'dist' 09:31:30 INFO - no previously-included directories found matching '.git*' 09:31:30 INFO - Successfully installed jsonschema functools32 09:31:30 INFO - Cleaning up... 09:31:30 INFO - Return code: 0 09:31:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:30 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 0x10bb88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb98420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff270ccbe50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba27e00>, '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 0x10b890db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba22b20>, '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.qWCHMelAoj/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.0YwVff4ZYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:31:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWCHMelAoj/Render', 09:31:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:30 INFO - 'HOME': '/Users/cltbld', 09:31:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:30 INFO - 'LOGNAME': 'cltbld', 09:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:30 INFO - 'MOZ_NO_REMOTE': '1', 09:31:30 INFO - 'NO_EM_RESTART': '1', 09:31:30 INFO - 'PAGER': '/bin/cat', 09:31:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:30 INFO - 'PWD': '/builds/slave/test', 09:31:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:30 INFO - 'SHELL': '/bin/bash', 09:31:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YwVff4ZYj/Listeners', 09:31:30 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:31:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:30 INFO - 'USER': 'cltbld', 09:31:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:30 INFO - 'XPC_FLAGS': '0x0', 09:31:30 INFO - 'XPC_SERVICE_NAME': '0', 09:31:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:30 INFO - Downloading/unpacking flake8 09:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:31:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:31:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:31:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:31:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:31:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:31:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:31:34 INFO - no previously-included directories found matching 'docs/_build' 09:31:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:31:34 INFO - Running setup.py install for pep8 09:31:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:31:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:31:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:31:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:31:34 INFO - no previously-included directories found matching 'docs/_build' 09:31:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:31:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:31:34 INFO - Cleaning up... 09:31:34 INFO - Return code: 0 09:31:34 INFO - Running post-action listener: _resource_record_post_action 09:31:34 INFO - Running post-action listener: _start_resource_monitoring 09:31:34 INFO - Starting resource monitoring. 09:31:34 INFO - ##### 09:31:34 INFO - ##### Running install step. 09:31:34 INFO - ##### 09:31:34 INFO - Running pre-action listener: _resource_record_pre_action 09:31:34 INFO - Running main action method: install 09:31:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:31:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:31:34 INFO - Reading from file tmpfile_stdout 09:31:34 INFO - Detecting whether we're running mozinstall >=1.0... 09:31:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:31:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:31:35 INFO - Reading from file tmpfile_stdout 09:31:35 INFO - Output received: 09:31:35 INFO - Usage: mozinstall [options] installer 09:31:35 INFO - Options: 09:31:35 INFO - -h, --help show this help message and exit 09:31:35 INFO - -d DEST, --destination=DEST 09:31:35 INFO - Directory to install application into. [default: 09:31:35 INFO - "/builds/slave/test"] 09:31:35 INFO - --app=APP Application being installed. [default: firefox] 09:31:35 INFO - mkdir: /builds/slave/test/build/application 09:31:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:31:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:32:06 INFO - Reading from file tmpfile_stdout 09:32:06 INFO - Output received: 09:32:06 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:32:06 INFO - Running post-action listener: _resource_record_post_action 09:32:06 INFO - ##### 09:32:06 INFO - ##### Running run-tests step. 09:32:06 INFO - ##### 09:32:06 INFO - Running pre-action listener: _resource_record_pre_action 09:32:06 INFO - Running main action method: run_tests 09:32:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:32:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:32:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:32:06 INFO - 2016-05-30 09:32:06.617 screenresolution[1941:15299] starting screenresolution argv=screenresolution get 09:32:06 INFO - 2016-05-30 09:32:06.619 screenresolution[1941:15299] Display 0: 1600x1200x32@60 09:32:06 INFO - 2016-05-30 09:32:06.625 screenresolution[1942:15301] starting screenresolution argv=screenresolution list 09:32:06 INFO - Available Modes on Display 0 09:32:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:32:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:32:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:32:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:32:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:32:06 INFO - Intel Iris: 09:32:06 INFO - Chipset Model: Intel Iris 09:32:06 INFO - Type: GPU 09:32:06 INFO - Bus: Built-In 09:32:06 INFO - VRAM (Dynamic, Max): 1536 MB 09:32:06 INFO - Vendor: Intel (0x8086) 09:32:06 INFO - Device ID: 0x0a2e 09:32:06 INFO - Revision ID: 0x0009 09:32:06 INFO - Displays: 09:32:06 INFO - Display: 09:32:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:32:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:32:06 INFO - Display Serial Number: 1600x1200 60 09:32:06 INFO - Main Display: Yes 09:32:06 INFO - Mirror: Off 09:32:06 INFO - Online: Yes 09:32:06 INFO - Rotation: Supported 09:32:06 INFO - Return code: 0 09:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:32:06 INFO - Python 2.7.3 09:32:06 INFO - Return code: 0 09:32:06 INFO - grabbing minidump binary from tooltool 09:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:32:06 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 0x10ba27e00>, '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 0x10b890db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba22b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:32:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:32:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:32:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:32:06 INFO - Return code: 0 09:32:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:32:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:32:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:32:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:32:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:32:08 INFO - Return code: 0 09:32:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0390 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:32:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWCHMelAoj/Render', 09:32:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:08 INFO - 'HOME': '/Users/cltbld', 09:32:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:08 INFO - 'LOGNAME': 'cltbld', 09:32:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:32:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:32:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:08 INFO - 'MOZ_NO_REMOTE': '1', 09:32:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:32:08 INFO - 'NO_EM_RESTART': '1', 09:32:08 INFO - 'PAGER': '/bin/cat', 09:32:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:08 INFO - 'PWD': '/builds/slave/test', 09:32:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:32:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:08 INFO - 'SHELL': '/bin/bash', 09:32:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0YwVff4ZYj/Listeners', 09:32:08 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:32:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:08 INFO - 'USER': 'cltbld', 09:32:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:08 INFO - 'XPC_FLAGS': '0x0', 09:32:08 INFO - 'XPC_SERVICE_NAME': '0', 09:32:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:32:08 INFO - mozversion application_buildid: 20160530073151 09:32:08 INFO - mozversion application_changeset: ed1c3aae460813241ce2c3480df30fae2268ab1f 09:32:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:32:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:32:08 INFO - mozversion application_name: Firefox 09:32:08 INFO - mozversion application_remotingname: firefox-dev 09:32:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:32:08 INFO - mozversion application_vendor: Mozilla 09:32:08 INFO - mozversion application_version: 48.0a2 09:32:08 INFO - mozversion platform_buildid: 20160530073151 09:32:08 INFO - mozversion platform_changeset: ed1c3aae460813241ce2c3480df30fae2268ab1f 09:32:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:32:08 INFO - mozversion platform_version: 48.0a2 09:32:08 INFO - using testdate: 1464625928 09:32:08 INFO - actual date: 1464625928 09:32:08 INFO - starting webserver on 'localhost:52331' 09:32:08 INFO - SUITE-START | Running 2 tests 09:32:08 INFO - TEST-START | dromaeo_css 09:32:08 INFO - operating with platform_type : mac_ 09:32:08 INFO - Initialising browser for dromaeo_css test... 09:32:08 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmproeNSZ/profile http://localhost:52331/getInfo.html) 09:32:10 INFO - PROCESS | 1960 | __metrics Screen width/height:1600/1200 09:32:10 INFO - PROCESS | 1960 | colorDepth:24 09:32:10 INFO - PROCESS | 1960 | Browser inner width/height: 1024/689 09:32:10 INFO - PROCESS | 1960 | __metrics 09:32:10 ERROR - PROCESS | 1960 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 09:32:11 INFO - TEST-INFO | 1960: exit 0 09:32:11 INFO - Browser initialized. 09:32:11 INFO - Running cycle 1/1 for dromaeo_css test... 09:32:11 INFO - TEST-INFO | started process 1963 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmproeNSZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:23 INFO - PROCESS | 1963 | RSS: Main: 220729344 09:32:23 INFO - PROCESS | 1963 | 09:33:13 INFO - PROCESS | 1963 | 1464625993274 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:35:24 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52331/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52331/tests/dromaeo/cssquery-ext.html) 09:35:25 INFO - PROCESS | 1963 | RSS: Main: 231604224 09:35:25 INFO - PROCESS | 1963 | 09:38:27 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52331/tests/dromaeo/cssquery-ext.html (next: http://localhost:52331/tests/dromaeo/cssquery-jquery.html) 09:38:28 INFO - PROCESS | 1963 | RSS: Main: 243499008 09:38:28 INFO - PROCESS | 1963 | 09:41:30 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52331/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52331/tests/dromaeo/cssquery-mootools.html) 09:41:30 INFO - PROCESS | 1963 | RSS: Main: 245293056 09:41:30 INFO - PROCESS | 1963 | 09:44:32 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52331/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52331/tests/dromaeo/cssquery-prototype.html) 09:44:33 INFO - PROCESS | 1963 | RSS: Main: 247226368 09:44:33 INFO - PROCESS | 1963 | 09:47:35 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52331/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52331/tests/dromaeo/cssquery-yui.html) 09:47:36 INFO - PROCESS | 1963 | RSS: Main: 250064896 09:47:36 INFO - PROCESS | 1963 | 09:50:38 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52331/tests/dromaeo/cssquery-yui.html (next: http://localhost:52331/tests/dromaeo/cssquery-dojo.html) 09:50:38 INFO - PROCESS | 1963 | RSS: Main: 250597376 09:50:38 INFO - PROCESS | 1963 | 09:50:38 INFO - PROCESS | 1963 | __start_tp_report 09:50:38 INFO - PROCESS | 1963 | _x_x_mozilla_page_load 09:50:38 INFO - PROCESS | 1963 | _x_x_mozilla_page_load_details 09:50:38 INFO - PROCESS | 1963 | |i|pagename|runs| 09:50:38 INFO - PROCESS | 1963 | |0;dojo.html;2891.2627280043585;3419.2135808763987;3501.089716673664;3521.137321356268;3526.418141006734;4321.416608757824;5435.402105768362;5692.203091567184;5710.314762228539;5768.077107662775;4955.826546070888;5709.172170035343;5740.942590574093;5776.335721392041;5787.450192231742;4584.266517357223;4803.678988278217;5189.221616757488;5235.58115350771;5304.575633949294;3796.810159492028;4837.443693975203;5198.974005129988;5227.764750586232;5329.280547126146;5285.207218917155;5298.072837253484;5298.496642818926;5299.788008479656;5317.042932272189;5251.264822924755;5283.181106928432;5294.629375943686;5301.893962120736;5316.5746740261075;5093.923591146136;5107.693538387709;5228.398734145546;5236.476352364733;5258.027264955957;4489.708168969007;5367.194920761917;5635.042042852684;5677.687727175004;5720.056190728525;6122.70311464876;6231.410990197516;6250.4374606285655;6256.280527739323;6291.6854157291955;6141.70080943337;6204.224471941007;6215.12988181655;6232.906506402408;6277;5838.892046304327;5858.238429004245;5859.44335288147;5864.765409383662;5901.734421951022;6144.556029333103;6191.721372538246;6195.535334849814;6297.96851015742;6323.7786677466065;6149.13912052313;6218.471429928507;6232.750689972441;6243.843903902439;6253.1245625612455;5465.262189604432;6219.782307619302;6230.345813689588;6238.282597501255;6266.968665156645;6120.508083830795;6125.326214116443;6153.9845925421805;6223.408776166257;6267.341929097471;6189.164462797465;6215.595986260879;6251.718672659741;6253.6560489173535;6308.842278943063;4891.926621100686;4914.238293064581;4931.87670308238;4946.307516947631;4954.900901982012;4342.218400687843;4389.3635422864145;4441.800118994653;4480.193565158303;4491.348754430589;5515.117581186991;5807.332156801937;5828.737706803204;5830.2420685310635;5840.766369345263;6192.752289908443;6238.344973777688;6254.405831446005;6256;6256.874862502725;5651.547876169897;5683.8010669626365;5726.599138060295;5826.592138550345;5841.561882858802;5424.728763561806;5432.864178395572;5434.157705555645;5444.972775136099;5461.290032295776;6343.175387199634;6363.459105976044;6364.522660800366;6372.203474565679;6376.74493020274;6123.785667501616;6154.984427569401;6217.191765070512;6223.688815559294;6240.438360547572;6290.308066112815;6336.683165841781;6344.492440604833;6349.904751428639;6354.856125897383;5983.37174596661;6212.347703491249;6222.782202622887;6239.220097487814;6248.9062664059165;4822.300766388924;6146.832101900624;6247.843803904939;6257.937420625735;6268.9686551568775;5008.649394542347;5621.566500542335;5833.82498525045;6135.478484328703;6145.754169833157;4389.956100439083;6045.304789949037;6100.1154832548145;6126.632402055891;6165.10605962124;5169.689818610896;5885.9405307045145;5942.811437712561;5951.315598706205;6037.547183961308;5459.890802184013;6133.9079913802225;6154.507639388928;6187.504999600112;6206.503479721521;5814.127880817742;5872.119182122716;5924.348321684697;6013.248343957005;6057.697115144313 09:50:38 INFO - PROCESS | 1963 | |1;ext.html;519.8440467859642;545.6535100211368;576.0552693582523;583.425326053837;607.6384551192042;33402.83298583503;38212;38743.99999999996;39020;39274;40609.59390406095;41133.38299925504;41243.968900777494;41371.58628413715;41385;9520.047995200477;9752.756181095458;9761.267904907161;9772.364796288253;9787.510624468783;9650.372725772837;9863.704088877344;9900.50497475127;9918.206543476505;9947.75130621733;9858.654947076853;9867.753306167331;9896.257780666443;9898.554565044555;9909.108180263775;9817.067378599022;9840.507974601242;9894.604215831358;9896.15882649972;9930.354526955796;9713.36863103896;9806.460644664541;9812.411255324703;9832.655857044969;9895.208383329316;39627.801860990796;39887.60112398897;40113.59886401128;40195.79902100471;40283.79148625546;2855.8714857831446;3030.1212648331957;3079.90760277192;3128.8435578220997;3176.8093914365213;26148.607770883453;26218.991439750458;27013;27132.321691957663;27447.039353622728;2617.227917764264;2712.972870271292;2742.80800343976;2805.004223500648;2836;387021;393551;393695.03152484057;395670;396674;34982.47526287108;35142;35265.64734352624;35477.82261088678;36749.81625091911;36588;36726.449103263476;36885.63114368822;36894.8155259222;37051.88844334674;15678.9216053919;15843.603909902346;15973.840261597468;16014.759778603331;16126.677466450605;13490.662733431547;13501.392437340342;13565.932170339085;13606.115602485806;13614.978876584295;8158.224968627971;8195.221453961864;8258.050324212672;8288.875666865002;8345.499270043816;2957.166556734838;3315.2540678347177;3390.881319153818;3444.293919746446;3456.6024907136;1017.043978660072;1022.0954455306958;1024.9180065594735;1101.8732845722684;1113.4265853085667;29865.701342986293;30602.704797140304;30719.846400767852;31745.365092698015;32294.515582266285;1051.779126383468;1088.4992903264588;1093.9398333091597;1141.914356423255;1187.1927089578996;11820.763584728258;14738.263086845614;14895.25523723831;15031.774523382159;15115.244237788067;1021093;1026127;1047990;1053845;1055277;61143.69428152831;61800.69099654473;62742.745145096844;62890.68554657289;62896.37103628905;1488.7990121333698;1497.6854860479204;1499.6175975126273;1504.601280660626;1513.2736286582501;28596.42807143887;29856.402871942006;30154.547681784352;30200.5469917947;30332.545011824397;53182;53206.2019069722;53796.73101634623;53825.46174538361;54086.72956635192;535042;603647;610639;610914;615025;27421.862890685417;27913.581296280165;27932.44135117327;28098.438031238853;28383.574246385906;45284.320735189656;45513.31730023986;46160.76919615381;46754;46957.765211173726;37115.81442092772;37498;37975.430368543944;39757.60242397539;40293;33934.79423837541;36412.08969775671;37041.44437833381;37187.442188367735;37279.44080838844;29928.70071299259;36711.816440917624;36713.265734685694;37501;37835.05412364603;33224.50163247505;33317.33365332728;33517;33588.16029599182;33782.15544611404;25516.362090947874;25560.8721956389;25701.3574660635;25861.870690646425;26495.867520662272 09:50:38 INFO - PROCESS | 1963 | |2;jquery.html;390.6367078616886;406.94099355593437;408.81807595619944;411.34185303514363;421.1976185366875;14702.264886755673;19921.701174482376;20605.27881524145;20649.380518584447;20690.172393104258;20245.696314555295;20465.488362790937;20590.48523786906;21032.263870764487;21143.04856281467;5644.520215781664;5655.786439102874;5883;5895.380984996578;5953.642781433106;5588.329400471949;5602.1596760485845;5809.3609702932645;5910.379410161936;5929.347771745104;5885.20549725788;5899.675517846538;5904.291485021782;5927.73325200367;5937.67342796146;6192.473639740605;6660.100886380325;6665.066890635315;6665.800025999228;6723.596584204964;5356.91964620531;5843.824685259449;5855.8536036599;5890.469857712784;5904.498117660004;13462.990275729262;13701.931490342484;13716.971227158003;13756.449742010307;14662.926685366505;2017.0434678361964;2127.5957568062017;2222.855514391559;2454.7299797022315;2533.733957934409;9848.50757462124;10040.544121102472;10207.744806379826;13931.442742290295;13949.511767088103;1685.8229885862054;1710.811810700822;1713.494519116851;1740.5996620777291;1741.0946307919937;87770.56114719449;124021;124298.37850810778;124582.37708811488;126881;11688.766224675459;11949.283043017447;12034.277943323428;12186.939065304794;12323.075769317336;15253.771193432107;15302.923485382502;15306.234688265542;15325.46360877364;15370.692586148214;8839.646414143492;8958.014618391971;8983.955080224558;8984.281257499384;9046.321525885453;8972.775680608038;8986.281097512183;9001.684941027033;9047.100108977056;9048.592813323416;3797.525309336333;4618.838340658128;4643.3963584733765;4656.837010704609;4784.210605250095;1649.5463747469303;2006.2576846566865;2013.6375452418706;2022.51459649683;2043.652579061563;410.9876703698894;429.49105310207506;449.4696258415036;839.9244068033784;1025.4923812712607;10917.890821091847;11668;12116.333601651993;12170.513179472722;12228.816567751492;458.1935793004336;461.3771408598432;462.45661347916064;464.8768076459741;472.7777944366142;5213.713245771443;6162.537809664203;6193.721282542297;6291.339409362043;6358.205224346957;668114;670479;676458;685842;688749;88589;104560;123271;141000;142496.28751856383;442.5839710671958;450.2480856968804;454.89537406396715;456.1378993701906;457.34142834318476;10872.402017889091;11494.482748276514;11531.942340288246;11637.76724465489;11693.473793679475;55153.44846551483;55428;56687.71656141858;57555.84888302294;59107.40892591019;76067.57695903587;83682.58158709167;84690;85079;85082.57458712667;6313.46335561464;7008.719651213894;8319;8363.03825060126;8414.621342039745;10133.391996480235;11769.293842369485;12632.178908370926;12878.742425151631;12964.546240881524;10733.516991735234;11003.119750419786;11224.214304998899;11302.660920172408;11492.425378731197;9003.594838232164;9741.025897410202;10168.593256269962;10770.946145269223;10863.728406789893;10948.850370710961;11245.493952772309;11314.981651651391;11523.769524609628;11634.767304653691;7963.044434667877;8091.231333023354;8349.457285276438;8426.705065322683;8743.650253989768;8255.133211012997;10229.28395012342;11227.157963152633;11402.973732364127;11515.53937842477 09:50:38 INFO - PROCESS | 1963 | |3;mootools.html;1557.400400845674;1577.9842201577985;1598.6562888978872;1874.746909167262;1942.8445724342055;7769.844603107935;8418.452800567959;8497.532635705042;8690.47857128572;8725.563721813907;7000.404965577932;7448.255174482549;8036.27673509384;8599.91400085999;8612.31101511879;2406.1337918349423;2419.4556224849443;2427.611582146857;2429.5505331513727;2433.0024689877155;1788.400885703291;2389.4026493376655;2394.820388470872;2450.8774561271953;2455.545724041049;2414.14297924237;2429.8177636677233;2435.0016493237777;2441.963370549443;2445.1319781477587;2415.806735461159;2422.2733180046002;2435.756424357568;2443.816713746467;2450.5221481810877;2426.17510046585;2447.155731272708;2452.068214078639;2454.7913427358694;2462.470568827711;8756.693515726918;8801.47191168532;8808;8823.426477278956;8825.42634728747;5350.170723537858;5438;5439.5104440600535;5449.155380915925;5451.509364157205;3484.250886059484;3520.3135388599235;3541.4333706607076;3566.215432604849;3594.9281014379567;1036.041661463151;1062.4315990944885;1062.4422178356347;1064.2071656615794;1064.5794911010107;11550.768984620345;11673.591424300108;11708.648740537797;11708.946194842505;11711.297322160783;4653.185692503798;4730.834420795324;4814.566688998007;4816.95183048165;4830.492798256203;9911.108000279864;9914.256430767577;9917.603295868228;9925.751856203653;9939.254555908192;5408.567314614821;5414.972925135349;5415.160650099241;5447.101228297287;5475.561955043587;4149.8962525937095;4742.4309082909585;4832.347633069561;4862.586680132157;5007.824726134568;4005.7997100144876;4016.49793775778;4055.8174882130374;4082.2447847148374;4094.0992981544014;1346.8720471555187;1356.938937747784;1361.2036958379458;1361.8501964783866;1365.2013572060257;896.8296023755465;914.4056363363734;930.9255259579218;953.4184147669915;956.3550692808498;4520.841770538082;5743.8851222976145;5759.884802303931;5820.243368362086;5933.991221492357;852.0371979662941;854.7991222062934;907.3421769217265;965.6330594374093;1031.4481752262504;3586.677199052098;3851.3837785954674;3894.221155768801;3906.746061505993;3920.3335432976464;10241.129503992095;10456.588360571226;10518.421486818146;10614.310069845435;10684.412357320267;10324.174066074696;10350.275480716427;10372.792544149075;10487;10521.631742888962;6917.7232910684015;7689.077310722749;8247.587620619064;8302.626381812712;8314.501129932223;4299.613034826755;5457.69982650958;5614.691191984317;5665.858353541194;5764.106563482667;9095.49974751395;9139.63441462334;9226.538673066454;9255.583498742708;9265.073492650681;9086.561453849896;9382.48396338208;9428.057194280516;9470.573824178065;9666.468344241135;5836.708164591839;6706.362895524917;7318.1584117825005;7347.522411043479;7420;7945.364370850202;8000.15998320168;8007.91992080095;8057.758267251992;8090.959545202236;7049.471289653194;7168.2831716827895;7179.533330333512;7273.127224733066;7281.672324745505;7050.788476345718;7074.752383666547;7157.785266441807;7205.495615306878;7222.0611320528;6662.467002639681;6764.391204791593;6841.760538381132;6858.862822743417;6910.378065974087;4706.76466176683;4792.856214313576;4801.231802911518;4884.340614017062;4930.112579735682;4850.466448690711;4881.414230292387;4888.437829649636;4909.189983652657;4944.802207911644 09:50:38 INFO - PROCESS | 1963 | |4;prototype.html;321.7795809870239;331.8606185402132;335.3243214921934;339.08785367361793;341.25776436251147;6494.44797192238;6545.5418120731565;6635.734570617182;6695.330466953308;6737.966310168448;6590.60456372618;6600;6628.933710662892;6648.368405001529;6666.3000384959605;4860.343853579757;4895.412550493946;4901.950980490203;4914.361133052698;4918;4833.564979151875;4869.756512174395;4884;4932.975335123319;4933.284673722308;4808.903821923559;4854.611631069506;4856.902861942759;4916.410030796309;4918.901621967559;4609.446866376023;4778.928316075262;4886.853394398174;4892;4898.877528061791;4051.736637118607;4052.959470405288;4058.6955978301744;4059.2287465381482;4088.713790034699;6147.323794382614;6182.938170618326;6183.81448556544;6213.813585592439;6220.751169953196;5146.716930568818;5649.8587535311535;5653.802116925888;5659.151127330892;5668.574856885709;7053.576785392893;7054.365107140382;7057.294270572953;7072.3634872861685;7094.503384763068;3577.821108944542;3732;4580.9770951145365;4590.701604395704;4592.609628181609;7441.8139546511775;7527.209642987517;7532.096148462219;7546.735864244725;7614.971978782849;4662.207424737802;4662.906741865144;4666.789994450258;4670.369500117441;4672.439307283148;4708.670393072452;4732.645051621143;4737.502562230985;4743.1225223333795;4746.02706445178;5652.802151924745;5658.462446067617;5659.151127330851;5674.404187560329;5677.432256774289;5014.846585285405;5540.141278101901;5719.19931209631;7205.747798827016;7225.277472252733;1490.4187366926908;1626.2763070433543;1646.3167785369178;1648.3077107614831;1654.2555849867608;985.113397941858;1037.5849660136007;1044.2220545693508;1055.5039131608132;1056.3714589819044;6546.378094081055;6683;6715.925451927668;6728.730850766013;6731.798046058626;5990.221271234799;6100.877982440326;7390.371818395497;7468.477206595486;7477.065366829146;6693.427044644504;6727.091842601284;6741.089952856301;6769.05233267343;6773.119494465786;3037.8481075946556;3224.822634755064;3905.9453947434035;3919.1377896862646;3981.741186822905;7525.548467091992;7814.3748500119855;7816.179301173409;7827.373810095179;7852.254035866584;7674.846503069907;7719.035120609924;7726.06848287928;7752.147263800977;7761.922380776233;7715;7808;7812.218778122142;7818.6090695465;7833.64748586315;7256.7460138894885;7373.29953654402;7430.665620047002;7459.477836551389;7465.44009199323;7298.56208627484;7329.377002954809;7332.560046397233;7364.742234021784;7373.926260737324;7755.72854950074;7777.883331749915;7783.688652453839;7808.804779880548;7821.882671759814;3816.5992570779667;4646.233371493801;4666.766661666971;4668.369770080995;4673.509281525527;7039.859202816017;7407.888881666671;7408;7423.294786995227;7438.03305570294;7108.431325494052;7108.715651373887;7110.013794206549;7115.217326094022;7124.465665075244;7057.50597458173;7086.893696594659;7116.359527642537;7135.286471353031;7140.500164988401;6848.1097457330225;6920.377166055079;6922.03091567191;6935.7225710971;6936.930630693629;6902.965485172542;7086.964565177141;7088.255733147946;7090.397316228178;7093.964530177316;6737.090492783412;6821.624810635462;6916.135483064617;6935.3411425914455;7045 09:50:38 INFO - PROCESS | 1963 | |5;yui.html;676.1716896801422;694.7047504810455;735.5917465806475;737.4211244173325;768.5350363030368;14626;15038.624034399145;15161.241937903102;15281.541553753392;15415.69168616627;13939.721205575883;13951.790723139162;13968.860311396884;13973.860261397384;14037.087589306713;729.7190581626072;896.4486840593021;896.551724137931;908.9863652045192;946.6118891254587;916.0473107967706;926.6571368593629;926.9675561355353;930.2557953637097;940.4028441939383;777.4091690315345;947.3510645208055;964.4888209249109;983.316594966495;1004.1264100232808;861.9310455163571;958.5830163878735;981.9459929703863;998.6305067125138;1005.1958433253404;701.6211245927193;702.3713776170299;703.7290643102391;732.1653315220652;744.222287709345;15042.774358384633;15200.695986080327;15240.237988100662;15281.923590382088;15294.923525382414;12789;12869.09916305859;12896.935515322364;12951.611451656465;13077.607671769862;9783.217342612574;10012.199024078056;10099.899001010042;10109.39343639384;10241.180705543538;3514.6309637487952;3563.8574457021687;3566.8394922228563;3636.9272614547563;3726.608706085849;82495;82564.58717706433;85130;85235.8691032723;85249.14750852536;9538.52307384628;9650.179734722487;9693.70918872435;9823.36148150368;9945.80108397828;21764.45588860291;21903.233386831384;22308.219212327815;22412;22412.439689007904;7283.490155689157;7338.192798792129;7358.11702595692;7367.778966630903;7439.85120297591;4016.3373043448087;4052.0477687743355;4096.303628383182;4106.3635136553885;4114.465119534501;3536.0806190390167;3603.891883243507;3606.945895811565;3608.530890984155;3611.0791748104066;551.5449753953005;646.9611823290523;648.0829626079045;659.475716805141;659.482306389482;567.6650776042155;586.9413058694097;596.4125336543501;602.9547783916136;613.5858295650418;7814.21857814214;7969.760907172794;8090.150534193943;8106.027276726831;8354.958225208835;570.2672066394663;588.7733222709288;597.6862147372664;601.7412512619528;605.794030029792;3267.2975310308398;3667.0832291927018;3731.1604888900324;3907.765534067965;3911.2568611963634;94478;94978;97045;97543;97639;81415.59292203501;82070;82332.58833705792;82418.58790706129;82634.99999999879;69077.65461172663;69246;71520.64239678768;71675.28324716687;72722;7470.6264686766535;7599.354054905395;8008.599570021359;8147.75556733299;8217.58912054383;43440;46881.827954301414;47891.76054119846;47986;48360;50956;68534;69103.65448172727;69649.65175174295;71018;10666.679999600025;10770.946145269223;10951.576295081588;10953.178511611502;11017.228793984345;11733.413329333329;11985.460654270753;12025.398730063636;12029.338386388832;12347.073969452507;11023.889761102286;11052.226344156154;11311.717207069889;11318.886811132114;11779;11201.159913006393;11261;11293.491792869176;11537.711557211136;12034.217775844541;9018.684346047858;9097.954510227406;10995.450227488434;11388.430578470878;11542.480588373375;8238.09380968105;9050;9312.534373281173;9513.191378732885;9747.951260243653;9299.488528130747;9613.213297229267;9771.169450596495;9775.853362199716;9848.606055757977 09:50:38 INFO - PROCESS | 1963 | __end_tp_report 09:50:38 INFO - PROCESS | 1963 | __start_cc_report 09:50:38 INFO - PROCESS | 1963 | _x_x_mozilla_cycle_collect,409 09:50:38 INFO - PROCESS | 1963 | __end_cc_report 09:50:38 INFO - PROCESS | 1963 | __startTimestamp1464627038728__endTimestamp 09:50:38 INFO - PROCESS | 1963 | 09:50:38 INFO - PROCESS | 1963 | ------- Summary: start ------- 09:50:38 INFO - PROCESS | 1963 | Number of tests: 6 09:50:38 INFO - PROCESS | 1963 | 09:50:38 INFO - PROCESS | 1963 | [#0] dojo.html Cycles:165 Average:5682.27 Median:5864.77 stddev:691.18 (11.8%) stddev-sans-first:657.71 09:50:38 INFO - PROCESS | 1963 | Values: 2891.3 3419.2 3501.1 3521.1 3526.4 4321.4 5435.4 5692.2 5710.3 5768.1 4955.8 5709.2 5740.9 5776.3 5787.5 4584.3 4803.7 5189.2 5235.6 5304.6 3796.8 4837.4 5199.0 5227.8 5329.3 5285.2 5298.1 5298.5 5299.8 5317.0 5251.3 5283.2 5294.6 5301.9 5316.6 5093.9 5107.7 5228.4 5236.5 5258.0 4489.7 5367.2 5635.0 5677.7 5720.1 6122.7 6231.4 6250.4 6256.3 6291.7 6141.7 6204.2 6215.1 6232.9 6277.0 5838.9 5858.2 5859.4 5864.8 5901.7 6144.6 6191.7 6195.5 6298.0 6323.8 6149.1 6218.5 6232.8 6243.8 6253.1 5465.3 6219.8 6230.3 6238.3 6267.0 6120.5 6125.3 6154.0 6223.4 6267.3 6189.2 6215.6 6251.7 6253.7 6308.8 4891.9 4914.2 4931.9 4946.3 4954.9 4342.2 4389.4 4441.8 4480.2 4491.3 5515.1 5807.3 5828.7 5830.2 5840.8 6192.8 6238.3 6254.4 6256.0 6256.9 5651.5 5683.8 5726.6 5826.6 5841.6 5424.7 5432.9 5434.2 5445.0 5461.3 6343.2 6363.5 6364.5 6372.2 6376.7 6123.8 6155.0 6217.2 6223.7 6240.4 6290.3 6336.7 6344.5 6349.9 6354.9 5983.4 6212.3 6222.8 6239.2 6248.9 4822.3 6146.8 6247.8 6257.9 6269.0 5008.6 5621.6 5833.8 6135.5 6145.8 4390.0 6045.3 6100.1 6126.6 6165.1 5169.7 5885.9 5942.8 5951.3 6037.5 5459.9 6133.9 6154.5 6187.5 6206.5 5814.1 5872.1 5924.3 6013.2 6057.7 09:50:38 INFO - PROCESS | 1963 | 09:50:38 INFO - PROCESS | 1963 | [#1] ext.html Cycles:180 Average:77296.04 Median:36719.86 stddev:198100.74 (539.5%) stddev-sans-first:198572.60 09:50:38 INFO - PROCESS | 1963 | Values: 519.8 545.7 576.1 583.4 607.6 33402.8 38212.0 38744.0 39020.0 39274.0 40609.6 41133.4 41244.0 41371.6 41385.0 9520.0 9752.8 9761.3 9772.4 9787.5 9650.4 9863.7 9900.5 9918.2 9947.8 9858.7 9867.8 9896.3 9898.6 9909.1 9817.1 9840.5 9894.6 9896.2 9930.4 9713.4 9806.5 9812.4 9832.7 9895.2 39627.8 39887.6 40113.6 40195.8 40283.8 2855.9 3030.1 3079.9 3128.8 3176.8 26148.6 26219.0 27013.0 27132.3 27447.0 2617.2 2713.0 2742.8 2805.0 2836.0 387021.0 393551.0 393695.0 395670.0 396674.0 34982.5 35142.0 35265.6 35477.8 36749.8 36588.0 36726.4 36885.6 36894.8 37051.9 15678.9 15843.6 15973.8 16014.8 16126.7 13490.7 13501.4 13565.9 13606.1 13615.0 8158.2 8195.2 8258.1 8288.9 8345.5 2957.2 3315.3 3390.9 3444.3 3456.6 1017.0 1022.1 1024.9 1101.9 1113.4 29865.7 30602.7 30719.8 31745.4 32294.5 1051.8 1088.5 1093.9 1141.9 1187.2 11820.8 14738.3 14895.3 15031.8 15115.2 1021093.0 1026127.0 1047990.0 1053845.0 1055277.0 61143.7 61800.7 62742.7 62890.7 62896.4 1488.8 1497.7 1499.6 1504.6 1513.3 28596.4 29856.4 30154.5 30200.5 30332.5 53182.0 53206.2 53796.7 53825.5 54086.7 535042.0 603647.0 610639.0 610914.0 615025.0 27421.9 27913.6 27932.4 28098.4 28383.6 45284.3 45513.3 46160.8 46754.0 46957.8 37115.8 37498.0 37975.4 39757.6 40293.0 33934.8 36412.1 37041.4 37187.4 37279.4 29928.7 36711.8 36713.3 37501.0 37835.1 33224.5 33317.3 33517.0 33588.2 33782.2 25516.4 25560.9 25701.4 25861.9 26495.9 09:50:38 INFO - PROCESS | 1963 | 09:50:38 INFO - PROCESS | 1963 | [#2] jquery.html Cycles:180 Average:36351.38 Median:416.27 stddev:112612.73 (27052.8%) stddev-sans-first:112896.27 09:50:38 INFO - PROCESS | 1963 | Values: 390.6 406.9 408.8 411.3 421.2 14702.3 19921.7 20605.3 20649.4 20690.2 20245.7 20465.5 20590.5 21032.3 21143.0 5644.5 5655.8 5883.0 5895.4 5953.6 5588.3 5602.2 5809.4 5910.4 5929.3 5885.2 5899.7 5904.3 5927.7 5937.7 6192.5 6660.1 6665.1 6665.8 6723.6 5356.9 5843.8 5855.9 5890.5 5904.5 13463.0 13701.9 13717.0 13756.4 14662.9 2017.0 2127.6 2222.9 2454.7 2533.7 9848.5 10040.5 10207.7 13931.4 13949.5 1685.8 1710.8 1713.5 1740.6 1741.1 87770.6 124021.0 124298.4 124582.4 126881.0 11688.8 11949.3 12034.3 12186.9 12323.1 15253.8 15302.9 15306.2 15325.5 15370.7 8839.6 8958.0 8984.0 8984.3 9046.3 8972.8 8986.3 9001.7 9047.1 9048.6 3797.5 4618.8 4643.4 4656.8 4784.2 1649.5 2006.3 2013.6 2022.5 2043.7 411.0 429.5 449.5 839.9 1025.5 10917.9 11668.0 12116.3 12170.5 12228.8 458.2 461.4 462.5 464.9 472.8 5213.7 6162.5 6193.7 6291.3 6358.2 668114.0 670479.0 676458.0 685842.0 688749.0 88589.0 104560.0 123271.0 141000.0 142496.3 442.6 450.2 454.9 456.1 457.3 10872.4 11494.5 11531.9 11637.8 11693.5 55153.4 55428.0 56687.7 57555.8 59107.4 76067.6 83682.6 84690.0 85079.0 85082.6 6313.5 7008.7 8319.0 8363.0 8414.6 10133.4 11769.3 12632.2 12878.7 12964.5 10733.5 11003.1 11224.2 11302.7 11492.4 9003.6 9741.0 10168.6 10770.9 10863.7 10948.9 11245.5 11315.0 11523.8 11634.8 7963.0 8091.2 8349.5 8426.7 8743.7 8255.1 10229.3 11227.2 11403.0 11515.5 09:50:38 INFO - PROCESS | 1963 | 09:50:38 INFO - PROCESS | 1963 | [#3] mootools.html Cycles:180 Average:5574.20 Median:4937.46 stddev:3063.52 (62.0%) stddev-sans-first:3057.24 09:50:38 INFO - PROCESS | 1963 | Values: 1557.4 1578.0 1598.7 1874.7 1942.8 7769.8 8418.5 8497.5 8690.5 8725.6 7000.4 7448.3 8036.3 8599.9 8612.3 2406.1 2419.5 2427.6 2429.6 2433.0 1788.4 2389.4 2394.8 2450.9 2455.5 2414.1 2429.8 2435.0 2442.0 2445.1 2415.8 2422.3 2435.8 2443.8 2450.5 2426.2 2447.2 2452.1 2454.8 2462.5 8756.7 8801.5 8808.0 8823.4 8825.4 5350.2 5438.0 5439.5 5449.2 5451.5 3484.3 3520.3 3541.4 3566.2 3594.9 1036.0 1062.4 1062.4 1064.2 1064.6 11550.8 11673.6 11708.6 11708.9 11711.3 4653.2 4730.8 4814.6 4817.0 4830.5 9911.1 9914.3 9917.6 9925.8 9939.3 5408.6 5415.0 5415.2 5447.1 5475.6 4149.9 4742.4 4832.3 4862.6 5007.8 4005.8 4016.5 4055.8 4082.2 4094.1 1346.9 1356.9 1361.2 1361.9 1365.2 896.8 914.4 930.9 953.4 956.4 4520.8 5743.9 5759.9 5820.2 5934.0 852.0 854.8 907.3 965.6 1031.4 3586.7 3851.4 3894.2 3906.7 3920.3 10241.1 10456.6 10518.4 10614.3 10684.4 10324.2 10350.3 10372.8 10487.0 10521.6 6917.7 7689.1 8247.6 8302.6 8314.5 4299.6 5457.7 5614.7 5665.9 5764.1 9095.5 9139.6 9226.5 9255.6 9265.1 9086.6 9382.5 9428.1 9470.6 9666.5 5836.7 6706.4 7318.2 7347.5 7420.0 7945.4 8000.2 8007.9 8057.8 8091.0 7049.5 7168.3 7179.5 7273.1 7281.7 7050.8 7074.8 7157.8 7205.5 7222.1 6662.5 6764.4 6841.8 6858.9 6910.4 4706.8 4792.9 4801.2 4884.3 4930.1 4850.5 4881.4 4888.4 4909.2 4944.8 09:50:38 INFO - PROCESS | 1963 | 09:50:38 INFO - PROCESS | 1963 | [#4] prototype.html Cycles:180 Average:5782.94 Median:6642.05 stddev:1900.87 (28.6%) stddev-sans-first:1861.48 09:50:38 INFO - PROCESS | 1963 | Values: 321.8 331.9 335.3 339.1 341.3 6494.4 6545.5 6635.7 6695.3 6738.0 6590.6 6600.0 6628.9 6648.4 6666.3 4860.3 4895.4 4902.0 4914.4 4918.0 4833.6 4869.8 4884.0 4933.0 4933.3 4808.9 4854.6 4856.9 4916.4 4918.9 4609.4 4778.9 4886.9 4892.0 4898.9 4051.7 4053.0 4058.7 4059.2 4088.7 6147.3 6182.9 6183.8 6213.8 6220.8 5146.7 5649.9 5653.8 5659.2 5668.6 7053.6 7054.4 7057.3 7072.4 7094.5 3577.8 3732.0 4581.0 4590.7 4592.6 7441.8 7527.2 7532.1 7546.7 7615.0 4662.2 4662.9 4666.8 4670.4 4672.4 4708.7 4732.6 4737.5 4743.1 4746.0 5652.8 5658.5 5659.2 5674.4 5677.4 5014.8 5540.1 5719.2 7205.7 7225.3 1490.4 1626.3 1646.3 1648.3 1654.3 985.1 1037.6 1044.2 1055.5 1056.4 6546.4 6683.0 6715.9 6728.7 6731.8 5990.2 6100.9 7390.4 7468.5 7477.1 6693.4 6727.1 6741.1 6769.1 6773.1 3037.8 3224.8 3905.9 3919.1 3981.7 7525.5 7814.4 7816.2 7827.4 7852.3 7674.8 7719.0 7726.1 7752.1 7761.9 7715.0 7808.0 7812.2 7818.6 7833.6 7256.7 7373.3 7430.7 7459.5 7465.4 7298.6 7329.4 7332.6 7364.7 7373.9 7755.7 7777.9 7783.7 7808.8 7821.9 3816.6 4646.2 4666.8 4668.4 4673.5 7039.9 7407.9 7408.0 7423.3 7438.0 7108.4 7108.7 7110.0 7115.2 7124.5 7057.5 7086.9 7116.4 7135.3 7140.5 6848.1 6920.4 6922.0 6935.7 6936.9 6903.0 7087.0 7088.3 7090.4 7094.0 6737.1 6821.6 6916.1 6935.3 7045.0 09:50:38 INFO - PROCESS | 1963 | 09:50:38 INFO - PROCESS | 1963 | [#5] yui.html Cycles:180 Average:18487.46 Median:630.27 stddev:26421.13 (4192.0%) stddev-sans-first:26461.40 09:50:38 INFO - PROCESS | 1963 | Values: 676.2 694.7 735.6 737.4 768.5 14626.0 15038.6 15161.2 15281.5 15415.7 13939.7 13951.8 13968.9 13973.9 14037.1 729.7 896.4 896.6 909.0 946.6 916.0 926.7 927.0 930.3 940.4 777.4 947.4 964.5 983.3 1004.1 861.9 958.6 981.9 998.6 1005.2 701.6 702.4 703.7 732.2 744.2 15042.8 15200.7 15240.2 15281.9 15294.9 12789.0 12869.1 12896.9 12951.6 13077.6 9783.2 10012.2 10099.9 10109.4 10241.2 3514.6 3563.9 3566.8 3636.9 3726.6 82495.0 82564.6 85130.0 85235.9 85249.1 9538.5 9650.2 9693.7 9823.4 9945.8 21764.5 21903.2 22308.2 22412.0 22412.4 7283.5 7338.2 7358.1 7367.8 7439.9 4016.3 4052.0 4096.3 4106.4 4114.5 3536.1 3603.9 3606.9 3608.5 3611.1 551.5 647.0 648.1 659.5 659.5 567.7 586.9 596.4 603.0 613.6 7814.2 7969.8 8090.2 8106.0 8355.0 570.3 588.8 597.7 601.7 605.8 3267.3 3667.1 3731.2 3907.8 3911.3 94478.0 94978.0 97045.0 97543.0 97639.0 81415.6 82070.0 82332.6 82418.6 82635.0 69077.7 69246.0 71520.6 71675.3 72722.0 7470.6 7599.4 8008.6 8147.8 8217.6 43440.0 46881.8 47891.8 47986.0 48360.0 50956.0 68534.0 69103.7 69649.7 71018.0 10666.7 10770.9 10951.6 10953.2 11017.2 11733.4 11985.5 12025.4 12029.3 12347.1 11023.9 11052.2 11311.7 11318.9 11779.0 11201.2 11261.0 11293.5 11537.7 12034.2 9018.7 9098.0 10995.5 11388.4 11542.5 8238.1 9050.0 9312.5 9513.2 9748.0 9299.5 9613.2 9771.2 9775.9 9848.6 09:50:38 INFO - PROCESS | 1963 | -------- Summary: end -------- 09:50:38 INFO - PROCESS | 1963 | 09:50:39 INFO - TEST-INFO | 1963: exit 0 09:50:39 INFO - TEST-OK | dromaeo_css | took 1111029ms 09:50:39 INFO - TEST-START | kraken 09:50:39 INFO - operating with platform_type : mac_ 09:50:39 INFO - Initialising browser for kraken test... 09:50:39 INFO - TEST-INFO | started process 1969 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpFkUaY3/profile http://localhost:52331/getInfo.html) 09:50:42 INFO - PROCESS | 1969 | __metrics Screen width/height:1600/1200 09:50:42 INFO - PROCESS | 1969 | colorDepth:24 09:50:42 INFO - PROCESS | 1969 | Browser inner width/height: 1024/689 09:50:42 INFO - PROCESS | 1969 | __metrics 09:50:42 ERROR - PROCESS | 1969 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 09:50:42 INFO - TEST-INFO | 1969: exit 0 09:50:42 INFO - Browser initialized. 09:50:42 INFO - Running cycle 1/1 for kraken test... 09:50:42 INFO - TEST-INFO | started process 1972 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpFkUaY3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:50:54 INFO - PROCESS | 1972 | RSS: Main: 221319168 09:50:54 INFO - PROCESS | 1972 | 09:50:57 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52331/tests/kraken/driver.html?testName=audio-beat-detection) 09:50:58 INFO - PROCESS | 1972 | RSS: Main: 232935424 09:50:58 INFO - PROCESS | 1972 | 09:51:01 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52331/tests/kraken/driver.html?testName=audio-dft) 09:51:01 INFO - PROCESS | 1972 | RSS: Main: 241766400 09:51:01 INFO - PROCESS | 1972 | 09:51:05 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52331/tests/kraken/driver.html?testName=audio-fft) 09:51:05 INFO - PROCESS | 1972 | RSS: Main: 246587392 09:51:05 INFO - PROCESS | 1972 | 09:51:07 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52331/tests/kraken/driver.html?testName=audio-oscillator) 09:51:08 INFO - PROCESS | 1972 | RSS: Main: 247193600 09:51:08 INFO - PROCESS | 1972 | 09:51:10 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52331/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:51:11 INFO - PROCESS | 1972 | RSS: Main: 247746560 09:51:11 INFO - PROCESS | 1972 | 09:51:13 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52331/tests/kraken/driver.html?testName=imaging-darkroom) 09:51:14 INFO - PROCESS | 1972 | RSS: Main: 248000512 09:51:14 INFO - PROCESS | 1972 | 09:51:17 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52331/tests/kraken/driver.html?testName=imaging-desaturate) 09:51:17 INFO - PROCESS | 1972 | RSS: Main: 248004608 09:51:17 INFO - PROCESS | 1972 | 09:51:20 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52331/tests/kraken/driver.html?testName=json-parse-financial) 09:51:21 INFO - PROCESS | 1972 | RSS: Main: 248102912 09:51:21 INFO - PROCESS | 1972 | 09:51:23 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52331/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:51:23 INFO - PROCESS | 1972 | RSS: Main: 247336960 09:51:23 INFO - PROCESS | 1972 | 09:51:25 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:51:26 INFO - PROCESS | 1972 | RSS: Main: 247328768 09:51:26 INFO - PROCESS | 1972 | 09:51:28 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:51:29 INFO - PROCESS | 1972 | RSS: Main: 247402496 09:51:29 INFO - PROCESS | 1972 | 09:51:32 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:51:32 INFO - PROCESS | 1972 | RSS: Main: 247410688 09:51:32 INFO - PROCESS | 1972 | 09:51:35 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:51:36 INFO - PROCESS | 1972 | RSS: Main: 247418880 09:51:36 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52331/tests/kraken/driver.html?testName=ai-astar) 09:51:38 INFO - PROCESS | 1972 | RSS: Main: 249344000 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | __start_tp_report 09:51:38 INFO - PROCESS | 1972 | _x_x_mozilla_page_load 09:51:38 INFO - PROCESS | 1972 | _x_x_mozilla_page_load_details 09:51:38 INFO - PROCESS | 1972 | |i|pagename|runs| 09:51:38 INFO - PROCESS | 1972 | |0;ai-astar;114;109;159;173;128;117;106;115;116;117 09:51:38 INFO - PROCESS | 1972 | |1;audio-beat-detection;160;152;178;168;132;157;123;163;158;152 09:51:38 INFO - PROCESS | 1972 | |2;audio-dft;198;197;193;223;278;210;197;194;196;198 09:51:38 INFO - PROCESS | 1972 | |3;audio-fft;63;78;78;145;55;57;57;61;129;56 09:51:38 INFO - PROCESS | 1972 | |4;audio-oscillator;77;82;126;78;77;80;122;79;84;123 09:51:38 INFO - PROCESS | 1972 | |5;imaging-gaussian-blur;88;86;87;85;89;85;88;103;89;87 09:51:38 INFO - PROCESS | 1972 | |6;imaging-darkroom;94;95;103;95;95;94;94;97;94;119 09:51:38 INFO - PROCESS | 1972 | |7;imaging-desaturate;150;147;90;89;91;90;111;90;90;92 09:51:38 INFO - PROCESS | 1972 | |8;json-parse-financial;63;64;66;59;80;97;94;112;74;63 09:51:38 INFO - PROCESS | 1972 | |9;json-stringify-tinderbox;65;70;77;97;67;66;64;62;92;105 09:51:38 INFO - PROCESS | 1972 | |10;stanford-crypto-aes;82;83;80;83;84;83;80;85;84;86 09:51:38 INFO - PROCESS | 1972 | |11;stanford-crypto-ccm;132;128;125;129;133;180;154;156;184;143 09:51:38 INFO - PROCESS | 1972 | |12;stanford-crypto-pbkdf2;173;171;173;169;172;176;166;174;222;228 09:51:38 INFO - PROCESS | 1972 | |13;stanford-crypto-sha256-iterative;67;70;71;67;82;99;93;94;69;69 09:51:38 INFO - PROCESS | 1972 | __end_tp_report 09:51:38 INFO - PROCESS | 1972 | __start_cc_report 09:51:38 INFO - PROCESS | 1972 | _x_x_mozilla_cycle_collect,1088 09:51:38 INFO - PROCESS | 1972 | __end_cc_report 09:51:38 INFO - PROCESS | 1972 | __startTimestamp1464627098354__endTimestamp 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | ------- Summary: start ------- 09:51:38 INFO - PROCESS | 1972 | Number of tests: 14 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | [#0] ai-astar Cycles:10 Average:125.40 Median:117.00 stddev:22.40 (19.1%) stddev-sans-first:23.37 09:51:38 INFO - PROCESS | 1972 | Values: 114.0 109.0 159.0 173.0 128.0 117.0 106.0 115.0 116.0 117.0 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | [#1] audio-beat-detection Cycles:10 Average:154.30 Median:159.00 stddev:16.21 (10.2%) stddev-sans-first:17.07 09:51:38 INFO - PROCESS | 1972 | Values: 160.0 152.0 178.0 168.0 132.0 157.0 123.0 163.0 158.0 152.0 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | [#2] audio-dft Cycles:10 Average:208.40 Median:198.00 stddev:26.11 (13.2%) stddev-sans-first:27.42 09:51:38 INFO - PROCESS | 1972 | Values: 198.0 197.0 193.0 223.0 278.0 210.0 197.0 194.0 196.0 198.0 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | [#3] audio-fft Cycles:10 Average:77.90 Median:59.00 stddev:32.49 (55.1%) stddev-sans-first:34.01 09:51:38 INFO - PROCESS | 1972 | Values: 63.0 78.0 78.0 145.0 55.0 57.0 57.0 61.0 129.0 56.0 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | [#4] audio-oscillator Cycles:10 Average:92.80 Median:78.50 stddev:21.43 (27.3%) stddev-sans-first:21.96 09:51:38 INFO - PROCESS | 1972 | Values: 77.0 82.0 126.0 78.0 77.0 80.0 122.0 79.0 84.0 123.0 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:87.50 stddev:5.23 (6.0%) stddev-sans-first:5.54 09:51:38 INFO - PROCESS | 1972 | Values: 88.0 86.0 87.0 85.0 89.0 85.0 88.0 103.0 89.0 87.0 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | [#6] imaging-darkroom Cycles:10 Average:98.00 Median:94.50 stddev:7.87 (8.3%) stddev-sans-first:8.22 09:51:38 INFO - PROCESS | 1972 | Values: 94.0 95.0 103.0 95.0 95.0 94.0 94.0 97.0 94.0 119.0 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | [#7] imaging-desaturate Cycles:10 Average:104.00 Median:90.00 stddev:24.35 (27.1%) stddev-sans-first:19.32 09:51:38 INFO - PROCESS | 1972 | Values: 150.0 147.0 90.0 89.0 91.0 90.0 111.0 90.0 90.0 92.0 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | [#8] json-parse-financial Cycles:10 Average:77.20 Median:70.00 stddev:18.07 (25.8%) stddev-sans-first:18.42 09:51:38 INFO - PROCESS | 1972 | Values: 63.0 64.0 66.0 59.0 80.0 97.0 94.0 112.0 74.0 63.0 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | [#9] json-stringify-tinderbox Cycles:10 Average:76.50 Median:68.50 stddev:15.69 (22.9%) stddev-sans-first:16.08 09:51:38 INFO - PROCESS | 1972 | Values: 65.0 70.0 77.0 97.0 67.0 66.0 64.0 62.0 92.0 105.0 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.50 stddev:1.94 (2.3%) stddev-sans-first:2.03 09:51:38 INFO - PROCESS | 1972 | Values: 82.0 83.0 80.0 83.0 84.0 83.0 80.0 85.0 84.0 86.0 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:148.50 stddev:21.58 (14.5%) stddev-sans-first:22.25 09:51:38 INFO - PROCESS | 1972 | Values: 132.0 128.0 125.0 129.0 133.0 180.0 154.0 156.0 184.0 143.0 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.40 Median:173.50 stddev:22.66 (13.1%) stddev-sans-first:23.78 09:51:38 INFO - PROCESS | 1972 | Values: 173.0 171.0 173.0 169.0 172.0 176.0 166.0 174.0 222.0 228.0 09:51:38 INFO - PROCESS | 1972 | 09:51:38 INFO - PROCESS | 1972 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.10 Median:76.50 stddev:12.71 (16.6%) stddev-sans-first:12.84 09:51:38 INFO - PROCESS | 1972 | Values: 67.0 70.0 71.0 67.0 82.0 99.0 93.0 94.0 69.0 69.0 09:51:38 INFO - PROCESS | 1972 | -------- Summary: end -------- 09:51:38 INFO - PROCESS | 1972 | 09:51:39 INFO - TEST-INFO | 1972: exit 0 09:51:39 INFO - TEST-OK | kraken | took 60040ms 09:51:39 INFO - SUITE-END | took 1171s 09:51:39 INFO - Completed test suite (00:19:31) 09:51:39 INFO - javascript benchmark 09:51:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2891.2627280043585, 3419.2135808763987, 3501.089716673664, 3521.137321356268, 3526.418141006734, 4321.416608757824, 5435.402105768362, 5692.203091567184, 5710.314762228539, 5768.077107662775, 4955.826546070888, 5709.172170035343, 5740.942590574093, 5776.335721392041, 5787.450192231742, 4584.266517357223, 4803.678988278217, 5189.221616757488, 5235.58115350771, 5304.575633949294, 3796.810159492028, 4837.443693975203, 5198.974005129988, 5227.764750586232, 5329.280547126146, 5285.207218917155, 5298.072837253484, 5298.496642818926, 5299.788008479656, 5317.042932272189, 5251.264822924755, 5283.181106928432, 5294.629375943686, 5301.893962120736, 5316.5746740261075, 5093.923591146136, 5107.693538387709, 5228.398734145546, 5236.476352364733, 5258.027264955957, 4489.708168969007, 5367.194920761917, 5635.042042852684, 5677.687727175004, 5720.056190728525, 6122.70311464876, 6231.410990197516, 6250.4374606285655, 6256.280527739323, 6291.6854157291955, 6141.70080943337, 6204.224471941007, 6215.12988181655, 6232.906506402408, 6277.0, 5838.892046304327, 5858.238429004245, 5859.44335288147, 5864.765409383662, 5901.734421951022, 6144.556029333103, 6191.721372538246, 6195.535334849814, 6297.96851015742, 6323.7786677466065, 6149.13912052313, 6218.471429928507, 6232.750689972441, 6243.843903902439, 6253.1245625612455, 5465.262189604432, 6219.782307619302, 6230.345813689588, 6238.282597501255, 6266.968665156645, 6120.508083830795, 6125.326214116443, 6153.9845925421805, 6223.408776166257, 6267.341929097471, 6189.164462797465, 6215.595986260879, 6251.718672659741, 6253.6560489173535, 6308.842278943063, 4891.926621100686, 4914.238293064581, 4931.87670308238, 4946.307516947631, 4954.900901982012, 4342.218400687843, 4389.3635422864145, 4441.800118994653, 4480.193565158303, 4491.348754430589, 5515.117581186991, 5807.332156801937, 5828.737706803204, 5830.2420685310635, 5840.766369345263, 6192.752289908443, 6238.344973777688, 6254.405831446005, 6256.0, 6256.874862502725, 5651.547876169897, 5683.8010669626365, 5726.599138060295, 5826.592138550345, 5841.561882858802, 5424.728763561806, 5432.864178395572, 5434.157705555645, 5444.972775136099, 5461.290032295776, 6343.175387199634, 6363.459105976044, 6364.522660800366, 6372.203474565679, 6376.74493020274, 6123.785667501616, 6154.984427569401, 6217.191765070512, 6223.688815559294, 6240.438360547572, 6290.308066112815, 6336.683165841781, 6344.492440604833, 6349.904751428639, 6354.856125897383, 5983.37174596661, 6212.347703491249, 6222.782202622887, 6239.220097487814, 6248.9062664059165, 4822.300766388924, 6146.832101900624, 6247.843803904939, 6257.937420625735, 6268.9686551568775, 5008.649394542347, 5621.566500542335, 5833.82498525045, 6135.478484328703, 6145.754169833157, 4389.956100439083, 6045.304789949037, 6100.1154832548145, 6126.632402055891, 6165.10605962124, 5169.689818610896, 5885.9405307045145, 5942.811437712561, 5951.315598706205, 6037.547183961308, 5459.890802184013, 6133.9079913802225, 6154.507639388928, 6187.504999600112, 6206.503479721521, 5814.127880817742, 5872.119182122716, 5924.348321684697, 6013.248343957005, 6057.697115144313], "value": 5640.7383789802125, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.8440467859642, 545.6535100211368, 576.0552693582523, 583.425326053837, 607.6384551192042, 33402.83298583503, 38212.0, 38743.99999999996, 39020.0, 39274.0, 40609.59390406095, 41133.38299925504, 41243.968900777494, 41371.58628413715, 41385.0, 9520.047995200477, 9752.756181095458, 9761.267904907161, 9772.364796288253, 9787.510624468783, 9650.372725772837, 9863.704088877344, 9900.50497475127, 9918.206543476505, 9947.75130621733, 9858.654947076853, 9867.753306167331, 9896.257780666443, 9898.554565044555, 9909.108180263775, 9817.067378599022, 9840.507974601242, 9894.604215831358, 9896.15882649972, 9930.354526955796, 9713.36863103896, 9806.460644664541, 9812.411255324703, 9832.655857044969, 9895.208383329316, 39627.801860990796, 39887.60112398897, 40113.59886401128, 40195.79902100471, 40283.79148625546, 2855.8714857831446, 3030.1212648331957, 3079.90760277192, 3128.8435578220997, 3176.8093914365213, 26148.607770883453, 26218.991439750458, 27013.0, 27132.321691957663, 27447.039353622728, 2617.227917764264, 2712.972870271292, 2742.80800343976, 2805.004223500648, 2836.0, 387021.0, 393551.0, 393695.03152484057, 395670.0, 396674.0, 34982.47526287108, 35142.0, 35265.64734352624, 35477.82261088678, 36749.81625091911, 36588.0, 36726.449103263476, 36885.63114368822, 36894.8155259222, 37051.88844334674, 15678.9216053919, 15843.603909902346, 15973.840261597468, 16014.759778603331, 16126.677466450605, 13490.662733431547, 13501.392437340342, 13565.932170339085, 13606.115602485806, 13614.978876584295, 8158.224968627971, 8195.221453961864, 8258.050324212672, 8288.875666865002, 8345.499270043816, 2957.166556734838, 3315.2540678347177, 3390.881319153818, 3444.293919746446, 3456.6024907136, 1017.043978660072, 1022.0954455306958, 1024.9180065594735, 1101.8732845722684, 1113.4265853085667, 29865.701342986293, 30602.704797140304, 30719.846400767852, 31745.365092698015, 32294.515582266285, 1051.779126383468, 1088.4992903264588, 1093.9398333091597, 1141.914356423255, 1187.1927089578996, 11820.763584728258, 14738.263086845614, 14895.25523723831, 15031.774523382159, 15115.244237788067, 1021093.0, 1026127.0, 1047990.0, 1053845.0, 1055277.0, 61143.69428152831, 61800.69099654473, 62742.745145096844, 62890.68554657289, 62896.37103628905, 1488.7990121333698, 1497.6854860479204, 1499.6175975126273, 1504.601280660626, 1513.2736286582501, 28596.42807143887, 29856.402871942006, 30154.547681784352, 30200.5469917947, 30332.545011824397, 53182.0, 53206.2019069722, 53796.73101634623, 53825.46174538361, 54086.72956635192, 535042.0, 603647.0, 610639.0, 610914.0, 615025.0, 27421.862890685417, 27913.581296280165, 27932.44135117327, 28098.438031238853, 28383.574246385906, 45284.320735189656, 45513.31730023986, 46160.76919615381, 46754.0, 46957.765211173726, 37115.81442092772, 37498.0, 37975.430368543944, 39757.60242397539, 40293.0, 33934.79423837541, 36412.08969775671, 37041.44437833381, 37187.442188367735, 37279.44080838844, 29928.70071299259, 36711.816440917624, 36713.265734685694, 37501.0, 37835.05412364603, 33224.50163247505, 33317.33365332728, 33517.0, 33588.16029599182, 33782.15544611404, 25516.362090947874, 25560.8721956389, 25701.3574660635, 25861.870690646425, 26495.867520662272], "value": 18688.06567433403, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.6367078616886, 406.94099355593437, 408.81807595619944, 411.34185303514363, 421.1976185366875, 14702.264886755673, 19921.701174482376, 20605.27881524145, 20649.380518584447, 20690.172393104258, 20245.696314555295, 20465.488362790937, 20590.48523786906, 21032.263870764487, 21143.04856281467, 5644.520215781664, 5655.786439102874, 5883.0, 5895.380984996578, 5953.642781433106, 5588.329400471949, 5602.1596760485845, 5809.3609702932645, 5910.379410161936, 5929.347771745104, 5885.20549725788, 5899.675517846538, 5904.291485021782, 5927.73325200367, 5937.67342796146, 6192.473639740605, 6660.100886380325, 6665.066890635315, 6665.800025999228, 6723.596584204964, 5356.91964620531, 5843.824685259449, 5855.8536036599, 5890.469857712784, 5904.498117660004, 13462.990275729262, 13701.931490342484, 13716.971227158003, 13756.449742010307, 14662.926685366505, 2017.0434678361964, 2127.5957568062017, 2222.855514391559, 2454.7299797022315, 2533.733957934409, 9848.50757462124, 10040.544121102472, 10207.744806379826, 13931.442742290295, 13949.511767088103, 1685.8229885862054, 1710.811810700822, 1713.494519116851, 1740.5996620777291, 1741.0946307919937, 87770.56114719449, 124021.0, 124298.37850810778, 124582.37708811488, 126881.0, 11688.766224675459, 11949.283043017447, 12034.277943323428, 12186.939065304794, 12323.075769317336, 15253.771193432107, 15302.923485382502, 15306.234688265542, 15325.46360877364, 15370.692586148214, 8839.646414143492, 8958.014618391971, 8983.955080224558, 8984.281257499384, 9046.321525885453, 8972.775680608038, 8986.281097512183, 9001.684941027033, 9047.100108977056, 9048.592813323416, 3797.525309336333, 4618.838340658128, 4643.3963584733765, 4656.837010704609, 4784.210605250095, 1649.5463747469303, 2006.2576846566865, 2013.6375452418706, 2022.51459649683, 2043.652579061563, 410.9876703698894, 429.49105310207506, 449.4696258415036, 839.9244068033784, 1025.4923812712607, 10917.890821091847, 11668.0, 12116.333601651993, 12170.513179472722, 12228.816567751492, 458.1935793004336, 461.3771408598432, 462.45661347916064, 464.8768076459741, 472.7777944366142, 5213.713245771443, 6162.537809664203, 6193.721282542297, 6291.339409362043, 6358.205224346957, 668114.0, 670479.0, 676458.0, 685842.0, 688749.0, 88589.0, 104560.0, 123271.0, 141000.0, 142496.28751856383, 442.5839710671958, 450.2480856968804, 454.89537406396715, 456.1378993701906, 457.34142834318476, 10872.402017889091, 11494.482748276514, 11531.942340288246, 11637.76724465489, 11693.473793679475, 55153.44846551483, 55428.0, 56687.71656141858, 57555.84888302294, 59107.40892591019, 76067.57695903587, 83682.58158709167, 84690.0, 85079.0, 85082.57458712667, 6313.46335561464, 7008.719651213894, 8319.0, 8363.03825060126, 8414.621342039745, 10133.391996480235, 11769.293842369485, 12632.178908370926, 12878.742425151631, 12964.546240881524, 10733.516991735234, 11003.119750419786, 11224.214304998899, 11302.660920172408, 11492.425378731197, 9003.594838232164, 9741.025897410202, 10168.593256269962, 10770.946145269223, 10863.728406789893, 10948.850370710961, 11245.493952772309, 11314.981651651391, 11523.769524609628, 11634.767304653691, 7963.044434667877, 8091.231333023354, 8349.457285276438, 8426.705065322683, 8743.650253989768, 8255.133211012997, 10229.28395012342, 11227.157963152633, 11402.973732364127, 11515.53937842477], "value": 8636.742512843522, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1557.400400845674, 1577.9842201577985, 1598.6562888978872, 1874.746909167262, 1942.8445724342055, 7769.844603107935, 8418.452800567959, 8497.532635705042, 8690.47857128572, 8725.563721813907, 7000.404965577932, 7448.255174482549, 8036.27673509384, 8599.91400085999, 8612.31101511879, 2406.1337918349423, 2419.4556224849443, 2427.611582146857, 2429.5505331513727, 2433.0024689877155, 1788.400885703291, 2389.4026493376655, 2394.820388470872, 2450.8774561271953, 2455.545724041049, 2414.14297924237, 2429.8177636677233, 2435.0016493237777, 2441.963370549443, 2445.1319781477587, 2415.806735461159, 2422.2733180046002, 2435.756424357568, 2443.816713746467, 2450.5221481810877, 2426.17510046585, 2447.155731272708, 2452.068214078639, 2454.7913427358694, 2462.470568827711, 8756.693515726918, 8801.47191168532, 8808.0, 8823.426477278956, 8825.42634728747, 5350.170723537858, 5438.0, 5439.5104440600535, 5449.155380915925, 5451.509364157205, 3484.250886059484, 3520.3135388599235, 3541.4333706607076, 3566.215432604849, 3594.9281014379567, 1036.041661463151, 1062.4315990944885, 1062.4422178356347, 1064.2071656615794, 1064.5794911010107, 11550.768984620345, 11673.591424300108, 11708.648740537797, 11708.946194842505, 11711.297322160783, 4653.185692503798, 4730.834420795324, 4814.566688998007, 4816.95183048165, 4830.492798256203, 9911.108000279864, 9914.256430767577, 9917.603295868228, 9925.751856203653, 9939.254555908192, 5408.567314614821, 5414.972925135349, 5415.160650099241, 5447.101228297287, 5475.561955043587, 4149.8962525937095, 4742.4309082909585, 4832.347633069561, 4862.586680132157, 5007.824726134568, 4005.7997100144876, 4016.49793775778, 4055.8174882130374, 4082.2447847148374, 4094.0992981544014, 1346.8720471555187, 1356.938937747784, 1361.2036958379458, 1361.8501964783866, 1365.2013572060257, 896.8296023755465, 914.4056363363734, 930.9255259579218, 953.4184147669915, 956.3550692808498, 4520.841770538082, 5743.8851222976145, 5759.884802303931, 5820.243368362086, 5933.991221492357, 852.0371979662941, 854.7991222062934, 907.3421769217265, 965.6330594374093, 1031.4481752262504, 3586.677199052098, 3851.3837785954674, 3894.221155768801, 3906.746061505993, 3920.3335432976464, 10241.129503992095, 10456.588360571226, 10518.421486818146, 10614.310069845435, 10684.412357320267, 10324.174066074696, 10350.275480716427, 10372.792544149075, 10487.0, 10521.631742888962, 6917.7232910684015, 7689.077310722749, 8247.587620619064, 8302.626381812712, 8314.501129932223, 4299.613034826755, 5457.69982650958, 5614.691191984317, 5665.858353541194, 5764.106563482667, 9095.49974751395, 9139.63441462334, 9226.538673066454, 9255.583498742708, 9265.073492650681, 9086.561453849896, 9382.48396338208, 9428.057194280516, 9470.573824178065, 9666.468344241135, 5836.708164591839, 6706.362895524917, 7318.1584117825005, 7347.522411043479, 7420.0, 7945.364370850202, 8000.15998320168, 8007.91992080095, 8057.758267251992, 8090.959545202236, 7049.471289653194, 7168.2831716827895, 7179.533330333512, 7273.127224733066, 7281.672324745505, 7050.788476345718, 7074.752383666547, 7157.785266441807, 7205.495615306878, 7222.0611320528, 6662.467002639681, 6764.391204791593, 6841.760538381132, 6858.862822743417, 6910.378065974087, 4706.76466176683, 4792.856214313576, 4801.231802911518, 4884.340614017062, 4930.112579735682, 4850.466448690711, 4881.414230292387, 4888.437829649636, 4909.189983652657, 4944.802207911644], "value": 4534.671337917415, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.7795809870239, 331.8606185402132, 335.3243214921934, 339.08785367361793, 341.25776436251147, 6494.44797192238, 6545.5418120731565, 6635.734570617182, 6695.330466953308, 6737.966310168448, 6590.60456372618, 6600.0, 6628.933710662892, 6648.368405001529, 6666.3000384959605, 4860.343853579757, 4895.412550493946, 4901.950980490203, 4914.361133052698, 4918.0, 4833.564979151875, 4869.756512174395, 4884.0, 4932.975335123319, 4933.284673722308, 4808.903821923559, 4854.611631069506, 4856.902861942759, 4916.410030796309, 4918.901621967559, 4609.446866376023, 4778.928316075262, 4886.853394398174, 4892.0, 4898.877528061791, 4051.736637118607, 4052.959470405288, 4058.6955978301744, 4059.2287465381482, 4088.713790034699, 6147.323794382614, 6182.938170618326, 6183.81448556544, 6213.813585592439, 6220.751169953196, 5146.716930568818, 5649.8587535311535, 5653.802116925888, 5659.151127330892, 5668.574856885709, 7053.576785392893, 7054.365107140382, 7057.294270572953, 7072.3634872861685, 7094.503384763068, 3577.821108944542, 3732.0, 4580.9770951145365, 4590.701604395704, 4592.609628181609, 7441.8139546511775, 7527.209642987517, 7532.096148462219, 7546.735864244725, 7614.971978782849, 4662.207424737802, 4662.906741865144, 4666.789994450258, 4670.369500117441, 4672.439307283148, 4708.670393072452, 4732.645051621143, 4737.502562230985, 4743.1225223333795, 4746.02706445178, 5652.802151924745, 5658.462446067617, 5659.151127330851, 5674.404187560329, 5677.432256774289, 5014.846585285405, 5540.141278101901, 5719.19931209631, 7205.747798827016, 7225.277472252733, 1490.4187366926908, 1626.2763070433543, 1646.3167785369178, 1648.3077107614831, 1654.2555849867608, 985.113397941858, 1037.5849660136007, 1044.2220545693508, 1055.5039131608132, 1056.3714589819044, 6546.378094081055, 6683.0, 6715.925451927668, 6728.730850766013, 6731.798046058626, 5990.221271234799, 6100.877982440326, 7390.371818395497, 7468.477206595486, 7477.065366829146, 6693.427044644504, 6727.091842601284, 6741.089952856301, 6769.05233267343, 6773.119494465786, 3037.8481075946556, 3224.822634755064, 3905.9453947434035, 3919.1377896862646, 3981.741186822905, 7525.548467091992, 7814.3748500119855, 7816.179301173409, 7827.373810095179, 7852.254035866584, 7674.846503069907, 7719.035120609924, 7726.06848287928, 7752.147263800977, 7761.922380776233, 7715.0, 7808.0, 7812.218778122142, 7818.6090695465, 7833.64748586315, 7256.7460138894885, 7373.29953654402, 7430.665620047002, 7459.477836551389, 7465.44009199323, 7298.56208627484, 7329.377002954809, 7332.560046397233, 7364.742234021784, 7373.926260737324, 7755.72854950074, 7777.883331749915, 7783.688652453839, 7808.804779880548, 7821.882671759814, 3816.5992570779667, 4646.233371493801, 4666.766661666971, 4668.369770080995, 4673.509281525527, 7039.859202816017, 7407.888881666671, 7408.0, 7423.294786995227, 7438.03305570294, 7108.431325494052, 7108.715651373887, 7110.013794206549, 7115.217326094022, 7124.465665075244, 7057.50597458173, 7086.893696594659, 7116.359527642537, 7135.286471353031, 7140.500164988401, 6848.1097457330225, 6920.377166055079, 6922.03091567191, 6935.7225710971, 6936.930630693629, 6902.965485172542, 7086.964565177141, 7088.255733147946, 7090.397316228178, 7093.964530177316, 6737.090492783412, 6821.624810635462, 6916.135483064617, 6935.3411425914455, 7045.0], "value": 5146.9758965470755, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.1716896801422, 694.7047504810455, 735.5917465806475, 737.4211244173325, 768.5350363030368, 14626.0, 15038.624034399145, 15161.241937903102, 15281.541553753392, 15415.69168616627, 13939.721205575883, 13951.790723139162, 13968.860311396884, 13973.860261397384, 14037.087589306713, 729.7190581626072, 896.4486840593021, 896.551724137931, 908.9863652045192, 946.6118891254587, 916.0473107967706, 926.6571368593629, 926.9675561355353, 930.2557953637097, 940.4028441939383, 777.4091690315345, 947.3510645208055, 964.4888209249109, 983.316594966495, 1004.1264100232808, 861.9310455163571, 958.5830163878735, 981.9459929703863, 998.6305067125138, 1005.1958433253404, 701.6211245927193, 702.3713776170299, 703.7290643102391, 732.1653315220652, 744.222287709345, 15042.774358384633, 15200.695986080327, 15240.237988100662, 15281.923590382088, 15294.923525382414, 12789.0, 12869.09916305859, 12896.935515322364, 12951.611451656465, 13077.607671769862, 9783.217342612574, 10012.199024078056, 10099.899001010042, 10109.39343639384, 10241.180705543538, 3514.6309637487952, 3563.8574457021687, 3566.8394922228563, 3636.9272614547563, 3726.608706085849, 82495.0, 82564.58717706433, 85130.0, 85235.8691032723, 85249.14750852536, 9538.52307384628, 9650.179734722487, 9693.70918872435, 9823.36148150368, 9945.80108397828, 21764.45588860291, 21903.233386831384, 22308.219212327815, 22412.0, 22412.439689007904, 7283.490155689157, 7338.192798792129, 7358.11702595692, 7367.778966630903, 7439.85120297591, 4016.3373043448087, 4052.0477687743355, 4096.303628383182, 4106.3635136553885, 4114.465119534501, 3536.0806190390167, 3603.891883243507, 3606.945895811565, 3608.530890984155, 3611.0791748104066, 551.5449753953005, 646.9611823290523, 648.0829626079045, 659.475716805141, 659.482306389482, 567.6650776042155, 586.9413058694097, 596.4125336543501, 602.9547783916136, 613.5858295650418, 7814.21857814214, 7969.760907172794, 8090.150534193943, 8106.027276726831, 8354.958225208835, 570.2672066394663, 588.7733222709288, 597.6862147372664, 601.7412512619528, 605.794030029792, 3267.2975310308398, 3667.0832291927018, 3731.1604888900324, 3907.765534067965, 3911.2568611963634, 94478.0, 94978.0, 97045.0, 97543.0, 97639.0, 81415.59292203501, 82070.0, 82332.58833705792, 82418.58790706129, 82634.99999999879, 69077.65461172663, 69246.0, 71520.64239678768, 71675.28324716687, 72722.0, 7470.6264686766535, 7599.354054905395, 8008.599570021359, 8147.75556733299, 8217.58912054383, 43440.0, 46881.827954301414, 47891.76054119846, 47986.0, 48360.0, 50956.0, 68534.0, 69103.65448172727, 69649.65175174295, 71018.0, 10666.679999600025, 10770.946145269223, 10951.576295081588, 10953.178511611502, 11017.228793984345, 11733.413329333329, 11985.460654270753, 12025.398730063636, 12029.338386388832, 12347.073969452507, 11023.889761102286, 11052.226344156154, 11311.717207069889, 11318.886811132114, 11779.0, 11201.159913006393, 11261.0, 11293.491792869176, 11537.711557211136, 12034.217775844541, 9018.684346047858, 9097.954510227406, 10995.450227488434, 11388.430578470878, 11542.480588373375, 8238.09380968105, 9050.0, 9312.534373281173, 9513.191378732885, 9747.951260243653, 9299.488528130747, 9613.213297229267, 9771.169450596495, 9775.853362199716, 9848.606055757977], "value": 6901.328183508074, "unit": "score"}], "name": "dromaeo_css", "value": 7261.93926236259, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 109.0, 159.0, 173.0, 128.0, 117.0, 106.0, 115.0, 116.0, 117.0], "value": 125.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 152.0, 178.0, 168.0, 132.0, 157.0, 123.0, 163.0, 158.0, 152.0], "value": 154.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 197.0, 193.0, 223.0, 278.0, 210.0, 197.0, 194.0, 196.0, 198.0], "value": 208.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 78.0, 78.0, 145.0, 55.0, 57.0, 57.0, 61.0, 129.0, 56.0], "value": 77.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 82.0, 126.0, 78.0, 77.0, 80.0, 122.0, 79.0, 84.0, 123.0], "value": 92.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 85.0, 89.0, 85.0, 88.0, 103.0, 89.0, 87.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 103.0, 95.0, 95.0, 94.0, 94.0, 97.0, 94.0, 119.0], "value": 98.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 147.0, 90.0, 89.0, 91.0, 90.0, 111.0, 90.0, 90.0, 92.0], "value": 104.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 66.0, 59.0, 80.0, 97.0, 94.0, 112.0, 74.0, 63.0], "value": 77.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 77.0, 97.0, 67.0, 66.0, 64.0, 62.0, 92.0, 105.0], "value": 76.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 80.0, 83.0, 84.0, 83.0, 80.0, 85.0, 84.0, 86.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 128.0, 125.0, 129.0, 133.0, 180.0, 154.0, 156.0, 184.0, 143.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 171.0, 173.0, 169.0, 172.0, 176.0, 166.0, 174.0, 222.0, 228.0], "value": 182.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 71.0, 67.0, 82.0, 99.0, 93.0, 94.0, 69.0, 69.0], "value": 78.1, "unit": "score"}], "name": "kraken", "value": 1593.1000000000001, "alertThreshold": 2.0}]} 09:51:39 INFO - Return code: 0 09:51:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:51:39 ERROR - # TBPL SUCCESS # 09:51:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:51:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:51:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:51:39 INFO - 2016-05-30 09:51:39.664 screenresolution[1977:17935] starting screenresolution argv=screenresolution get 09:51:39 INFO - 2016-05-30 09:51:39.667 screenresolution[1977:17935] Display 0: 1600x1200x32@60 09:51:39 INFO - 2016-05-30 09:51:39.673 screenresolution[1978:17938] starting screenresolution argv=screenresolution list 09:51:39 INFO - Available Modes on Display 0 09:51:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:51:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:51:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:51:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:51:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:51:39 INFO - Intel Iris: 09:51:39 INFO - Chipset Model: Intel Iris 09:51:39 INFO - Type: GPU 09:51:39 INFO - Bus: Built-In 09:51:39 INFO - VRAM (Dynamic, Max): 1536 MB 09:51:39 INFO - Vendor: Intel (0x8086) 09:51:39 INFO - Device ID: 0x0a2e 09:51:39 INFO - Revision ID: 0x0009 09:51:39 INFO - Displays: 09:51:39 INFO - Display: 09:51:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:51:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:51:39 INFO - Display Serial Number: 1600x1200 60 09:51:39 INFO - Main Display: Yes 09:51:39 INFO - Mirror: Off 09:51:39 INFO - Online: Yes 09:51:39 INFO - Rotation: Supported 09:51:39 INFO - Return code: 0 09:51:39 INFO - Running post-action listener: _resource_record_post_action 09:51:39 INFO - Running post-run listener: _resource_record_post_run 09:51:40 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 339968; Write bytes: 652795392; Read time: 4; Write time: 1817 09:51:40 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344762880; Write bytes: 345740288; Read time: 26634; Write time: 671 09:51:40 INFO - run-tests - Wall time: 1173s; CPU: 54.0%; Read bytes: 339968; Write bytes: 296106496; Read time: 4; Write time: 1107 09:51:40 INFO - Running post-run listener: _upload_blobber_files 09:51:40 INFO - Blob upload gear active. 09:51:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:51:40 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:51:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:51:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:51:41 INFO - (blobuploader) - INFO - Open directory for files ... 09:51:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:51:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:51:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:42 INFO - (blobuploader) - INFO - Done attempting. 09:51:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:51:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:51:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:43 INFO - (blobuploader) - INFO - Done attempting. 09:51:43 INFO - (blobuploader) - INFO - Iteration through files over. 09:51:43 INFO - Return code: 0 09:51:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:51:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:51:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f5cf9e8047b0a39b02c7fade3698111aefbd08030a4a4932956beb6616eb8cc3ab8b6bf528a9a387126135897b6adbccddfdcc8ee0efcdc7c297c3f6bfdd20f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8abd7c37e7dbc3c47abfc44ec7c16fd64a78a303cd7654fec11998801af04d62ca27ce31ec08c26c40f0470961d602b8a942cc60aca061ce457436b49e6d7356"} 09:51:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:51:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:51:43 INFO - Contents: 09:51:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f5cf9e8047b0a39b02c7fade3698111aefbd08030a4a4932956beb6616eb8cc3ab8b6bf528a9a387126135897b6adbccddfdcc8ee0efcdc7c297c3f6bfdd20f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8abd7c37e7dbc3c47abfc44ec7c16fd64a78a303cd7654fec11998801af04d62ca27ce31ec08c26c40f0470961d602b8a942cc60aca061ce457436b49e6d7356"} 09:51:43 INFO - Running post-run listener: copy_logs_to_upload_dir 09:51:43 INFO - Copying logs to upload dir... 09:51:43 INFO - mkdir: /builds/slave/test/build/upload/logs 09:51:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.704489 ========= master_lag: 1.90 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2016-05-30 09:51:45.008578) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 09:51:45.012387) ========= 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.qWCHMelAoj/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.0YwVff4ZYj/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f5cf9e8047b0a39b02c7fade3698111aefbd08030a4a4932956beb6616eb8cc3ab8b6bf528a9a387126135897b6adbccddfdcc8ee0efcdc7c297c3f6bfdd20f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8abd7c37e7dbc3c47abfc44ec7c16fd64a78a303cd7654fec11998801af04d62ca27ce31ec08c26c40f0470961d602b8a942cc60aca061ce457436b49e6d7356"} build_url:https://queue.taskcluster.net/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011210 build_url: 'https://queue.taskcluster.net/v1/task/Jk2uN2dnQ96SQ5vl3DZlWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f5cf9e8047b0a39b02c7fade3698111aefbd08030a4a4932956beb6616eb8cc3ab8b6bf528a9a387126135897b6adbccddfdcc8ee0efcdc7c297c3f6bfdd20f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8abd7c37e7dbc3c47abfc44ec7c16fd64a78a303cd7654fec11998801af04d62ca27ce31ec08c26c40f0470961d602b8a942cc60aca061ce457436b49e6d7356"}' ========= master_lag: 0.70 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 09:51:45.719546) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:51:45.719865) ========= 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.qWCHMelAoj/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.0YwVff4ZYj/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.005319 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:51:45.745998) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 09:51:45.746272) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 09:51:45.746568) ========= ========= Total master_lag: 2.69 =========