builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0069 starttime: 1464947354.65 results: success (0) buildid: 20160603004101 builduid: d3d53c7dcb0f4d7795846bf280fe9beb revision: effbb9ad4e93c4383e484f3a515d23194d3c6329 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 02:49:14.646417) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 02:49:14.647213) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 02:49:14.647581) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 02:49:14.735435) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 02:49:14.735737) ========= 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.Jv2LMAupjO/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.pAEb5FZmG6/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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-06-03 02:49:14-- 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% 39.7M=0s 2016-06-03 02:49:15 (39.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.630024 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 02:49:15.387038) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 02:49:15.387360) ========= 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.Jv2LMAupjO/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.pAEb5FZmG6/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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.027643 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 02:49:15.435806) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 02:49:15.436196) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev effbb9ad4e93c4383e484f3a515d23194d3c6329 --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 effbb9ad4e93c4383e484f3a515d23194d3c6329 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Jv2LMAupjO/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.pAEb5FZmG6/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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-06-03 02:49:15,511 truncating revision to first 12 chars 2016-06-03 02:49:15,511 Setting DEBUG logging. 2016-06-03 02:49:15,511 attempt 1/10 2016-06-03 02:49:15,511 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/effbb9ad4e93?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-03 02:49:16,155 unpacking tar archive at: mozilla-aurora-effbb9ad4e93/testing/mozharness/ program finished with exit code 0 elapsedTime=0.907957 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 02:49:16.363808) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 02:49:16.364140) ========= script_repo_revision: effbb9ad4e93c4383e484f3a515d23194d3c6329 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 02:49:16.364535) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 02:49:16.364812) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 02:49:16.381297) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-06-03 02:49:16.381723) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.Jv2LMAupjO/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.pAEb5FZmG6/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 02:49:16 INFO - MultiFileLogger online at 20160603 02:49:16 in /builds/slave/test 02:49:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:49:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:49:16 INFO - {'append_to_log': False, 02:49:16 INFO - 'base_work_dir': '/builds/slave/test', 02:49:16 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:49:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:49:16 INFO - 'branch': 'Mozilla-Aurora', 02:49:16 INFO - 'buildbot_json_path': 'buildprops.json', 02:49:16 INFO - 'config_files': ('talos/mac_config.py',), 02:49:16 INFO - 'default_actions': ('clobber', 02:49:16 INFO - 'read-buildbot-config', 02:49:16 INFO - 'download-and-extract', 02:49:16 INFO - 'populate-webroot', 02:49:16 INFO - 'create-virtualenv', 02:49:16 INFO - 'install', 02:49:16 INFO - 'run-tests'), 02:49:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:49:16 INFO - 'download_minidump_stackwalk': True, 02:49:16 INFO - 'download_symbols': 'ondemand', 02:49:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:49:16 INFO - 'tooltool.py': '/tools/tooltool.py', 02:49:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:49:16 INFO - '/tools/misc-python/virtualenv.py')}, 02:49:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:49:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:49:16 INFO - 'installer_path': 'installer.exe', 02:49:16 INFO - 'log_level': 'info', 02:49:16 INFO - 'log_name': 'talos', 02:49:16 INFO - 'log_to_console': True, 02:49:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:49:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:49:16 INFO - 'opt_config_files': (), 02:49:16 INFO - 'pip_index': False, 02:49:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:49:16 INFO - 'cmd': ('bash', 02:49:16 INFO - '-c', 02:49:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:49:16 INFO - 'enabled': True, 02:49:16 INFO - 'halt_on_failure': False, 02:49:16 INFO - 'name': 'check_screen_resolution'},), 02:49:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:49:16 INFO - 'cmd': ('bash', 02:49:16 INFO - '-c', 02:49:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:49:16 INFO - 'enabled': True, 02:49:16 INFO - 'halt_on_failure': False, 02:49:16 INFO - 'name': 'check_screen_resolution'},), 02:49:16 INFO - 'run_cmd_checks_enabled': True, 02:49:16 INFO - 'sps_profile': False, 02:49:16 INFO - 'sps_profile_interval': 0, 02:49:16 INFO - 'suite': 'dromaeojs', 02:49:16 INFO - 'system_bits': '32', 02:49:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:49:16 INFO - 'title': 't-yosemite-r7-0069', 02:49:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:49:16 INFO - 'use_talos_json': True, 02:49:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:49:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:49:16 INFO - 'work_dir': 'build'} 02:49:16 INFO - ##### 02:49:16 INFO - ##### Running clobber step. 02:49:16 INFO - ##### 02:49:16 INFO - Running pre-action listener: _resource_record_pre_action 02:49:16 INFO - Running main action method: clobber 02:49:16 INFO - rmtree: /builds/slave/test/build 02:49:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:49:18 INFO - Running post-action listener: _resource_record_post_action 02:49:18 INFO - ##### 02:49:18 INFO - ##### Running read-buildbot-config step. 02:49:18 INFO - ##### 02:49:18 INFO - Running pre-action listener: _resource_record_pre_action 02:49:18 INFO - Running main action method: read_buildbot_config 02:49:18 INFO - Using buildbot properties: 02:49:18 INFO - { 02:49:18 INFO - "project": "", 02:49:18 INFO - "product": "firefox", 02:49:18 INFO - "script_repo_revision": "production", 02:49:18 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:49:18 INFO - "repository": "", 02:49:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:49:18 INFO - "buildid": "20160603004101", 02:49:18 INFO - "stage_platform": "macosx64", 02:49:18 INFO - "basedir": "/builds/slave/test", 02:49:18 INFO - "nightly_build": "True", 02:49:18 INFO - "buildnumber": 19, 02:49:18 INFO - "slavename": "t-yosemite-r7-0069", 02:49:18 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:49:18 INFO - "platform": "yosemite_r7", 02:49:18 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:49:18 INFO - "branch": "mozilla-aurora", 02:49:18 INFO - "repo_path": "releases/mozilla-aurora", 02:49:18 INFO - "revision": "effbb9ad4e93c4383e484f3a515d23194d3c6329", 02:49:18 INFO - "pgo_build": "False", 02:49:18 INFO - "builduid": "d3d53c7dcb0f4d7795846bf280fe9beb", 02:49:18 INFO - "slavebuilddir": "test" 02:49:18 INFO - } 02:49:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:49:18 INFO - Running post-action listener: _resource_record_post_action 02:49:18 INFO - ##### 02:49:18 INFO - ##### Running download-and-extract step. 02:49:18 INFO - ##### 02:49:18 INFO - Running pre-action listener: _resource_record_pre_action 02:49:18 INFO - Running main action method: download_and_extract 02:49:18 INFO - mkdir: /builds/slave/test/build/tests 02:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:49:18 INFO - https://queue.taskcluster.net/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:49:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:49:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:49:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/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 02:49:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/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 02:49:23 INFO - Downloaded 1199 bytes. 02:49:23 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:49:23 INFO - Using the following test package requirements: 02:49:23 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:49:23 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:49:23 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:49:23 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'jsshell-mac.zip'], 02:49:23 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:49:23 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:49:23 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:49:23 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:49:23 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:49:23 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:49:23 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:49:23 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:49:23 INFO - https://queue.taskcluster.net/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:49:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:49:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:49:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/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 02:49:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/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 02:49:24 INFO - Downloaded 18463908 bytes. 02:49:24 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'] 02:49:24 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 02:49:27 INFO - Return code: 0 02:49:27 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 02:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:49:27 INFO - https://queue.taskcluster.net/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:49:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:49:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:49:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/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 02:49:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/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 02:49:28 INFO - Downloaded 18463908 bytes. 02:49:28 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'] 02:49:28 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 02:49:32 INFO - Return code: 0 02:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:49:32 INFO - https://queue.taskcluster.net/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:49:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:49:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:49:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/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 02:49:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/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 02:49:33 INFO - Downloaded 10840145 bytes. 02:49:33 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'] 02:49:33 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 02:49:34 INFO - Return code: 0 02:49:34 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')]} 02:49:34 INFO - https://queue.taskcluster.net/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:49:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:49:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:49:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:49:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WE4SlDMmQ4K4jZR1miItZA/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 02:49:40 INFO - Downloaded 114225261 bytes. 02:49:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:49:40 INFO - mkdir: /builds/slave/test/properties 02:49:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:49:40 INFO - Writing to file /builds/slave/test/properties/build_url 02:49:40 INFO - Contents: 02:49:40 INFO - build_url:https://queue.taskcluster.net/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:49:41 INFO - Running post-action listener: _resource_record_post_action 02:49:41 INFO - Running post-action listener: set_extra_try_arguments 02:49:41 INFO - ##### 02:49:41 INFO - ##### Running populate-webroot step. 02:49:41 INFO - ##### 02:49:41 INFO - Running pre-action listener: _resource_record_pre_action 02:49:41 INFO - Running main action method: populate_webroot 02:49:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:49:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:49:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:49:41 INFO - '--tppagecycles', 02:49:41 INFO - '7'], 02:49:41 INFO - 'tests': ['tsvgm']}}, 02:49:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:49:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:49:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:49:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:49:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:49:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:49:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:41 INFO - 'talos_options': ['--disable-e10s'], 02:49:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:49:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:49:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:41 INFO - 'talos_options': ['--disable-e10s'], 02:49:41 INFO - 'tests': ['damp', 'tps']}, 02:49:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:41 INFO - 'tests': ['damp', 'tps']}, 02:49:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:49:41 INFO - 'tests': ['dromaeo_dom']}, 02:49:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:49:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:49:41 INFO - 'tests': ['a11yr', 02:49:41 INFO - 'ts_paint', 02:49:41 INFO - 'tpaint', 02:49:41 INFO - 'sessionrestore', 02:49:41 INFO - 'sessionrestore_no_auto_restore', 02:49:41 INFO - 'tabpaint']}, 02:49:41 INFO - 'other-e10s': {'tests': ['a11yr', 02:49:41 INFO - 'ts_paint', 02:49:41 INFO - 'tpaint', 02:49:41 INFO - 'sessionrestore', 02:49:41 INFO - 'sessionrestore_no_auto_restore', 02:49:41 INFO - 'tabpaint']}, 02:49:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:49:41 INFO - 'ts_paint', 02:49:41 INFO - 'tpaint', 02:49:41 INFO - 'sessionrestore', 02:49:41 INFO - 'sessionrestore_no_auto_restore', 02:49:41 INFO - 'tabpaint']}, 02:49:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:49:41 INFO - 'ts_paint', 02:49:41 INFO - 'tpaint', 02:49:41 INFO - 'sessionrestore', 02:49:41 INFO - 'sessionrestore_no_auto_restore', 02:49:41 INFO - 'tabpaint']}, 02:49:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:49:41 INFO - 'tests': ['a11yr', 02:49:41 INFO - 'ts_paint', 02:49:41 INFO - 'tpaint', 02:49:41 INFO - 'sessionrestore', 02:49:41 INFO - 'sessionrestore_no_auto_restore', 02:49:41 INFO - 'tabpaint']}, 02:49:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:49:41 INFO - 'tests': ['a11yr', 02:49:41 INFO - 'ts_paint', 02:49:41 INFO - 'tpaint', 02:49:41 INFO - 'sessionrestore', 02:49:41 INFO - 'sessionrestore_no_auto_restore', 02:49:41 INFO - 'tabpaint']}, 02:49:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:49:41 INFO - 'tests': ['tsvgx', 02:49:41 INFO - 'tsvgr_opacity', 02:49:41 INFO - 'tart', 02:49:41 INFO - 'tscrollx', 02:49:41 INFO - 'cart']}, 02:49:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:49:41 INFO - 'tsvgr_opacity', 02:49:41 INFO - 'tart', 02:49:41 INFO - 'tscrollx', 02:49:41 INFO - 'cart']}, 02:49:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:41 INFO - 'talos_options': ['--disable-e10s'], 02:49:41 INFO - 'tests': ['tp5o']}, 02:49:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:41 INFO - 'tests': ['tp5o']}, 02:49:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:41 INFO - 'talos_options': ['--disable-e10s', 02:49:41 INFO - '--xperf_path', 02:49:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:49:41 INFO - 'tests': ['tp5n']}, 02:49:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:49:41 INFO - 'talos_options': ['--xperf_path', 02:49:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:49:41 INFO - 'tests': ['tp5n']}}, 02:49:41 INFO - 'talos.zip': {'path': '', 02:49:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:49:41 INFO - Running post-action listener: _resource_record_post_action 02:49:41 INFO - ##### 02:49:41 INFO - ##### Running create-virtualenv step. 02:49:41 INFO - ##### 02:49:41 INFO - Running pre-action listener: _resource_record_pre_action 02:49:41 INFO - Running main action method: create_virtualenv 02:49:41 INFO - Creating virtualenv /builds/slave/test/build/venv 02:49:41 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 02:49:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:49:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:49:42 INFO - Using real prefix '/tools/python27' 02:49:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:49:42 INFO - Installing distribute.............................................................................................................................................................................................done. 02:49:45 INFO - Installing pip.................done. 02:49:45 INFO - Return code: 0 02:49:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:49:45 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')]} 02:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:45 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')]} 02:49:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:45 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 0x1019e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868a49c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101885e00>, '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 0x1016eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101880b20>, '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.Jv2LMAupjO/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.pAEb5FZmG6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 02:49:45 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 02:49:45 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 02:49:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jv2LMAupjO/Render', 02:49:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:45 INFO - 'HOME': '/Users/cltbld', 02:49:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:45 INFO - 'LOGNAME': 'cltbld', 02:49:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:45 INFO - 'MOZ_NO_REMOTE': '1', 02:49:45 INFO - 'NO_EM_RESTART': '1', 02:49:45 INFO - 'PAGER': '/bin/cat', 02:49:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:45 INFO - 'PWD': '/builds/slave/test', 02:49:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:45 INFO - 'SHELL': '/bin/bash', 02:49:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAEb5FZmG6/Listeners', 02:49:45 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 02:49:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:45 INFO - 'USER': 'cltbld', 02:49:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:45 INFO - 'XPC_FLAGS': '0x0', 02:49:45 INFO - 'XPC_SERVICE_NAME': '0', 02:49:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:45 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 02:49:45 INFO - Cleaning up... 02:49:45 INFO - Return code: 0 02:49:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:49:45 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')]} 02:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:45 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')]} 02:49:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:45 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 0x1019e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868a49c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101885e00>, '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 0x1016eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101880b20>, '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.Jv2LMAupjO/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.pAEb5FZmG6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 02:49:45 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 02:49:45 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 02:49:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jv2LMAupjO/Render', 02:49:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:45 INFO - 'HOME': '/Users/cltbld', 02:49:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:45 INFO - 'LOGNAME': 'cltbld', 02:49:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:45 INFO - 'MOZ_NO_REMOTE': '1', 02:49:45 INFO - 'NO_EM_RESTART': '1', 02:49:45 INFO - 'PAGER': '/bin/cat', 02:49:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:45 INFO - 'PWD': '/builds/slave/test', 02:49:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:45 INFO - 'SHELL': '/bin/bash', 02:49:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAEb5FZmG6/Listeners', 02:49:45 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 02:49:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:45 INFO - 'USER': 'cltbld', 02:49:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:45 INFO - 'XPC_FLAGS': '0x0', 02:49:45 INFO - 'XPC_SERVICE_NAME': '0', 02:49:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:46 INFO - Downloading/unpacking psutil>=0.7.1 02:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:49:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:49:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:49:48 INFO - Installing collected packages: psutil 02:49:48 INFO - Running setup.py install for psutil 02:49:48 INFO - building 'psutil._psutil_osx' extension 02:49:48 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 02:49:49 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 02:49:49 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 02:49:49 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 02:49:49 INFO - building 'psutil._psutil_posix' extension 02:49:49 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 02:49:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:49:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:49:49 INFO - ^ 02:49:49 INFO - 1 warning generated. 02:49:49 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 02:49:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:49:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:49:50 INFO - Successfully installed psutil 02:49:50 INFO - Cleaning up... 02:49:50 INFO - Return code: 0 02:49:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:49:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:49:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:49:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:50 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 0x1019e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868a49c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101885e00>, '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 0x1016eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101880b20>, '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.Jv2LMAupjO/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.pAEb5FZmG6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 02:49:50 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 02:49:50 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 02:49:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jv2LMAupjO/Render', 02:49:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:50 INFO - 'HOME': '/Users/cltbld', 02:49:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:50 INFO - 'LOGNAME': 'cltbld', 02:49:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:50 INFO - 'MOZ_NO_REMOTE': '1', 02:49:50 INFO - 'NO_EM_RESTART': '1', 02:49:50 INFO - 'PAGER': '/bin/cat', 02:49:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:50 INFO - 'PWD': '/builds/slave/test', 02:49:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:50 INFO - 'SHELL': '/bin/bash', 02:49:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAEb5FZmG6/Listeners', 02:49:50 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 02:49:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:50 INFO - 'USER': 'cltbld', 02:49:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:50 INFO - 'XPC_FLAGS': '0x0', 02:49:50 INFO - 'XPC_SERVICE_NAME': '0', 02:49:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:49:50 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 02:49:50 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 02:49:50 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 02:49:50 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 02:49:50 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 02:49:50 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 02:49:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:49:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:49:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:49:52 INFO - Installing collected packages: mozsystemmonitor 02:49:52 INFO - Running setup.py install for mozsystemmonitor 02:49:53 INFO - Successfully installed mozsystemmonitor 02:49:53 INFO - Cleaning up... 02:49:53 INFO - Return code: 0 02:49:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:49:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:49:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:53 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 0x1019e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868a49c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101885e00>, '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 0x1016eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101880b20>, '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.Jv2LMAupjO/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.pAEb5FZmG6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 02:49:53 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 02:49:53 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 02:49:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jv2LMAupjO/Render', 02:49:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:53 INFO - 'HOME': '/Users/cltbld', 02:49:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:53 INFO - 'LOGNAME': 'cltbld', 02:49:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:53 INFO - 'MOZ_NO_REMOTE': '1', 02:49:53 INFO - 'NO_EM_RESTART': '1', 02:49:53 INFO - 'PAGER': '/bin/cat', 02:49:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:53 INFO - 'PWD': '/builds/slave/test', 02:49:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:53 INFO - 'SHELL': '/bin/bash', 02:49:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAEb5FZmG6/Listeners', 02:49:53 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 02:49:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:53 INFO - 'USER': 'cltbld', 02:49:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:53 INFO - 'XPC_FLAGS': '0x0', 02:49:53 INFO - 'XPC_SERVICE_NAME': '0', 02:49:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:53 INFO - Downloading/unpacking blobuploader==1.2.4 02:49:53 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 02:49:53 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 02:49:53 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 02:49:53 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 02:49:53 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 02:49:53 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 02:49:55 INFO - Downloading blobuploader-1.2.4.tar.gz 02:49:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:49:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:49:55 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 02:49:55 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 02:49:55 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 02:49:55 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 02:49:55 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 02:49:55 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 02:49:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:49:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:56 INFO - Downloading docopt-0.6.1.tar.gz 02:49:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:49:57 INFO - Installing collected packages: blobuploader, requests, docopt 02:49:57 INFO - Running setup.py install for blobuploader 02:49:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:49:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:49:57 INFO - Running setup.py install for requests 02:49:57 INFO - Running setup.py install for docopt 02:49:57 INFO - Successfully installed blobuploader requests docopt 02:49:57 INFO - Cleaning up... 02:49:57 INFO - Return code: 0 02:49:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:49:57 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')]} 02:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:57 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')]} 02:49:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:49:57 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 0x1019e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868a49c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101885e00>, '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 0x1016eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101880b20>, '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.Jv2LMAupjO/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.pAEb5FZmG6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 02:49:57 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 02:49:57 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 02:49:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jv2LMAupjO/Render', 02:49:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:57 INFO - 'HOME': '/Users/cltbld', 02:49:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:57 INFO - 'LOGNAME': 'cltbld', 02:49:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:57 INFO - 'MOZ_NO_REMOTE': '1', 02:49:57 INFO - 'NO_EM_RESTART': '1', 02:49:57 INFO - 'PAGER': '/bin/cat', 02:49:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:57 INFO - 'PWD': '/builds/slave/test', 02:49:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:57 INFO - 'SHELL': '/bin/bash', 02:49:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAEb5FZmG6/Listeners', 02:49:57 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 02:49:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:57 INFO - 'USER': 'cltbld', 02:49:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:57 INFO - 'XPC_FLAGS': '0x0', 02:49:57 INFO - 'XPC_SERVICE_NAME': '0', 02:49:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:49:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-u0hj6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:49:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-isSjVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:49:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-milDXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:49:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-n9QZbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:49:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-XDb5B2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:49:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-Wnl3c2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:49:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-HclhUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:49:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-6UauEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:49:58 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-BUogDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:49:59 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-te6Uk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:49:59 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-qcFCB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:49:59 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-PNB0JA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:49:59 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-87pGub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:49:59 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-lVuGlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:59 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-fReGh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:49:59 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-kMeNlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:49:59 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-1w2IIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:49:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:49:59 INFO - Running setup.py install for manifestparser 02:50:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:50:00 INFO - Running setup.py install for mozcrash 02:50:00 INFO - Running setup.py install for mozdebug 02:50:00 INFO - Running setup.py install for mozdevice 02:50:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:50:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:50:00 INFO - Running setup.py install for mozfile 02:50:00 INFO - Running setup.py install for mozhttpd 02:50:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:50:00 INFO - Running setup.py install for mozinfo 02:50:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:50:00 INFO - Running setup.py install for mozInstall 02:50:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:50:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:50:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:50:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:50:01 INFO - Running setup.py install for mozleak 02:50:01 INFO - Running setup.py install for mozlog 02:50:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:50:01 INFO - Running setup.py install for moznetwork 02:50:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:50:01 INFO - Running setup.py install for mozprocess 02:50:01 INFO - Running setup.py install for mozprofile 02:50:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:50:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:50:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:50:01 INFO - Running setup.py install for mozrunner 02:50:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:50:02 INFO - Running setup.py install for mozscreenshot 02:50:02 INFO - Running setup.py install for moztest 02:50:02 INFO - Running setup.py install for mozversion 02:50:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:50:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:50:02 INFO - Cleaning up... 02:50:02 INFO - Return code: 0 02:50:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:50:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:02 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 0x1019e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868a49c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101885e00>, '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 0x1016eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101880b20>, '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.Jv2LMAupjO/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.pAEb5FZmG6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 02:50:02 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 02:50:02 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 02:50:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jv2LMAupjO/Render', 02:50:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:02 INFO - 'HOME': '/Users/cltbld', 02:50:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:02 INFO - 'LOGNAME': 'cltbld', 02:50:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:02 INFO - 'MOZ_NO_REMOTE': '1', 02:50:02 INFO - 'NO_EM_RESTART': '1', 02:50:02 INFO - 'PAGER': '/bin/cat', 02:50:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:02 INFO - 'PWD': '/builds/slave/test', 02:50:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:02 INFO - 'SHELL': '/bin/bash', 02:50:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAEb5FZmG6/Listeners', 02:50:02 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 02:50:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:02 INFO - 'USER': 'cltbld', 02:50:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:02 INFO - 'XPC_FLAGS': '0x0', 02:50:02 INFO - 'XPC_SERVICE_NAME': '0', 02:50:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:50:02 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-ey4Zj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:50:02 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)) 02:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:50:02 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-M6YUFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:50:03 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)) 02:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:50:03 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-7dZm98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:50:03 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)) 02:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:50:03 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-q26Cqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:50:03 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)) 02:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:50:03 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-43DLSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:50:03 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)) 02:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:50:03 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-evmaJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:50:03 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)) 02:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:50:03 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-2ASMte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:50:03 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)) 02:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:50:03 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-CmwfrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:50:03 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)) 02:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:50:03 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-DW1B0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:50:03 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)) 02:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:50:03 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-vJQP1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:50:03 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)) 02:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:50:03 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-jkEfNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:50:03 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)) 02:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:50:03 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-fOK1MR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:50:04 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)) 02:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:50:04 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-UQVkvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:50:04 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)) 02:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:50:04 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-KFT7Xr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:50:04 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)) 02:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:50:04 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-vWmA0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:50:04 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)) 02:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:50:04 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-4q4XoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:50:04 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)) 02:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:50:04 INFO - Running setup.py (path:/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/pip-K1jEZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:50:04 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)) 02:50:04 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)) 02:50:04 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)) 02:50:04 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)) 02:50:04 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)) 02:50:04 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)) 02:50:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:50:04 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 02:50:04 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 02:50:04 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 02:50:04 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 02:50:04 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 02:50:04 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 02:50:07 INFO - Downloading blessings-1.6.tar.gz 02:50:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:50:07 INFO - Installing collected packages: blessings 02:50:07 INFO - Running setup.py install for blessings 02:50:07 INFO - Successfully installed blessings 02:50:07 INFO - Cleaning up... 02:50:07 INFO - Return code: 0 02:50:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:50:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:50:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:50:07 INFO - Reading from file tmpfile_stdout 02:50:07 INFO - Current package versions: 02:50:07 INFO - blessings == 1.6 02:50:07 INFO - blobuploader == 1.2.4 02:50:07 INFO - docopt == 0.6.1 02:50:07 INFO - manifestparser == 1.1 02:50:07 INFO - mozInstall == 1.12 02:50:07 INFO - mozcrash == 0.17 02:50:07 INFO - mozdebug == 0.1 02:50:07 INFO - mozdevice == 0.48 02:50:07 INFO - mozfile == 1.2 02:50:07 INFO - mozhttpd == 0.7 02:50:07 INFO - mozinfo == 0.9 02:50:07 INFO - mozleak == 0.1 02:50:07 INFO - mozlog == 3.1 02:50:07 INFO - moznetwork == 0.27 02:50:07 INFO - mozprocess == 0.22 02:50:07 INFO - mozprofile == 0.28 02:50:07 INFO - mozrunner == 6.11 02:50:07 INFO - mozscreenshot == 0.1 02:50:07 INFO - mozsystemmonitor == 0.0 02:50:07 INFO - moztest == 0.7 02:50:07 INFO - mozversion == 1.4 02:50:07 INFO - psutil == 3.1.1 02:50:07 INFO - requests == 1.2.3 02:50:07 INFO - wsgiref == 0.1.2 02:50:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:50: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')]} 02:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50: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')]} 02:50:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:07 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 0x1019e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868a49c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101885e00>, '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 0x1016eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101880b20>, '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.Jv2LMAupjO/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.pAEb5FZmG6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 02:50:07 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 02:50:07 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 02:50:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jv2LMAupjO/Render', 02:50:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:07 INFO - 'HOME': '/Users/cltbld', 02:50:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:07 INFO - 'LOGNAME': 'cltbld', 02:50:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:07 INFO - 'MOZ_NO_REMOTE': '1', 02:50:07 INFO - 'NO_EM_RESTART': '1', 02:50:07 INFO - 'PAGER': '/bin/cat', 02:50:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:07 INFO - 'PWD': '/builds/slave/test', 02:50:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:07 INFO - 'SHELL': '/bin/bash', 02:50:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAEb5FZmG6/Listeners', 02:50:07 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 02:50:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:07 INFO - 'USER': 'cltbld', 02:50:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:07 INFO - 'XPC_FLAGS': '0x0', 02:50:07 INFO - 'XPC_SERVICE_NAME': '0', 02:50:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:07 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)) 02:50:07 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)) 02:50:07 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)) 02:50:07 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)) 02:50:07 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)) 02:50:07 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)) 02:50:07 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)) 02:50:07 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)) 02:50:07 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)) 02:50:07 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)) 02:50:07 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)) 02:50:07 INFO - Cleaning up... 02:50:07 INFO - Return code: 0 02:50:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:50: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')]} 02:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50: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')]} 02:50:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50:07 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 0x1019e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868a49c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101885e00>, '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 0x1016eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101880b20>, '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.Jv2LMAupjO/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.pAEb5FZmG6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 02:50:07 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 02:50:07 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 02:50:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jv2LMAupjO/Render', 02:50:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:07 INFO - 'HOME': '/Users/cltbld', 02:50:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:07 INFO - 'LOGNAME': 'cltbld', 02:50:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:07 INFO - 'MOZ_NO_REMOTE': '1', 02:50:07 INFO - 'NO_EM_RESTART': '1', 02:50:07 INFO - 'PAGER': '/bin/cat', 02:50:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:07 INFO - 'PWD': '/builds/slave/test', 02:50:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:07 INFO - 'SHELL': '/bin/bash', 02:50:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAEb5FZmG6/Listeners', 02:50:07 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 02:50:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:07 INFO - 'USER': 'cltbld', 02:50:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:07 INFO - 'XPC_FLAGS': '0x0', 02:50:07 INFO - 'XPC_SERVICE_NAME': '0', 02:50:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:08 INFO - Downloading/unpacking jsonschema 02:50: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 02:50: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 02:50: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 02:50: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 02:50: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 02:50: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 02:50:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:50:10 INFO - Downloading/unpacking functools32 (from jsonschema) 02:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:50:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:10 INFO - Downloading functools32-3.2.3-2.tar.gz 02:50:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:50:11 INFO - warning: no files found matching '*.txt' 02:50:11 INFO - no previously-included directories found matching 'build' 02:50:11 INFO - no previously-included directories found matching 'dist' 02:50:11 INFO - no previously-included directories found matching '.git*' 02:50:11 INFO - Installing collected packages: jsonschema, functools32 02:50:11 INFO - Running setup.py install for functools32 02:50:11 INFO - warning: no files found matching '*.txt' 02:50:11 INFO - no previously-included directories found matching 'build' 02:50:11 INFO - no previously-included directories found matching 'dist' 02:50:11 INFO - no previously-included directories found matching '.git*' 02:50:11 INFO - Successfully installed jsonschema functools32 02:50:11 INFO - Cleaning up... 02:50:11 INFO - Return code: 0 02:50:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:50: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')]} 02:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50: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')]} 02:50:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:50: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1019e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019f6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f868a49c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101885e00>, '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 0x1016eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101880b20>, '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.Jv2LMAupjO/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.pAEb5FZmG6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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 02:50: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', 'flake8'] in /builds/slave/test/build 02:50: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 flake8 02:50:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jv2LMAupjO/Render', 02:50:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:11 INFO - 'HOME': '/Users/cltbld', 02:50:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:11 INFO - 'LOGNAME': 'cltbld', 02:50:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:11 INFO - 'MOZ_NO_REMOTE': '1', 02:50:11 INFO - 'NO_EM_RESTART': '1', 02:50:11 INFO - 'PAGER': '/bin/cat', 02:50:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:11 INFO - 'PWD': '/builds/slave/test', 02:50:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:11 INFO - 'SHELL': '/bin/bash', 02:50:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAEb5FZmG6/Listeners', 02:50:11 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 02:50:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:11 INFO - 'USER': 'cltbld', 02:50:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:11 INFO - 'XPC_FLAGS': '0x0', 02:50:11 INFO - 'XPC_SERVICE_NAME': '0', 02:50:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:11 INFO - Downloading/unpacking flake8 02:50: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 02:50: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 02:50: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 02:50: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 02:50: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 02:50: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 02:50:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:50:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:50: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 02:50: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 02:50: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 02:50: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 02:50: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 02:50: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 02:50:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:50:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:50: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 02:50: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 02:50: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 02:50: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 02:50: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 02:50: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 02:50:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:50: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 02:50: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 02:50: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 02:50: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 02:50: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 02:50: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 02:50:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:50:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:50:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:50:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:50:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:50:15 INFO - no previously-included directories found matching 'docs/_build' 02:50:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:50:15 INFO - Running setup.py install for pep8 02:50:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:50:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:50:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:50:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:50:15 INFO - no previously-included directories found matching 'docs/_build' 02:50:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:50:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:50:15 INFO - Cleaning up... 02:50:15 INFO - Return code: 0 02:50:15 INFO - Running post-action listener: _resource_record_post_action 02:50:15 INFO - Running post-action listener: _start_resource_monitoring 02:50:15 INFO - Starting resource monitoring. 02:50:15 INFO - ##### 02:50:15 INFO - ##### Running install step. 02:50:15 INFO - ##### 02:50:15 INFO - Running pre-action listener: _resource_record_pre_action 02:50:15 INFO - Running main action method: install 02:50:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:50:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:50:15 INFO - Reading from file tmpfile_stdout 02:50:15 INFO - Detecting whether we're running mozinstall >=1.0... 02:50:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:50:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:50:16 INFO - Reading from file tmpfile_stdout 02:50:16 INFO - Output received: 02:50:16 INFO - Usage: mozinstall [options] installer 02:50:16 INFO - Options: 02:50:16 INFO - -h, --help show this help message and exit 02:50:16 INFO - -d DEST, --destination=DEST 02:50:16 INFO - Directory to install application into. [default: 02:50:16 INFO - "/builds/slave/test"] 02:50:16 INFO - --app=APP Application being installed. [default: firefox] 02:50:16 INFO - mkdir: /builds/slave/test/build/application 02:50:16 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'] 02:50:16 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 02:50:47 INFO - Reading from file tmpfile_stdout 02:50:47 INFO - Output received: 02:50:47 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:50:47 INFO - Running post-action listener: _resource_record_post_action 02:50:47 INFO - ##### 02:50:47 INFO - ##### Running run-tests step. 02:50:47 INFO - ##### 02:50:47 INFO - Running pre-action listener: _resource_record_pre_action 02:50:47 INFO - Running main action method: run_tests 02:50:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:50:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:50:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:50:47 INFO - 2016-06-03 02:50:47.529 screenresolution[1649:8241] starting screenresolution argv=screenresolution get 02:50:47 INFO - 2016-06-03 02:50:47.532 screenresolution[1649:8241] Display 0: 1600x1200x32@60 02:50:47 INFO - 2016-06-03 02:50:47.538 screenresolution[1650:8244] starting screenresolution argv=screenresolution list 02:50:47 INFO - Available Modes on Display 0 02:50:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:50:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:50:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:50:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:50:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:50:47 INFO - Intel Iris: 02:50:47 INFO - Chipset Model: Intel Iris 02:50:47 INFO - Type: GPU 02:50:47 INFO - Bus: Built-In 02:50:47 INFO - VRAM (Dynamic, Max): 1536 MB 02:50:47 INFO - Vendor: Intel (0x8086) 02:50:47 INFO - Device ID: 0x0a2e 02:50:47 INFO - Revision ID: 0x0009 02:50:47 INFO - Displays: 02:50:47 INFO - Display: 02:50:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:50:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:50:47 INFO - Display Serial Number: 1600x1200 60 02:50:47 INFO - Main Display: Yes 02:50:47 INFO - Mirror: Off 02:50:47 INFO - Online: Yes 02:50:47 INFO - Rotation: Supported 02:50:47 INFO - Return code: 0 02:50:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:50:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:50:47 INFO - Python 2.7.3 02:50:47 INFO - Return code: 0 02:50:47 INFO - grabbing minidump binary from tooltool 02:50: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')]} 02:50:47 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 0x101885e00>, '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 0x1016eedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101880b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:50:47 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 02:50:47 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 02:50:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:50:47 INFO - Return code: 0 02:50:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:50:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:50:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:50:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:50:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:50:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:50:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:50:48 INFO - Return code: 0 02:50:48 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:50:48 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0069 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:50:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jv2LMAupjO/Render', 02:50:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:48 INFO - 'HOME': '/Users/cltbld', 02:50:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:48 INFO - 'LOGNAME': 'cltbld', 02:50:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:50:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:50:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:48 INFO - 'MOZ_NO_REMOTE': '1', 02:50:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:50:48 INFO - 'NO_EM_RESTART': '1', 02:50:48 INFO - 'PAGER': '/bin/cat', 02:50:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:48 INFO - 'PWD': '/builds/slave/test', 02:50:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:50:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:48 INFO - 'SHELL': '/bin/bash', 02:50:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pAEb5FZmG6/Listeners', 02:50:48 INFO - 'TMPDIR': '/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/', 02:50:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:48 INFO - 'USER': 'cltbld', 02:50:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:48 INFO - 'XPC_FLAGS': '0x0', 02:50:48 INFO - 'XPC_SERVICE_NAME': '0', 02:50:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:50:49 INFO - mozversion application_buildid: 20160603004101 02:50:49 INFO - mozversion application_changeset: effbb9ad4e93c4383e484f3a515d23194d3c6329 02:50:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:50:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:50:49 INFO - mozversion application_name: Firefox 02:50:49 INFO - mozversion application_remotingname: firefox-dev 02:50:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:50:49 INFO - mozversion application_vendor: Mozilla 02:50:49 INFO - mozversion application_version: 48.0a2 02:50:49 INFO - mozversion platform_buildid: 20160603004101 02:50:49 INFO - mozversion platform_changeset: effbb9ad4e93c4383e484f3a515d23194d3c6329 02:50:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:50:49 INFO - mozversion platform_version: 48.0a2 02:50:49 INFO - using testdate: 1464947449 02:50:49 INFO - actual date: 1464947449 02:50:49 INFO - starting webserver on 'localhost:49275' 02:50:49 INFO - SUITE-START | Running 2 tests 02:50:49 INFO - TEST-START | dromaeo_css 02:50:49 INFO - operating with platform_type : mac_ 02:50:49 INFO - Initialising browser for dromaeo_css test... 02:50:49 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmpPsy5nx/profile http://localhost:49275/getInfo.html) 02:50:51 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 02:50:51 INFO - PROCESS | 1668 | colorDepth:24 02:50:51 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 02:50:51 INFO - PROCESS | 1668 | __metrics 02:50:51 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:50:51 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:50:52 INFO - TEST-INFO | 1668: exit 0 02:50:52 INFO - Browser initialized. 02:50:52 INFO - Running cycle 1/1 for dromaeo_css test... 02:50:52 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmpPsy5nx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:51:04 INFO - PROCESS | 1672 | RSS: Main: 211111936 02:51:04 INFO - PROCESS | 1672 | 02:51:55 INFO - PROCESS | 1672 | 1464947515858 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] 02:54:06 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 02:54:07 INFO - PROCESS | 1672 | RSS: Main: 389468160 02:54:07 INFO - PROCESS | 1672 | 02:57:11 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 02:57:11 INFO - PROCESS | 1672 | RSS: Main: 359759872 02:57:11 INFO - PROCESS | 1672 | 03:00:13 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 03:00:14 INFO - PROCESS | 1672 | RSS: Main: 362164224 03:00:14 INFO - PROCESS | 1672 | 03:03:16 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 03:03:17 INFO - PROCESS | 1672 | RSS: Main: 357683200 03:03:17 INFO - PROCESS | 1672 | 03:06:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 03:06:20 INFO - PROCESS | 1672 | RSS: Main: 325332992 03:06:20 INFO - PROCESS | 1672 | 03:09:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 03:09:23 INFO - PROCESS | 1672 | RSS: Main: 370499584 03:09:23 INFO - PROCESS | 1672 | 03:09:23 INFO - PROCESS | 1672 | __start_tp_report 03:09:23 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 03:09:23 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 03:09:23 INFO - PROCESS | 1672 | |i|pagename|runs| 03:09:23 INFO - PROCESS | 1672 | |0;dojo.html;3352.8994130176093;3387.4072037393466;3394.9490757638623;3410.607780105287;3462.948055779164;5591.049521581331;5599.300087489064;5605.103183490643;5608.831735047945;5609.663420194791;5600.355959064709;5621.269234999455;5633.661980281186;5663.7168141592965;5680.687562184088;5118.539331460168;5161.793528258874;5175.560077393426;5184.844454666366;5248.895022099556;5256.185291279858;5256.684598924076;5263.947360526384;5266.947330526684;5278.815241466549;5143.228515722634;5194.71429071401;5201;5222.242774797671;5259.263703081571;5225.581953443734;5252.343457067866;5260.395054568697;5264.7367631618645;5315.893682126336;5069.214271787879;5104.872378190538;5156.690598564098;5168.457311982263;5195.870103247411;5523.337199536041;5524.917126243109;5555.388907220203;5561.304836895388;5577.9163312550345;6185.505159587222;6196.535259855484;6202.658853763041;6203.131561581382;6204.193454850841;6088.269407671063;6185.721642526097;6220.315765265817;6268.310485846553;6281.811545653638;5744.965906136936;5777.566682498829;5778;5779.682117483537;5781.768729250825;6231.688415579293;6231.781887633911;6234.251889773256;6237.937620623736;6240.843978900473;5917.230760001173;6020.819375418744;6055.666938318434;6121.087175196994;6129;6222.62664240138;6225.533085018642;6236.220472440945;6236.251649802053;6239.188905442355;6118.816435506942;6172.598781079216;6183.505319574423;6185.597936134137;6188.257409110935;6053.243344581927;6122.489639783254;6125.632272693312;6126.029144987373;6191.040388739752;4811.566958973639;4850.95149048505;4854.708717476963;4855.465898751135;4860.68405553645;4350.086481838849;4357.3899654048455;4373.562643735665;4433.800478978454;4454.710443821141;5581.079121944915;5607.691576963224;5611;5619.522340601013;5654.434556544395;6083.634981901012;6196.81409557714;6204.069389591597;6219.222597175353;6228.065790131516;5591.10542313228;5631.8592035199445;5639.971800140973;5674.659520428788;5681.488666020078;5316.46835316473;5327.38735045467;5328.987492376436;5333.973330133324;5347.197920311906;6141.63943600049;6200.0699895016105;6200.410960958701;6258.405451482102;6273.466755325757;6110.77140286259;6138.204480310071;6150.769846030722;6154.630722156685;6163.907541386793;6273.153124328157;6286.717097730704;6289.685515724287;6293.905591416041;6299.779507717208;6150.354212807591;6153.230846144232;6157.384261573807;6163.722632481459;6171.876562468636;6067.878642427215;6149.985252433477;6153.969230153821;6164.722587483664;6227.532935029982;5187.806804434926;5958.255218097738;5989.7304621293015;6002.159697642246;6029.668368239789;6011.639301641915;6031.60794548353;6071.605345652519;6114.633122012693;6121.938780612137;4948.678335908155;5906.675132867734;5906.911396329145;5920.141579471038;5950.315713692808;6068.180795592715;6071.666058366833;6074.544409169418;6086.421789929949;6122.051082082285;5782;5858.2677165354335;5940.019896717166;5945.494632956247;5976.223090998143 03:09:23 INFO - PROCESS | 1672 | |1;ext.html;507.6725512044731;536.1394961087457;547.4252035362867;551.4072372199886;566.7223060700256;30946.38107237854;38017;39289.41065884007;39367;39610.207795844064;41186.58813411865;41776.95557611054;41835.58164418363;41850;41963;8406.621702023393;9468.289878259124;9657.082577155159;9690;9690.56392462341;8554.743357699279;11591.072714182887;11704.239224450383;11720.413979301044;11720.941395293012;11499.885001150007;11587.768244635103;11588.42057897106;11609.419529023557;11693.649190524298;8549.40154189207;8708.651653933835;8754.518501482416;11364.977152056314;11520.423978801027;9790.55942482583;11694.649160525198;11710.238834475731;11713.94143029288;11755.118366122575;32192.678073219202;38725;38740.41889371662;38793;39477.42090316384;3199.2481766784786;3278.246003419203;3343.2979074394407;3450.344434557451;3516.384632689269;27427;27646.032388866293;28351.57472637912;28832.567511487345;28892.566611500843;2700.3114205877573;2741.4928238275984;2744.2727677165567;2863.4702580022554;2882.3802882380387;509561;510057;510335;510866;511099;36320.273594527964;36889.44665830015;36932.44601330982;62006.0699089514;63313.10060698186;35878;35903.820480897426;35904;35942.64057359445;36001;15265.855060870479;15401.306941187673;15440.922795385952;15449.84550154484;15449.922750386177;11817;11926.940365298291;12906.09657323997;12906.612801615778;13172.60482185536;8178.977627796526;8182.836343273101;8207.671693132328;8237.382196335298;8305.086440491541;3408.352413041514;3447.293304872495;3447.413939630269;3462.0825481246984;3504.123969007748;947.3557980573139;953.50894289441;1003.7691330993912;1044.3055368180076;1057.4554104636118;27328.726712733016;30007.49887516846;30150.09549713512;30736.077917662507;30774.384512310076;1152.948117334722;1177.1642134084896;1183.1126655008743;1214.6841821126393;1222.957196498136;15299.082055076731;15340.463083792014;15445.880532960771;15546.300416481285;15599.454019109276;664617;665838;668628.9411317668;671551;671747;60670.78658426807;62181.68909155425;62196.37803621996;62418;62597;1354.7696891528465;1385.625881012121;1525.6262215756988;1542.4215919030364;1592.585927658794;28688.713112868605;28834.990775322764;28996.855015724785;29711.40577188401;29860.402791944474;47081.82295442641;47163.056738865715;47499.76250118727;47528;47550;331576;346855;364074;369204;370122;23376.883115584315;26946.461070778867;27206.455870882866;27464;27530.44939101247;42964.78517607392;45021;45688.771556143336;46045.30932035955;46169;37337.066573335884;37445.81277093706;37768.244635107694;38187.61812381841;38312;36698;37114.25771484609;37556;37796.43305350367;37990;36903.81548092242;37180.07049823776;38068.80965595154;38105.23789524249;38170.23659526849;27713.86143069272;29197.708022919498;32025.67974320227;32593.511097333085;33467.49798752972;24676.876615617526;24736.876315618305;24828.875855620605;25024.62463063019;25174.370640734127 03:09:23 INFO - PROCESS | 1672 | |2;jquery.html;342.3375767889134;364.7136997456996;412.25793571571165;415.2774172939085;419.6768488264035;19296.614067718638;20707;20732.792672073294;20742.48143796406;20762.480937976557;20135.39593812188;20488.487787805312;20936.37190884272;21295.361139165812;21306.89346553269;5372.9462705372935;5493.533049690781;5541.806036788713;5582.804601838937;5585.664860108386;5364.597655175858;5449.809256676017;5479.931413374394;5505.476979686923;5532.5573954083775;5495.917561236564;5496.615236933416;5497.670139791605;5509.972450137744;5513.283273174482;5313.123334649778;5406.135018397057;5485.396606373296;5495.53287970523;5518.806841760559;5313.973430132824;5365.919511207335;5366.785328586851;5370.624056316059;5371.248025276464;13196.736065278641;13909.234992075431;13910.374033168431;13933.442662293495;13937.954653400933;2077.95844083119;2106.3891471473253;2167.219800871685;2340.9180678676335;2739.1919383781833;9175.816483670356;9825.263105267133;10096.646617368431;12742.36288185587;14602.561923142322;1712.143928035982;1733.0696053499141;1737.9478615641551;1801.846843018345;1824.917878695462;114333;114896;117047.82952170454;119242.40378798137;120245.39877300558;11523.21390184506;11775.470103845177;11788.88211117895;11799.646010619696;11926.105542084379;15247.094113235846;15281;15299.15854627984;15332.693346133237;15333.156676382683;8482.745517634481;8751.693690720795;8788.736337909873;8844.955775221082;9039.819203616022;8885.555722213863;8887.688930887518;8929.598168082452;8975.685850995314;9035.689824975341;4726.196546587088;4726.716397016121;4745.596624286905;4779.522047795263;4803.711777293373;1675.078706711304;1677.2975429938622;1949.6685563453964;1998.5003749062735;2002.0890494824819;899.4738078224182;910.8724778531011;955.4553904274496;1012.772126271583;1015.8527013582989;20189.192432302545;20773.169073237204;20939.267125650833;21016.474588135417;21251.787482124982;952.3952290295712;977.8386566216636;1023.5087158163974;1033.850091736694;1048.4181279390011;10703.250772445854;11160.274582152135;11177.776444471065;11188.440577971232;11201.439928003567;237331;238593;516488.4175579098;592690;628805;130232.3488382552;136679;136688;140332.29833850765;141693;1008.1732978957331;1011.3577878047493;1027.6043723166636;1027.9640212592524;1031.8297480915564;17965;18155.636887262444;18278;18516.351927682466;18776.81223187805;50482.2427663578;53981.730091349295;54984.45015549954;55531;57773.71113144408;78922;79681;81201;83281;85776.14223857931;6830.385265326145;7822.804429889298;8077;8187.181281871765;8245.257926786619;7166.677499512639;10727.034566889017;10967.177461690564;11195.664130075786;11974.820377694516;10479;10515.684529464128;10859.022687958122;11024.559017639205;11071.225014248925;10364.429956352473;10669;11057.336559806115;11121.443927803415;11351.943240284076;10328.896711032794;11011.559537618727;11237.943810280896;11284;11611.825822612836;8161.143079976517;8249.587520624065;8295.626696798538;8367.456115352483;8551.828963420821;10516.579336826748;10816.08063314627;11024.61311278838;11119.777604448027;11135.832962505407 03:09:23 INFO - PROCESS | 1672 | |3;mootools.html;1445.3784872504818;1580.1862041048857;1599.2403608286063;1821.6812057889865;1942.2036964844417;7951.92048079519;8580.871286930693;8752.168543988328;8753.518556479406;8765.16730910564;8478.491290522574;8689.478631282127;8695.000074991378;8700.129987001297;8715.172058654434;2149.451889768107;2171.7502487213937;2288.942776430586;2409.2531315292226;2439.1462987954164;2461.4584791345874;2471.233917485576;2474.2206205045445;2474.7896428803483;2480.888360023794;2381.785639292472;2413.8793060347;2436.683231179953;2438.5976313908186;2441.170002199252;2128.659414493689;2197.263916587945;2200.471886747185;2208.9116435342567;2427.2596857958315;2097.213544920655;2396.928092157238;2460.335709358463;2465.901363945458;2466.5313590417763;7861.056673199196;8233.629486673113;8629.95685021577;8701.30389568833;8709.695160669346;4501.212287849613;5441.938821929725;5461.399246082928;5469.507744302992;5473.370562385297;2883.3224192314788;3386.407378708716;3434.1414646338417;3498.5276987606603;3519.419295816213;977.7066879935991;994.5375400438759;1016.654337525245;1019.9133073688745;1024.1653052761976;12179.391030448442;12211.69470763238;12215.511379544807;12227.510899564008;12276;4808.519148085234;4842.83050093245;4885.5358740919555;4902.803887844446;4974.602031837444;10231.028052335016;10231.386116833013;10244.334118282288;10254.28220024606;10278.126359259397;5523.25436066134;5556.083246264321;5562.332520097533;5563.443655634404;5573.247611572386;4425.3140763181755;4578.221702310549;4894.3147959285725;4953.380827396575;4979.601631869442;4092.7135100543273;4095.9180816383505;4121.4848143982;4151.481064866892;4158.708890377705;1318;1342.3959218351781;1353.0123010202608;1354.525915929417;1361.5574938145141;906.664534122379;918.7427520294328;934.7055677461578;943.0051295882772;975.2490582251627;5899.616524925952;5973.372795856372;6019.976603977334;6120.969395152996;6169.96915015422;903.8599017152352;991.6876184002016;1067.5409573883148;1094.4910616563336;1104.817705078653;3753.868614598476;3869.304813902716;3932.8426862925735;3974.145558704892;3999.8400063997115;8239.670413183405;10444;10445.84331235032;10558.366498010144;10995.945020274847;10243.897561024443;10433.113185379176;10442.373457592568;10458.215633827493;10514.158867290598;8111.75664730059;8232.62953167109;8363;8375.8324833503;8449.493030418073;5692.430756924275;5787.710614469175;5974.103884417372;5978.581499295007;5993.850153746192;9451.913030001639;9809.362391444532;9819.459929703935;9952.353097048668;9971.45157016371;9261.351705380559;9949.154321882721;10019.048190421898;10113;10119;6925.445964322741;7089.113860767404;7089.149302083783;7540.434467415075;7658.885116723142;7759.068911730628;7809.609519523888;8108.027036755627;8307.584620768817;8365.539895305574;6400.615963042233;7365.410767138723;7372.926270737438;7535.246475352421;7543.811404714926;7195.748148814766;7257.927420725725;7305.707771689073;7309.159446663702;7315.926840731525;6963.1296087989;7035.1909530404655;7047.01341812157;7056.400205982549;7064.646767661699;4757.072370887644;4768.856934291976;4788.233882578771;4906.582940449959;4919.778609962632;4357.237483440449;4874.341963834837;4908.509149085063;4925.901481970269;5007.198848184273 03:09:23 INFO - PROCESS | 1672 | |4;prototype.html;322.2668429323289;327.64614216646027;333.7380156577087;340.4246822869352;343.82464942879125;6515.7719479818215;6532.379423954717;6537.378948999849;6555.803325900225;6556.2788093309755;6403.135576697145;6404.071409645599;6515.869682606345;6531.2815590285145;6543.770968016121;4642.025174713314;4767.880802979936;4772.976135119319;4773.331733557305;4790.7604619769045;4788.856334309976;4800.279958006292;4886.193778026639;4922.145155809621;4948.134076536609;4792.849716068136;4822.204336284509;4842.249451335031;4845.733484658343;4856.17445034345;3981.7013723970817;4628.190066738307;4870.829520966749;4879.341288925985;4886.340344053543;4045.9595404045876;4126.690498212646;4146.66826653867;4147.81334839933;4156.896077598054;5082.644214904958;6215.782447614357;6242.875142497124;6303.5272354573135;6356;5673.290838645169;5682.943170568283;5694.715264236772;5700.7719691212305;5718.7426565804635;6936.826579335507;6960.234374218882;6960.30396960305;6963.617001064939;6967.268436814163;3458.429359155736;4236.449261596003;4367.62875155609;4662.790174442158;4672.976635116803;7233.674484648203;7295.270472952715;7296.8175795605;7329.743458978964;7355.77932662021;4630.189716799547;4631.722096674211;4635.7218566886095;4636.327732478839;4638.79125439356;4687.812487500461;4704.411948506437;4711.929321060187;4716.882077948078;4719.244920812654;5598.412166722517;5602.579806514527;5602.65536271292;5613.887722245532;5623.409541998113;6761.526693131532;6996.405305549085;7011.54424962386;7011.684474198673;7018.543794653331;1609.4527860527478;1623.9269232884549;1631.804183497964;1633.3874796951143;1634.5831812887639;1026.5175367577226;1028.665683652816;1042.3433237060603;1046.26238501856;1049.480507148951;6396.71214795326;6553.082568440422;6556.737730490822;6586.73653053882;6698.665066746643;7356.926430735732;7361.079865016873;7362.374198193106;7363.300486453778;7366.558006519519;6675.396595327826;6679.165104361955;6686.5653732507235;6689.632070236086;6690.19717633887;3539.9734077117346;3577.4097273949506;3828.0812604974603;3883.49514563105;3890.96109038906;7320.414366850639;7619.580923049286;7620.466567340233;7622;7635.541867487857;7459.142198647225;7484.47608667399;7486.064241969754;7498.475106742476;7503.399728021745;7597.924020759832;7617.162112167657;7621.3902887769955;7621.3902887769955;7640.503367281221;7275.672594733355;7302.2697730226555;7304.5252058616015;7311.4150867929375;7320.450966177452;6917.446604271546;7147.678354474165;7166.53417527859;7192.748253811092;7264.927350726425;6536.444402225864;7422.25777422275;7560.470767046254;7665.156832748282;7669.156392796899;4816.518348165156;4866.489018653132;4868.245421959602;4869.099216644932;4878.853634390974;7246.818829529304;7257.52826066304;7264.89102663471;7273.199948005609;7276;6687.799366019027;6987.056747339044;6997.055397521473;7009.228984811769;7021.403180729695;5685.374608793112;6960.269171736894;7004.719811207495;7009.334113259232;7009.894851577333;6837.2820853809635;6843.076184715;6843.418309443753;6849.931500685128;6866.965665171842;6775.728970841111;7019.754308599175;7025.543339682904;7028.92646669244;7038.120234970629;6655.334466553306;6778.186617605722;6853.828654283683;6863.588184708734;6892.276310987475 03:09:23 INFO - PROCESS | 1672 | |5;yui.html;722.2055738687443;725.6879541797025;749.5540153608608;756.1304499825201;807.2492581898836;14924.477643282473;15155.318010689518;15329.693406131884;15355.769663455058;15355.769663455058;12083.818742718866;13899.444022239124;14352.21062841546;14363.138211707304;14387.280635968213;709;756.3117563017655;769.9653515591784;890.9064548222441;923.9861402078974;913.7350168451142;916.9587368568397;918.7289749523873;920.8756817829608;930.1953809954408;969.0793745941384;971.6404930175845;971.7862070344513;972.2027937091588;991.9752006199831;787.4802630264027;792.9008873890764;795.7970717467009;796.38678217772;981.9901800982005;721.6752461392394;723.1972510513326;723.7141329174999;724.1635910523354;727.1347096954607;12351.444185011605;14822;14836.629084272872;14880.255987200704;14908.925455372762;11986.52053917851;12153.696157596043;12179.33013684247;12249.326287054208;12260.81608775869;9925.2059835213;10042.598296068149;10043.24675649322;10240.539175737034;10439.582416703322;3459.41189997701;3472.878449254289;3537.7700449470703;3545.9290814183573;3626.0934766308424;63086.36913630851;81773.77339339914;82302.17697823005;85160.14839851584;85161.57419212926;9439.056094390644;9441;9633.807323853624;9758.512074396253;9914.702558923243;21176.047077881532;21912.780872191073;21940.89029554842;21972;21987.670184947237;7222.96388518054;7308.780736577911;7421.8144546386775;7439.665215065227;7460.664270107858;4082.4488694026522;4129.876103716893;4183.205191013698;4286.571342865688;4333.414988976511;3362.4956256561713;3416.5046068319953;3417.6924076832706;3424;3425.1950791563963;601.59392410123;626.1096631569548;629.6725702634604;629.7606909374408;634.5621521150391;556.4852511426914;584.2200662116122;593.5073888672391;606.169547719627;623.0280762011276;7887.0904933550755;8026.438149329608;8070.798230044295;8214.671413143407;8274.048484424247;547.9260299859548;575.1258087706746;599.0505049496596;612.2652816620074;612.4763327355137;3728.683061939711;3780.0360907968843;3801.2207497463505;3816.38937769961;3840.1743625120735;88631;98828.50585747026;100209.99790002007;100300.49549256767;100486.49756751169;70244;70941.64529177321;71037.2896271041;71753.6412317935;71888.64055679792;62986.37013629805;63131;64545.67727161334;65126.67436662786;65750.67124664345;7330.413566914741;7342.082239720035;7390.778276651709;7478.4765066444825;7758.262965018314;45740;46867.062658747316;47545.762271188425;47607.76196118997;47731.76134119307;64320.71358572896;67734;68515.65742171257;69447.65276173587;70466.59066818737;10840.186985975926;10895.618653347095;11075.280106793032;11432.371219583003;11486.368249746343;11758.03515473467;12062.819057714318;12073.456694448596;12117.151799374311;12134.75730485403;11274.154438417316;11564.074874009892;11566.537338506365;11568.13239007061;11640.88359116398;11374.260673056226;11610;11614.012808911337;11688.649340519798;12222.266664000188;10956.506957186786;11190.888091118984;11542.480588373712;11556.94221528887;11584.594539191088;9469.763255918657;9627.663031793854;9708.126268635857;9739.026097390204;9935.105840474393;9310.48792316402;9556.474393908124;9590.952045239728;9700.563474643517;9853.901460985298 03:09:23 INFO - PROCESS | 1672 | __end_tp_report 03:09:23 INFO - PROCESS | 1672 | __start_cc_report 03:09:23 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1591 03:09:23 INFO - PROCESS | 1672 | __end_cc_report 03:09:23 INFO - PROCESS | 1672 | __startTimestamp1464948563946__endTimestamp 03:09:23 INFO - PROCESS | 1672 | 03:09:23 INFO - PROCESS | 1672 | ------- Summary: start ------- 03:09:23 INFO - PROCESS | 1672 | Number of tests: 6 03:09:23 INFO - PROCESS | 1672 | 03:09:23 INFO - PROCESS | 1672 | [#0] dojo.html Cycles:165 Average:5691.59 Median:5920.14 stddev:625.09 (10.6%) stddev-sans-first:599.47 03:09:23 INFO - PROCESS | 1672 | Values: 3352.9 3387.4 3394.9 3410.6 3462.9 5591.0 5599.3 5605.1 5608.8 5609.7 5600.4 5621.3 5633.7 5663.7 5680.7 5118.5 5161.8 5175.6 5184.8 5248.9 5256.2 5256.7 5263.9 5266.9 5278.8 5143.2 5194.7 5201.0 5222.2 5259.3 5225.6 5252.3 5260.4 5264.7 5315.9 5069.2 5104.9 5156.7 5168.5 5195.9 5523.3 5524.9 5555.4 5561.3 5577.9 6185.5 6196.5 6202.7 6203.1 6204.2 6088.3 6185.7 6220.3 6268.3 6281.8 5745.0 5777.6 5778.0 5779.7 5781.8 6231.7 6231.8 6234.3 6237.9 6240.8 5917.2 6020.8 6055.7 6121.1 6129.0 6222.6 6225.5 6236.2 6236.3 6239.2 6118.8 6172.6 6183.5 6185.6 6188.3 6053.2 6122.5 6125.6 6126.0 6191.0 4811.6 4851.0 4854.7 4855.5 4860.7 4350.1 4357.4 4373.6 4433.8 4454.7 5581.1 5607.7 5611.0 5619.5 5654.4 6083.6 6196.8 6204.1 6219.2 6228.1 5591.1 5631.9 5640.0 5674.7 5681.5 5316.5 5327.4 5329.0 5334.0 5347.2 6141.6 6200.1 6200.4 6258.4 6273.5 6110.8 6138.2 6150.8 6154.6 6163.9 6273.2 6286.7 6289.7 6293.9 6299.8 6150.4 6153.2 6157.4 6163.7 6171.9 6067.9 6150.0 6154.0 6164.7 6227.5 5187.8 5958.3 5989.7 6002.2 6029.7 6011.6 6031.6 6071.6 6114.6 6121.9 4948.7 5906.7 5906.9 5920.1 5950.3 6068.2 6071.7 6074.5 6086.4 6122.1 5782.0 5858.3 5940.0 5945.5 5976.2 03:09:23 INFO - PROCESS | 1672 | 03:09:23 INFO - PROCESS | 1672 | [#1] ext.html Cycles:180 Average:63630.48 Median:3510.25 stddev:141381.05 (4027.7%) stddev-sans-first:141698.23 03:09:23 INFO - PROCESS | 1672 | Values: 507.7 536.1 547.4 551.4 566.7 30946.4 38017.0 39289.4 39367.0 39610.2 41186.6 41777.0 41835.6 41850.0 41963.0 8406.6 9468.3 9657.1 9690.0 9690.6 8554.7 11591.1 11704.2 11720.4 11720.9 11499.9 11587.8 11588.4 11609.4 11693.6 8549.4 8708.7 8754.5 11365.0 11520.4 9790.6 11694.6 11710.2 11713.9 11755.1 32192.7 38725.0 38740.4 38793.0 39477.4 3199.2 3278.2 3343.3 3450.3 3516.4 27427.0 27646.0 28351.6 28832.6 28892.6 2700.3 2741.5 2744.3 2863.5 2882.4 509561.0 510057.0 510335.0 510866.0 511099.0 36320.3 36889.4 36932.4 62006.1 63313.1 35878.0 35903.8 35904.0 35942.6 36001.0 15265.9 15401.3 15440.9 15449.8 15449.9 11817.0 11926.9 12906.1 12906.6 13172.6 8179.0 8182.8 8207.7 8237.4 8305.1 3408.4 3447.3 3447.4 3462.1 3504.1 947.4 953.5 1003.8 1044.3 1057.5 27328.7 30007.5 30150.1 30736.1 30774.4 1152.9 1177.2 1183.1 1214.7 1223.0 15299.1 15340.5 15445.9 15546.3 15599.5 664617.0 665838.0 668628.9 671551.0 671747.0 60670.8 62181.7 62196.4 62418.0 62597.0 1354.8 1385.6 1525.6 1542.4 1592.6 28688.7 28835.0 28996.9 29711.4 29860.4 47081.8 47163.1 47499.8 47528.0 47550.0 331576.0 346855.0 364074.0 369204.0 370122.0 23376.9 26946.5 27206.5 27464.0 27530.4 42964.8 45021.0 45688.8 46045.3 46169.0 37337.1 37445.8 37768.2 38187.6 38312.0 36698.0 37114.3 37556.0 37796.4 37990.0 36903.8 37180.1 38068.8 38105.2 38170.2 27713.9 29197.7 32025.7 32593.5 33467.5 24676.9 24736.9 24828.9 25024.6 25174.4 03:09:23 INFO - PROCESS | 1672 | 03:09:23 INFO - PROCESS | 1672 | [#2] jquery.html Cycles:180 Average:30732.97 Median:11425.56 stddev:81374.94 (712.2%) stddev-sans-first:81571.22 03:09:23 INFO - PROCESS | 1672 | Values: 342.3 364.7 412.3 415.3 419.7 19296.6 20707.0 20732.8 20742.5 20762.5 20135.4 20488.5 20936.4 21295.4 21306.9 5372.9 5493.5 5541.8 5582.8 5585.7 5364.6 5449.8 5479.9 5505.5 5532.6 5495.9 5496.6 5497.7 5510.0 5513.3 5313.1 5406.1 5485.4 5495.5 5518.8 5314.0 5365.9 5366.8 5370.6 5371.2 13196.7 13909.2 13910.4 13933.4 13938.0 2078.0 2106.4 2167.2 2340.9 2739.2 9175.8 9825.3 10096.6 12742.4 14602.6 1712.1 1733.1 1737.9 1801.8 1824.9 114333.0 114896.0 117047.8 119242.4 120245.4 11523.2 11775.5 11788.9 11799.6 11926.1 15247.1 15281.0 15299.2 15332.7 15333.2 8482.7 8751.7 8788.7 8845.0 9039.8 8885.6 8887.7 8929.6 8975.7 9035.7 4726.2 4726.7 4745.6 4779.5 4803.7 1675.1 1677.3 1949.7 1998.5 2002.1 899.5 910.9 955.5 1012.8 1015.9 20189.2 20773.2 20939.3 21016.5 21251.8 952.4 977.8 1023.5 1033.9 1048.4 10703.3 11160.3 11177.8 11188.4 11201.4 237331.0 238593.0 516488.4 592690.0 628805.0 130232.3 136679.0 136688.0 140332.3 141693.0 1008.2 1011.4 1027.6 1028.0 1031.8 17965.0 18155.6 18278.0 18516.4 18776.8 50482.2 53981.7 54984.5 55531.0 57773.7 78922.0 79681.0 81201.0 83281.0 85776.1 6830.4 7822.8 8077.0 8187.2 8245.3 7166.7 10727.0 10967.2 11195.7 11974.8 10479.0 10515.7 10859.0 11024.6 11071.2 10364.4 10669.0 11057.3 11121.4 11351.9 10328.9 11011.6 11237.9 11284.0 11611.8 8161.1 8249.6 8295.6 8367.5 8551.8 10516.6 10816.1 11024.6 11119.8 11135.8 03:09:23 INFO - PROCESS | 1672 | 03:09:23 INFO - PROCESS | 1672 | [#3] mootools.html Cycles:180 Average:5687.81 Median:4904.69 stddev:3169.62 (64.6%) stddev-sans-first:3162.48 03:09:23 INFO - PROCESS | 1672 | Values: 1445.4 1580.2 1599.2 1821.7 1942.2 7951.9 8580.9 8752.2 8753.5 8765.2 8478.5 8689.5 8695.0 8700.1 8715.2 2149.5 2171.8 2288.9 2409.3 2439.1 2461.5 2471.2 2474.2 2474.8 2480.9 2381.8 2413.9 2436.7 2438.6 2441.2 2128.7 2197.3 2200.5 2208.9 2427.3 2097.2 2396.9 2460.3 2465.9 2466.5 7861.1 8233.6 8630.0 8701.3 8709.7 4501.2 5441.9 5461.4 5469.5 5473.4 2883.3 3386.4 3434.1 3498.5 3519.4 977.7 994.5 1016.7 1019.9 1024.2 12179.4 12211.7 12215.5 12227.5 12276.0 4808.5 4842.8 4885.5 4902.8 4974.6 10231.0 10231.4 10244.3 10254.3 10278.1 5523.3 5556.1 5562.3 5563.4 5573.2 4425.3 4578.2 4894.3 4953.4 4979.6 4092.7 4095.9 4121.5 4151.5 4158.7 1318.0 1342.4 1353.0 1354.5 1361.6 906.7 918.7 934.7 943.0 975.2 5899.6 5973.4 6020.0 6121.0 6170.0 903.9 991.7 1067.5 1094.5 1104.8 3753.9 3869.3 3932.8 3974.1 3999.8 8239.7 10444.0 10445.8 10558.4 10995.9 10243.9 10433.1 10442.4 10458.2 10514.2 8111.8 8232.6 8363.0 8375.8 8449.5 5692.4 5787.7 5974.1 5978.6 5993.9 9451.9 9809.4 9819.5 9952.4 9971.5 9261.4 9949.2 10019.0 10113.0 10119.0 6925.4 7089.1 7089.1 7540.4 7658.9 7759.1 7809.6 8108.0 8307.6 8365.5 6400.6 7365.4 7372.9 7535.2 7543.8 7195.7 7257.9 7305.7 7309.2 7315.9 6963.1 7035.2 7047.0 7056.4 7064.6 4757.1 4768.9 4788.2 4906.6 4919.8 4357.2 4874.3 4908.5 4925.9 5007.2 03:09:23 INFO - PROCESS | 1672 | 03:09:23 INFO - PROCESS | 1672 | [#4] prototype.html Cycles:180 Average:5741.36 Median:6548.43 stddev:1841.77 (28.1%) stddev-sans-first:1801.47 03:09:23 INFO - PROCESS | 1672 | Values: 322.3 327.6 333.7 340.4 343.8 6515.8 6532.4 6537.4 6555.8 6556.3 6403.1 6404.1 6515.9 6531.3 6543.8 4642.0 4767.9 4773.0 4773.3 4790.8 4788.9 4800.3 4886.2 4922.1 4948.1 4792.8 4822.2 4842.2 4845.7 4856.2 3981.7 4628.2 4870.8 4879.3 4886.3 4046.0 4126.7 4146.7 4147.8 4156.9 5082.6 6215.8 6242.9 6303.5 6356.0 5673.3 5682.9 5694.7 5700.8 5718.7 6936.8 6960.2 6960.3 6963.6 6967.3 3458.4 4236.4 4367.6 4662.8 4673.0 7233.7 7295.3 7296.8 7329.7 7355.8 4630.2 4631.7 4635.7 4636.3 4638.8 4687.8 4704.4 4711.9 4716.9 4719.2 5598.4 5602.6 5602.7 5613.9 5623.4 6761.5 6996.4 7011.5 7011.7 7018.5 1609.5 1623.9 1631.8 1633.4 1634.6 1026.5 1028.7 1042.3 1046.3 1049.5 6396.7 6553.1 6556.7 6586.7 6698.7 7356.9 7361.1 7362.4 7363.3 7366.6 6675.4 6679.2 6686.6 6689.6 6690.2 3540.0 3577.4 3828.1 3883.5 3891.0 7320.4 7619.6 7620.5 7622.0 7635.5 7459.1 7484.5 7486.1 7498.5 7503.4 7597.9 7617.2 7621.4 7621.4 7640.5 7275.7 7302.3 7304.5 7311.4 7320.5 6917.4 7147.7 7166.5 7192.7 7264.9 6536.4 7422.3 7560.5 7665.2 7669.2 4816.5 4866.5 4868.2 4869.1 4878.9 7246.8 7257.5 7264.9 7273.2 7276.0 6687.8 6987.1 6997.1 7009.2 7021.4 5685.4 6960.3 7004.7 7009.3 7009.9 6837.3 6843.1 6843.4 6849.9 6867.0 6775.7 7019.8 7025.5 7028.9 7038.1 6655.3 6778.2 6853.8 6863.6 6892.3 03:09:23 INFO - PROCESS | 1672 | 03:09:23 INFO - PROCESS | 1672 | [#5] yui.html Cycles:180 Average:17965.23 Median:612.37 stddev:25312.97 (4133.6%) stddev-sans-first:25350.87 03:09:23 INFO - PROCESS | 1672 | Values: 722.2 725.7 749.6 756.1 807.2 14924.5 15155.3 15329.7 15355.8 15355.8 12083.8 13899.4 14352.2 14363.1 14387.3 709.0 756.3 770.0 890.9 924.0 913.7 917.0 918.7 920.9 930.2 969.1 971.6 971.8 972.2 992.0 787.5 792.9 795.8 796.4 982.0 721.7 723.2 723.7 724.2 727.1 12351.4 14822.0 14836.6 14880.3 14908.9 11986.5 12153.7 12179.3 12249.3 12260.8 9925.2 10042.6 10043.2 10240.5 10439.6 3459.4 3472.9 3537.8 3545.9 3626.1 63086.4 81773.8 82302.2 85160.1 85161.6 9439.1 9441.0 9633.8 9758.5 9914.7 21176.0 21912.8 21940.9 21972.0 21987.7 7223.0 7308.8 7421.8 7439.7 7460.7 4082.4 4129.9 4183.2 4286.6 4333.4 3362.5 3416.5 3417.7 3424.0 3425.2 601.6 626.1 629.7 629.8 634.6 556.5 584.2 593.5 606.2 623.0 7887.1 8026.4 8070.8 8214.7 8274.0 547.9 575.1 599.1 612.3 612.5 3728.7 3780.0 3801.2 3816.4 3840.2 88631.0 98828.5 100210.0 100300.5 100486.5 70244.0 70941.6 71037.3 71753.6 71888.6 62986.4 63131.0 64545.7 65126.7 65750.7 7330.4 7342.1 7390.8 7478.5 7758.3 45740.0 46867.1 47545.8 47607.8 47731.8 64320.7 67734.0 68515.7 69447.7 70466.6 10840.2 10895.6 11075.3 11432.4 11486.4 11758.0 12062.8 12073.5 12117.2 12134.8 11274.2 11564.1 11566.5 11568.1 11640.9 11374.3 11610.0 11614.0 11688.6 12222.3 10956.5 11190.9 11542.5 11556.9 11584.6 9469.8 9627.7 9708.1 9739.0 9935.1 9310.5 9556.5 9591.0 9700.6 9853.9 03:09:23 INFO - PROCESS | 1672 | -------- Summary: end -------- 03:09:23 INFO - PROCESS | 1672 | 03:09:24 INFO - TEST-INFO | 1672: exit 0 03:09:24 INFO - TEST-OK | dromaeo_css | took 1115299ms 03:09:24 INFO - TEST-START | kraken 03:09:24 INFO - operating with platform_type : mac_ 03:09:24 INFO - Initialising browser for kraken test... 03:09:24 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmpUGK6Ft/profile http://localhost:49275/getInfo.html) 03:09:26 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 03:09:26 INFO - PROCESS | 1678 | colorDepth:24 03:09:26 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 03:09:26 INFO - PROCESS | 1678 | __metrics 03:09:26 INFO - PROCESS | 1678 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:09:26 ERROR - PROCESS | 1678 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:09:27 INFO - TEST-INFO | 1678: exit 0 03:09:27 INFO - Browser initialized. 03:09:27 INFO - Running cycle 1/1 for kraken test... 03:09:27 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/tmpUGK6Ft/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:09:39 INFO - PROCESS | 1681 | RSS: Main: 211230720 03:09:39 INFO - PROCESS | 1681 | 03:09:42 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 03:09:43 INFO - PROCESS | 1681 | RSS: Main: 272191488 03:09:43 INFO - PROCESS | 1681 | 03:09:45 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 03:09:46 INFO - PROCESS | 1681 | RSS: Main: 286265344 03:09:46 INFO - PROCESS | 1681 | 03:09:49 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 03:09:50 INFO - PROCESS | 1681 | RSS: Main: 374595584 03:09:50 INFO - PROCESS | 1681 | 03:09:52 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 03:09:52 INFO - PROCESS | 1681 | RSS: Main: 279830528 03:09:52 INFO - PROCESS | 1681 | 03:09:54 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:09:55 INFO - PROCESS | 1681 | RSS: Main: 280731648 03:09:55 INFO - PROCESS | 1681 | 03:09:58 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 03:09:59 INFO - PROCESS | 1681 | RSS: Main: 318418944 03:09:59 INFO - PROCESS | 1681 | 03:10:01 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 03:10:02 INFO - PROCESS | 1681 | RSS: Main: 318656512 03:10:02 INFO - PROCESS | 1681 | 03:10:05 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 03:10:05 INFO - PROCESS | 1681 | RSS: Main: 318500864 03:10:05 INFO - PROCESS | 1681 | 03:10:07 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:10:08 INFO - PROCESS | 1681 | RSS: Main: 309583872 03:10:08 INFO - PROCESS | 1681 | 03:10:10 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:10:10 INFO - PROCESS | 1681 | RSS: Main: 316108800 03:10:10 INFO - PROCESS | 1681 | 03:10:12 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:10:13 INFO - PROCESS | 1681 | RSS: Main: 318885888 03:10:13 INFO - PROCESS | 1681 | 03:10:16 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:10:17 INFO - PROCESS | 1681 | RSS: Main: 321085440 03:10:17 INFO - PROCESS | 1681 | 03:10:19 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:10:20 INFO - PROCESS | 1681 | RSS: Main: 316858368 03:10:20 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 03:10:22 INFO - PROCESS | 1681 | RSS: Main: 380952576 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | __start_tp_report 03:10:22 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 03:10:22 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 03:10:22 INFO - PROCESS | 1681 | |i|pagename|runs| 03:10:22 INFO - PROCESS | 1681 | |0;ai-astar;113;112;119;114;112;112;113;113;112;133 03:10:22 INFO - PROCESS | 1681 | |1;audio-beat-detection;158;123;142;164;124;174;171;145;162;117 03:10:22 INFO - PROCESS | 1681 | |2;audio-dft;189;185;186;185;184;184;183;184;183;182 03:10:22 INFO - PROCESS | 1681 | |3;audio-fft;73;77;74;148;55;55;60;65;140;58 03:10:22 INFO - PROCESS | 1681 | |4;audio-oscillator;93;89;140;77;78;92;123;79;79;131 03:10:22 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;85;88;87;83;91;130;88;84;87;85 03:10:22 INFO - PROCESS | 1681 | |6;imaging-darkroom;94;92;92;92;92;122;150;96;92;91 03:10:22 INFO - PROCESS | 1681 | |7;imaging-desaturate;96;119;83;85;83;83;83;86;84;85 03:10:22 INFO - PROCESS | 1681 | |8;json-parse-financial;60;63;65;63;63;66;64;83;59;58 03:10:22 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;63;62;69;73;75;69;68;65;89;97 03:10:22 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;81;81;80;80;81;78;80;80;82;103 03:10:22 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;126;128;127;130;108;140;136;148;149;148 03:10:22 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;169;166;165;165;164;166;164;193;163;165 03:10:22 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;68;67;84;94;92;90;93;69;67;66 03:10:22 INFO - PROCESS | 1681 | __end_tp_report 03:10:22 INFO - PROCESS | 1681 | __start_cc_report 03:10:22 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,1969 03:10:22 INFO - PROCESS | 1681 | __end_cc_report 03:10:22 INFO - PROCESS | 1681 | __startTimestamp1464948622593__endTimestamp 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | ------- Summary: start ------- 03:10:22 INFO - PROCESS | 1681 | Number of tests: 14 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:115.30 Median:113.00 stddev:6.57 (5.8%) stddev-sans-first:6.91 03:10:22 INFO - PROCESS | 1681 | Values: 113.0 112.0 119.0 114.0 112.0 112.0 113.0 113.0 112.0 133.0 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:148.00 Median:160.00 stddev:20.99 (13.1%) stddev-sans-first:21.95 03:10:22 INFO - PROCESS | 1681 | Values: 158.0 123.0 142.0 164.0 124.0 174.0 171.0 145.0 162.0 117.0 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:184.50 Median:184.50 stddev:1.96 (1.1%) stddev-sans-first:1.22 03:10:22 INFO - PROCESS | 1681 | Values: 189.0 185.0 186.0 185.0 184.0 184.0 183.0 184.0 183.0 182.0 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:80.50 Median:62.50 stddev:34.44 (55.1%) stddev-sans-first:36.42 03:10:22 INFO - PROCESS | 1681 | Values: 73.0 77.0 74.0 148.0 55.0 55.0 60.0 65.0 140.0 58.0 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:98.10 Median:79.00 stddev:24.00 (30.4%) stddev-sans-first:25.38 03:10:22 INFO - PROCESS | 1681 | Values: 93.0 89.0 140.0 77.0 78.0 92.0 123.0 79.0 79.0 131.0 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:87.00 stddev:13.97 (16.1%) stddev-sans-first:14.66 03:10:22 INFO - PROCESS | 1681 | Values: 85.0 88.0 87.0 83.0 91.0 130.0 88.0 84.0 87.0 85.0 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:92.00 stddev:19.49 (21.2%) stddev-sans-first:20.50 03:10:22 INFO - PROCESS | 1681 | Values: 94.0 92.0 92.0 92.0 92.0 122.0 150.0 96.0 92.0 91.0 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:88.70 Median:84.50 stddev:11.34 (13.4%) stddev-sans-first:11.72 03:10:22 INFO - PROCESS | 1681 | Values: 96.0 119.0 83.0 85.0 83.0 83.0 83.0 86.0 84.0 85.0 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:63.50 stddev:7.03 (11.1%) stddev-sans-first:7.27 03:10:22 INFO - PROCESS | 1681 | Values: 60.0 63.0 65.0 63.0 63.0 66.0 64.0 83.0 59.0 58.0 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:71.00 stddev:11.44 (16.1%) stddev-sans-first:11.55 03:10:22 INFO - PROCESS | 1681 | Values: 63.0 62.0 69.0 73.0 75.0 69.0 68.0 65.0 89.0 97.0 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:80.50 stddev:7.24 (9.0%) stddev-sans-first:7.66 03:10:22 INFO - PROCESS | 1681 | Values: 81.0 81.0 80.0 80.0 81.0 78.0 80.0 80.0 82.0 103.0 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:134.00 Median:138.00 stddev:12.90 (9.3%) stddev-sans-first:13.36 03:10:22 INFO - PROCESS | 1681 | Values: 126.0 128.0 127.0 130.0 108.0 140.0 136.0 148.0 149.0 148.0 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:165.50 stddev:8.93 (5.4%) stddev-sans-first:9.47 03:10:22 INFO - PROCESS | 1681 | Values: 169.0 166.0 165.0 165.0 164.0 166.0 164.0 193.0 163.0 165.0 03:10:22 INFO - PROCESS | 1681 | 03:10:22 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.00 Median:87.00 stddev:12.53 (14.4%) stddev-sans-first:12.65 03:10:22 INFO - PROCESS | 1681 | Values: 68.0 67.0 84.0 94.0 92.0 90.0 93.0 69.0 67.0 66.0 03:10:22 INFO - PROCESS | 1681 | -------- Summary: end -------- 03:10:22 INFO - PROCESS | 1681 | 03:10:23 INFO - TEST-INFO | 1681: exit 0 03:10:23 INFO - TEST-OK | kraken | took 58918ms 03:10:23 INFO - SUITE-END | took 1174s 03:10:23 INFO - Completed test suite (00:19:34) 03:10:23 INFO - javascript benchmark 03:10:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3352.8994130176093, 3387.4072037393466, 3394.9490757638623, 3410.607780105287, 3462.948055779164, 5591.049521581331, 5599.300087489064, 5605.103183490643, 5608.831735047945, 5609.663420194791, 5600.355959064709, 5621.269234999455, 5633.661980281186, 5663.7168141592965, 5680.687562184088, 5118.539331460168, 5161.793528258874, 5175.560077393426, 5184.844454666366, 5248.895022099556, 5256.185291279858, 5256.684598924076, 5263.947360526384, 5266.947330526684, 5278.815241466549, 5143.228515722634, 5194.71429071401, 5201.0, 5222.242774797671, 5259.263703081571, 5225.581953443734, 5252.343457067866, 5260.395054568697, 5264.7367631618645, 5315.893682126336, 5069.214271787879, 5104.872378190538, 5156.690598564098, 5168.457311982263, 5195.870103247411, 5523.337199536041, 5524.917126243109, 5555.388907220203, 5561.304836895388, 5577.9163312550345, 6185.505159587222, 6196.535259855484, 6202.658853763041, 6203.131561581382, 6204.193454850841, 6088.269407671063, 6185.721642526097, 6220.315765265817, 6268.310485846553, 6281.811545653638, 5744.965906136936, 5777.566682498829, 5778.0, 5779.682117483537, 5781.768729250825, 6231.688415579293, 6231.781887633911, 6234.251889773256, 6237.937620623736, 6240.843978900473, 5917.230760001173, 6020.819375418744, 6055.666938318434, 6121.087175196994, 6129.0, 6222.62664240138, 6225.533085018642, 6236.220472440945, 6236.251649802053, 6239.188905442355, 6118.816435506942, 6172.598781079216, 6183.505319574423, 6185.597936134137, 6188.257409110935, 6053.243344581927, 6122.489639783254, 6125.632272693312, 6126.029144987373, 6191.040388739752, 4811.566958973639, 4850.95149048505, 4854.708717476963, 4855.465898751135, 4860.68405553645, 4350.086481838849, 4357.3899654048455, 4373.562643735665, 4433.800478978454, 4454.710443821141, 5581.079121944915, 5607.691576963224, 5611.0, 5619.522340601013, 5654.434556544395, 6083.634981901012, 6196.81409557714, 6204.069389591597, 6219.222597175353, 6228.065790131516, 5591.10542313228, 5631.8592035199445, 5639.971800140973, 5674.659520428788, 5681.488666020078, 5316.46835316473, 5327.38735045467, 5328.987492376436, 5333.973330133324, 5347.197920311906, 6141.63943600049, 6200.0699895016105, 6200.410960958701, 6258.405451482102, 6273.466755325757, 6110.77140286259, 6138.204480310071, 6150.769846030722, 6154.630722156685, 6163.907541386793, 6273.153124328157, 6286.717097730704, 6289.685515724287, 6293.905591416041, 6299.779507717208, 6150.354212807591, 6153.230846144232, 6157.384261573807, 6163.722632481459, 6171.876562468636, 6067.878642427215, 6149.985252433477, 6153.969230153821, 6164.722587483664, 6227.532935029982, 5187.806804434926, 5958.255218097738, 5989.7304621293015, 6002.159697642246, 6029.668368239789, 6011.639301641915, 6031.60794548353, 6071.605345652519, 6114.633122012693, 6121.938780612137, 4948.678335908155, 5906.675132867734, 5906.911396329145, 5920.141579471038, 5950.315713692808, 6068.180795592715, 6071.666058366833, 6074.544409169418, 6086.421789929949, 6122.051082082285, 5782.0, 5858.2677165354335, 5940.019896717166, 5945.494632956247, 5976.223090998143], "value": 5652.244419696375, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.6725512044731, 536.1394961087457, 547.4252035362867, 551.4072372199886, 566.7223060700256, 30946.38107237854, 38017.0, 39289.41065884007, 39367.0, 39610.207795844064, 41186.58813411865, 41776.95557611054, 41835.58164418363, 41850.0, 41963.0, 8406.621702023393, 9468.289878259124, 9657.082577155159, 9690.0, 9690.56392462341, 8554.743357699279, 11591.072714182887, 11704.239224450383, 11720.413979301044, 11720.941395293012, 11499.885001150007, 11587.768244635103, 11588.42057897106, 11609.419529023557, 11693.649190524298, 8549.40154189207, 8708.651653933835, 8754.518501482416, 11364.977152056314, 11520.423978801027, 9790.55942482583, 11694.649160525198, 11710.238834475731, 11713.94143029288, 11755.118366122575, 32192.678073219202, 38725.0, 38740.41889371662, 38793.0, 39477.42090316384, 3199.2481766784786, 3278.246003419203, 3343.2979074394407, 3450.344434557451, 3516.384632689269, 27427.0, 27646.032388866293, 28351.57472637912, 28832.567511487345, 28892.566611500843, 2700.3114205877573, 2741.4928238275984, 2744.2727677165567, 2863.4702580022554, 2882.3802882380387, 509561.0, 510057.0, 510335.0, 510866.0, 511099.0, 36320.273594527964, 36889.44665830015, 36932.44601330982, 62006.0699089514, 63313.10060698186, 35878.0, 35903.820480897426, 35904.0, 35942.64057359445, 36001.0, 15265.855060870479, 15401.306941187673, 15440.922795385952, 15449.84550154484, 15449.922750386177, 11817.0, 11926.940365298291, 12906.09657323997, 12906.612801615778, 13172.60482185536, 8178.977627796526, 8182.836343273101, 8207.671693132328, 8237.382196335298, 8305.086440491541, 3408.352413041514, 3447.293304872495, 3447.413939630269, 3462.0825481246984, 3504.123969007748, 947.3557980573139, 953.50894289441, 1003.7691330993912, 1044.3055368180076, 1057.4554104636118, 27328.726712733016, 30007.49887516846, 30150.09549713512, 30736.077917662507, 30774.384512310076, 1152.948117334722, 1177.1642134084896, 1183.1126655008743, 1214.6841821126393, 1222.957196498136, 15299.082055076731, 15340.463083792014, 15445.880532960771, 15546.300416481285, 15599.454019109276, 664617.0, 665838.0, 668628.9411317668, 671551.0, 671747.0, 60670.78658426807, 62181.68909155425, 62196.37803621996, 62418.0, 62597.0, 1354.7696891528465, 1385.625881012121, 1525.6262215756988, 1542.4215919030364, 1592.585927658794, 28688.713112868605, 28834.990775322764, 28996.855015724785, 29711.40577188401, 29860.402791944474, 47081.82295442641, 47163.056738865715, 47499.76250118727, 47528.0, 47550.0, 331576.0, 346855.0, 364074.0, 369204.0, 370122.0, 23376.883115584315, 26946.461070778867, 27206.455870882866, 27464.0, 27530.44939101247, 42964.78517607392, 45021.0, 45688.771556143336, 46045.30932035955, 46169.0, 37337.066573335884, 37445.81277093706, 37768.244635107694, 38187.61812381841, 38312.0, 36698.0, 37114.25771484609, 37556.0, 37796.43305350367, 37990.0, 36903.81548092242, 37180.07049823776, 38068.80965595154, 38105.23789524249, 38170.23659526849, 27713.86143069272, 29197.708022919498, 32025.67974320227, 32593.511097333085, 33467.49798752972, 24676.876615617526, 24736.876315618305, 24828.875855620605, 25024.62463063019, 25174.370640734127], "value": 18525.577547667323, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.3375767889134, 364.7136997456996, 412.25793571571165, 415.2774172939085, 419.6768488264035, 19296.614067718638, 20707.0, 20732.792672073294, 20742.48143796406, 20762.480937976557, 20135.39593812188, 20488.487787805312, 20936.37190884272, 21295.361139165812, 21306.89346553269, 5372.9462705372935, 5493.533049690781, 5541.806036788713, 5582.804601838937, 5585.664860108386, 5364.597655175858, 5449.809256676017, 5479.931413374394, 5505.476979686923, 5532.5573954083775, 5495.917561236564, 5496.615236933416, 5497.670139791605, 5509.972450137744, 5513.283273174482, 5313.123334649778, 5406.135018397057, 5485.396606373296, 5495.53287970523, 5518.806841760559, 5313.973430132824, 5365.919511207335, 5366.785328586851, 5370.624056316059, 5371.248025276464, 13196.736065278641, 13909.234992075431, 13910.374033168431, 13933.442662293495, 13937.954653400933, 2077.95844083119, 2106.3891471473253, 2167.219800871685, 2340.9180678676335, 2739.1919383781833, 9175.816483670356, 9825.263105267133, 10096.646617368431, 12742.36288185587, 14602.561923142322, 1712.143928035982, 1733.0696053499141, 1737.9478615641551, 1801.846843018345, 1824.917878695462, 114333.0, 114896.0, 117047.82952170454, 119242.40378798137, 120245.39877300558, 11523.21390184506, 11775.470103845177, 11788.88211117895, 11799.646010619696, 11926.105542084379, 15247.094113235846, 15281.0, 15299.15854627984, 15332.693346133237, 15333.156676382683, 8482.745517634481, 8751.693690720795, 8788.736337909873, 8844.955775221082, 9039.819203616022, 8885.555722213863, 8887.688930887518, 8929.598168082452, 8975.685850995314, 9035.689824975341, 4726.196546587088, 4726.716397016121, 4745.596624286905, 4779.522047795263, 4803.711777293373, 1675.078706711304, 1677.2975429938622, 1949.6685563453964, 1998.5003749062735, 2002.0890494824819, 899.4738078224182, 910.8724778531011, 955.4553904274496, 1012.772126271583, 1015.8527013582989, 20189.192432302545, 20773.169073237204, 20939.267125650833, 21016.474588135417, 21251.787482124982, 952.3952290295712, 977.8386566216636, 1023.5087158163974, 1033.850091736694, 1048.4181279390011, 10703.250772445854, 11160.274582152135, 11177.776444471065, 11188.440577971232, 11201.439928003567, 237331.0, 238593.0, 516488.4175579098, 592690.0, 628805.0, 130232.3488382552, 136679.0, 136688.0, 140332.29833850765, 141693.0, 1008.1732978957331, 1011.3577878047493, 1027.6043723166636, 1027.9640212592524, 1031.8297480915564, 17965.0, 18155.636887262444, 18278.0, 18516.351927682466, 18776.81223187805, 50482.2427663578, 53981.730091349295, 54984.45015549954, 55531.0, 57773.71113144408, 78922.0, 79681.0, 81201.0, 83281.0, 85776.14223857931, 6830.385265326145, 7822.804429889298, 8077.0, 8187.181281871765, 8245.257926786619, 7166.677499512639, 10727.034566889017, 10967.177461690564, 11195.664130075786, 11974.820377694516, 10479.0, 10515.684529464128, 10859.022687958122, 11024.559017639205, 11071.225014248925, 10364.429956352473, 10669.0, 11057.336559806115, 11121.443927803415, 11351.943240284076, 10328.896711032794, 11011.559537618727, 11237.943810280896, 11284.0, 11611.825822612836, 8161.143079976517, 8249.587520624065, 8295.626696798538, 8367.456115352483, 8551.828963420821, 10516.579336826748, 10816.08063314627, 11024.61311278838, 11119.777604448027, 11135.832962505407], "value": 9327.321158254479, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.3784872504818, 1580.1862041048857, 1599.2403608286063, 1821.6812057889865, 1942.2036964844417, 7951.92048079519, 8580.871286930693, 8752.168543988328, 8753.518556479406, 8765.16730910564, 8478.491290522574, 8689.478631282127, 8695.000074991378, 8700.129987001297, 8715.172058654434, 2149.451889768107, 2171.7502487213937, 2288.942776430586, 2409.2531315292226, 2439.1462987954164, 2461.4584791345874, 2471.233917485576, 2474.2206205045445, 2474.7896428803483, 2480.888360023794, 2381.785639292472, 2413.8793060347, 2436.683231179953, 2438.5976313908186, 2441.170002199252, 2128.659414493689, 2197.263916587945, 2200.471886747185, 2208.9116435342567, 2427.2596857958315, 2097.213544920655, 2396.928092157238, 2460.335709358463, 2465.901363945458, 2466.5313590417763, 7861.056673199196, 8233.629486673113, 8629.95685021577, 8701.30389568833, 8709.695160669346, 4501.212287849613, 5441.938821929725, 5461.399246082928, 5469.507744302992, 5473.370562385297, 2883.3224192314788, 3386.407378708716, 3434.1414646338417, 3498.5276987606603, 3519.419295816213, 977.7066879935991, 994.5375400438759, 1016.654337525245, 1019.9133073688745, 1024.1653052761976, 12179.391030448442, 12211.69470763238, 12215.511379544807, 12227.510899564008, 12276.0, 4808.519148085234, 4842.83050093245, 4885.5358740919555, 4902.803887844446, 4974.602031837444, 10231.028052335016, 10231.386116833013, 10244.334118282288, 10254.28220024606, 10278.126359259397, 5523.25436066134, 5556.083246264321, 5562.332520097533, 5563.443655634404, 5573.247611572386, 4425.3140763181755, 4578.221702310549, 4894.3147959285725, 4953.380827396575, 4979.601631869442, 4092.7135100543273, 4095.9180816383505, 4121.4848143982, 4151.481064866892, 4158.708890377705, 1318.0, 1342.3959218351781, 1353.0123010202608, 1354.525915929417, 1361.5574938145141, 906.664534122379, 918.7427520294328, 934.7055677461578, 943.0051295882772, 975.2490582251627, 5899.616524925952, 5973.372795856372, 6019.976603977334, 6120.969395152996, 6169.96915015422, 903.8599017152352, 991.6876184002016, 1067.5409573883148, 1094.4910616563336, 1104.817705078653, 3753.868614598476, 3869.304813902716, 3932.8426862925735, 3974.145558704892, 3999.8400063997115, 8239.670413183405, 10444.0, 10445.84331235032, 10558.366498010144, 10995.945020274847, 10243.897561024443, 10433.113185379176, 10442.373457592568, 10458.215633827493, 10514.158867290598, 8111.75664730059, 8232.62953167109, 8363.0, 8375.8324833503, 8449.493030418073, 5692.430756924275, 5787.710614469175, 5974.103884417372, 5978.581499295007, 5993.850153746192, 9451.913030001639, 9809.362391444532, 9819.459929703935, 9952.353097048668, 9971.45157016371, 9261.351705380559, 9949.154321882721, 10019.048190421898, 10113.0, 10119.0, 6925.445964322741, 7089.113860767404, 7089.149302083783, 7540.434467415075, 7658.885116723142, 7759.068911730628, 7809.609519523888, 8108.027036755627, 8307.584620768817, 8365.539895305574, 6400.615963042233, 7365.410767138723, 7372.926270737438, 7535.246475352421, 7543.811404714926, 7195.748148814766, 7257.927420725725, 7305.707771689073, 7309.159446663702, 7315.926840731525, 6963.1296087989, 7035.1909530404655, 7047.01341812157, 7056.400205982549, 7064.646767661699, 4757.072370887644, 4768.856934291976, 4788.233882578771, 4906.582940449959, 4919.778609962632, 4357.237483440449, 4874.341963834837, 4908.509149085063, 4925.901481970269, 5007.198848184273], "value": 4596.301484059367, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.2668429323289, 327.64614216646027, 333.7380156577087, 340.4246822869352, 343.82464942879125, 6515.7719479818215, 6532.379423954717, 6537.378948999849, 6555.803325900225, 6556.2788093309755, 6403.135576697145, 6404.071409645599, 6515.869682606345, 6531.2815590285145, 6543.770968016121, 4642.025174713314, 4767.880802979936, 4772.976135119319, 4773.331733557305, 4790.7604619769045, 4788.856334309976, 4800.279958006292, 4886.193778026639, 4922.145155809621, 4948.134076536609, 4792.849716068136, 4822.204336284509, 4842.249451335031, 4845.733484658343, 4856.17445034345, 3981.7013723970817, 4628.190066738307, 4870.829520966749, 4879.341288925985, 4886.340344053543, 4045.9595404045876, 4126.690498212646, 4146.66826653867, 4147.81334839933, 4156.896077598054, 5082.644214904958, 6215.782447614357, 6242.875142497124, 6303.5272354573135, 6356.0, 5673.290838645169, 5682.943170568283, 5694.715264236772, 5700.7719691212305, 5718.7426565804635, 6936.826579335507, 6960.234374218882, 6960.30396960305, 6963.617001064939, 6967.268436814163, 3458.429359155736, 4236.449261596003, 4367.62875155609, 4662.790174442158, 4672.976635116803, 7233.674484648203, 7295.270472952715, 7296.8175795605, 7329.743458978964, 7355.77932662021, 4630.189716799547, 4631.722096674211, 4635.7218566886095, 4636.327732478839, 4638.79125439356, 4687.812487500461, 4704.411948506437, 4711.929321060187, 4716.882077948078, 4719.244920812654, 5598.412166722517, 5602.579806514527, 5602.65536271292, 5613.887722245532, 5623.409541998113, 6761.526693131532, 6996.405305549085, 7011.54424962386, 7011.684474198673, 7018.543794653331, 1609.4527860527478, 1623.9269232884549, 1631.804183497964, 1633.3874796951143, 1634.5831812887639, 1026.5175367577226, 1028.665683652816, 1042.3433237060603, 1046.26238501856, 1049.480507148951, 6396.71214795326, 6553.082568440422, 6556.737730490822, 6586.73653053882, 6698.665066746643, 7356.926430735732, 7361.079865016873, 7362.374198193106, 7363.300486453778, 7366.558006519519, 6675.396595327826, 6679.165104361955, 6686.5653732507235, 6689.632070236086, 6690.19717633887, 3539.9734077117346, 3577.4097273949506, 3828.0812604974603, 3883.49514563105, 3890.96109038906, 7320.414366850639, 7619.580923049286, 7620.466567340233, 7622.0, 7635.541867487857, 7459.142198647225, 7484.47608667399, 7486.064241969754, 7498.475106742476, 7503.399728021745, 7597.924020759832, 7617.162112167657, 7621.3902887769955, 7621.3902887769955, 7640.503367281221, 7275.672594733355, 7302.2697730226555, 7304.5252058616015, 7311.4150867929375, 7320.450966177452, 6917.446604271546, 7147.678354474165, 7166.53417527859, 7192.748253811092, 7264.927350726425, 6536.444402225864, 7422.25777422275, 7560.470767046254, 7665.156832748282, 7669.156392796899, 4816.518348165156, 4866.489018653132, 4868.245421959602, 4869.099216644932, 4878.853634390974, 7246.818829529304, 7257.52826066304, 7264.89102663471, 7273.199948005609, 7276.0, 6687.799366019027, 6987.056747339044, 6997.055397521473, 7009.228984811769, 7021.403180729695, 5685.374608793112, 6960.269171736894, 7004.719811207495, 7009.334113259232, 7009.894851577333, 6837.2820853809635, 6843.076184715, 6843.418309443753, 6849.931500685128, 6866.965665171842, 6775.728970841111, 7019.754308599175, 7025.543339682904, 7028.92646669244, 7038.120234970629, 6655.334466553306, 6778.186617605722, 6853.828654283683, 6863.588184708734, 6892.276310987475], "value": 5124.0048939942635, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.2055738687443, 725.6879541797025, 749.5540153608608, 756.1304499825201, 807.2492581898836, 14924.477643282473, 15155.318010689518, 15329.693406131884, 15355.769663455058, 15355.769663455058, 12083.818742718866, 13899.444022239124, 14352.21062841546, 14363.138211707304, 14387.280635968213, 709.0, 756.3117563017655, 769.9653515591784, 890.9064548222441, 923.9861402078974, 913.7350168451142, 916.9587368568397, 918.7289749523873, 920.8756817829608, 930.1953809954408, 969.0793745941384, 971.6404930175845, 971.7862070344513, 972.2027937091588, 991.9752006199831, 787.4802630264027, 792.9008873890764, 795.7970717467009, 796.38678217772, 981.9901800982005, 721.6752461392394, 723.1972510513326, 723.7141329174999, 724.1635910523354, 727.1347096954607, 12351.444185011605, 14822.0, 14836.629084272872, 14880.255987200704, 14908.925455372762, 11986.52053917851, 12153.696157596043, 12179.33013684247, 12249.326287054208, 12260.81608775869, 9925.2059835213, 10042.598296068149, 10043.24675649322, 10240.539175737034, 10439.582416703322, 3459.41189997701, 3472.878449254289, 3537.7700449470703, 3545.9290814183573, 3626.0934766308424, 63086.36913630851, 81773.77339339914, 82302.17697823005, 85160.14839851584, 85161.57419212926, 9439.056094390644, 9441.0, 9633.807323853624, 9758.512074396253, 9914.702558923243, 21176.047077881532, 21912.780872191073, 21940.89029554842, 21972.0, 21987.670184947237, 7222.96388518054, 7308.780736577911, 7421.8144546386775, 7439.665215065227, 7460.664270107858, 4082.4488694026522, 4129.876103716893, 4183.205191013698, 4286.571342865688, 4333.414988976511, 3362.4956256561713, 3416.5046068319953, 3417.6924076832706, 3424.0, 3425.1950791563963, 601.59392410123, 626.1096631569548, 629.6725702634604, 629.7606909374408, 634.5621521150391, 556.4852511426914, 584.2200662116122, 593.5073888672391, 606.169547719627, 623.0280762011276, 7887.0904933550755, 8026.438149329608, 8070.798230044295, 8214.671413143407, 8274.048484424247, 547.9260299859548, 575.1258087706746, 599.0505049496596, 612.2652816620074, 612.4763327355137, 3728.683061939711, 3780.0360907968843, 3801.2207497463505, 3816.38937769961, 3840.1743625120735, 88631.0, 98828.50585747026, 100209.99790002007, 100300.49549256767, 100486.49756751169, 70244.0, 70941.64529177321, 71037.2896271041, 71753.6412317935, 71888.64055679792, 62986.37013629805, 63131.0, 64545.67727161334, 65126.67436662786, 65750.67124664345, 7330.413566914741, 7342.082239720035, 7390.778276651709, 7478.4765066444825, 7758.262965018314, 45740.0, 46867.062658747316, 47545.762271188425, 47607.76196118997, 47731.76134119307, 64320.71358572896, 67734.0, 68515.65742171257, 69447.65276173587, 70466.59066818737, 10840.186985975926, 10895.618653347095, 11075.280106793032, 11432.371219583003, 11486.368249746343, 11758.03515473467, 12062.819057714318, 12073.456694448596, 12117.151799374311, 12134.75730485403, 11274.154438417316, 11564.074874009892, 11566.537338506365, 11568.13239007061, 11640.88359116398, 11374.260673056226, 11610.0, 11614.012808911337, 11688.649340519798, 12222.266664000188, 10956.506957186786, 11190.888091118984, 11542.480588373712, 11556.94221528887, 11584.594539191088, 9469.763255918657, 9627.663031793854, 9708.126268635857, 9739.026097390204, 9935.105840474393, 9310.48792316402, 9556.474393908124, 9590.952045239728, 9700.563474643517, 9853.901460985298], "value": 6823.150506778042, "unit": "score"}], "name": "dromaeo_css", "value": 7344.539856352946, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 119.0, 114.0, 112.0, 112.0, 113.0, 113.0, 112.0, 133.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 123.0, 142.0, 164.0, 124.0, 174.0, 171.0, 145.0, 162.0, 117.0], "value": 148.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 185.0, 186.0, 185.0, 184.0, 184.0, 183.0, 184.0, 183.0, 182.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 77.0, 74.0, 148.0, 55.0, 55.0, 60.0, 65.0, 140.0, 58.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 89.0, 140.0, 77.0, 78.0, 92.0, 123.0, 79.0, 79.0, 131.0], "value": 98.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 87.0, 83.0, 91.0, 130.0, 88.0, 84.0, 87.0, 85.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 92.0, 92.0, 92.0, 92.0, 122.0, 150.0, 96.0, 92.0, 91.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 119.0, 83.0, 85.0, 83.0, 83.0, 83.0, 86.0, 84.0, 85.0], "value": 88.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 65.0, 63.0, 63.0, 66.0, 64.0, 83.0, 59.0, 58.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 69.0, 73.0, 75.0, 69.0, 68.0, 65.0, 89.0, 97.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 80.0, 80.0, 81.0, 78.0, 80.0, 80.0, 82.0, 103.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 128.0, 127.0, 130.0, 108.0, 140.0, 136.0, 148.0, 149.0, 148.0], "value": 134.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 165.0, 165.0, 164.0, 166.0, 164.0, 193.0, 163.0, 165.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 84.0, 94.0, 92.0, 90.0, 93.0, 69.0, 67.0, 66.0], "value": 79.0, "unit": "score"}], "name": "kraken", "value": 1508.1999999999998, "alertThreshold": 2.0}]} 03:10:23 INFO - Return code: 0 03:10:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:10:23 ERROR - # TBPL SUCCESS # 03:10:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:10:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:10:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:10:23 INFO - 2016-06-03 03:10:23.900 screenresolution[1685:10494] starting screenresolution argv=screenresolution get 03:10:23 INFO - 2016-06-03 03:10:23.903 screenresolution[1685:10494] Display 0: 1600x1200x32@60 03:10:23 INFO - 2016-06-03 03:10:23.909 screenresolution[1686:10497] starting screenresolution argv=screenresolution list 03:10:23 INFO - Available Modes on Display 0 03:10:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:10:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:10:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:10:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:10:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:10:24 INFO - Intel Iris: 03:10:24 INFO - Chipset Model: Intel Iris 03:10:24 INFO - Type: GPU 03:10:24 INFO - Bus: Built-In 03:10:24 INFO - VRAM (Dynamic, Max): 1536 MB 03:10:24 INFO - Vendor: Intel (0x8086) 03:10:24 INFO - Device ID: 0x0a2e 03:10:24 INFO - Revision ID: 0x0009 03:10:24 INFO - Displays: 03:10:24 INFO - Display: 03:10:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:10:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:10:24 INFO - Display Serial Number: 1600x1200 60 03:10:24 INFO - Main Display: Yes 03:10:24 INFO - Mirror: Off 03:10:24 INFO - Online: Yes 03:10:24 INFO - Rotation: Supported 03:10:24 INFO - Return code: 0 03:10:24 INFO - Running post-action listener: _resource_record_post_action 03:10:24 INFO - Running post-run listener: _resource_record_post_run 03:10:25 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 32701952; Write bytes: 654797312; Read time: 322; Write time: 1981 03:10:25 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345137664; Write bytes: 345999360; Read time: 26981; Write time: 659 03:10:25 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 31776256; Write bytes: 298840576; Read time: 309; Write time: 1260 03:10:25 INFO - Running post-run listener: _upload_blobber_files 03:10:25 INFO - Blob upload gear active. 03:10:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:10:25 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 03:10:25 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'] 03:10:25 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 03:10:25 INFO - (blobuploader) - INFO - Open directory for files ... 03:10:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:10:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:10:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:10:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:10:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:10:26 INFO - (blobuploader) - INFO - Done attempting. 03:10:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:10:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:10:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:10:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:10:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:10:27 INFO - (blobuploader) - INFO - Done attempting. 03:10:27 INFO - (blobuploader) - INFO - Iteration through files over. 03:10:27 INFO - Return code: 0 03:10:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:10:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:10:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed62b1a3548de42ddcbff53a3ccdbd44a9a9b9d0b48942b816bb7bfce8dfa93cfca5d80f043a9fc19b4c58d9b9e307c5bfa40858226ddcf419209ebb28e25bdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0eb68c8b24224c513edcd921a740433c9335b0b54e018efadd83eb97cd34284c04dace8f1a09c573edefca6bf438864d83fa4ee8652580a6fa663a88beb76017"} 03:10:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:10:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:10:27 INFO - Contents: 03:10:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed62b1a3548de42ddcbff53a3ccdbd44a9a9b9d0b48942b816bb7bfce8dfa93cfca5d80f043a9fc19b4c58d9b9e307c5bfa40858226ddcf419209ebb28e25bdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0eb68c8b24224c513edcd921a740433c9335b0b54e018efadd83eb97cd34284c04dace8f1a09c573edefca6bf438864d83fa4ee8652580a6fa663a88beb76017"} 03:10:27 INFO - Running post-run listener: copy_logs_to_upload_dir 03:10:27 INFO - Copying logs to upload dir... 03:10:27 INFO - mkdir: /builds/slave/test/build/upload/logs 03:10:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.254853 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-06-03 03:10:27.672204) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 03:10:27.675496) ========= 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.Jv2LMAupjO/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.pAEb5FZmG6/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed62b1a3548de42ddcbff53a3ccdbd44a9a9b9d0b48942b816bb7bfce8dfa93cfca5d80f043a9fc19b4c58d9b9e307c5bfa40858226ddcf419209ebb28e25bdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0eb68c8b24224c513edcd921a740433c9335b0b54e018efadd83eb97cd34284c04dace8f1a09c573edefca6bf438864d83fa4ee8652580a6fa663a88beb76017"} build_url:https://queue.taskcluster.net/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011270 build_url: 'https://queue.taskcluster.net/v1/task/WE4SlDMmQ4K4jZR1miItZA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ed62b1a3548de42ddcbff53a3ccdbd44a9a9b9d0b48942b816bb7bfce8dfa93cfca5d80f043a9fc19b4c58d9b9e307c5bfa40858226ddcf419209ebb28e25bdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0eb68c8b24224c513edcd921a740433c9335b0b54e018efadd83eb97cd34284c04dace8f1a09c573edefca6bf438864d83fa4ee8652580a6fa663a88beb76017"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 03:10:27.706474) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 03:10:27.706806) ========= 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.Jv2LMAupjO/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.pAEb5FZmG6/Listeners TMPDIR=/var/folders/7f/3lpp9ct90ln73152f8wy_3th00000w/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.005160 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 03:10:27.755402) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 03:10:27.755758) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 03:10:27.756216) ========= ========= Total master_lag: 0.16 =========