builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0095 starttime: 1462963509.38 results: success (0) buildid: 20160510142413 builduid: 93e058acf8334e7aae447a8479c457fb revision: 1dd42a351054c5b697873d647169019d4d77d762 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:09.382761) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:09.383346) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:09.383754) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:09.405089) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:09.405487) ========= 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.aMHzDit7Vp/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.7CM0zz5uCM/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-11 03:45:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.02M=0.001s 2016-05-11 03:45:09 (8.02 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.267827 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:09.690135) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:09.690451) ========= 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.aMHzDit7Vp/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.7CM0zz5uCM/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.021081 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:09.745579) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 03:45:09.745968) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1dd42a351054c5b697873d647169019d4d77d762 --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 1dd42a351054c5b697873d647169019d4d77d762 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aMHzDit7Vp/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.7CM0zz5uCM/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-11 03:45:09,820 truncating revision to first 12 chars 2016-05-11 03:45:09,820 Setting DEBUG logging. 2016-05-11 03:45:09,820 attempt 1/10 2016-05-11 03:45:09,820 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1dd42a351054?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 03:45:10,599 unpacking tar archive at: mozilla-aurora-1dd42a351054/testing/mozharness/ program finished with exit code 0 elapsedTime=1.021023 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 03:45:10.783365) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:10.786723) ========= script_repo_revision: 1dd42a351054c5b697873d647169019d4d77d762 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:10.787108) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:10.787371) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:10.800447) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-11 03:45:10.800786) ========= /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.aMHzDit7Vp/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.7CM0zz5uCM/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 03:45:10 INFO - MultiFileLogger online at 20160511 03:45:10 in /builds/slave/test 03:45:10 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 03:45:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:45:10 INFO - {'append_to_log': False, 03:45:10 INFO - 'base_work_dir': '/builds/slave/test', 03:45:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:45:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:45:10 INFO - 'branch': 'Mozilla-Aurora', 03:45:10 INFO - 'buildbot_json_path': 'buildprops.json', 03:45:10 INFO - 'config_files': ('talos/mac_config.py',), 03:45:10 INFO - 'default_actions': ('clobber', 03:45:10 INFO - 'read-buildbot-config', 03:45:10 INFO - 'download-and-extract', 03:45:10 INFO - 'populate-webroot', 03:45:10 INFO - 'create-virtualenv', 03:45:10 INFO - 'install', 03:45:10 INFO - 'run-tests'), 03:45:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:45:10 INFO - 'download_minidump_stackwalk': True, 03:45:10 INFO - 'download_symbols': 'ondemand', 03:45:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:45:10 INFO - 'tooltool.py': '/tools/tooltool.py', 03:45:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:45:10 INFO - '/tools/misc-python/virtualenv.py')}, 03:45:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:45:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:45:10 INFO - 'installer_path': 'installer.exe', 03:45:10 INFO - 'log_level': 'info', 03:45:10 INFO - 'log_name': 'talos', 03:45:10 INFO - 'log_to_console': True, 03:45:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:45:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:45:10 INFO - 'opt_config_files': (), 03:45:10 INFO - 'pip_index': False, 03:45:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:45:10 INFO - 'cmd': ('bash', 03:45:10 INFO - '-c', 03:45:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:45:10 INFO - 'enabled': True, 03:45:10 INFO - 'halt_on_failure': False, 03:45:10 INFO - 'name': 'check_screen_resolution'},), 03:45:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:45:10 INFO - 'cmd': ('bash', 03:45:10 INFO - '-c', 03:45:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:45:10 INFO - 'enabled': True, 03:45:10 INFO - 'halt_on_failure': False, 03:45:10 INFO - 'name': 'check_screen_resolution'},), 03:45:10 INFO - 'run_cmd_checks_enabled': True, 03:45:10 INFO - 'sps_profile': False, 03:45:10 INFO - 'sps_profile_interval': 0, 03:45:10 INFO - 'suite': 'dromaeojs', 03:45:10 INFO - 'system_bits': '32', 03:45:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:45:10 INFO - 'title': 't-yosemite-r7-0095', 03:45:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:45:10 INFO - 'use_talos_json': True, 03:45:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:45:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:45:10 INFO - 'work_dir': 'build'} 03:45:10 INFO - ##### 03:45:10 INFO - ##### Running clobber step. 03:45:10 INFO - ##### 03:45:10 INFO - Running pre-action listener: _resource_record_pre_action 03:45:10 INFO - Running main action method: clobber 03:45:10 INFO - rmtree: /builds/slave/test/build 03:45:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:45:11 INFO - Running post-action listener: _resource_record_post_action 03:45:11 INFO - ##### 03:45:11 INFO - ##### Running read-buildbot-config step. 03:45:11 INFO - ##### 03:45:11 INFO - Running pre-action listener: _resource_record_pre_action 03:45:11 INFO - Running main action method: read_buildbot_config 03:45:11 INFO - Using buildbot properties: 03:45:11 INFO - { 03:45:11 INFO - "project": "", 03:45:11 INFO - "product": "firefox", 03:45:11 INFO - "script_repo_revision": "production", 03:45:11 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:45:11 INFO - "repository": "", 03:45:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:45:11 INFO - "buildid": "20160510142413", 03:45:11 INFO - "pgo_build": "False", 03:45:11 INFO - "basedir": "/builds/slave/test", 03:45:11 INFO - "buildnumber": 8, 03:45:11 INFO - "slavename": "t-yosemite-r7-0095", 03:45:11 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 03:45:11 INFO - "platform": "yosemite_r7", 03:45:11 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:45:11 INFO - "branch": "mozilla-aurora", 03:45:11 INFO - "repo_path": "releases/mozilla-aurora", 03:45:11 INFO - "revision": "1dd42a351054c5b697873d647169019d4d77d762", 03:45:11 INFO - "stage_platform": "macosx64", 03:45:11 INFO - "builduid": "93e058acf8334e7aae447a8479c457fb", 03:45:11 INFO - "slavebuilddir": "test" 03:45:11 INFO - } 03:45:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:45:11 INFO - Running post-action listener: _resource_record_post_action 03:45:11 INFO - ##### 03:45:11 INFO - ##### Running download-and-extract step. 03:45:11 INFO - ##### 03:45:11 INFO - Running pre-action listener: _resource_record_pre_action 03:45:11 INFO - Running main action method: download_and_extract 03:45:11 INFO - mkdir: /builds/slave/test/build/tests 03:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:11 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:45:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:45:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:45:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:45:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 03:45:12 INFO - Downloaded 1199 bytes. 03:45:12 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:45:12 INFO - Using the following test package requirements: 03:45:12 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:45:12 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:12 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:45:12 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:12 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:45:12 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:12 INFO - u'jsshell-mac.zip'], 03:45:12 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:12 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:45:12 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:45:12 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:12 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:45:12 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:12 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:45:12 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:12 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:45:12 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:12 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:45:12 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:45:12 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')]} 03:45:12 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:45:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:45:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:45:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:45:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:45:13 INFO - Downloaded 18089246 bytes. 03:45:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:45:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:45:16 INFO - Return code: 0 03:45:16 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:16 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:45:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:45:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:45:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:45:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:45:17 INFO - Downloaded 18089246 bytes. 03:45:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:45:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:45:21 INFO - Return code: 0 03:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:21 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:45:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:45:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:45:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:45:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:45:22 INFO - Downloaded 10840145 bytes. 03:45:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:45:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:45:22 INFO - Return code: 0 03:45:22 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')]} 03:45:22 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:45:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:45:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:45:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:45:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 03:45:26 INFO - Downloaded 114088564 bytes. 03:45:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:45:26 INFO - mkdir: /builds/slave/test/properties 03:45:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:45:26 INFO - Writing to file /builds/slave/test/properties/build_url 03:45:26 INFO - Contents: 03:45:26 INFO - build_url:https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:45:27 INFO - Running post-action listener: _resource_record_post_action 03:45:27 INFO - Running post-action listener: set_extra_try_arguments 03:45:27 INFO - ##### 03:45:27 INFO - ##### Running populate-webroot step. 03:45:27 INFO - ##### 03:45:27 INFO - Running pre-action listener: _resource_record_pre_action 03:45:27 INFO - Running main action method: populate_webroot 03:45:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:45:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:45:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:45:27 INFO - '--tppagecycles', 03:45:27 INFO - '7'], 03:45:27 INFO - 'tests': ['tsvgm']}}, 03:45:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:45:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:45:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:45:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:45:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:45:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:45:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:27 INFO - 'talos_options': ['--disable-e10s'], 03:45:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:45:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:45:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:27 INFO - 'talos_options': ['--disable-e10s'], 03:45:27 INFO - 'tests': ['damp', 'tps']}, 03:45:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:27 INFO - 'tests': ['damp', 'tps']}, 03:45:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:45:27 INFO - 'tests': ['dromaeo_dom']}, 03:45:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:45:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:45:27 INFO - 'tests': ['a11yr', 03:45:27 INFO - 'ts_paint', 03:45:27 INFO - 'tpaint', 03:45:27 INFO - 'sessionrestore', 03:45:27 INFO - 'sessionrestore_no_auto_restore', 03:45:27 INFO - 'tabpaint']}, 03:45:27 INFO - 'other-e10s': {'tests': ['a11yr', 03:45:27 INFO - 'ts_paint', 03:45:27 INFO - 'tpaint', 03:45:27 INFO - 'sessionrestore', 03:45:27 INFO - 'sessionrestore_no_auto_restore', 03:45:27 INFO - 'tabpaint']}, 03:45:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:45:27 INFO - 'ts_paint', 03:45:27 INFO - 'tpaint', 03:45:27 INFO - 'sessionrestore', 03:45:27 INFO - 'sessionrestore_no_auto_restore', 03:45:27 INFO - 'tabpaint']}, 03:45:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:45:27 INFO - 'ts_paint', 03:45:27 INFO - 'tpaint', 03:45:27 INFO - 'sessionrestore', 03:45:27 INFO - 'sessionrestore_no_auto_restore', 03:45:27 INFO - 'tabpaint']}, 03:45:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:45:27 INFO - 'tests': ['a11yr', 03:45:27 INFO - 'ts_paint', 03:45:27 INFO - 'tpaint', 03:45:27 INFO - 'sessionrestore', 03:45:27 INFO - 'sessionrestore_no_auto_restore', 03:45:27 INFO - 'tabpaint']}, 03:45:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:45:27 INFO - 'tests': ['a11yr', 03:45:27 INFO - 'ts_paint', 03:45:27 INFO - 'tpaint', 03:45:27 INFO - 'sessionrestore', 03:45:27 INFO - 'sessionrestore_no_auto_restore', 03:45:27 INFO - 'tabpaint']}, 03:45:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:45:27 INFO - 'tests': ['tsvgx', 03:45:27 INFO - 'tsvgr_opacity', 03:45:27 INFO - 'tart', 03:45:27 INFO - 'tscrollx', 03:45:27 INFO - 'cart']}, 03:45:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:45:27 INFO - 'tsvgr_opacity', 03:45:27 INFO - 'tart', 03:45:27 INFO - 'tscrollx', 03:45:27 INFO - 'cart']}, 03:45:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:27 INFO - 'talos_options': ['--disable-e10s'], 03:45:27 INFO - 'tests': ['tp5o']}, 03:45:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:27 INFO - 'tests': ['tp5o']}, 03:45:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:27 INFO - 'talos_options': ['--disable-e10s', 03:45:27 INFO - '--xperf_path', 03:45:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:45:27 INFO - 'tests': ['tp5n']}, 03:45:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:27 INFO - 'talos_options': ['--xperf_path', 03:45:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:45:27 INFO - 'tests': ['tp5n']}}, 03:45:27 INFO - 'talos.zip': {'path': '', 03:45:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:45:27 INFO - Running post-action listener: _resource_record_post_action 03:45:27 INFO - ##### 03:45:27 INFO - ##### Running create-virtualenv step. 03:45:27 INFO - ##### 03:45:27 INFO - Running pre-action listener: _resource_record_pre_action 03:45:27 INFO - Running main action method: create_virtualenv 03:45:27 INFO - Creating virtualenv /builds/slave/test/build/venv 03:45:27 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 03:45:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:45:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:45:27 INFO - Using real prefix '/tools/python27' 03:45:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:45:27 INFO - Installing distribute.............................................................................................................................................................................................done. 03:45:30 INFO - Installing pip.................done. 03:45:30 INFO - Return code: 0 03:45:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:45:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aae1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9350da7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a970e00>, '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 0x10a7d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a96bb20>, '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.aMHzDit7Vp/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.7CM0zz5uCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 03:45:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:45:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMHzDit7Vp/Render', 03:45:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:45:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:45:30 INFO - 'HOME': '/Users/cltbld', 03:45:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:45:30 INFO - 'LOGNAME': 'cltbld', 03:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:30 INFO - 'MOZ_NO_REMOTE': '1', 03:45:30 INFO - 'NO_EM_RESTART': '1', 03:45:30 INFO - 'PAGER': '/bin/cat', 03:45:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:30 INFO - 'PWD': '/builds/slave/test', 03:45:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:45:30 INFO - 'SHELL': '/bin/bash', 03:45:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7CM0zz5uCM/Listeners', 03:45:30 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 03:45:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:45:30 INFO - 'USER': 'cltbld', 03:45:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:45:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:30 INFO - 'XPC_FLAGS': '0x0', 03:45:30 INFO - 'XPC_SERVICE_NAME': '0', 03:45:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:31 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 03:45:31 INFO - Cleaning up... 03:45:31 INFO - Return code: 0 03:45:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:45: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')]} 03:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45: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')]} 03:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45: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', '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 0x10aad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aae1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9350da7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a970e00>, '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 0x10a7d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a96bb20>, '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.aMHzDit7Vp/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.7CM0zz5uCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 03:45: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', 'psutil>=0.7.1'] in /builds/slave/test/build 03:45: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 psutil>=0.7.1 03:45:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMHzDit7Vp/Render', 03:45:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:45:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:45:31 INFO - 'HOME': '/Users/cltbld', 03:45:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:45:31 INFO - 'LOGNAME': 'cltbld', 03:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:31 INFO - 'MOZ_NO_REMOTE': '1', 03:45:31 INFO - 'NO_EM_RESTART': '1', 03:45:31 INFO - 'PAGER': '/bin/cat', 03:45:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:31 INFO - 'PWD': '/builds/slave/test', 03:45:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:45:31 INFO - 'SHELL': '/bin/bash', 03:45:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7CM0zz5uCM/Listeners', 03:45:31 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 03:45:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:45:31 INFO - 'USER': 'cltbld', 03:45:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:45:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:31 INFO - 'XPC_FLAGS': '0x0', 03:45:31 INFO - 'XPC_SERVICE_NAME': '0', 03:45:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:31 INFO - Downloading/unpacking psutil>=0.7.1 03:45: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 03:45: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 03:45: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 03:45: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 03:45: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 03:45: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 03:45:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:45:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:45:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:45:34 INFO - Installing collected packages: psutil 03:45:34 INFO - Running setup.py install for psutil 03:45:34 INFO - building 'psutil._psutil_osx' extension 03:45:34 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 03:45:34 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 03:45:34 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 03:45:34 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 03:45:34 INFO - building 'psutil._psutil_posix' extension 03:45:34 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 03:45:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:45:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:45:34 INFO - ^ 03:45:34 INFO - 1 warning generated. 03:45:34 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 03:45:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:45:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:45:35 INFO - Successfully installed psutil 03:45:35 INFO - Cleaning up... 03:45:35 INFO - Return code: 0 03:45:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:35 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 0x10aad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aae1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9350da7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a970e00>, '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 0x10a7d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a96bb20>, '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.aMHzDit7Vp/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.7CM0zz5uCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 03:45:35 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 03:45:35 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 03:45:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMHzDit7Vp/Render', 03:45:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:45:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:45:35 INFO - 'HOME': '/Users/cltbld', 03:45:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:45:35 INFO - 'LOGNAME': 'cltbld', 03:45:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:35 INFO - 'MOZ_NO_REMOTE': '1', 03:45:35 INFO - 'NO_EM_RESTART': '1', 03:45:35 INFO - 'PAGER': '/bin/cat', 03:45:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:45:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:35 INFO - 'PWD': '/builds/slave/test', 03:45:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:45:35 INFO - 'SHELL': '/bin/bash', 03:45:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7CM0zz5uCM/Listeners', 03:45:35 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 03:45:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:45:35 INFO - 'USER': 'cltbld', 03:45:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:45:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:45:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:35 INFO - 'XPC_FLAGS': '0x0', 03:45:35 INFO - 'XPC_SERVICE_NAME': '0', 03:45:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:45:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:45:35 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 03:45:35 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 03:45:35 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 03:45:35 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 03:45:35 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 03:45:35 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 03:45:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:45:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:45:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:45:37 INFO - Installing collected packages: mozsystemmonitor 03:45:37 INFO - Running setup.py install for mozsystemmonitor 03:45:38 INFO - Successfully installed mozsystemmonitor 03:45:38 INFO - Cleaning up... 03:45:38 INFO - Return code: 0 03:45:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:38 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 0x10aad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aae1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9350da7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a970e00>, '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 0x10a7d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a96bb20>, '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.aMHzDit7Vp/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.7CM0zz5uCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 03:45:38 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 03:45:38 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 03:45:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMHzDit7Vp/Render', 03:45:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:45:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:45:38 INFO - 'HOME': '/Users/cltbld', 03:45:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:45:38 INFO - 'LOGNAME': 'cltbld', 03:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:38 INFO - 'MOZ_NO_REMOTE': '1', 03:45:38 INFO - 'NO_EM_RESTART': '1', 03:45:38 INFO - 'PAGER': '/bin/cat', 03:45:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:38 INFO - 'PWD': '/builds/slave/test', 03:45:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:45:38 INFO - 'SHELL': '/bin/bash', 03:45:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7CM0zz5uCM/Listeners', 03:45:38 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 03:45:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:45:38 INFO - 'USER': 'cltbld', 03:45:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:45:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:38 INFO - 'XPC_FLAGS': '0x0', 03:45:38 INFO - 'XPC_SERVICE_NAME': '0', 03:45:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:45:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:38 INFO - Downloading/unpacking blobuploader==1.2.4 03:45:38 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 03:45:38 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 03:45:38 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 03:45:38 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 03:45:38 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 03:45:38 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 03:45:40 INFO - Downloading blobuploader-1.2.4.tar.gz 03:45:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:45:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:45:40 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 03:45:40 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 03:45:40 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 03:45:40 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 03:45:40 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 03:45:40 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 03:45:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:45:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:41 INFO - Downloading docopt-0.6.1.tar.gz 03:45:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:45:41 INFO - Installing collected packages: blobuploader, requests, docopt 03:45:41 INFO - Running setup.py install for blobuploader 03:45:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:45:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:45:42 INFO - Running setup.py install for requests 03:45:42 INFO - Running setup.py install for docopt 03:45:42 INFO - Successfully installed blobuploader requests docopt 03:45:42 INFO - Cleaning up... 03:45:42 INFO - Return code: 0 03:45:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:45:42 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')]} 03:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:42 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')]} 03:45:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:42 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 0x10aad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aae1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9350da7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a970e00>, '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 0x10a7d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a96bb20>, '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.aMHzDit7Vp/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.7CM0zz5uCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 03:45:42 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 03:45:42 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 03:45:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMHzDit7Vp/Render', 03:45:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:45:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:45:42 INFO - 'HOME': '/Users/cltbld', 03:45:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:45:42 INFO - 'LOGNAME': 'cltbld', 03:45:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:42 INFO - 'MOZ_NO_REMOTE': '1', 03:45:42 INFO - 'NO_EM_RESTART': '1', 03:45:42 INFO - 'PAGER': '/bin/cat', 03:45:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:45:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:42 INFO - 'PWD': '/builds/slave/test', 03:45:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:45:42 INFO - 'SHELL': '/bin/bash', 03:45:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7CM0zz5uCM/Listeners', 03:45:42 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 03:45:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:45:42 INFO - 'USER': 'cltbld', 03:45:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:45:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:45:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:42 INFO - 'XPC_FLAGS': '0x0', 03:45:42 INFO - 'XPC_SERVICE_NAME': '0', 03:45:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:45:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:45:43 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-xCGJUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:45:43 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Q9pQOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:45:43 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-_deLVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:45:43 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-08SHeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:45:43 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-oB3h7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:45:43 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-ya7qf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:45:43 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-A17CXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:45:43 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-rJFcXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:45:43 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-sShai0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:45:44 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-qaWGfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:45:44 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-UX1d8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:45:44 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-OVlcV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:45:44 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-RSqXY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:45:44 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-ccYhBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:45:44 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-YxvKye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:45:44 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-jYzsFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:45:44 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-6tU4Qa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:45:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:45:44 INFO - Running setup.py install for manifestparser 03:45:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:45:45 INFO - Running setup.py install for mozcrash 03:45:45 INFO - Running setup.py install for mozdebug 03:45:45 INFO - Running setup.py install for mozdevice 03:45:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:45:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:45:45 INFO - Running setup.py install for mozfile 03:45:45 INFO - Running setup.py install for mozhttpd 03:45:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:45:45 INFO - Running setup.py install for mozinfo 03:45:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:45:45 INFO - Running setup.py install for mozInstall 03:45:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:45:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:45:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:45:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:45:46 INFO - Running setup.py install for mozleak 03:45:46 INFO - Running setup.py install for mozlog 03:45:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:45:46 INFO - Running setup.py install for moznetwork 03:45:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:45:46 INFO - Running setup.py install for mozprocess 03:45:46 INFO - Running setup.py install for mozprofile 03:45:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:45:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:45:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:45:46 INFO - Running setup.py install for mozrunner 03:45:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:45:47 INFO - Running setup.py install for mozscreenshot 03:45:47 INFO - Running setup.py install for moztest 03:45:47 INFO - Running setup.py install for mozversion 03:45:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:45:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:45:47 INFO - Cleaning up... 03:45:47 INFO - Return code: 0 03:45:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:47 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 0x10aad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aae1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9350da7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a970e00>, '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 0x10a7d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a96bb20>, '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.aMHzDit7Vp/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.7CM0zz5uCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 03:45:47 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 03:45:47 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 03:45:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMHzDit7Vp/Render', 03:45:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:45:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:45:47 INFO - 'HOME': '/Users/cltbld', 03:45:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:45:47 INFO - 'LOGNAME': 'cltbld', 03:45:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:47 INFO - 'MOZ_NO_REMOTE': '1', 03:45:47 INFO - 'NO_EM_RESTART': '1', 03:45:47 INFO - 'PAGER': '/bin/cat', 03:45:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:45:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:47 INFO - 'PWD': '/builds/slave/test', 03:45:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:45:47 INFO - 'SHELL': '/bin/bash', 03:45:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7CM0zz5uCM/Listeners', 03:45:47 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 03:45:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:45:47 INFO - 'USER': 'cltbld', 03:45:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:45:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:45:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:47 INFO - 'XPC_FLAGS': '0x0', 03:45:47 INFO - 'XPC_SERVICE_NAME': '0', 03:45:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:45:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:45:47 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-6dNbh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:45:47 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)) 03:45:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:45:47 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-L27nit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:45:48 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)) 03:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:45:48 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-O37hFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:45:48 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)) 03:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:45:48 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-rDt0vq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:45:48 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)) 03:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:45:48 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-_XQ35I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:45:48 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)) 03:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:45:48 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-b5_Mms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:45:48 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)) 03:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:45:48 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-DmF45c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:45:48 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)) 03:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:45:48 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-qrUjP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:45:48 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)) 03:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:45:48 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-hg5hXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:45:48 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)) 03:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:45:48 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-_SeeKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:45:48 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)) 03:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:45:48 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-8A1dUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:45:48 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)) 03:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:45:48 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-PROjIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:45:49 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)) 03:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:45:49 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-X4Rln9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:45:49 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)) 03:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:45:49 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-jGyt6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:45:49 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)) 03:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:45:49 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-jsCPEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:45:49 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)) 03:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:45:49 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-OiVujX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:45:49 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)) 03:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:45:49 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-TrVTnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:45:49 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)) 03:45:49 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)) 03:45:49 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)) 03:45:49 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)) 03:45:49 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)) 03:45:49 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)) 03:45:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:45: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 03:45: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 03:45: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 03:45: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 03:45: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 03:45: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 03:45:51 INFO - Downloading blessings-1.6.tar.gz 03:45:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:45:52 INFO - Installing collected packages: blessings 03:45:52 INFO - Running setup.py install for blessings 03:45:52 INFO - Successfully installed blessings 03:45:52 INFO - Cleaning up... 03:45:52 INFO - Return code: 0 03:45:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:45:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:45:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:45:52 INFO - Reading from file tmpfile_stdout 03:45:52 INFO - Current package versions: 03:45:52 INFO - blessings == 1.6 03:45:52 INFO - blobuploader == 1.2.4 03:45:52 INFO - docopt == 0.6.1 03:45:52 INFO - manifestparser == 1.1 03:45:52 INFO - mozInstall == 1.12 03:45:52 INFO - mozcrash == 0.17 03:45:52 INFO - mozdebug == 0.1 03:45:52 INFO - mozdevice == 0.48 03:45:52 INFO - mozfile == 1.2 03:45:52 INFO - mozhttpd == 0.7 03:45:52 INFO - mozinfo == 0.9 03:45:52 INFO - mozleak == 0.1 03:45:52 INFO - mozlog == 3.1 03:45:52 INFO - moznetwork == 0.27 03:45:52 INFO - mozprocess == 0.22 03:45:52 INFO - mozprofile == 0.28 03:45:52 INFO - mozrunner == 6.11 03:45:52 INFO - mozscreenshot == 0.1 03:45:52 INFO - mozsystemmonitor == 0.0 03:45:52 INFO - moztest == 0.7 03:45:52 INFO - mozversion == 1.4 03:45:52 INFO - psutil == 3.1.1 03:45:52 INFO - requests == 1.2.3 03:45:52 INFO - wsgiref == 0.1.2 03:45:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:45:52 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')]} 03:45:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:52 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')]} 03:45:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:52 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 0x10aad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aae1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9350da7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a970e00>, '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 0x10a7d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a96bb20>, '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.aMHzDit7Vp/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.7CM0zz5uCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 03:45:52 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 03:45:52 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 03:45:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMHzDit7Vp/Render', 03:45:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:45:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:45:52 INFO - 'HOME': '/Users/cltbld', 03:45:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:45:52 INFO - 'LOGNAME': 'cltbld', 03:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:52 INFO - 'MOZ_NO_REMOTE': '1', 03:45:52 INFO - 'NO_EM_RESTART': '1', 03:45:52 INFO - 'PAGER': '/bin/cat', 03:45:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:45:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:52 INFO - 'PWD': '/builds/slave/test', 03:45:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:45:52 INFO - 'SHELL': '/bin/bash', 03:45:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7CM0zz5uCM/Listeners', 03:45:52 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 03:45:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:45:52 INFO - 'USER': 'cltbld', 03:45:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:45:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:52 INFO - 'XPC_FLAGS': '0x0', 03:45:52 INFO - 'XPC_SERVICE_NAME': '0', 03:45:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:45:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:52 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)) 03:45:52 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)) 03:45:52 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)) 03:45:52 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)) 03:45:52 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)) 03:45:52 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)) 03:45:52 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)) 03:45:52 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)) 03:45:52 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)) 03:45:52 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)) 03:45:52 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)) 03:45:52 INFO - Cleaning up... 03:45:52 INFO - Return code: 0 03:45:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:45:52 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')]} 03:45:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:52 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')]} 03:45:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:52 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 0x10aad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aae1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9350da7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a970e00>, '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 0x10a7d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a96bb20>, '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.aMHzDit7Vp/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.7CM0zz5uCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 03:45:52 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 03:45:52 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 03:45:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMHzDit7Vp/Render', 03:45:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:45:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:45:52 INFO - 'HOME': '/Users/cltbld', 03:45:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:45:52 INFO - 'LOGNAME': 'cltbld', 03:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:52 INFO - 'MOZ_NO_REMOTE': '1', 03:45:52 INFO - 'NO_EM_RESTART': '1', 03:45:52 INFO - 'PAGER': '/bin/cat', 03:45:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:45:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:52 INFO - 'PWD': '/builds/slave/test', 03:45:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:45:52 INFO - 'SHELL': '/bin/bash', 03:45:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7CM0zz5uCM/Listeners', 03:45:52 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 03:45:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:45:52 INFO - 'USER': 'cltbld', 03:45:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:45:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:52 INFO - 'XPC_FLAGS': '0x0', 03:45:52 INFO - 'XPC_SERVICE_NAME': '0', 03:45:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:45:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:53 INFO - Downloading/unpacking jsonschema 03:45: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 03:45: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 03:45: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 03:45: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 03:45: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 03:45: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 03:45:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:45:55 INFO - Downloading/unpacking functools32 (from jsonschema) 03:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:55 INFO - Downloading functools32-3.2.3-2.tar.gz 03:45:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:45:55 INFO - warning: no files found matching '*.txt' 03:45:55 INFO - no previously-included directories found matching 'build' 03:45:55 INFO - no previously-included directories found matching 'dist' 03:45:55 INFO - no previously-included directories found matching '.git*' 03:45:56 INFO - Installing collected packages: jsonschema, functools32 03:45:56 INFO - Running setup.py install for functools32 03:45:56 INFO - warning: no files found matching '*.txt' 03:45:56 INFO - no previously-included directories found matching 'build' 03:45:56 INFO - no previously-included directories found matching 'dist' 03:45:56 INFO - no previously-included directories found matching '.git*' 03:45:56 INFO - Successfully installed jsonschema functools32 03:45:56 INFO - Cleaning up... 03:45:56 INFO - Return code: 0 03:45:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:45:56 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')]} 03:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:56 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')]} 03:45:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:56 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 0x10aad1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aae1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9350da7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a970e00>, '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 0x10a7d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a96bb20>, '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.aMHzDit7Vp/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.7CM0zz5uCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 03:45:56 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 03:45:56 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 03:45:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMHzDit7Vp/Render', 03:45:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:45:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:45:56 INFO - 'HOME': '/Users/cltbld', 03:45:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:45:56 INFO - 'LOGNAME': 'cltbld', 03:45:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:56 INFO - 'MOZ_NO_REMOTE': '1', 03:45:56 INFO - 'NO_EM_RESTART': '1', 03:45:56 INFO - 'PAGER': '/bin/cat', 03:45:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:45:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:56 INFO - 'PWD': '/builds/slave/test', 03:45:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:45:56 INFO - 'SHELL': '/bin/bash', 03:45:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7CM0zz5uCM/Listeners', 03:45:56 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 03:45:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:45:56 INFO - 'USER': 'cltbld', 03:45:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:45:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:45:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:56 INFO - 'XPC_FLAGS': '0x0', 03:45:56 INFO - 'XPC_SERVICE_NAME': '0', 03:45:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:45:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:56 INFO - Downloading/unpacking flake8 03:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:45:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:45:58 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 03:45:58 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 03:45:58 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 03:45:58 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 03:45:58 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 03:45:58 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 03:45:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:45:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:46:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:46:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:46:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:46:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:46:00 INFO - no previously-included directories found matching 'docs/_build' 03:46:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:46:00 INFO - Running setup.py install for pep8 03:46:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:46:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:46:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:46:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:46:00 INFO - no previously-included directories found matching 'docs/_build' 03:46:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:46:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:46:00 INFO - Cleaning up... 03:46:00 INFO - Return code: 0 03:46:00 INFO - Running post-action listener: _resource_record_post_action 03:46:00 INFO - Running post-action listener: _start_resource_monitoring 03:46:00 INFO - Starting resource monitoring. 03:46:00 INFO - ##### 03:46:00 INFO - ##### Running install step. 03:46:00 INFO - ##### 03:46:00 INFO - Running pre-action listener: _resource_record_pre_action 03:46:00 INFO - Running main action method: install 03:46:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:46:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:46:00 INFO - Reading from file tmpfile_stdout 03:46:00 INFO - Detecting whether we're running mozinstall >=1.0... 03:46:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:46:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:46:01 INFO - Reading from file tmpfile_stdout 03:46:01 INFO - Output received: 03:46:01 INFO - Usage: mozinstall [options] installer 03:46:01 INFO - Options: 03:46:01 INFO - -h, --help show this help message and exit 03:46:01 INFO - -d DEST, --destination=DEST 03:46:01 INFO - Directory to install application into. [default: 03:46:01 INFO - "/builds/slave/test"] 03:46:01 INFO - --app=APP Application being installed. [default: firefox] 03:46:01 INFO - mkdir: /builds/slave/test/build/application 03:46:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:46:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:46:32 INFO - Reading from file tmpfile_stdout 03:46:32 INFO - Output received: 03:46:32 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:46:32 INFO - Running post-action listener: _resource_record_post_action 03:46:32 INFO - ##### 03:46:32 INFO - ##### Running run-tests step. 03:46:32 INFO - ##### 03:46:32 INFO - Running pre-action listener: _resource_record_pre_action 03:46:32 INFO - Running main action method: run_tests 03:46:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:46:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:46:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:46:32 INFO - 2016-05-11 03:46:32.060 screenresolution[11195:199780] starting screenresolution argv=screenresolution get 03:46:32 INFO - 2016-05-11 03:46:32.063 screenresolution[11195:199780] Display 0: 1600x1200x32@60 03:46:32 INFO - 2016-05-11 03:46:32.069 screenresolution[11196:199782] starting screenresolution argv=screenresolution list 03:46:32 INFO - Available Modes on Display 0 03:46:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:46:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:46:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:46:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:46:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:46:32 INFO - Intel Iris: 03:46:32 INFO - Chipset Model: Intel Iris 03:46:32 INFO - Type: GPU 03:46:32 INFO - Bus: Built-In 03:46:32 INFO - VRAM (Dynamic, Max): 1536 MB 03:46:32 INFO - Vendor: Intel (0x8086) 03:46:32 INFO - Device ID: 0x0a2e 03:46:32 INFO - Revision ID: 0x0009 03:46:32 INFO - Displays: 03:46:32 INFO - Display: 03:46:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:46:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:46:32 INFO - Display Serial Number: 1600x1200 60 03:46:32 INFO - Main Display: Yes 03:46:32 INFO - Mirror: Off 03:46:32 INFO - Online: Yes 03:46:32 INFO - Rotation: Supported 03:46:32 INFO - Return code: 0 03:46:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:46:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:46:32 INFO - Python 2.7.3 03:46:32 INFO - Return code: 0 03:46:32 INFO - grabbing minidump binary from tooltool 03:46:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:32 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 0x10a970e00>, '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 0x10a7d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a96bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:46:32 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 03:46:32 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 03:46:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:46:32 INFO - Return code: 0 03:46:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:46:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:46:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:46:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:46:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:46:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:46:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:46:33 INFO - Return code: 0 03:46:33 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/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--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 03:46:33 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/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0095 --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 03:46:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aMHzDit7Vp/Render', 03:46:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:33 INFO - 'HOME': '/Users/cltbld', 03:46:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:33 INFO - 'LOGNAME': 'cltbld', 03:46:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:46:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:46:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:33 INFO - 'MOZ_NO_REMOTE': '1', 03:46:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:46:33 INFO - 'NO_EM_RESTART': '1', 03:46:33 INFO - 'PAGER': '/bin/cat', 03:46:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:33 INFO - 'PWD': '/builds/slave/test', 03:46:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:46:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:33 INFO - 'SHELL': '/bin/bash', 03:46:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7CM0zz5uCM/Listeners', 03:46:33 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 03:46:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:33 INFO - 'USER': 'cltbld', 03:46:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:33 INFO - 'XPC_FLAGS': '0x0', 03:46:33 INFO - 'XPC_SERVICE_NAME': '0', 03:46:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:33 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/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--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 03:46:33 INFO - mozversion application_buildid: 20160510142413 03:46:33 INFO - mozversion application_changeset: 1dd42a351054c5b697873d647169019d4d77d762 03:46:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:46:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:46:33 INFO - mozversion application_name: Firefox 03:46:33 INFO - mozversion application_remotingname: firefox-dev 03:46:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:46:33 INFO - mozversion application_vendor: Mozilla 03:46:33 INFO - mozversion application_version: 48.0a2 03:46:33 INFO - mozversion platform_buildid: 20160510142413 03:46:33 INFO - mozversion platform_changeset: 1dd42a351054c5b697873d647169019d4d77d762 03:46:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:46:33 INFO - mozversion platform_version: 48.0a2 03:46:33 INFO - using testdate: 1462963593 03:46:33 INFO - actual date: 1462963593 03:46:33 INFO - starting webserver on 'localhost:64773' 03:46:33 INFO - SUITE-START | Running 2 tests 03:46:33 INFO - TEST-START | dromaeo_css 03:46:33 INFO - operating with platform_type : mac_ 03:46:33 INFO - Initialising browser for dromaeo_css test... 03:46:33 INFO - TEST-INFO | started process 11214 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmp7rBB6a/profile http://localhost:64773/getInfo.html) 03:46:35 INFO - PROCESS | 11214 | __metrics Screen width/height:1600/1200 03:46:35 INFO - PROCESS | 11214 | colorDepth:24 03:46:35 INFO - PROCESS | 11214 | Browser inner width/height: 1024/689 03:46:35 INFO - PROCESS | 11214 | __metrics 03:46:36 INFO - PROCESS | 11214 | 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:46:36 ERROR - PROCESS | 11214 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:46:36 INFO - TEST-INFO | 11214: exit 0 03:46:36 INFO - Browser initialized. 03:46:36 INFO - Running cycle 1/1 for dromaeo_css test... 03:46:36 INFO - TEST-INFO | started process 11215 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmp7rBB6a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:46:48 INFO - PROCESS | 11215 | RSS: Main: 211468288 03:46:48 INFO - PROCESS | 11215 | 03:47:39 INFO - PROCESS | 11215 | 1462963659467 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] 03:49:51 INFO - PROCESS | 11215 | Cycle 1(1): loaded http://localhost:64773/tests/dromaeo/cssquery-dojo.html (next: http://localhost:64773/tests/dromaeo/cssquery-ext.html) 03:49:52 INFO - PROCESS | 11215 | RSS: Main: 344993792 03:49:52 INFO - PROCESS | 11215 | 03:52:55 INFO - PROCESS | 11215 | Cycle 1(1): loaded http://localhost:64773/tests/dromaeo/cssquery-ext.html (next: http://localhost:64773/tests/dromaeo/cssquery-jquery.html) 03:52:56 INFO - PROCESS | 11215 | RSS: Main: 356888576 03:52:56 INFO - PROCESS | 11215 | 03:55:58 INFO - PROCESS | 11215 | Cycle 1(1): loaded http://localhost:64773/tests/dromaeo/cssquery-jquery.html (next: http://localhost:64773/tests/dromaeo/cssquery-mootools.html) 03:55:59 INFO - PROCESS | 11215 | RSS: Main: 358596608 03:55:59 INFO - PROCESS | 11215 | 03:59:01 INFO - PROCESS | 11215 | Cycle 1(1): loaded http://localhost:64773/tests/dromaeo/cssquery-mootools.html (next: http://localhost:64773/tests/dromaeo/cssquery-prototype.html) 03:59:01 INFO - PROCESS | 11215 | RSS: Main: 346005504 03:59:01 INFO - PROCESS | 11215 | 04:02:04 INFO - PROCESS | 11215 | Cycle 1(1): loaded http://localhost:64773/tests/dromaeo/cssquery-prototype.html (next: http://localhost:64773/tests/dromaeo/cssquery-yui.html) 04:02:05 INFO - PROCESS | 11215 | RSS: Main: 324972544 04:02:05 INFO - PROCESS | 11215 | 04:05:08 INFO - PROCESS | 11215 | Cycle 1(1): loaded http://localhost:64773/tests/dromaeo/cssquery-yui.html (next: http://localhost:64773/tests/dromaeo/cssquery-dojo.html) 04:05:08 INFO - PROCESS | 11215 | RSS: Main: 365010944 04:05:08 INFO - PROCESS | 11215 | 04:05:08 INFO - PROCESS | 11215 | __start_tp_report 04:05:08 INFO - PROCESS | 11215 | _x_x_mozilla_page_load 04:05:08 INFO - PROCESS | 11215 | _x_x_mozilla_page_load_details 04:05:08 INFO - PROCESS | 11215 | |i|pagename|runs| 04:05:08 INFO - PROCESS | 11215 | |0;dojo.html;3390.5083762854383;3430.3310854383403;3449.758516903816;3468.1329667583104;3498.2303893143517;5892.08672655738;5897.941020589793;5908.67502287374;5917.822465326037;5918.674472903994;5880.411958804118;5943.3462319144855;5957.672328021957;5961.910571341423;5965.46310832025;5201.115810312256;5229.980153869988;5248.553872920787;5319.680819150844;5380.488853558905;3942.5466071401725;5330.933813237349;5334.626576139672;5356.625036247445;5372.006178856905;5287.286216360781;5324.680519168843;5349.384820745606;5353.330833645794;5370.275012873251;5322.33470816148;5352.116900711378;5363.275957745694;5366.382865970424;5384.0847056000575;5133.127368347353;5207.817726379559;5288.8413347599635;5290.418054014055;5296.814611488579;5674.716264186774;5743.425657434265;5758.481736643722;5760;5793.246877905887;5202.739863006835;6301.117843501914;6326.87346253077;6344.7462101515885;6360.7137678804565;5898.941010589882;6224.719887605023;6313.210848643919;6316.147320111818;6346;5106.412762532319;5962.701864906738;5964.254468191476;5964.761409543569;5981.611195272293;6328.968355158195;6331.145295385156;6344.238691357066;6348.4286414222015;6364.936350636434;6128.662923539159;6215.223097112861;6272.968635156795;6274.466670333073;6281.96859015702;6259.6870156492;6324.462420694293;6325.873482530324;6332.905006424815;6348.77779277723;6248.843778905472;6313.305536390993;6326.778562750282;6330.746770129143;6367.331430199855;6207.131001659771;6275.121482992385;6292.119103325538;6362.363763623694;6378.107065010902;4968.130577148984;4979.825706100269;4997.775100120503;4997.950020499821;4999.825006124768;3534.3991521441417;3564.982175089108;4458.108378324348;4464.17412778637;4528.682992190515;5797.333306669787;5811.418858114154;5814.970925145347;5817.360090390053;5867.853303667443;6226.40849119333;6284.183056202664;6294.433500984842;6317.368263173737;6349.174607301114;5764.769409223668;5826.912596311059;5832.096025116114;5862.267216597926;5874.794382196603;5447.863803404867;5458.099413596791;5466.9179962300595;5471.179323101566;5474.425185355481;6239.656818875005;6365.236171659245;6429.485641148697;6442.710078046593;6455.289918109004;6301.6534090625455;6303.590266632654;6325.778597749057;6332.145160403286;6332.461740751904;6365.490760739222;6440.8389790255615;6447.838804029937;6448.967755161194;6451.806445806634;6156.199694039746;6273.435390814849;6329.430351268408;6331.5251356147555;6344.238691357066;6326.873462530815;6337.461315788025;6350.460210882126;6355.872882542416;6369.8726025480155;6128.601640893327;6143.631382117087;6228.377162283736;6228.844278893064;6232.470240029648;6111.480524155319;6123.84690382744;6130.948521379913;6189.1954045975535;6201.224846894138;6117.888303246575;6139.321127956533;6139.4781443577795;6144.508439324753;6146.815595532141;6255;6259.436650701276;6273.65494897785;6273.937260627518;6280.403361680633;5949;6040.516758659384;6071.696415179312;6145.569810113249;6148.877022459616 04:05:08 INFO - PROCESS | 11215 | |1;ext.html;522.3835873669071;533.5091715621628;583.0408977232453;586.4956137721556;593.2613895699855;31815;32223.516647250282;32410.865619703258;32431.837840810764;39597.81206563808;46043.53956460435;46585;46698.06603867929;46726.76636616812;46741.53258467414;7683.270089341518;9735.707928762113;9738.711402820332;9745.853812192823;9758.414495130279;8627;9506.382085164476;11635.011024062922;11896.881031189663;11916.40417979102;11630.418479076056;11699.766004679901;11704.882951170506;11708.414579271044;11712.882871171265;8537.359698022674;11554.364509951949;11638.359890206035;11647.35939523326;11650.47572859215;10449.529771160316;11598.768024639545;11807.94096029523;11823.753486837473;11824.645260642108;39766.602333976865;40316.79841600802;40362.798186008884;40395.77823219722;40423.7978810104;3155.29860507844;3294.9505757413417;3759.3984962405884;3770.3401904666575;3916.353801622729;26701.332466688295;26737.866310668516;26880.924763009458;27278.318042048908;27662.723372766217;2678.5714285714193;2777.7361150690654;2813.113869131217;2819.3515491437083;2879.6688380836263;321232;324765;325498;325676;328147.35926320456;37076.25847483035;37994.43008354877;54837;58415;62243.37756622376;36196.45705314422;36430.453543196876;36550.634493655256;36645.450318245246;36646;15188.620284492976;15456.922715386576;15477.613059673598;15529.689406211812;15541.300641471162;12108.273503589813;12312.322822244683;13048.869511304956;13094.541691040768;13149.67125821843;8299.626516806758;8369.455985360932;8399.538025408661;8453.66185352579;8460.492370457792;3389.508521264403;3435.5018522314126;3436;3439.5356626855555;3469.098034511045;869.330615426131;975.8438649815995;995.5918073589938;1042.0309112525424;1045.5660900726114;29446.11661650154;29767.40465190684;29949.700502994692;30628.54057189144;31330.903418380247;1140.4582823159099;1200.4117982188839;1205.5057426455112;1223.651259391087;1313.5993521975888;15309.923450382677;15482.5355239343;15693.764593531107;15810.525684229491;16038.037717736974;686588;696690;700852;704655;706988;63773.36226637767;64197.67901160558;64397;64492;64629;1382.3572039001913;1486.4500134950138;1509.222750283605;1515.999440369373;1529.4264650755968;28870.278243044093;29519.114426567237;29575.816967321065;29700.257493562833;30183;49791.50208498014;50161.74919125381;50307.74846125746;50330.7483462595;50362.74818625883;422858;435604;436210;436453;437866;24563.87718061398;27658.89364425481;27714.8914043436;28014.859925700242;28216;44128.7793561043;44340.33489497725;44767.32849007333;45376;45659.31511027404;34744;34916.65083349134;35532.467012995345;35769.1057723559;36152.819235903655;35509.82245088861;35960.460593090604;36137.45793813042;39273.80363098166;42082.78958605188;37040.814795926926;37389.06527336838;37501.2499749998;37598.8120059398;38192.61807381891;31546.858293755824;32345.191370215933;32815;33123.50314745329;33161;24764;24986.500269994136;25089.023490315078;25192;25324.620130697687 04:05:08 INFO - PROCESS | 11215 | |2;jquery.html;397.85279446604744;400.40139990713885;400.6554363247607;410.4397497416028;418.87224396559037;14867.702645947074;14950.775738363905;20089.497762555944;20663;20689.896550517245;15831.604209894758;16749.748753768705;20116.195352185932;20204.59590808183;20728.652637578583;5724.7137643117785;5837.532997360201;6019.909701354483;6028.879422411571;6042.879142417149;4197.832086716528;5972.761089556412;6089.482393996515;6404.903926441107;6715.966420167917;6655.933440665604;6668.499862510307;6674.299198584152;6675.332466753334;6679.766208182715;5622.437756224385;5858.150568167592;5928.436798504135;5941.821745347668;6649.601023938555;5710.885782284331;5733.283339582552;5737.483626473638;5740.942590574083;5741.110127930177;12494.125411221217;13234.404451799692;13236.00729945258;13236.338183090904;13247.271400072992;1972.9901350493299;2071.979280207209;2206.426329154431;2222.866628002309;2527.2544599343237;9755.658551950648;10111.09000189979;10849.728756781065;11096.55613775448;14880;1739.8434140927377;1761.9383321583812;1774.4499205246416;1777.3779177287975;1804.1520485371855;80600.59699701522;102272;105982;115173;115253;10967.670969870916;11478.598249061242;11511.352388950589;11667.658219304718;11729.4135293235;15009.399624015135;15028.323725432381;15093.622659433382;15103;15158.924205379122;7841.176676448891;8169.836603267901;8626.482411055356;8741;8781.648734050566;8553.828923421497;8559.186877246651;8577.914220857712;8582.227599516074;8680.262177714838;4078.57174996627;4634.97682511592;4676.088162808288;4678.7660616969015;4685.888011163969;1750.5345687811832;1908.8950107744208;2009.2565750672345;2015.314792970397;2018.7476565429322;826.9586520673943;859.3554833874595;930.9208717259108;979.7256768104942;980.5489474841653;18822.71765923512;19209.231630734892;19727.30954416617;20377.79622203759;20398.184072637225;938.9248860091313;949.8670186173941;953.1564565359706;972.7373609125497;1005.4118340770731;10427.478626068667;10634.893651063545;10659.467026648637;10670.573177072985;10795.83806242907;629817.8509107425;641860.7906960435;642408;659797;674534;119404;120227;129200.3539982294;137339;140904.29547852196;1001.6093723447862;1019.2253887045898;1029.4543891737542;1041.9479026048818;1044.3107549017611;17245.879017863797;17632.82367176312;17798.394361850544;18033.909830451288;18149;51656.483435165166;52950;53152;53295.200571990674;53952.46047539474;72894;74881.62559187169;75784.04267552048;77773;80539;7284.635768211674;7754.681704110202;8059.637316320663;8129.552874591717;8192.098869124511;8993.550322483981;11809.173357865211;12584.559540416041;12728.23630582168;13054.955603551503;10989.06592939609;11177.329360238411;11343.092552595608;11469.967702906779;11517.53929842797;9215.76960575991;10887.401192934465;10974.341539507655;11208.831867522156;11481.311121332747;11196.94401527987;11268.3802390866;11381.772364552828;11513.88486115128;11595.246308989888;8170.754877353689;8217.876731848908;8315.584220788814;8378.24595786382;8568.400211985101;10475.790484190426;10887.836682449612;11094.057005154653;11165.441727913734;11181.664550063511 04:05:08 INFO - PROCESS | 11215 | |3;mootools.html;1330.521012435523;1370.5477192526466;1439.460202424091;1700.9574760630983;1926.6435709393754;7865.724699635507;8597.65609375625;8773.473591584509;8773.956130219356;8783.077776833421;7782.066152061746;8518.787030324245;8791.560421978907;8801.031886492481;8806;2169.40341406113;2235.6870038194666;2238.350878245315;2411.16814698929;2512.572862613351;2245.2702871566726;2445.08309383981;2519.2694118705554;2520.6597109390273;2527.36815796051;2416.830821842472;2437.2566367257978;2455.2511483997373;2463.618139188429;2466.087547607379;2134.530403311269;2157.471419502232;2398.8920498577604;2462.1259452894324;2465.6424818401233;2420.165043060141;2483.540544999181;2492.538880307131;2495.6755621769234;2496.5631014572377;8596.398252122353;8676.956615216946;8694.260987816044;8707.782305442415;8722.476651400937;5305.708186049766;5465.699386533739;5505.339359276913;5511.917321240185;5516.972415137939;3450.3099380124077;3534.4978384186616;3568.7145028397667;3589.335972845032;3593.5148754918014;1037.968860934173;1053.3206082077018;1062.4687656171914;1079.94060326682;1093.5680406239492;10242.743931401774;11964.982976447012;12016.939915300454;12170.878291217063;12179.756404871941;4798.904021919542;4833.226683730586;4884.438289596671;4942.308076869242;4945.629077819178;10267;10300;10317.174626029899;10322.329048611817;10333.48332583383;5535.529479994165;5566.471185237396;5569.721513924287;5571.359293681197;5573.359063707644;4395.670324725658;4515.661325400608;4997.550220480101;5055.469175736494;5058.494150584912;4007.29872272357;4056.3915412688334;4103.7742924138865;4116.608922152391;4123.567025462343;1316.1905428161613;1330.880220780115;1335.1922087137336;1346.5960211936576;1350.0819442779004;905.5608030105394;911.8176364727082;919.0579655852671;933.4912472702393;957.8036502517107;5686.118651609007;5791.884162316815;5831.79588714393;5925.377835327315;5991.430814072656;778.6145857800425;886.9600867960828;975.1370037516849;990.9009099090033;1004.8342023566175;3721.906952326159;3759.642833930772;3899.3760998240714;3922.5881282465502;3955.940660890089;9113.134252246158;10325.122364599094;10519.684409467729;10716;10870.967258110624;9688.12806847387;10448.843267350996;10599.89400105989;10699.94650026745;10741.355518668905;8109.310708589836;8212.753617391489;8325.916740832514;8398.790030249293;8403.537805420638;5451.209574611738;5521.254630624814;5541.806036788693;5636.464535869086;5836.766529338779;9119.088091190828;9199.724008279762;9385.953070234606;9488.715338539854;9754.512274386394;7932.484388514728;9428.245740340893;9448.95275523618;9470.905290947003;9471.810563788547;7222.855542889008;7269.927300726925;7302.087239095114;7398.371138453292;7403.629818508945;7969.92030079715;8034.799130021796;8044.034715834137;8060.879086813819;8067.677292908453;6153.353897840662;7450.776476705708;7460.328570428687;7553.660085296065;7594.430417718582;6792.490563207681;6840.521163518506;6888.242293347627;6918.169819621678;6958.443324533924;6964;7005.124359455068;7023.122109736283;7053.788386348418;7083.150021997187;4146.901071216191;4730.526947305242;4843.515648435128;4879.072976134523;5003.699778013331;4834.661573689808;4854.077725232195;4915.410150781929;4918.0901533216465;4943.283223932582 04:05:08 INFO - PROCESS | 11215 | |4;prototype.html;325.67595242733466;328.2630494539758;340.4263815470931;342.028638666188;346.02593698738065;6484.6757662116825;6640.900386494201;6684.899726504106;6726.327367263278;6748.932510674892;6526.151600291955;6681.632510211936;6682.6992785324655;6717.462602991761;6724.865502689943;4910.895048614069;4942.802287908479;4947.925781113287;4953.678010929287;4967.528084831935;4692.178868697982;4778.426588809348;4933.925991110136;4973;4974.552290293873;4858.659893807435;4860.5139486051285;4874.561289483946;4889.877753056185;4916.975415122919;4842.661013729023;4879.512048795127;4885.169521181372;4885.487023862479;4887.682300650482;3455.2398472336045;4182.330827067685;4184.581541845791;4184.581541845821;4186.853460128881;6326.46225070865;6442.420182183626;6442.677866106723;6456.063870738763;6467.450266727334;5367.221752845854;5848.473637372615;5856.736446859902;5857.355690874;5894.233749612566;6901.206361268418;6910.723571057151;6982.720691172347;6988.720451181946;6992.300769922967;4537.523560026216;4573.885652858672;4576.382188404589;4577.267637178069;4589.380433641449;7293.963530182368;7439.404847612178;7447;7555.395568354518;7555.773326800205;3815.503984482055;4673.859784206479;4681.110588988081;4691.741954192552;4695.835645752382;4776.641751868624;4782.856514304576;4783.378160839069;4784.378030855966;4794.856154315376;5676.545876329967;5680.630759000652;5685.914711279334;5688.288963879515;5704.315482142087;5844.386339434298;7171.713131474683;7175.031370764984;7195.560887822457;7220.819479513054;1520.4906356370518;1530.7015132049144;1599.8640115590072;1640.450449099565;1656.850883420498;1020.2093377632245;1034.4052170002278;1037.097724979273;1042.3537406807827;1048.2557384257111;5343.679379237258;6520.641364724986;6594.505412094112;6608.603483790988;6612.239592446834;5982.670953097621;7332.596707181049;7355.926440735631;7360.447966402541;7360.447966402541;6585.111010013683;6585.6377899216;6590.769323073765;6594.406503414716;6613.14029176204;3846.11539345952;3898.103436209631;3903.1803321302277;3912.628300311466;3923.6076392361106;7272.345488906024;7714.1128770192145;7725;7728.961355193188;7739.34215591681;7543.396528277724;7578.886316705254;7586.431017673696;7605.201453847377;7606.505577137468;7656.425768067417;7666.348360389206;7669.309762121436;7680.231976802387;7688.578097899144;7171.247019063132;7281.199068102399;7302.744403946049;7329.1937886833475;7336.532693461436;7300.634968251672;7367.042284502981;7374;7379.926200737924;7382.446316526175;7792.6103694816165;7793.883091753515;7802.219778022152;7842.686292548462;7851.60741962911;4617.8383756568355;4705.670603057753;4706.458757242961;4708.693934894221;4715.292706094114;5897.233359663216;6725.630090345078;7440.144383395978;7443.962780186281;7454.552726836408;7033.29667033313;7106.040684507525;7107.431405487445;7109.502334836512;7126.893096603451;7026.859462810818;7079.575225486487;7093.467989900882;7102.928970710227;7110.893336599852;6935.549189302679;6942.8611427772175;6945.2707465715375;6951.2353641098425;6977.197622273504;5451.809186678447;6785.1518560179975;7086.149662040382;7098.645067746489;7122.5726456412785;6881.862362752617;6932.757353492805;6953.895691564529;6957;6978.197507286524 04:05:08 INFO - PROCESS | 11215 | |5;yui.html;698.2947223304462;713.001797483523;734.7208060936844;745.1393640345403;784.6861255497804;12424.813627795591;15125.621859453518;15136.772948405755;15176.772348414755;15204.467843625462;11613.941930290337;13784.17294962303;13843.653908652288;13850.099743516665;13858;730.240549828178;743.8995735575683;754.5321900421746;757.7650928212245;925.6852670092168;735.2169939014932;753.0248328414689;924.4083786376724;927.0173615967096;944.3059351376733;966.0436168193473;982.7444864335253;992.3500107429645;994.9502524873764;998.8851282102543;966.970990870275;992.5880759484842;992.8510723391477;994.5723338964242;997.4464172384323;581.7411251992864;730.7515444748758;735.1252010108008;736.4255880413286;737.5464089584867;14689.192094434802;14720.852791472056;14766.630834229123;14779.778303325458;14796.964212505129;12175.112857507012;12409.317487538181;12444.937775311157;12452.25286482805;12666.240025598401;9776.16902563283;9902.55438505269;10053.497325133714;10233.181345492343;10670.679879603624;3445.207602251496;3470.132466883279;3471.7222622190166;3561.4123669594487;3646.690031347339;86372.13627863704;87210.56394718049;88879.55560222158;90140.54929725309;91245.08754912499;9224.723258302261;9307.395019323721;9369.203617692572;9395.624175032923;9569.952150239204;21299.78700213009;21445.67831482529;21458.141674332852;21464.678029829563;21561.56876862454;6405;7375.704971801175;7685.269899359552;7778.688852445839;7934.087579928267;3980.144268982183;4050.6354428101613;4067.1255680028935;4096.774677392772;4299.720518166309;3614.3494171049465;3648.7445878788762;3660.743747937672;3661.6268899162815;3674.356987527172;700.4606453031239;708.4969671533166;712.5226098514008;721.6211488968333;742.4542960923717;515.3249243490964;526.8498477933847;546.8332158691637;616.5653214483779;635.9968200159062;7608.695652173851;7949.125596184415;7978.321842643324;8023.9598802006785;8027.277545020859;550.5182964905707;584.2754983820117;610.7404353149893;620.3207487800851;624.8375422390211;3516.1209697575605;3775.7923314217983;3811.3901775715754;3884.4367566703236;3900;88468;92175.07824921799;92229.53885230531;92509;92533;75611.24388756041;77561;78252.60873695595;79426.60286698645;79599.01002474868;66686;68691.31308686949;68874;69718.65140674265;69768.6511567449;6169.259688837189;7587.506812057199;7666.233376662289;7674.424418168548;7732.420068494773;34598.7890423844;46239.84400390017;46614;46731.29903051389;46829.76585117052;56549.717251415124;62362.68818655878;64226;64247;65172.02241966469;9718.9028109718;9784.315097943076;9945.701628951143;9997.500124993867;10020.398776073458;10491.632792852213;10554.736131596772;10870.673879783619;10874.456277185951;11212.43937803123;10253.282270241012;10405.843912341472;10418.895811041793;10668.786624267626;10672.57309707634;10114.747131321776;10244.180465562886;10369.84445233307;10420.166386688896;11002.944985275342;9866.260030497599;9985.900140998787;10151.84772228432;10738.838917416402;10795.622153224913;8702.173293537357;8868.068852770624;9018.729438116867;9126;9126.680566180152;8813;9592.76018099553;9638;9656.130948214695;9667.323287369816 04:05:08 INFO - PROCESS | 11215 | __end_tp_report 04:05:08 INFO - PROCESS | 11215 | __start_cc_report 04:05:08 INFO - PROCESS | 11215 | _x_x_mozilla_cycle_collect,1225 04:05:08 INFO - PROCESS | 11215 | __end_cc_report 04:05:08 INFO - PROCESS | 11215 | __startTimestamp1462964708485__endTimestamp 04:05:08 INFO - PROCESS | 11215 | 04:05:08 INFO - PROCESS | 11215 | ------- Summary: start ------- 04:05:08 INFO - PROCESS | 11215 | Number of tests: 6 04:05:08 INFO - PROCESS | 11215 | 04:05:08 INFO - PROCESS | 11215 | [#0] dojo.html Cycles:165 Average:5820.20 Median:6123.85 stddev:691.08 (11.3%) stddev-sans-first:666.39 04:05:08 INFO - PROCESS | 11215 | Values: 3390.5 3430.3 3449.8 3468.1 3498.2 5892.1 5897.9 5908.7 5917.8 5918.7 5880.4 5943.3 5957.7 5961.9 5965.5 5201.1 5230.0 5248.6 5319.7 5380.5 3942.5 5330.9 5334.6 5356.6 5372.0 5287.3 5324.7 5349.4 5353.3 5370.3 5322.3 5352.1 5363.3 5366.4 5384.1 5133.1 5207.8 5288.8 5290.4 5296.8 5674.7 5743.4 5758.5 5760.0 5793.2 5202.7 6301.1 6326.9 6344.7 6360.7 5898.9 6224.7 6313.2 6316.1 6346.0 5106.4 5962.7 5964.3 5964.8 5981.6 6329.0 6331.1 6344.2 6348.4 6364.9 6128.7 6215.2 6273.0 6274.5 6282.0 6259.7 6324.5 6325.9 6332.9 6348.8 6248.8 6313.3 6326.8 6330.7 6367.3 6207.1 6275.1 6292.1 6362.4 6378.1 4968.1 4979.8 4997.8 4998.0 4999.8 3534.4 3565.0 4458.1 4464.2 4528.7 5797.3 5811.4 5815.0 5817.4 5867.9 6226.4 6284.2 6294.4 6317.4 6349.2 5764.8 5826.9 5832.1 5862.3 5874.8 5447.9 5458.1 5466.9 5471.2 5474.4 6239.7 6365.2 6429.5 6442.7 6455.3 6301.7 6303.6 6325.8 6332.1 6332.5 6365.5 6440.8 6447.8 6449.0 6451.8 6156.2 6273.4 6329.4 6331.5 6344.2 6326.9 6337.5 6350.5 6355.9 6369.9 6128.6 6143.6 6228.4 6228.8 6232.5 6111.5 6123.8 6130.9 6189.2 6201.2 6117.9 6139.3 6139.5 6144.5 6146.8 6255.0 6259.4 6273.7 6273.9 6280.4 5949.0 6040.5 6071.7 6145.6 6148.9 04:05:08 INFO - PROCESS | 11215 | 04:05:08 INFO - PROCESS | 11215 | [#1] ext.html Cycles:180 Average:61875.94 Median:3412.51 stddev:137096.69 (4017.5%) stddev-sans-first:137403.89 04:05:08 INFO - PROCESS | 11215 | Values: 522.4 533.5 583.0 586.5 593.3 31815.0 32223.5 32410.9 32431.8 39597.8 46043.5 46585.0 46698.1 46726.8 46741.5 7683.3 9735.7 9738.7 9745.9 9758.4 8627.0 9506.4 11635.0 11896.9 11916.4 11630.4 11699.8 11704.9 11708.4 11712.9 8537.4 11554.4 11638.4 11647.4 11650.5 10449.5 11598.8 11807.9 11823.8 11824.6 39766.6 40316.8 40362.8 40395.8 40423.8 3155.3 3295.0 3759.4 3770.3 3916.4 26701.3 26737.9 26880.9 27278.3 27662.7 2678.6 2777.7 2813.1 2819.4 2879.7 321232.0 324765.0 325498.0 325676.0 328147.4 37076.3 37994.4 54837.0 58415.0 62243.4 36196.5 36430.5 36550.6 36645.5 36646.0 15188.6 15456.9 15477.6 15529.7 15541.3 12108.3 12312.3 13048.9 13094.5 13149.7 8299.6 8369.5 8399.5 8453.7 8460.5 3389.5 3435.5 3436.0 3439.5 3469.1 869.3 975.8 995.6 1042.0 1045.6 29446.1 29767.4 29949.7 30628.5 31330.9 1140.5 1200.4 1205.5 1223.7 1313.6 15309.9 15482.5 15693.8 15810.5 16038.0 686588.0 696690.0 700852.0 704655.0 706988.0 63773.4 64197.7 64397.0 64492.0 64629.0 1382.4 1486.5 1509.2 1516.0 1529.4 28870.3 29519.1 29575.8 29700.3 30183.0 49791.5 50161.7 50307.7 50330.7 50362.7 422858.0 435604.0 436210.0 436453.0 437866.0 24563.9 27658.9 27714.9 28014.9 28216.0 44128.8 44340.3 44767.3 45376.0 45659.3 34744.0 34916.7 35532.5 35769.1 36152.8 35509.8 35960.5 36137.5 39273.8 42082.8 37040.8 37389.1 37501.2 37598.8 38192.6 31546.9 32345.2 32815.0 33123.5 33161.0 24764.0 24986.5 25089.0 25192.0 25324.6 04:05:08 INFO - PROCESS | 11215 | 04:05:08 INFO - PROCESS | 11215 | [#2] jquery.html Cycles:180 Average:35607.38 Median:20676.45 stddev:107850.97 (521.6%) stddev-sans-first:108121.11 04:05:08 INFO - PROCESS | 11215 | Values: 397.9 400.4 400.7 410.4 418.9 14867.7 14950.8 20089.5 20663.0 20689.9 15831.6 16749.7 20116.2 20204.6 20728.7 5724.7 5837.5 6019.9 6028.9 6042.9 4197.8 5972.8 6089.5 6404.9 6716.0 6655.9 6668.5 6674.3 6675.3 6679.8 5622.4 5858.2 5928.4 5941.8 6649.6 5710.9 5733.3 5737.5 5740.9 5741.1 12494.1 13234.4 13236.0 13236.3 13247.3 1973.0 2072.0 2206.4 2222.9 2527.3 9755.7 10111.1 10849.7 11096.6 14880.0 1739.8 1761.9 1774.4 1777.4 1804.2 80600.6 102272.0 105982.0 115173.0 115253.0 10967.7 11478.6 11511.4 11667.7 11729.4 15009.4 15028.3 15093.6 15103.0 15158.9 7841.2 8169.8 8626.5 8741.0 8781.6 8553.8 8559.2 8577.9 8582.2 8680.3 4078.6 4635.0 4676.1 4678.8 4685.9 1750.5 1908.9 2009.3 2015.3 2018.7 827.0 859.4 930.9 979.7 980.5 18822.7 19209.2 19727.3 20377.8 20398.2 938.9 949.9 953.2 972.7 1005.4 10427.5 10634.9 10659.5 10670.6 10795.8 629817.9 641860.8 642408.0 659797.0 674534.0 119404.0 120227.0 129200.4 137339.0 140904.3 1001.6 1019.2 1029.5 1041.9 1044.3 17245.9 17632.8 17798.4 18033.9 18149.0 51656.5 52950.0 53152.0 53295.2 53952.5 72894.0 74881.6 75784.0 77773.0 80539.0 7284.6 7754.7 8059.6 8129.6 8192.1 8993.6 11809.2 12584.6 12728.2 13055.0 10989.1 11177.3 11343.1 11470.0 11517.5 9215.8 10887.4 10974.3 11208.8 11481.3 11196.9 11268.4 11381.8 11513.9 11595.2 8170.8 8217.9 8315.6 8378.2 8568.4 10475.8 10887.8 11094.1 11165.4 11181.7 04:05:08 INFO - PROCESS | 11215 | 04:05:08 INFO - PROCESS | 11215 | [#3] mootools.html Cycles:180 Average:5637.44 Median:5000.62 stddev:3108.28 (62.2%) stddev-sans-first:3100.14 04:05:08 INFO - PROCESS | 11215 | Values: 1330.5 1370.5 1439.5 1701.0 1926.6 7865.7 8597.7 8773.5 8774.0 8783.1 7782.1 8518.8 8791.6 8801.0 8806.0 2169.4 2235.7 2238.4 2411.2 2512.6 2245.3 2445.1 2519.3 2520.7 2527.4 2416.8 2437.3 2455.3 2463.6 2466.1 2134.5 2157.5 2398.9 2462.1 2465.6 2420.2 2483.5 2492.5 2495.7 2496.6 8596.4 8677.0 8694.3 8707.8 8722.5 5305.7 5465.7 5505.3 5511.9 5517.0 3450.3 3534.5 3568.7 3589.3 3593.5 1038.0 1053.3 1062.5 1079.9 1093.6 10242.7 11965.0 12016.9 12170.9 12179.8 4798.9 4833.2 4884.4 4942.3 4945.6 10267.0 10300.0 10317.2 10322.3 10333.5 5535.5 5566.5 5569.7 5571.4 5573.4 4395.7 4515.7 4997.6 5055.5 5058.5 4007.3 4056.4 4103.8 4116.6 4123.6 1316.2 1330.9 1335.2 1346.6 1350.1 905.6 911.8 919.1 933.5 957.8 5686.1 5791.9 5831.8 5925.4 5991.4 778.6 887.0 975.1 990.9 1004.8 3721.9 3759.6 3899.4 3922.6 3955.9 9113.1 10325.1 10519.7 10716.0 10871.0 9688.1 10448.8 10599.9 10699.9 10741.4 8109.3 8212.8 8325.9 8398.8 8403.5 5451.2 5521.3 5541.8 5636.5 5836.8 9119.1 9199.7 9386.0 9488.7 9754.5 7932.5 9428.2 9449.0 9470.9 9471.8 7222.9 7269.9 7302.1 7398.4 7403.6 7969.9 8034.8 8044.0 8060.9 8067.7 6153.4 7450.8 7460.3 7553.7 7594.4 6792.5 6840.5 6888.2 6918.2 6958.4 6964.0 7005.1 7023.1 7053.8 7083.2 4146.9 4730.5 4843.5 4879.1 5003.7 4834.7 4854.1 4915.4 4918.1 4943.3 04:05:08 INFO - PROCESS | 11215 | 04:05:08 INFO - PROCESS | 11215 | [#4] prototype.html Cycles:180 Average:5797.17 Median:6592.59 stddev:1864.34 (28.3%) stddev-sans-first:1823.78 04:05:08 INFO - PROCESS | 11215 | Values: 325.7 328.3 340.4 342.0 346.0 6484.7 6640.9 6684.9 6726.3 6748.9 6526.2 6681.6 6682.7 6717.5 6724.9 4910.9 4942.8 4947.9 4953.7 4967.5 4692.2 4778.4 4933.9 4973.0 4974.6 4858.7 4860.5 4874.6 4889.9 4917.0 4842.7 4879.5 4885.2 4885.5 4887.7 3455.2 4182.3 4184.6 4184.6 4186.9 6326.5 6442.4 6442.7 6456.1 6467.5 5367.2 5848.5 5856.7 5857.4 5894.2 6901.2 6910.7 6982.7 6988.7 6992.3 4537.5 4573.9 4576.4 4577.3 4589.4 7294.0 7439.4 7447.0 7555.4 7555.8 3815.5 4673.9 4681.1 4691.7 4695.8 4776.6 4782.9 4783.4 4784.4 4794.9 5676.5 5680.6 5685.9 5688.3 5704.3 5844.4 7171.7 7175.0 7195.6 7220.8 1520.5 1530.7 1599.9 1640.5 1656.9 1020.2 1034.4 1037.1 1042.4 1048.3 5343.7 6520.6 6594.5 6608.6 6612.2 5982.7 7332.6 7355.9 7360.4 7360.4 6585.1 6585.6 6590.8 6594.4 6613.1 3846.1 3898.1 3903.2 3912.6 3923.6 7272.3 7714.1 7725.0 7729.0 7739.3 7543.4 7578.9 7586.4 7605.2 7606.5 7656.4 7666.3 7669.3 7680.2 7688.6 7171.2 7281.2 7302.7 7329.2 7336.5 7300.6 7367.0 7374.0 7379.9 7382.4 7792.6 7793.9 7802.2 7842.7 7851.6 4617.8 4705.7 4706.5 4708.7 4715.3 5897.2 6725.6 7440.1 7444.0 7454.6 7033.3 7106.0 7107.4 7109.5 7126.9 7026.9 7079.6 7093.5 7102.9 7110.9 6935.5 6942.9 6945.3 6951.2 6977.2 5451.8 6785.2 7086.1 7098.6 7122.6 6881.9 6932.8 6953.9 6957.0 6978.2 04:05:08 INFO - PROCESS | 11215 | 04:05:08 INFO - PROCESS | 11215 | [#5] yui.html Cycles:180 Average:17919.38 Median:67688.66 stddev:25782.89 (38.1%) stddev-sans-first:25822.79 04:05:08 INFO - PROCESS | 11215 | Values: 698.3 713.0 734.7 745.1 784.7 12424.8 15125.6 15136.8 15176.8 15204.5 11613.9 13784.2 13843.7 13850.1 13858.0 730.2 743.9 754.5 757.8 925.7 735.2 753.0 924.4 927.0 944.3 966.0 982.7 992.4 995.0 998.9 967.0 992.6 992.9 994.6 997.4 581.7 730.8 735.1 736.4 737.5 14689.2 14720.9 14766.6 14779.8 14797.0 12175.1 12409.3 12444.9 12452.3 12666.2 9776.2 9902.6 10053.5 10233.2 10670.7 3445.2 3470.1 3471.7 3561.4 3646.7 86372.1 87210.6 88879.6 90140.5 91245.1 9224.7 9307.4 9369.2 9395.6 9570.0 21299.8 21445.7 21458.1 21464.7 21561.6 6405.0 7375.7 7685.3 7778.7 7934.1 3980.1 4050.6 4067.1 4096.8 4299.7 3614.3 3648.7 3660.7 3661.6 3674.4 700.5 708.5 712.5 721.6 742.5 515.3 526.8 546.8 616.6 636.0 7608.7 7949.1 7978.3 8024.0 8027.3 550.5 584.3 610.7 620.3 624.8 3516.1 3775.8 3811.4 3884.4 3900.0 88468.0 92175.1 92229.5 92509.0 92533.0 75611.2 77561.0 78252.6 79426.6 79599.0 66686.0 68691.3 68874.0 69718.7 69768.7 6169.3 7587.5 7666.2 7674.4 7732.4 34598.8 46239.8 46614.0 46731.3 46829.8 56549.7 62362.7 64226.0 64247.0 65172.0 9718.9 9784.3 9945.7 9997.5 10020.4 10491.6 10554.7 10870.7 10874.5 11212.4 10253.3 10405.8 10418.9 10668.8 10672.6 10114.7 10244.2 10369.8 10420.2 11002.9 9866.3 9985.9 10151.8 10738.8 10795.6 8702.2 8868.1 9018.7 9126.0 9126.7 8813.0 9592.8 9638.0 9656.1 9667.3 04:05:08 INFO - PROCESS | 11215 | -------- Summary: end -------- 04:05:08 INFO - PROCESS | 11215 | 04:05:09 INFO - TEST-INFO | 11215: exit 0 04:05:09 INFO - TEST-OK | dromaeo_css | took 1115350ms 04:05:09 INFO - TEST-START | kraken 04:05:09 INFO - operating with platform_type : mac_ 04:05:09 INFO - Initialising browser for kraken test... 04:05:09 INFO - TEST-INFO | started process 11221 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpBi0ntt/profile http://localhost:64773/getInfo.html) 04:05:11 INFO - PROCESS | 11221 | __metrics Screen width/height:1600/1200 04:05:11 INFO - PROCESS | 11221 | colorDepth:24 04:05:11 INFO - PROCESS | 11221 | Browser inner width/height: 1024/689 04:05:11 INFO - PROCESS | 11221 | __metrics 04:05:11 INFO - PROCESS | 11221 | 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" 04:05:11 ERROR - PROCESS | 11221 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:05:12 INFO - TEST-INFO | 11221: exit 0 04:05:12 INFO - Browser initialized. 04:05:12 INFO - Running cycle 1/1 for kraken test... 04:05:12 INFO - TEST-INFO | started process 11223 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpBi0ntt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:05:24 INFO - PROCESS | 11223 | RSS: Main: 210911232 04:05:24 INFO - PROCESS | 11223 | 04:05:27 INFO - PROCESS | 11223 | Cycle 1(1): loaded http://localhost:64773/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:64773/tests/kraken/driver.html?testName=audio-beat-detection) 04:05:27 INFO - PROCESS | 11223 | RSS: Main: 271777792 04:05:27 INFO - PROCESS | 11223 | 04:05:30 INFO - PROCESS | 11223 | Cycle 1(1): loaded http://localhost:64773/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:64773/tests/kraken/driver.html?testName=audio-dft) 04:05:31 INFO - PROCESS | 11223 | RSS: Main: 286183424 04:05:31 INFO - PROCESS | 11223 | 04:05:34 INFO - PROCESS | 11223 | Cycle 1(1): loaded http://localhost:64773/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:64773/tests/kraken/driver.html?testName=audio-fft) 04:05:35 INFO - PROCESS | 11223 | RSS: Main: 374616064 04:05:35 INFO - PROCESS | 11223 | 04:05:37 INFO - PROCESS | 11223 | Cycle 1(1): loaded http://localhost:64773/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:64773/tests/kraken/driver.html?testName=audio-oscillator) 04:05:37 INFO - PROCESS | 11223 | RSS: Main: 278462464 04:05:37 INFO - PROCESS | 11223 | 04:05:39 INFO - PROCESS | 11223 | Cycle 1(1): loaded http://localhost:64773/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:64773/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:05:40 INFO - PROCESS | 11223 | RSS: Main: 279482368 04:05:40 INFO - PROCESS | 11223 | 04:05:43 INFO - PROCESS | 11223 | Cycle 1(1): loaded http://localhost:64773/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:64773/tests/kraken/driver.html?testName=imaging-darkroom) 04:05:44 INFO - PROCESS | 11223 | RSS: Main: 316092416 04:05:44 INFO - PROCESS | 11223 | 04:05:46 INFO - PROCESS | 11223 | Cycle 1(1): loaded http://localhost:64773/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:64773/tests/kraken/driver.html?testName=imaging-desaturate) 04:05:47 INFO - PROCESS | 11223 | RSS: Main: 319012864 04:05:47 INFO - PROCESS | 11223 | 04:05:50 INFO - PROCESS | 11223 | Cycle 1(1): loaded http://localhost:64773/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:64773/tests/kraken/driver.html?testName=json-parse-financial) 04:05:50 INFO - PROCESS | 11223 | RSS: Main: 316608512 04:05:50 INFO - PROCESS | 11223 | 04:05:52 INFO - PROCESS | 11223 | Cycle 1(1): loaded http://localhost:64773/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:64773/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:05:53 INFO - PROCESS | 11223 | RSS: Main: 309264384 04:05:53 INFO - PROCESS | 11223 | 04:05:54 INFO - PROCESS | 11223 | Cycle 1(1): loaded http://localhost:64773/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:64773/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:05:55 INFO - PROCESS | 11223 | RSS: Main: 316002304 04:05:55 INFO - PROCESS | 11223 | 04:05:57 INFO - PROCESS | 11223 | Cycle 1(1): loaded http://localhost:64773/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:64773/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:05:58 INFO - PROCESS | 11223 | RSS: Main: 319115264 04:05:58 INFO - PROCESS | 11223 | 04:06:01 INFO - PROCESS | 11223 | Cycle 1(1): loaded http://localhost:64773/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:64773/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:06:01 INFO - PROCESS | 11223 | RSS: Main: 324136960 04:06:01 INFO - PROCESS | 11223 | 04:06:04 INFO - PROCESS | 11223 | Cycle 1(1): loaded http://localhost:64773/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:64773/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:06:05 INFO - PROCESS | 11223 | RSS: Main: 322203648 04:06:05 INFO - PROCESS | 11223 | 04:06:06 INFO - PROCESS | 11223 | Cycle 1(1): loaded http://localhost:64773/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:64773/tests/kraken/driver.html?testName=ai-astar) 04:06:07 INFO - PROCESS | 11223 | RSS: Main: 386404352 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | __start_tp_report 04:06:07 INFO - PROCESS | 11223 | _x_x_mozilla_page_load 04:06:07 INFO - PROCESS | 11223 | _x_x_mozilla_page_load_details 04:06:07 INFO - PROCESS | 11223 | |i|pagename|runs| 04:06:07 INFO - PROCESS | 11223 | |0;ai-astar;114;113;120;158;164;113;115;114;114;144 04:06:07 INFO - PROCESS | 11223 | |1;audio-beat-detection;162;120;150;168;117;149;139;120;177;118 04:06:07 INFO - PROCESS | 11223 | |2;audio-dft;185;187;185;185;198;262;184;183;184;188 04:06:07 INFO - PROCESS | 11223 | |3;audio-fft;71;80;76;161;55;53;55;71;135;53 04:06:07 INFO - PROCESS | 11223 | |4;audio-oscillator;92;90;186;116;113;92;126;79;75;126 04:06:07 INFO - PROCESS | 11223 | |5;imaging-gaussian-blur;86;84;87;88;107;113;135;85;86;83 04:06:07 INFO - PROCESS | 11223 | |6;imaging-darkroom;93;92;92;92;92;92;92;92;92;92 04:06:07 INFO - PROCESS | 11223 | |7;imaging-desaturate;84;83;83;81;85;83;84;85;86;84 04:06:07 INFO - PROCESS | 11223 | |8;json-parse-financial;61;63;65;62;64;63;63;83;54;55 04:06:07 INFO - PROCESS | 11223 | |9;json-stringify-tinderbox;67;65;77;79;74;71;70;71;69;70 04:06:07 INFO - PROCESS | 11223 | |10;stanford-crypto-aes;80;81;80;81;79;81;81;80;80;85 04:06:07 INFO - PROCESS | 11223 | |11;stanford-crypto-ccm;130;134;146;152;126;127;124;114;142;172 04:06:07 INFO - PROCESS | 11223 | |12;stanford-crypto-pbkdf2;155;163;162;163;162;161;162;162;161;160 04:06:07 INFO - PROCESS | 11223 | |13;stanford-crypto-sha256-iterative;67;68;68;68;69;69;67;68;66;67 04:06:07 INFO - PROCESS | 11223 | __end_tp_report 04:06:07 INFO - PROCESS | 11223 | __start_cc_report 04:06:07 INFO - PROCESS | 11223 | _x_x_mozilla_cycle_collect,2044 04:06:07 INFO - PROCESS | 11223 | __end_cc_report 04:06:07 INFO - PROCESS | 11223 | __startTimestamp1462964767210__endTimestamp 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | ------- Summary: start ------- 04:06:07 INFO - PROCESS | 11223 | Number of tests: 14 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | [#0] ai-astar Cycles:10 Average:126.90 Median:117.50 stddev:20.31 (17.3%) stddev-sans-first:20.99 04:06:07 INFO - PROCESS | 11223 | Values: 114.0 113.0 120.0 158.0 164.0 113.0 115.0 114.0 114.0 144.0 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:149.50 stddev:22.59 (15.1%) stddev-sans-first:22.77 04:06:07 INFO - PROCESS | 11223 | Values: 162.0 120.0 150.0 168.0 117.0 149.0 139.0 120.0 177.0 118.0 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | [#2] audio-dft Cycles:10 Average:194.10 Median:186.00 stddev:24.24 (13.0%) stddev-sans-first:25.49 04:06:07 INFO - PROCESS | 11223 | Values: 185.0 187.0 185.0 185.0 198.0 262.0 184.0 183.0 184.0 188.0 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | [#3] audio-fft Cycles:10 Average:81.00 Median:63.00 stddev:37.21 (59.1%) stddev-sans-first:39.29 04:06:07 INFO - PROCESS | 11223 | Values: 71.0 80.0 76.0 161.0 55.0 53.0 55.0 71.0 135.0 53.0 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | [#4] audio-oscillator Cycles:10 Average:109.50 Median:77.00 stddev:32.60 (42.3%) stddev-sans-first:33.96 04:06:07 INFO - PROCESS | 11223 | Values: 92.0 90.0 186.0 116.0 113.0 92.0 126.0 79.0 75.0 126.0 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | [#5] imaging-gaussian-blur Cycles:10 Average:95.40 Median:85.50 stddev:17.34 (20.3%) stddev-sans-first:18.06 04:06:07 INFO - PROCESS | 11223 | Values: 86.0 84.0 87.0 88.0 107.0 113.0 135.0 85.0 86.0 83.0 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | [#6] imaging-darkroom Cycles:10 Average:92.10 Median:92.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 04:06:07 INFO - PROCESS | 11223 | Values: 93.0 92.0 92.0 92.0 92.0 92.0 92.0 92.0 92.0 92.0 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | [#7] imaging-desaturate Cycles:10 Average:83.80 Median:84.00 stddev:1.40 (1.7%) stddev-sans-first:1.48 04:06:07 INFO - PROCESS | 11223 | Values: 84.0 83.0 83.0 81.0 85.0 83.0 84.0 85.0 86.0 84.0 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:63.00 stddev:7.85 (12.5%) stddev-sans-first:8.28 04:06:07 INFO - PROCESS | 11223 | Values: 61.0 63.0 65.0 62.0 64.0 63.0 63.0 83.0 54.0 55.0 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.00 stddev:4.30 (6.1%) stddev-sans-first:4.27 04:06:07 INFO - PROCESS | 11223 | Values: 67.0 65.0 77.0 79.0 74.0 71.0 70.0 71.0 69.0 70.0 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:81.00 stddev:1.62 (2.0%) stddev-sans-first:1.69 04:06:07 INFO - PROCESS | 11223 | Values: 80.0 81.0 80.0 81.0 79.0 81.0 81.0 80.0 80.0 85.0 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | [#11] stanford-crypto-ccm Cycles:10 Average:136.70 Median:138.00 stddev:16.77 (12.2%) stddev-sans-first:17.61 04:06:07 INFO - PROCESS | 11223 | Values: 130.0 134.0 146.0 152.0 126.0 127.0 124.0 114.0 142.0 172.0 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.10 Median:162.00 stddev:2.33 (1.4%) stddev-sans-first:0.97 04:06:07 INFO - PROCESS | 11223 | Values: 155.0 163.0 162.0 163.0 162.0 161.0 162.0 162.0 161.0 160.0 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - PROCESS | 11223 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:0.95 (1.4%) stddev-sans-first:0.97 04:06:07 INFO - PROCESS | 11223 | Values: 67.0 68.0 68.0 68.0 69.0 69.0 67.0 68.0 66.0 67.0 04:06:07 INFO - PROCESS | 11223 | -------- Summary: end -------- 04:06:07 INFO - PROCESS | 11223 | 04:06:07 INFO - TEST-INFO | 11223: exit 0 04:06:07 INFO - TEST-OK | kraken | took 58921ms 04:06:07 INFO - SUITE-END | took 1174s 04:06:08 INFO - Completed test suite (00:19:34) 04:06:08 INFO - javascript benchmark 04:06:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3390.5083762854383, 3430.3310854383403, 3449.758516903816, 3468.1329667583104, 3498.2303893143517, 5892.08672655738, 5897.941020589793, 5908.67502287374, 5917.822465326037, 5918.674472903994, 5880.411958804118, 5943.3462319144855, 5957.672328021957, 5961.910571341423, 5965.46310832025, 5201.115810312256, 5229.980153869988, 5248.553872920787, 5319.680819150844, 5380.488853558905, 3942.5466071401725, 5330.933813237349, 5334.626576139672, 5356.625036247445, 5372.006178856905, 5287.286216360781, 5324.680519168843, 5349.384820745606, 5353.330833645794, 5370.275012873251, 5322.33470816148, 5352.116900711378, 5363.275957745694, 5366.382865970424, 5384.0847056000575, 5133.127368347353, 5207.817726379559, 5288.8413347599635, 5290.418054014055, 5296.814611488579, 5674.716264186774, 5743.425657434265, 5758.481736643722, 5760.0, 5793.246877905887, 5202.739863006835, 6301.117843501914, 6326.87346253077, 6344.7462101515885, 6360.7137678804565, 5898.941010589882, 6224.719887605023, 6313.210848643919, 6316.147320111818, 6346.0, 5106.412762532319, 5962.701864906738, 5964.254468191476, 5964.761409543569, 5981.611195272293, 6328.968355158195, 6331.145295385156, 6344.238691357066, 6348.4286414222015, 6364.936350636434, 6128.662923539159, 6215.223097112861, 6272.968635156795, 6274.466670333073, 6281.96859015702, 6259.6870156492, 6324.462420694293, 6325.873482530324, 6332.905006424815, 6348.77779277723, 6248.843778905472, 6313.305536390993, 6326.778562750282, 6330.746770129143, 6367.331430199855, 6207.131001659771, 6275.121482992385, 6292.119103325538, 6362.363763623694, 6378.107065010902, 4968.130577148984, 4979.825706100269, 4997.775100120503, 4997.950020499821, 4999.825006124768, 3534.3991521441417, 3564.982175089108, 4458.108378324348, 4464.17412778637, 4528.682992190515, 5797.333306669787, 5811.418858114154, 5814.970925145347, 5817.360090390053, 5867.853303667443, 6226.40849119333, 6284.183056202664, 6294.433500984842, 6317.368263173737, 6349.174607301114, 5764.769409223668, 5826.912596311059, 5832.096025116114, 5862.267216597926, 5874.794382196603, 5447.863803404867, 5458.099413596791, 5466.9179962300595, 5471.179323101566, 5474.425185355481, 6239.656818875005, 6365.236171659245, 6429.485641148697, 6442.710078046593, 6455.289918109004, 6301.6534090625455, 6303.590266632654, 6325.778597749057, 6332.145160403286, 6332.461740751904, 6365.490760739222, 6440.8389790255615, 6447.838804029937, 6448.967755161194, 6451.806445806634, 6156.199694039746, 6273.435390814849, 6329.430351268408, 6331.5251356147555, 6344.238691357066, 6326.873462530815, 6337.461315788025, 6350.460210882126, 6355.872882542416, 6369.8726025480155, 6128.601640893327, 6143.631382117087, 6228.377162283736, 6228.844278893064, 6232.470240029648, 6111.480524155319, 6123.84690382744, 6130.948521379913, 6189.1954045975535, 6201.224846894138, 6117.888303246575, 6139.321127956533, 6139.4781443577795, 6144.508439324753, 6146.815595532141, 6255.0, 6259.436650701276, 6273.65494897785, 6273.937260627518, 6280.403361680633, 5949.0, 6040.516758659384, 6071.696415179312, 6145.569810113249, 6148.877022459616], "value": 5774.270374173886, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.3835873669071, 533.5091715621628, 583.0408977232453, 586.4956137721556, 593.2613895699855, 31815.0, 32223.516647250282, 32410.865619703258, 32431.837840810764, 39597.81206563808, 46043.53956460435, 46585.0, 46698.06603867929, 46726.76636616812, 46741.53258467414, 7683.270089341518, 9735.707928762113, 9738.711402820332, 9745.853812192823, 9758.414495130279, 8627.0, 9506.382085164476, 11635.011024062922, 11896.881031189663, 11916.40417979102, 11630.418479076056, 11699.766004679901, 11704.882951170506, 11708.414579271044, 11712.882871171265, 8537.359698022674, 11554.364509951949, 11638.359890206035, 11647.35939523326, 11650.47572859215, 10449.529771160316, 11598.768024639545, 11807.94096029523, 11823.753486837473, 11824.645260642108, 39766.602333976865, 40316.79841600802, 40362.798186008884, 40395.77823219722, 40423.7978810104, 3155.29860507844, 3294.9505757413417, 3759.3984962405884, 3770.3401904666575, 3916.353801622729, 26701.332466688295, 26737.866310668516, 26880.924763009458, 27278.318042048908, 27662.723372766217, 2678.5714285714193, 2777.7361150690654, 2813.113869131217, 2819.3515491437083, 2879.6688380836263, 321232.0, 324765.0, 325498.0, 325676.0, 328147.35926320456, 37076.25847483035, 37994.43008354877, 54837.0, 58415.0, 62243.37756622376, 36196.45705314422, 36430.453543196876, 36550.634493655256, 36645.450318245246, 36646.0, 15188.620284492976, 15456.922715386576, 15477.613059673598, 15529.689406211812, 15541.300641471162, 12108.273503589813, 12312.322822244683, 13048.869511304956, 13094.541691040768, 13149.67125821843, 8299.626516806758, 8369.455985360932, 8399.538025408661, 8453.66185352579, 8460.492370457792, 3389.508521264403, 3435.5018522314126, 3436.0, 3439.5356626855555, 3469.098034511045, 869.330615426131, 975.8438649815995, 995.5918073589938, 1042.0309112525424, 1045.5660900726114, 29446.11661650154, 29767.40465190684, 29949.700502994692, 30628.54057189144, 31330.903418380247, 1140.4582823159099, 1200.4117982188839, 1205.5057426455112, 1223.651259391087, 1313.5993521975888, 15309.923450382677, 15482.5355239343, 15693.764593531107, 15810.525684229491, 16038.037717736974, 686588.0, 696690.0, 700852.0, 704655.0, 706988.0, 63773.36226637767, 64197.67901160558, 64397.0, 64492.0, 64629.0, 1382.3572039001913, 1486.4500134950138, 1509.222750283605, 1515.999440369373, 1529.4264650755968, 28870.278243044093, 29519.114426567237, 29575.816967321065, 29700.257493562833, 30183.0, 49791.50208498014, 50161.74919125381, 50307.74846125746, 50330.7483462595, 50362.74818625883, 422858.0, 435604.0, 436210.0, 436453.0, 437866.0, 24563.87718061398, 27658.89364425481, 27714.8914043436, 28014.859925700242, 28216.0, 44128.7793561043, 44340.33489497725, 44767.32849007333, 45376.0, 45659.31511027404, 34744.0, 34916.65083349134, 35532.467012995345, 35769.1057723559, 36152.819235903655, 35509.82245088861, 35960.460593090604, 36137.45793813042, 39273.80363098166, 42082.78958605188, 37040.814795926926, 37389.06527336838, 37501.2499749998, 37598.8120059398, 38192.61807381891, 31546.858293755824, 32345.191370215933, 32815.0, 33123.50314745329, 33161.0, 24764.0, 24986.500269994136, 25089.023490315078, 25192.0, 25324.620130697687], "value": 18682.576786275957, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.85279446604744, 400.40139990713885, 400.6554363247607, 410.4397497416028, 418.87224396559037, 14867.702645947074, 14950.775738363905, 20089.497762555944, 20663.0, 20689.896550517245, 15831.604209894758, 16749.748753768705, 20116.195352185932, 20204.59590808183, 20728.652637578583, 5724.7137643117785, 5837.532997360201, 6019.909701354483, 6028.879422411571, 6042.879142417149, 4197.832086716528, 5972.761089556412, 6089.482393996515, 6404.903926441107, 6715.966420167917, 6655.933440665604, 6668.499862510307, 6674.299198584152, 6675.332466753334, 6679.766208182715, 5622.437756224385, 5858.150568167592, 5928.436798504135, 5941.821745347668, 6649.601023938555, 5710.885782284331, 5733.283339582552, 5737.483626473638, 5740.942590574083, 5741.110127930177, 12494.125411221217, 13234.404451799692, 13236.00729945258, 13236.338183090904, 13247.271400072992, 1972.9901350493299, 2071.979280207209, 2206.426329154431, 2222.866628002309, 2527.2544599343237, 9755.658551950648, 10111.09000189979, 10849.728756781065, 11096.55613775448, 14880.0, 1739.8434140927377, 1761.9383321583812, 1774.4499205246416, 1777.3779177287975, 1804.1520485371855, 80600.59699701522, 102272.0, 105982.0, 115173.0, 115253.0, 10967.670969870916, 11478.598249061242, 11511.352388950589, 11667.658219304718, 11729.4135293235, 15009.399624015135, 15028.323725432381, 15093.622659433382, 15103.0, 15158.924205379122, 7841.176676448891, 8169.836603267901, 8626.482411055356, 8741.0, 8781.648734050566, 8553.828923421497, 8559.186877246651, 8577.914220857712, 8582.227599516074, 8680.262177714838, 4078.57174996627, 4634.97682511592, 4676.088162808288, 4678.7660616969015, 4685.888011163969, 1750.5345687811832, 1908.8950107744208, 2009.2565750672345, 2015.314792970397, 2018.7476565429322, 826.9586520673943, 859.3554833874595, 930.9208717259108, 979.7256768104942, 980.5489474841653, 18822.71765923512, 19209.231630734892, 19727.30954416617, 20377.79622203759, 20398.184072637225, 938.9248860091313, 949.8670186173941, 953.1564565359706, 972.7373609125497, 1005.4118340770731, 10427.478626068667, 10634.893651063545, 10659.467026648637, 10670.573177072985, 10795.83806242907, 629817.8509107425, 641860.7906960435, 642408.0, 659797.0, 674534.0, 119404.0, 120227.0, 129200.3539982294, 137339.0, 140904.29547852196, 1001.6093723447862, 1019.2253887045898, 1029.4543891737542, 1041.9479026048818, 1044.3107549017611, 17245.879017863797, 17632.82367176312, 17798.394361850544, 18033.909830451288, 18149.0, 51656.483435165166, 52950.0, 53152.0, 53295.200571990674, 53952.46047539474, 72894.0, 74881.62559187169, 75784.04267552048, 77773.0, 80539.0, 7284.635768211674, 7754.681704110202, 8059.637316320663, 8129.552874591717, 8192.098869124511, 8993.550322483981, 11809.173357865211, 12584.559540416041, 12728.23630582168, 13054.955603551503, 10989.06592939609, 11177.329360238411, 11343.092552595608, 11469.967702906779, 11517.53929842797, 9215.76960575991, 10887.401192934465, 10974.341539507655, 11208.831867522156, 11481.311121332747, 11196.94401527987, 11268.3802390866, 11381.772364552828, 11513.88486115128, 11595.246308989888, 8170.754877353689, 8217.876731848908, 8315.584220788814, 8378.24595786382, 8568.400211985101, 10475.790484190426, 10887.836682449612, 11094.057005154653, 11165.441727913734, 11181.664550063511], "value": 9381.747340952059, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.521012435523, 1370.5477192526466, 1439.460202424091, 1700.9574760630983, 1926.6435709393754, 7865.724699635507, 8597.65609375625, 8773.473591584509, 8773.956130219356, 8783.077776833421, 7782.066152061746, 8518.787030324245, 8791.560421978907, 8801.031886492481, 8806.0, 2169.40341406113, 2235.6870038194666, 2238.350878245315, 2411.16814698929, 2512.572862613351, 2245.2702871566726, 2445.08309383981, 2519.2694118705554, 2520.6597109390273, 2527.36815796051, 2416.830821842472, 2437.2566367257978, 2455.2511483997373, 2463.618139188429, 2466.087547607379, 2134.530403311269, 2157.471419502232, 2398.8920498577604, 2462.1259452894324, 2465.6424818401233, 2420.165043060141, 2483.540544999181, 2492.538880307131, 2495.6755621769234, 2496.5631014572377, 8596.398252122353, 8676.956615216946, 8694.260987816044, 8707.782305442415, 8722.476651400937, 5305.708186049766, 5465.699386533739, 5505.339359276913, 5511.917321240185, 5516.972415137939, 3450.3099380124077, 3534.4978384186616, 3568.7145028397667, 3589.335972845032, 3593.5148754918014, 1037.968860934173, 1053.3206082077018, 1062.4687656171914, 1079.94060326682, 1093.5680406239492, 10242.743931401774, 11964.982976447012, 12016.939915300454, 12170.878291217063, 12179.756404871941, 4798.904021919542, 4833.226683730586, 4884.438289596671, 4942.308076869242, 4945.629077819178, 10267.0, 10300.0, 10317.174626029899, 10322.329048611817, 10333.48332583383, 5535.529479994165, 5566.471185237396, 5569.721513924287, 5571.359293681197, 5573.359063707644, 4395.670324725658, 4515.661325400608, 4997.550220480101, 5055.469175736494, 5058.494150584912, 4007.29872272357, 4056.3915412688334, 4103.7742924138865, 4116.608922152391, 4123.567025462343, 1316.1905428161613, 1330.880220780115, 1335.1922087137336, 1346.5960211936576, 1350.0819442779004, 905.5608030105394, 911.8176364727082, 919.0579655852671, 933.4912472702393, 957.8036502517107, 5686.118651609007, 5791.884162316815, 5831.79588714393, 5925.377835327315, 5991.430814072656, 778.6145857800425, 886.9600867960828, 975.1370037516849, 990.9009099090033, 1004.8342023566175, 3721.906952326159, 3759.642833930772, 3899.3760998240714, 3922.5881282465502, 3955.940660890089, 9113.134252246158, 10325.122364599094, 10519.684409467729, 10716.0, 10870.967258110624, 9688.12806847387, 10448.843267350996, 10599.89400105989, 10699.94650026745, 10741.355518668905, 8109.310708589836, 8212.753617391489, 8325.916740832514, 8398.790030249293, 8403.537805420638, 5451.209574611738, 5521.254630624814, 5541.806036788693, 5636.464535869086, 5836.766529338779, 9119.088091190828, 9199.724008279762, 9385.953070234606, 9488.715338539854, 9754.512274386394, 7932.484388514728, 9428.245740340893, 9448.95275523618, 9470.905290947003, 9471.810563788547, 7222.855542889008, 7269.927300726925, 7302.087239095114, 7398.371138453292, 7403.629818508945, 7969.92030079715, 8034.799130021796, 8044.034715834137, 8060.879086813819, 8067.677292908453, 6153.353897840662, 7450.776476705708, 7460.328570428687, 7553.660085296065, 7594.430417718582, 6792.490563207681, 6840.521163518506, 6888.242293347627, 6918.169819621678, 6958.443324533924, 6964.0, 7005.124359455068, 7023.122109736283, 7053.788386348418, 7083.150021997187, 4146.901071216191, 4730.526947305242, 4843.515648435128, 4879.072976134523, 5003.699778013331, 4834.661573689808, 4854.077725232195, 4915.410150781929, 4918.0901533216465, 4943.283223932582], "value": 4565.6204508134015, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.67595242733466, 328.2630494539758, 340.4263815470931, 342.028638666188, 346.02593698738065, 6484.6757662116825, 6640.900386494201, 6684.899726504106, 6726.327367263278, 6748.932510674892, 6526.151600291955, 6681.632510211936, 6682.6992785324655, 6717.462602991761, 6724.865502689943, 4910.895048614069, 4942.802287908479, 4947.925781113287, 4953.678010929287, 4967.528084831935, 4692.178868697982, 4778.426588809348, 4933.925991110136, 4973.0, 4974.552290293873, 4858.659893807435, 4860.5139486051285, 4874.561289483946, 4889.877753056185, 4916.975415122919, 4842.661013729023, 4879.512048795127, 4885.169521181372, 4885.487023862479, 4887.682300650482, 3455.2398472336045, 4182.330827067685, 4184.581541845791, 4184.581541845821, 4186.853460128881, 6326.46225070865, 6442.420182183626, 6442.677866106723, 6456.063870738763, 6467.450266727334, 5367.221752845854, 5848.473637372615, 5856.736446859902, 5857.355690874, 5894.233749612566, 6901.206361268418, 6910.723571057151, 6982.720691172347, 6988.720451181946, 6992.300769922967, 4537.523560026216, 4573.885652858672, 4576.382188404589, 4577.267637178069, 4589.380433641449, 7293.963530182368, 7439.404847612178, 7447.0, 7555.395568354518, 7555.773326800205, 3815.503984482055, 4673.859784206479, 4681.110588988081, 4691.741954192552, 4695.835645752382, 4776.641751868624, 4782.856514304576, 4783.378160839069, 4784.378030855966, 4794.856154315376, 5676.545876329967, 5680.630759000652, 5685.914711279334, 5688.288963879515, 5704.315482142087, 5844.386339434298, 7171.713131474683, 7175.031370764984, 7195.560887822457, 7220.819479513054, 1520.4906356370518, 1530.7015132049144, 1599.8640115590072, 1640.450449099565, 1656.850883420498, 1020.2093377632245, 1034.4052170002278, 1037.097724979273, 1042.3537406807827, 1048.2557384257111, 5343.679379237258, 6520.641364724986, 6594.505412094112, 6608.603483790988, 6612.239592446834, 5982.670953097621, 7332.596707181049, 7355.926440735631, 7360.447966402541, 7360.447966402541, 6585.111010013683, 6585.6377899216, 6590.769323073765, 6594.406503414716, 6613.14029176204, 3846.11539345952, 3898.103436209631, 3903.1803321302277, 3912.628300311466, 3923.6076392361106, 7272.345488906024, 7714.1128770192145, 7725.0, 7728.961355193188, 7739.34215591681, 7543.396528277724, 7578.886316705254, 7586.431017673696, 7605.201453847377, 7606.505577137468, 7656.425768067417, 7666.348360389206, 7669.309762121436, 7680.231976802387, 7688.578097899144, 7171.247019063132, 7281.199068102399, 7302.744403946049, 7329.1937886833475, 7336.532693461436, 7300.634968251672, 7367.042284502981, 7374.0, 7379.926200737924, 7382.446316526175, 7792.6103694816165, 7793.883091753515, 7802.219778022152, 7842.686292548462, 7851.60741962911, 4617.8383756568355, 4705.670603057753, 4706.458757242961, 4708.693934894221, 4715.292706094114, 5897.233359663216, 6725.630090345078, 7440.144383395978, 7443.962780186281, 7454.552726836408, 7033.29667033313, 7106.040684507525, 7107.431405487445, 7109.502334836512, 7126.893096603451, 7026.859462810818, 7079.575225486487, 7093.467989900882, 7102.928970710227, 7110.893336599852, 6935.549189302679, 6942.8611427772175, 6945.2707465715375, 6951.2353641098425, 6977.197622273504, 5451.809186678447, 6785.1518560179975, 7086.149662040382, 7098.645067746489, 7122.5726456412785, 6881.862362752617, 6932.757353492805, 6953.895691564529, 6957.0, 6978.197507286524], "value": 5170.967916397141, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.2947223304462, 713.001797483523, 734.7208060936844, 745.1393640345403, 784.6861255497804, 12424.813627795591, 15125.621859453518, 15136.772948405755, 15176.772348414755, 15204.467843625462, 11613.941930290337, 13784.17294962303, 13843.653908652288, 13850.099743516665, 13858.0, 730.240549828178, 743.8995735575683, 754.5321900421746, 757.7650928212245, 925.6852670092168, 735.2169939014932, 753.0248328414689, 924.4083786376724, 927.0173615967096, 944.3059351376733, 966.0436168193473, 982.7444864335253, 992.3500107429645, 994.9502524873764, 998.8851282102543, 966.970990870275, 992.5880759484842, 992.8510723391477, 994.5723338964242, 997.4464172384323, 581.7411251992864, 730.7515444748758, 735.1252010108008, 736.4255880413286, 737.5464089584867, 14689.192094434802, 14720.852791472056, 14766.630834229123, 14779.778303325458, 14796.964212505129, 12175.112857507012, 12409.317487538181, 12444.937775311157, 12452.25286482805, 12666.240025598401, 9776.16902563283, 9902.55438505269, 10053.497325133714, 10233.181345492343, 10670.679879603624, 3445.207602251496, 3470.132466883279, 3471.7222622190166, 3561.4123669594487, 3646.690031347339, 86372.13627863704, 87210.56394718049, 88879.55560222158, 90140.54929725309, 91245.08754912499, 9224.723258302261, 9307.395019323721, 9369.203617692572, 9395.624175032923, 9569.952150239204, 21299.78700213009, 21445.67831482529, 21458.141674332852, 21464.678029829563, 21561.56876862454, 6405.0, 7375.704971801175, 7685.269899359552, 7778.688852445839, 7934.087579928267, 3980.144268982183, 4050.6354428101613, 4067.1255680028935, 4096.774677392772, 4299.720518166309, 3614.3494171049465, 3648.7445878788762, 3660.743747937672, 3661.6268899162815, 3674.356987527172, 700.4606453031239, 708.4969671533166, 712.5226098514008, 721.6211488968333, 742.4542960923717, 515.3249243490964, 526.8498477933847, 546.8332158691637, 616.5653214483779, 635.9968200159062, 7608.695652173851, 7949.125596184415, 7978.321842643324, 8023.9598802006785, 8027.277545020859, 550.5182964905707, 584.2754983820117, 610.7404353149893, 620.3207487800851, 624.8375422390211, 3516.1209697575605, 3775.7923314217983, 3811.3901775715754, 3884.4367566703236, 3900.0, 88468.0, 92175.07824921799, 92229.53885230531, 92509.0, 92533.0, 75611.24388756041, 77561.0, 78252.60873695595, 79426.60286698645, 79599.01002474868, 66686.0, 68691.31308686949, 68874.0, 69718.65140674265, 69768.6511567449, 6169.259688837189, 7587.506812057199, 7666.233376662289, 7674.424418168548, 7732.420068494773, 34598.7890423844, 46239.84400390017, 46614.0, 46731.29903051389, 46829.76585117052, 56549.717251415124, 62362.68818655878, 64226.0, 64247.0, 65172.02241966469, 9718.9028109718, 9784.315097943076, 9945.701628951143, 9997.500124993867, 10020.398776073458, 10491.632792852213, 10554.736131596772, 10870.673879783619, 10874.456277185951, 11212.43937803123, 10253.282270241012, 10405.843912341472, 10418.895811041793, 10668.786624267626, 10672.57309707634, 10114.747131321776, 10244.180465562886, 10369.84445233307, 10420.166386688896, 11002.944985275342, 9866.260030497599, 9985.900140998787, 10151.84772228432, 10738.838917416402, 10795.622153224913, 8702.173293537357, 8868.068852770624, 9018.729438116867, 9126.0, 9126.680566180152, 8813.0, 9592.76018099553, 9638.0, 9656.130948214695, 9667.323287369816], "value": 6731.443173108154, "unit": "score"}], "name": "dromaeo_css", "value": 7374.604714903191, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 120.0, 158.0, 164.0, 113.0, 115.0, 114.0, 114.0, 144.0], "value": 126.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 120.0, 150.0, 168.0, 117.0, 149.0, 139.0, 120.0, 177.0, 118.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 185.0, 185.0, 198.0, 262.0, 184.0, 183.0, 184.0, 188.0], "value": 194.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 80.0, 76.0, 161.0, 55.0, 53.0, 55.0, 71.0, 135.0, 53.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 186.0, 116.0, 113.0, 92.0, 126.0, 79.0, 75.0, 126.0], "value": 109.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 88.0, 107.0, 113.0, 135.0, 85.0, 86.0, 83.0], "value": 95.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0], "value": 92.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 81.0, 85.0, 83.0, 84.0, 85.0, 86.0, 84.0], "value": 83.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 65.0, 62.0, 64.0, 63.0, 63.0, 83.0, 54.0, 55.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 77.0, 79.0, 74.0, 71.0, 70.0, 71.0, 69.0, 70.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 80.0, 81.0, 79.0, 81.0, 81.0, 80.0, 80.0, 85.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 134.0, 146.0, 152.0, 126.0, 127.0, 124.0, 114.0, 142.0, 172.0], "value": 136.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 163.0, 162.0, 163.0, 162.0, 161.0, 162.0, 162.0, 161.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 68.0, 69.0, 69.0, 67.0, 68.0, 66.0, 67.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1505.6999999999998, "alertThreshold": 2.0}]} 04:06:08 INFO - Return code: 0 04:06:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:06:08 ERROR - # TBPL SUCCESS # 04:06:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:06:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:06:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:06:08 INFO - 2016-05-11 04:06:08.511 screenresolution[11227:201702] starting screenresolution argv=screenresolution get 04:06:08 INFO - 2016-05-11 04:06:08.514 screenresolution[11227:201702] Display 0: 1600x1200x32@60 04:06:08 INFO - 2016-05-11 04:06:08.520 screenresolution[11228:201705] starting screenresolution argv=screenresolution list 04:06:08 INFO - Available Modes on Display 0 04:06:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:06:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:06:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:06:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:06:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:06:08 INFO - Intel Iris: 04:06:08 INFO - Chipset Model: Intel Iris 04:06:08 INFO - Type: GPU 04:06:08 INFO - Bus: Built-In 04:06:08 INFO - VRAM (Dynamic, Max): 1536 MB 04:06:08 INFO - Vendor: Intel (0x8086) 04:06:08 INFO - Device ID: 0x0a2e 04:06:08 INFO - Revision ID: 0x0009 04:06:08 INFO - Displays: 04:06:08 INFO - Display: 04:06:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:06:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:06:08 INFO - Display Serial Number: 1600x1200 60 04:06:08 INFO - Main Display: Yes 04:06:08 INFO - Mirror: Off 04:06:08 INFO - Online: Yes 04:06:08 INFO - Rotation: Supported 04:06:08 INFO - Return code: 0 04:06:08 INFO - Running post-action listener: _resource_record_post_action 04:06:08 INFO - Running post-run listener: _resource_record_post_run 04:06:09 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 471040; Write bytes: 650554368; Read time: 10; Write time: 1948 04:06:09 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 350777856; Write bytes: 345990656; Read time: 26571; Write time: 683 04:06:09 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 372736; Write bytes: 293770752; Read time: 8; Write time: 1230 04:06:09 INFO - Running post-run listener: _upload_blobber_files 04:06:09 INFO - Blob upload gear active. 04:06:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:06:09 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 04:06:09 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'] 04:06:09 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 04:06:10 INFO - (blobuploader) - INFO - Open directory for files ... 04:06:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:06:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:06:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:11 INFO - (blobuploader) - INFO - Done attempting. 04:06:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:06:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:06:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:12 INFO - (blobuploader) - INFO - Done attempting. 04:06:12 INFO - (blobuploader) - INFO - Iteration through files over. 04:06:12 INFO - Return code: 0 04:06:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:06:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:06:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acc4b39b8e5a7b56defc71bc053bf2a1d51f6f1e67c8d53a54ea593bea4dcb1f795039a8fde9da24383212a4c8513a79e4c40ba2349c9a397e6c5bd4a7e429d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c135b77467b9664af50c502789690cc8eda048e1705f90824c6f1301b799e3948a721da97107d71de3ebf0e6b3e494b788b34921bf0e22782cb9b76032e61f3b"} 04:06:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:06:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:06:12 INFO - Contents: 04:06:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acc4b39b8e5a7b56defc71bc053bf2a1d51f6f1e67c8d53a54ea593bea4dcb1f795039a8fde9da24383212a4c8513a79e4c40ba2349c9a397e6c5bd4a7e429d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c135b77467b9664af50c502789690cc8eda048e1705f90824c6f1301b799e3948a721da97107d71de3ebf0e6b3e494b788b34921bf0e22782cb9b76032e61f3b"} 04:06:12 INFO - Running post-run listener: copy_logs_to_upload_dir 04:06:12 INFO - Copying logs to upload dir... 04:06:12 INFO - mkdir: /builds/slave/test/build/upload/logs 04:06:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.279317 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-11 04:06:12.114944) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 04:06:12.118929) ========= 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.aMHzDit7Vp/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.7CM0zz5uCM/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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/acc4b39b8e5a7b56defc71bc053bf2a1d51f6f1e67c8d53a54ea593bea4dcb1f795039a8fde9da24383212a4c8513a79e4c40ba2349c9a397e6c5bd4a7e429d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c135b77467b9664af50c502789690cc8eda048e1705f90824c6f1301b799e3948a721da97107d71de3ebf0e6b3e494b788b34921bf0e22782cb9b76032e61f3b"} build_url:https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011507 build_url: 'https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acc4b39b8e5a7b56defc71bc053bf2a1d51f6f1e67c8d53a54ea593bea4dcb1f795039a8fde9da24383212a4c8513a79e4c40ba2349c9a397e6c5bd4a7e429d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c135b77467b9664af50c502789690cc8eda048e1705f90824c6f1301b799e3948a721da97107d71de3ebf0e6b3e494b788b34921bf0e22782cb9b76032e61f3b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 04:06:12.151300) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 04:06:12.152046) ========= 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.aMHzDit7Vp/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.7CM0zz5uCM/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.005338 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 04:06:12.203885) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 04:06:12.204594) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 04:06:12.205195) ========= ========= Total master_lag: 0.17 =========