builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0032 starttime: 1463910915.99 results: success (0) buildid: 20160522004024 builduid: 63cd0a1e6ce04fec96e7f8af48c79ac1 revision: 110e5a10dce56ed02e43c58c179e6ac101df216d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:15.986654) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:15.987051) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:15.987332) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:16.120931) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:16.121218) ========= 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.MbdmuhvGqt/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.Px8udZFJLX/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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-22 02:55:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-05-22 02:55:16 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.570039 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:16.713626) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:16.713909) ========= 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.MbdmuhvGqt/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.Px8udZFJLX/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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.027757 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:16.765575) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-05-22 02:55:16.765885) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 110e5a10dce56ed02e43c58c179e6ac101df216d --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 110e5a10dce56ed02e43c58c179e6ac101df216d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MbdmuhvGqt/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.Px8udZFJLX/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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-22 02:55:16,843 truncating revision to first 12 chars 2016-05-22 02:55:16,843 Setting DEBUG logging. 2016-05-22 02:55:16,843 attempt 1/10 2016-05-22 02:55:16,843 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/110e5a10dce5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-22 02:55:21,644 unpacking tar archive at: mozilla-aurora-110e5a10dce5/testing/mozharness/ program finished with exit code 0 elapsedTime=5.055397 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-05-22 02:55:21.839318) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:21.839642) ========= script_repo_revision: 110e5a10dce56ed02e43c58c179e6ac101df216d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:21.840050) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:21.840325) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-22 02:55:21.855853) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-22 02:55:21.856139) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MbdmuhvGqt/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.Px8udZFJLX/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:55:22 INFO - MultiFileLogger online at 20160522 02:55:22 in /builds/slave/test 02:55:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:55:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:55:22 INFO - {'append_to_log': False, 02:55:22 INFO - 'base_work_dir': '/builds/slave/test', 02:55:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:55:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:55:22 INFO - 'branch': 'Mozilla-Aurora', 02:55:22 INFO - 'buildbot_json_path': 'buildprops.json', 02:55:22 INFO - 'config_files': ('talos/mac_config.py',), 02:55:22 INFO - 'default_actions': ('clobber', 02:55:22 INFO - 'read-buildbot-config', 02:55:22 INFO - 'download-and-extract', 02:55:22 INFO - 'populate-webroot', 02:55:22 INFO - 'create-virtualenv', 02:55:22 INFO - 'install', 02:55:22 INFO - 'run-tests'), 02:55:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:55:22 INFO - 'download_minidump_stackwalk': True, 02:55:22 INFO - 'download_symbols': 'ondemand', 02:55:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:55:22 INFO - 'tooltool.py': '/tools/tooltool.py', 02:55:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:55:22 INFO - '/tools/misc-python/virtualenv.py')}, 02:55:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:55:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:55:22 INFO - 'installer_path': 'installer.exe', 02:55:22 INFO - 'log_level': 'info', 02:55:22 INFO - 'log_name': 'talos', 02:55:22 INFO - 'log_to_console': True, 02:55:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:55:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:55:22 INFO - 'opt_config_files': (), 02:55:22 INFO - 'pip_index': False, 02:55:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:55:22 INFO - 'cmd': ('bash', 02:55:22 INFO - '-c', 02:55:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:55:22 INFO - 'enabled': True, 02:55:22 INFO - 'halt_on_failure': False, 02:55:22 INFO - 'name': 'check_screen_resolution'},), 02:55:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:55:22 INFO - 'cmd': ('bash', 02:55:22 INFO - '-c', 02:55:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:55:22 INFO - 'enabled': True, 02:55:22 INFO - 'halt_on_failure': False, 02:55:22 INFO - 'name': 'check_screen_resolution'},), 02:55:22 INFO - 'run_cmd_checks_enabled': True, 02:55:22 INFO - 'sps_profile': False, 02:55:22 INFO - 'sps_profile_interval': 0, 02:55:22 INFO - 'suite': 'dromaeojs-e10s', 02:55:22 INFO - 'system_bits': '32', 02:55:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:55:22 INFO - 'title': 't-yosemite-r7-0032', 02:55:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:55:22 INFO - 'use_talos_json': True, 02:55:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:55:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:55:22 INFO - 'work_dir': 'build'} 02:55:22 INFO - ##### 02:55:22 INFO - ##### Running clobber step. 02:55:22 INFO - ##### 02:55:22 INFO - Running pre-action listener: _resource_record_pre_action 02:55:22 INFO - Running main action method: clobber 02:55:22 INFO - rmtree: /builds/slave/test/build 02:55:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:55:23 INFO - Running post-action listener: _resource_record_post_action 02:55:23 INFO - ##### 02:55:23 INFO - ##### Running read-buildbot-config step. 02:55:23 INFO - ##### 02:55:23 INFO - Running pre-action listener: _resource_record_pre_action 02:55:23 INFO - Running main action method: read_buildbot_config 02:55:23 INFO - Using buildbot properties: 02:55:23 INFO - { 02:55:23 INFO - "project": "", 02:55:23 INFO - "product": "firefox", 02:55:23 INFO - "script_repo_revision": "production", 02:55:23 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:55:23 INFO - "repository": "", 02:55:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:55:23 INFO - "buildid": "20160522004024", 02:55:23 INFO - "stage_platform": "macosx64", 02:55:23 INFO - "basedir": "/builds/slave/test", 02:55:23 INFO - "nightly_build": "True", 02:55:23 INFO - "buildnumber": 7, 02:55:23 INFO - "slavename": "t-yosemite-r7-0032", 02:55:23 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:55:23 INFO - "platform": "yosemite_r7", 02:55:23 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:55:23 INFO - "branch": "mozilla-aurora", 02:55:23 INFO - "repo_path": "releases/mozilla-aurora", 02:55:23 INFO - "revision": "110e5a10dce56ed02e43c58c179e6ac101df216d", 02:55:23 INFO - "pgo_build": "False", 02:55:23 INFO - "builduid": "63cd0a1e6ce04fec96e7f8af48c79ac1", 02:55:23 INFO - "slavebuilddir": "test" 02:55:23 INFO - } 02:55:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:55:23 INFO - Running post-action listener: _resource_record_post_action 02:55:23 INFO - ##### 02:55:23 INFO - ##### Running download-and-extract step. 02:55:23 INFO - ##### 02:55:23 INFO - Running pre-action listener: _resource_record_pre_action 02:55:23 INFO - Running main action method: download_and_extract 02:55:23 INFO - mkdir: /builds/slave/test/build/tests 02:55:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:23 INFO - https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:55:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:55:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:55:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:55:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:55:24 INFO - Downloaded 1199 bytes. 02:55:24 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:55:24 INFO - Using the following test package requirements: 02:55:24 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:55:24 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:55:24 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:55:24 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:55:24 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:55:24 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:55:24 INFO - u'jsshell-mac.zip'], 02:55:24 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:55:24 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:55:24 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:55:24 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:55:24 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:55:24 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:55:24 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:55:24 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:55:24 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:55:24 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:55:24 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:55:24 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:55:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:24 INFO - https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:55:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:55:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:55:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:55:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:55:29 INFO - Downloaded 18102570 bytes. 02:55:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:55:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:55:32 INFO - Return code: 0 02:55:32 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:55:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:32 INFO - https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:55:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:55:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:55:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:55:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:55:33 INFO - Downloaded 18102570 bytes. 02:55:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:55:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:55:37 INFO - Return code: 0 02:55:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:37 INFO - https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:55:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:55:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:55:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:55:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:55:38 INFO - Downloaded 10840145 bytes. 02:55:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:55:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:55:39 INFO - Return code: 0 02:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:39 INFO - https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:55:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:55:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:55:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:55:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:55:43 INFO - Downloaded 114237977 bytes. 02:55:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:55:43 INFO - mkdir: /builds/slave/test/properties 02:55:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:55:43 INFO - Writing to file /builds/slave/test/properties/build_url 02:55:43 INFO - Contents: 02:55:43 INFO - build_url:https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:55:43 INFO - Running post-action listener: _resource_record_post_action 02:55:43 INFO - Running post-action listener: set_extra_try_arguments 02:55:43 INFO - ##### 02:55:43 INFO - ##### Running populate-webroot step. 02:55:43 INFO - ##### 02:55:43 INFO - Running pre-action listener: _resource_record_pre_action 02:55:43 INFO - Running main action method: populate_webroot 02:55:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:55:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:55:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:55:43 INFO - '--tppagecycles', 02:55:43 INFO - '7'], 02:55:43 INFO - 'tests': ['tsvgm']}}, 02:55:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:55:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:55:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:55:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:55:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:55:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:55:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:55:43 INFO - 'talos_options': ['--disable-e10s'], 02:55:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:55:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:55:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:55:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:55:43 INFO - 'talos_options': ['--disable-e10s'], 02:55:43 INFO - 'tests': ['damp', 'tps']}, 02:55:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:55:43 INFO - 'tests': ['damp', 'tps']}, 02:55:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:55:43 INFO - 'tests': ['dromaeo_dom']}, 02:55:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:55:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:55:43 INFO - 'tests': ['a11yr', 02:55:43 INFO - 'ts_paint', 02:55:43 INFO - 'tpaint', 02:55:43 INFO - 'sessionrestore', 02:55:43 INFO - 'sessionrestore_no_auto_restore', 02:55:43 INFO - 'tabpaint']}, 02:55:43 INFO - 'other-e10s': {'tests': ['a11yr', 02:55:43 INFO - 'ts_paint', 02:55:43 INFO - 'tpaint', 02:55:43 INFO - 'sessionrestore', 02:55:43 INFO - 'sessionrestore_no_auto_restore', 02:55:43 INFO - 'tabpaint']}, 02:55:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:55:43 INFO - 'ts_paint', 02:55:43 INFO - 'tpaint', 02:55:43 INFO - 'sessionrestore', 02:55:43 INFO - 'sessionrestore_no_auto_restore', 02:55:43 INFO - 'tabpaint']}, 02:55:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:55:43 INFO - 'ts_paint', 02:55:43 INFO - 'tpaint', 02:55:43 INFO - 'sessionrestore', 02:55:43 INFO - 'sessionrestore_no_auto_restore', 02:55:43 INFO - 'tabpaint']}, 02:55:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:55:43 INFO - 'tests': ['a11yr', 02:55:43 INFO - 'ts_paint', 02:55:43 INFO - 'tpaint', 02:55:43 INFO - 'sessionrestore', 02:55:43 INFO - 'sessionrestore_no_auto_restore', 02:55:43 INFO - 'tabpaint']}, 02:55:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:55:43 INFO - 'tests': ['a11yr', 02:55:43 INFO - 'ts_paint', 02:55:43 INFO - 'tpaint', 02:55:43 INFO - 'sessionrestore', 02:55:43 INFO - 'sessionrestore_no_auto_restore', 02:55:43 INFO - 'tabpaint']}, 02:55:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:55:43 INFO - 'tests': ['tsvgx', 02:55:43 INFO - 'tsvgr_opacity', 02:55:43 INFO - 'tart', 02:55:43 INFO - 'tscrollx', 02:55:43 INFO - 'cart']}, 02:55:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:55:43 INFO - 'tsvgr_opacity', 02:55:43 INFO - 'tart', 02:55:43 INFO - 'tscrollx', 02:55:43 INFO - 'cart']}, 02:55:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:55:43 INFO - 'talos_options': ['--disable-e10s'], 02:55:43 INFO - 'tests': ['tp5o']}, 02:55:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:55:43 INFO - 'tests': ['tp5o']}, 02:55:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:55:43 INFO - 'talos_options': ['--disable-e10s', 02:55:43 INFO - '--xperf_path', 02:55:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:55:43 INFO - 'tests': ['tp5n']}, 02:55:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:55:43 INFO - 'talos_options': ['--xperf_path', 02:55:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:55:43 INFO - 'tests': ['tp5n']}}, 02:55:43 INFO - 'talos.zip': {'path': '', 02:55:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:55:43 INFO - Running post-action listener: _resource_record_post_action 02:55:43 INFO - ##### 02:55:43 INFO - ##### Running create-virtualenv step. 02:55:43 INFO - ##### 02:55:43 INFO - Running pre-action listener: _resource_record_pre_action 02:55:43 INFO - Running main action method: create_virtualenv 02:55:43 INFO - Creating virtualenv /builds/slave/test/build/venv 02:55:43 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:55:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:55:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:55:43 INFO - Using real prefix '/tools/python27' 02:55:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:55:44 INFO - Installing distribute.............................................................................................................................................................................................done. 02:55:47 INFO - Installing pip.................done. 02:55:47 INFO - Return code: 0 02:55:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:55:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:47 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 0x108455a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108465420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc793d5dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082f4e00>, '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 0x10815ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082efb20>, '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.MbdmuhvGqt/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.Px8udZFJLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:55:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:55:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MbdmuhvGqt/Render', 02:55:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:47 INFO - 'HOME': '/Users/cltbld', 02:55:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:47 INFO - 'LOGNAME': 'cltbld', 02:55:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:47 INFO - 'MOZ_NO_REMOTE': '1', 02:55:47 INFO - 'NO_EM_RESTART': '1', 02:55:47 INFO - 'PAGER': '/bin/cat', 02:55:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:47 INFO - 'PWD': '/builds/slave/test', 02:55:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:47 INFO - 'SHELL': '/bin/bash', 02:55:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Px8udZFJLX/Listeners', 02:55:47 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 02:55:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:47 INFO - 'USER': 'cltbld', 02:55:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:47 INFO - 'XPC_FLAGS': '0x0', 02:55:47 INFO - 'XPC_SERVICE_NAME': '0', 02:55:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:55:47 INFO - Cleaning up... 02:55:47 INFO - Return code: 0 02:55:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:55:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:47 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 0x108455a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108465420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc793d5dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082f4e00>, '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 0x10815ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082efb20>, '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.MbdmuhvGqt/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.Px8udZFJLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:55:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:55:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MbdmuhvGqt/Render', 02:55:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:47 INFO - 'HOME': '/Users/cltbld', 02:55:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:47 INFO - 'LOGNAME': 'cltbld', 02:55:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:47 INFO - 'MOZ_NO_REMOTE': '1', 02:55:47 INFO - 'NO_EM_RESTART': '1', 02:55:47 INFO - 'PAGER': '/bin/cat', 02:55:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:47 INFO - 'PWD': '/builds/slave/test', 02:55:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:47 INFO - 'SHELL': '/bin/bash', 02:55:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Px8udZFJLX/Listeners', 02:55:47 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 02:55:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:47 INFO - 'USER': 'cltbld', 02:55:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:47 INFO - 'XPC_FLAGS': '0x0', 02:55:47 INFO - 'XPC_SERVICE_NAME': '0', 02:55:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:47 INFO - Downloading/unpacking psutil>=0.7.1 02:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:55:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:55:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:55:51 INFO - Installing collected packages: psutil 02:55:51 INFO - Running setup.py install for psutil 02:55:51 INFO - building 'psutil._psutil_osx' extension 02:55:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:55:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:55:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:55:51 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:55:51 INFO - building 'psutil._psutil_posix' extension 02:55:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:55:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:55:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:55:52 INFO - ^ 02:55:52 INFO - 1 warning generated. 02:55:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:55:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:55:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:55:52 INFO - Successfully installed psutil 02:55:52 INFO - Cleaning up... 02:55:52 INFO - Return code: 0 02:55:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:55: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')]} 02:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55: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')]} 02:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55: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', '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 0x108455a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108465420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc793d5dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082f4e00>, '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 0x10815ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082efb20>, '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.MbdmuhvGqt/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.Px8udZFJLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:55: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 mozsystemmonitor==0.0.0 02:55:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MbdmuhvGqt/Render', 02:55:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:52 INFO - 'HOME': '/Users/cltbld', 02:55:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:52 INFO - 'LOGNAME': 'cltbld', 02:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:52 INFO - 'MOZ_NO_REMOTE': '1', 02:55:52 INFO - 'NO_EM_RESTART': '1', 02:55:52 INFO - 'PAGER': '/bin/cat', 02:55:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:52 INFO - 'PWD': '/builds/slave/test', 02:55:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:52 INFO - 'SHELL': '/bin/bash', 02:55:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Px8udZFJLX/Listeners', 02:55:52 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 02:55:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:52 INFO - 'USER': 'cltbld', 02:55:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:52 INFO - 'XPC_FLAGS': '0x0', 02:55:52 INFO - 'XPC_SERVICE_NAME': '0', 02:55:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:55:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:55:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:55:55 INFO - Installing collected packages: mozsystemmonitor 02:55:55 INFO - Running setup.py install for mozsystemmonitor 02:55:55 INFO - Successfully installed mozsystemmonitor 02:55:55 INFO - Cleaning up... 02:55:55 INFO - Return code: 0 02:55:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108455a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108465420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc793d5dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082f4e00>, '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 0x10815ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082efb20>, '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.MbdmuhvGqt/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.Px8udZFJLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:55:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:55:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MbdmuhvGqt/Render', 02:55:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:55 INFO - 'HOME': '/Users/cltbld', 02:55:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:55 INFO - 'LOGNAME': 'cltbld', 02:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:55 INFO - 'MOZ_NO_REMOTE': '1', 02:55:55 INFO - 'NO_EM_RESTART': '1', 02:55:55 INFO - 'PAGER': '/bin/cat', 02:55:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:55 INFO - 'PWD': '/builds/slave/test', 02:55:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:55 INFO - 'SHELL': '/bin/bash', 02:55:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Px8udZFJLX/Listeners', 02:55:55 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 02:55:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:55 INFO - 'USER': 'cltbld', 02:55:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:55 INFO - 'XPC_FLAGS': '0x0', 02:55:55 INFO - 'XPC_SERVICE_NAME': '0', 02:55:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:55 INFO - Downloading/unpacking blobuploader==1.2.4 02:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:57 INFO - Downloading blobuploader-1.2.4.tar.gz 02:55:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:55:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:55: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 02:55: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 02:55: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 02:55: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 02:55: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 02:55: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 02:55:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:55:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:55: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 02:55: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 02:55: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 02:55: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 02:55: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 02:55: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 02:55:59 INFO - Downloading docopt-0.6.1.tar.gz 02:55:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:55:59 INFO - Installing collected packages: blobuploader, requests, docopt 02:55:59 INFO - Running setup.py install for blobuploader 02:55:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:55:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:55:59 INFO - Running setup.py install for requests 02:55:59 INFO - Running setup.py install for docopt 02:56:00 INFO - Successfully installed blobuploader requests docopt 02:56:00 INFO - Cleaning up... 02:56:00 INFO - Return code: 0 02:56:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:00 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 0x108455a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108465420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc793d5dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082f4e00>, '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 0x10815ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082efb20>, '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.MbdmuhvGqt/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.Px8udZFJLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:56:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:56:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:56:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MbdmuhvGqt/Render', 02:56:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:00 INFO - 'HOME': '/Users/cltbld', 02:56:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:00 INFO - 'LOGNAME': 'cltbld', 02:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:00 INFO - 'MOZ_NO_REMOTE': '1', 02:56:00 INFO - 'NO_EM_RESTART': '1', 02:56:00 INFO - 'PAGER': '/bin/cat', 02:56:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:00 INFO - 'PWD': '/builds/slave/test', 02:56:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:00 INFO - 'SHELL': '/bin/bash', 02:56:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Px8udZFJLX/Listeners', 02:56:00 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 02:56:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:00 INFO - 'USER': 'cltbld', 02:56:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:00 INFO - 'XPC_FLAGS': '0x0', 02:56:00 INFO - 'XPC_SERVICE_NAME': '0', 02:56:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:56:00 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-lJI88N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:56:00 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-NqumYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:56:00 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-ighfvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:56:00 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-ntTzrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:56:00 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-7TZywa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:56:00 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-5e7iZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:56:00 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-tje1BY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:56:01 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-X0IRvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:56:01 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-jMKybD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:56:01 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-B1VFfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:56:01 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-UnjrMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:56:01 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-SAjKlz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:56:01 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-zd7sxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:56:01 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-A09ORf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:01 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-qHddqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:56:01 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-OYVsbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:56:02 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-nD7vez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:56:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:56:02 INFO - Running setup.py install for manifestparser 02:56:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:56:02 INFO - Running setup.py install for mozcrash 02:56:02 INFO - Running setup.py install for mozdebug 02:56:02 INFO - Running setup.py install for mozdevice 02:56:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:56:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:56:02 INFO - Running setup.py install for mozfile 02:56:02 INFO - Running setup.py install for mozhttpd 02:56:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:56:03 INFO - Running setup.py install for mozinfo 02:56:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:56:03 INFO - Running setup.py install for mozInstall 02:56:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:56:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:56:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:56:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:56:03 INFO - Running setup.py install for mozleak 02:56:03 INFO - Running setup.py install for mozlog 02:56:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:56:03 INFO - Running setup.py install for moznetwork 02:56:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:56:03 INFO - Running setup.py install for mozprocess 02:56:03 INFO - Running setup.py install for mozprofile 02:56:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:56:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:56:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:56:04 INFO - Running setup.py install for mozrunner 02:56:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:56:04 INFO - Running setup.py install for mozscreenshot 02:56:04 INFO - Running setup.py install for moztest 02:56:04 INFO - Running setup.py install for mozversion 02:56:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:56:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:56:04 INFO - Cleaning up... 02:56:04 INFO - Return code: 0 02:56:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:04 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 0x108455a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108465420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc793d5dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082f4e00>, '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 0x10815ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082efb20>, '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.MbdmuhvGqt/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.Px8udZFJLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:56:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:56:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:56:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MbdmuhvGqt/Render', 02:56:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:04 INFO - 'HOME': '/Users/cltbld', 02:56:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:04 INFO - 'LOGNAME': 'cltbld', 02:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:04 INFO - 'MOZ_NO_REMOTE': '1', 02:56:04 INFO - 'NO_EM_RESTART': '1', 02:56:04 INFO - 'PAGER': '/bin/cat', 02:56:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:04 INFO - 'PWD': '/builds/slave/test', 02:56:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:04 INFO - 'SHELL': '/bin/bash', 02:56:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Px8udZFJLX/Listeners', 02:56:04 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 02:56:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:04 INFO - 'USER': 'cltbld', 02:56:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:04 INFO - 'XPC_FLAGS': '0x0', 02:56:04 INFO - 'XPC_SERVICE_NAME': '0', 02:56:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:56:05 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-AI_AAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:56:05 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-rOnZJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:56:05 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-LD_PbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:56:05 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-66Qqf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:56:05 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-jAEFUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:56:05 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-ImwN3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:56:05 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-zezh_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:56:05 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-4gwwD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:56:05 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-UTGNwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:56:05 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-5GHFj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:56:06 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-VlgOhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:56:06 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-nro5iN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:56:06 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-EbvRDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:56:06 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-CjfT2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:06 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-o8Xw1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:56:06 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-G_XGYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:56:06 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-gVvojR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:56:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:56:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:09 INFO - Downloading blessings-1.6.tar.gz 02:56:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:56:09 INFO - Installing collected packages: blessings 02:56:09 INFO - Running setup.py install for blessings 02:56:09 INFO - Successfully installed blessings 02:56:09 INFO - Cleaning up... 02:56:09 INFO - Return code: 0 02:56:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:56:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:56:09 INFO - Reading from file tmpfile_stdout 02:56:09 INFO - Current package versions: 02:56:09 INFO - blessings == 1.6 02:56:09 INFO - blobuploader == 1.2.4 02:56:09 INFO - docopt == 0.6.1 02:56:09 INFO - manifestparser == 1.1 02:56:09 INFO - mozInstall == 1.12 02:56:09 INFO - mozcrash == 0.17 02:56:09 INFO - mozdebug == 0.1 02:56:09 INFO - mozdevice == 0.48 02:56:09 INFO - mozfile == 1.2 02:56:09 INFO - mozhttpd == 0.7 02:56:09 INFO - mozinfo == 0.9 02:56:09 INFO - mozleak == 0.1 02:56:09 INFO - mozlog == 3.1 02:56:09 INFO - moznetwork == 0.27 02:56:09 INFO - mozprocess == 0.22 02:56:09 INFO - mozprofile == 0.28 02:56:09 INFO - mozrunner == 6.11 02:56:09 INFO - mozscreenshot == 0.1 02:56:09 INFO - mozsystemmonitor == 0.0 02:56:09 INFO - moztest == 0.7 02:56:09 INFO - mozversion == 1.4 02:56:09 INFO - psutil == 3.1.1 02:56:09 INFO - requests == 1.2.3 02:56:09 INFO - wsgiref == 0.1.2 02:56:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:56:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:09 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 0x108455a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108465420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc793d5dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082f4e00>, '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 0x10815ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082efb20>, '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.MbdmuhvGqt/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.Px8udZFJLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:56:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:56:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:56:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MbdmuhvGqt/Render', 02:56:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:09 INFO - 'HOME': '/Users/cltbld', 02:56:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:09 INFO - 'LOGNAME': 'cltbld', 02:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:09 INFO - 'MOZ_NO_REMOTE': '1', 02:56:09 INFO - 'NO_EM_RESTART': '1', 02:56:09 INFO - 'PAGER': '/bin/cat', 02:56:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:09 INFO - 'PWD': '/builds/slave/test', 02:56:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:09 INFO - 'SHELL': '/bin/bash', 02:56:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Px8udZFJLX/Listeners', 02:56:09 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 02:56:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:09 INFO - 'USER': 'cltbld', 02:56:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:09 INFO - 'XPC_FLAGS': '0x0', 02:56:09 INFO - 'XPC_SERVICE_NAME': '0', 02:56:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:56:10 INFO - Cleaning up... 02:56:10 INFO - Return code: 0 02:56:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:10 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 0x108455a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108465420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc793d5dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082f4e00>, '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 0x10815ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082efb20>, '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.MbdmuhvGqt/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.Px8udZFJLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:56:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:56:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:56:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MbdmuhvGqt/Render', 02:56:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:10 INFO - 'HOME': '/Users/cltbld', 02:56:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:10 INFO - 'LOGNAME': 'cltbld', 02:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:10 INFO - 'MOZ_NO_REMOTE': '1', 02:56:10 INFO - 'NO_EM_RESTART': '1', 02:56:10 INFO - 'PAGER': '/bin/cat', 02:56:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:10 INFO - 'PWD': '/builds/slave/test', 02:56:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:10 INFO - 'SHELL': '/bin/bash', 02:56:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Px8udZFJLX/Listeners', 02:56:10 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 02:56:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:10 INFO - 'USER': 'cltbld', 02:56:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:10 INFO - 'XPC_FLAGS': '0x0', 02:56:10 INFO - 'XPC_SERVICE_NAME': '0', 02:56:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:10 INFO - Downloading/unpacking jsonschema 02:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:56:12 INFO - Downloading/unpacking functools32 (from jsonschema) 02:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:13 INFO - Downloading functools32-3.2.3-2.tar.gz 02:56:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:56:13 INFO - warning: no files found matching '*.txt' 02:56:13 INFO - no previously-included directories found matching 'build' 02:56:13 INFO - no previously-included directories found matching 'dist' 02:56:13 INFO - no previously-included directories found matching '.git*' 02:56:13 INFO - Installing collected packages: jsonschema, functools32 02:56:13 INFO - Running setup.py install for functools32 02:56:13 INFO - warning: no files found matching '*.txt' 02:56:13 INFO - no previously-included directories found matching 'build' 02:56:13 INFO - no previously-included directories found matching 'dist' 02:56:13 INFO - no previously-included directories found matching '.git*' 02:56:13 INFO - Successfully installed jsonschema functools32 02:56:13 INFO - Cleaning up... 02:56:13 INFO - Return code: 0 02:56:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108455a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108465420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc793d5dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082f4e00>, '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 0x10815ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082efb20>, '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.MbdmuhvGqt/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.Px8udZFJLX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:56:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:56:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MbdmuhvGqt/Render', 02:56:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:13 INFO - 'HOME': '/Users/cltbld', 02:56:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:13 INFO - 'LOGNAME': 'cltbld', 02:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:13 INFO - 'MOZ_NO_REMOTE': '1', 02:56:13 INFO - 'NO_EM_RESTART': '1', 02:56:13 INFO - 'PAGER': '/bin/cat', 02:56:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:13 INFO - 'PWD': '/builds/slave/test', 02:56:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:13 INFO - 'SHELL': '/bin/bash', 02:56:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Px8udZFJLX/Listeners', 02:56:13 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 02:56:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:13 INFO - 'USER': 'cltbld', 02:56:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:13 INFO - 'XPC_FLAGS': '0x0', 02:56:13 INFO - 'XPC_SERVICE_NAME': '0', 02:56:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:13 INFO - Downloading/unpacking flake8 02:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:56:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:56:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:56:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:56:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:56:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:56:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:56:17 INFO - no previously-included directories found matching 'docs/_build' 02:56:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:56:17 INFO - Running setup.py install for pep8 02:56:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:56:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:56:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:56:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:56:17 INFO - no previously-included directories found matching 'docs/_build' 02:56:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:56:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:56:17 INFO - Cleaning up... 02:56:17 INFO - Return code: 0 02:56:17 INFO - Running post-action listener: _resource_record_post_action 02:56:17 INFO - Running post-action listener: _start_resource_monitoring 02:56:17 INFO - Starting resource monitoring. 02:56:17 INFO - ##### 02:56:17 INFO - ##### Running install step. 02:56:17 INFO - ##### 02:56:17 INFO - Running pre-action listener: _resource_record_pre_action 02:56:17 INFO - Running main action method: install 02:56:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:56:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:56:18 INFO - Reading from file tmpfile_stdout 02:56:18 INFO - Detecting whether we're running mozinstall >=1.0... 02:56:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:56:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:56:18 INFO - Reading from file tmpfile_stdout 02:56:18 INFO - Output received: 02:56:18 INFO - Usage: mozinstall [options] installer 02:56:18 INFO - Options: 02:56:18 INFO - -h, --help show this help message and exit 02:56:18 INFO - -d DEST, --destination=DEST 02:56:18 INFO - Directory to install application into. [default: 02:56:18 INFO - "/builds/slave/test"] 02:56:18 INFO - --app=APP Application being installed. [default: firefox] 02:56:18 INFO - mkdir: /builds/slave/test/build/application 02:56:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:56:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:56:50 INFO - Reading from file tmpfile_stdout 02:56:50 INFO - Output received: 02:56:50 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:56:50 INFO - Running post-action listener: _resource_record_post_action 02:56:50 INFO - ##### 02:56:50 INFO - ##### Running run-tests step. 02:56:50 INFO - ##### 02:56:50 INFO - Running pre-action listener: _resource_record_pre_action 02:56:50 INFO - Running main action method: run_tests 02:56:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:56:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:56:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:56:50 INFO - 2016-05-22 02:56:50.560 screenresolution[1644:7924] starting screenresolution argv=screenresolution get 02:56:50 INFO - 2016-05-22 02:56:50.563 screenresolution[1644:7924] Display 0: 1600x1200x32@60 02:56:50 INFO - 2016-05-22 02:56:50.569 screenresolution[1645:7926] starting screenresolution argv=screenresolution list 02:56:50 INFO - Available Modes on Display 0 02:56:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:56:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:56:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:56:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:56:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:56:50 INFO - Intel Iris: 02:56:50 INFO - Chipset Model: Intel Iris 02:56:50 INFO - Type: GPU 02:56:50 INFO - Bus: Built-In 02:56:50 INFO - VRAM (Dynamic, Max): 1536 MB 02:56:50 INFO - Vendor: Intel (0x8086) 02:56:50 INFO - Device ID: 0x0a2e 02:56:50 INFO - Revision ID: 0x0009 02:56:50 INFO - Displays: 02:56:50 INFO - Display: 02:56:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:56:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:56:50 INFO - Display Serial Number: 1600x1200 60 02:56:50 INFO - Main Display: Yes 02:56:50 INFO - Mirror: Off 02:56:50 INFO - Online: Yes 02:56:50 INFO - Rotation: Supported 02:56:50 INFO - Return code: 0 02:56:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:56:50 INFO - Python 2.7.3 02:56:50 INFO - Return code: 0 02:56:50 INFO - grabbing minidump binary from tooltool 02:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:50 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 0x1082f4e00>, '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 0x10815ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082efb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:56:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:56:50 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:56:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:56:50 INFO - Return code: 0 02:56:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:56:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:56:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:56:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:56:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:56:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:56:51 INFO - Return code: 0 02:56:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:56:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0032 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:56:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MbdmuhvGqt/Render', 02:56:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:51 INFO - 'HOME': '/Users/cltbld', 02:56:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:51 INFO - 'LOGNAME': 'cltbld', 02:56:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:56:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:56:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:51 INFO - 'MOZ_NO_REMOTE': '1', 02:56:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:56:51 INFO - 'NO_EM_RESTART': '1', 02:56:51 INFO - 'PAGER': '/bin/cat', 02:56:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:52 INFO - 'PWD': '/builds/slave/test', 02:56:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:56:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:52 INFO - 'SHELL': '/bin/bash', 02:56:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Px8udZFJLX/Listeners', 02:56:52 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 02:56:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:52 INFO - 'USER': 'cltbld', 02:56:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:52 INFO - 'XPC_FLAGS': '0x0', 02:56:52 INFO - 'XPC_SERVICE_NAME': '0', 02:56:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:56:52 INFO - mozversion application_buildid: 20160522004024 02:56:52 INFO - mozversion application_changeset: 110e5a10dce56ed02e43c58c179e6ac101df216d 02:56:52 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:56:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:56:52 INFO - mozversion application_name: Firefox 02:56:52 INFO - mozversion application_remotingname: firefox-dev 02:56:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:56:52 INFO - mozversion application_vendor: Mozilla 02:56:52 INFO - mozversion application_version: 48.0a2 02:56:52 INFO - mozversion platform_buildid: 20160522004024 02:56:52 INFO - mozversion platform_changeset: 110e5a10dce56ed02e43c58c179e6ac101df216d 02:56:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:56:52 INFO - mozversion platform_version: 48.0a2 02:56:52 INFO - using testdate: 1463911012 02:56:52 INFO - actual date: 1463911012 02:56:52 INFO - starting webserver on 'localhost:49278' 02:56:52 INFO - SUITE-START | Running 2 tests 02:56:52 INFO - TEST-START | dromaeo_css 02:56:52 INFO - operating with platform_type : mac_ 02:56:52 INFO - Initialising browser for dromaeo_css test... 02:56:52 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpvbaUIu/profile http://localhost:49278/getInfo.html) 02:56:55 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 02:56:55 INFO - PROCESS | 1663 | colorDepth:24 02:56:55 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 02:56:55 INFO - PROCESS | 1663 | __metrics 02:56:55 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 02:56:56 INFO - TEST-INFO | 1663: exit 0 02:56:56 INFO - Browser initialized. 02:56:56 INFO - Running cycle 1/1 for dromaeo_css test... 02:56:56 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpvbaUIu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:57:08 INFO - PROCESS | 1668 | RSS: Main: 220598272 02:57:08 INFO - PROCESS | 1668 | 02:57:57 INFO - PROCESS | 1668 | 1463911077802 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:00:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 03:00:10 INFO - PROCESS | 1668 | RSS: Main: 231485440 03:00:10 INFO - PROCESS | 1668 | 03:03:12 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 03:03:12 INFO - PROCESS | 1668 | RSS: Main: 243261440 03:03:12 INFO - PROCESS | 1668 | 03:06:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 03:06:15 INFO - PROCESS | 1668 | RSS: Main: 245276672 03:06:15 INFO - PROCESS | 1668 | 03:09:16 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 03:09:17 INFO - PROCESS | 1668 | RSS: Main: 246738944 03:09:17 INFO - PROCESS | 1668 | 03:12:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 03:12:20 INFO - PROCESS | 1668 | RSS: Main: 249987072 03:12:20 INFO - PROCESS | 1668 | 03:15:22 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 03:15:23 INFO - PROCESS | 1668 | RSS: Main: 250679296 03:15:23 INFO - PROCESS | 1668 | 03:15:23 INFO - PROCESS | 1668 | __start_tp_report 03:15:23 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 03:15:23 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 03:15:23 INFO - PROCESS | 1668 | |i|pagename|runs| 03:15:23 INFO - PROCESS | 1668 | |0;dojo.html;3458.152752575619;3489.3719130556487;3496.0385893879197;3547.8048707321086;3548.9645103548974;5885.705144868133;5909.290885093784;5924.111383292508;5925.377835327293;5952.940470595293;5123.103456895038;5899.823005309837;5947.553933454987;5970.522358211343;5973.820785376446;5265.4997775211295;5269.973650131763;5342.118550439173;5345.080763582974;5376.784928602851;4702.965347623517;4928.137575924216;5389.51494365507;5421.620486565942;5431;4462.705815313555;5272.789088436458;5325.680459172443;5326.414094449608;5455.399906010336;4342.609165175118;5348.732563371816;5356.11624082026;5362.35651721794;5394.298741163663;5298.894022119574;5328.094223981932;5341.599380046473;5356.437574054746;5362.51737343641;4666.9066618667775;5643.830685079454;5687.317521897398;5795.507381872546;5935.0503919372695;4695.88260293492;5880.8235752927485;6180.258368995749;6261;6273.12176295319;5608.691521966291;6194.783182588588;6223.906641400383;6237.126802247689;6264.5301602380005;4898.755062246873;5917.497012753921;5937.554683398719;5973.044312909914;6050.122732203894;5153.6907785532985;6089.330173680892;6146.569740118238;6162;6185.597936134137;6131.264248290234;6226.346233645492;6297.685115744194;6304.148939893147;6307.05394190875;6256.374362563707;6289.371062893765;6329.68351582419;6348;6355.205599300088;5188.728761453468;5590.496855283044;6226.533010024266;6236.846183455985;6256.155419018465;6101.12621667637;6232.781852635136;6244.406781355764;6254.186955695731;6268.811935641938;4971.6271279654175;5033.094043072282;5035.7985680572365;5052.7726252318735;5084.618653600995;4504.842330518416;4521.434820647419;4550.408446901882;4566.908661826745;4573.748443835554;5854.736536855852;5932.970335148383;5956.255468066492;6015.30823955242;6021.307549631849;6114.114890844268;6180.845478862974;6238.844028899223;6246.312905580383;6349.46029587481;4917.041176970456;6040.124181993674;6060.000099983541;6072.787452439232;6075.2405949256345;5409.053415652324;5481.862953426116;5566.55467562594;5583.804566840141;5600.6919619420505;6243.687815609202;6254.749810007731;6286.465650419583;6296.370362963758;6301.842453938688;6212.850622634827;6231;6237.906431403623;6248.562600617913;6256.217972753406;6261.0608408737235;6293.244810622755;6301.905471417841;6326.43062124411;6452.032195170762;5160.09698302803;6196.47329976957;6196.81409557714;6231.283402408781;6239.844003899939;4930.038642464685;5782.10377391505;6239.500839932885;6249.312575616769;6260.342664020212;4940.036692845005;6111.96944015277;6135.07955838608;6207.16203312547;6215.627062376272;6212.161358216583;6212.409821066992;6254.405831446005;6265.467435267871;6266.905996409966;5414;6083.969580152071;6114.807612515517;6143.784967526115;6185.505159587312;6003.939960600338;6114.327423983269;6171.938280617136;6226.968865155645;6231.189945307081;6154.692265386802;6169.259688837368;6186.1648677427975;6186.690665466798;6200.81397558074 03:15:23 INFO - PROCESS | 1668 | |1;ext.html;527.1670760198887;545.5172172627225;553.9585579111271;574.0222223260988;596.439347013807;34662.48006279905;38219.85340439788;38455.80772096143;38534.61465385345;38779.418308725355;39228.21543569134;39245.803770981176;39624;39691;39803;9365.719028429141;9431.245500359955;9442.244620430383;9572.617095316215;9588.18500427461;9463.905360946406;9510.00144984777;9590.568424420882;9611.423314601112;9621.085996830325;9698.757531061709;9743.171830394425;9750.317477776558;9754.46350450725;9755.219582433388;9631.662891798753;9710.9514452428;9714.22286217101;9742.415455072718;9745.853812192823;9529.523523823782;9585.472798996052;9616.51917404127;9662.75843103921;9666.275029372826;38821;38828;39038.60961390378;39125.21749565021;39232.60767392346;3095.5666206731075;3101.255698632312;3445.9655403445895;3470.1498132957336;3790.431435284702;21404.89297553518;24558.50882982348;25104.623430648557;26303.473930521282;26307.736922630913;2735.617013618095;2755.0908200294057;2802.313433208877;2865.226388874992;2900.7824413169096;460963;463985;466234;468466;468670.99999999657;36893.446598301045;37892.62107378891;60806;61075.69462152661;61376.69311653413;34358.82820585881;36664;36753.63246367555;36934.8153259232;37088;13563.525276615272;14572.41710331596;15800.525984220492;15931;15998.96006759557;13239.470421183045;13244.470221191044;13452.798208026887;13577.981651375989;13614.591562253148;8256.215659512336;8258.587070646443;8312.21034001769;8313.833723325499;8379.203975622306;3444.2422667013216;3484.8257587120543;3496.6328535503912;3499.7725147865817;3521.9295614088087;851.5475430759374;1007.3804610164696;1007.9718686939281;1019.6991887393236;1040.7606250562262;23416;28121.859390702917;28229.71770282271;28405;28471.145865624065;1010.9595616175418;1121.971950701239;1127.7124333295121;1226.8282440458343;1252.392589594046;14417.495387661591;14766.335514901855;14783.852161478248;14991.250437478082;15109.244537773067;629862;635950;636550;638366;645364;60921.695391523645;61219.693901530205;61431;61488.38511614827;61600.075998860055;1519.931603077864;1521.6576270339306;1529.602303401146;1541.745611974034;1543.5215083324206;29173.416531668823;29205.707942921148;29271.268218294714;29699.703002970557;29901.700982990762;50600;51320.973580528924;51524.48475515197;51595;51666.74166629293;579425;594722;596481;599739;603299;26606;26903.596446052932;27231.455370892076;27256.591151133147;27338.589921151597;45159.54840451553;45853;46694.53305467038;47235.29147062728;47761;37530.437043443824;37746.62253377541;38254.23491530209;38345;38997;30376.696233037386;37700;38049;38445.23109537738;38482.8455146346;36980.44529332116;38408;38604;39091;39196;29882.402351953275;32710.50934236036;33039.66960330366;33373.49939750954;33427.66289348399;25066.37334066663;25184.874075629505;25309.367265817764;25541.616875746506;25689.486210276064 03:15:23 INFO - PROCESS | 1668 | |2;jquery.html;335.8975512468697;395.0439935356439;400.85569195089766;405.33525018968896;408.8323787247229;19315.937623430724;20165.09257083431;20638.277660281892;20877.582448351022;21002;17952;20552.280670176548;21097.367078987616;21302.57394852102;21474.463138421546;4171.916561668765;5817.331006934204;6065.181200537937;6107.297660769024;6113.572049956505;4555.977220113895;6164.321924588314;6650.567713098657;6669;6695.49783766217;5524.640898341615;6589.308122647125;6590.077389165521;6597.2083349998065;6614.2393624733295;5689.374168841424;5715.3998830122655;5748.7125643717645;5796.362400135981;5849.912251316234;4755.619550435957;4868.829590964299;5586.804461843856;5687.4881260686325;5828.912566311508;11189.944050279777;12799.744005119845;13114.737705245938;13872.514461993882;23859.522809543887;2043.9386818395471;2126.5109024924354;2138.647123224666;2182.6289530779804;2289.0386037864137;10206.693799186036;10256.692299231036;14156.433742650283;14224.217668028254;14403.78394324086;1453.6583902782838;1743.834335738103;1758.5603599100225;1777.3246166456663;1843.0876716025518;91284.54357728235;96396.51801741017;100775.49612251892;101412.49293753484;102753.4862325691;11545.845415458554;11620.593279235187;11833.290002599872;12025.278483291031;12095.455704493319;14798.408063677332;14912.328945197492;14924.403023878924;14924.955253132177;14927.029743066665;8879.778005549913;8950.194482496476;8958.283337332998;9028.232600229054;9066.727998160066;8973.192412682889;8988.865167022499;8994.640214391351;9008.954955225181;9079.364444488954;3862.24686186191;4565.634749220121;4659.4175728034;4698.906021879543;4707.95292047082;1943.24213556713;1998.2706312195944;2067.803558661925;2100.4433825036376;2105.1684584589;901.6078006067381;924.3991405586288;960.6109525642127;982.1160955140288;1017.966763734795;19492.70760938587;20625.58748825045;20637.380878573666;20712.89643551803;20748.792512074688;954.4893481987103;968.1480297338438;989.9209861251127;1000.3547711725896;1068.6687126990657;9856.655017074369;10092.44491552957;10770.084542813793;10913.945430272957;10956.12351011933;610183;614886;627814;634660;635720;106406;110075;124306;127793.08310375352;127874;940.3652534539159;1034.2294990232326;1059.9523021464054;1079.1582565598846;1079.7246702090918;17168.570785729957;18307.809992350456;18324.450266492026;18334.449966501026;18728.906355468134;46963.06073878435;47963.76018120027;47975.28037079377;49799;50481;63071.25882452576;63342.36657633365;68141.65929170322;68520.6573967127;68588.6570567144;7537.170911199654;7889.684412623431;8186.631601577824;8246.793830154294;8261.793455163668;11236.775264494501;12863.742725145257;12869;13143.93428032892;13210.339483026002;11006.229563930448;11027.17296202804;11137.777244455228;11163.386013769321;11174.776504470028;9193.448393096436;10703.19726020536;10815.297005694605;11067.944660276647;11195.776084478428;8877.37858349935;10296.897031029894;11246.493907774333;11352.262102963281;11701.590444334408;8225.300849427626;8271.627776750182;8310.916890831257;8349.916500834914;8384.748457546284;10140.64507742255;11221.046211071824;11478.08175345987;11523.654290371302;11530.250533715282 03:15:23 INFO - PROCESS | 1668 | |3;mootools.html;1557.2680840005198;1612.7016501947141;1640.2045008171035;1824.8540116790657;1871.9812801871976;7996.640151193195;8690.348223883218;8702.56487175641;8898.688545900886;8934.195922366986;8643.697470588531;8702.303815694746;8801.91198088019;8812.4712517249;8818.338624603148;2450.4119011437215;2456.2017344363153;2468.543319485892;2473.134402958968;2491.9626205606933;2428.7935525480357;2477.430191056058;2485.9254222373356;2487.11707343893;2490.1160088168713;2457.9754202458016;2460.3357093584723;2464.223769512607;2491.4145175883746;2509.4353770401694;2438.207582535683;2447.4493239021167;2449.081594402099;2479.2934013806157;2480.2435257246534;2232.162938897913;2377.9893545243203;2467.580511313081;2482.4786794773117;2496.5506208882393;8948;8987.236084932789;8987.910120898772;9004.41471304363;9010.59452324647;5410.810621628263;5507.779688812443;5534.557235421156;5548.167774833767;5573.996680597491;3505.5968563615256;3604.6755791978585;3645.143391303042;3646.179609587848;3649.197176621139;1040.5213601743133;1070.925035247533;1073.4847273308856;1073.7208325835338;1074.978500429987;11735.7066073348;11891.227070240408;12006.399680015877;12032.578859739955;12090.274583524928;4816.975915120472;4820.517948205152;4859.65982381237;4900.485449027872;4979.726115063634;10241.743956401;10256.692299231036;10271.281010329354;10278.486075696186;10307.896921030844;5420.539254163361;5434.130539113723;5511.311086114236;5523.696196709219;5551;4459.687821852439;4879.878003049952;5004.449510553909;5007.349044624176;5027.421846487628;3787.223619158121;3977.3636218204947;4102.774347410861;4115.492887571335;4168.2080404723;1188.7147084699782;1348.9123206991515;1361.611940596925;1367.6717587778862;1375.3604573873197;927.0590350793949;930.9666270439807;955.3408148377597;959.52023988006;959.9232061435068;5958.523318134538;5962.791302304398;5982.132590774313;5989.221401217814;6076.605020673642;474.8314348406335;477.55587303807795;483.37161689803116;486.1832122035015;493.5705935835846;3861.131245469803;3981;4099.815508302134;4108.424820525129;4164.83340666376;10579.153667706565;10674.932506749263;10779.514921828537;10788.676339709822;10794.78410431787;10339.586416543405;10519.053285204369;10526.631567895087;10542.789144217073;10595.152387808957;8084.514929104273;8223.095459499451;8289.088200297962;8295.377846661404;8324.125966773523;5772.422757724194;5966.910496342472;5996;6052.727627256872;6058.787942421994;9547.76130596713;9590.42457452555;9761.56072976732;9767;9793.706188814347;9572.377795443273;9969;10084;10105.989401059835;10139.695809125738;6798.626075565693;7414.925850741423;7479.626018698935;7645.923540764744;7660.770176894702;8161.428699990944;8251.793705157419;8268;8323.500589964622;8347.332213422791;7276.636168191675;7322;7477.626118694152;7533.849323013618;7601.7719468416035;7318.524295920748;7354.77935661931;7373.3732632726815;7446.925530744623;7466.25337466249;5989.371116032754;6921.792346229621;6978.337057979484;7028.718851245892;7034;4809.78355973975;4853.757312134449;4931.9753401232765;4955.72743499111;4964.205727083649;4900.905211947123;5016.172331565251;5017.598592112695;5035.0685123252315;5175.9741201295255 03:15:23 INFO - PROCESS | 1668 | |4;prototype.html;294.14550730128974;313.4107877190881;327.74927180706777;328.3941128617701;330.2997644992616;6632.176151558312;6727.159105111861;6752.358525940028;6830.3511166439175;6925.619090949996;6767.830804229897;6828.624425656587;6850.451963842893;6880.2775708550635;6890.655467226644;4964.627652926045;5009.123403404408;5029.899402011957;5036.924446133311;5040.445550989388;5017.172166592526;5028.748562571856;5041.420236672793;5056;5063.645544811865;4619.006913513594;4656.231721765905;4945.629077819178;4948.960718249172;4953.801847926096;4638.1187574360765;4820.108279968217;4976.054549635558;4979.950200498003;5002.924956125643;4066.207089617526;4095.24238015968;4108.096218831853;4108.301588729923;4120;6429.132067170919;6444.903326450107;6455.644939528324;6468.644224567647;6480.189976252968;4706.29405589161;5839.0949402842625;5849.561282903799;5850.210221620112;5870.618409803392;7257.129144502694;7267.818304542376;7272.600006999613;7285.526440781332;7306.81732956675;4490.39379683745;4658.277966915133;4659.207934651084;4668.976655116737;4685.1332503486965;7658.425618078611;7708.5374877506965;7747.031621047369;7754.728584499516;7774.65014074368;3919.255341485166;4731.148393289241;4731.787069581877;4736.455307639666;4739.241721324572;4694.108119457293;4818.855434336975;4827.927581086286;4828.686135401188;4845.3216549683075;4414.713043652152;4661.3707149534375;4768.737719425465;5799.9130013049835;5868.178455016301;7104.751333703399;7273.63631818407;7353.669084891193;7374.594397308199;7388.408927285912;1382.571402865115;1645.8271881452515;1675.1707904587308;1678.7337094921759;1682.0664531185064;883.4434306387063;979.6277414582555;1037.7768779712399;1038.3925403638955;1059.8781140168826;6723.159605049369;6879.828004299832;6902.965485172643;6904.896426553606;6925.861482770317;7700.037495313086;7708.691652333956;7713.537187768752;7722.768316950501;7757;6860.794176174722;6881.690323935435;6883.65581720912;6895.207051189177;6928.896066559006;3904.511936007999;4011.9799401003393;4052.696047796427;4058.6550143237564;4078.3678529827926;7946.284834364819;8009.359251259885;8016.559089250147;8027.879581806277;8030.759077227693;6434.8391290218115;7901;7920.5247685139075;7943.841123177504;7978.321842643324;7962.004749406325;7988.760337189894;7990.280874721303;8002.559859207567;8010.679572817022;7313.414926805736;7576.545407275581;7580.9620951894885;7698.228637690315;7699.923000769921;5257.868553286198;7393.630318483947;7439.367653749492;7509.849803004018;7558;6957.199922008831;7459.925400745923;7966.800829979297;7967.681292748225;7980;4582.702124362039;4590.586847183769;4715.787789549547;4776.689515181502;4777.976110119566;7305.963470182615;7618.847623047619;7625.50434221796;7626.589081020029;7637.656305466378;6042.1843051187525;6123.84690382744;7339.266073392618;7344.621474121964;7374;7309.707611695472;7312.341889229995;7331.376832969044;7341.449391295568;7349.522281051714;7113.288671132846;7124.85750284981;7128.322809333105;7138.357547820721;7151.2133665297815;5612.522935550361;6056.242969628796;7290.5625662460425;7299.379552737864;7327;6808.489363297872;6956.0609317741455;7147.499675022698;7173.820654483679;7192.568445893263 03:15:23 INFO - PROCESS | 1668 | |5;yui.html;678.4877417549749;719.320242370959;735.8638651849404;736.3485250700872;750.204782930094;12186.390680465975;15011.249437528135;15048;15102.39590416382;15142.16718080505;13189.010824188177;13725.1078679886;13811.65470863229;13838.723225535483;13852.168869867815;885.9512726800008;898.6585097662879;906.5240748606968;907.4782000349792;907.7503686486203;896.6234181643725;902.0843843498853;904.5567671840784;905.5381755304809;906.7461110888962;919.2278486071698;960.4189465373453;963.4604621411996;965.512416229803;969.1955676788249;920.9915142918494;946.3328353510758;956.6938579654513;957.1003256938455;962.2734835199415;685.0512040823446;703.4934846910216;710.3393843725333;710.70860947012;712.590260600157;10975.176861735323;12693.428795704216;12847.93576032114;12894.613161605166;13186.538471153559;11225.270357426823;11383.317000979967;11415.25800822952;11446.942765286203;11620.244684095507;9242.537873106317;9495.43027418357;9662.08210220028;10989.670309890716;11424;4017.4576432181866;4065.349544072964;4080.9387859182134;4158.9376159357635;4162.687798415101;74017.62991185063;75311.62344188245;79230.60384698097;79829.60085199594;80558;9496.43021418717;9529.571169297398;9535.90464095364;9566.760830979281;9605.711828645151;17110.82889171117;19749.40751777449;20402.28591999273;20527.38417847467;20542.589148216954;7357.52176108562;7429.405647548183;7445.255474452511;7536.807607319292;7538.623068846536;3934.724569280123;4048.291548978917;4094.242565125461;4128.02991297045;4152.231837110145;3620.185458271868;3681.8711345102793;3692.02161427222;3693.593704692482;3700.2599480104086;612.1063247658379;668.7926742709775;670.5574320948151;672.653583404547;672.9528932974742;493.3512431153094;591.7899145803264;594.8750762339871;604.1421181921681;613.8925688004581;8238.299744521782;8282.71010514629;8300.70947516834;8373.790655233668;8487.960902733621;590.8818236352661;596.4482853360624;613.1722175063628;615.7290792051573;628.2868943748878;3371.983140084284;3733.4773131761576;3830.0999265172663;3879.515060617423;4002.3396139637216;87097.56451217704;88717;89006.77483062976;89084.10915890888;89503.55248223717;76914.61542692363;77498.22501774911;78878.21121788824;79703.20296797073;80411;68415.31584684188;69715.9085227444;70076.29923700902;70219;70220.59558808897;7692.536573270519;7700.152983171959;7742.961285193538;7752.689892404241;7869.960650196713;35567;48523;49315.26027109524;49589.50410495849;49647.751761240965;49174.50825491699;62623.37376626176;63713;64839;64998;8198.877016844634;9757.365771224848;10054.396736195851;10113.039261270167;10161.644342447978;10476.580936762444;10604.151667866707;10657.25399222047;10873.07578855806;10886.183536234656;10223.591056357662;10265;10369;10436.478176091317;11062.889371106186;9954.45250511229;10132.240081993732;10217.386956782617;10517.894821051692;10733.570657173626;10115.494225288854;10192.643257485952;10207.795844083224;10325.9483702584;10398.324108932895;8624.827503450022;8627.95686021591;8844.071372505794;8917.42036767608;9187.77030574241;8866.601002954754;8966.417182883091;9049.230815380502;9085.591148398205;9169.174774270348 03:15:23 INFO - PROCESS | 1668 | __end_tp_report 03:15:23 INFO - PROCESS | 1668 | __start_cc_report 03:15:23 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,422 03:15:23 INFO - PROCESS | 1668 | __end_cc_report 03:15:23 INFO - PROCESS | 1668 | __startTimestamp1463912123197__endTimestamp 03:15:23 INFO - PROCESS | 1668 | 03:15:23 INFO - PROCESS | 1668 | ------- Summary: start ------- 03:15:23 INFO - PROCESS | 1668 | Number of tests: 6 03:15:23 INFO - PROCESS | 1668 | 03:15:23 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5745.94 Median:6040.12 stddev:654.18 (10.8%) stddev-sans-first:631.09 03:15:23 INFO - PROCESS | 1668 | Values: 3458.2 3489.4 3496.0 3547.8 3549.0 5885.7 5909.3 5924.1 5925.4 5952.9 5123.1 5899.8 5947.6 5970.5 5973.8 5265.5 5270.0 5342.1 5345.1 5376.8 4703.0 4928.1 5389.5 5421.6 5431.0 4462.7 5272.8 5325.7 5326.4 5455.4 4342.6 5348.7 5356.1 5362.4 5394.3 5298.9 5328.1 5341.6 5356.4 5362.5 4666.9 5643.8 5687.3 5795.5 5935.1 4695.9 5880.8 6180.3 6261.0 6273.1 5608.7 6194.8 6223.9 6237.1 6264.5 4898.8 5917.5 5937.6 5973.0 6050.1 5153.7 6089.3 6146.6 6162.0 6185.6 6131.3 6226.3 6297.7 6304.1 6307.1 6256.4 6289.4 6329.7 6348.0 6355.2 5188.7 5590.5 6226.5 6236.8 6256.2 6101.1 6232.8 6244.4 6254.2 6268.8 4971.6 5033.1 5035.8 5052.8 5084.6 4504.8 4521.4 4550.4 4566.9 4573.7 5854.7 5933.0 5956.3 6015.3 6021.3 6114.1 6180.8 6238.8 6246.3 6349.5 4917.0 6040.1 6060.0 6072.8 6075.2 5409.1 5481.9 5566.6 5583.8 5600.7 6243.7 6254.7 6286.5 6296.4 6301.8 6212.9 6231.0 6237.9 6248.6 6256.2 6261.1 6293.2 6301.9 6326.4 6452.0 5160.1 6196.5 6196.8 6231.3 6239.8 4930.0 5782.1 6239.5 6249.3 6260.3 4940.0 6112.0 6135.1 6207.2 6215.6 6212.2 6212.4 6254.4 6265.5 6266.9 5414.0 6084.0 6114.8 6143.8 6185.5 6003.9 6114.3 6171.9 6227.0 6231.2 6154.7 6169.3 6186.2 6186.7 6200.8 03:15:23 INFO - PROCESS | 1668 | 03:15:23 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:68179.13 Median:38376.50 stddev:152877.79 (398.4%) stddev-sans-first:153222.27 03:15:23 INFO - PROCESS | 1668 | Values: 527.2 545.5 554.0 574.0 596.4 34662.5 38219.9 38455.8 38534.6 38779.4 39228.2 39245.8 39624.0 39691.0 39803.0 9365.7 9431.2 9442.2 9572.6 9588.2 9463.9 9510.0 9590.6 9611.4 9621.1 9698.8 9743.2 9750.3 9754.5 9755.2 9631.7 9711.0 9714.2 9742.4 9745.9 9529.5 9585.5 9616.5 9662.8 9666.3 38821.0 38828.0 39038.6 39125.2 39232.6 3095.6 3101.3 3446.0 3470.1 3790.4 21404.9 24558.5 25104.6 26303.5 26307.7 2735.6 2755.1 2802.3 2865.2 2900.8 460963.0 463985.0 466234.0 468466.0 468671.0 36893.4 37892.6 60806.0 61075.7 61376.7 34358.8 36664.0 36753.6 36934.8 37088.0 13563.5 14572.4 15800.5 15931.0 15999.0 13239.5 13244.5 13452.8 13578.0 13614.6 8256.2 8258.6 8312.2 8313.8 8379.2 3444.2 3484.8 3496.6 3499.8 3521.9 851.5 1007.4 1008.0 1019.7 1040.8 23416.0 28121.9 28229.7 28405.0 28471.1 1011.0 1122.0 1127.7 1226.8 1252.4 14417.5 14766.3 14783.9 14991.3 15109.2 629862.0 635950.0 636550.0 638366.0 645364.0 60921.7 61219.7 61431.0 61488.4 61600.1 1519.9 1521.7 1529.6 1541.7 1543.5 29173.4 29205.7 29271.3 29699.7 29901.7 50600.0 51321.0 51524.5 51595.0 51666.7 579425.0 594722.0 596481.0 599739.0 603299.0 26606.0 26903.6 27231.5 27256.6 27338.6 45159.5 45853.0 46694.5 47235.3 47761.0 37530.4 37746.6 38254.2 38345.0 38997.0 30376.7 37700.0 38049.0 38445.2 38482.8 36980.4 38408.0 38604.0 39091.0 39196.0 29882.4 32710.5 33039.7 33373.5 33427.7 25066.4 25184.9 25309.4 25541.6 25689.5 03:15:23 INFO - PROCESS | 1668 | 03:15:23 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:34317.36 Median:11714.54 stddev:103341.43 (882.2%) stddev-sans-first:103599.83 03:15:23 INFO - PROCESS | 1668 | Values: 335.9 395.0 400.9 405.3 408.8 19315.9 20165.1 20638.3 20877.6 21002.0 17952.0 20552.3 21097.4 21302.6 21474.5 4171.9 5817.3 6065.2 6107.3 6113.6 4556.0 6164.3 6650.6 6669.0 6695.5 5524.6 6589.3 6590.1 6597.2 6614.2 5689.4 5715.4 5748.7 5796.4 5849.9 4755.6 4868.8 5586.8 5687.5 5828.9 11189.9 12799.7 13114.7 13872.5 23859.5 2043.9 2126.5 2138.6 2182.6 2289.0 10206.7 10256.7 14156.4 14224.2 14403.8 1453.7 1743.8 1758.6 1777.3 1843.1 91284.5 96396.5 100775.5 101412.5 102753.5 11545.8 11620.6 11833.3 12025.3 12095.5 14798.4 14912.3 14924.4 14925.0 14927.0 8879.8 8950.2 8958.3 9028.2 9066.7 8973.2 8988.9 8994.6 9009.0 9079.4 3862.2 4565.6 4659.4 4698.9 4708.0 1943.2 1998.3 2067.8 2100.4 2105.2 901.6 924.4 960.6 982.1 1018.0 19492.7 20625.6 20637.4 20712.9 20748.8 954.5 968.1 989.9 1000.4 1068.7 9856.7 10092.4 10770.1 10913.9 10956.1 610183.0 614886.0 627814.0 634660.0 635720.0 106406.0 110075.0 124306.0 127793.1 127874.0 940.4 1034.2 1060.0 1079.2 1079.7 17168.6 18307.8 18324.5 18334.4 18728.9 46963.1 47963.8 47975.3 49799.0 50481.0 63071.3 63342.4 68141.7 68520.7 68588.7 7537.2 7889.7 8186.6 8246.8 8261.8 11236.8 12863.7 12869.0 13143.9 13210.3 11006.2 11027.2 11137.8 11163.4 11174.8 9193.4 10703.2 10815.3 11067.9 11195.8 8877.4 10296.9 11246.5 11352.3 11701.6 8225.3 8271.6 8310.9 8349.9 8384.7 10140.6 11221.0 11478.1 11523.7 11530.3 03:15:23 INFO - PROCESS | 1668 | 03:15:23 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5755.32 Median:2697.24 stddev:3199.27 (118.6%) stddev-sans-first:3192.69 03:15:23 INFO - PROCESS | 1668 | Values: 1557.3 1612.7 1640.2 1824.9 1872.0 7996.6 8690.3 8702.6 8898.7 8934.2 8643.7 8702.3 8801.9 8812.5 8818.3 2450.4 2456.2 2468.5 2473.1 2492.0 2428.8 2477.4 2485.9 2487.1 2490.1 2458.0 2460.3 2464.2 2491.4 2509.4 2438.2 2447.4 2449.1 2479.3 2480.2 2232.2 2378.0 2467.6 2482.5 2496.6 8948.0 8987.2 8987.9 9004.4 9010.6 5410.8 5507.8 5534.6 5548.2 5574.0 3505.6 3604.7 3645.1 3646.2 3649.2 1040.5 1070.9 1073.5 1073.7 1075.0 11735.7 11891.2 12006.4 12032.6 12090.3 4817.0 4820.5 4859.7 4900.5 4979.7 10241.7 10256.7 10271.3 10278.5 10307.9 5420.5 5434.1 5511.3 5523.7 5551.0 4459.7 4879.9 5004.4 5007.3 5027.4 3787.2 3977.4 4102.8 4115.5 4168.2 1188.7 1348.9 1361.6 1367.7 1375.4 927.1 931.0 955.3 959.5 959.9 5958.5 5962.8 5982.1 5989.2 6076.6 474.8 477.6 483.4 486.2 493.6 3861.1 3981.0 4099.8 4108.4 4164.8 10579.2 10674.9 10779.5 10788.7 10794.8 10339.6 10519.1 10526.6 10542.8 10595.2 8084.5 8223.1 8289.1 8295.4 8324.1 5772.4 5966.9 5996.0 6052.7 6058.8 9547.8 9590.4 9761.6 9767.0 9793.7 9572.4 9969.0 10084.0 10106.0 10139.7 6798.6 7414.9 7479.6 7645.9 7660.8 8161.4 8251.8 8268.0 8323.5 8347.3 7276.6 7322.0 7477.6 7533.8 7601.8 7318.5 7354.8 7373.4 7446.9 7466.3 5989.4 6921.8 6978.3 7028.7 7034.0 4809.8 4853.8 4932.0 4955.7 4964.2 4900.9 5016.2 5017.6 5035.1 5176.0 03:15:23 INFO - PROCESS | 1668 | 03:15:23 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:5906.69 Median:6829.49 stddev:1952.72 (28.6%) stddev-sans-first:1912.22 03:15:23 INFO - PROCESS | 1668 | Values: 294.1 313.4 327.7 328.4 330.3 6632.2 6727.2 6752.4 6830.4 6925.6 6767.8 6828.6 6850.5 6880.3 6890.7 4964.6 5009.1 5029.9 5036.9 5040.4 5017.2 5028.7 5041.4 5056.0 5063.6 4619.0 4656.2 4945.6 4949.0 4953.8 4638.1 4820.1 4976.1 4980.0 5002.9 4066.2 4095.2 4108.1 4108.3 4120.0 6429.1 6444.9 6455.6 6468.6 6480.2 4706.3 5839.1 5849.6 5850.2 5870.6 7257.1 7267.8 7272.6 7285.5 7306.8 4490.4 4658.3 4659.2 4669.0 4685.1 7658.4 7708.5 7747.0 7754.7 7774.7 3919.3 4731.1 4731.8 4736.5 4739.2 4694.1 4818.9 4827.9 4828.7 4845.3 4414.7 4661.4 4768.7 5799.9 5868.2 7104.8 7273.6 7353.7 7374.6 7388.4 1382.6 1645.8 1675.2 1678.7 1682.1 883.4 979.6 1037.8 1038.4 1059.9 6723.2 6879.8 6903.0 6904.9 6925.9 7700.0 7708.7 7713.5 7722.8 7757.0 6860.8 6881.7 6883.7 6895.2 6928.9 3904.5 4012.0 4052.7 4058.7 4078.4 7946.3 8009.4 8016.6 8027.9 8030.8 6434.8 7901.0 7920.5 7943.8 7978.3 7962.0 7988.8 7990.3 8002.6 8010.7 7313.4 7576.5 7581.0 7698.2 7699.9 5257.9 7393.6 7439.4 7509.8 7558.0 6957.2 7459.9 7966.8 7967.7 7980.0 4582.7 4590.6 4715.8 4776.7 4778.0 7306.0 7618.8 7625.5 7626.6 7637.7 6042.2 6123.8 7339.3 7344.6 7374.0 7309.7 7312.3 7331.4 7341.4 7349.5 7113.3 7124.9 7128.3 7138.4 7151.2 5612.5 6056.2 7290.6 7299.4 7327.0 6808.5 6956.1 7147.5 7173.8 7192.6 03:15:23 INFO - PROCESS | 1668 | 03:15:23 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:17482.63 Median:669.68 stddev:24872.48 (3714.1%) stddev-sans-first:24910.25 03:15:23 INFO - PROCESS | 1668 | Values: 678.5 719.3 735.9 736.3 750.2 12186.4 15011.2 15048.0 15102.4 15142.2 13189.0 13725.1 13811.7 13838.7 13852.2 886.0 898.7 906.5 907.5 907.8 896.6 902.1 904.6 905.5 906.7 919.2 960.4 963.5 965.5 969.2 921.0 946.3 956.7 957.1 962.3 685.1 703.5 710.3 710.7 712.6 10975.2 12693.4 12847.9 12894.6 13186.5 11225.3 11383.3 11415.3 11446.9 11620.2 9242.5 9495.4 9662.1 10989.7 11424.0 4017.5 4065.3 4080.9 4158.9 4162.7 74017.6 75311.6 79230.6 79829.6 80558.0 9496.4 9529.6 9535.9 9566.8 9605.7 17110.8 19749.4 20402.3 20527.4 20542.6 7357.5 7429.4 7445.3 7536.8 7538.6 3934.7 4048.3 4094.2 4128.0 4152.2 3620.2 3681.9 3692.0 3693.6 3700.3 612.1 668.8 670.6 672.7 673.0 493.4 591.8 594.9 604.1 613.9 8238.3 8282.7 8300.7 8373.8 8488.0 590.9 596.4 613.2 615.7 628.3 3372.0 3733.5 3830.1 3879.5 4002.3 87097.6 88717.0 89006.8 89084.1 89503.6 76914.6 77498.2 78878.2 79703.2 80411.0 68415.3 69715.9 70076.3 70219.0 70220.6 7692.5 7700.2 7743.0 7752.7 7870.0 35567.0 48523.0 49315.3 49589.5 49647.8 49174.5 62623.4 63713.0 64839.0 64998.0 8198.9 9757.4 10054.4 10113.0 10161.6 10476.6 10604.2 10657.3 10873.1 10886.2 10223.6 10265.0 10369.0 10436.5 11062.9 9954.5 10132.2 10217.4 10517.9 10733.6 10115.5 10192.6 10207.8 10325.9 10398.3 8624.8 8628.0 8844.1 8917.4 9187.8 8866.6 8966.4 9049.2 9085.6 9169.2 03:15:23 INFO - PROCESS | 1668 | -------- Summary: end -------- 03:15:23 INFO - PROCESS | 1668 | 03:15:23 INFO - TEST-INFO | 1668: exit 0 03:15:23 INFO - TEST-OK | dromaeo_css | took 1111544ms 03:15:23 INFO - TEST-START | kraken 03:15:23 INFO - operating with platform_type : mac_ 03:15:23 INFO - Initialising browser for kraken test... 03:15:23 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpil_0ER/profile http://localhost:49278/getInfo.html) 03:15:26 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 03:15:26 INFO - PROCESS | 1676 | colorDepth:24 03:15:26 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 03:15:26 INFO - PROCESS | 1676 | __metrics 03:15:26 ERROR - PROCESS | 1676 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 03:15:27 INFO - TEST-INFO | 1676: exit 0 03:15:27 INFO - Browser initialized. 03:15:27 INFO - Running cycle 1/1 for kraken test... 03:15:27 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpil_0ER/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:15:39 INFO - PROCESS | 1680 | RSS: Main: 220647424 03:15:39 INFO - PROCESS | 1680 | 03:15:42 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 03:15:43 INFO - PROCESS | 1680 | RSS: Main: 232935424 03:15:43 INFO - PROCESS | 1680 | 03:15:45 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 03:15:46 INFO - PROCESS | 1680 | RSS: Main: 241778688 03:15:46 INFO - PROCESS | 1680 | 03:15:49 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 03:15:50 INFO - PROCESS | 1680 | RSS: Main: 245383168 03:15:50 INFO - PROCESS | 1680 | 03:15:52 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 03:15:53 INFO - PROCESS | 1680 | RSS: Main: 246484992 03:15:53 INFO - PROCESS | 1680 | 03:15:55 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:15:56 INFO - PROCESS | 1680 | RSS: Main: 247955456 03:15:56 INFO - PROCESS | 1680 | 03:15:58 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 03:15:59 INFO - PROCESS | 1680 | RSS: Main: 247910400 03:15:59 INFO - PROCESS | 1680 | 03:16:02 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 03:16:03 INFO - PROCESS | 1680 | RSS: Main: 248074240 03:16:03 INFO - PROCESS | 1680 | 03:16:05 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 03:16:06 INFO - PROCESS | 1680 | RSS: Main: 248004608 03:16:06 INFO - PROCESS | 1680 | 03:16:08 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:16:08 INFO - PROCESS | 1680 | RSS: Main: 247468032 03:16:08 INFO - PROCESS | 1680 | 03:16:10 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:16:11 INFO - PROCESS | 1680 | RSS: Main: 247353344 03:16:11 INFO - PROCESS | 1680 | 03:16:13 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:16:13 INFO - PROCESS | 1680 | RSS: Main: 247558144 03:16:13 INFO - PROCESS | 1680 | 03:16:16 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:16:17 INFO - PROCESS | 1680 | RSS: Main: 247828480 03:16:17 INFO - PROCESS | 1680 | 03:16:20 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:16:20 INFO - PROCESS | 1680 | RSS: Main: 247820288 03:16:20 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 03:16:22 INFO - PROCESS | 1680 | RSS: Main: 249810944 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | __start_tp_report 03:16:22 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 03:16:22 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 03:16:22 INFO - PROCESS | 1680 | |i|pagename|runs| 03:16:22 INFO - PROCESS | 1680 | |0;ai-astar;114;120;109;116;119;118;106;115;118;116 03:16:22 INFO - PROCESS | 1680 | |1;audio-beat-detection;184;133;147;171;139;168;166;149;164;121 03:16:22 INFO - PROCESS | 1680 | |2;audio-dft;217;183;262;277;208;200;196;200;198;196 03:16:22 INFO - PROCESS | 1680 | |3;audio-fft;67;86;97;135;64;80;81;89;149;56 03:16:22 INFO - PROCESS | 1680 | |4;audio-oscillator;79;102;155;113;88;82;113;80;81;118 03:16:22 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;92;101;134;90;92;87;92;89;92;88 03:16:22 INFO - PROCESS | 1680 | |6;imaging-darkroom;95;102;98;93;143;98;118;149;117;97 03:16:22 INFO - PROCESS | 1680 | |7;imaging-desaturate;90;96;91;94;90;89;90;89;91;91 03:16:22 INFO - PROCESS | 1680 | |8;json-parse-financial;60;65;64;65;67;65;66;80;63;60 03:16:22 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;65;66;65;67;77;76;74;67;69;69 03:16:22 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;81;83;82;82;81;80;83;82;85;85 03:16:22 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;129;129;128;137;151;136;137;128;138;163 03:16:22 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;176;217;207;173;165;162;164;169;165;167 03:16:22 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;87;59;66;68;92;94;94;68;69;73 03:16:22 INFO - PROCESS | 1680 | __end_tp_report 03:16:22 INFO - PROCESS | 1680 | __start_cc_report 03:16:22 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1094 03:16:22 INFO - PROCESS | 1680 | __end_cc_report 03:16:22 INFO - PROCESS | 1680 | __startTimestamp1463912182868__endTimestamp 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | ------- Summary: start ------- 03:16:22 INFO - PROCESS | 1680 | Number of tests: 14 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:115.10 Median:117.00 stddev:4.46 (3.8%) stddev-sans-first:4.71 03:16:22 INFO - PROCESS | 1680 | Values: 114.0 120.0 109.0 116.0 119.0 118.0 106.0 115.0 118.0 116.0 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:154.20 Median:165.00 stddev:19.60 (11.9%) stddev-sans-first:17.57 03:16:22 INFO - PROCESS | 1680 | Values: 184.0 133.0 147.0 171.0 139.0 168.0 166.0 149.0 164.0 121.0 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:213.70 Median:204.00 stddev:30.87 (15.1%) stddev-sans-first:32.71 03:16:22 INFO - PROCESS | 1680 | Values: 217.0 183.0 262.0 277.0 208.0 200.0 196.0 200.0 198.0 196.0 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:90.40 Median:80.50 stddev:30.02 (37.3%) stddev-sans-first:30.63 03:16:22 INFO - PROCESS | 1680 | Values: 67.0 86.0 97.0 135.0 64.0 80.0 81.0 89.0 149.0 56.0 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:101.10 Median:79.50 stddev:24.38 (30.7%) stddev-sans-first:24.51 03:16:22 INFO - PROCESS | 1680 | Values: 79.0 102.0 155.0 113.0 88.0 82.0 113.0 80.0 81.0 118.0 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:91.00 stddev:13.99 (15.4%) stddev-sans-first:14.78 03:16:22 INFO - PROCESS | 1680 | Values: 92.0 101.0 134.0 90.0 92.0 87.0 92.0 89.0 92.0 88.0 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:111.00 Median:94.00 stddev:20.41 (21.7%) stddev-sans-first:20.81 03:16:22 INFO - PROCESS | 1680 | Values: 95.0 102.0 98.0 93.0 143.0 98.0 118.0 149.0 117.0 97.0 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:91.10 Median:91.00 stddev:2.23 (2.5%) stddev-sans-first:2.33 03:16:22 INFO - PROCESS | 1680 | Values: 90.0 96.0 91.0 94.0 90.0 89.0 90.0 89.0 91.0 91.0 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:65.00 stddev:5.60 (8.6%) stddev-sans-first:5.58 03:16:22 INFO - PROCESS | 1680 | Values: 60.0 65.0 64.0 65.0 67.0 65.0 66.0 80.0 63.0 60.0 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:69.00 stddev:4.53 (6.6%) stddev-sans-first:4.50 03:16:22 INFO - PROCESS | 1680 | Values: 65.0 66.0 65.0 67.0 77.0 76.0 74.0 67.0 69.0 69.0 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:82.50 stddev:1.65 (2.0%) stddev-sans-first:1.67 03:16:22 INFO - PROCESS | 1680 | Values: 81.0 83.0 82.0 82.0 81.0 80.0 83.0 82.0 85.0 85.0 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:137.60 Median:137.00 stddev:11.35 (8.3%) stddev-sans-first:11.61 03:16:22 INFO - PROCESS | 1680 | Values: 129.0 129.0 128.0 137.0 151.0 136.0 137.0 128.0 138.0 163.0 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.50 Median:171.00 stddev:19.32 (11.3%) stddev-sans-first:20.49 03:16:22 INFO - PROCESS | 1680 | Values: 176.0 217.0 207.0 173.0 165.0 162.0 164.0 169.0 165.0 167.0 03:16:22 INFO - PROCESS | 1680 | 03:16:22 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.00 Median:80.00 stddev:13.29 (16.6%) stddev-sans-first:13.60 03:16:22 INFO - PROCESS | 1680 | Values: 87.0 59.0 66.0 68.0 92.0 94.0 94.0 68.0 69.0 73.0 03:16:22 INFO - PROCESS | 1680 | -------- Summary: end -------- 03:16:22 INFO - PROCESS | 1680 | 03:16:23 INFO - TEST-INFO | 1680: exit 0 03:16:23 INFO - TEST-OK | kraken | took 59850ms 03:16:23 INFO - SUITE-END | took 1171s 03:16:24 INFO - Completed test suite (00:19:31) 03:16:24 INFO - javascript benchmark 03:16:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3458.152752575619, 3489.3719130556487, 3496.0385893879197, 3547.8048707321086, 3548.9645103548974, 5885.705144868133, 5909.290885093784, 5924.111383292508, 5925.377835327293, 5952.940470595293, 5123.103456895038, 5899.823005309837, 5947.553933454987, 5970.522358211343, 5973.820785376446, 5265.4997775211295, 5269.973650131763, 5342.118550439173, 5345.080763582974, 5376.784928602851, 4702.965347623517, 4928.137575924216, 5389.51494365507, 5421.620486565942, 5431.0, 4462.705815313555, 5272.789088436458, 5325.680459172443, 5326.414094449608, 5455.399906010336, 4342.609165175118, 5348.732563371816, 5356.11624082026, 5362.35651721794, 5394.298741163663, 5298.894022119574, 5328.094223981932, 5341.599380046473, 5356.437574054746, 5362.51737343641, 4666.9066618667775, 5643.830685079454, 5687.317521897398, 5795.507381872546, 5935.0503919372695, 4695.88260293492, 5880.8235752927485, 6180.258368995749, 6261.0, 6273.12176295319, 5608.691521966291, 6194.783182588588, 6223.906641400383, 6237.126802247689, 6264.5301602380005, 4898.755062246873, 5917.497012753921, 5937.554683398719, 5973.044312909914, 6050.122732203894, 5153.6907785532985, 6089.330173680892, 6146.569740118238, 6162.0, 6185.597936134137, 6131.264248290234, 6226.346233645492, 6297.685115744194, 6304.148939893147, 6307.05394190875, 6256.374362563707, 6289.371062893765, 6329.68351582419, 6348.0, 6355.205599300088, 5188.728761453468, 5590.496855283044, 6226.533010024266, 6236.846183455985, 6256.155419018465, 6101.12621667637, 6232.781852635136, 6244.406781355764, 6254.186955695731, 6268.811935641938, 4971.6271279654175, 5033.094043072282, 5035.7985680572365, 5052.7726252318735, 5084.618653600995, 4504.842330518416, 4521.434820647419, 4550.408446901882, 4566.908661826745, 4573.748443835554, 5854.736536855852, 5932.970335148383, 5956.255468066492, 6015.30823955242, 6021.307549631849, 6114.114890844268, 6180.845478862974, 6238.844028899223, 6246.312905580383, 6349.46029587481, 4917.041176970456, 6040.124181993674, 6060.000099983541, 6072.787452439232, 6075.2405949256345, 5409.053415652324, 5481.862953426116, 5566.55467562594, 5583.804566840141, 5600.6919619420505, 6243.687815609202, 6254.749810007731, 6286.465650419583, 6296.370362963758, 6301.842453938688, 6212.850622634827, 6231.0, 6237.906431403623, 6248.562600617913, 6256.217972753406, 6261.0608408737235, 6293.244810622755, 6301.905471417841, 6326.43062124411, 6452.032195170762, 5160.09698302803, 6196.47329976957, 6196.81409557714, 6231.283402408781, 6239.844003899939, 4930.038642464685, 5782.10377391505, 6239.500839932885, 6249.312575616769, 6260.342664020212, 4940.036692845005, 6111.96944015277, 6135.07955838608, 6207.16203312547, 6215.627062376272, 6212.161358216583, 6212.409821066992, 6254.405831446005, 6265.467435267871, 6266.905996409966, 5414.0, 6083.969580152071, 6114.807612515517, 6143.784967526115, 6185.505159587312, 6003.939960600338, 6114.327423983269, 6171.938280617136, 6226.968865155645, 6231.189945307081, 6154.692265386802, 6169.259688837368, 6186.1648677427975, 6186.690665466798, 6200.81397558074], "value": 5710.605563095808, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [527.1670760198887, 545.5172172627225, 553.9585579111271, 574.0222223260988, 596.439347013807, 34662.48006279905, 38219.85340439788, 38455.80772096143, 38534.61465385345, 38779.418308725355, 39228.21543569134, 39245.803770981176, 39624.0, 39691.0, 39803.0, 9365.719028429141, 9431.245500359955, 9442.244620430383, 9572.617095316215, 9588.18500427461, 9463.905360946406, 9510.00144984777, 9590.568424420882, 9611.423314601112, 9621.085996830325, 9698.757531061709, 9743.171830394425, 9750.317477776558, 9754.46350450725, 9755.219582433388, 9631.662891798753, 9710.9514452428, 9714.22286217101, 9742.415455072718, 9745.853812192823, 9529.523523823782, 9585.472798996052, 9616.51917404127, 9662.75843103921, 9666.275029372826, 38821.0, 38828.0, 39038.60961390378, 39125.21749565021, 39232.60767392346, 3095.5666206731075, 3101.255698632312, 3445.9655403445895, 3470.1498132957336, 3790.431435284702, 21404.89297553518, 24558.50882982348, 25104.623430648557, 26303.473930521282, 26307.736922630913, 2735.617013618095, 2755.0908200294057, 2802.313433208877, 2865.226388874992, 2900.7824413169096, 460963.0, 463985.0, 466234.0, 468466.0, 468670.99999999657, 36893.446598301045, 37892.62107378891, 60806.0, 61075.69462152661, 61376.69311653413, 34358.82820585881, 36664.0, 36753.63246367555, 36934.8153259232, 37088.0, 13563.525276615272, 14572.41710331596, 15800.525984220492, 15931.0, 15998.96006759557, 13239.470421183045, 13244.470221191044, 13452.798208026887, 13577.981651375989, 13614.591562253148, 8256.215659512336, 8258.587070646443, 8312.21034001769, 8313.833723325499, 8379.203975622306, 3444.2422667013216, 3484.8257587120543, 3496.6328535503912, 3499.7725147865817, 3521.9295614088087, 851.5475430759374, 1007.3804610164696, 1007.9718686939281, 1019.6991887393236, 1040.7606250562262, 23416.0, 28121.859390702917, 28229.71770282271, 28405.0, 28471.145865624065, 1010.9595616175418, 1121.971950701239, 1127.7124333295121, 1226.8282440458343, 1252.392589594046, 14417.495387661591, 14766.335514901855, 14783.852161478248, 14991.250437478082, 15109.244537773067, 629862.0, 635950.0, 636550.0, 638366.0, 645364.0, 60921.695391523645, 61219.693901530205, 61431.0, 61488.38511614827, 61600.075998860055, 1519.931603077864, 1521.6576270339306, 1529.602303401146, 1541.745611974034, 1543.5215083324206, 29173.416531668823, 29205.707942921148, 29271.268218294714, 29699.703002970557, 29901.700982990762, 50600.0, 51320.973580528924, 51524.48475515197, 51595.0, 51666.74166629293, 579425.0, 594722.0, 596481.0, 599739.0, 603299.0, 26606.0, 26903.596446052932, 27231.455370892076, 27256.591151133147, 27338.589921151597, 45159.54840451553, 45853.0, 46694.53305467038, 47235.29147062728, 47761.0, 37530.437043443824, 37746.62253377541, 38254.23491530209, 38345.0, 38997.0, 30376.696233037386, 37700.0, 38049.0, 38445.23109537738, 38482.8455146346, 36980.44529332116, 38408.0, 38604.0, 39091.0, 39196.0, 29882.402351953275, 32710.50934236036, 33039.66960330366, 33373.49939750954, 33427.66289348399, 25066.37334066663, 25184.874075629505, 25309.367265817764, 25541.616875746506, 25689.486210276064], "value": 18540.360014609465, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.8975512468697, 395.0439935356439, 400.85569195089766, 405.33525018968896, 408.8323787247229, 19315.937623430724, 20165.09257083431, 20638.277660281892, 20877.582448351022, 21002.0, 17952.0, 20552.280670176548, 21097.367078987616, 21302.57394852102, 21474.463138421546, 4171.916561668765, 5817.331006934204, 6065.181200537937, 6107.297660769024, 6113.572049956505, 4555.977220113895, 6164.321924588314, 6650.567713098657, 6669.0, 6695.49783766217, 5524.640898341615, 6589.308122647125, 6590.077389165521, 6597.2083349998065, 6614.2393624733295, 5689.374168841424, 5715.3998830122655, 5748.7125643717645, 5796.362400135981, 5849.912251316234, 4755.619550435957, 4868.829590964299, 5586.804461843856, 5687.4881260686325, 5828.912566311508, 11189.944050279777, 12799.744005119845, 13114.737705245938, 13872.514461993882, 23859.522809543887, 2043.9386818395471, 2126.5109024924354, 2138.647123224666, 2182.6289530779804, 2289.0386037864137, 10206.693799186036, 10256.692299231036, 14156.433742650283, 14224.217668028254, 14403.78394324086, 1453.6583902782838, 1743.834335738103, 1758.5603599100225, 1777.3246166456663, 1843.0876716025518, 91284.54357728235, 96396.51801741017, 100775.49612251892, 101412.49293753484, 102753.4862325691, 11545.845415458554, 11620.593279235187, 11833.290002599872, 12025.278483291031, 12095.455704493319, 14798.408063677332, 14912.328945197492, 14924.403023878924, 14924.955253132177, 14927.029743066665, 8879.778005549913, 8950.194482496476, 8958.283337332998, 9028.232600229054, 9066.727998160066, 8973.192412682889, 8988.865167022499, 8994.640214391351, 9008.954955225181, 9079.364444488954, 3862.24686186191, 4565.634749220121, 4659.4175728034, 4698.906021879543, 4707.95292047082, 1943.24213556713, 1998.2706312195944, 2067.803558661925, 2100.4433825036376, 2105.1684584589, 901.6078006067381, 924.3991405586288, 960.6109525642127, 982.1160955140288, 1017.966763734795, 19492.70760938587, 20625.58748825045, 20637.380878573666, 20712.89643551803, 20748.792512074688, 954.4893481987103, 968.1480297338438, 989.9209861251127, 1000.3547711725896, 1068.6687126990657, 9856.655017074369, 10092.44491552957, 10770.084542813793, 10913.945430272957, 10956.12351011933, 610183.0, 614886.0, 627814.0, 634660.0, 635720.0, 106406.0, 110075.0, 124306.0, 127793.08310375352, 127874.0, 940.3652534539159, 1034.2294990232326, 1059.9523021464054, 1079.1582565598846, 1079.7246702090918, 17168.570785729957, 18307.809992350456, 18324.450266492026, 18334.449966501026, 18728.906355468134, 46963.06073878435, 47963.76018120027, 47975.28037079377, 49799.0, 50481.0, 63071.25882452576, 63342.36657633365, 68141.65929170322, 68520.6573967127, 68588.6570567144, 7537.170911199654, 7889.684412623431, 8186.631601577824, 8246.793830154294, 8261.793455163668, 11236.775264494501, 12863.742725145257, 12869.0, 13143.93428032892, 13210.339483026002, 11006.229563930448, 11027.17296202804, 11137.777244455228, 11163.386013769321, 11174.776504470028, 9193.448393096436, 10703.19726020536, 10815.297005694605, 11067.944660276647, 11195.776084478428, 8877.37858349935, 10296.897031029894, 11246.493907774333, 11352.262102963281, 11701.590444334408, 8225.300849427626, 8271.627776750182, 8310.916890831257, 8349.916500834914, 8384.748457546284, 10140.64507742255, 11221.046211071824, 11478.08175345987, 11523.654290371302, 11530.250533715282], "value": 9408.877571352106, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1557.2680840005198, 1612.7016501947141, 1640.2045008171035, 1824.8540116790657, 1871.9812801871976, 7996.640151193195, 8690.348223883218, 8702.56487175641, 8898.688545900886, 8934.195922366986, 8643.697470588531, 8702.303815694746, 8801.91198088019, 8812.4712517249, 8818.338624603148, 2450.4119011437215, 2456.2017344363153, 2468.543319485892, 2473.134402958968, 2491.9626205606933, 2428.7935525480357, 2477.430191056058, 2485.9254222373356, 2487.11707343893, 2490.1160088168713, 2457.9754202458016, 2460.3357093584723, 2464.223769512607, 2491.4145175883746, 2509.4353770401694, 2438.207582535683, 2447.4493239021167, 2449.081594402099, 2479.2934013806157, 2480.2435257246534, 2232.162938897913, 2377.9893545243203, 2467.580511313081, 2482.4786794773117, 2496.5506208882393, 8948.0, 8987.236084932789, 8987.910120898772, 9004.41471304363, 9010.59452324647, 5410.810621628263, 5507.779688812443, 5534.557235421156, 5548.167774833767, 5573.996680597491, 3505.5968563615256, 3604.6755791978585, 3645.143391303042, 3646.179609587848, 3649.197176621139, 1040.5213601743133, 1070.925035247533, 1073.4847273308856, 1073.7208325835338, 1074.978500429987, 11735.7066073348, 11891.227070240408, 12006.399680015877, 12032.578859739955, 12090.274583524928, 4816.975915120472, 4820.517948205152, 4859.65982381237, 4900.485449027872, 4979.726115063634, 10241.743956401, 10256.692299231036, 10271.281010329354, 10278.486075696186, 10307.896921030844, 5420.539254163361, 5434.130539113723, 5511.311086114236, 5523.696196709219, 5551.0, 4459.687821852439, 4879.878003049952, 5004.449510553909, 5007.349044624176, 5027.421846487628, 3787.223619158121, 3977.3636218204947, 4102.774347410861, 4115.492887571335, 4168.2080404723, 1188.7147084699782, 1348.9123206991515, 1361.611940596925, 1367.6717587778862, 1375.3604573873197, 927.0590350793949, 930.9666270439807, 955.3408148377597, 959.52023988006, 959.9232061435068, 5958.523318134538, 5962.791302304398, 5982.132590774313, 5989.221401217814, 6076.605020673642, 474.8314348406335, 477.55587303807795, 483.37161689803116, 486.1832122035015, 493.5705935835846, 3861.131245469803, 3981.0, 4099.815508302134, 4108.424820525129, 4164.83340666376, 10579.153667706565, 10674.932506749263, 10779.514921828537, 10788.676339709822, 10794.78410431787, 10339.586416543405, 10519.053285204369, 10526.631567895087, 10542.789144217073, 10595.152387808957, 8084.514929104273, 8223.095459499451, 8289.088200297962, 8295.377846661404, 8324.125966773523, 5772.422757724194, 5966.910496342472, 5996.0, 6052.727627256872, 6058.787942421994, 9547.76130596713, 9590.42457452555, 9761.56072976732, 9767.0, 9793.706188814347, 9572.377795443273, 9969.0, 10084.0, 10105.989401059835, 10139.695809125738, 6798.626075565693, 7414.925850741423, 7479.626018698935, 7645.923540764744, 7660.770176894702, 8161.428699990944, 8251.793705157419, 8268.0, 8323.500589964622, 8347.332213422791, 7276.636168191675, 7322.0, 7477.626118694152, 7533.849323013618, 7601.7719468416035, 7318.524295920748, 7354.77935661931, 7373.3732632726815, 7446.925530744623, 7466.25337466249, 5989.371116032754, 6921.792346229621, 6978.337057979484, 7028.718851245892, 7034.0, 4809.78355973975, 4853.757312134449, 4931.9753401232765, 4955.72743499111, 4964.205727083649, 4900.905211947123, 5016.172331565251, 5017.598592112695, 5035.0685123252315, 5175.9741201295255], "value": 4583.378120842675, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.14550730128974, 313.4107877190881, 327.74927180706777, 328.3941128617701, 330.2997644992616, 6632.176151558312, 6727.159105111861, 6752.358525940028, 6830.3511166439175, 6925.619090949996, 6767.830804229897, 6828.624425656587, 6850.451963842893, 6880.2775708550635, 6890.655467226644, 4964.627652926045, 5009.123403404408, 5029.899402011957, 5036.924446133311, 5040.445550989388, 5017.172166592526, 5028.748562571856, 5041.420236672793, 5056.0, 5063.645544811865, 4619.006913513594, 4656.231721765905, 4945.629077819178, 4948.960718249172, 4953.801847926096, 4638.1187574360765, 4820.108279968217, 4976.054549635558, 4979.950200498003, 5002.924956125643, 4066.207089617526, 4095.24238015968, 4108.096218831853, 4108.301588729923, 4120.0, 6429.132067170919, 6444.903326450107, 6455.644939528324, 6468.644224567647, 6480.189976252968, 4706.29405589161, 5839.0949402842625, 5849.561282903799, 5850.210221620112, 5870.618409803392, 7257.129144502694, 7267.818304542376, 7272.600006999613, 7285.526440781332, 7306.81732956675, 4490.39379683745, 4658.277966915133, 4659.207934651084, 4668.976655116737, 4685.1332503486965, 7658.425618078611, 7708.5374877506965, 7747.031621047369, 7754.728584499516, 7774.65014074368, 3919.255341485166, 4731.148393289241, 4731.787069581877, 4736.455307639666, 4739.241721324572, 4694.108119457293, 4818.855434336975, 4827.927581086286, 4828.686135401188, 4845.3216549683075, 4414.713043652152, 4661.3707149534375, 4768.737719425465, 5799.9130013049835, 5868.178455016301, 7104.751333703399, 7273.63631818407, 7353.669084891193, 7374.594397308199, 7388.408927285912, 1382.571402865115, 1645.8271881452515, 1675.1707904587308, 1678.7337094921759, 1682.0664531185064, 883.4434306387063, 979.6277414582555, 1037.7768779712399, 1038.3925403638955, 1059.8781140168826, 6723.159605049369, 6879.828004299832, 6902.965485172643, 6904.896426553606, 6925.861482770317, 7700.037495313086, 7708.691652333956, 7713.537187768752, 7722.768316950501, 7757.0, 6860.794176174722, 6881.690323935435, 6883.65581720912, 6895.207051189177, 6928.896066559006, 3904.511936007999, 4011.9799401003393, 4052.696047796427, 4058.6550143237564, 4078.3678529827926, 7946.284834364819, 8009.359251259885, 8016.559089250147, 8027.879581806277, 8030.759077227693, 6434.8391290218115, 7901.0, 7920.5247685139075, 7943.841123177504, 7978.321842643324, 7962.004749406325, 7988.760337189894, 7990.280874721303, 8002.559859207567, 8010.679572817022, 7313.414926805736, 7576.545407275581, 7580.9620951894885, 7698.228637690315, 7699.923000769921, 5257.868553286198, 7393.630318483947, 7439.367653749492, 7509.849803004018, 7558.0, 6957.199922008831, 7459.925400745923, 7966.800829979297, 7967.681292748225, 7980.0, 4582.702124362039, 4590.586847183769, 4715.787789549547, 4776.689515181502, 4777.976110119566, 7305.963470182615, 7618.847623047619, 7625.50434221796, 7626.589081020029, 7637.656305466378, 6042.1843051187525, 6123.84690382744, 7339.266073392618, 7344.621474121964, 7374.0, 7309.707611695472, 7312.341889229995, 7331.376832969044, 7341.449391295568, 7349.522281051714, 7113.288671132846, 7124.85750284981, 7128.322809333105, 7138.357547820721, 7151.2133665297815, 5612.522935550361, 6056.242969628796, 7290.5625662460425, 7299.379552737864, 7327.0, 6808.489363297872, 6956.0609317741455, 7147.499675022698, 7173.820654483679, 7192.568445893263], "value": 5241.631541862075, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.4877417549749, 719.320242370959, 735.8638651849404, 736.3485250700872, 750.204782930094, 12186.390680465975, 15011.249437528135, 15048.0, 15102.39590416382, 15142.16718080505, 13189.010824188177, 13725.1078679886, 13811.65470863229, 13838.723225535483, 13852.168869867815, 885.9512726800008, 898.6585097662879, 906.5240748606968, 907.4782000349792, 907.7503686486203, 896.6234181643725, 902.0843843498853, 904.5567671840784, 905.5381755304809, 906.7461110888962, 919.2278486071698, 960.4189465373453, 963.4604621411996, 965.512416229803, 969.1955676788249, 920.9915142918494, 946.3328353510758, 956.6938579654513, 957.1003256938455, 962.2734835199415, 685.0512040823446, 703.4934846910216, 710.3393843725333, 710.70860947012, 712.590260600157, 10975.176861735323, 12693.428795704216, 12847.93576032114, 12894.613161605166, 13186.538471153559, 11225.270357426823, 11383.317000979967, 11415.25800822952, 11446.942765286203, 11620.244684095507, 9242.537873106317, 9495.43027418357, 9662.08210220028, 10989.670309890716, 11424.0, 4017.4576432181866, 4065.349544072964, 4080.9387859182134, 4158.9376159357635, 4162.687798415101, 74017.62991185063, 75311.62344188245, 79230.60384698097, 79829.60085199594, 80558.0, 9496.43021418717, 9529.571169297398, 9535.90464095364, 9566.760830979281, 9605.711828645151, 17110.82889171117, 19749.40751777449, 20402.28591999273, 20527.38417847467, 20542.589148216954, 7357.52176108562, 7429.405647548183, 7445.255474452511, 7536.807607319292, 7538.623068846536, 3934.724569280123, 4048.291548978917, 4094.242565125461, 4128.02991297045, 4152.231837110145, 3620.185458271868, 3681.8711345102793, 3692.02161427222, 3693.593704692482, 3700.2599480104086, 612.1063247658379, 668.7926742709775, 670.5574320948151, 672.653583404547, 672.9528932974742, 493.3512431153094, 591.7899145803264, 594.8750762339871, 604.1421181921681, 613.8925688004581, 8238.299744521782, 8282.71010514629, 8300.70947516834, 8373.790655233668, 8487.960902733621, 590.8818236352661, 596.4482853360624, 613.1722175063628, 615.7290792051573, 628.2868943748878, 3371.983140084284, 3733.4773131761576, 3830.0999265172663, 3879.515060617423, 4002.3396139637216, 87097.56451217704, 88717.0, 89006.77483062976, 89084.10915890888, 89503.55248223717, 76914.61542692363, 77498.22501774911, 78878.21121788824, 79703.20296797073, 80411.0, 68415.31584684188, 69715.9085227444, 70076.29923700902, 70219.0, 70220.59558808897, 7692.536573270519, 7700.152983171959, 7742.961285193538, 7752.689892404241, 7869.960650196713, 35567.0, 48523.0, 49315.26027109524, 49589.50410495849, 49647.751761240965, 49174.50825491699, 62623.37376626176, 63713.0, 64839.0, 64998.0, 8198.877016844634, 9757.365771224848, 10054.396736195851, 10113.039261270167, 10161.644342447978, 10476.580936762444, 10604.151667866707, 10657.25399222047, 10873.07578855806, 10886.183536234656, 10223.591056357662, 10265.0, 10369.0, 10436.478176091317, 11062.889371106186, 9954.45250511229, 10132.240081993732, 10217.386956782617, 10517.894821051692, 10733.570657173626, 10115.494225288854, 10192.643257485952, 10207.795844083224, 10325.9483702584, 10398.324108932895, 8624.827503450022, 8627.95686021591, 8844.071372505794, 8917.42036767608, 9187.77030574241, 8866.601002954754, 8966.417182883091, 9049.230815380502, 9085.591148398205, 9169.174774270348], "value": 6691.208301002213, "unit": "score"}], "name": "dromaeo_css", "value": 7369.220777883414, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 120.0, 109.0, 116.0, 119.0, 118.0, 106.0, 115.0, 118.0, 116.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 133.0, 147.0, 171.0, 139.0, 168.0, 166.0, 149.0, 164.0, 121.0], "value": 154.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [217.0, 183.0, 262.0, 277.0, 208.0, 200.0, 196.0, 200.0, 198.0, 196.0], "value": 213.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 86.0, 97.0, 135.0, 64.0, 80.0, 81.0, 89.0, 149.0, 56.0], "value": 90.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 102.0, 155.0, 113.0, 88.0, 82.0, 113.0, 80.0, 81.0, 118.0], "value": 101.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 101.0, 134.0, 90.0, 92.0, 87.0, 92.0, 89.0, 92.0, 88.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 102.0, 98.0, 93.0, 143.0, 98.0, 118.0, 149.0, 117.0, 97.0], "value": 111.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 96.0, 91.0, 94.0, 90.0, 89.0, 90.0, 89.0, 91.0, 91.0], "value": 91.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 65.0, 64.0, 65.0, 67.0, 65.0, 66.0, 80.0, 63.0, 60.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 67.0, 77.0, 76.0, 74.0, 67.0, 69.0, 69.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 82.0, 82.0, 81.0, 80.0, 83.0, 82.0, 85.0, 85.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 129.0, 128.0, 137.0, 151.0, 136.0, 137.0, 128.0, 138.0, 163.0], "value": 137.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 217.0, 207.0, 173.0, 165.0, 162.0, 164.0, 169.0, 165.0, 167.0], "value": 176.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 59.0, 66.0, 68.0, 92.0, 94.0, 94.0, 68.0, 69.0, 73.0], "value": 77.0, "unit": "score"}], "name": "kraken", "value": 1580.8000000000002, "alertThreshold": 2.0}]} 03:16:24 INFO - Return code: 0 03:16:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:16:24 ERROR - # TBPL SUCCESS # 03:16:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:16:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:16:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:16:24 INFO - 2016-05-22 03:16:24.162 screenresolution[1685:10674] starting screenresolution argv=screenresolution get 03:16:24 INFO - 2016-05-22 03:16:24.165 screenresolution[1685:10674] Display 0: 1600x1200x32@60 03:16:24 INFO - 2016-05-22 03:16:24.171 screenresolution[1686:10677] starting screenresolution argv=screenresolution list 03:16:24 INFO - Available Modes on Display 0 03:16:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:16:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:16:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:16:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:16:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:16:24 INFO - Intel Iris: 03:16:24 INFO - Chipset Model: Intel Iris 03:16:24 INFO - Type: GPU 03:16:24 INFO - Bus: Built-In 03:16:24 INFO - VRAM (Dynamic, Max): 1536 MB 03:16:24 INFO - Vendor: Intel (0x8086) 03:16:24 INFO - Device ID: 0x0a2e 03:16:24 INFO - Revision ID: 0x0009 03:16:24 INFO - Displays: 03:16:24 INFO - Display: 03:16:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:16:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:16:24 INFO - Display Serial Number: 1600x1200 60 03:16:24 INFO - Main Display: Yes 03:16:24 INFO - Mirror: Off 03:16:24 INFO - Online: Yes 03:16:24 INFO - Rotation: Supported 03:16:24 INFO - Return code: 0 03:16:24 INFO - Running post-action listener: _resource_record_post_action 03:16:24 INFO - Running post-run listener: _resource_record_post_run 03:16:25 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 32853504; Write bytes: 655673344; Read time: 326; Write time: 1999 03:16:25 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 348914176; Write bytes: 346093568; Read time: 27473; Write time: 679 03:16:25 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 31059456; Write bytes: 298786816; Read time: 306; Write time: 1286 03:16:25 INFO - Running post-run listener: _upload_blobber_files 03:16:25 INFO - Blob upload gear active. 03:16:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:16:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:16:25 INFO - (blobuploader) - INFO - Open directory for files ... 03:16:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:16:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:16:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:16:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:16:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:16:27 INFO - (blobuploader) - INFO - Done attempting. 03:16:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:16:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:16:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:16:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:16:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:16:27 INFO - (blobuploader) - INFO - Done attempting. 03:16:27 INFO - (blobuploader) - INFO - Iteration through files over. 03:16:27 INFO - Return code: 0 03:16:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:16:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:16:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efb370f7d01165af1adb3eea217d6453077dbbb7b2fe2630753bd9f2779b304c6513b5635c1cac0435d3bac828aac3ffb81c67cf7a98a83e4d8dd0854e262779", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b163c086762d1209de7469584382fc99fe6ee3c3c53bd8880610970fccd868cae7ca657cf8c52476c324debbba4be329c61db3ede50c2aedef3b96e158fb476"} 03:16:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:16:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:16:27 INFO - Contents: 03:16:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efb370f7d01165af1adb3eea217d6453077dbbb7b2fe2630753bd9f2779b304c6513b5635c1cac0435d3bac828aac3ffb81c67cf7a98a83e4d8dd0854e262779", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b163c086762d1209de7469584382fc99fe6ee3c3c53bd8880610970fccd868cae7ca657cf8c52476c324debbba4be329c61db3ede50c2aedef3b96e158fb476"} 03:16:27 INFO - Running post-run listener: copy_logs_to_upload_dir 03:16:27 INFO - Copying logs to upload dir... 03:16:27 INFO - mkdir: /builds/slave/test/build/upload/logs 03:16:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.132573 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-22 03:16:28.021892) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-22 03:16:28.025449) ========= 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.MbdmuhvGqt/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.Px8udZFJLX/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efb370f7d01165af1adb3eea217d6453077dbbb7b2fe2630753bd9f2779b304c6513b5635c1cac0435d3bac828aac3ffb81c67cf7a98a83e4d8dd0854e262779", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b163c086762d1209de7469584382fc99fe6ee3c3c53bd8880610970fccd868cae7ca657cf8c52476c324debbba4be329c61db3ede50c2aedef3b96e158fb476"} build_url:https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011166 build_url: 'https://queue.taskcluster.net/v1/task/d4ILtt_8RtyKZ12ZC8-lYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/efb370f7d01165af1adb3eea217d6453077dbbb7b2fe2630753bd9f2779b304c6513b5635c1cac0435d3bac828aac3ffb81c67cf7a98a83e4d8dd0854e262779", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b163c086762d1209de7469584382fc99fe6ee3c3c53bd8880610970fccd868cae7ca657cf8c52476c324debbba4be329c61db3ede50c2aedef3b96e158fb476"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-22 03:16:28.054683) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 03:16:28.054989) ========= 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.MbdmuhvGqt/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.Px8udZFJLX/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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.005085 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 03:16:28.103526) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-22 03:16:28.103806) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-22 03:16:28.104104) ========= ========= Total master_lag: 0.16 =========