builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0302 starttime: 1464050353.63 results: success (0) buildid: 20160523164246 builduid: a700a399d11c4827ae9262abaf6f03b7 revision: c40d4d7f4bb2b10c82348b900978fd2c0c3576a7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 17:39:13.625311) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 17:39:13.625689) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 17:39:13.625980) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 17:39:13.670152) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 17:39:13.670450) ========= 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.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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-23 17:39:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-05-23 17:39:14 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.464783 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 17:39:14.155909) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 17:39:14.156189) ========= 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.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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.025570 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 17:39:14.204235) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 17:39:14.204752) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c40d4d7f4bb2b10c82348b900978fd2c0c3576a7 --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 c40d4d7f4bb2b10c82348b900978fd2c0c3576a7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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-23 17:39:14,276 truncating revision to first 12 chars 2016-05-23 17:39:14,276 Setting DEBUG logging. 2016-05-23 17:39:14,276 attempt 1/10 2016-05-23 17:39:14,276 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c40d4d7f4bb2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 17:39:15,318 unpacking tar archive at: mozilla-aurora-c40d4d7f4bb2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.297310 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 17:39:15.520470) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 17:39:15.520799) ========= script_repo_revision: c40d4d7f4bb2b10c82348b900978fd2c0c3576a7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 17:39:15.521203) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 17:39:15.521480) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 17:39:15.535928) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2016-05-23 17:39:15.536210) ========= /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.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 17:39:15 INFO - MultiFileLogger online at 20160523 17:39:15 in /builds/slave/test 17:39:15 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 17:39:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:39:15 INFO - {'append_to_log': False, 17:39:15 INFO - 'base_work_dir': '/builds/slave/test', 17:39:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:39:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:39:15 INFO - 'branch': 'Mozilla-Aurora', 17:39:15 INFO - 'buildbot_json_path': 'buildprops.json', 17:39:15 INFO - 'config_files': ('talos/mac_config.py',), 17:39:15 INFO - 'default_actions': ('clobber', 17:39:15 INFO - 'read-buildbot-config', 17:39:15 INFO - 'download-and-extract', 17:39:15 INFO - 'populate-webroot', 17:39:15 INFO - 'create-virtualenv', 17:39:15 INFO - 'install', 17:39:15 INFO - 'run-tests'), 17:39:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:39:15 INFO - 'download_minidump_stackwalk': True, 17:39:15 INFO - 'download_symbols': 'ondemand', 17:39:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:39:15 INFO - 'tooltool.py': '/tools/tooltool.py', 17:39:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:39:15 INFO - '/tools/misc-python/virtualenv.py')}, 17:39:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:39:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:39:15 INFO - 'installer_path': 'installer.exe', 17:39:15 INFO - 'log_level': 'info', 17:39:15 INFO - 'log_name': 'talos', 17:39:15 INFO - 'log_to_console': True, 17:39:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:39:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:39:15 INFO - 'opt_config_files': (), 17:39:15 INFO - 'pip_index': False, 17:39:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:39:15 INFO - 'cmd': ('bash', 17:39:15 INFO - '-c', 17:39:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:39:15 INFO - 'enabled': True, 17:39:15 INFO - 'halt_on_failure': False, 17:39:15 INFO - 'name': 'check_screen_resolution'},), 17:39:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:39:15 INFO - 'cmd': ('bash', 17:39:15 INFO - '-c', 17:39:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:39:15 INFO - 'enabled': True, 17:39:15 INFO - 'halt_on_failure': False, 17:39:15 INFO - 'name': 'check_screen_resolution'},), 17:39:15 INFO - 'run_cmd_checks_enabled': True, 17:39:15 INFO - 'sps_profile': False, 17:39:15 INFO - 'sps_profile_interval': 0, 17:39:15 INFO - 'suite': 'dromaeojs-e10s', 17:39:15 INFO - 'system_bits': '32', 17:39:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:39:15 INFO - 'title': 't-yosemite-r7-0302', 17:39:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:39:15 INFO - 'use_talos_json': True, 17:39:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:39:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:39:15 INFO - 'work_dir': 'build'} 17:39:15 INFO - ##### 17:39:15 INFO - ##### Running clobber step. 17:39:15 INFO - ##### 17:39:15 INFO - Running pre-action listener: _resource_record_pre_action 17:39:15 INFO - Running main action method: clobber 17:39:15 INFO - rmtree: /builds/slave/test/build 17:39:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:39:17 INFO - Running post-action listener: _resource_record_post_action 17:39:17 INFO - ##### 17:39:17 INFO - ##### Running read-buildbot-config step. 17:39:17 INFO - ##### 17:39:17 INFO - Running pre-action listener: _resource_record_pre_action 17:39:17 INFO - Running main action method: read_buildbot_config 17:39:17 INFO - Using buildbot properties: 17:39:17 INFO - { 17:39:17 INFO - "project": "", 17:39:17 INFO - "product": "firefox", 17:39:17 INFO - "script_repo_revision": "production", 17:39:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 17:39:17 INFO - "repository": "", 17:39:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 17:39:17 INFO - "buildid": "20160523164246", 17:39:17 INFO - "pgo_build": "False", 17:39:17 INFO - "basedir": "/builds/slave/test", 17:39:17 INFO - "buildnumber": 8, 17:39:17 INFO - "slavename": "t-yosemite-r7-0302", 17:39:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:39:17 INFO - "platform": "yosemite_r7", 17:39:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 17:39:17 INFO - "branch": "mozilla-aurora", 17:39:17 INFO - "repo_path": "releases/mozilla-aurora", 17:39:17 INFO - "revision": "c40d4d7f4bb2b10c82348b900978fd2c0c3576a7", 17:39:17 INFO - "stage_platform": "macosx64", 17:39:17 INFO - "builduid": "a700a399d11c4827ae9262abaf6f03b7", 17:39:17 INFO - "slavebuilddir": "test" 17:39:17 INFO - } 17:39:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 17:39:17 INFO - Running post-action listener: _resource_record_post_action 17:39:17 INFO - ##### 17:39:17 INFO - ##### Running download-and-extract step. 17:39:17 INFO - ##### 17:39:17 INFO - Running pre-action listener: _resource_record_pre_action 17:39:17 INFO - Running main action method: download_and_extract 17:39:17 INFO - mkdir: /builds/slave/test/build/tests 17:39:17 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')]} 17:39:17 INFO - https://queue.taskcluster.net/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:39:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 17:39:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 17:39:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/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 17:39:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/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 17:39:34 INFO - Downloaded 1199 bytes. 17:39:34 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 17:39:34 INFO - Using the following test package requirements: 17:39:34 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 17:39:34 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:39:34 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 17:39:34 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:39:34 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 17:39:34 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:39:34 INFO - u'jsshell-mac.zip'], 17:39:34 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:39:34 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 17:39:34 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 17:39:34 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:39:34 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 17:39:34 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:39:34 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 17:39:34 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:39:34 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 17:39:34 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:39:34 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 17:39:34 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 17:39:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:39:34 INFO - https://queue.taskcluster.net/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:39:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:39:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:39:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/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 17:39:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/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 17:39:43 INFO - Downloaded 18102389 bytes. 17:39:43 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'] 17:39:43 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 17:39:46 INFO - Return code: 0 17:39:46 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 17:39:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:39:46 INFO - https://queue.taskcluster.net/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:39:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:39:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:39:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/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 17:39:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/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 17:39:52 INFO - Downloaded 18102389 bytes. 17:39:52 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'] 17:39:52 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 17:39:55 INFO - Return code: 0 17:39: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')]} 17:39:55 INFO - https://queue.taskcluster.net/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:39:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 17:39:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 17:39:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/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 17:39:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/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 17:39:57 INFO - Downloaded 10840145 bytes. 17:39:57 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'] 17:39:57 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 17:39:58 INFO - Return code: 0 17:39:58 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')]} 17:39:58 INFO - https://queue.taskcluster.net/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 17:39:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:39:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:39:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 17:39:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/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 17:40:01 INFO - Downloaded 114114386 bytes. 17:40:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:40:01 INFO - mkdir: /builds/slave/test/properties 17:40:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:40:01 INFO - Writing to file /builds/slave/test/properties/build_url 17:40:01 INFO - Contents: 17:40:01 INFO - build_url:https://queue.taskcluster.net/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:40:02 INFO - Running post-action listener: _resource_record_post_action 17:40:02 INFO - Running post-action listener: set_extra_try_arguments 17:40:02 INFO - ##### 17:40:02 INFO - ##### Running populate-webroot step. 17:40:02 INFO - ##### 17:40:02 INFO - Running pre-action listener: _resource_record_pre_action 17:40:02 INFO - Running main action method: populate_webroot 17:40:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:40:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:40:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:40:02 INFO - '--tppagecycles', 17:40:02 INFO - '7'], 17:40:02 INFO - 'tests': ['tsvgm']}}, 17:40:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:40:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:40:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:40:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:40:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:40:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:40:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:02 INFO - 'talos_options': ['--disable-e10s'], 17:40:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:40:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:40:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:02 INFO - 'talos_options': ['--disable-e10s'], 17:40:02 INFO - 'tests': ['damp', 'tps']}, 17:40:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:02 INFO - 'tests': ['damp', 'tps']}, 17:40:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:40:02 INFO - 'tests': ['dromaeo_dom']}, 17:40:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:40:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:40:02 INFO - 'tests': ['a11yr', 17:40:02 INFO - 'ts_paint', 17:40:02 INFO - 'tpaint', 17:40:02 INFO - 'sessionrestore', 17:40:02 INFO - 'sessionrestore_no_auto_restore', 17:40:02 INFO - 'tabpaint']}, 17:40:02 INFO - 'other-e10s': {'tests': ['a11yr', 17:40:02 INFO - 'ts_paint', 17:40:02 INFO - 'tpaint', 17:40:02 INFO - 'sessionrestore', 17:40:02 INFO - 'sessionrestore_no_auto_restore', 17:40:02 INFO - 'tabpaint']}, 17:40:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:40:02 INFO - 'ts_paint', 17:40:02 INFO - 'tpaint', 17:40:02 INFO - 'sessionrestore', 17:40:02 INFO - 'sessionrestore_no_auto_restore', 17:40:02 INFO - 'tabpaint']}, 17:40:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:40:02 INFO - 'ts_paint', 17:40:02 INFO - 'tpaint', 17:40:02 INFO - 'sessionrestore', 17:40:02 INFO - 'sessionrestore_no_auto_restore', 17:40:02 INFO - 'tabpaint']}, 17:40:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:40:02 INFO - 'tests': ['a11yr', 17:40:02 INFO - 'ts_paint', 17:40:02 INFO - 'tpaint', 17:40:02 INFO - 'sessionrestore', 17:40:02 INFO - 'sessionrestore_no_auto_restore', 17:40:02 INFO - 'tabpaint']}, 17:40:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:40:02 INFO - 'tests': ['a11yr', 17:40:02 INFO - 'ts_paint', 17:40:02 INFO - 'tpaint', 17:40:02 INFO - 'sessionrestore', 17:40:02 INFO - 'sessionrestore_no_auto_restore', 17:40:02 INFO - 'tabpaint']}, 17:40:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:40:02 INFO - 'tests': ['tsvgx', 17:40:02 INFO - 'tsvgr_opacity', 17:40:02 INFO - 'tart', 17:40:02 INFO - 'tscrollx', 17:40:02 INFO - 'cart']}, 17:40:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:40:02 INFO - 'tsvgr_opacity', 17:40:02 INFO - 'tart', 17:40:02 INFO - 'tscrollx', 17:40:02 INFO - 'cart']}, 17:40:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:02 INFO - 'talos_options': ['--disable-e10s'], 17:40:02 INFO - 'tests': ['tp5o']}, 17:40:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:02 INFO - 'tests': ['tp5o']}, 17:40:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:02 INFO - 'talos_options': ['--disable-e10s', 17:40:02 INFO - '--xperf_path', 17:40:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:40:02 INFO - 'tests': ['tp5n']}, 17:40:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:02 INFO - 'talos_options': ['--xperf_path', 17:40:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:40:02 INFO - 'tests': ['tp5n']}}, 17:40:02 INFO - 'talos.zip': {'path': '', 17:40:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:40:02 INFO - Running post-action listener: _resource_record_post_action 17:40:02 INFO - ##### 17:40:02 INFO - ##### Running create-virtualenv step. 17:40:02 INFO - ##### 17:40:02 INFO - Running pre-action listener: _resource_record_pre_action 17:40:02 INFO - Running main action method: create_virtualenv 17:40:02 INFO - Creating virtualenv /builds/slave/test/build/venv 17:40:02 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 17:40:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:40:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:40:02 INFO - Using real prefix '/tools/python27' 17:40:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:40:03 INFO - Installing distribute.............................................................................................................................................................................................done. 17:40:06 INFO - Installing pip.................done. 17:40:06 INFO - Return code: 0 17:40:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:06 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 0x1046d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328d59500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104570e00>, '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 0x1043d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10456bb20>, '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.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 17:40:06 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 17:40:06 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 17:40:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m4vbcyMdlb/Render', 17:40:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:06 INFO - 'HOME': '/Users/cltbld', 17:40:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:06 INFO - 'LOGNAME': 'cltbld', 17:40:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:06 INFO - 'MOZ_NO_REMOTE': '1', 17:40:06 INFO - 'NO_EM_RESTART': '1', 17:40:06 INFO - 'PAGER': '/bin/cat', 17:40:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:06 INFO - 'PWD': '/builds/slave/test', 17:40:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:06 INFO - 'SHELL': '/bin/bash', 17:40:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIsZ5k2mrj/Listeners', 17:40:06 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 17:40:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:06 INFO - 'USER': 'cltbld', 17:40:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:06 INFO - 'XPC_FLAGS': '0x0', 17:40:06 INFO - 'XPC_SERVICE_NAME': '0', 17:40:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:06 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 17:40:06 INFO - Cleaning up... 17:40:06 INFO - Return code: 0 17:40:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:06 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 0x1046d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328d59500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104570e00>, '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 0x1043d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10456bb20>, '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.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 17:40:06 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 17:40:06 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 17:40:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m4vbcyMdlb/Render', 17:40:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:06 INFO - 'HOME': '/Users/cltbld', 17:40:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:06 INFO - 'LOGNAME': 'cltbld', 17:40:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:06 INFO - 'MOZ_NO_REMOTE': '1', 17:40:06 INFO - 'NO_EM_RESTART': '1', 17:40:06 INFO - 'PAGER': '/bin/cat', 17:40:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:06 INFO - 'PWD': '/builds/slave/test', 17:40:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:06 INFO - 'SHELL': '/bin/bash', 17:40:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIsZ5k2mrj/Listeners', 17:40:06 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 17:40:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:06 INFO - 'USER': 'cltbld', 17:40:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:06 INFO - 'XPC_FLAGS': '0x0', 17:40:06 INFO - 'XPC_SERVICE_NAME': '0', 17:40:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:06 INFO - Downloading/unpacking psutil>=0.7.1 17:40: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 17:40: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 17:40: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 17:40: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 17:40: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 17:40: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 17:40:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:40:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:40:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:40:10 INFO - Installing collected packages: psutil 17:40:10 INFO - Running setup.py install for psutil 17:40:10 INFO - building 'psutil._psutil_osx' extension 17:40:10 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 17:40:10 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 17:40:10 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 17:40:10 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 17:40:10 INFO - building 'psutil._psutil_posix' extension 17:40:10 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 17:40:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:40:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:40:10 INFO - ^ 17:40:11 INFO - 1 warning generated. 17:40:11 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 17:40:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:40:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:40:11 INFO - Successfully installed psutil 17:40:11 INFO - Cleaning up... 17:40:11 INFO - Return code: 0 17:40:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:40:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1046d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328d59500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104570e00>, '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 0x1043d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10456bb20>, '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.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 17:40:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 17:40:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 17:40:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m4vbcyMdlb/Render', 17:40:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:11 INFO - 'HOME': '/Users/cltbld', 17:40:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:11 INFO - 'LOGNAME': 'cltbld', 17:40:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:11 INFO - 'MOZ_NO_REMOTE': '1', 17:40:11 INFO - 'NO_EM_RESTART': '1', 17:40:11 INFO - 'PAGER': '/bin/cat', 17:40:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:11 INFO - 'PWD': '/builds/slave/test', 17:40:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:11 INFO - 'SHELL': '/bin/bash', 17:40:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIsZ5k2mrj/Listeners', 17:40:11 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 17:40:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:11 INFO - 'USER': 'cltbld', 17:40:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:11 INFO - 'XPC_FLAGS': '0x0', 17:40:11 INFO - 'XPC_SERVICE_NAME': '0', 17:40:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:40:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:40:13 INFO - Installing collected packages: mozsystemmonitor 17:40:13 INFO - Running setup.py install for mozsystemmonitor 17:40:14 INFO - Successfully installed mozsystemmonitor 17:40:14 INFO - Cleaning up... 17:40:14 INFO - Return code: 0 17:40:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:40:14 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')]} 17:40:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:14 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')]} 17:40:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:14 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 0x1046d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328d59500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104570e00>, '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 0x1043d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10456bb20>, '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.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 17:40:14 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 17:40:14 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 17:40:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m4vbcyMdlb/Render', 17:40:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:14 INFO - 'HOME': '/Users/cltbld', 17:40:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:14 INFO - 'LOGNAME': 'cltbld', 17:40:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:14 INFO - 'MOZ_NO_REMOTE': '1', 17:40:14 INFO - 'NO_EM_RESTART': '1', 17:40:14 INFO - 'PAGER': '/bin/cat', 17:40:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:14 INFO - 'PWD': '/builds/slave/test', 17:40:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:14 INFO - 'SHELL': '/bin/bash', 17:40:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIsZ5k2mrj/Listeners', 17:40:14 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 17:40:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:14 INFO - 'USER': 'cltbld', 17:40:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:14 INFO - 'XPC_FLAGS': '0x0', 17:40:14 INFO - 'XPC_SERVICE_NAME': '0', 17:40:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:14 INFO - Downloading/unpacking blobuploader==1.2.4 17:40:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:16 INFO - Downloading blobuploader-1.2.4.tar.gz 17:40:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:40:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:40: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 17:40: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 17:40: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 17:40: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 17:40: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 17:40: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 17:40:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:40:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:40: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 17:40: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 17:40: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 17:40: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 17:40: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 17:40: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 17:40:17 INFO - Downloading docopt-0.6.1.tar.gz 17:40:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:40:18 INFO - Installing collected packages: blobuploader, requests, docopt 17:40:18 INFO - Running setup.py install for blobuploader 17:40:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:40:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:40:18 INFO - Running setup.py install for requests 17:40:18 INFO - Running setup.py install for docopt 17:40:18 INFO - Successfully installed blobuploader requests docopt 17:40:18 INFO - Cleaning up... 17:40:18 INFO - Return code: 0 17:40:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:19 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')]} 17:40:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:19 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 0x1046d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328d59500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104570e00>, '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 0x1043d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10456bb20>, '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.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 17:40:19 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 17:40:19 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 17:40:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m4vbcyMdlb/Render', 17:40:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:19 INFO - 'HOME': '/Users/cltbld', 17:40:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:19 INFO - 'LOGNAME': 'cltbld', 17:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:19 INFO - 'MOZ_NO_REMOTE': '1', 17:40:19 INFO - 'NO_EM_RESTART': '1', 17:40:19 INFO - 'PAGER': '/bin/cat', 17:40:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:19 INFO - 'PWD': '/builds/slave/test', 17:40:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:19 INFO - 'SHELL': '/bin/bash', 17:40:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIsZ5k2mrj/Listeners', 17:40:19 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 17:40:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:19 INFO - 'USER': 'cltbld', 17:40:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:19 INFO - 'XPC_FLAGS': '0x0', 17:40:19 INFO - 'XPC_SERVICE_NAME': '0', 17:40:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:40:19 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-VAeH4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:40:19 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-MNOl8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:40:19 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-trne41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:40:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:40:20 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-Yk0IeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:40:20 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-I1KC74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:40:20 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-aHYJNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:40:20 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-qrkrFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:40:20 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-Jug4tS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:40:20 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-mo9a7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:40:20 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-zPh1i3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:40:20 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-H_H9Ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:40:20 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-xrJlhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:40:20 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-uRi8X9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:40:21 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-S0KLT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:40:21 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-Jlq8Rr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:40:21 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-fqfdEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:40:21 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-m0VJEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:40:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:40:21 INFO - Running setup.py install for manifestparser 17:40:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:40:21 INFO - Running setup.py install for mozcrash 17:40:21 INFO - Running setup.py install for mozdebug 17:40:21 INFO - Running setup.py install for mozdevice 17:40:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:40:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:40:22 INFO - Running setup.py install for mozfile 17:40:22 INFO - Running setup.py install for mozhttpd 17:40:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:40:22 INFO - Running setup.py install for mozinfo 17:40:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:40:22 INFO - Running setup.py install for mozInstall 17:40:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:40:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:40:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:40:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:40:22 INFO - Running setup.py install for mozleak 17:40:22 INFO - Running setup.py install for mozlog 17:40:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:40:22 INFO - Running setup.py install for moznetwork 17:40:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:40:23 INFO - Running setup.py install for mozprocess 17:40:23 INFO - Running setup.py install for mozprofile 17:40:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:40:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:40:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:40:23 INFO - Running setup.py install for mozrunner 17:40:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:40:23 INFO - Running setup.py install for mozscreenshot 17:40:23 INFO - Running setup.py install for moztest 17:40:23 INFO - Running setup.py install for mozversion 17:40:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:40:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:40:24 INFO - Cleaning up... 17:40:24 INFO - Return code: 0 17:40:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:40: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')]} 17:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40: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')]} 17:40:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:24 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 0x1046d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328d59500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104570e00>, '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 0x1043d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10456bb20>, '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.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 17:40:24 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 17:40:24 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 17:40:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m4vbcyMdlb/Render', 17:40:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:24 INFO - 'HOME': '/Users/cltbld', 17:40:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:24 INFO - 'LOGNAME': 'cltbld', 17:40:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:24 INFO - 'MOZ_NO_REMOTE': '1', 17:40:24 INFO - 'NO_EM_RESTART': '1', 17:40:24 INFO - 'PAGER': '/bin/cat', 17:40:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:24 INFO - 'PWD': '/builds/slave/test', 17:40:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:24 INFO - 'SHELL': '/bin/bash', 17:40:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIsZ5k2mrj/Listeners', 17:40:24 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 17:40:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:24 INFO - 'USER': 'cltbld', 17:40:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:24 INFO - 'XPC_FLAGS': '0x0', 17:40:24 INFO - 'XPC_SERVICE_NAME': '0', 17:40:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:40:24 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-1OD8pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:40:24 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)) 17:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:40:24 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-LdBdkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:40:24 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)) 17:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:40:24 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-W1Q9T4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:40:24 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)) 17:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:40:24 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-qW1qlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:40:24 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)) 17:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:40:24 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-RAVQdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:40:24 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)) 17:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:40:24 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-pK8qcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:40:24 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)) 17:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:40:24 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-VxFiCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:40:25 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)) 17:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:40:25 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-akfifm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:40:25 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)) 17:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:40:25 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-NMMS16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:40:25 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)) 17:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:40:25 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-VGfhif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:40:25 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)) 17:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:40:25 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-yDcHQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:40:25 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)) 17:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:40:25 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-o4eZV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:40:25 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)) 17:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:40:25 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-7De2eS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:40:25 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)) 17:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:40:25 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-OVJNq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:40:25 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)) 17:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:40:25 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-_VHAj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:40:25 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)) 17:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:40:25 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-kUT6cX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:40:25 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)) 17:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:40:25 INFO - Running setup.py (path:/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/pip-tyBy3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:40:26 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)) 17:40:26 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)) 17:40:26 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)) 17:40:26 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)) 17:40:26 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)) 17:40:26 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)) 17:40:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:40:26 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 17:40:26 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 17:40:26 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 17:40:26 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 17:40:26 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 17:40:26 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 17:40:28 INFO - Downloading blessings-1.6.tar.gz 17:40:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:40:29 INFO - Installing collected packages: blessings 17:40:29 INFO - Running setup.py install for blessings 17:40:29 INFO - Successfully installed blessings 17:40:29 INFO - Cleaning up... 17:40:29 INFO - Return code: 0 17:40:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:40:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:40:29 INFO - Reading from file tmpfile_stdout 17:40:29 INFO - Current package versions: 17:40:29 INFO - blessings == 1.6 17:40:29 INFO - blobuploader == 1.2.4 17:40:29 INFO - docopt == 0.6.1 17:40:29 INFO - manifestparser == 1.1 17:40:29 INFO - mozInstall == 1.12 17:40:29 INFO - mozcrash == 0.17 17:40:29 INFO - mozdebug == 0.1 17:40:29 INFO - mozdevice == 0.48 17:40:29 INFO - mozfile == 1.2 17:40:29 INFO - mozhttpd == 0.7 17:40:29 INFO - mozinfo == 0.9 17:40:29 INFO - mozleak == 0.1 17:40:29 INFO - mozlog == 3.1 17:40:29 INFO - moznetwork == 0.27 17:40:29 INFO - mozprocess == 0.22 17:40:29 INFO - mozprofile == 0.28 17:40:29 INFO - mozrunner == 6.11 17:40:29 INFO - mozscreenshot == 0.1 17:40:29 INFO - mozsystemmonitor == 0.0 17:40:29 INFO - moztest == 0.7 17:40:29 INFO - mozversion == 1.4 17:40:29 INFO - psutil == 3.1.1 17:40:29 INFO - requests == 1.2.3 17:40:29 INFO - wsgiref == 0.1.2 17:40:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:40:29 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')]} 17:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:29 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')]} 17:40:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:29 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 0x1046d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328d59500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104570e00>, '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 0x1043d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10456bb20>, '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.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 17:40:29 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 17:40:29 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 17:40:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m4vbcyMdlb/Render', 17:40:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:29 INFO - 'HOME': '/Users/cltbld', 17:40:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:29 INFO - 'LOGNAME': 'cltbld', 17:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:29 INFO - 'MOZ_NO_REMOTE': '1', 17:40:29 INFO - 'NO_EM_RESTART': '1', 17:40:29 INFO - 'PAGER': '/bin/cat', 17:40:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:29 INFO - 'PWD': '/builds/slave/test', 17:40:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:29 INFO - 'SHELL': '/bin/bash', 17:40:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIsZ5k2mrj/Listeners', 17:40:29 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 17:40:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:29 INFO - 'USER': 'cltbld', 17:40:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:29 INFO - 'XPC_FLAGS': '0x0', 17:40:29 INFO - 'XPC_SERVICE_NAME': '0', 17:40:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:29 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)) 17:40:29 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)) 17:40:29 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)) 17:40:29 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)) 17:40:29 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)) 17:40:29 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)) 17:40:29 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)) 17:40:29 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)) 17:40:29 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)) 17:40:29 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)) 17:40:29 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)) 17:40:29 INFO - Cleaning up... 17:40:29 INFO - Return code: 0 17:40:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:40:29 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')]} 17:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:29 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')]} 17:40:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:29 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 0x1046d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328d59500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104570e00>, '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 0x1043d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10456bb20>, '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.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 17:40:29 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 17:40:29 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 17:40:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m4vbcyMdlb/Render', 17:40:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:29 INFO - 'HOME': '/Users/cltbld', 17:40:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:29 INFO - 'LOGNAME': 'cltbld', 17:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:29 INFO - 'MOZ_NO_REMOTE': '1', 17:40:29 INFO - 'NO_EM_RESTART': '1', 17:40:29 INFO - 'PAGER': '/bin/cat', 17:40:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:29 INFO - 'PWD': '/builds/slave/test', 17:40:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:29 INFO - 'SHELL': '/bin/bash', 17:40:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIsZ5k2mrj/Listeners', 17:40:29 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 17:40:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:29 INFO - 'USER': 'cltbld', 17:40:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:29 INFO - 'XPC_FLAGS': '0x0', 17:40:29 INFO - 'XPC_SERVICE_NAME': '0', 17:40:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:30 INFO - Downloading/unpacking jsonschema 17:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:40:32 INFO - Downloading/unpacking functools32 (from jsonschema) 17:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:32 INFO - Downloading functools32-3.2.3-2.tar.gz 17:40:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:40:32 INFO - warning: no files found matching '*.txt' 17:40:32 INFO - no previously-included directories found matching 'build' 17:40:32 INFO - no previously-included directories found matching 'dist' 17:40:32 INFO - no previously-included directories found matching '.git*' 17:40:32 INFO - Installing collected packages: jsonschema, functools32 17:40:32 INFO - Running setup.py install for functools32 17:40:33 INFO - warning: no files found matching '*.txt' 17:40:33 INFO - no previously-included directories found matching 'build' 17:40:33 INFO - no previously-included directories found matching 'dist' 17:40:33 INFO - no previously-included directories found matching '.git*' 17:40:33 INFO - Successfully installed jsonschema functools32 17:40:33 INFO - Cleaning up... 17:40:33 INFO - Return code: 0 17:40:33 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:40:33 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')]} 17:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:33 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')]} 17:40:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:33 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 0x1046d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc328d59500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104570e00>, '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 0x1043d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10456bb20>, '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.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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 17:40:33 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 17:40:33 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 17:40:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m4vbcyMdlb/Render', 17:40:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:33 INFO - 'HOME': '/Users/cltbld', 17:40:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:33 INFO - 'LOGNAME': 'cltbld', 17:40:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:33 INFO - 'MOZ_NO_REMOTE': '1', 17:40:33 INFO - 'NO_EM_RESTART': '1', 17:40:33 INFO - 'PAGER': '/bin/cat', 17:40:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:33 INFO - 'PWD': '/builds/slave/test', 17:40:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:33 INFO - 'SHELL': '/bin/bash', 17:40:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIsZ5k2mrj/Listeners', 17:40:33 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 17:40:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:33 INFO - 'USER': 'cltbld', 17:40:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:33 INFO - 'XPC_FLAGS': '0x0', 17:40:33 INFO - 'XPC_SERVICE_NAME': '0', 17:40:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:33 INFO - Downloading/unpacking flake8 17:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:40:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:40:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:40:36 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 17:40:36 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 17:40:36 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 17:40:36 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 17:40:36 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 17:40:36 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 17:40:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:40:36 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 17:40:36 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 17:40:36 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 17:40:36 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 17:40:36 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 17:40:36 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 17:40:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:40:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:40:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:40:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:40:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:40:37 INFO - no previously-included directories found matching 'docs/_build' 17:40:37 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:40:37 INFO - Running setup.py install for pep8 17:40:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:40:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:40:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:40:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:40:37 INFO - no previously-included directories found matching 'docs/_build' 17:40:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:40:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:40:37 INFO - Cleaning up... 17:40:37 INFO - Return code: 0 17:40:37 INFO - Running post-action listener: _resource_record_post_action 17:40:37 INFO - Running post-action listener: _start_resource_monitoring 17:40:37 INFO - Starting resource monitoring. 17:40:37 INFO - ##### 17:40:37 INFO - ##### Running install step. 17:40:37 INFO - ##### 17:40:37 INFO - Running pre-action listener: _resource_record_pre_action 17:40:37 INFO - Running main action method: install 17:40:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:40:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:40:37 INFO - Reading from file tmpfile_stdout 17:40:37 INFO - Detecting whether we're running mozinstall >=1.0... 17:40:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:40:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:40:37 INFO - Reading from file tmpfile_stdout 17:40:37 INFO - Output received: 17:40:37 INFO - Usage: mozinstall [options] installer 17:40:37 INFO - Options: 17:40:37 INFO - -h, --help show this help message and exit 17:40:37 INFO - -d DEST, --destination=DEST 17:40:37 INFO - Directory to install application into. [default: 17:40:37 INFO - "/builds/slave/test"] 17:40:37 INFO - --app=APP Application being installed. [default: firefox] 17:40:37 INFO - mkdir: /builds/slave/test/build/application 17:40:37 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'] 17:40:37 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 17:41:09 INFO - Reading from file tmpfile_stdout 17:41:09 INFO - Output received: 17:41:09 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:41:09 INFO - Running post-action listener: _resource_record_post_action 17:41:09 INFO - ##### 17:41:09 INFO - ##### Running run-tests step. 17:41:09 INFO - ##### 17:41:09 INFO - Running pre-action listener: _resource_record_pre_action 17:41:09 INFO - Running main action method: run_tests 17:41:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:41:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:41:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:41:09 INFO - 2016-05-23 17:41:09.259 screenresolution[1723:8169] starting screenresolution argv=screenresolution get 17:41:09 INFO - 2016-05-23 17:41:09.261 screenresolution[1723:8169] Display 0: 1600x1200x32@60 17:41:09 INFO - 2016-05-23 17:41:09.267 screenresolution[1724:8171] starting screenresolution argv=screenresolution list 17:41:09 INFO - Available Modes on Display 0 17:41:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:41:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:41:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:41:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:41:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:41:09 INFO - Intel Iris: 17:41:09 INFO - Chipset Model: Intel Iris 17:41:09 INFO - Type: GPU 17:41:09 INFO - Bus: Built-In 17:41:09 INFO - VRAM (Dynamic, Max): 1536 MB 17:41:09 INFO - Vendor: Intel (0x8086) 17:41:09 INFO - Device ID: 0x0a2e 17:41:09 INFO - Revision ID: 0x0009 17:41:09 INFO - Displays: 17:41:09 INFO - Display: 17:41:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:41:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:41:09 INFO - Display Serial Number: 1600x1200 60 17:41:09 INFO - Main Display: Yes 17:41:09 INFO - Mirror: Off 17:41:09 INFO - Online: Yes 17:41:09 INFO - Rotation: Supported 17:41:09 INFO - Return code: 0 17:41:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:41:09 INFO - Python 2.7.3 17:41:09 INFO - Return code: 0 17:41:09 INFO - grabbing minidump binary from tooltool 17:41: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')]} 17:41:09 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 0x104570e00>, '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 0x1043d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10456bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:41:09 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 17:41:09 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 17:41:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:41:09 INFO - Return code: 0 17:41:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:41:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:41:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:41:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:41:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:41:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:41:10 INFO - Return code: 0 17:41:10 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/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0302', '--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 17:41:10 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/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0302 --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 17:41:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m4vbcyMdlb/Render', 17:41:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:41:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:41:10 INFO - 'HOME': '/Users/cltbld', 17:41:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:41:10 INFO - 'LOGNAME': 'cltbld', 17:41:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:41:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:41:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:10 INFO - 'MOZ_NO_REMOTE': '1', 17:41:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:41:10 INFO - 'NO_EM_RESTART': '1', 17:41:10 INFO - 'PAGER': '/bin/cat', 17:41:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:41:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:10 INFO - 'PWD': '/builds/slave/test', 17:41:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:41:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:41:10 INFO - 'SHELL': '/bin/bash', 17:41:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fIsZ5k2mrj/Listeners', 17:41:10 INFO - 'TMPDIR': '/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/', 17:41:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:41:10 INFO - 'USER': 'cltbld', 17:41:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:41:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:41:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:10 INFO - 'XPC_FLAGS': '0x0', 17:41:10 INFO - 'XPC_SERVICE_NAME': '0', 17:41:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:41:10 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/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0302', '--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 17:41:11 INFO - mozversion application_buildid: 20160523164246 17:41:11 INFO - mozversion application_changeset: c40d4d7f4bb2b10c82348b900978fd2c0c3576a7 17:41:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:41:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:41:11 INFO - mozversion application_name: Firefox 17:41:11 INFO - mozversion application_remotingname: firefox-dev 17:41:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:41:11 INFO - mozversion application_vendor: Mozilla 17:41:11 INFO - mozversion application_version: 48.0a2 17:41:11 INFO - mozversion platform_buildid: 20160523164246 17:41:11 INFO - mozversion platform_changeset: c40d4d7f4bb2b10c82348b900978fd2c0c3576a7 17:41:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:41:11 INFO - mozversion platform_version: 48.0a2 17:41:11 INFO - using testdate: 1464050471 17:41:11 INFO - actual date: 1464050471 17:41:11 INFO - starting webserver on 'localhost:49270' 17:41:11 INFO - SUITE-START | Running 2 tests 17:41:11 INFO - TEST-START | dromaeo_css 17:41:11 INFO - operating with platform_type : mac_ 17:41:11 INFO - Initialising browser for dromaeo_css test... 17:41:11 INFO - TEST-INFO | started process 1742 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmptnFtjQ/profile http://localhost:49270/getInfo.html) 17:41:13 INFO - PROCESS | 1742 | __metrics Screen width/height:1600/1200 17:41:13 INFO - PROCESS | 1742 | colorDepth:24 17:41:13 INFO - PROCESS | 1742 | Browser inner width/height: 1024/689 17:41:13 INFO - PROCESS | 1742 | __metrics 17:41:14 ERROR - PROCESS | 1742 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 17:41:14 INFO - TEST-INFO | 1742: exit 0 17:41:14 INFO - Browser initialized. 17:41:14 INFO - Running cycle 1/1 for dromaeo_css test... 17:41:14 INFO - TEST-INFO | started process 1747 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmptnFtjQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:41:26 INFO - PROCESS | 1747 | RSS: Main: 220540928 17:41:26 INFO - PROCESS | 1747 | 17:42:16 INFO - PROCESS | 1747 | 1464050536212 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] 17:44:27 INFO - PROCESS | 1747 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 17:44:28 INFO - PROCESS | 1747 | RSS: Main: 231260160 17:44:28 INFO - PROCESS | 1747 | 17:47:30 INFO - PROCESS | 1747 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 17:47:31 INFO - PROCESS | 1747 | RSS: Main: 243183616 17:47:31 INFO - PROCESS | 1747 | 17:50:33 INFO - PROCESS | 1747 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 17:50:33 INFO - PROCESS | 1747 | RSS: Main: 245039104 17:50:33 INFO - PROCESS | 1747 | 17:53:35 INFO - PROCESS | 1747 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 17:53:36 INFO - PROCESS | 1747 | RSS: Main: 246591488 17:53:36 INFO - PROCESS | 1747 | 17:56:38 INFO - PROCESS | 1747 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 17:56:39 INFO - PROCESS | 1747 | RSS: Main: 249954304 17:56:39 INFO - PROCESS | 1747 | 17:59:41 INFO - PROCESS | 1747 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 17:59:41 INFO - PROCESS | 1747 | RSS: Main: 250359808 17:59:41 INFO - PROCESS | 1747 | 17:59:41 INFO - PROCESS | 1747 | __start_tp_report 17:59:41 INFO - PROCESS | 1747 | _x_x_mozilla_page_load 17:59:41 INFO - PROCESS | 1747 | _x_x_mozilla_page_load_details 17:59:41 INFO - PROCESS | 1747 | |i|pagename|runs| 17:59:41 INFO - PROCESS | 1747 | |0;dojo.html;2800.159952014395;3445.724342052636;3518.261165155317;3536.8408421621025;3574.785512869227;5755.482006619403;5775.2203452533895;5798.072308430652;5819.970900145504;5827.096799996008;5764.279465066867;5813.912791308123;5831.708414579265;5851.941480585193;5873.972054890388;5179.274901513772;5267.130923397634;5277.7888884444765;5291.629585928987;5332.466753324676;5147.588192944556;5235.11003129467;5244.134717771563;5247.763850626712;5253.0544501989625;5254.238135470355;5256.842294731145;5264.447233040533;5266.7366631668265;5276.023935571931;4440.934175797817;4857.951420485785;5192.818251361185;5220.895582088375;5241.5020573045695;3860.7104467164795;4370.628496577795;5099.566536844372;5264.184051472028;5300.840974770763;5731.426857314277;5763.6541807491685;5764.452377024218;5782.97108514459;5804.651720896717;6119.908201376983;6142.96928515359;6268.059791031336;6283.654399008053;6315.179026726542;6226.688665566703;6254.343293954114;6257.968710156466;6264.655443950581;6280.748770049193;5878.559108066912;5884.999999999957;5896.528277737771;5911.4975227105315;5922.7927022554;6222.968885155636;6244.12582238487;6335.619862808247;6344.6827658616885;6345.302016778151;6159.19930409044;6245.5628106033055;6277.591956522811;6283.994560870239;6291.591046582049;6249.437550620375;6268.717907694074;6270.592411493239;6277.24673039229;6286.811395658137;6124.777645458507;6182.474489668429;6236.719347629368;6259.71831267594;6288.9056664150075;6215.471684906744;6232.781852635136;6242.344553821874;6267.373262673696;6285.497160227171;4878.829240976549;4975.179095449283;4981.551660350586;4984.5513903748115;4996.050750357421;3620.909477263037;4392.363107349481;4416.84541041062;4420.801063952065;4498.212812757754;5842.970785146047;5924.555658325557;5940.851478713067;5964.373740757244;5970.791022314198;6271.529635277372;6281.497480201573;6283.7800676976085;6285.968570157212;6294.181756371734;5711.914321285184;5806.912896306559;5875.148103524965;5887.4701276884425;5895.35151133375;5425.213344065168;5433.891322173614;5434.8913021739345;5438.5377242934;5439.510444059974;6250.562460627894;6385.616862988236;6408.679566021773;6417.871642567123;6837;6160.969195153995;6193.225846769154;6235.563510554218;6254.6247225166635;6263.561550691408;6407.711652975534;6437.581557198767;6447.838804029937;6465.676716164267;6473.676316184266;5799.623024503394;5857.853553661193;6255.56211065221;6261.311255761953;6280.497560195265;6205.162303089206;6216.409441103088;6228.065790131516;6257.2491301044165;6289.716962736779;6172.6913654318005;6215.782447614311;6236;6241.812745617639;6268.216472940882;4497.977510112429;6065.6049108704365;6073.210482637229;6221.377862213742;6242.063690446469;4961.6030717543235;5926.377730338252;5961.314448838439;6108.281362586102;6121.785737499166;6099.420555047263;6102.694865256808;6109.3890610940325;6118.47992920589;6147;5927.31835838884;6102.420270074336;6111.022236442148;6120.908186377119;6126.571140020156 17:59:41 INFO - PROCESS | 1747 | |1;ext.html;518.7613697699061;525.4955242966752;562.791767046193;577.7400169923533;689.8169639068996;35670;38033.61966380335;38136;38157.23685526291;38192.618073819256;38627.61372386282;39114.60885391152;39257.41113883294;39297.41053884194;39340.409893851545;9305.674301399453;9519.71440856772;9533.285003624722;9538.189253913426;9554.378965367263;8039.5176289422825;9457.432554046745;9504.192143667797;9589.76025599362;9625.807483850318;8024.317932975704;8387.370947178955;9563.952180239088;9633.133018028377;9681;9540.475273859935;9637;9637.132658060773;9640.710778676581;9640.95179524105;9492.430454172703;9509.381890177116;9600.183984361267;9623.51882405884;9633.710988670351;38312.23375532502;38503.229935401134;38504.61495385038;38695.613043869766;38899.805500972594;3443.276911848515;3492.807895565743;3720.7023438124884;3815.294170578452;3892.260470510594;24071.676057816814;27624.033158839346;28390.85804570964;28546.429071418454;29153.854230728924;2773.611694362791;2808.69104398516;2811.8312901225786;2814.493391189585;2893.8263704177607;473722;478431;479638.9999999965;479652;480514;61257.08114378288;61708.691456543325;61770;61842.0723689145;61871;67928.94319755623;68538.65730671315;69517;69574.30425695678;69590.65204673944;14634.853651463562;17104;17484.825151748573;17486.21312040961;17656.470305890845;13349;13417.194968301934;13466.394012269471;13485.662858428617;13530.661733456545;19298.32455864038;19719.704204436945;19741.60516789685;19786.802131978497;19851.503712407015;8165.591720414074;8171.63227654757;8196.180381961756;8262.339012878956;8483.109273526314;1010.3584224017654;1054.9736256593646;1064.5954537275786;1108.6729414822646;1112.942704430794;29613;29983;30025.699743002726;30135.246618834706;30142.54786178209;1128.3681138562267;1156.8900954409319;1192.7554851255472;1203.891649751509;1221.1207930290177;12742.80885786714;14931.70136597262;15588.610284742745;15861.52415427539;16170.919145404197;617385;626507;635613;637342;637447;58615.70692146597;58816.11775823366;58961.70519147377;59198;59746.50633734191;1308.7447947650307;1488.4864721671006;1504.3606467251375;1515.9469418570518;1517.4006267524267;29353.266168345963;29437.705622943497;29570.852145739133;29937.700622993492;29945.251368715955;51249.74375128101;51387;51559.96880062453;51905.48094519158;52170.21744673757;602097;606302;608242;608911;609289;21580.568388631826;21766.89116554407;21921.78078219261;24733.38166545779;25358.492830143663;42401.78799105985;42748.358774619024;44163.77918110389;44677;44835;31044.137351758967;35739;36187.09532261715;36249.637503624625;36740;30228.697713023466;36324.63675363213;36647.26705465929;36728.81635591805;37120.628793711716;30140;35434.645653543135;35661.64162473584;35712.10719732028;36261.27477450489;30163.698363016967;31335.903243386372;31394.52908206333;31415.371692565564;31485;20632.793672063086;23479.88260058757;24057.75942240555;24375.51248974975;24454.755452445246 17:59:41 INFO - PROCESS | 1747 | |2;jquery.html;398.5576010628204;401.51416785689315;401.87541862022795;405.1026975249822;413;20299.1880324787;20646.48383790406;20932.790672093295;20933.267335643235;21892.12431502742;21676.783232167672;21892.671609925863;21959;22101.115955361805;22170.88914555429;5764.855878603026;5781.653100813944;6032.849178770522;6043.305019922721;6078.969605151968;5825.592208545403;5825.737841797109;5867.853303667421;5898.380670029649;5956.374580669032;5272.657277276984;5316.654417462872;5334.839954801362;5339.332583427072;5914.645121292736;5010.473900240477;5764.711764411783;5802.448767367136;5890.293164820249;5908.231929849114;5104.515071068243;5112.616553758446;5203.219517072469;5205.349331333583;5225.059489291926;12912.741745165044;13283.535076272285;13536.526221582295;26225.344366390993;27038.5944210837;2125.3836387447623;2164.729408823897;2181.934541963728;2237.8433509654333;2250.0999600159903;9944.453055081967;10021.148202402805;10091.192704583616;10233.181345492343;10240.129588984944;1609.4769200010044;1735.6962531557053;1764.2942822870955;1853.379117995473;1989.621971825363;105575;114844;118421.40789296085;134136;141110;21562.353129406143;21912.342629721134;22143;22575.437228528626;22798.55428191487;33666.49500257498;36321.818390908404;36362.63637363645;36679.08302292464;37522;10669.359838409566;11004.119670426348;11617;11815.814074396265;11855.881441185651;9638.566264518113;9672.75818104539;9682.515874206261;9705.6603018894;9796.804063918824;10205.336653117673;10390.48047597617;10417.895821041844;10503.579856805642;10508.527116279633;3562.2163124112653;3935.5670876203594;4116.485439320085;4165.521514698749;4216.683748718859;886.8980067292215;921.2629896083107;984.9704508864602;1041.4792603698152;1048.6067724603274;14549.636259093608;16939.23773430174;20087.598248034956;20892.353392677614;21172.25897093625;945.3807755919884;947.3226642950324;971.019270536763;982.6462473509531;984.7095106943469;11007.284526505751;11225.775484490428;11241.269317494336;11269.436528173559;11274.54901803935;561970.1901490466;582259;586420;590088;640264;103208;104265.47867260616;115150;117447;123537;1019.8572199892021;1032.4579595712162;1032.7624646331385;1041.833306670929;1052.5737076484036;18078.153108135015;18344.816551834843;18425.355112570996;18567.071646417357;18646.067696614842;26337.868310658323;32017.87937422179;32390.67609323971;52651.47348526466;52672.68318292074;69326.65336673486;69429;72467.55064898777;73607;74628;8346.290565301775;8459.915400845914;8504.574771261536;8573.828523429622;8580.957095214484;11446.427678616203;12365.258084514959;12602.621921342374;12727.045471589483;12951.805722913976;10666.89333106659;10966.506507207036;11006.614768483065;11078.947499987487;11208.943955280172;10832.566697332333;10927.83608245861;10979.1216702662;11044.502997364978;11068.778624427627;10732.765731940926;10908.400037997986;10940.234183607072;11311;11439.313641181556;8293;8323.959505061866;8429.988401391873;8490.06609272967;8640.481571105754;10573.471326433493;10676.62631807883;10862.674119776419;11080.05819505351;11352.240402737589 17:59:41 INFO - PROCESS | 1747 | |3;mootools.html;1375.924324162171;1588.0630428047452;1808.6653969015733;1946.600946805904;1987.1455274232073;7883.6846526139025;8544.316454683625;8685.73942781716;8916.286697064235;8926.86609700855;8643.394962352622;8710.128987101303;8717.7820554486;8791.47251164929;8802.515861627608;2451.4851881104946;2469.469064151207;2475.282168171228;2479.2562231330617;2481.4416756229884;2415.613501839706;2493.2021753039035;2495.950080998379;2501.187114187896;2505.6116301973225;2450.3384086296687;2458.7418321076298;2491.700995880497;2492.9127480538186;2497.8001759859167;2460.729319774823;2472.196536125757;2485.639582260571;2488.539620170256;2491.215267190847;2441.316431399211;2462.544429280571;2474.134053081425;2478.628205769131;2483.788877945377;8845.557722113868;8902.910970890272;8903;8916.331275154389;8921.55392230386;5440.510354068153;5536.667799932017;5577.665340079568;5606.747696353674;5663.348714897757;3486.0761898097026;3591.3894637911358;3597.388443964532;3617.7105831533413;3668.1563240454843;1058.9894101059044;1072.399456304472;1073.849661047454;1075.1022895881947;1077.504347999921;9948.602055917754;11087;12001.87998120025;12183.90344868966;12208.877911220863;3744.812759362021;4168.291390463629;4801.1117943179925;4942.061008408391;4966.850994470172;10072;10177.745556361002;10207.030332118436;10215.795684086277;10221.9488902555;5471.480209380103;5491.176323551419;5522.364928033247;5541.667499950016;5565.7217139143695;4418.8453404130705;4768.904621907543;4838.95161048392;4882.5605695487575;5023.070731914534;4074.5925407459017;4099.418026410338;4143.854965076268;4148.937765933513;4163.770992595436;1361.5779108476206;1370.8971827113007;1374.2647683489286;1382.7580173469603;1385.0927642394047;928.2713070239829;942.9580313753341;945.1493655709916;949.8955114937352;979.6228452045871;5775.104858746901;5785.363610002896;5840;5916.497097746654;5970.104484327298;437.8288079387625;456.1401757686718;464.9395578574831;472.9810807567659;485.2018429683222;3326.3014766899223;3885.630865067814;4030.093229023505;4031.475908131924;4080.4083407906282;10576.947115264375;10763.19276054299;10925.781484370267;10954;11012.284201526874;10631.149508039338;10738.838917416246;10778.838317425245;10830;10843.07833834117;8316.958415208006;8366.874496882552;8445.57772111392;8532.104129066483;8647.221750042527;5610.691411972381;5674.546036317167;5683.687397193194;5768.192453056491;5769.249997500466;9497.810043799223;9609.279304052365;9635.759106022406;9691.418514889128;9708.417494950325;9577.042295770367;9980.101790838859;10075.244356673426;10076.899231007596;10106.646267380607;6707.1616047994;7179.964100179465;7392.260773922565;7439.851202975802;7536.811579710551;8206.712765053195;8218.712345067894;8252.669893204205;8269.131741167092;8278.875816862632;7217.422606191387;7338.119425668954;7400.851982960418;7493.887591686238;7502.437317201341;7229.49393542447;7260.782176534713;7308.598027108347;7367.1527774304495;7417.8887316689215;6959.826004349932;7003.26465721092;7032.26161253061;7037.401820845285;7057.153141623038;4751.429828420473;4780.450248221499;4795.880102997453;4861.076395484846;4873.975630121827;4822.565969062801;4833.879153021062;4866.391701037371;4926.211806111005;5036.370453693288 17:59:41 INFO - PROCESS | 1747 | |4;prototype.html;274.24172163966637;329.69009131416465;333.65466741922114;335.0451214039986;339.1707275710888;6743.527953043283;6758.966205168979;6772.8306792330095;6781.457483401328;6787.592744435331;6732.427743641784;6780.084563637633;6780.660966951657;6794;6810.3189681032;4698.201305778018;4807.447143578481;4853.102176097415;4892.339534162896;4918.262260660894;4179.310413781723;4875.488073752258;4911.386076740408;4911.975440122795;4922.8030878764985;4877.634177436688;4878.585320247766;4880.292357608145;4885.2427873679635;4902.411710594733;3955.9604403956023;3956.742811717258;4833.202521583935;4880.316755654211;4924.06442775875;4238.936415953733;4242.469691288589;4254.765987870666;4272.658187345005;4290.806913688892;6260.154879091356;6515.804525864231;6548.246951600579;6560.770373036921;6588.604683718992;5777.335606405233;5783.884322313572;5791.247137872092;5791.913121303184;5843.18195452637;5124.308218390507;6757.412008178074;7058.54119482232;7224.566526008456;7232.8553428931655;4621.69958952667;4651.860444186646;4653.86038418848;4654.0924519718665;4660.86017419478;5867.237259156283;7608.277213664693;7637.69449222036;7656.9234307657325;7672.0409948756405;4167.708260421741;4199.160167966419;4678.929816052762;4679.485256621769;4682.2508398656055;4716.221823399169;4747.525247475225;4751.097291514601;4759.214729569651;4775.403074615673;4549.54504549549;4860.784803799051;5363.141897296414;5651.745671444794;5679.687617181012;6772.966135169293;7178.102737157856;7209.711611535585;7235.421166306788;7248.456365772483;1448.5364683301243;1664.9167541622871;1665.1341302522621;1670.0397271568897;1670.6491636756175;1023.3041531758475;1052.2949623752104;1066.0991462214417;1068.2095249515303;1072.9141668666489;6664.933350666528;6690.197176338773;6724.991251312343;6728.966355168291;6757.526973111835;7256.528325658815;7498.800671623908;7503.924960750432;7523.699052037857;7528.585927774025;6648.5346025777735;6802.489813264025;6850.38346548803;6858.279880612564;6859.725610975505;3950.881473555798;3982.7809470478824;3989.5212574491247;3992.3612222044335;3997.060690737674;7942.523448593103;7943.801404964922;7949.523028618186;7952.761417157494;7967.04395472547;7614;7799.766007019798;7813.687452501837;7832.960835195788;7947.086085100172;6431.810115128623;7989.600519974094;8046.718364857202;8062.274395304334;8064.354851611857;7606.429517786299;7608.429367797326;7617.619119044137;7640.961795190989;7648.08223013242;6620.536562440776;6895.4138898194215;7475.4019678426685;7481.925180748341;7513.173550909505;7723.6138193089;8002.839943200987;8007.799805004922;8009.038915330198;8027.357811375193;4707.8587642370785;4731.243001119804;4760.333553302471;4767.856964291077;4777.283407488904;7529.134149572869;7547.547147171187;7548.962255188689;7550.811229719301;7614.619269036415;6600.702968366531;7284.599347035964;7289.234630363735;7294.562326260441;7328.633568321456;7094.579799424754;7248.166460857069;7295.52579082358;7296.671649775667;7314.232005639544;7078.964605176941;7103.609301488261;7109.964450177716;7115.53749006313;7141.178764442156;6508.219013718384;6892;7299.598522081336;7300.598467084362;7400.222976587596;6795.082663840516;7129.964350178423;7131.0729605151;7131.8930216045765;7187.065681461377 17:59:41 INFO - PROCESS | 1747 | |5;yui.html;674.7570874485186;705.4814711187273;727.8653449111914;743.5204293230867;776.9533827970321;15011;15288.464903728373;15341.23293835308;15353.462628807994;15360.07839529629;13926.930365348184;14127.858721412782;14282.928585357085;14296.285185740697;14306.284685765722;714.9714011439536;893.9597718102701;904.6291020681522;920.516728717422;935.854942483916;920.8710780490736;921.1157289002566;922.8246633139715;923.3859483443536;924.5100096948628;964.5370222293303;969.8351280282334;971.3346357744967;976.0629795396393;980.4460479828874;963.802420503795;966.3573723473952;967.2503809547586;970.6602689058843;972.4408465132542;679.4428568573771;692.6848284030754;693.6670398208888;694.0179645801185;712.9679164437613;14838.480653177086;14896.106233626017;14899.851001489955;14934.850651493454;14990;12860.356982150855;12900;13057.804132938014;13089.083764136514;13293.73412531744;9803.950980245125;9837.508124593813;10030.297879148462;10112.797744045152;10182.898171018343;3506.8421921013614;3517.4723791431234;3519.0674471265133;3627.1838836261895;4285.442892423996;59082;79564.80652790212;80002.59998700027;80847.5957620214;81810;9308.069193080637;9399.859002114974;9470.100340467645;9542;9699.951500242454;20730.792692072886;20758.688619670716;20763.273285434938;20765.480862978246;20791.58416831655;7378.077740282465;7431.256874312634;7478.326950574366;7503;7607.277308655669;3402.8979130625626;3492.1880042299604;4029.7582145071387;4100.6719462443525;4105.507339119265;3617.909552261162;3632.3461776879885;3680.687141592941;3690.55713314404;3698.278835627027;631.9810405687745;663.2372771313029;668.108075718911;669.0666520204288;674.8987651852163;552.7678375082475;561.3796754586206;600.2886579403421;606.3875485759414;609.5062998970759;8146.83706325882;8290.212429819157;8402.41183117176;8404.747857564284;8413.284870786052;413.8965258685329;583.7723287917715;599.1701493431628;620.2618883528677;640.4268179978893;3897.590752970954;3927.998360418075;3944.3097457944746;3953.802309884552;4039.4950631171105;93679;94512.52743736237;95049;96350.51824740971;96867.51566242265;39712.00719982023;39875.211854874324;39876.40185397222;54260;84374.57812710897;72040.63979680068;72714.27285727076;73334.79995600248;73433;74462;7859.410544209093;7877.724279650185;7953.085395179628;7969.6015199240965;8064.919350806417;47182;47837;48481.03037939151;48559;48837;63031.6848415755;69970.30029699639;70247.64876175586;70962.64518677373;71348.57302854018;9000.054994225513;9960.501974901372;10035.799284014425;10144.644937427154;10314.174866010851;9429.632703258127;10017.39895606266;10785.676429707122;10786.73033174177;10851.511681974172;10261.74345641365;10286.125679317342;10354.637587684394;10591.417472039111;10710.089642380484;10300.536475858455;10455.163586912877;10530.210234232616;10688.866799204772;10775.872800880383;9701.272404569545;10143.54354054084;10387.480625968521;10531.05220530156;10572.682819515427;8645.351598630003;8933.28533717314;9096.727098187064;9158.221551167959;9206.631734730803;8939.687110951085;8980.506072166094;9002.63989440415;9064.365494415328;9072.455652660597 17:59:41 INFO - PROCESS | 1747 | __end_tp_report 17:59:41 INFO - PROCESS | 1747 | __start_cc_report 17:59:41 INFO - PROCESS | 1747 | _x_x_mozilla_cycle_collect,413 17:59:41 INFO - PROCESS | 1747 | __end_cc_report 17:59:41 INFO - PROCESS | 1747 | __startTimestamp1464051581890__endTimestamp 17:59:41 INFO - PROCESS | 1747 | 17:59:41 INFO - PROCESS | 1747 | ------- Summary: start ------- 17:59:41 INFO - PROCESS | 1747 | Number of tests: 6 17:59:41 INFO - PROCESS | 1747 | 17:59:41 INFO - PROCESS | 1747 | [#0] dojo.html Cycles:165 Average:5763.47 Median:6065.60 stddev:705.97 (11.6%) stddev-sans-first:668.77 17:59:41 INFO - PROCESS | 1747 | Values: 2800.2 3445.7 3518.3 3536.8 3574.8 5755.5 5775.2 5798.1 5820.0 5827.1 5764.3 5813.9 5831.7 5851.9 5874.0 5179.3 5267.1 5277.8 5291.6 5332.5 5147.6 5235.1 5244.1 5247.8 5253.1 5254.2 5256.8 5264.4 5266.7 5276.0 4440.9 4858.0 5192.8 5220.9 5241.5 3860.7 4370.6 5099.6 5264.2 5300.8 5731.4 5763.7 5764.5 5783.0 5804.7 6119.9 6143.0 6268.1 6283.7 6315.2 6226.7 6254.3 6258.0 6264.7 6280.7 5878.6 5885.0 5896.5 5911.5 5922.8 6223.0 6244.1 6335.6 6344.7 6345.3 6159.2 6245.6 6277.6 6284.0 6291.6 6249.4 6268.7 6270.6 6277.2 6286.8 6124.8 6182.5 6236.7 6259.7 6288.9 6215.5 6232.8 6242.3 6267.4 6285.5 4878.8 4975.2 4981.6 4984.6 4996.1 3620.9 4392.4 4416.8 4420.8 4498.2 5843.0 5924.6 5940.9 5964.4 5970.8 6271.5 6281.5 6283.8 6286.0 6294.2 5711.9 5806.9 5875.1 5887.5 5895.4 5425.2 5433.9 5434.9 5438.5 5439.5 6250.6 6385.6 6408.7 6417.9 6837.0 6161.0 6193.2 6235.6 6254.6 6263.6 6407.7 6437.6 6447.8 6465.7 6473.7 5799.6 5857.9 6255.6 6261.3 6280.5 6205.2 6216.4 6228.1 6257.2 6289.7 6172.7 6215.8 6236.0 6241.8 6268.2 4498.0 6065.6 6073.2 6221.4 6242.1 4961.6 5926.4 5961.3 6108.3 6121.8 6099.4 6102.7 6109.4 6118.5 6147.0 5927.3 6102.4 6111.0 6120.9 6126.6 17:59:41 INFO - PROCESS | 1747 | 17:59:41 INFO - PROCESS | 1747 | [#1] ext.html Cycles:180 Average:69942.27 Median:20975.65 stddev:154108.03 (734.7%) stddev-sans-first:154452.19 17:59:41 INFO - PROCESS | 1747 | Values: 518.8 525.5 562.8 577.7 689.8 35670.0 38033.6 38136.0 38157.2 38192.6 38627.6 39114.6 39257.4 39297.4 39340.4 9305.7 9519.7 9533.3 9538.2 9554.4 8039.5 9457.4 9504.2 9589.8 9625.8 8024.3 8387.4 9564.0 9633.1 9681.0 9540.5 9637.0 9637.1 9640.7 9641.0 9492.4 9509.4 9600.2 9623.5 9633.7 38312.2 38503.2 38504.6 38695.6 38899.8 3443.3 3492.8 3720.7 3815.3 3892.3 24071.7 27624.0 28390.9 28546.4 29153.9 2773.6 2808.7 2811.8 2814.5 2893.8 473722.0 478431.0 479639.0 479652.0 480514.0 61257.1 61708.7 61770.0 61842.1 61871.0 67928.9 68538.7 69517.0 69574.3 69590.7 14634.9 17104.0 17484.8 17486.2 17656.5 13349.0 13417.2 13466.4 13485.7 13530.7 19298.3 19719.7 19741.6 19786.8 19851.5 8165.6 8171.6 8196.2 8262.3 8483.1 1010.4 1055.0 1064.6 1108.7 1112.9 29613.0 29983.0 30025.7 30135.2 30142.5 1128.4 1156.9 1192.8 1203.9 1221.1 12742.8 14931.7 15588.6 15861.5 16170.9 617385.0 626507.0 635613.0 637342.0 637447.0 58615.7 58816.1 58961.7 59198.0 59746.5 1308.7 1488.5 1504.4 1515.9 1517.4 29353.3 29437.7 29570.9 29937.7 29945.3 51249.7 51387.0 51560.0 51905.5 52170.2 602097.0 606302.0 608242.0 608911.0 609289.0 21580.6 21766.9 21921.8 24733.4 25358.5 42401.8 42748.4 44163.8 44677.0 44835.0 31044.1 35739.0 36187.1 36249.6 36740.0 30228.7 36324.6 36647.3 36728.8 37120.6 30140.0 35434.6 35661.6 35712.1 36261.3 30163.7 31335.9 31394.5 31415.4 31485.0 20632.8 23479.9 24057.8 24375.5 24454.8 17:59:41 INFO - PROCESS | 1747 | 17:59:41 INFO - PROCESS | 1747 | [#2] jquery.html Cycles:180 Average:35037.23 Median:21935.67 stddev:98503.76 (449.1%) stddev-sans-first:98745.75 17:59:41 INFO - PROCESS | 1747 | Values: 398.6 401.5 401.9 405.1 413.0 20299.2 20646.5 20932.8 20933.3 21892.1 21676.8 21892.7 21959.0 22101.1 22170.9 5764.9 5781.7 6032.8 6043.3 6079.0 5825.6 5825.7 5867.9 5898.4 5956.4 5272.7 5316.7 5334.8 5339.3 5914.6 5010.5 5764.7 5802.4 5890.3 5908.2 5104.5 5112.6 5203.2 5205.3 5225.1 12912.7 13283.5 13536.5 26225.3 27038.6 2125.4 2164.7 2181.9 2237.8 2250.1 9944.5 10021.1 10091.2 10233.2 10240.1 1609.5 1735.7 1764.3 1853.4 1989.6 105575.0 114844.0 118421.4 134136.0 141110.0 21562.4 21912.3 22143.0 22575.4 22798.6 33666.5 36321.8 36362.6 36679.1 37522.0 10669.4 11004.1 11617.0 11815.8 11855.9 9638.6 9672.8 9682.5 9705.7 9796.8 10205.3 10390.5 10417.9 10503.6 10508.5 3562.2 3935.6 4116.5 4165.5 4216.7 886.9 921.3 985.0 1041.5 1048.6 14549.6 16939.2 20087.6 20892.4 21172.3 945.4 947.3 971.0 982.6 984.7 11007.3 11225.8 11241.3 11269.4 11274.5 561970.2 582259.0 586420.0 590088.0 640264.0 103208.0 104265.5 115150.0 117447.0 123537.0 1019.9 1032.5 1032.8 1041.8 1052.6 18078.2 18344.8 18425.4 18567.1 18646.1 26337.9 32017.9 32390.7 52651.5 52672.7 69326.7 69429.0 72467.6 73607.0 74628.0 8346.3 8459.9 8504.6 8573.8 8581.0 11446.4 12365.3 12602.6 12727.0 12951.8 10666.9 10966.5 11006.6 11078.9 11208.9 10832.6 10927.8 10979.1 11044.5 11068.8 10732.8 10908.4 10940.2 11311.0 11439.3 8293.0 8324.0 8430.0 8490.1 8640.5 10573.5 10676.6 10862.7 11080.1 11352.2 17:59:41 INFO - PROCESS | 1747 | 17:59:41 INFO - PROCESS | 1747 | [#3] mootools.html Cycles:180 Average:5722.37 Median:4836.42 stddev:3188.98 (65.9%) stddev-sans-first:3181.20 17:59:41 INFO - PROCESS | 1747 | Values: 1375.9 1588.1 1808.7 1946.6 1987.1 7883.7 8544.3 8685.7 8916.3 8926.9 8643.4 8710.1 8717.8 8791.5 8802.5 2451.5 2469.5 2475.3 2479.3 2481.4 2415.6 2493.2 2496.0 2501.2 2505.6 2450.3 2458.7 2491.7 2492.9 2497.8 2460.7 2472.2 2485.6 2488.5 2491.2 2441.3 2462.5 2474.1 2478.6 2483.8 8845.6 8902.9 8903.0 8916.3 8921.6 5440.5 5536.7 5577.7 5606.7 5663.3 3486.1 3591.4 3597.4 3617.7 3668.2 1059.0 1072.4 1073.8 1075.1 1077.5 9948.6 11087.0 12001.9 12183.9 12208.9 3744.8 4168.3 4801.1 4942.1 4966.9 10072.0 10177.7 10207.0 10215.8 10221.9 5471.5 5491.2 5522.4 5541.7 5565.7 4418.8 4768.9 4839.0 4882.6 5023.1 4074.6 4099.4 4143.9 4148.9 4163.8 1361.6 1370.9 1374.3 1382.8 1385.1 928.3 943.0 945.1 949.9 979.6 5775.1 5785.4 5840.0 5916.5 5970.1 437.8 456.1 464.9 473.0 485.2 3326.3 3885.6 4030.1 4031.5 4080.4 10576.9 10763.2 10925.8 10954.0 11012.3 10631.1 10738.8 10778.8 10830.0 10843.1 8317.0 8366.9 8445.6 8532.1 8647.2 5610.7 5674.5 5683.7 5768.2 5769.2 9497.8 9609.3 9635.8 9691.4 9708.4 9577.0 9980.1 10075.2 10076.9 10106.6 6707.2 7180.0 7392.3 7439.9 7536.8 8206.7 8218.7 8252.7 8269.1 8278.9 7217.4 7338.1 7400.9 7493.9 7502.4 7229.5 7260.8 7308.6 7367.2 7417.9 6959.8 7003.3 7032.3 7037.4 7057.2 4751.4 4780.5 4795.9 4861.1 4874.0 4822.6 4833.9 4866.4 4926.2 5036.4 17:59:41 INFO - PROCESS | 1747 | 17:59:41 INFO - PROCESS | 1747 | [#4] prototype.html Cycles:180 Average:5874.47 Median:6730.70 stddev:1935.48 (28.8%) stddev-sans-first:1894.71 17:59:41 INFO - PROCESS | 1747 | Values: 274.2 329.7 333.7 335.0 339.2 6743.5 6759.0 6772.8 6781.5 6787.6 6732.4 6780.1 6780.7 6794.0 6810.3 4698.2 4807.4 4853.1 4892.3 4918.3 4179.3 4875.5 4911.4 4912.0 4922.8 4877.6 4878.6 4880.3 4885.2 4902.4 3956.0 3956.7 4833.2 4880.3 4924.1 4238.9 4242.5 4254.8 4272.7 4290.8 6260.2 6515.8 6548.2 6560.8 6588.6 5777.3 5783.9 5791.2 5791.9 5843.2 5124.3 6757.4 7058.5 7224.6 7232.9 4621.7 4651.9 4653.9 4654.1 4660.9 5867.2 7608.3 7637.7 7656.9 7672.0 4167.7 4199.2 4678.9 4679.5 4682.3 4716.2 4747.5 4751.1 4759.2 4775.4 4549.5 4860.8 5363.1 5651.7 5679.7 6773.0 7178.1 7209.7 7235.4 7248.5 1448.5 1664.9 1665.1 1670.0 1670.6 1023.3 1052.3 1066.1 1068.2 1072.9 6664.9 6690.2 6725.0 6729.0 6757.5 7256.5 7498.8 7503.9 7523.7 7528.6 6648.5 6802.5 6850.4 6858.3 6859.7 3950.9 3982.8 3989.5 3992.4 3997.1 7942.5 7943.8 7949.5 7952.8 7967.0 7614.0 7799.8 7813.7 7833.0 7947.1 6431.8 7989.6 8046.7 8062.3 8064.4 7606.4 7608.4 7617.6 7641.0 7648.1 6620.5 6895.4 7475.4 7481.9 7513.2 7723.6 8002.8 8007.8 8009.0 8027.4 4707.9 4731.2 4760.3 4767.9 4777.3 7529.1 7547.5 7549.0 7550.8 7614.6 6600.7 7284.6 7289.2 7294.6 7328.6 7094.6 7248.2 7295.5 7296.7 7314.2 7079.0 7103.6 7110.0 7115.5 7141.2 6508.2 6892.0 7299.6 7300.6 7400.2 6795.1 7130.0 7131.1 7131.9 7187.1 17:59:41 INFO - PROCESS | 1747 | 17:59:41 INFO - PROCESS | 1747 | [#5] yui.html Cycles:180 Average:17408.92 Median:651.83 stddev:24724.98 (3793.2%) stddev-sans-first:24762.41 17:59:41 INFO - PROCESS | 1747 | Values: 674.8 705.5 727.9 743.5 777.0 15011.0 15288.5 15341.2 15353.5 15360.1 13926.9 14127.9 14282.9 14296.3 14306.3 715.0 894.0 904.6 920.5 935.9 920.9 921.1 922.8 923.4 924.5 964.5 969.8 971.3 976.1 980.4 963.8 966.4 967.3 970.7 972.4 679.4 692.7 693.7 694.0 713.0 14838.5 14896.1 14899.9 14934.9 14990.0 12860.4 12900.0 13057.8 13089.1 13293.7 9804.0 9837.5 10030.3 10112.8 10182.9 3506.8 3517.5 3519.1 3627.2 4285.4 59082.0 79564.8 80002.6 80847.6 81810.0 9308.1 9399.9 9470.1 9542.0 9700.0 20730.8 20758.7 20763.3 20765.5 20791.6 7378.1 7431.3 7478.3 7503.0 7607.3 3402.9 3492.2 4029.8 4100.7 4105.5 3617.9 3632.3 3680.7 3690.6 3698.3 632.0 663.2 668.1 669.1 674.9 552.8 561.4 600.3 606.4 609.5 8146.8 8290.2 8402.4 8404.7 8413.3 413.9 583.8 599.2 620.3 640.4 3897.6 3928.0 3944.3 3953.8 4039.5 93679.0 94512.5 95049.0 96350.5 96867.5 39712.0 39875.2 39876.4 54260.0 84374.6 72040.6 72714.3 73334.8 73433.0 74462.0 7859.4 7877.7 7953.1 7969.6 8064.9 47182.0 47837.0 48481.0 48559.0 48837.0 63031.7 69970.3 70247.6 70962.6 71348.6 9000.1 9960.5 10035.8 10144.6 10314.2 9429.6 10017.4 10785.7 10786.7 10851.5 10261.7 10286.1 10354.6 10591.4 10710.1 10300.5 10455.2 10530.2 10688.9 10775.9 9701.3 10143.5 10387.5 10531.1 10572.7 8645.4 8933.3 9096.7 9158.2 9206.6 8939.7 8980.5 9002.6 9064.4 9072.5 17:59:41 INFO - PROCESS | 1747 | -------- Summary: end -------- 17:59:41 INFO - PROCESS | 1747 | 17:59:42 INFO - TEST-INFO | 1747: exit 0 17:59:42 INFO - TEST-OK | dromaeo_css | took 1111383ms 17:59:42 INFO - TEST-START | kraken 17:59:42 INFO - operating with platform_type : mac_ 17:59:42 INFO - Initialising browser for kraken test... 17:59:42 INFO - TEST-INFO | started process 1865 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmpreVph0/profile http://localhost:49270/getInfo.html) 17:59:45 INFO - PROCESS | 1865 | __metrics Screen width/height:1600/1200 17:59:45 INFO - PROCESS | 1865 | colorDepth:24 17:59:45 INFO - PROCESS | 1865 | Browser inner width/height: 1024/689 17:59:45 INFO - PROCESS | 1865 | __metrics 17:59:45 ERROR - PROCESS | 1865 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 17:59:45 INFO - TEST-INFO | 1865: exit 0 17:59:45 INFO - Browser initialized. 17:59:45 INFO - Running cycle 1/1 for kraken test... 17:59:45 INFO - TEST-INFO | started process 1869 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/T/tmpreVph0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:59:57 INFO - PROCESS | 1869 | RSS: Main: 221278208 17:59:57 INFO - PROCESS | 1869 | 18:00:00 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 18:00:01 INFO - PROCESS | 1869 | RSS: Main: 232951808 18:00:01 INFO - PROCESS | 1869 | 18:00:04 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 18:00:04 INFO - PROCESS | 1869 | RSS: Main: 241885184 18:00:04 INFO - PROCESS | 1869 | 18:00:08 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 18:00:08 INFO - PROCESS | 1869 | RSS: Main: 245710848 18:00:08 INFO - PROCESS | 1869 | 18:00:10 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 18:00:11 INFO - PROCESS | 1869 | RSS: Main: 246022144 18:00:11 INFO - PROCESS | 1869 | 18:00:13 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:00:14 INFO - PROCESS | 1869 | RSS: Main: 247590912 18:00:14 INFO - PROCESS | 1869 | 18:00:16 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 18:00:17 INFO - PROCESS | 1869 | RSS: Main: 247828480 18:00:17 INFO - PROCESS | 1869 | 18:00:20 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 18:00:21 INFO - PROCESS | 1869 | RSS: Main: 247853056 18:00:21 INFO - PROCESS | 1869 | 18:00:24 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 18:00:24 INFO - PROCESS | 1869 | RSS: Main: 248045568 18:00:24 INFO - PROCESS | 1869 | 18:00:26 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:00:26 INFO - PROCESS | 1869 | RSS: Main: 247095296 18:00:26 INFO - PROCESS | 1869 | 18:00:28 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:00:29 INFO - PROCESS | 1869 | RSS: Main: 247291904 18:00:29 INFO - PROCESS | 1869 | 18:00:31 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:00:32 INFO - PROCESS | 1869 | RSS: Main: 247439360 18:00:32 INFO - PROCESS | 1869 | 18:00:35 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:00:35 INFO - PROCESS | 1869 | RSS: Main: 247418880 18:00:35 INFO - PROCESS | 1869 | 18:00:38 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:00:39 INFO - PROCESS | 1869 | RSS: Main: 247398400 18:00:39 INFO - PROCESS | 1869 | 18:00:40 INFO - PROCESS | 1869 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 18:00:41 INFO - PROCESS | 1869 | RSS: Main: 249233408 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | __start_tp_report 18:00:41 INFO - PROCESS | 1869 | _x_x_mozilla_page_load 18:00:41 INFO - PROCESS | 1869 | _x_x_mozilla_page_load_details 18:00:41 INFO - PROCESS | 1869 | |i|pagename|runs| 18:00:41 INFO - PROCESS | 1869 | |0;ai-astar;114;120;108;124;138;119;106;117;117;115 18:00:41 INFO - PROCESS | 1869 | |1;audio-beat-detection;150;122;157;151;135;173;166;153;168;124 18:00:41 INFO - PROCESS | 1869 | |2;audio-dft;194;199;193;194;232;256;194;194;194;201 18:00:41 INFO - PROCESS | 1869 | |3;audio-fft;68;73;84;141;57;58;69;119;162;82 18:00:41 INFO - PROCESS | 1869 | |4;audio-oscillator;77;80;121;76;79;80;114;79;79;121 18:00:41 INFO - PROCESS | 1869 | |5;imaging-gaussian-blur;76;74;83;132;135;83;111;132;77;87 18:00:41 INFO - PROCESS | 1869 | |6;imaging-darkroom;96;90;151;100;150;103;152;150;113;94 18:00:41 INFO - PROCESS | 1869 | |7;imaging-desaturate;90;93;90;88;95;90;93;93;91;90 18:00:41 INFO - PROCESS | 1869 | |8;json-parse-financial;60;63;65;66;66;66;66;82;64;60 18:00:41 INFO - PROCESS | 1869 | |9;json-stringify-tinderbox;65;67;69;74;75;66;66;67;92;61 18:00:41 INFO - PROCESS | 1869 | |10;stanford-crypto-aes;81;82;76;88;92;104;98;81;85;89 18:00:41 INFO - PROCESS | 1869 | |11;stanford-crypto-ccm;146;128;144;118;145;151;135;144;185;152 18:00:41 INFO - PROCESS | 1869 | |12;stanford-crypto-pbkdf2;170;173;165;167;169;168;173;166;164;170 18:00:41 INFO - PROCESS | 1869 | |13;stanford-crypto-sha256-iterative;67;68;65;66;68;68;72;89;100;73 18:00:41 INFO - PROCESS | 1869 | __end_tp_report 18:00:41 INFO - PROCESS | 1869 | __start_cc_report 18:00:41 INFO - PROCESS | 1869 | _x_x_mozilla_cycle_collect,1092 18:00:41 INFO - PROCESS | 1869 | __end_cc_report 18:00:41 INFO - PROCESS | 1869 | __startTimestamp1464051641235__endTimestamp 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | ------- Summary: start ------- 18:00:41 INFO - PROCESS | 1869 | Number of tests: 14 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | [#0] ai-astar Cycles:10 Average:117.80 Median:118.00 stddev:8.89 (7.5%) stddev-sans-first:9.32 18:00:41 INFO - PROCESS | 1869 | Values: 114.0 120.0 108.0 124.0 138.0 119.0 106.0 117.0 117.0 115.0 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | [#1] audio-beat-detection Cycles:10 Average:149.90 Median:155.00 stddev:17.80 (11.5%) stddev-sans-first:18.88 18:00:41 INFO - PROCESS | 1869 | Values: 150.0 122.0 157.0 151.0 135.0 173.0 166.0 153.0 168.0 124.0 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | [#2] audio-dft Cycles:10 Average:205.10 Median:196.50 stddev:21.42 (10.9%) stddev-sans-first:22.34 18:00:41 INFO - PROCESS | 1869 | Values: 194.0 199.0 193.0 194.0 232.0 256.0 194.0 194.0 194.0 201.0 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | [#3] audio-fft Cycles:10 Average:91.30 Median:68.50 stddev:36.57 (53.4%) stddev-sans-first:37.80 18:00:41 INFO - PROCESS | 1869 | Values: 68.0 73.0 84.0 141.0 57.0 58.0 69.0 119.0 162.0 82.0 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:79.00 stddev:19.50 (24.7%) stddev-sans-first:20.05 18:00:41 INFO - PROCESS | 1869 | Values: 77.0 80.0 121.0 76.0 79.0 80.0 114.0 79.0 79.0 121.0 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | [#5] imaging-gaussian-blur Cycles:10 Average:99.00 Median:76.50 stddev:25.63 (33.5%) stddev-sans-first:25.80 18:00:41 INFO - PROCESS | 1869 | Values: 76.0 74.0 83.0 132.0 135.0 83.0 111.0 132.0 77.0 87.0 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | [#6] imaging-darkroom Cycles:10 Average:119.90 Median:151.50 stddev:27.23 (18.0%) stddev-sans-first:27.48 18:00:41 INFO - PROCESS | 1869 | Values: 96.0 90.0 151.0 100.0 150.0 103.0 152.0 150.0 113.0 94.0 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:92.00 stddev:2.11 (2.3%) stddev-sans-first:2.19 18:00:41 INFO - PROCESS | 1869 | Values: 90.0 93.0 90.0 88.0 95.0 90.0 93.0 93.0 91.0 90.0 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:66.00 stddev:6.16 (9.3%) stddev-sans-first:6.17 18:00:41 INFO - PROCESS | 1869 | Values: 60.0 63.0 65.0 66.0 66.0 66.0 66.0 82.0 64.0 60.0 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:68.00 stddev:8.70 (12.8%) stddev-sans-first:9.02 18:00:41 INFO - PROCESS | 1869 | Values: 65.0 67.0 69.0 74.0 75.0 66.0 66.0 67.0 92.0 61.0 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:86.50 stddev:8.55 (9.9%) stddev-sans-first:8.73 18:00:41 INFO - PROCESS | 1869 | Values: 81.0 82.0 76.0 88.0 92.0 104.0 98.0 81.0 85.0 89.0 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:145.50 stddev:17.66 (12.1%) stddev-sans-first:18.72 18:00:41 INFO - PROCESS | 1869 | Values: 146.0 128.0 144.0 118.0 145.0 151.0 135.0 144.0 185.0 152.0 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.50 Median:169.50 stddev:3.10 (1.8%) stddev-sans-first:3.24 18:00:41 INFO - PROCESS | 1869 | Values: 170.0 173.0 165.0 167.0 169.0 168.0 173.0 166.0 164.0 170.0 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - PROCESS | 1869 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:68.00 stddev:11.58 (17.0%) stddev-sans-first:12.03 18:00:41 INFO - PROCESS | 1869 | Values: 67.0 68.0 65.0 66.0 68.0 68.0 72.0 89.0 100.0 73.0 18:00:41 INFO - PROCESS | 1869 | -------- Summary: end -------- 18:00:41 INFO - PROCESS | 1869 | 18:00:41 INFO - TEST-INFO | 1869: exit 0 18:00:41 INFO - TEST-OK | kraken | took 59562ms 18:00:41 INFO - SUITE-END | took 1170s 18:00:42 INFO - Completed test suite (00:19:31) 18:00:42 INFO - javascript benchmark 18:00:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2800.159952014395, 3445.724342052636, 3518.261165155317, 3536.8408421621025, 3574.785512869227, 5755.482006619403, 5775.2203452533895, 5798.072308430652, 5819.970900145504, 5827.096799996008, 5764.279465066867, 5813.912791308123, 5831.708414579265, 5851.941480585193, 5873.972054890388, 5179.274901513772, 5267.130923397634, 5277.7888884444765, 5291.629585928987, 5332.466753324676, 5147.588192944556, 5235.11003129467, 5244.134717771563, 5247.763850626712, 5253.0544501989625, 5254.238135470355, 5256.842294731145, 5264.447233040533, 5266.7366631668265, 5276.023935571931, 4440.934175797817, 4857.951420485785, 5192.818251361185, 5220.895582088375, 5241.5020573045695, 3860.7104467164795, 4370.628496577795, 5099.566536844372, 5264.184051472028, 5300.840974770763, 5731.426857314277, 5763.6541807491685, 5764.452377024218, 5782.97108514459, 5804.651720896717, 6119.908201376983, 6142.96928515359, 6268.059791031336, 6283.654399008053, 6315.179026726542, 6226.688665566703, 6254.343293954114, 6257.968710156466, 6264.655443950581, 6280.748770049193, 5878.559108066912, 5884.999999999957, 5896.528277737771, 5911.4975227105315, 5922.7927022554, 6222.968885155636, 6244.12582238487, 6335.619862808247, 6344.6827658616885, 6345.302016778151, 6159.19930409044, 6245.5628106033055, 6277.591956522811, 6283.994560870239, 6291.591046582049, 6249.437550620375, 6268.717907694074, 6270.592411493239, 6277.24673039229, 6286.811395658137, 6124.777645458507, 6182.474489668429, 6236.719347629368, 6259.71831267594, 6288.9056664150075, 6215.471684906744, 6232.781852635136, 6242.344553821874, 6267.373262673696, 6285.497160227171, 4878.829240976549, 4975.179095449283, 4981.551660350586, 4984.5513903748115, 4996.050750357421, 3620.909477263037, 4392.363107349481, 4416.84541041062, 4420.801063952065, 4498.212812757754, 5842.970785146047, 5924.555658325557, 5940.851478713067, 5964.373740757244, 5970.791022314198, 6271.529635277372, 6281.497480201573, 6283.7800676976085, 6285.968570157212, 6294.181756371734, 5711.914321285184, 5806.912896306559, 5875.148103524965, 5887.4701276884425, 5895.35151133375, 5425.213344065168, 5433.891322173614, 5434.8913021739345, 5438.5377242934, 5439.510444059974, 6250.562460627894, 6385.616862988236, 6408.679566021773, 6417.871642567123, 6837.0, 6160.969195153995, 6193.225846769154, 6235.563510554218, 6254.6247225166635, 6263.561550691408, 6407.711652975534, 6437.581557198767, 6447.838804029937, 6465.676716164267, 6473.676316184266, 5799.623024503394, 5857.853553661193, 6255.56211065221, 6261.311255761953, 6280.497560195265, 6205.162303089206, 6216.409441103088, 6228.065790131516, 6257.2491301044165, 6289.716962736779, 6172.6913654318005, 6215.782447614311, 6236.0, 6241.812745617639, 6268.216472940882, 4497.977510112429, 6065.6049108704365, 6073.210482637229, 6221.377862213742, 6242.063690446469, 4961.6030717543235, 5926.377730338252, 5961.314448838439, 6108.281362586102, 6121.785737499166, 6099.420555047263, 6102.694865256808, 6109.3890610940325, 6118.47992920589, 6147.0, 5927.31835838884, 6102.420270074336, 6111.022236442148, 6120.908186377119, 6126.571140020156], "value": 5716.719506726129, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.7613697699061, 525.4955242966752, 562.791767046193, 577.7400169923533, 689.8169639068996, 35670.0, 38033.61966380335, 38136.0, 38157.23685526291, 38192.618073819256, 38627.61372386282, 39114.60885391152, 39257.41113883294, 39297.41053884194, 39340.409893851545, 9305.674301399453, 9519.71440856772, 9533.285003624722, 9538.189253913426, 9554.378965367263, 8039.5176289422825, 9457.432554046745, 9504.192143667797, 9589.76025599362, 9625.807483850318, 8024.317932975704, 8387.370947178955, 9563.952180239088, 9633.133018028377, 9681.0, 9540.475273859935, 9637.0, 9637.132658060773, 9640.710778676581, 9640.95179524105, 9492.430454172703, 9509.381890177116, 9600.183984361267, 9623.51882405884, 9633.710988670351, 38312.23375532502, 38503.229935401134, 38504.61495385038, 38695.613043869766, 38899.805500972594, 3443.276911848515, 3492.807895565743, 3720.7023438124884, 3815.294170578452, 3892.260470510594, 24071.676057816814, 27624.033158839346, 28390.85804570964, 28546.429071418454, 29153.854230728924, 2773.611694362791, 2808.69104398516, 2811.8312901225786, 2814.493391189585, 2893.8263704177607, 473722.0, 478431.0, 479638.9999999965, 479652.0, 480514.0, 61257.08114378288, 61708.691456543325, 61770.0, 61842.0723689145, 61871.0, 67928.94319755623, 68538.65730671315, 69517.0, 69574.30425695678, 69590.65204673944, 14634.853651463562, 17104.0, 17484.825151748573, 17486.21312040961, 17656.470305890845, 13349.0, 13417.194968301934, 13466.394012269471, 13485.662858428617, 13530.661733456545, 19298.32455864038, 19719.704204436945, 19741.60516789685, 19786.802131978497, 19851.503712407015, 8165.591720414074, 8171.63227654757, 8196.180381961756, 8262.339012878956, 8483.109273526314, 1010.3584224017654, 1054.9736256593646, 1064.5954537275786, 1108.6729414822646, 1112.942704430794, 29613.0, 29983.0, 30025.699743002726, 30135.246618834706, 30142.54786178209, 1128.3681138562267, 1156.8900954409319, 1192.7554851255472, 1203.891649751509, 1221.1207930290177, 12742.80885786714, 14931.70136597262, 15588.610284742745, 15861.52415427539, 16170.919145404197, 617385.0, 626507.0, 635613.0, 637342.0, 637447.0, 58615.70692146597, 58816.11775823366, 58961.70519147377, 59198.0, 59746.50633734191, 1308.7447947650307, 1488.4864721671006, 1504.3606467251375, 1515.9469418570518, 1517.4006267524267, 29353.266168345963, 29437.705622943497, 29570.852145739133, 29937.700622993492, 29945.251368715955, 51249.74375128101, 51387.0, 51559.96880062453, 51905.48094519158, 52170.21744673757, 602097.0, 606302.0, 608242.0, 608911.0, 609289.0, 21580.568388631826, 21766.89116554407, 21921.78078219261, 24733.38166545779, 25358.492830143663, 42401.78799105985, 42748.358774619024, 44163.77918110389, 44677.0, 44835.0, 31044.137351758967, 35739.0, 36187.09532261715, 36249.637503624625, 36740.0, 30228.697713023466, 36324.63675363213, 36647.26705465929, 36728.81635591805, 37120.628793711716, 30140.0, 35434.645653543135, 35661.64162473584, 35712.10719732028, 36261.27477450489, 30163.698363016967, 31335.903243386372, 31394.52908206333, 31415.371692565564, 31485.0, 20632.793672063086, 23479.88260058757, 24057.75942240555, 24375.51248974975, 24454.755452445246], "value": 19795.01426698113, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.5576010628204, 401.51416785689315, 401.87541862022795, 405.1026975249822, 413.0, 20299.1880324787, 20646.48383790406, 20932.790672093295, 20933.267335643235, 21892.12431502742, 21676.783232167672, 21892.671609925863, 21959.0, 22101.115955361805, 22170.88914555429, 5764.855878603026, 5781.653100813944, 6032.849178770522, 6043.305019922721, 6078.969605151968, 5825.592208545403, 5825.737841797109, 5867.853303667421, 5898.380670029649, 5956.374580669032, 5272.657277276984, 5316.654417462872, 5334.839954801362, 5339.332583427072, 5914.645121292736, 5010.473900240477, 5764.711764411783, 5802.448767367136, 5890.293164820249, 5908.231929849114, 5104.515071068243, 5112.616553758446, 5203.219517072469, 5205.349331333583, 5225.059489291926, 12912.741745165044, 13283.535076272285, 13536.526221582295, 26225.344366390993, 27038.5944210837, 2125.3836387447623, 2164.729408823897, 2181.934541963728, 2237.8433509654333, 2250.0999600159903, 9944.453055081967, 10021.148202402805, 10091.192704583616, 10233.181345492343, 10240.129588984944, 1609.4769200010044, 1735.6962531557053, 1764.2942822870955, 1853.379117995473, 1989.621971825363, 105575.0, 114844.0, 118421.40789296085, 134136.0, 141110.0, 21562.353129406143, 21912.342629721134, 22143.0, 22575.437228528626, 22798.55428191487, 33666.49500257498, 36321.818390908404, 36362.63637363645, 36679.08302292464, 37522.0, 10669.359838409566, 11004.119670426348, 11617.0, 11815.814074396265, 11855.881441185651, 9638.566264518113, 9672.75818104539, 9682.515874206261, 9705.6603018894, 9796.804063918824, 10205.336653117673, 10390.48047597617, 10417.895821041844, 10503.579856805642, 10508.527116279633, 3562.2163124112653, 3935.5670876203594, 4116.485439320085, 4165.521514698749, 4216.683748718859, 886.8980067292215, 921.2629896083107, 984.9704508864602, 1041.4792603698152, 1048.6067724603274, 14549.636259093608, 16939.23773430174, 20087.598248034956, 20892.353392677614, 21172.25897093625, 945.3807755919884, 947.3226642950324, 971.019270536763, 982.6462473509531, 984.7095106943469, 11007.284526505751, 11225.775484490428, 11241.269317494336, 11269.436528173559, 11274.54901803935, 561970.1901490466, 582259.0, 586420.0, 590088.0, 640264.0, 103208.0, 104265.47867260616, 115150.0, 117447.0, 123537.0, 1019.8572199892021, 1032.4579595712162, 1032.7624646331385, 1041.833306670929, 1052.5737076484036, 18078.153108135015, 18344.816551834843, 18425.355112570996, 18567.071646417357, 18646.067696614842, 26337.868310658323, 32017.87937422179, 32390.67609323971, 52651.47348526466, 52672.68318292074, 69326.65336673486, 69429.0, 72467.55064898777, 73607.0, 74628.0, 8346.290565301775, 8459.915400845914, 8504.574771261536, 8573.828523429622, 8580.957095214484, 11446.427678616203, 12365.258084514959, 12602.621921342374, 12727.045471589483, 12951.805722913976, 10666.89333106659, 10966.506507207036, 11006.614768483065, 11078.947499987487, 11208.943955280172, 10832.566697332333, 10927.83608245861, 10979.1216702662, 11044.502997364978, 11068.778624427627, 10732.765731940926, 10908.400037997986, 10940.234183607072, 11311.0, 11439.313641181556, 8293.0, 8323.959505061866, 8429.988401391873, 8490.06609272967, 8640.481571105754, 10573.471326433493, 10676.62631807883, 10862.674119776419, 11080.05819505351, 11352.240402737589], "value": 10327.949353740321, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1375.924324162171, 1588.0630428047452, 1808.6653969015733, 1946.600946805904, 1987.1455274232073, 7883.6846526139025, 8544.316454683625, 8685.73942781716, 8916.286697064235, 8926.86609700855, 8643.394962352622, 8710.128987101303, 8717.7820554486, 8791.47251164929, 8802.515861627608, 2451.4851881104946, 2469.469064151207, 2475.282168171228, 2479.2562231330617, 2481.4416756229884, 2415.613501839706, 2493.2021753039035, 2495.950080998379, 2501.187114187896, 2505.6116301973225, 2450.3384086296687, 2458.7418321076298, 2491.700995880497, 2492.9127480538186, 2497.8001759859167, 2460.729319774823, 2472.196536125757, 2485.639582260571, 2488.539620170256, 2491.215267190847, 2441.316431399211, 2462.544429280571, 2474.134053081425, 2478.628205769131, 2483.788877945377, 8845.557722113868, 8902.910970890272, 8903.0, 8916.331275154389, 8921.55392230386, 5440.510354068153, 5536.667799932017, 5577.665340079568, 5606.747696353674, 5663.348714897757, 3486.0761898097026, 3591.3894637911358, 3597.388443964532, 3617.7105831533413, 3668.1563240454843, 1058.9894101059044, 1072.399456304472, 1073.849661047454, 1075.1022895881947, 1077.504347999921, 9948.602055917754, 11087.0, 12001.87998120025, 12183.90344868966, 12208.877911220863, 3744.812759362021, 4168.291390463629, 4801.1117943179925, 4942.061008408391, 4966.850994470172, 10072.0, 10177.745556361002, 10207.030332118436, 10215.795684086277, 10221.9488902555, 5471.480209380103, 5491.176323551419, 5522.364928033247, 5541.667499950016, 5565.7217139143695, 4418.8453404130705, 4768.904621907543, 4838.95161048392, 4882.5605695487575, 5023.070731914534, 4074.5925407459017, 4099.418026410338, 4143.854965076268, 4148.937765933513, 4163.770992595436, 1361.5779108476206, 1370.8971827113007, 1374.2647683489286, 1382.7580173469603, 1385.0927642394047, 928.2713070239829, 942.9580313753341, 945.1493655709916, 949.8955114937352, 979.6228452045871, 5775.104858746901, 5785.363610002896, 5840.0, 5916.497097746654, 5970.104484327298, 437.8288079387625, 456.1401757686718, 464.9395578574831, 472.9810807567659, 485.2018429683222, 3326.3014766899223, 3885.630865067814, 4030.093229023505, 4031.475908131924, 4080.4083407906282, 10576.947115264375, 10763.19276054299, 10925.781484370267, 10954.0, 11012.284201526874, 10631.149508039338, 10738.838917416246, 10778.838317425245, 10830.0, 10843.07833834117, 8316.958415208006, 8366.874496882552, 8445.57772111392, 8532.104129066483, 8647.221750042527, 5610.691411972381, 5674.546036317167, 5683.687397193194, 5768.192453056491, 5769.249997500466, 9497.810043799223, 9609.279304052365, 9635.759106022406, 9691.418514889128, 9708.417494950325, 9577.042295770367, 9980.101790838859, 10075.244356673426, 10076.899231007596, 10106.646267380607, 6707.1616047994, 7179.964100179465, 7392.260773922565, 7439.851202975802, 7536.811579710551, 8206.712765053195, 8218.712345067894, 8252.669893204205, 8269.131741167092, 8278.875816862632, 7217.422606191387, 7338.119425668954, 7400.851982960418, 7493.887591686238, 7502.437317201341, 7229.49393542447, 7260.782176534713, 7308.598027108347, 7367.1527774304495, 7417.8887316689215, 6959.826004349932, 7003.26465721092, 7032.26161253061, 7037.401820845285, 7057.153141623038, 4751.429828420473, 4780.450248221499, 4795.880102997453, 4861.076395484846, 4873.975630121827, 4822.565969062801, 4833.879153021062, 4866.391701037371, 4926.211806111005, 5036.370453693288], "value": 4560.727188789301, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.24172163966637, 329.69009131416465, 333.65466741922114, 335.0451214039986, 339.1707275710888, 6743.527953043283, 6758.966205168979, 6772.8306792330095, 6781.457483401328, 6787.592744435331, 6732.427743641784, 6780.084563637633, 6780.660966951657, 6794.0, 6810.3189681032, 4698.201305778018, 4807.447143578481, 4853.102176097415, 4892.339534162896, 4918.262260660894, 4179.310413781723, 4875.488073752258, 4911.386076740408, 4911.975440122795, 4922.8030878764985, 4877.634177436688, 4878.585320247766, 4880.292357608145, 4885.2427873679635, 4902.411710594733, 3955.9604403956023, 3956.742811717258, 4833.202521583935, 4880.316755654211, 4924.06442775875, 4238.936415953733, 4242.469691288589, 4254.765987870666, 4272.658187345005, 4290.806913688892, 6260.154879091356, 6515.804525864231, 6548.246951600579, 6560.770373036921, 6588.604683718992, 5777.335606405233, 5783.884322313572, 5791.247137872092, 5791.913121303184, 5843.18195452637, 5124.308218390507, 6757.412008178074, 7058.54119482232, 7224.566526008456, 7232.8553428931655, 4621.69958952667, 4651.860444186646, 4653.86038418848, 4654.0924519718665, 4660.86017419478, 5867.237259156283, 7608.277213664693, 7637.69449222036, 7656.9234307657325, 7672.0409948756405, 4167.708260421741, 4199.160167966419, 4678.929816052762, 4679.485256621769, 4682.2508398656055, 4716.221823399169, 4747.525247475225, 4751.097291514601, 4759.214729569651, 4775.403074615673, 4549.54504549549, 4860.784803799051, 5363.141897296414, 5651.745671444794, 5679.687617181012, 6772.966135169293, 7178.102737157856, 7209.711611535585, 7235.421166306788, 7248.456365772483, 1448.5364683301243, 1664.9167541622871, 1665.1341302522621, 1670.0397271568897, 1670.6491636756175, 1023.3041531758475, 1052.2949623752104, 1066.0991462214417, 1068.2095249515303, 1072.9141668666489, 6664.933350666528, 6690.197176338773, 6724.991251312343, 6728.966355168291, 6757.526973111835, 7256.528325658815, 7498.800671623908, 7503.924960750432, 7523.699052037857, 7528.585927774025, 6648.5346025777735, 6802.489813264025, 6850.38346548803, 6858.279880612564, 6859.725610975505, 3950.881473555798, 3982.7809470478824, 3989.5212574491247, 3992.3612222044335, 3997.060690737674, 7942.523448593103, 7943.801404964922, 7949.523028618186, 7952.761417157494, 7967.04395472547, 7614.0, 7799.766007019798, 7813.687452501837, 7832.960835195788, 7947.086085100172, 6431.810115128623, 7989.600519974094, 8046.718364857202, 8062.274395304334, 8064.354851611857, 7606.429517786299, 7608.429367797326, 7617.619119044137, 7640.961795190989, 7648.08223013242, 6620.536562440776, 6895.4138898194215, 7475.4019678426685, 7481.925180748341, 7513.173550909505, 7723.6138193089, 8002.839943200987, 8007.799805004922, 8009.038915330198, 8027.357811375193, 4707.8587642370785, 4731.243001119804, 4760.333553302471, 4767.856964291077, 4777.283407488904, 7529.134149572869, 7547.547147171187, 7548.962255188689, 7550.811229719301, 7614.619269036415, 6600.702968366531, 7284.599347035964, 7289.234630363735, 7294.562326260441, 7328.633568321456, 7094.579799424754, 7248.166460857069, 7295.52579082358, 7296.671649775667, 7314.232005639544, 7078.964605176941, 7103.609301488261, 7109.964450177716, 7115.53749006313, 7141.178764442156, 6508.219013718384, 6892.0, 7299.598522081336, 7300.598467084362, 7400.222976587596, 6795.082663840516, 7129.964350178423, 7131.0729605151, 7131.8930216045765, 7187.065681461377], "value": 5222.740879585804, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [674.7570874485186, 705.4814711187273, 727.8653449111914, 743.5204293230867, 776.9533827970321, 15011.0, 15288.464903728373, 15341.23293835308, 15353.462628807994, 15360.07839529629, 13926.930365348184, 14127.858721412782, 14282.928585357085, 14296.285185740697, 14306.284685765722, 714.9714011439536, 893.9597718102701, 904.6291020681522, 920.516728717422, 935.854942483916, 920.8710780490736, 921.1157289002566, 922.8246633139715, 923.3859483443536, 924.5100096948628, 964.5370222293303, 969.8351280282334, 971.3346357744967, 976.0629795396393, 980.4460479828874, 963.802420503795, 966.3573723473952, 967.2503809547586, 970.6602689058843, 972.4408465132542, 679.4428568573771, 692.6848284030754, 693.6670398208888, 694.0179645801185, 712.9679164437613, 14838.480653177086, 14896.106233626017, 14899.851001489955, 14934.850651493454, 14990.0, 12860.356982150855, 12900.0, 13057.804132938014, 13089.083764136514, 13293.73412531744, 9803.950980245125, 9837.508124593813, 10030.297879148462, 10112.797744045152, 10182.898171018343, 3506.8421921013614, 3517.4723791431234, 3519.0674471265133, 3627.1838836261895, 4285.442892423996, 59082.0, 79564.80652790212, 80002.59998700027, 80847.5957620214, 81810.0, 9308.069193080637, 9399.859002114974, 9470.100340467645, 9542.0, 9699.951500242454, 20730.792692072886, 20758.688619670716, 20763.273285434938, 20765.480862978246, 20791.58416831655, 7378.077740282465, 7431.256874312634, 7478.326950574366, 7503.0, 7607.277308655669, 3402.8979130625626, 3492.1880042299604, 4029.7582145071387, 4100.6719462443525, 4105.507339119265, 3617.909552261162, 3632.3461776879885, 3680.687141592941, 3690.55713314404, 3698.278835627027, 631.9810405687745, 663.2372771313029, 668.108075718911, 669.0666520204288, 674.8987651852163, 552.7678375082475, 561.3796754586206, 600.2886579403421, 606.3875485759414, 609.5062998970759, 8146.83706325882, 8290.212429819157, 8402.41183117176, 8404.747857564284, 8413.284870786052, 413.8965258685329, 583.7723287917715, 599.1701493431628, 620.2618883528677, 640.4268179978893, 3897.590752970954, 3927.998360418075, 3944.3097457944746, 3953.802309884552, 4039.4950631171105, 93679.0, 94512.52743736237, 95049.0, 96350.51824740971, 96867.51566242265, 39712.00719982023, 39875.211854874324, 39876.40185397222, 54260.0, 84374.57812710897, 72040.63979680068, 72714.27285727076, 73334.79995600248, 73433.0, 74462.0, 7859.410544209093, 7877.724279650185, 7953.085395179628, 7969.6015199240965, 8064.919350806417, 47182.0, 47837.0, 48481.03037939151, 48559.0, 48837.0, 63031.6848415755, 69970.30029699639, 70247.64876175586, 70962.64518677373, 71348.57302854018, 9000.054994225513, 9960.501974901372, 10035.799284014425, 10144.644937427154, 10314.174866010851, 9429.632703258127, 10017.39895606266, 10785.676429707122, 10786.73033174177, 10851.511681974172, 10261.74345641365, 10286.125679317342, 10354.637587684394, 10591.417472039111, 10710.089642380484, 10300.536475858455, 10455.163586912877, 10530.210234232616, 10688.866799204772, 10775.872800880383, 9701.272404569545, 10143.54354054084, 10387.480625968521, 10531.05220530156, 10572.682819515427, 8645.351598630003, 8933.28533717314, 9096.727098187064, 9158.221551167959, 9206.631734730803, 8939.687110951085, 8980.506072166094, 9002.63989440415, 9064.365494415328, 9072.455652660597], "value": 6722.690328754095, "unit": "score"}], "name": "dromaeo_css", "value": 7563.192368776964, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 120.0, 108.0, 124.0, 138.0, 119.0, 106.0, 117.0, 117.0, 115.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 122.0, 157.0, 151.0, 135.0, 173.0, 166.0, 153.0, 168.0, 124.0], "value": 149.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 199.0, 193.0, 194.0, 232.0, 256.0, 194.0, 194.0, 194.0, 201.0], "value": 205.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 84.0, 141.0, 57.0, 58.0, 69.0, 119.0, 162.0, 82.0], "value": 91.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 80.0, 121.0, 76.0, 79.0, 80.0, 114.0, 79.0, 79.0, 121.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 83.0, 132.0, 135.0, 83.0, 111.0, 132.0, 77.0, 87.0], "value": 99.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 90.0, 151.0, 100.0, 150.0, 103.0, 152.0, 150.0, 113.0, 94.0], "value": 119.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 90.0, 88.0, 95.0, 90.0, 93.0, 93.0, 91.0, 90.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 65.0, 66.0, 66.0, 66.0, 66.0, 82.0, 64.0, 60.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 69.0, 74.0, 75.0, 66.0, 66.0, 67.0, 92.0, 61.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 76.0, 88.0, 92.0, 104.0, 98.0, 81.0, 85.0, 89.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 128.0, 144.0, 118.0, 145.0, 151.0, 135.0, 144.0, 185.0, 152.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 165.0, 167.0, 169.0, 168.0, 173.0, 166.0, 164.0, 170.0], "value": 168.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 65.0, 66.0, 68.0, 68.0, 72.0, 89.0, 100.0, 73.0], "value": 73.6, "unit": "score"}], "name": "kraken", "value": 1575.3999999999996, "alertThreshold": 2.0}]} 18:00:42 INFO - Return code: 0 18:00:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:00:42 ERROR - # TBPL SUCCESS # 18:00:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:00:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:00:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:00:42 INFO - 2016-05-23 18:00:42.542 screenresolution[1879:11113] starting screenresolution argv=screenresolution get 18:00:42 INFO - 2016-05-23 18:00:42.544 screenresolution[1879:11113] Display 0: 1600x1200x32@60 18:00:42 INFO - 2016-05-23 18:00:42.550 screenresolution[1880:11116] starting screenresolution argv=screenresolution list 18:00:42 INFO - Available Modes on Display 0 18:00:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:00:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:00:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:00:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:00:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:00:42 INFO - Intel Iris: 18:00:42 INFO - Chipset Model: Intel Iris 18:00:42 INFO - Type: GPU 18:00:42 INFO - Bus: Built-In 18:00:42 INFO - VRAM (Dynamic, Max): 1536 MB 18:00:42 INFO - Vendor: Intel (0x8086) 18:00:42 INFO - Device ID: 0x0a2e 18:00:42 INFO - Revision ID: 0x0009 18:00:42 INFO - Displays: 18:00:42 INFO - Display: 18:00:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:00:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:00:42 INFO - Display Serial Number: 1600x1200 60 18:00:42 INFO - Main Display: Yes 18:00:42 INFO - Mirror: Off 18:00:42 INFO - Online: Yes 18:00:42 INFO - Rotation: Supported 18:00:42 INFO - Return code: 0 18:00:42 INFO - Running post-action listener: _resource_record_post_action 18:00:42 INFO - Running post-run listener: _resource_record_post_run 18:00:43 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 31555072; Write bytes: 662715904; Read time: 193; Write time: 1987 18:00:43 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346833408; Write bytes: 347658240; Read time: 26518; Write time: 757 18:00:43 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 29793792; Write bytes: 305100288; Read time: 180; Write time: 1194 18:00:43 INFO - Running post-run listener: _upload_blobber_files 18:00:43 INFO - Blob upload gear active. 18:00:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:00:43 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 18:00:43 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'] 18:00:43 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 18:00:44 INFO - (blobuploader) - INFO - Open directory for files ... 18:00:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:00:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:00:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:00:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:00:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:00:45 INFO - (blobuploader) - INFO - Done attempting. 18:00:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:00:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:00:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:00:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:00:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:00:46 INFO - (blobuploader) - INFO - Done attempting. 18:00:46 INFO - (blobuploader) - INFO - Iteration through files over. 18:00:46 INFO - Return code: 0 18:00:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:00:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:00:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36082ac226d28cc00698ae6384bbb0eb9fca5fc5a19d28d160c49d94c520ad72dd81f30017a468aaae873995e01f041411f15e5ba140a18762ee5dd3ded1f38f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad87128146db09cabe46eeb953b7f8b0c33dc02e5d65a5fbd6480fe9758a11fbffcf31a7306992dddf31bfaf20618f25277d4f718f7abe9573d0aefb0dcb8d7d"} 18:00:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:00:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:00:46 INFO - Contents: 18:00:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36082ac226d28cc00698ae6384bbb0eb9fca5fc5a19d28d160c49d94c520ad72dd81f30017a468aaae873995e01f041411f15e5ba140a18762ee5dd3ded1f38f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad87128146db09cabe46eeb953b7f8b0c33dc02e5d65a5fbd6480fe9758a11fbffcf31a7306992dddf31bfaf20618f25277d4f718f7abe9573d0aefb0dcb8d7d"} 18:00:46 INFO - Running post-run listener: copy_logs_to_upload_dir 18:00:46 INFO - Copying logs to upload dir... 18:00:46 INFO - mkdir: /builds/slave/test/build/upload/logs 18:00:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1291.102352 ========= master_lag: 0.38 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 31 secs) (at 2016-05-23 18:00:47.015397) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 18:00:47.019070) ========= 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.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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/36082ac226d28cc00698ae6384bbb0eb9fca5fc5a19d28d160c49d94c520ad72dd81f30017a468aaae873995e01f041411f15e5ba140a18762ee5dd3ded1f38f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad87128146db09cabe46eeb953b7f8b0c33dc02e5d65a5fbd6480fe9758a11fbffcf31a7306992dddf31bfaf20618f25277d4f718f7abe9573d0aefb0dcb8d7d"} build_url:https://queue.taskcluster.net/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011055 build_url: 'https://queue.taskcluster.net/v1/task/CQ2AAK2WTGGNhYzMMf3P2Q/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/36082ac226d28cc00698ae6384bbb0eb9fca5fc5a19d28d160c49d94c520ad72dd81f30017a468aaae873995e01f041411f15e5ba140a18762ee5dd3ded1f38f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad87128146db09cabe46eeb953b7f8b0c33dc02e5d65a5fbd6480fe9758a11fbffcf31a7306992dddf31bfaf20618f25277d4f718f7abe9573d0aefb0dcb8d7d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 18:00:47.051207) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:00:47.051783) ========= 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.m4vbcyMdlb/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.fIsZ5k2mrj/Listeners TMPDIR=/var/folders/2g/ml3_1wdx5nq793p95g6sr6tw00000w/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.005075 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:00:47.103635) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 18:00:47.104308) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 18:00:47.105011) ========= ========= Total master_lag: 0.51 =========