builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0167 starttime: 1460799800.66 results: success (0) buildid: 20160416004025 builduid: 220d779b6ed14bf38826e5d94665b5ca revision: aca518830db248be63b2e879d12bfb899fc39ec1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-16 02:43:20.655785) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-16 02:43:20.656257) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-16 02:43:20.656612) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-16 02:43:20.723314) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 02:43:20.723614) ========= 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.ZKJpU17RVP/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.d75MTPUCzO/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-16 02:43:20-- 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% 165M=0s 2016-04-16 02:43:20 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.144671 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 02:43:20.928276) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 02:43:20.928597) ========= 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.ZKJpU17RVP/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.d75MTPUCzO/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.028468 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 02:43:21.014542) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-16 02:43:21.014942) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev aca518830db248be63b2e879d12bfb899fc39ec1 --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 aca518830db248be63b2e879d12bfb899fc39ec1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZKJpU17RVP/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.d75MTPUCzO/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-16 02:43:21,094 truncating revision to first 12 chars 2016-04-16 02:43:21,094 Setting DEBUG logging. 2016-04-16 02:43:21,094 attempt 1/10 2016-04-16 02:43:21,094 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/aca518830db2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-16 02:43:22,523 unpacking tar archive at: mozilla-aurora-aca518830db2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.670426 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-16 02:43:22.708907) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-16 02:43:22.709215) ========= script_repo_revision: aca518830db248be63b2e879d12bfb899fc39ec1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-16 02:43:22.709628) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-16 02:43:22.709998) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-16 02:43:22.723717) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 25 secs) (at 2016-04-16 02:43:22.724025) ========= /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.ZKJpU17RVP/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.d75MTPUCzO/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:43:22 INFO - MultiFileLogger online at 20160416 02:43:22 in /builds/slave/test 02:43:22 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:43:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:43:22 INFO - {'append_to_log': False, 02:43:22 INFO - 'base_work_dir': '/builds/slave/test', 02:43:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:43:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:43:22 INFO - 'branch': 'Mozilla-Aurora', 02:43:22 INFO - 'buildbot_json_path': 'buildprops.json', 02:43:22 INFO - 'config_files': ('talos/mac_config.py',), 02:43:22 INFO - 'default_actions': ('clobber', 02:43:22 INFO - 'read-buildbot-config', 02:43:22 INFO - 'download-and-extract', 02:43:22 INFO - 'populate-webroot', 02:43:22 INFO - 'create-virtualenv', 02:43:22 INFO - 'install', 02:43:22 INFO - 'run-tests'), 02:43:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:43:22 INFO - 'download_minidump_stackwalk': True, 02:43:22 INFO - 'download_symbols': 'ondemand', 02:43:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:43:22 INFO - 'tooltool.py': '/tools/tooltool.py', 02:43:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:43:22 INFO - '/tools/misc-python/virtualenv.py')}, 02:43:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:43:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:43:22 INFO - 'installer_path': 'installer.exe', 02:43:22 INFO - 'log_level': 'info', 02:43:22 INFO - 'log_name': 'talos', 02:43:22 INFO - 'log_to_console': True, 02:43:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:43:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:43:22 INFO - 'opt_config_files': (), 02:43:22 INFO - 'pip_index': False, 02:43:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:43:22 INFO - 'cmd': ('bash', 02:43:22 INFO - '-c', 02:43:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:43:22 INFO - 'enabled': True, 02:43:22 INFO - 'halt_on_failure': False, 02:43:22 INFO - 'name': 'check_screen_resolution'},), 02:43:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:43:22 INFO - 'cmd': ('bash', 02:43:22 INFO - '-c', 02:43:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:43:22 INFO - 'enabled': True, 02:43:22 INFO - 'halt_on_failure': False, 02:43:22 INFO - 'name': 'check_screen_resolution'},), 02:43:22 INFO - 'run_cmd_checks_enabled': True, 02:43:22 INFO - 'sps_profile': False, 02:43:22 INFO - 'sps_profile_interval': 0, 02:43:22 INFO - 'suite': 'dromaeojs', 02:43:22 INFO - 'system_bits': '32', 02:43:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:43:22 INFO - 'title': 't-yosemite-r7-0167', 02:43:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:43:22 INFO - 'use_talos_json': True, 02:43:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:43:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:43:22 INFO - 'work_dir': 'build'} 02:43:22 INFO - ##### 02:43:22 INFO - ##### Running clobber step. 02:43:22 INFO - ##### 02:43:22 INFO - Running pre-action listener: _resource_record_pre_action 02:43:22 INFO - Running main action method: clobber 02:43:22 INFO - rmtree: /builds/slave/test/build 02:43:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:43:25 INFO - Running post-action listener: _resource_record_post_action 02:43:25 INFO - ##### 02:43:25 INFO - ##### Running read-buildbot-config step. 02:43:25 INFO - ##### 02:43:25 INFO - Running pre-action listener: _resource_record_pre_action 02:43:25 INFO - Running main action method: read_buildbot_config 02:43:25 INFO - Using buildbot properties: 02:43:25 INFO - { 02:43:25 INFO - "project": "", 02:43:25 INFO - "product": "firefox", 02:43:25 INFO - "script_repo_revision": "production", 02:43:25 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:43:25 INFO - "repository": "", 02:43:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:43:25 INFO - "buildid": "20160416004025", 02:43:25 INFO - "stage_platform": "macosx64", 02:43:25 INFO - "basedir": "/builds/slave/test", 02:43:25 INFO - "nightly_build": "True", 02:43:25 INFO - "buildnumber": 285, 02:43:25 INFO - "slavename": "t-yosemite-r7-0167", 02:43:25 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:43:25 INFO - "platform": "yosemite_r7", 02:43:25 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:43:25 INFO - "branch": "mozilla-aurora", 02:43:25 INFO - "repo_path": "releases/mozilla-aurora", 02:43:25 INFO - "revision": "aca518830db248be63b2e879d12bfb899fc39ec1", 02:43:25 INFO - "pgo_build": "False", 02:43:25 INFO - "builduid": "220d779b6ed14bf38826e5d94665b5ca", 02:43:25 INFO - "slavebuilddir": "test" 02:43:25 INFO - } 02:43:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 02:43:25 INFO - Running post-action listener: _resource_record_post_action 02:43:25 INFO - ##### 02:43:25 INFO - ##### Running download-and-extract step. 02:43:25 INFO - ##### 02:43:25 INFO - Running pre-action listener: _resource_record_pre_action 02:43:25 INFO - Running main action method: download_and_extract 02:43:25 INFO - mkdir: /builds/slave/test/build/tests 02:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:43:25 INFO - https://queue.taskcluster.net/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:43:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 02:43:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 02:43:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 02:43:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 02:43:40 INFO - Downloaded 1277 bytes. 02:43:40 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 02:43:40 INFO - Using the following test package requirements: 02:43:40 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 02:43:40 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:43:40 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 02:43:40 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:43:40 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 02:43:40 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:43:40 INFO - u'jsshell-mac.zip'], 02:43:40 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:43:40 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 02:43:40 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 02:43:40 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:43:40 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 02:43:40 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:43:40 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 02:43:40 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:43:40 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 02:43:40 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 02:43:40 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 02:43:40 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 02:43:40 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:43:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:43:40 INFO - https://queue.taskcluster.net/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:43:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 02:43:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 02:43:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 02:43:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:44:00 INFO - Downloaded 17911147 bytes. 02:44:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:44:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:44:04 INFO - Return code: 0 02:44:04 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:44:04 INFO - https://queue.taskcluster.net/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:44:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 02:44:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 02:44:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 02:44:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:44:05 INFO - Downloaded 17911147 bytes. 02:44:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:44:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:44:09 INFO - Return code: 0 02:44:09 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:44:09 INFO - https://queue.taskcluster.net/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:44:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 02:44:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 02:44:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 02:44:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:44:10 INFO - Downloaded 10845168 bytes. 02:44:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:44:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:44:10 INFO - Return code: 0 02:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:44:10 INFO - https://queue.taskcluster.net/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:44:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 02:44:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 02:44:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 02:44:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 02:44:18 INFO - Downloaded 115312704 bytes. 02:44:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 02:44:18 INFO - mkdir: /builds/slave/test/properties 02:44:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:44:18 INFO - Writing to file /builds/slave/test/properties/build_url 02:44:18 INFO - Contents: 02:44:18 INFO - build_url:https://queue.taskcluster.net/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 02:44:19 INFO - Running post-action listener: _resource_record_post_action 02:44:19 INFO - Running post-action listener: set_extra_try_arguments 02:44:19 INFO - ##### 02:44:19 INFO - ##### Running populate-webroot step. 02:44:19 INFO - ##### 02:44:19 INFO - Running pre-action listener: _resource_record_pre_action 02:44:19 INFO - Running main action method: populate_webroot 02:44:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:44:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:44:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:44:19 INFO - '--tppagecycles', 02:44:19 INFO - '7'], 02:44:19 INFO - 'tests': ['tsvgm']}}, 02:44:19 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 02:44:19 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 02:44:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:44:19 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 02:44:19 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 02:44:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:44:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:19 INFO - 'talos_options': ['--e10s'], 02:44:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:19 INFO - 'tests': ['damp', 'tps']}, 02:44:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:19 INFO - 'talos_options': ['--e10s'], 02:44:19 INFO - 'tests': ['damp', 'tps']}, 02:44:19 INFO - 'g3': {'tests': ['dromaeo_dom']}, 02:44:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:44:19 INFO - 'other': {'tests': ['a11yr', 02:44:19 INFO - 'ts_paint', 02:44:19 INFO - 'tpaint', 02:44:19 INFO - 'sessionrestore', 02:44:19 INFO - 'sessionrestore_no_auto_restore', 02:44:19 INFO - 'tabpaint']}, 02:44:19 INFO - 'other-e10s': {'talos_options': ['--e10s'], 02:44:19 INFO - 'tests': ['a11yr', 02:44:19 INFO - 'ts_paint', 02:44:19 INFO - 'tpaint', 02:44:19 INFO - 'sessionrestore', 02:44:19 INFO - 'sessionrestore_no_auto_restore', 02:44:19 INFO - 'tabpaint']}, 02:44:19 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 02:44:19 INFO - 'tests': ['a11yr', 02:44:19 INFO - 'ts_paint', 02:44:19 INFO - 'tpaint', 02:44:19 INFO - 'sessionrestore', 02:44:19 INFO - 'sessionrestore_no_auto_restore', 02:44:19 INFO - 'tabpaint']}, 02:44:19 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 02:44:19 INFO - 'tests': ['a11yr', 02:44:19 INFO - 'ts_paint', 02:44:19 INFO - 'tpaint', 02:44:19 INFO - 'sessionrestore', 02:44:19 INFO - 'sessionrestore_no_auto_restore', 02:44:19 INFO - 'tabpaint']}, 02:44:19 INFO - 'other_l64': {'tests': ['a11yr', 02:44:19 INFO - 'ts_paint', 02:44:19 INFO - 'tpaint', 02:44:19 INFO - 'sessionrestore', 02:44:19 INFO - 'sessionrestore_no_auto_restore', 02:44:19 INFO - 'tabpaint']}, 02:44:19 INFO - 'other_nol64': {'tests': ['a11yr', 02:44:19 INFO - 'ts_paint', 02:44:19 INFO - 'tpaint', 02:44:19 INFO - 'sessionrestore', 02:44:19 INFO - 'sessionrestore_no_auto_restore', 02:44:19 INFO - 'tabpaint']}, 02:44:19 INFO - 'svgr': {'tests': ['tsvgx', 02:44:19 INFO - 'tsvgr_opacity', 02:44:19 INFO - 'tart', 02:44:19 INFO - 'tscrollx', 02:44:19 INFO - 'cart']}, 02:44:19 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 02:44:19 INFO - 'tests': ['tsvgx', 02:44:19 INFO - 'tsvgr_opacity', 02:44:19 INFO - 'tart', 02:44:19 INFO - 'tscrollx', 02:44:19 INFO - 'cart']}, 02:44:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:19 INFO - 'tests': ['tp5o']}, 02:44:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:19 INFO - 'talos_options': ['--e10s'], 02:44:19 INFO - 'tests': ['tp5o']}, 02:44:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:19 INFO - 'talos_options': ['--xperf_path', 02:44:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:19 INFO - 'tests': ['tp5n']}, 02:44:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:19 INFO - 'talos_options': ['--e10s', 02:44:19 INFO - '--xperf_path', 02:44:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:19 INFO - 'tests': ['tp5n']}}, 02:44:19 INFO - 'talos.zip': {'path': '', 02:44:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:44:19 INFO - Running post-action listener: _resource_record_post_action 02:44:19 INFO - ##### 02:44:19 INFO - ##### Running create-virtualenv step. 02:44:19 INFO - ##### 02:44:19 INFO - Running pre-action listener: _resource_record_pre_action 02:44:19 INFO - Running main action method: create_virtualenv 02:44:19 INFO - Creating virtualenv /builds/slave/test/build/venv 02:44:19 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:44:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:44:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:44:19 INFO - Using real prefix '/tools/python27' 02:44:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:44:20 INFO - Installing distribute.............................................................................................................................................................................................done. 02:44:23 INFO - Installing pip.................done. 02:44:23 INFO - Return code: 0 02:44:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:44: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:44:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44: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:44:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bf4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9588d86990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a98d30>, '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 0x1008fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8fb20>, '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.ZKJpU17RVP/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.d75MTPUCzO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:44:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:44:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:44:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKJpU17RVP/Render', 02:44:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:23 INFO - 'HOME': '/Users/cltbld', 02:44:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:23 INFO - 'LOGNAME': 'cltbld', 02:44:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:23 INFO - 'MOZ_NO_REMOTE': '1', 02:44:23 INFO - 'NO_EM_RESTART': '1', 02:44:23 INFO - 'PAGER': '/bin/cat', 02:44:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:23 INFO - 'PWD': '/builds/slave/test', 02:44:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:23 INFO - 'SHELL': '/bin/bash', 02:44:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d75MTPUCzO/Listeners', 02:44:23 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 02:44:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:23 INFO - 'USER': 'cltbld', 02:44:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:23 INFO - 'XPC_FLAGS': '0x0', 02:44:23 INFO - 'XPC_SERVICE_NAME': '0', 02:44:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:23 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:44:23 INFO - Cleaning up... 02:44:23 INFO - Return code: 0 02:44:23 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:44: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:44:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44: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:44:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100bf4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9588d86990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a98d30>, '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 0x1008fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8fb20>, '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.ZKJpU17RVP/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.d75MTPUCzO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:44:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:44:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:44:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKJpU17RVP/Render', 02:44:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:23 INFO - 'HOME': '/Users/cltbld', 02:44:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:23 INFO - 'LOGNAME': 'cltbld', 02:44:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:23 INFO - 'MOZ_NO_REMOTE': '1', 02:44:23 INFO - 'NO_EM_RESTART': '1', 02:44:23 INFO - 'PAGER': '/bin/cat', 02:44:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:23 INFO - 'PWD': '/builds/slave/test', 02:44:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:23 INFO - 'SHELL': '/bin/bash', 02:44:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d75MTPUCzO/Listeners', 02:44:23 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 02:44:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:23 INFO - 'USER': 'cltbld', 02:44:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:23 INFO - 'XPC_FLAGS': '0x0', 02:44:23 INFO - 'XPC_SERVICE_NAME': '0', 02:44:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:23 INFO - Downloading/unpacking psutil>=0.7.1 02:44:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:44:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:26 INFO - Installing collected packages: psutil 02:44:26 INFO - Running setup.py install for psutil 02:44:26 INFO - building 'psutil._psutil_osx' extension 02:44:26 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:44:27 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:44:27 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:44:27 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:44:27 INFO - building 'psutil._psutil_posix' extension 02:44:27 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:44:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:44:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:44:27 INFO - ^ 02:44:27 INFO - 1 warning generated. 02:44:27 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:44:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:28 INFO - Successfully installed psutil 02:44:28 INFO - Cleaning up... 02:44:28 INFO - Return code: 0 02:44:28 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:44:28 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:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:28 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:44:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:28 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 0x100bf4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9588d86990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a98d30>, '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 0x1008fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8fb20>, '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.ZKJpU17RVP/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.d75MTPUCzO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:44:28 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:44:28 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:44:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKJpU17RVP/Render', 02:44:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:28 INFO - 'HOME': '/Users/cltbld', 02:44:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:28 INFO - 'LOGNAME': 'cltbld', 02:44:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:28 INFO - 'MOZ_NO_REMOTE': '1', 02:44:28 INFO - 'NO_EM_RESTART': '1', 02:44:28 INFO - 'PAGER': '/bin/cat', 02:44:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:28 INFO - 'PWD': '/builds/slave/test', 02:44:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:28 INFO - 'SHELL': '/bin/bash', 02:44:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d75MTPUCzO/Listeners', 02:44:28 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 02:44:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:28 INFO - 'USER': 'cltbld', 02:44:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:28 INFO - 'XPC_FLAGS': '0x0', 02:44:28 INFO - 'XPC_SERVICE_NAME': '0', 02:44:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:28 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:31 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:44:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:44:31 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:44:31 INFO - Installing collected packages: mozsystemmonitor 02:44:31 INFO - Running setup.py install for mozsystemmonitor 02:44:31 INFO - Successfully installed mozsystemmonitor 02:44:31 INFO - Cleaning up... 02:44:31 INFO - Return code: 0 02:44:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:31 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 0x100bf4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9588d86990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a98d30>, '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 0x1008fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8fb20>, '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.ZKJpU17RVP/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.d75MTPUCzO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:44:31 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:44:31 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:44:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKJpU17RVP/Render', 02:44:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:31 INFO - 'HOME': '/Users/cltbld', 02:44:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:31 INFO - 'LOGNAME': 'cltbld', 02:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:31 INFO - 'MOZ_NO_REMOTE': '1', 02:44:31 INFO - 'NO_EM_RESTART': '1', 02:44:31 INFO - 'PAGER': '/bin/cat', 02:44:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:31 INFO - 'PWD': '/builds/slave/test', 02:44:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:31 INFO - 'SHELL': '/bin/bash', 02:44:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d75MTPUCzO/Listeners', 02:44:31 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 02:44:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:31 INFO - 'USER': 'cltbld', 02:44:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:31 INFO - 'XPC_FLAGS': '0x0', 02:44:31 INFO - 'XPC_SERVICE_NAME': '0', 02:44:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:31 INFO - Downloading/unpacking blobuploader==1.2.4 02:44:31 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:44:31 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:44:31 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:44:31 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:44:31 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:44:31 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:44:34 INFO - Downloading blobuploader-1.2.4.tar.gz 02:44:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:44:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:44:34 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:44:34 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:44:34 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:44:34 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:44:34 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:44:34 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:44:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:44:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:44:34 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:44:34 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:44:34 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:44:34 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:44:34 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:44:34 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:44:35 INFO - Downloading docopt-0.6.1.tar.gz 02:44:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:44:35 INFO - Installing collected packages: blobuploader, requests, docopt 02:44:35 INFO - Running setup.py install for blobuploader 02:44:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:44:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:44:35 INFO - Running setup.py install for requests 02:44:36 INFO - Running setup.py install for docopt 02:44:36 INFO - Successfully installed blobuploader requests docopt 02:44:36 INFO - Cleaning up... 02:44:36 INFO - Return code: 0 02:44:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:36 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:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:36 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:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:36 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 0x100bf4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9588d86990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a98d30>, '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 0x1008fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8fb20>, '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.ZKJpU17RVP/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.d75MTPUCzO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:44:36 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:44:36 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:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKJpU17RVP/Render', 02:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:36 INFO - 'HOME': '/Users/cltbld', 02:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:36 INFO - 'LOGNAME': 'cltbld', 02:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:36 INFO - 'MOZ_NO_REMOTE': '1', 02:44:36 INFO - 'NO_EM_RESTART': '1', 02:44:36 INFO - 'PAGER': '/bin/cat', 02:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:36 INFO - 'PWD': '/builds/slave/test', 02:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:36 INFO - 'SHELL': '/bin/bash', 02:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d75MTPUCzO/Listeners', 02:44:36 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 02:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:36 INFO - 'USER': 'cltbld', 02:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:36 INFO - 'XPC_FLAGS': '0x0', 02:44:36 INFO - 'XPC_SERVICE_NAME': '0', 02:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:44:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-m0pfur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:44:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-gfHKAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:44:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-7ziPir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:44:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-hkOndW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:44:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:44:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-7lGIeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:44:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-t9zVAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:44:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-QFiSaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:44:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-sBvp9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:44:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-KikUm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:44:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Ei_xIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:44:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-74bk7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:44:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-cLv790-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:44:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-oo_R_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:44:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Zi3rh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-dhs5XT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:44:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-3UoFrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:44:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-nKemx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:44:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:44:38 INFO - Running setup.py install for manifestparser 02:44:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:44:38 INFO - Running setup.py install for mozcrash 02:44:38 INFO - Running setup.py install for mozdebug 02:44:38 INFO - Running setup.py install for mozdevice 02:44:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:44:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:44:38 INFO - Running setup.py install for mozfile 02:44:39 INFO - Running setup.py install for mozhttpd 02:44:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:44:39 INFO - Running setup.py install for mozinfo 02:44:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:44:39 INFO - Running setup.py install for mozInstall 02:44:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:44:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:44:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:44:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:44:39 INFO - Running setup.py install for mozleak 02:44:39 INFO - Running setup.py install for mozlog 02:44:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:44:39 INFO - Running setup.py install for moznetwork 02:44:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:44:39 INFO - Running setup.py install for mozprocess 02:44:40 INFO - Running setup.py install for mozprofile 02:44:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:44:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:44:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:44:40 INFO - Running setup.py install for mozrunner 02:44:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:44:40 INFO - Running setup.py install for mozscreenshot 02:44:40 INFO - Running setup.py install for moztest 02:44:40 INFO - Running setup.py install for mozversion 02:44:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:44:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:44:40 INFO - Cleaning up... 02:44:40 INFO - Return code: 0 02:44:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:40 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 0x100bf4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9588d86990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a98d30>, '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 0x1008fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8fb20>, '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.ZKJpU17RVP/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.d75MTPUCzO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:44:40 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:44:40 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:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKJpU17RVP/Render', 02:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:40 INFO - 'HOME': '/Users/cltbld', 02:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:40 INFO - 'LOGNAME': 'cltbld', 02:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:40 INFO - 'MOZ_NO_REMOTE': '1', 02:44:40 INFO - 'NO_EM_RESTART': '1', 02:44:40 INFO - 'PAGER': '/bin/cat', 02:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:40 INFO - 'PWD': '/builds/slave/test', 02:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:40 INFO - 'SHELL': '/bin/bash', 02:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d75MTPUCzO/Listeners', 02:44:40 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 02:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:40 INFO - 'USER': 'cltbld', 02:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:40 INFO - 'XPC_FLAGS': '0x0', 02:44:40 INFO - 'XPC_SERVICE_NAME': '0', 02:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:44:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-oggDG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:44:41 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:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:44:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-q4Ogxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:44:41 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:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:44:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-bazXrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:44:41 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:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:44:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-svUvsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:44:41 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:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:44:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-SlDz8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:44:41 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:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:44:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-U90zyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:44:41 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:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:44:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-CjdEaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:44:41 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:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:44:41 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-MJL1vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:44:42 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:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:44:42 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-xJxwKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:44:42 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:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:44:42 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-0LUyEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:44:42 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:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:44:42 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-KGLool-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:44:42 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:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:44:42 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-p8e0sG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:44:42 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:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:44:42 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-wTc0CT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:44:42 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:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:44:42 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-y_Tmyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:44:42 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:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:42 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-htl9Wb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:42 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:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:44:42 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-TCTG89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:44:42 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:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:44:42 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-hXa2af-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:44:42 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:44:42 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:44:42 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:44:42 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:44:42 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:44:42 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:44:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:45 INFO - Downloading blessings-1.6.tar.gz 02:44:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:44:45 INFO - Installing collected packages: blessings 02:44:45 INFO - Running setup.py install for blessings 02:44:45 INFO - Successfully installed blessings 02:44:45 INFO - Cleaning up... 02:44:45 INFO - Return code: 0 02:44:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:44:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:44:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:44:45 INFO - Reading from file tmpfile_stdout 02:44:45 INFO - Current package versions: 02:44:45 INFO - blessings == 1.6 02:44:45 INFO - blobuploader == 1.2.4 02:44:45 INFO - docopt == 0.6.1 02:44:45 INFO - manifestparser == 1.1 02:44:45 INFO - mozInstall == 1.12 02:44:45 INFO - mozcrash == 0.17 02:44:45 INFO - mozdebug == 0.1 02:44:45 INFO - mozdevice == 0.48 02:44:45 INFO - mozfile == 1.2 02:44:45 INFO - mozhttpd == 0.7 02:44:45 INFO - mozinfo == 0.9 02:44:45 INFO - mozleak == 0.1 02:44:45 INFO - mozlog == 3.1 02:44:45 INFO - moznetwork == 0.27 02:44:45 INFO - mozprocess == 0.22 02:44:45 INFO - mozprofile == 0.28 02:44:45 INFO - mozrunner == 6.11 02:44:45 INFO - mozscreenshot == 0.1 02:44:45 INFO - mozsystemmonitor == 0.0 02:44:45 INFO - moztest == 0.7 02:44:45 INFO - mozversion == 1.4 02:44:45 INFO - psutil == 3.1.1 02:44:45 INFO - requests == 1.2.3 02:44:45 INFO - wsgiref == 0.1.2 02:44:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44: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:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44: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:44:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:45 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 0x100bf4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9588d86990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a98d30>, '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 0x1008fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8fb20>, '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.ZKJpU17RVP/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.d75MTPUCzO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:44:45 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:44:45 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:44:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKJpU17RVP/Render', 02:44:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:45 INFO - 'HOME': '/Users/cltbld', 02:44:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:45 INFO - 'LOGNAME': 'cltbld', 02:44:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:45 INFO - 'MOZ_NO_REMOTE': '1', 02:44:45 INFO - 'NO_EM_RESTART': '1', 02:44:45 INFO - 'PAGER': '/bin/cat', 02:44:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:45 INFO - 'PWD': '/builds/slave/test', 02:44:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:45 INFO - 'SHELL': '/bin/bash', 02:44:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d75MTPUCzO/Listeners', 02:44:45 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 02:44:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:45 INFO - 'USER': 'cltbld', 02:44:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:45 INFO - 'XPC_FLAGS': '0x0', 02:44:45 INFO - 'XPC_SERVICE_NAME': '0', 02:44:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:46 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:44:46 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:44:46 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:44:46 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:44:46 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:44:46 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:44:46 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:44:46 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:44:46 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:44:46 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:44:46 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:44:46 INFO - Cleaning up... 02:44:46 INFO - Return code: 0 02:44:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:44:46 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:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:46 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:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:46 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 0x100bf4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9588d86990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a98d30>, '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 0x1008fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8fb20>, '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.ZKJpU17RVP/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.d75MTPUCzO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:44:46 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:44:46 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:44:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKJpU17RVP/Render', 02:44:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:46 INFO - 'HOME': '/Users/cltbld', 02:44:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:46 INFO - 'LOGNAME': 'cltbld', 02:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:46 INFO - 'MOZ_NO_REMOTE': '1', 02:44:46 INFO - 'NO_EM_RESTART': '1', 02:44:46 INFO - 'PAGER': '/bin/cat', 02:44:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:46 INFO - 'PWD': '/builds/slave/test', 02:44:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:46 INFO - 'SHELL': '/bin/bash', 02:44:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d75MTPUCzO/Listeners', 02:44:46 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 02:44:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:46 INFO - 'USER': 'cltbld', 02:44:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:46 INFO - 'XPC_FLAGS': '0x0', 02:44:46 INFO - 'XPC_SERVICE_NAME': '0', 02:44:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:46 INFO - Downloading/unpacking jsonschema 02:44: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:44: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:44: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:44: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:44: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:44: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:44:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:44:49 INFO - Downloading/unpacking functools32 (from jsonschema) 02:44:49 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:44:49 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:44:49 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:44:49 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:44:49 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:44:49 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:44:49 INFO - Downloading functools32-3.2.3-2.tar.gz 02:44:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:44:49 INFO - warning: no files found matching '*.txt' 02:44:49 INFO - no previously-included directories found matching 'build' 02:44:49 INFO - no previously-included directories found matching 'dist' 02:44:49 INFO - no previously-included directories found matching '.git*' 02:44:49 INFO - Installing collected packages: jsonschema, functools32 02:44:49 INFO - Running setup.py install for functools32 02:44:49 INFO - warning: no files found matching '*.txt' 02:44:49 INFO - no previously-included directories found matching 'build' 02:44:49 INFO - no previously-included directories found matching 'dist' 02:44:49 INFO - no previously-included directories found matching '.git*' 02:44:49 INFO - Successfully installed jsonschema functools32 02:44:49 INFO - Cleaning up... 02:44:49 INFO - Return code: 0 02:44:49 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:49 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 0x100bf4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bfb4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9588d86990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a98d30>, '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 0x1008fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8fb20>, '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.ZKJpU17RVP/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.d75MTPUCzO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:44:49 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:44:49 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:44:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKJpU17RVP/Render', 02:44:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:49 INFO - 'HOME': '/Users/cltbld', 02:44:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:49 INFO - 'LOGNAME': 'cltbld', 02:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:49 INFO - 'MOZ_NO_REMOTE': '1', 02:44:49 INFO - 'NO_EM_RESTART': '1', 02:44:49 INFO - 'PAGER': '/bin/cat', 02:44:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:49 INFO - 'PWD': '/builds/slave/test', 02:44:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:49 INFO - 'SHELL': '/bin/bash', 02:44:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d75MTPUCzO/Listeners', 02:44:49 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 02:44:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:49 INFO - 'USER': 'cltbld', 02:44:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:49 INFO - 'XPC_FLAGS': '0x0', 02:44:49 INFO - 'XPC_SERVICE_NAME': '0', 02:44:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:50 INFO - Downloading/unpacking flake8 02:44: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:44: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:44: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:44: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:44: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:44: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:44:52 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:44:52 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:44:52 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:44:52 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:44:52 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:44:52 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:44:52 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:44:52 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:44:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:44:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:44:52 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:44:52 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:44:52 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:44:52 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:44:52 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:44:52 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:44:53 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:44: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:44: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:44: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:44: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:44: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:44: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:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:44:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:44:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:44:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:44:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:44:53 INFO - no previously-included directories found matching 'docs/_build' 02:44:53 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:44:53 INFO - Running setup.py install for pep8 02:44:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:44:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:44:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:44:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:44:53 INFO - no previously-included directories found matching 'docs/_build' 02:44:53 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:44:54 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:44:54 INFO - Cleaning up... 02:44:54 INFO - Return code: 0 02:44:54 INFO - Running post-action listener: _resource_record_post_action 02:44:54 INFO - Running post-action listener: _start_resource_monitoring 02:44:54 INFO - Starting resource monitoring. 02:44:54 INFO - ##### 02:44:54 INFO - ##### Running install step. 02:44:54 INFO - ##### 02:44:54 INFO - Running pre-action listener: _resource_record_pre_action 02:44:54 INFO - Running main action method: install 02:44:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:44:54 INFO - Reading from file tmpfile_stdout 02:44:54 INFO - Detecting whether we're running mozinstall >=1.0... 02:44:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:44:54 INFO - Reading from file tmpfile_stdout 02:44:54 INFO - Output received: 02:44:54 INFO - Usage: mozinstall [options] installer 02:44:54 INFO - Options: 02:44:54 INFO - -h, --help show this help message and exit 02:44:54 INFO - -d DEST, --destination=DEST 02:44:54 INFO - Directory to install application into. [default: 02:44:54 INFO - "/builds/slave/test"] 02:44:54 INFO - --app=APP Application being installed. [default: firefox] 02:44:54 INFO - mkdir: /builds/slave/test/build/application 02:44:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:45:26 INFO - Reading from file tmpfile_stdout 02:45:26 INFO - Output received: 02:45:26 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:45:26 INFO - Running post-action listener: _resource_record_post_action 02:45:26 INFO - ##### 02:45:26 INFO - ##### Running run-tests step. 02:45:26 INFO - ##### 02:45:26 INFO - Running pre-action listener: _resource_record_pre_action 02:45:26 INFO - Running main action method: run_tests 02:45:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:45:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:45:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:45:26 INFO - 2016-04-16 02:45:26.420 screenresolution[1646:8416] starting screenresolution argv=screenresolution get 02:45:26 INFO - 2016-04-16 02:45:26.423 screenresolution[1646:8416] Display 0: 1600x1200x32@60 02:45:26 INFO - 2016-04-16 02:45:26.429 screenresolution[1647:8419] starting screenresolution argv=screenresolution list 02:45:26 INFO - Available Modes on Display 0 02:45:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:45:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:45:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:45:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:45:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:45:26 INFO - Intel Iris: 02:45:26 INFO - Chipset Model: Intel Iris 02:45:26 INFO - Type: GPU 02:45:26 INFO - Bus: Built-In 02:45:26 INFO - VRAM (Dynamic, Max): 1536 MB 02:45:26 INFO - Vendor: Intel (0x8086) 02:45:26 INFO - Device ID: 0x0a2e 02:45:26 INFO - Revision ID: 0x0009 02:45:26 INFO - Displays: 02:45:26 INFO - Display: 02:45:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:45:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:45:26 INFO - Display Serial Number: 1600x1200 60 02:45:26 INFO - Main Display: Yes 02:45:26 INFO - Mirror: Off 02:45:26 INFO - Online: Yes 02:45:26 INFO - Rotation: Supported 02:45:26 INFO - Return code: 0 02:45:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:45:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:45:26 INFO - Python 2.7.3 02:45:26 INFO - Return code: 0 02:45:26 INFO - grabbing minidump binary from tooltool 02:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:26 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 0x100a98d30>, '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 0x1008fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:45:26 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:45:26 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:45:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:45:26 INFO - Return code: 0 02:45:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:45:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:45:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:45:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:45:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:45:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:45:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:45:27 INFO - Return code: 0 02:45:27 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/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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:45:27 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/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0167 --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:45:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKJpU17RVP/Render', 02:45:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:27 INFO - 'HOME': '/Users/cltbld', 02:45:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:27 INFO - 'LOGNAME': 'cltbld', 02:45:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:45:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:27 INFO - 'MOZ_NO_REMOTE': '1', 02:45:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:27 INFO - 'NO_EM_RESTART': '1', 02:45:27 INFO - 'PAGER': '/bin/cat', 02:45:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:27 INFO - 'PWD': '/builds/slave/test', 02:45:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:27 INFO - 'SHELL': '/bin/bash', 02:45:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d75MTPUCzO/Listeners', 02:45:27 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 02:45:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:27 INFO - 'USER': 'cltbld', 02:45:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:27 INFO - 'XPC_FLAGS': '0x0', 02:45:27 INFO - 'XPC_SERVICE_NAME': '0', 02:45:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:27 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/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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:45:28 INFO - mozversion application_buildid: 20160416004025 02:45:28 INFO - mozversion application_changeset: aca518830db248be63b2e879d12bfb899fc39ec1 02:45:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:45:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:45:28 INFO - mozversion application_name: Firefox 02:45:28 INFO - mozversion application_remotingname: firefox-dev 02:45:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:45:28 INFO - mozversion application_vendor: Mozilla 02:45:28 INFO - mozversion application_version: 47.0a2 02:45:28 INFO - mozversion platform_buildid: 20160416004025 02:45:28 INFO - mozversion platform_changeset: aca518830db248be63b2e879d12bfb899fc39ec1 02:45:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:45:28 INFO - mozversion platform_version: 47.0a2 02:45:28 INFO - using testdate: 1460799928 02:45:28 INFO - actual date: 1460799928 02:45:28 INFO - starting webserver on 'localhost:49277' 02:45:28 INFO - SUITE-START | Running 2 tests 02:45:28 INFO - TEST-START | dromaeo_css 02:45:28 INFO - operating with platform_type : mac_ 02:45:28 INFO - Initialising browser for dromaeo_css test... 02:45:28 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpt5zpE2/profile http://localhost:49277/getInfo.html) 02:45:30 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 02:45:30 INFO - PROCESS | 1665 | colorDepth:24 02:45:30 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 02:45:30 INFO - PROCESS | 1665 | __metrics 02:45:30 INFO - PROCESS | 1665 | 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:45:30 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:45:31 INFO - TEST-INFO | 1665: exit 0 02:45:31 INFO - Browser initialized. 02:45:31 INFO - Running cycle 1/1 for dromaeo_css test... 02:45:31 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpt5zpE2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:45:43 INFO - PROCESS | 1669 | RSS: Main: 207425536 02:45:43 INFO - PROCESS | 1669 | 02:46:34 INFO - PROCESS | 1669 | 1460799994424 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:48:44 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 02:48:45 INFO - PROCESS | 1669 | RSS: Main: 300687360 02:48:45 INFO - PROCESS | 1669 | 02:51:48 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 02:51:48 INFO - PROCESS | 1669 | RSS: Main: 343158784 02:51:48 INFO - PROCESS | 1669 | 02:54:50 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 02:54:51 INFO - PROCESS | 1669 | RSS: Main: 346419200 02:54:51 INFO - PROCESS | 1669 | 02:57:53 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 02:57:54 INFO - PROCESS | 1669 | RSS: Main: 304390144 02:57:54 INFO - PROCESS | 1669 | 03:00:57 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 03:00:57 INFO - PROCESS | 1669 | RSS: Main: 308404224 03:00:57 INFO - PROCESS | 1669 | 03:04:00 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 03:04:00 INFO - PROCESS | 1669 | RSS: Main: 360722432 03:04:00 INFO - PROCESS | 1669 | 03:04:00 INFO - PROCESS | 1669 | __start_tp_report 03:04:00 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 03:04:00 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 03:04:00 INFO - PROCESS | 1669 | |i|pagename|runs| 03:04:00 INFO - PROCESS | 1669 | |0;dojo.html;3414.112330793994;3504.929901401971;3506.018314871835;3531.6644918732713;3566.8038257895805;4891.99714058618;5598.860028499279;5658.49073583378;5675.120356344768;5706.543476521879;5584.553235741141;5754.510866576346;5761.395053519383;5773.220615216945;5773.971130144354;5168.48315168482;5202.167653175493;5229.947700523003;5230.979958908005;5237.214417837316;5314.92027619586;5316.149416093426;5325.7869685212545;5327.413984461706;5328.680279183242;5214.139666954967;5288.286081379022;5307.469253074681;5307.946920530803;5310.283111779918;5288.338957630296;5328.174133009409;5332.813351532678;5338.012467693489;5360;5039.0929632666475;5234.738263086831;5235.136202526579;5253.369595648508;5263.605229607757;5584.246126772915;5640.09758438652;5642.04085305499;5650.124230744241;5658.179563963202;5563.916541251884;6089.847753806146;6292.748290068392;6311.274203466614;6342.5243106767175;5976;6258.906116408258;6314.684265786692;6349.714262858136;6361.872762544724;5833.32916714579;5846.649201047951;5904.704764761831;5909.379515150933;5910.527157827363;6235.75056997715;6267.091271765568;6277.623342599459;6308.905366419507;6311.96844015777;6169.907451388232;6199.194104766351;6205.410486003822;6210.378962103844;6225.875482490324;5795.304563452412;6222.066689996446;6340.953742632506;6369.745210191633;6372.4583410409705;6010;6120.479759220516;6153.907691384633;6265.154204182468;6275.121482992385;6247.2815626202655;6258.186435763413;6283.717232724447;6297.811065668037;6301.40136687014;4911.287863259808;4913.484084171182;4917.213245880642;4917.680350777188;4923.064617722621;4327.3725309830525;4458.509563947963;4463.486699029653;4472.642188624902;4476.955230447719;5605.775768969277;5643.633163844337;5643.830685079454;5655.575831812631;5799.043157878987;5475.479829416199;6211.006239001738;6329.651869147241;6340.714667839959;6366.840828979312;5680.886382272414;5704.71476426177;5726.685032323262;5737.139429085671;5749.8275051748515;5161.5870730341485;5342.492463215988;5379.220013098157;5401.756920938567;5405.945940540544;6256.092866534418;6337.207849018872;6338.461230795434;6357.491400688026;6362.681865906686;6347.206599175103;6347.5556711029785;6350.364963503613;6352.333005034405;6362.331955144643;6217.937820621736;6238.095476156022;6255.624662520263;6270.0908368287255;6339.841503962438;6157.568970172045;6161.5686901918225;6180.81457556274;6181.5363847710705;6221.47117495018;6302.936970630419;6316.778912738032;6322.399372059647;6333.841653958688;6335.936640633719;5960.374160713062;6046.09308603713;6084.574079814371;6148.262208535004;6170.629762214281;6091.850495379587;6120.233815636221;6125.876495711566;6134.7121296814075;6140.412224875342;5962.880742385041;5972.134040564007;5980.820575382745;5989.880202396015;5993.940060599512;5735.197072409783;5921.526277897844;5938.406159384027;6038.456538911519;6069.757209711562;5976.312724036791;5977.9402205977385;5994.8501287466415;5998.1002849572915;6020.638761674313 03:04:00 INFO - PROCESS | 1669 | |1;ext.html;554.0083250980745;594.180031556452;595.8838026584816;611.8710978245136;672.2907332763933;31178;31743.888963886267;31811.363772724533;31814.522782158227;32047.839760801162;41057;41428.17143657133;41546.58453415465;41615.37576936348;41838.16323673525;9633.518324083803;9812.165965892871;9837.360571562862;9844.852327215098;9868.555914983806;9598.952005239964;9731.854022189673;9733.07535784103;9733.951330243339;9744.561494732754;9571.760705982371;9695.709128726114;9758.072983066597;9759.609615615367;9779.55991980359;9667.516624168798;9739.513024348755;9749.610015599368;9756.073173048584;9779.657711980082;9675.516224188832;9782.119609235131;9797.510124493818;9965.70102896907;10009.499525023719;41631.58368416307;42119;42148.15703685909;42158.57841421577;42201.57798422007;3248.7076163145193;3355.07735372772;3378.2736711606876;3390.1185691720084;3416.0776590320725;28318.292042698893;28506.714932850613;28536.434185406575;28732.425351492853;29162.70837291621;2693.0708905427596;2698.662667166604;2699.9730002700117;2734.53512902807;2753.821001634887;488769;490376;490868.545657273;491526;491928.6210706842;55712;66528.33471665317;66621.00068498976;66653.33346666569;66727.33272667212;69781.65109174422;75981;76899.61550192213;76946.2305376939;77417.83873241793;18013;18196.999165045785;18233.270669173085;18245.178966946518;18269.54326141857;13783.448662053606;13856.653583660489;13857;13885.722285554233;13903.930480347533;22568.887155564116;22906;22919.770802292096;22934.7706522936;22981.88509057444;9307.76730581741;9547.61809527625;9548.522573871278;9551.283653726;9557.61769529211;996.740847379672;1058.5765693722572;1060.591672206206;1104.081317729513;1135.3358285402967;30151.698483015327;30207.848960755055;30640;30659.38681226363;30683.38633227323;1123.4217470904293;1158.1487606609135;1174.266083697689;1201.3092471828734;1217.5677634439824;15185.924070379577;15311;15643.296051677702;15675;15725.213739312989;538712;544537;545121;545891;549707;61603.07595386073;62050;62052.069218961755;62061.689691551255;62199.378006220264;1404.466302804948;1535.4165417141414;1541.0522528644804;1557.7196104701263;1567.7021365940664;28570.28574285576;29052.56421153727;29062;29424;29721.851390742908;44089.67730968076;44626.3306050416;44720.77639611781;44729.55270447254;44945.32582011338;304791;312995;313538.43230784615;314184;314326;29456;48862.75568622134;50244;51087;51416;47122.52877471181;47669.52330476789;48261.276080858115;48802.75598621984;49150.26274605812;38575.842724718306;39010;40127.59872401355;41579.792101039304;41892.79053604713;39036;40409.189267527836;40587;40923.7953810229;41371;41126.58873411227;41684.58315416807;41825.79087104545;42148.57851421447;43113.78443107764;32895.01314960555;34426.48360274548;35239.82380088083;35344.11639709028;35387;25877.48253969821;25902.35244118912;26050.088246911266;26247.60628590611;26875.596866047417 03:04:00 INFO - PROCESS | 1669 | |2;jquery.html;377.2360969037698;383.7256361701383;384.384984025559;385.70300868331384;391.44610376317513;17499.475015749533;19623.018849057542;21333.719976801403;21827.78172218277;21879;20233.190672373086;20507.38477845663;21147;21171.788282117173;21471.463213419673;4890.657653964224;5895.941040589603;5914.556408269375;5932;5933.228680271559;4189.706720529564;6229.750809967596;6501.577397469173;6516.315786842384;6517.576357536768;6370.9681451592905;6450.129232553592;6458.644774537399;6467.870642587145;6514.934850651504;3869.613038696136;6211.161493198428;6290.937090629081;6385.297617262097;6429.967850160742;5309.33633295838;5710.428957104298;5713.571482138815;5718.59969802114;5725.169850371714;26701;26956.97822663308;27583.724162758317;28327.57508637372;28364.432711345864;2138.2622995066677;2181.6073106840763;2207.1612787140944;2254.5265494246064;2314.4445333120098;14995.95028348016;15215;15422.305996230085;16039.599010024725;16173.4339298124;1755.1224387806096;1801.7838853699425;1812.3294381078954;1829.5426143464133;1840.1075478392968;102784.48607756988;105474.94525054727;106885.39671904927;107496.46251768769;107806;10994.34033957965;11547.826782598268;11742.88257117418;11891.524339026342;12018.819717704242;14509.347079381454;14583.708325833424;14679.633009174857;14706;14742.852571474363;9006.568226578787;9183.173514383603;9213.90786092144;9259.490728009889;9262.16640502358;9376.484293363794;9394.671186508576;9401.76495587602;9499.050094990584;9519.428834269966;4248.575142485788;4604.286335617984;4669.369635099286;4679.157751604744;4712.717236965793;1682.932682692703;1799.0141051295868;1895.2608482691762;1978.3768113044346;2006.1273346094204;936.9531523423801;991.6430085169334;1006.3257617396362;1105.9723506912392;1123.2757716904512;18639.254429822926;20241.79758202399;21103.472413189793;21166.729996200276;21310.893445532984;945.7682867697321;980.9656662016795;996.9900300996898;1023.3932725620853;1046.4453839465095;10941.507632156572;11095.944520277508;11171.32972021665;11179.66461006171;11209.21535492524;612345;635577.8221108865;644701;651907;652941.73529133;114101;115456;122291;127827;129346;1020.3775696824931;1035.140833108518;1035.7203555040096;1037.071820720451;1069.8020866139789;18131.001577129497;18598.349057782914;18758.812411875708;18845.24619015279;18867.339643112424;48447.75776121238;51037.48962510328;54726.72636636791;54934.72532637311;57210.71394643;61565.15304540871;72926.90609640753;73418.26581734115;77974.610126949;78144;8056.597170141352;8099.433039687166;8260.876086858581;8318.83362332762;8426.368022398223;9684.85472717924;13042.608721738363;13351.332433378098;13587.59237222885;13762.724745504833;5623.775048997994;11335.659930202108;11528.02011829004;11876.465559049691;12052.0960927929;11175.050120739828;11765.647030589096;11771.411429428323;11788.882111179122;11912.821307680219;11431.085513159094;11865.703357415789;11905.583304584297;11985.041196704417;12117;8124.959375203322;8205.671773129247;8257.21556452136;8435.620397082268;8613.00950390688;9827;11569;11615.361155136548;11732.824007639721;12053.337066461429 03:04:00 INFO - PROCESS | 1669 | |3;mootools.html;1497.1241823533232;1640.967180656387;1658.0300524193347;1913.7703475582932;1953.9546342706647;8296.253337199658;8916.91083089169;9054.094590540943;9081.18269355758;9097.044810294914;8779.692710755126;8996.955015224914;9017.909820901788;9030.006699263076;9062.864057039133;2251.3808702606834;2504.7745702886737;2546.7835234005133;2565.307367010906;2584.2634849068018;2556.335352808265;2588.456424150921;2594.987025064872;2595.7663810257072;2597.4805038992276;2446.425090103824;2548.617707343904;2550.1966880432606;2550.693916729995;2552.2517075803044;2527.2165628655175;2571.485702859426;2585.922422327333;2587.4178309880126;2590.9481810363877;2522.3946252899354;2551.6427700122;2569.961450578243;2570.3959569501153;2570.5630042892753;9053.411528250643;9116.225120864734;9125.452472851584;9128.543572821332;9128.954355228248;4823.445303790074;5563.749631266563;5682.6306290091015;5694.914576281359;5699.857503562403;3477.1133360992785;3558.9644103558894;3596.7482276240676;3675.2649470105816;3726.9254614907823;960.9711708648751;970.834958057132;987.1165307050221;1034.777522832587;1087.8422628718793;12248;12262.26426414418;12278.324692141929;12291.508339666403;12305.630831075083;4941.135301322254;4977.751112444435;5065.240213967861;5079.593632509391;5087.821926232564;10498.370097794157;10499.317544359592;10508;10530.526126324334;10553.683389498327;5706.686132262766;5707.71461426927;5714.0571805652435;5715;5730.570207234474;4566.931496027562;4745.027269409763;4945.480724523945;5098.949010509921;5113.335266415343;4052.7456540863864;4091.672666186698;4139.710220284612;4170.749755014709;4175.916481670411;1328.266612071237;1344.0913902258817;1353.4653811744504;1359.0758284366527;1361.0880709924375;1387.840398354182;1416.6812467195005;1419.013785419124;1431.0054512113982;1451.1438251431705;8479.788005299917;8667.956660216658;8691.478511289342;8721.520316382534;8726.825463490695;1251.5932321995388;1267.6098346639071;1268.6130730127215;1273.1597145883673;1288.8968882489378;6464.806055818333;6506.577072490273;6548.869022619616;6564.048213009087;6566.179227596551;10161.390316581028;11209.71975700614;11358.920902514414;11364.204505684687;11376.772464550662;10841.728956776144;10999.890001100046;11102.833457498144;11181.944090279496;11205.66383008511;7267.854642907112;8560.529170895536;8705.6517739291;8708.608112634947;8802.427842190236;8141.470804397695;8200.753977380688;8257.669693212205;8278.503289802631;8299.170082991652;9819.312648114563;9908.405495670282;10017;10114.342567733074;10199.541020654236;10184.898151018395;10319.174466042849;10346.37921724699;10357.4303413313;10554.788904222027;7082.893756593759;7280.96359518199;7472.701091956261;7551.848963020819;7749.535027898119;8579.2707619853;8601.956990215009;8622.870656940277;8638.82722345562;8650.610722517627;7503.587302698185;7651.043619547557;7689.577073260801;7713.19011503784;7725.768226953201;7344.339009489172;7473.775786726407;7494.437917156126;7601.505902116345;7651.502652327581;7945.324647405036;8037.879431808645;8046.678132874619;8055.597220139087;8065.1934806518875;7253.637318134178;7310.817229569303;7311.378532824557;7352.595607241653;7372.631368431665;7353;7419.629018549159;7466.962665186639;7473.551586904803;7487.32614064715 03:04:00 INFO - PROCESS | 1669 | |4;prototype.html;332.96448046575154;339.61792982894247;340.2174997505736;345.4041777933065;346.4993084992187;6723.394894459498;6760;6761.66191690416;6764.8985265221;6775.559588626736;5613.624661957816;6669.666516674171;6684.431823295025;6692.29730878258;6711.094002309686;4511;4553.430821147356;4816.277558366238;4933.950660493385;4963.478834722366;4984.252362145671;4989.65072444929;4992.725400102993;4995.100881841286;5004.824831130893;4547.24970379887;4933.876653083684;4947.604191664658;4960.627952903528;4962.131626965267;4800.183968725308;4861.052094841507;4962.156433406293;4964;5026;4286.721363111419;4291.069018941457;4301.41930839339;4314.956850431487;4315.201687687788;6421.29365769765;6423.261324947644;6450.516211284125;6469.773557925497;6475.417212450902;5862.20860183874;5866.73599688015;5895.882082358329;5899.262592175978;5900.675462849542;6793.218779840332;7080.327368899946;7081.468889833283;7086.18508871476;7113.786586402416;4766.976165119187;4793.209120495114;4808.447028591721;4819.108464933998;4826.058918510892;7424.480286379956;7479.626018699098;7499.400047996147;7500.8124796879965;7512.812179695551;4900.142475066849;4913.70517768935;4925.359703238556;4926;4928.137575924271;4988.226824842155;4996.150654388763;4997.350344455198;5005.624578156653;5022.146235140035;5626;5896.557758168155;5900.409959004065;5908.261467316585;5917.940820591825;7045.577265364094;7055.611941343275;7069.116360454943;7075.787726368218;7076.823079422953;1701.702202114625;1717.407494414425;1719.2864961041028;1720.4838548435418;1720.6128621060411;1055.3562326980536;1079.0935614084208;1108.894655007773;1117.0951529261324;1143.9199256051998;6571.83570410734;6730.629815360202;6750.5274630775375;6750.628715420699;6773.627450490172;7437.181909989897;7442.962785186148;7455.85088298231;7465.58939258346;7487.66305516253;6701.497387695845;6733.93266067333;6743.15710536183;6752.898706519406;6760.121184246115;3181.618205815317;3758.924821503609;3906.4726261954843;3910.687145028391;3919.274934137137;7708.38332933364;7728.381729461742;7735.033120859893;7740.225977402328;7759.340456061298;7598.924010759822;7631.084269887649;7633.847323053508;7639.961800190964;7646.273604007611;7689.692412303557;7718.614069296513;7725.884111738441;7729.729459469004;7735.729249476241;7086.39765619928;7404.407647388303;7432.739854105081;7440.888386674313;7452.292032256927;7202.423806095605;7265.237150099375;7277.854442911218;7278.381337586153;7285.7814265572115;7710.8843367350655;7715;7723.111842138226;7728.884066738891;7739.651715672921;4943.975280123576;4948.727819969792;4960.404751429852;4968.4286307075145;4971.65198436106;7128.358447739728;7378.151512575908;7385.187629360658;7405.7778266652085;7405.925940740523;7089.929100708927;7136.6788494518905;7139.250378710369;7144.678489467882;7161.641917904188;7112.395446387115;7112.644367781693;7115.324044215791;7127.536710113826;7133.500654954103;6956.652167391711;6960.582365058112;6967.756128535477;6968.338007889242;6968.581885086911;6848.657567121524;7118.928810711826;7122;7126.786196413908;7146.3568278855155;6636.502262330441;6799.762008329685;6809.591424514545;6858.9368647859665;6923.757668481579 03:04:00 INFO - PROCESS | 1669 | |5;yui.html;681.6762038031934;760.6919197724922;765.4374035084209;774.5546310871247;777.8579066471043;15492.225388730561;15500.457483988048;15790.684186316266;16288.83711162888;16355.182240887967;14960.551183464513;15026.549203523884;15063.171525566117;15077;15083.245837708126;809.9068607110199;819.983600327993;960.1790469148882;980.9166220871217;1007.3401921741234;979.9608015679399;996.0587245053428;998.8301988661945;999.1557134221607;1001.639409812467;1037.6420135053431;1059.2902755154066;1061.0927656853357;1061.777026824364;1062.3678911047914;827.6358402302998;842.8693552499373;1025.2003437318904;1033.0495943731746;1051.9000694933968;776.6582703610393;785.6896525872248;785.8428314337099;786.1745167574024;787.8069872881124;15537.378504859793;15965.361385544564;15965.680686386324;15968.920155399148;16029.519114426586;13108.80336794949;13112.409941552653;13171;13262.602121936357;13400.798988015187;9370.531473426301;10106.140978016878;10190.796184076276;10374.429406382646;10519.579216831318;3339.482380231068;3514.6485351464903;3551.538300020981;3577.5706915170344;3773.2264885698364;71254.28745712529;71612.56774864474;73402.63298683526;73670;73806.26193738048;9732;9822.361546499455;9878.27130252203;9889.604415823287;9929.553170107363;21995.340139795833;22542.098316067175;22605.304602154905;22639.43401414945;22650.320490385315;6669.066330713704;6701.497387695942;6875.106236189264;6937.202221744463;7043.506954513135;4151.97924010378;4174.998000479924;4178.01816573105;4193.077522944947;4193.790310484464;3711.814409279579;3732.664060234592;3763.41667041609;3766.3597188478025;3767.830447629863;669.517947078103;673.4847841401332;681.5127184063418;685.7359916432209;700.5866538742166;524.9632525723239;564.5060571999501;602.6022824935521;619.294004834489;642.6825008732174;8196.877046844302;8337.541435221121;8416.419653283023;8419.458791769766;8512.914870851213;591.8461200088008;606.7991345651723;608.6986941464004;612.586504109733;627.4290395739855;3319.302946381287;3891.455196272524;3962.7226094173707;3966.940495892564;4034.576369481221;86154.56922715346;87458;87902.68145977815;88323;88481;79517;80399.59800200962;80607.59696201481;81656.59171704104;82630;73143.34283285815;74282;75548.62225688946;76548.61725691336;77842.22157778463;7750.651220694969;7950.602469876599;7984.6406911687955;8017.318527925192;8198.057223419382;45660.63018109462;52515.737421312646;54014.72992635012;54977.25113744377;55053.44946550483;70123;70958.29041709517;71067.64466177636;71953.6402317985;77585.61207193928;10307.330023548446;11272.492737826982;11337;11727.061835053346;12034.977026952807;12099.697507562381;12357.073219508393;12386.938065309616;12558.93720531428;12602.36988150607;11544.966175885262;11815.527378905092;12081.395930203631;12087.576934807239;12179;11536.423178840856;11753.647390578297;11860.644180674593;12165.45255463524;12412.627621171017;11139.10687965587;11313.434328283389;11435.542578296774;11663.59177428786;11670.416479175838;9718.70843874685;9758.902410976083;9787.657431989846;9804.607815687292;9837.508124593885;9746.512674366395;9909;9939.552720127756;9942.950285248817;10006.44964526958 03:04:00 INFO - PROCESS | 1669 | __end_tp_report 03:04:00 INFO - PROCESS | 1669 | __start_cc_report 03:04:00 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1152 03:04:00 INFO - PROCESS | 1669 | __end_cc_report 03:04:00 INFO - PROCESS | 1669 | __startTimestamp1460801040649__endTimestamp 03:04:00 INFO - PROCESS | 1669 | 03:04:00 INFO - PROCESS | 1669 | ------- Summary: start ------- 03:04:00 INFO - PROCESS | 1669 | Number of tests: 6 03:04:00 INFO - PROCESS | 1669 | 03:04:00 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5755.38 Median:5962.88 stddev:626.89 (10.5%) stddev-sans-first:601.31 03:04:00 INFO - PROCESS | 1669 | Values: 3414.1 3504.9 3506.0 3531.7 3566.8 4892.0 5598.9 5658.5 5675.1 5706.5 5584.6 5754.5 5761.4 5773.2 5774.0 5168.5 5202.2 5229.9 5231.0 5237.2 5314.9 5316.1 5325.8 5327.4 5328.7 5214.1 5288.3 5307.5 5307.9 5310.3 5288.3 5328.2 5332.8 5338.0 5360.0 5039.1 5234.7 5235.1 5253.4 5263.6 5584.2 5640.1 5642.0 5650.1 5658.2 5563.9 6089.8 6292.7 6311.3 6342.5 5976.0 6258.9 6314.7 6349.7 6361.9 5833.3 5846.6 5904.7 5909.4 5910.5 6235.8 6267.1 6277.6 6308.9 6312.0 6169.9 6199.2 6205.4 6210.4 6225.9 5795.3 6222.1 6341.0 6369.7 6372.5 6010.0 6120.5 6153.9 6265.2 6275.1 6247.3 6258.2 6283.7 6297.8 6301.4 4911.3 4913.5 4917.2 4917.7 4923.1 4327.4 4458.5 4463.5 4472.6 4477.0 5605.8 5643.6 5643.8 5655.6 5799.0 5475.5 6211.0 6329.7 6340.7 6366.8 5680.9 5704.7 5726.7 5737.1 5749.8 5161.6 5342.5 5379.2 5401.8 5405.9 6256.1 6337.2 6338.5 6357.5 6362.7 6347.2 6347.6 6350.4 6352.3 6362.3 6217.9 6238.1 6255.6 6270.1 6339.8 6157.6 6161.6 6180.8 6181.5 6221.5 6302.9 6316.8 6322.4 6333.8 6335.9 5960.4 6046.1 6084.6 6148.3 6170.6 6091.9 6120.2 6125.9 6134.7 6140.4 5962.9 5972.1 5980.8 5989.9 5993.9 5735.2 5921.5 5938.4 6038.5 6069.8 5976.3 5977.9 5994.9 5998.1 6020.6 03:04:00 INFO - PROCESS | 1669 | 03:04:00 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:61516.58 Median:41399.59 stddev:122224.63 (295.2%) stddev-sans-first:122481.80 03:04:00 INFO - PROCESS | 1669 | Values: 554.0 594.2 595.9 611.9 672.3 31178.0 31743.9 31811.4 31814.5 32047.8 41057.0 41428.2 41546.6 41615.4 41838.2 9633.5 9812.2 9837.4 9844.9 9868.6 9599.0 9731.9 9733.1 9734.0 9744.6 9571.8 9695.7 9758.1 9759.6 9779.6 9667.5 9739.5 9749.6 9756.1 9779.7 9675.5 9782.1 9797.5 9965.7 10009.5 41631.6 42119.0 42148.2 42158.6 42201.6 3248.7 3355.1 3378.3 3390.1 3416.1 28318.3 28506.7 28536.4 28732.4 29162.7 2693.1 2698.7 2700.0 2734.5 2753.8 488769.0 490376.0 490868.5 491526.0 491928.6 55712.0 66528.3 66621.0 66653.3 66727.3 69781.7 75981.0 76899.6 76946.2 77417.8 18013.0 18197.0 18233.3 18245.2 18269.5 13783.4 13856.7 13857.0 13885.7 13903.9 22568.9 22906.0 22919.8 22934.8 22981.9 9307.8 9547.6 9548.5 9551.3 9557.6 996.7 1058.6 1060.6 1104.1 1135.3 30151.7 30207.8 30640.0 30659.4 30683.4 1123.4 1158.1 1174.3 1201.3 1217.6 15185.9 15311.0 15643.3 15675.0 15725.2 538712.0 544537.0 545121.0 545891.0 549707.0 61603.1 62050.0 62052.1 62061.7 62199.4 1404.5 1535.4 1541.1 1557.7 1567.7 28570.3 29052.6 29062.0 29424.0 29721.9 44089.7 44626.3 44720.8 44729.6 44945.3 304791.0 312995.0 313538.4 314184.0 314326.0 29456.0 48862.8 50244.0 51087.0 51416.0 47122.5 47669.5 48261.3 48802.8 49150.3 38575.8 39010.0 40127.6 41579.8 41892.8 39036.0 40409.2 40587.0 40923.8 41371.0 41126.6 41684.6 41825.8 42148.6 43113.8 32895.0 34426.5 35239.8 35344.1 35387.0 25877.5 25902.4 26050.1 26247.6 26875.6 03:04:00 INFO - PROCESS | 1669 | 03:04:00 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:35869.95 Median:11826.54 stddev:105899.85 (895.4%) stddev-sans-first:106163.39 03:04:00 INFO - PROCESS | 1669 | Values: 377.2 383.7 384.4 385.7 391.4 17499.5 19623.0 21333.7 21827.8 21879.0 20233.2 20507.4 21147.0 21171.8 21471.5 4890.7 5895.9 5914.6 5932.0 5933.2 4189.7 6229.8 6501.6 6516.3 6517.6 6371.0 6450.1 6458.6 6467.9 6514.9 3869.6 6211.2 6290.9 6385.3 6430.0 5309.3 5710.4 5713.6 5718.6 5725.2 26701.0 26957.0 27583.7 28327.6 28364.4 2138.3 2181.6 2207.2 2254.5 2314.4 14996.0 15215.0 15422.3 16039.6 16173.4 1755.1 1801.8 1812.3 1829.5 1840.1 102784.5 105474.9 106885.4 107496.5 107806.0 10994.3 11547.8 11742.9 11891.5 12018.8 14509.3 14583.7 14679.6 14706.0 14742.9 9006.6 9183.2 9213.9 9259.5 9262.2 9376.5 9394.7 9401.8 9499.1 9519.4 4248.6 4604.3 4669.4 4679.2 4712.7 1682.9 1799.0 1895.3 1978.4 2006.1 937.0 991.6 1006.3 1106.0 1123.3 18639.3 20241.8 21103.5 21166.7 21310.9 945.8 981.0 997.0 1023.4 1046.4 10941.5 11095.9 11171.3 11179.7 11209.2 612345.0 635577.8 644701.0 651907.0 652941.7 114101.0 115456.0 122291.0 127827.0 129346.0 1020.4 1035.1 1035.7 1037.1 1069.8 18131.0 18598.3 18758.8 18845.2 18867.3 48447.8 51037.5 54726.7 54934.7 57210.7 61565.2 72926.9 73418.3 77974.6 78144.0 8056.6 8099.4 8260.9 8318.8 8426.4 9684.9 13042.6 13351.3 13587.6 13762.7 5623.8 11335.7 11528.0 11876.5 12052.1 11175.1 11765.6 11771.4 11788.9 11912.8 11431.1 11865.7 11905.6 11985.0 12117.0 8125.0 8205.7 8257.2 8435.6 8613.0 9827.0 11569.0 11615.4 11732.8 12053.3 03:04:00 INFO - PROCESS | 1669 | 03:04:00 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:6292.18 Median:5021.50 stddev:3303.83 (65.8%) stddev-sans-first:3293.44 03:04:00 INFO - PROCESS | 1669 | Values: 1497.1 1641.0 1658.0 1913.8 1954.0 8296.3 8916.9 9054.1 9081.2 9097.0 8779.7 8997.0 9017.9 9030.0 9062.9 2251.4 2504.8 2546.8 2565.3 2584.3 2556.3 2588.5 2595.0 2595.8 2597.5 2446.4 2548.6 2550.2 2550.7 2552.3 2527.2 2571.5 2585.9 2587.4 2590.9 2522.4 2551.6 2570.0 2570.4 2570.6 9053.4 9116.2 9125.5 9128.5 9129.0 4823.4 5563.7 5682.6 5694.9 5699.9 3477.1 3559.0 3596.7 3675.3 3726.9 961.0 970.8 987.1 1034.8 1087.8 12248.0 12262.3 12278.3 12291.5 12305.6 4941.1 4977.8 5065.2 5079.6 5087.8 10498.4 10499.3 10508.0 10530.5 10553.7 5706.7 5707.7 5714.1 5715.0 5730.6 4566.9 4745.0 4945.5 5098.9 5113.3 4052.7 4091.7 4139.7 4170.7 4175.9 1328.3 1344.1 1353.5 1359.1 1361.1 1387.8 1416.7 1419.0 1431.0 1451.1 8479.8 8668.0 8691.5 8721.5 8726.8 1251.6 1267.6 1268.6 1273.2 1288.9 6464.8 6506.6 6548.9 6564.0 6566.2 10161.4 11209.7 11358.9 11364.2 11376.8 10841.7 10999.9 11102.8 11181.9 11205.7 7267.9 8560.5 8705.7 8708.6 8802.4 8141.5 8200.8 8257.7 8278.5 8299.2 9819.3 9908.4 10017.0 10114.3 10199.5 10184.9 10319.2 10346.4 10357.4 10554.8 7082.9 7281.0 7472.7 7551.8 7749.5 8579.3 8602.0 8622.9 8638.8 8650.6 7503.6 7651.0 7689.6 7713.2 7725.8 7344.3 7473.8 7494.4 7601.5 7651.5 7945.3 8037.9 8046.7 8055.6 8065.2 7253.6 7310.8 7311.4 7352.6 7372.6 7353.0 7419.6 7467.0 7473.6 7487.3 03:04:00 INFO - PROCESS | 1669 | 03:04:00 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:5894.30 Median:6738.54 stddev:1852.26 (27.5%) stddev-sans-first:1809.81 03:04:00 INFO - PROCESS | 1669 | Values: 333.0 339.6 340.2 345.4 346.5 6723.4 6760.0 6761.7 6764.9 6775.6 5613.6 6669.7 6684.4 6692.3 6711.1 4511.0 4553.4 4816.3 4934.0 4963.5 4984.3 4989.7 4992.7 4995.1 5004.8 4547.2 4933.9 4947.6 4960.6 4962.1 4800.2 4861.1 4962.2 4964.0 5026.0 4286.7 4291.1 4301.4 4315.0 4315.2 6421.3 6423.3 6450.5 6469.8 6475.4 5862.2 5866.7 5895.9 5899.3 5900.7 6793.2 7080.3 7081.5 7086.2 7113.8 4767.0 4793.2 4808.4 4819.1 4826.1 7424.5 7479.6 7499.4 7500.8 7512.8 4900.1 4913.7 4925.4 4926.0 4928.1 4988.2 4996.2 4997.4 5005.6 5022.1 5626.0 5896.6 5900.4 5908.3 5917.9 7045.6 7055.6 7069.1 7075.8 7076.8 1701.7 1717.4 1719.3 1720.5 1720.6 1055.4 1079.1 1108.9 1117.1 1143.9 6571.8 6730.6 6750.5 6750.6 6773.6 7437.2 7443.0 7455.9 7465.6 7487.7 6701.5 6733.9 6743.2 6752.9 6760.1 3181.6 3758.9 3906.5 3910.7 3919.3 7708.4 7728.4 7735.0 7740.2 7759.3 7598.9 7631.1 7633.8 7640.0 7646.3 7689.7 7718.6 7725.9 7729.7 7735.7 7086.4 7404.4 7432.7 7440.9 7452.3 7202.4 7265.2 7277.9 7278.4 7285.8 7710.9 7715.0 7723.1 7728.9 7739.7 4944.0 4948.7 4960.4 4968.4 4971.7 7128.4 7378.2 7385.2 7405.8 7405.9 7089.9 7136.7 7139.3 7144.7 7161.6 7112.4 7112.6 7115.3 7127.5 7133.5 6956.7 6960.6 6967.8 6968.3 6968.6 6848.7 7118.9 7122.0 7126.8 7146.4 6636.5 6799.8 6809.6 6858.9 6923.8 03:04:00 INFO - PROCESS | 1669 | 03:04:00 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:18575.78 Median:27808.98 stddev:25656.07 (92.3%) stddev-sans-first:25692.86 03:04:00 INFO - PROCESS | 1669 | Values: 681.7 760.7 765.4 774.6 777.9 15492.2 15500.5 15790.7 16288.8 16355.2 14960.6 15026.5 15063.2 15077.0 15083.2 809.9 820.0 960.2 980.9 1007.3 980.0 996.1 998.8 999.2 1001.6 1037.6 1059.3 1061.1 1061.8 1062.4 827.6 842.9 1025.2 1033.0 1051.9 776.7 785.7 785.8 786.2 787.8 15537.4 15965.4 15965.7 15968.9 16029.5 13108.8 13112.4 13171.0 13262.6 13400.8 9370.5 10106.1 10190.8 10374.4 10519.6 3339.5 3514.6 3551.5 3577.6 3773.2 71254.3 71612.6 73402.6 73670.0 73806.3 9732.0 9822.4 9878.3 9889.6 9929.6 21995.3 22542.1 22605.3 22639.4 22650.3 6669.1 6701.5 6875.1 6937.2 7043.5 4152.0 4175.0 4178.0 4193.1 4193.8 3711.8 3732.7 3763.4 3766.4 3767.8 669.5 673.5 681.5 685.7 700.6 525.0 564.5 602.6 619.3 642.7 8196.9 8337.5 8416.4 8419.5 8512.9 591.8 606.8 608.7 612.6 627.4 3319.3 3891.5 3962.7 3966.9 4034.6 86154.6 87458.0 87902.7 88323.0 88481.0 79517.0 80399.6 80607.6 81656.6 82630.0 73143.3 74282.0 75548.6 76548.6 77842.2 7750.7 7950.6 7984.6 8017.3 8198.1 45660.6 52515.7 54014.7 54977.3 55053.4 70123.0 70958.3 71067.6 71953.6 77585.6 10307.3 11272.5 11337.0 11727.1 12035.0 12099.7 12357.1 12386.9 12558.9 12602.4 11545.0 11815.5 12081.4 12087.6 12179.0 11536.4 11753.6 11860.6 12165.5 12412.6 11139.1 11313.4 11435.5 11663.6 11670.4 9718.7 9758.9 9787.7 9804.6 9837.5 9746.5 9909.0 9939.6 9943.0 10006.4 03:04:00 INFO - PROCESS | 1669 | -------- Summary: end -------- 03:04:00 INFO - PROCESS | 1669 | 03:04:01 INFO - TEST-INFO | 1669: exit 0 03:04:01 INFO - TEST-OK | dromaeo_css | took 1113029ms 03:04:01 INFO - TEST-START | kraken 03:04:01 INFO - operating with platform_type : mac_ 03:04:01 INFO - Initialising browser for kraken test... 03:04:01 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpv_kg6f/profile http://localhost:49277/getInfo.html) 03:04:03 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 03:04:03 INFO - PROCESS | 1675 | colorDepth:24 03:04:03 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 03:04:03 INFO - PROCESS | 1675 | __metrics 03:04:03 INFO - PROCESS | 1675 | 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:04:03 INFO - PROCESS | 1675 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:04:03 INFO - TEST-INFO | 1675: exit 0 03:04:03 INFO - Browser initialized. 03:04:03 INFO - Running cycle 1/1 for kraken test... 03:04:03 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpv_kg6f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:04:15 INFO - PROCESS | 1678 | RSS: Main: 207470592 03:04:15 INFO - PROCESS | 1678 | 03:04:18 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 03:04:19 INFO - PROCESS | 1678 | RSS: Main: 262905856 03:04:19 INFO - PROCESS | 1678 | 03:04:21 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 03:04:22 INFO - PROCESS | 1678 | RSS: Main: 273285120 03:04:22 INFO - PROCESS | 1678 | 03:04:25 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 03:04:25 INFO - PROCESS | 1678 | RSS: Main: 262799360 03:04:25 INFO - PROCESS | 1678 | 03:04:27 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 03:04:28 INFO - PROCESS | 1678 | RSS: Main: 263729152 03:04:28 INFO - PROCESS | 1678 | 03:04:30 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:04:31 INFO - PROCESS | 1678 | RSS: Main: 264880128 03:04:31 INFO - PROCESS | 1678 | 03:04:34 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 03:04:34 INFO - PROCESS | 1678 | RSS: Main: 264835072 03:04:34 INFO - PROCESS | 1678 | 03:04:37 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 03:04:38 INFO - PROCESS | 1678 | RSS: Main: 264929280 03:04:38 INFO - PROCESS | 1678 | 03:04:40 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 03:04:41 INFO - PROCESS | 1678 | RSS: Main: 265256960 03:04:41 INFO - PROCESS | 1678 | 03:04:42 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:04:43 INFO - PROCESS | 1678 | RSS: Main: 297746432 03:04:43 INFO - PROCESS | 1678 | 03:04:45 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:04:45 INFO - PROCESS | 1678 | RSS: Main: 298786816 03:04:45 INFO - PROCESS | 1678 | 03:04:48 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:04:48 INFO - PROCESS | 1678 | RSS: Main: 305963008 03:04:48 INFO - PROCESS | 1678 | 03:04:51 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:04:51 INFO - PROCESS | 1678 | RSS: Main: 307396608 03:04:51 INFO - PROCESS | 1678 | 03:04:54 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:04:54 INFO - PROCESS | 1678 | RSS: Main: 307429376 03:04:54 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 03:04:56 INFO - PROCESS | 1678 | RSS: Main: 369926144 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | __start_tp_report 03:04:56 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 03:04:56 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 03:04:56 INFO - PROCESS | 1678 | |i|pagename|runs| 03:04:56 INFO - PROCESS | 1678 | |0;ai-astar;113;109;112;110;112;112;120;112;111;110 03:04:56 INFO - PROCESS | 1678 | |1;audio-beat-detection;154;120;142;155;117;140;117;148;146;119 03:04:56 INFO - PROCESS | 1678 | |2;audio-dft;184;184;184;183;188;186;185;186;182;184 03:04:56 INFO - PROCESS | 1678 | |3;audio-fft;72;78;73;116;55;75;75;82;157;46 03:04:56 INFO - PROCESS | 1678 | |4;audio-oscillator;122;128;164;77;81;82;120;76;77;119 03:04:56 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;88;85;89;89;87;85;89;86;87;85 03:04:56 INFO - PROCESS | 1678 | |6;imaging-darkroom;92;93;93;93;92;101;94;94;92;100 03:04:56 INFO - PROCESS | 1678 | |7;imaging-desaturate;83;87;86;85;99;133;85;85;87;84 03:04:56 INFO - PROCESS | 1678 | |8;json-parse-financial;57;58;58;58;58;58;57;67;51;51 03:04:56 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;62;60;61;78;70;68;65;66;66;68 03:04:56 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;81;77;82;81;81;79;79;87;97;105 03:04:56 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;130;123;122;114;118;118;130;116;120;127 03:04:56 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;133;125;133;135;137;122;132;132;132;136 03:04:56 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;66;65;65;65;67;65;65;64;65;66 03:04:56 INFO - PROCESS | 1678 | __end_tp_report 03:04:56 INFO - PROCESS | 1678 | __start_cc_report 03:04:56 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1255 03:04:56 INFO - PROCESS | 1678 | __end_cc_report 03:04:56 INFO - PROCESS | 1678 | __startTimestamp1460801096746__endTimestamp 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | ------- Summary: start ------- 03:04:56 INFO - PROCESS | 1678 | Number of tests: 14 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:112.10 Median:112.00 stddev:3.03 (2.7%) stddev-sans-first:3.20 03:04:56 INFO - PROCESS | 1678 | Values: 113.0 109.0 112.0 110.0 112.0 112.0 120.0 112.0 111.0 110.0 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:144.00 stddev:15.80 (11.0%) stddev-sans-first:15.33 03:04:56 INFO - PROCESS | 1678 | Values: 154.0 120.0 142.0 155.0 117.0 140.0 117.0 148.0 146.0 119.0 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:184.60 Median:184.50 stddev:1.71 (0.9%) stddev-sans-first:1.80 03:04:56 INFO - PROCESS | 1678 | Values: 184.0 184.0 184.0 183.0 188.0 186.0 185.0 186.0 182.0 184.0 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:82.90 Median:74.00 stddev:31.79 (43.0%) stddev-sans-first:33.47 03:04:56 INFO - PROCESS | 1678 | Values: 72.0 78.0 73.0 116.0 55.0 75.0 75.0 82.0 157.0 46.0 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:104.60 Median:76.50 stddev:30.24 (39.5%) stddev-sans-first:31.42 03:04:56 INFO - PROCESS | 1678 | Values: 122.0 128.0 164.0 77.0 81.0 82.0 120.0 76.0 77.0 119.0 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:87.50 stddev:1.70 (1.9%) stddev-sans-first:1.76 03:04:56 INFO - PROCESS | 1678 | Values: 88.0 85.0 89.0 89.0 87.0 85.0 89.0 86.0 87.0 85.0 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:93.00 stddev:3.31 (3.6%) stddev-sans-first:3.39 03:04:56 INFO - PROCESS | 1678 | Values: 92.0 93.0 93.0 93.0 92.0 101.0 94.0 94.0 92.0 100.0 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:91.40 Median:85.50 stddev:15.29 (17.9%) stddev-sans-first:15.91 03:04:56 INFO - PROCESS | 1678 | Values: 83.0 87.0 86.0 85.0 99.0 133.0 85.0 85.0 87.0 84.0 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:58.00 stddev:4.42 (7.6%) stddev-sans-first:4.69 03:04:56 INFO - PROCESS | 1678 | Values: 57.0 58.0 58.0 58.0 58.0 58.0 57.0 67.0 51.0 51.0 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:67.00 stddev:5.21 (7.8%) stddev-sans-first:5.28 03:04:56 INFO - PROCESS | 1678 | Values: 62.0 60.0 61.0 78.0 70.0 68.0 65.0 66.0 66.0 68.0 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:81.00 stddev:9.07 (11.2%) stddev-sans-first:9.51 03:04:56 INFO - PROCESS | 1678 | Values: 81.0 77.0 82.0 81.0 81.0 79.0 79.0 87.0 97.0 105.0 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:121.80 Median:122.50 stddev:5.67 (4.6%) stddev-sans-first:5.18 03:04:56 INFO - PROCESS | 1678 | Values: 130.0 123.0 122.0 114.0 118.0 118.0 130.0 116.0 120.0 127.0 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:131.70 Median:133.00 stddev:4.72 (3.5%) stddev-sans-first:4.98 03:04:56 INFO - PROCESS | 1678 | Values: 133.0 125.0 133.0 135.0 137.0 122.0 132.0 132.0 132.0 136.0 03:04:56 INFO - PROCESS | 1678 | 03:04:56 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.83 03:04:56 INFO - PROCESS | 1678 | Values: 66.0 65.0 65.0 65.0 67.0 65.0 65.0 64.0 65.0 66.0 03:04:56 INFO - PROCESS | 1678 | -------- Summary: end -------- 03:04:56 INFO - PROCESS | 1678 | 03:04:57 INFO - TEST-INFO | 1678: exit 0 03:04:57 INFO - TEST-OK | kraken | took 56255ms 03:04:57 INFO - SUITE-END | took 1169s 03:04:57 INFO - Completed test suite (00:19:29) 03:04:57 INFO - javascript benchmark 03:04:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3414.112330793994, 3504.929901401971, 3506.018314871835, 3531.6644918732713, 3566.8038257895805, 4891.99714058618, 5598.860028499279, 5658.49073583378, 5675.120356344768, 5706.543476521879, 5584.553235741141, 5754.510866576346, 5761.395053519383, 5773.220615216945, 5773.971130144354, 5168.48315168482, 5202.167653175493, 5229.947700523003, 5230.979958908005, 5237.214417837316, 5314.92027619586, 5316.149416093426, 5325.7869685212545, 5327.413984461706, 5328.680279183242, 5214.139666954967, 5288.286081379022, 5307.469253074681, 5307.946920530803, 5310.283111779918, 5288.338957630296, 5328.174133009409, 5332.813351532678, 5338.012467693489, 5360.0, 5039.0929632666475, 5234.738263086831, 5235.136202526579, 5253.369595648508, 5263.605229607757, 5584.246126772915, 5640.09758438652, 5642.04085305499, 5650.124230744241, 5658.179563963202, 5563.916541251884, 6089.847753806146, 6292.748290068392, 6311.274203466614, 6342.5243106767175, 5976.0, 6258.906116408258, 6314.684265786692, 6349.714262858136, 6361.872762544724, 5833.32916714579, 5846.649201047951, 5904.704764761831, 5909.379515150933, 5910.527157827363, 6235.75056997715, 6267.091271765568, 6277.623342599459, 6308.905366419507, 6311.96844015777, 6169.907451388232, 6199.194104766351, 6205.410486003822, 6210.378962103844, 6225.875482490324, 5795.304563452412, 6222.066689996446, 6340.953742632506, 6369.745210191633, 6372.4583410409705, 6010.0, 6120.479759220516, 6153.907691384633, 6265.154204182468, 6275.121482992385, 6247.2815626202655, 6258.186435763413, 6283.717232724447, 6297.811065668037, 6301.40136687014, 4911.287863259808, 4913.484084171182, 4917.213245880642, 4917.680350777188, 4923.064617722621, 4327.3725309830525, 4458.509563947963, 4463.486699029653, 4472.642188624902, 4476.955230447719, 5605.775768969277, 5643.633163844337, 5643.830685079454, 5655.575831812631, 5799.043157878987, 5475.479829416199, 6211.006239001738, 6329.651869147241, 6340.714667839959, 6366.840828979312, 5680.886382272414, 5704.71476426177, 5726.685032323262, 5737.139429085671, 5749.8275051748515, 5161.5870730341485, 5342.492463215988, 5379.220013098157, 5401.756920938567, 5405.945940540544, 6256.092866534418, 6337.207849018872, 6338.461230795434, 6357.491400688026, 6362.681865906686, 6347.206599175103, 6347.5556711029785, 6350.364963503613, 6352.333005034405, 6362.331955144643, 6217.937820621736, 6238.095476156022, 6255.624662520263, 6270.0908368287255, 6339.841503962438, 6157.568970172045, 6161.5686901918225, 6180.81457556274, 6181.5363847710705, 6221.47117495018, 6302.936970630419, 6316.778912738032, 6322.399372059647, 6333.841653958688, 6335.936640633719, 5960.374160713062, 6046.09308603713, 6084.574079814371, 6148.262208535004, 6170.629762214281, 6091.850495379587, 6120.233815636221, 6125.876495711566, 6134.7121296814075, 6140.412224875342, 5962.880742385041, 5972.134040564007, 5980.820575382745, 5989.880202396015, 5993.940060599512, 5735.197072409783, 5921.526277897844, 5938.406159384027, 6038.456538911519, 6069.757209711562, 5976.312724036791, 5977.9402205977385, 5994.8501287466415, 5998.1002849572915, 6020.638761674313], "unit": "score", "name": "dojo.html", "value": 5716.9730292906}, {"lowerIsBetter": false, "replicates": [554.0083250980745, 594.180031556452, 595.8838026584816, 611.8710978245136, 672.2907332763933, 31178.0, 31743.888963886267, 31811.363772724533, 31814.522782158227, 32047.839760801162, 41057.0, 41428.17143657133, 41546.58453415465, 41615.37576936348, 41838.16323673525, 9633.518324083803, 9812.165965892871, 9837.360571562862, 9844.852327215098, 9868.555914983806, 9598.952005239964, 9731.854022189673, 9733.07535784103, 9733.951330243339, 9744.561494732754, 9571.760705982371, 9695.709128726114, 9758.072983066597, 9759.609615615367, 9779.55991980359, 9667.516624168798, 9739.513024348755, 9749.610015599368, 9756.073173048584, 9779.657711980082, 9675.516224188832, 9782.119609235131, 9797.510124493818, 9965.70102896907, 10009.499525023719, 41631.58368416307, 42119.0, 42148.15703685909, 42158.57841421577, 42201.57798422007, 3248.7076163145193, 3355.07735372772, 3378.2736711606876, 3390.1185691720084, 3416.0776590320725, 28318.292042698893, 28506.714932850613, 28536.434185406575, 28732.425351492853, 29162.70837291621, 2693.0708905427596, 2698.662667166604, 2699.9730002700117, 2734.53512902807, 2753.821001634887, 488769.0, 490376.0, 490868.545657273, 491526.0, 491928.6210706842, 55712.0, 66528.33471665317, 66621.00068498976, 66653.33346666569, 66727.33272667212, 69781.65109174422, 75981.0, 76899.61550192213, 76946.2305376939, 77417.83873241793, 18013.0, 18196.999165045785, 18233.270669173085, 18245.178966946518, 18269.54326141857, 13783.448662053606, 13856.653583660489, 13857.0, 13885.722285554233, 13903.930480347533, 22568.887155564116, 22906.0, 22919.770802292096, 22934.7706522936, 22981.88509057444, 9307.76730581741, 9547.61809527625, 9548.522573871278, 9551.283653726, 9557.61769529211, 996.740847379672, 1058.5765693722572, 1060.591672206206, 1104.081317729513, 1135.3358285402967, 30151.698483015327, 30207.848960755055, 30640.0, 30659.38681226363, 30683.38633227323, 1123.4217470904293, 1158.1487606609135, 1174.266083697689, 1201.3092471828734, 1217.5677634439824, 15185.924070379577, 15311.0, 15643.296051677702, 15675.0, 15725.213739312989, 538712.0, 544537.0, 545121.0, 545891.0, 549707.0, 61603.07595386073, 62050.0, 62052.069218961755, 62061.689691551255, 62199.378006220264, 1404.466302804948, 1535.4165417141414, 1541.0522528644804, 1557.7196104701263, 1567.7021365940664, 28570.28574285576, 29052.56421153727, 29062.0, 29424.0, 29721.851390742908, 44089.67730968076, 44626.3306050416, 44720.77639611781, 44729.55270447254, 44945.32582011338, 304791.0, 312995.0, 313538.43230784615, 314184.0, 314326.0, 29456.0, 48862.75568622134, 50244.0, 51087.0, 51416.0, 47122.52877471181, 47669.52330476789, 48261.276080858115, 48802.75598621984, 49150.26274605812, 38575.842724718306, 39010.0, 40127.59872401355, 41579.792101039304, 41892.79053604713, 39036.0, 40409.189267527836, 40587.0, 40923.7953810229, 41371.0, 41126.58873411227, 41684.58315416807, 41825.79087104545, 42148.57851421447, 43113.78443107764, 32895.01314960555, 34426.48360274548, 35239.82380088083, 35344.11639709028, 35387.0, 25877.48253969821, 25902.35244118912, 26050.088246911266, 26247.60628590611, 26875.596866047417], "unit": "score", "name": "ext.html", "value": 20524.48434138032}, {"lowerIsBetter": false, "replicates": [377.2360969037698, 383.7256361701383, 384.384984025559, 385.70300868331384, 391.44610376317513, 17499.475015749533, 19623.018849057542, 21333.719976801403, 21827.78172218277, 21879.0, 20233.190672373086, 20507.38477845663, 21147.0, 21171.788282117173, 21471.463213419673, 4890.657653964224, 5895.941040589603, 5914.556408269375, 5932.0, 5933.228680271559, 4189.706720529564, 6229.750809967596, 6501.577397469173, 6516.315786842384, 6517.576357536768, 6370.9681451592905, 6450.129232553592, 6458.644774537399, 6467.870642587145, 6514.934850651504, 3869.613038696136, 6211.161493198428, 6290.937090629081, 6385.297617262097, 6429.967850160742, 5309.33633295838, 5710.428957104298, 5713.571482138815, 5718.59969802114, 5725.169850371714, 26701.0, 26956.97822663308, 27583.724162758317, 28327.57508637372, 28364.432711345864, 2138.2622995066677, 2181.6073106840763, 2207.1612787140944, 2254.5265494246064, 2314.4445333120098, 14995.95028348016, 15215.0, 15422.305996230085, 16039.599010024725, 16173.4339298124, 1755.1224387806096, 1801.7838853699425, 1812.3294381078954, 1829.5426143464133, 1840.1075478392968, 102784.48607756988, 105474.94525054727, 106885.39671904927, 107496.46251768769, 107806.0, 10994.34033957965, 11547.826782598268, 11742.88257117418, 11891.524339026342, 12018.819717704242, 14509.347079381454, 14583.708325833424, 14679.633009174857, 14706.0, 14742.852571474363, 9006.568226578787, 9183.173514383603, 9213.90786092144, 9259.490728009889, 9262.16640502358, 9376.484293363794, 9394.671186508576, 9401.76495587602, 9499.050094990584, 9519.428834269966, 4248.575142485788, 4604.286335617984, 4669.369635099286, 4679.157751604744, 4712.717236965793, 1682.932682692703, 1799.0141051295868, 1895.2608482691762, 1978.3768113044346, 2006.1273346094204, 936.9531523423801, 991.6430085169334, 1006.3257617396362, 1105.9723506912392, 1123.2757716904512, 18639.254429822926, 20241.79758202399, 21103.472413189793, 21166.729996200276, 21310.893445532984, 945.7682867697321, 980.9656662016795, 996.9900300996898, 1023.3932725620853, 1046.4453839465095, 10941.507632156572, 11095.944520277508, 11171.32972021665, 11179.66461006171, 11209.21535492524, 612345.0, 635577.8221108865, 644701.0, 651907.0, 652941.73529133, 114101.0, 115456.0, 122291.0, 127827.0, 129346.0, 1020.3775696824931, 1035.140833108518, 1035.7203555040096, 1037.071820720451, 1069.8020866139789, 18131.001577129497, 18598.349057782914, 18758.812411875708, 18845.24619015279, 18867.339643112424, 48447.75776121238, 51037.48962510328, 54726.72636636791, 54934.72532637311, 57210.71394643, 61565.15304540871, 72926.90609640753, 73418.26581734115, 77974.610126949, 78144.0, 8056.597170141352, 8099.433039687166, 8260.876086858581, 8318.83362332762, 8426.368022398223, 9684.85472717924, 13042.608721738363, 13351.332433378098, 13587.59237222885, 13762.724745504833, 5623.775048997994, 11335.659930202108, 11528.02011829004, 11876.465559049691, 12052.0960927929, 11175.050120739828, 11765.647030589096, 11771.411429428323, 11788.882111179122, 11912.821307680219, 11431.085513159094, 11865.703357415789, 11905.583304584297, 11985.041196704417, 12117.0, 8124.959375203322, 8205.671773129247, 8257.21556452136, 8435.620397082268, 8613.00950390688, 9827.0, 11569.0, 11615.361155136548, 11732.824007639721, 12053.337066461429], "unit": "score", "name": "jquery.html", "value": 9835.014857944749}, {"lowerIsBetter": false, "replicates": [1497.1241823533232, 1640.967180656387, 1658.0300524193347, 1913.7703475582932, 1953.9546342706647, 8296.253337199658, 8916.91083089169, 9054.094590540943, 9081.18269355758, 9097.044810294914, 8779.692710755126, 8996.955015224914, 9017.909820901788, 9030.006699263076, 9062.864057039133, 2251.3808702606834, 2504.7745702886737, 2546.7835234005133, 2565.307367010906, 2584.2634849068018, 2556.335352808265, 2588.456424150921, 2594.987025064872, 2595.7663810257072, 2597.4805038992276, 2446.425090103824, 2548.617707343904, 2550.1966880432606, 2550.693916729995, 2552.2517075803044, 2527.2165628655175, 2571.485702859426, 2585.922422327333, 2587.4178309880126, 2590.9481810363877, 2522.3946252899354, 2551.6427700122, 2569.961450578243, 2570.3959569501153, 2570.5630042892753, 9053.411528250643, 9116.225120864734, 9125.452472851584, 9128.543572821332, 9128.954355228248, 4823.445303790074, 5563.749631266563, 5682.6306290091015, 5694.914576281359, 5699.857503562403, 3477.1133360992785, 3558.9644103558894, 3596.7482276240676, 3675.2649470105816, 3726.9254614907823, 960.9711708648751, 970.834958057132, 987.1165307050221, 1034.777522832587, 1087.8422628718793, 12248.0, 12262.26426414418, 12278.324692141929, 12291.508339666403, 12305.630831075083, 4941.135301322254, 4977.751112444435, 5065.240213967861, 5079.593632509391, 5087.821926232564, 10498.370097794157, 10499.317544359592, 10508.0, 10530.526126324334, 10553.683389498327, 5706.686132262766, 5707.71461426927, 5714.0571805652435, 5715.0, 5730.570207234474, 4566.931496027562, 4745.027269409763, 4945.480724523945, 5098.949010509921, 5113.335266415343, 4052.7456540863864, 4091.672666186698, 4139.710220284612, 4170.749755014709, 4175.916481670411, 1328.266612071237, 1344.0913902258817, 1353.4653811744504, 1359.0758284366527, 1361.0880709924375, 1387.840398354182, 1416.6812467195005, 1419.013785419124, 1431.0054512113982, 1451.1438251431705, 8479.788005299917, 8667.956660216658, 8691.478511289342, 8721.520316382534, 8726.825463490695, 1251.5932321995388, 1267.6098346639071, 1268.6130730127215, 1273.1597145883673, 1288.8968882489378, 6464.806055818333, 6506.577072490273, 6548.869022619616, 6564.048213009087, 6566.179227596551, 10161.390316581028, 11209.71975700614, 11358.920902514414, 11364.204505684687, 11376.772464550662, 10841.728956776144, 10999.890001100046, 11102.833457498144, 11181.944090279496, 11205.66383008511, 7267.854642907112, 8560.529170895536, 8705.6517739291, 8708.608112634947, 8802.427842190236, 8141.470804397695, 8200.753977380688, 8257.669693212205, 8278.503289802631, 8299.170082991652, 9819.312648114563, 9908.405495670282, 10017.0, 10114.342567733074, 10199.541020654236, 10184.898151018395, 10319.174466042849, 10346.37921724699, 10357.4303413313, 10554.788904222027, 7082.893756593759, 7280.96359518199, 7472.701091956261, 7551.848963020819, 7749.535027898119, 8579.2707619853, 8601.956990215009, 8622.870656940277, 8638.82722345562, 8650.610722517627, 7503.587302698185, 7651.043619547557, 7689.577073260801, 7713.19011503784, 7725.768226953201, 7344.339009489172, 7473.775786726407, 7494.437917156126, 7601.505902116345, 7651.502652327581, 7945.324647405036, 8037.879431808645, 8046.678132874619, 8055.597220139087, 8065.1934806518875, 7253.637318134178, 7310.817229569303, 7311.378532824557, 7352.595607241653, 7372.631368431665, 7353.0, 7419.629018549159, 7466.962665186639, 7473.551586904803, 7487.32614064715], "unit": "score", "name": "mootools.html", "value": 5141.012402066645}, {"lowerIsBetter": false, "replicates": [332.96448046575154, 339.61792982894247, 340.2174997505736, 345.4041777933065, 346.4993084992187, 6723.394894459498, 6760.0, 6761.66191690416, 6764.8985265221, 6775.559588626736, 5613.624661957816, 6669.666516674171, 6684.431823295025, 6692.29730878258, 6711.094002309686, 4511.0, 4553.430821147356, 4816.277558366238, 4933.950660493385, 4963.478834722366, 4984.252362145671, 4989.65072444929, 4992.725400102993, 4995.100881841286, 5004.824831130893, 4547.24970379887, 4933.876653083684, 4947.604191664658, 4960.627952903528, 4962.131626965267, 4800.183968725308, 4861.052094841507, 4962.156433406293, 4964.0, 5026.0, 4286.721363111419, 4291.069018941457, 4301.41930839339, 4314.956850431487, 4315.201687687788, 6421.29365769765, 6423.261324947644, 6450.516211284125, 6469.773557925497, 6475.417212450902, 5862.20860183874, 5866.73599688015, 5895.882082358329, 5899.262592175978, 5900.675462849542, 6793.218779840332, 7080.327368899946, 7081.468889833283, 7086.18508871476, 7113.786586402416, 4766.976165119187, 4793.209120495114, 4808.447028591721, 4819.108464933998, 4826.058918510892, 7424.480286379956, 7479.626018699098, 7499.400047996147, 7500.8124796879965, 7512.812179695551, 4900.142475066849, 4913.70517768935, 4925.359703238556, 4926.0, 4928.137575924271, 4988.226824842155, 4996.150654388763, 4997.350344455198, 5005.624578156653, 5022.146235140035, 5626.0, 5896.557758168155, 5900.409959004065, 5908.261467316585, 5917.940820591825, 7045.577265364094, 7055.611941343275, 7069.116360454943, 7075.787726368218, 7076.823079422953, 1701.702202114625, 1717.407494414425, 1719.2864961041028, 1720.4838548435418, 1720.6128621060411, 1055.3562326980536, 1079.0935614084208, 1108.894655007773, 1117.0951529261324, 1143.9199256051998, 6571.83570410734, 6730.629815360202, 6750.5274630775375, 6750.628715420699, 6773.627450490172, 7437.181909989897, 7442.962785186148, 7455.85088298231, 7465.58939258346, 7487.66305516253, 6701.497387695845, 6733.93266067333, 6743.15710536183, 6752.898706519406, 6760.121184246115, 3181.618205815317, 3758.924821503609, 3906.4726261954843, 3910.687145028391, 3919.274934137137, 7708.38332933364, 7728.381729461742, 7735.033120859893, 7740.225977402328, 7759.340456061298, 7598.924010759822, 7631.084269887649, 7633.847323053508, 7639.961800190964, 7646.273604007611, 7689.692412303557, 7718.614069296513, 7725.884111738441, 7729.729459469004, 7735.729249476241, 7086.39765619928, 7404.407647388303, 7432.739854105081, 7440.888386674313, 7452.292032256927, 7202.423806095605, 7265.237150099375, 7277.854442911218, 7278.381337586153, 7285.7814265572115, 7710.8843367350655, 7715.0, 7723.111842138226, 7728.884066738891, 7739.651715672921, 4943.975280123576, 4948.727819969792, 4960.404751429852, 4968.4286307075145, 4971.65198436106, 7128.358447739728, 7378.151512575908, 7385.187629360658, 7405.7778266652085, 7405.925940740523, 7089.929100708927, 7136.6788494518905, 7139.250378710369, 7144.678489467882, 7161.641917904188, 7112.395446387115, 7112.644367781693, 7115.324044215791, 7127.536710113826, 7133.500654954103, 6956.652167391711, 6960.582365058112, 6967.756128535477, 6968.338007889242, 6968.581885086911, 6848.657567121524, 7118.928810711826, 7122.0, 7126.786196413908, 7146.3568278855155, 6636.502262330441, 6799.762008329685, 6809.591424514545, 6858.9368647859665, 6923.757668481579], "unit": "score", "name": "prototype.html", "value": 5273.8274358239905}, {"lowerIsBetter": false, "replicates": [681.6762038031934, 760.6919197724922, 765.4374035084209, 774.5546310871247, 777.8579066471043, 15492.225388730561, 15500.457483988048, 15790.684186316266, 16288.83711162888, 16355.182240887967, 14960.551183464513, 15026.549203523884, 15063.171525566117, 15077.0, 15083.245837708126, 809.9068607110199, 819.983600327993, 960.1790469148882, 980.9166220871217, 1007.3401921741234, 979.9608015679399, 996.0587245053428, 998.8301988661945, 999.1557134221607, 1001.639409812467, 1037.6420135053431, 1059.2902755154066, 1061.0927656853357, 1061.777026824364, 1062.3678911047914, 827.6358402302998, 842.8693552499373, 1025.2003437318904, 1033.0495943731746, 1051.9000694933968, 776.6582703610393, 785.6896525872248, 785.8428314337099, 786.1745167574024, 787.8069872881124, 15537.378504859793, 15965.361385544564, 15965.680686386324, 15968.920155399148, 16029.519114426586, 13108.80336794949, 13112.409941552653, 13171.0, 13262.602121936357, 13400.798988015187, 9370.531473426301, 10106.140978016878, 10190.796184076276, 10374.429406382646, 10519.579216831318, 3339.482380231068, 3514.6485351464903, 3551.538300020981, 3577.5706915170344, 3773.2264885698364, 71254.28745712529, 71612.56774864474, 73402.63298683526, 73670.0, 73806.26193738048, 9732.0, 9822.361546499455, 9878.27130252203, 9889.604415823287, 9929.553170107363, 21995.340139795833, 22542.098316067175, 22605.304602154905, 22639.43401414945, 22650.320490385315, 6669.066330713704, 6701.497387695942, 6875.106236189264, 6937.202221744463, 7043.506954513135, 4151.97924010378, 4174.998000479924, 4178.01816573105, 4193.077522944947, 4193.790310484464, 3711.814409279579, 3732.664060234592, 3763.41667041609, 3766.3597188478025, 3767.830447629863, 669.517947078103, 673.4847841401332, 681.5127184063418, 685.7359916432209, 700.5866538742166, 524.9632525723239, 564.5060571999501, 602.6022824935521, 619.294004834489, 642.6825008732174, 8196.877046844302, 8337.541435221121, 8416.419653283023, 8419.458791769766, 8512.914870851213, 591.8461200088008, 606.7991345651723, 608.6986941464004, 612.586504109733, 627.4290395739855, 3319.302946381287, 3891.455196272524, 3962.7226094173707, 3966.940495892564, 4034.576369481221, 86154.56922715346, 87458.0, 87902.68145977815, 88323.0, 88481.0, 79517.0, 80399.59800200962, 80607.59696201481, 81656.59171704104, 82630.0, 73143.34283285815, 74282.0, 75548.62225688946, 76548.61725691336, 77842.22157778463, 7750.651220694969, 7950.602469876599, 7984.6406911687955, 8017.318527925192, 8198.057223419382, 45660.63018109462, 52515.737421312646, 54014.72992635012, 54977.25113744377, 55053.44946550483, 70123.0, 70958.29041709517, 71067.64466177636, 71953.6402317985, 77585.61207193928, 10307.330023548446, 11272.492737826982, 11337.0, 11727.061835053346, 12034.977026952807, 12099.697507562381, 12357.073219508393, 12386.938065309616, 12558.93720531428, 12602.36988150607, 11544.966175885262, 11815.527378905092, 12081.395930203631, 12087.576934807239, 12179.0, 11536.423178840856, 11753.647390578297, 11860.644180674593, 12165.45255463524, 12412.627621171017, 11139.10687965587, 11313.434328283389, 11435.542578296774, 11663.59177428786, 11670.416479175838, 9718.70843874685, 9758.902410976083, 9787.657431989846, 9804.607815687292, 9837.508124593885, 9746.512674366395, 9909.0, 9939.552720127756, 9942.950285248817, 10006.44964526958], "unit": "score", "name": "yui.html", "value": 7118.590746701416}], "name": "dromaeo_css", "value": 7785.790967921531}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 109.0, 112.0, 110.0, 112.0, 112.0, 120.0, 112.0, 111.0, 110.0], "unit": "score", "name": "ai-astar", "value": 112.1}, {"lowerIsBetter": true, "replicates": [154.0, 120.0, 142.0, 155.0, 117.0, 140.0, 117.0, 148.0, 146.0, 119.0], "unit": "score", "name": "audio-beat-detection", "value": 135.8}, {"lowerIsBetter": true, "replicates": [184.0, 184.0, 184.0, 183.0, 188.0, 186.0, 185.0, 186.0, 182.0, 184.0], "unit": "score", "name": "audio-dft", "value": 184.6}, {"lowerIsBetter": true, "replicates": [72.0, 78.0, 73.0, 116.0, 55.0, 75.0, 75.0, 82.0, 157.0, 46.0], "unit": "score", "name": "audio-fft", "value": 82.9}, {"lowerIsBetter": true, "replicates": [122.0, 128.0, 164.0, 77.0, 81.0, 82.0, 120.0, 76.0, 77.0, 119.0], "unit": "score", "name": "audio-oscillator", "value": 104.6}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 89.0, 89.0, 87.0, 85.0, 89.0, 86.0, 87.0, 85.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 87.0}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 93.0, 93.0, 92.0, 101.0, 94.0, 94.0, 92.0, 100.0], "unit": "score", "name": "imaging-darkroom", "value": 94.4}, {"lowerIsBetter": true, "replicates": [83.0, 87.0, 86.0, 85.0, 99.0, 133.0, 85.0, 85.0, 87.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 91.4}, {"lowerIsBetter": true, "replicates": [57.0, 58.0, 58.0, 58.0, 58.0, 58.0, 57.0, 67.0, 51.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 57.3}, {"lowerIsBetter": true, "replicates": [62.0, 60.0, 61.0, 78.0, 70.0, 68.0, 65.0, 66.0, 66.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.4}, {"lowerIsBetter": true, "replicates": [81.0, 77.0, 82.0, 81.0, 81.0, 79.0, 79.0, 87.0, 97.0, 105.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.9}, {"lowerIsBetter": true, "replicates": [130.0, 123.0, 122.0, 114.0, 118.0, 118.0, 130.0, 116.0, 120.0, 127.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.8}, {"lowerIsBetter": true, "replicates": [133.0, 125.0, 133.0, 135.0, 137.0, 122.0, 132.0, 132.0, 132.0, 136.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 131.7}, {"lowerIsBetter": true, "replicates": [66.0, 65.0, 65.0, 65.0, 67.0, 65.0, 65.0, 64.0, 65.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.3}], "name": "kraken", "value": 1420.1999999999998}]} 03:04:58 INFO - Return code: 0 03:04:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:04:58 INFO - # TBPL SUCCESS # 03:04:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:04:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:04:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:04:58 INFO - 2016-04-16 03:04:58.091 screenresolution[1682:10527] starting screenresolution argv=screenresolution get 03:04:58 INFO - 2016-04-16 03:04:58.093 screenresolution[1682:10527] Display 0: 1600x1200x32@60 03:04:58 INFO - 2016-04-16 03:04:58.099 screenresolution[1683:10530] starting screenresolution argv=screenresolution list 03:04:58 INFO - Available Modes on Display 0 03:04:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:04:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:04:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:04:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:04:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:04:58 INFO - Intel Iris: 03:04:58 INFO - Chipset Model: Intel Iris 03:04:58 INFO - Type: GPU 03:04:58 INFO - Bus: Built-In 03:04:58 INFO - VRAM (Dynamic, Max): 1536 MB 03:04:58 INFO - Vendor: Intel (0x8086) 03:04:58 INFO - Device ID: 0x0a2e 03:04:58 INFO - Revision ID: 0x0009 03:04:58 INFO - Displays: 03:04:58 INFO - Display: 03:04:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:04:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:04:58 INFO - Display Serial Number: 1600x1200 60 03:04:58 INFO - Main Display: Yes 03:04:58 INFO - Mirror: Off 03:04:58 INFO - Online: Yes 03:04:58 INFO - Rotation: Supported 03:04:58 INFO - Return code: 0 03:04:58 INFO - Running post-action listener: _resource_record_post_action 03:04:58 INFO - Running post-run listener: _resource_record_post_run 03:04:59 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 32017920; Write bytes: 663710208; Read time: 324; Write time: 1900 03:04:59 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 358375936; Write bytes: 353043968; Read time: 27893; Write time: 617 03:04:59 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 30236160; Write bytes: 299488256; Read time: 305; Write time: 1249 03:04:59 INFO - Running post-run listener: _upload_blobber_files 03:04:59 INFO - Blob upload gear active. 03:04:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:04:59 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:04:59 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:04:59 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:04:59 INFO - (blobuploader) - INFO - Open directory for files ... 03:04:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:04:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:05:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:00 INFO - (blobuploader) - INFO - Done attempting. 03:05:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:05:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:05:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:01 INFO - (blobuploader) - INFO - Done attempting. 03:05:01 INFO - (blobuploader) - INFO - Iteration through files over. 03:05:01 INFO - Return code: 0 03:05:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:05:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:05:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/772fa9013bae66d37debc5d38ee96a725a21c58b80d88be556a18b73005062a177902ea59f33d59a6c4e68320e130be990f3731640ee11ae3a5609433a5be367", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b64a539e4baa5a98643b2ee9dce0b30df83212a6434ce3fb6d82c2f36c99b71be8b5aa30e8818e5fb11f47665954c8b0dde9500296ecb7f58b30fff6fe33ee61"} 03:05:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:05:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:05:01 INFO - Contents: 03:05:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/772fa9013bae66d37debc5d38ee96a725a21c58b80d88be556a18b73005062a177902ea59f33d59a6c4e68320e130be990f3731640ee11ae3a5609433a5be367", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b64a539e4baa5a98643b2ee9dce0b30df83212a6434ce3fb6d82c2f36c99b71be8b5aa30e8818e5fb11f47665954c8b0dde9500296ecb7f58b30fff6fe33ee61"} 03:05:01 INFO - Running post-run listener: copy_logs_to_upload_dir 03:05:01 INFO - Copying logs to upload dir... 03:05:01 INFO - mkdir: /builds/slave/test/build/upload/logs 03:05:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1299.271394 ========= master_lag: 46.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 25 secs) (at 2016-04-16 03:05:48.023129) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-16 03:05:48.027363) ========= 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.ZKJpU17RVP/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.d75MTPUCzO/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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/772fa9013bae66d37debc5d38ee96a725a21c58b80d88be556a18b73005062a177902ea59f33d59a6c4e68320e130be990f3731640ee11ae3a5609433a5be367", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b64a539e4baa5a98643b2ee9dce0b30df83212a6434ce3fb6d82c2f36c99b71be8b5aa30e8818e5fb11f47665954c8b0dde9500296ecb7f58b30fff6fe33ee61"} build_url:https://queue.taskcluster.net/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011565 build_url: 'https://queue.taskcluster.net/v1/task/UjQ8wUVRQjSNr8a1J8PQRA/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/772fa9013bae66d37debc5d38ee96a725a21c58b80d88be556a18b73005062a177902ea59f33d59a6c4e68320e130be990f3731640ee11ae3a5609433a5be367", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b64a539e4baa5a98643b2ee9dce0b30df83212a6434ce3fb6d82c2f36c99b71be8b5aa30e8818e5fb11f47665954c8b0dde9500296ecb7f58b30fff6fe33ee61"}' ========= master_lag: 0.56 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-16 03:05:48.602015) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 27 secs) (at 2016-04-16 03:05:48.602337) ========= 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.ZKJpU17RVP/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.d75MTPUCzO/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.005019 ========= master_lag: 27.61 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 27 secs) (at 2016-04-16 03:06:16.218015) ========= ========= Started reboot skipped (results: 3, elapsed: 15 secs) (at 2016-04-16 03:06:16.220886) ========= ========= Finished reboot skipped (results: 3, elapsed: 15 secs) (at 2016-04-16 03:06:32.184661) ========= ========= Total master_lag: 74.34 =========