builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0090 starttime: 1462922320.93 results: success (0) buildid: 20160510151907 builduid: 734dcb5e781e43bba4b18c7eb1d0f7da revision: ee562525573f8896fe4f7a5ac053de3d97ae4ccb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 16:18:40.930318) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 16:18:40.930743) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 16:18:40.931028) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 16:18:40.950937) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 16:18:40.951219) ========= 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.6SVJ1CG7kr/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.PRlpTHwleV/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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-10 16:18:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.01M=0.001s 2016-05-10 16:18:41 (8.01 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.163626 ========= master_lag: 0.18 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 16:18:41.293976) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 16:18:41.294284) ========= 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.6SVJ1CG7kr/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.PRlpTHwleV/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.019871 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 16:18:41.332202) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 16:18:41.332504) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ee562525573f8896fe4f7a5ac053de3d97ae4ccb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ee562525573f8896fe4f7a5ac053de3d97ae4ccb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6SVJ1CG7kr/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.PRlpTHwleV/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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-10 16:18:41,412 truncating revision to first 12 chars 2016-05-10 16:18:41,412 Setting DEBUG logging. 2016-05-10 16:18:41,412 attempt 1/10 2016-05-10 16:18:41,412 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ee562525573f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 16:18:42,133 unpacking tar archive at: mozilla-central-ee562525573f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.968112 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 16:18:42.319881) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 16:18:42.320161) ========= script_repo_revision: ee562525573f8896fe4f7a5ac053de3d97ae4ccb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 16:18:42.320531) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 16:18:42.323838) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 16:18:42.341127) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-10 16:18:42.341388) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6SVJ1CG7kr/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.PRlpTHwleV/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 16:18:42 INFO - MultiFileLogger online at 20160510 16:18:42 in /builds/slave/test 16:18:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:18:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:18:42 INFO - {'append_to_log': False, 16:18:42 INFO - 'base_work_dir': '/builds/slave/test', 16:18:42 INFO - 'blob_upload_branch': 'Firefox', 16:18:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:18:42 INFO - 'branch': 'Firefox', 16:18:42 INFO - 'buildbot_json_path': 'buildprops.json', 16:18:42 INFO - 'config_files': ('talos/mac_config.py',), 16:18:42 INFO - 'default_actions': ('clobber', 16:18:42 INFO - 'read-buildbot-config', 16:18:42 INFO - 'download-and-extract', 16:18:42 INFO - 'populate-webroot', 16:18:42 INFO - 'create-virtualenv', 16:18:42 INFO - 'install', 16:18:42 INFO - 'run-tests'), 16:18:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:18:42 INFO - 'download_minidump_stackwalk': True, 16:18:42 INFO - 'download_symbols': 'ondemand', 16:18:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:18:42 INFO - 'tooltool.py': '/tools/tooltool.py', 16:18:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:18:42 INFO - '/tools/misc-python/virtualenv.py')}, 16:18:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:18:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:18:42 INFO - 'installer_path': 'installer.exe', 16:18:42 INFO - 'log_level': 'info', 16:18:42 INFO - 'log_name': 'talos', 16:18:42 INFO - 'log_to_console': True, 16:18:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:18:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:18:42 INFO - 'opt_config_files': (), 16:18:42 INFO - 'pip_index': False, 16:18:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:18:42 INFO - 'cmd': ('bash', 16:18:42 INFO - '-c', 16:18:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:18:42 INFO - 'enabled': True, 16:18:42 INFO - 'halt_on_failure': False, 16:18:42 INFO - 'name': 'check_screen_resolution'},), 16:18:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:18:42 INFO - 'cmd': ('bash', 16:18:42 INFO - '-c', 16:18:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:18:42 INFO - 'enabled': True, 16:18:42 INFO - 'halt_on_failure': False, 16:18:42 INFO - 'name': 'check_screen_resolution'},), 16:18:42 INFO - 'run_cmd_checks_enabled': True, 16:18:42 INFO - 'sps_profile': False, 16:18:42 INFO - 'sps_profile_interval': 0, 16:18:42 INFO - 'suite': 'dromaeojs', 16:18:42 INFO - 'system_bits': '32', 16:18:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:18:42 INFO - 'title': 't-yosemite-r7-0090', 16:18:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:18:42 INFO - 'use_talos_json': True, 16:18:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:18:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:18:42 INFO - 'work_dir': 'build'} 16:18:42 INFO - ##### 16:18:42 INFO - ##### Running clobber step. 16:18:42 INFO - ##### 16:18:42 INFO - Running pre-action listener: _resource_record_pre_action 16:18:42 INFO - Running main action method: clobber 16:18:42 INFO - rmtree: /builds/slave/test/build 16:18:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:18:42 INFO - Running post-action listener: _resource_record_post_action 16:18:42 INFO - ##### 16:18:42 INFO - ##### Finished clobber step (success) 16:18:42 INFO - ##### 16:18:42 INFO - ##### 16:18:42 INFO - ##### Running read-buildbot-config step. 16:18:42 INFO - ##### 16:18:42 INFO - Running pre-action listener: _resource_record_pre_action 16:18:42 INFO - Running main action method: read_buildbot_config 16:18:42 INFO - Using buildbot properties: 16:18:42 INFO - { 16:18:42 INFO - "project": "", 16:18:42 INFO - "product": "firefox", 16:18:42 INFO - "script_repo_revision": "production", 16:18:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:18:42 INFO - "repository": "", 16:18:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:18:42 INFO - "buildid": "20160510151907", 16:18:42 INFO - "pgo_build": "False", 16:18:42 INFO - "basedir": "/builds/slave/test", 16:18:42 INFO - "buildnumber": 245, 16:18:42 INFO - "slavename": "t-yosemite-r7-0090", 16:18:42 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:18:42 INFO - "platform": "yosemite_r7", 16:18:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:18:42 INFO - "branch": "mozilla-central", 16:18:42 INFO - "repo_path": "mozilla-central", 16:18:42 INFO - "revision": "ee562525573f8896fe4f7a5ac053de3d97ae4ccb", 16:18:42 INFO - "stage_platform": "macosx64", 16:18:42 INFO - "builduid": "734dcb5e781e43bba4b18c7eb1d0f7da", 16:18:42 INFO - "slavebuilddir": "test" 16:18:42 INFO - } 16:18:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 16:18:42 INFO - Running post-action listener: _resource_record_post_action 16:18:42 INFO - ##### 16:18:42 INFO - ##### Finished read-buildbot-config step (success) 16:18:42 INFO - ##### 16:18:42 INFO - ##### 16:18:42 INFO - ##### Running download-and-extract step. 16:18:42 INFO - ##### 16:18:42 INFO - Running pre-action listener: _resource_record_pre_action 16:18:42 INFO - Running main action method: download_and_extract 16:18:42 INFO - mkdir: /builds/slave/test/build/tests 16:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:42 INFO - https://queue.taskcluster.net/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:18:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:18:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:18:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:18:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 16:18:46 INFO - Downloaded 1199 bytes. 16:18:46 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:18:46 INFO - Using the following test package requirements: 16:18:46 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:18:46 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:18:46 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 16:18:46 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:18:46 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 16:18:46 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:18:46 INFO - u'jsshell-mac.zip'], 16:18:46 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:18:46 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 16:18:46 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:18:46 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:18:46 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 16:18:46 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:18:46 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 16:18:46 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:18:46 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 16:18:46 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:18:46 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 16:18:46 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:18: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')]} 16:18:46 INFO - https://queue.taskcluster.net/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:18:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:18:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:18:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:18:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:18:47 INFO - Downloaded 18149626 bytes. 16:18:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:18:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:18:50 INFO - Return code: 0 16:18:50 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:50 INFO - https://queue.taskcluster.net/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:18:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:18:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:18:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:18:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:18:51 INFO - Downloaded 18149626 bytes. 16:18:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:18:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:18:55 INFO - Return code: 0 16:18: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')]} 16:18:55 INFO - https://queue.taskcluster.net/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:18:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:18:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:18:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:18:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:18:56 INFO - Downloaded 10840152 bytes. 16:18:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:18:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:18:56 INFO - Return code: 0 16:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:56 INFO - https://queue.taskcluster.net/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:18:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:18:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:18:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 16:18:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 16:18:59 INFO - Downloaded 109786451 bytes. 16:18:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:18:59 INFO - mkdir: /builds/slave/test/properties 16:18:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:18:59 INFO - Writing to file /builds/slave/test/properties/build_url 16:18:59 INFO - Contents: 16:18:59 INFO - build_url:https://queue.taskcluster.net/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:19:00 INFO - Running post-action listener: _resource_record_post_action 16:19:00 INFO - Running post-action listener: set_extra_try_arguments 16:19:00 INFO - ##### 16:19:00 INFO - ##### Finished download-and-extract step (success) 16:19:00 INFO - ##### 16:19:00 INFO - ##### 16:19:00 INFO - ##### Running populate-webroot step. 16:19:00 INFO - ##### 16:19:00 INFO - Running pre-action listener: _resource_record_pre_action 16:19:00 INFO - Running main action method: populate_webroot 16:19:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:19:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:19:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:19:00 INFO - '--tppagecycles', 16:19:00 INFO - '7'], 16:19:00 INFO - 'tests': ['tsvgm']}}, 16:19:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:19:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:19:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:19:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:19:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:19:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:19:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:00 INFO - 'talos_options': ['--disable-e10s'], 16:19:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:19:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:19:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:00 INFO - 'talos_options': ['--disable-e10s'], 16:19:00 INFO - 'tests': ['damp', 'tps']}, 16:19:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:00 INFO - 'tests': ['damp', 'tps']}, 16:19:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:19:00 INFO - 'tests': ['dromaeo_dom']}, 16:19:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:19:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:19:00 INFO - 'tests': ['a11yr', 16:19:00 INFO - 'ts_paint', 16:19:00 INFO - 'tpaint', 16:19:00 INFO - 'sessionrestore', 16:19:00 INFO - 'sessionrestore_no_auto_restore', 16:19:00 INFO - 'tabpaint']}, 16:19:00 INFO - 'other-e10s': {'tests': ['a11yr', 16:19:00 INFO - 'ts_paint', 16:19:00 INFO - 'tpaint', 16:19:00 INFO - 'sessionrestore', 16:19:00 INFO - 'sessionrestore_no_auto_restore', 16:19:00 INFO - 'tabpaint']}, 16:19:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:19:00 INFO - 'ts_paint', 16:19:00 INFO - 'tpaint', 16:19:00 INFO - 'sessionrestore', 16:19:00 INFO - 'sessionrestore_no_auto_restore', 16:19:00 INFO - 'tabpaint']}, 16:19:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:19:00 INFO - 'ts_paint', 16:19:00 INFO - 'tpaint', 16:19:00 INFO - 'sessionrestore', 16:19:00 INFO - 'sessionrestore_no_auto_restore', 16:19:00 INFO - 'tabpaint']}, 16:19:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:19:00 INFO - 'tests': ['a11yr', 16:19:00 INFO - 'ts_paint', 16:19:00 INFO - 'tpaint', 16:19:00 INFO - 'sessionrestore', 16:19:00 INFO - 'sessionrestore_no_auto_restore', 16:19:00 INFO - 'tabpaint']}, 16:19:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:19:00 INFO - 'tests': ['a11yr', 16:19:00 INFO - 'ts_paint', 16:19:00 INFO - 'tpaint', 16:19:00 INFO - 'sessionrestore', 16:19:00 INFO - 'sessionrestore_no_auto_restore', 16:19:00 INFO - 'tabpaint']}, 16:19:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:19:00 INFO - 'tests': ['tsvgx', 16:19:00 INFO - 'tsvgr_opacity', 16:19:00 INFO - 'tart', 16:19:00 INFO - 'tscrollx', 16:19:00 INFO - 'cart']}, 16:19:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:19:00 INFO - 'tsvgr_opacity', 16:19:00 INFO - 'tart', 16:19:00 INFO - 'tscrollx', 16:19:00 INFO - 'cart']}, 16:19:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:00 INFO - 'talos_options': ['--disable-e10s'], 16:19:00 INFO - 'tests': ['tp5o']}, 16:19:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:00 INFO - 'tests': ['tp5o']}, 16:19:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:00 INFO - 'talos_options': ['--disable-e10s', 16:19:00 INFO - '--xperf_path', 16:19:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:19:00 INFO - 'tests': ['tp5n']}, 16:19:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:00 INFO - 'talos_options': ['--xperf_path', 16:19:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:19:00 INFO - 'tests': ['tp5n']}}, 16:19:00 INFO - 'talos.zip': {'path': '', 16:19:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:19:00 INFO - Running post-action listener: _resource_record_post_action 16:19:00 INFO - ##### 16:19:00 INFO - ##### Finished populate-webroot step (success) 16:19:00 INFO - ##### 16:19:00 INFO - ##### 16:19:00 INFO - ##### Running create-virtualenv step. 16:19:00 INFO - ##### 16:19:00 INFO - Running pre-action listener: _resource_record_pre_action 16:19:00 INFO - Running main action method: create_virtualenv 16:19:00 INFO - Creating virtualenv /builds/slave/test/build/venv 16:19:00 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 16:19:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:19:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:19:00 INFO - Using real prefix '/tools/python27' 16:19:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:19:01 INFO - Installing distribute.............................................................................................................................................................................................done. 16:19:04 INFO - Installing pip.................done. 16:19:04 INFO - Return code: 0 16:19:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:04 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 0x10789da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e2d9dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107741d30>, '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 0x107565db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107738b20>, '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.6SVJ1CG7kr/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.PRlpTHwleV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 16:19:04 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 16:19:04 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 16:19:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6SVJ1CG7kr/Render', 16:19:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:04 INFO - 'HOME': '/Users/cltbld', 16:19:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:04 INFO - 'LOGNAME': 'cltbld', 16:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:04 INFO - 'MOZ_NO_REMOTE': '1', 16:19:04 INFO - 'NO_EM_RESTART': '1', 16:19:04 INFO - 'PAGER': '/bin/cat', 16:19:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:04 INFO - 'PWD': '/builds/slave/test', 16:19:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:04 INFO - 'SHELL': '/bin/bash', 16:19:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PRlpTHwleV/Listeners', 16:19:04 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:19:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:04 INFO - 'USER': 'cltbld', 16:19:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:04 INFO - 'XPC_FLAGS': '0x0', 16:19:04 INFO - 'XPC_SERVICE_NAME': '0', 16:19:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:04 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 16:19:04 INFO - Cleaning up... 16:19:04 INFO - Return code: 0 16:19:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:04 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 0x10789da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e2d9dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107741d30>, '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 0x107565db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107738b20>, '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.6SVJ1CG7kr/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.PRlpTHwleV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 16:19:04 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 16:19:04 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 16:19:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6SVJ1CG7kr/Render', 16:19:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:04 INFO - 'HOME': '/Users/cltbld', 16:19:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:04 INFO - 'LOGNAME': 'cltbld', 16:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:04 INFO - 'MOZ_NO_REMOTE': '1', 16:19:04 INFO - 'NO_EM_RESTART': '1', 16:19:04 INFO - 'PAGER': '/bin/cat', 16:19:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:04 INFO - 'PWD': '/builds/slave/test', 16:19:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:04 INFO - 'SHELL': '/bin/bash', 16:19:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PRlpTHwleV/Listeners', 16:19:04 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:19:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:04 INFO - 'USER': 'cltbld', 16:19:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:04 INFO - 'XPC_FLAGS': '0x0', 16:19:04 INFO - 'XPC_SERVICE_NAME': '0', 16:19:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:04 INFO - Downloading/unpacking psutil>=0.7.1 16:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:19:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:19:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:19:07 INFO - Installing collected packages: psutil 16:19:07 INFO - Running setup.py install for psutil 16:19:07 INFO - building 'psutil._psutil_osx' extension 16:19:07 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 16:19:08 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 16:19:08 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 16:19:08 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 16:19:08 INFO - building 'psutil._psutil_posix' extension 16:19:08 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 16:19:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:19:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:19:08 INFO - ^ 16:19:08 INFO - 1 warning generated. 16:19:08 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 16:19:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:19:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:19:08 INFO - Successfully installed psutil 16:19:08 INFO - Cleaning up... 16:19:08 INFO - Return code: 0 16:19:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:19:08 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')]} 16:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:08 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')]} 16:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:08 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 0x10789da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e2d9dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107741d30>, '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 0x107565db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107738b20>, '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.6SVJ1CG7kr/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.PRlpTHwleV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 16:19:08 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 16:19:08 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 16:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6SVJ1CG7kr/Render', 16:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:08 INFO - 'HOME': '/Users/cltbld', 16:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:08 INFO - 'LOGNAME': 'cltbld', 16:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:08 INFO - 'MOZ_NO_REMOTE': '1', 16:19:08 INFO - 'NO_EM_RESTART': '1', 16:19:08 INFO - 'PAGER': '/bin/cat', 16:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:08 INFO - 'PWD': '/builds/slave/test', 16:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:08 INFO - 'SHELL': '/bin/bash', 16:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PRlpTHwleV/Listeners', 16:19:08 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:08 INFO - 'USER': 'cltbld', 16:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:08 INFO - 'XPC_FLAGS': '0x0', 16:19:08 INFO - 'XPC_SERVICE_NAME': '0', 16:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:19:11 INFO - Installing collected packages: mozsystemmonitor 16:19:11 INFO - Running setup.py install for mozsystemmonitor 16:19:11 INFO - Successfully installed mozsystemmonitor 16:19:11 INFO - Cleaning up... 16:19:11 INFO - Return code: 0 16:19:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:19: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')]} 16:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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')]} 16:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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', '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 0x10789da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e2d9dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107741d30>, '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 0x107565db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107738b20>, '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.6SVJ1CG7kr/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.PRlpTHwleV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 16:19: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:19: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 blobuploader==1.2.4 16:19:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6SVJ1CG7kr/Render', 16:19:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:11 INFO - 'HOME': '/Users/cltbld', 16:19:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:11 INFO - 'LOGNAME': 'cltbld', 16:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:11 INFO - 'MOZ_NO_REMOTE': '1', 16:19:11 INFO - 'NO_EM_RESTART': '1', 16:19:11 INFO - 'PAGER': '/bin/cat', 16:19:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:11 INFO - 'PWD': '/builds/slave/test', 16:19:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:11 INFO - 'SHELL': '/bin/bash', 16:19:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PRlpTHwleV/Listeners', 16:19:11 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:19:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:11 INFO - 'USER': 'cltbld', 16:19:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:11 INFO - 'XPC_FLAGS': '0x0', 16:19:11 INFO - 'XPC_SERVICE_NAME': '0', 16:19:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:11 INFO - Downloading/unpacking blobuploader==1.2.4 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19:14 INFO - Downloading blobuploader-1.2.4.tar.gz 16:19:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:19:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:19:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19:15 INFO - Downloading docopt-0.6.1.tar.gz 16:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:19:15 INFO - Installing collected packages: blobuploader, requests, docopt 16:19:15 INFO - Running setup.py install for blobuploader 16:19:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:19:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:19:15 INFO - Running setup.py install for requests 16:19:16 INFO - Running setup.py install for docopt 16:19:16 INFO - Successfully installed blobuploader requests docopt 16:19:16 INFO - Cleaning up... 16:19:16 INFO - Return code: 0 16:19:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:16 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 0x10789da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e2d9dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107741d30>, '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 0x107565db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107738b20>, '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.6SVJ1CG7kr/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.PRlpTHwleV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 16:19:16 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 16:19:16 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 16:19:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6SVJ1CG7kr/Render', 16:19:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:16 INFO - 'HOME': '/Users/cltbld', 16:19:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:16 INFO - 'LOGNAME': 'cltbld', 16:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:16 INFO - 'MOZ_NO_REMOTE': '1', 16:19:16 INFO - 'NO_EM_RESTART': '1', 16:19:16 INFO - 'PAGER': '/bin/cat', 16:19:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:16 INFO - 'PWD': '/builds/slave/test', 16:19:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:16 INFO - 'SHELL': '/bin/bash', 16:19:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PRlpTHwleV/Listeners', 16:19:16 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:19:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:16 INFO - 'USER': 'cltbld', 16:19:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:16 INFO - 'XPC_FLAGS': '0x0', 16:19:16 INFO - 'XPC_SERVICE_NAME': '0', 16:19:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:19:16 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-qeqoZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:19:16 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-C1B0MJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:19:16 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-xqF9i8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:19:16 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-HzEKv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:19:16 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-k7QJ3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:19:17 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-2VREHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:19:17 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-2cKgVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:19:17 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-hxga_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:19:17 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-a1ZkkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:19:17 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-IEKoSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:19:17 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-gjsA_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:19:17 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-CDH5JC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:19:17 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-HR8zel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:19:17 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-uVvObF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:17 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-jWJEly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:19:18 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-rqzd3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:19:18 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-AALw0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:19:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:19:18 INFO - Running setup.py install for manifestparser 16:19:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:19:18 INFO - Running setup.py install for mozcrash 16:19:18 INFO - Running setup.py install for mozdebug 16:19:18 INFO - Running setup.py install for mozdevice 16:19:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:19:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:19:18 INFO - Running setup.py install for mozfile 16:19:18 INFO - Running setup.py install for mozhttpd 16:19:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:19:19 INFO - Running setup.py install for mozinfo 16:19:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:19:19 INFO - Running setup.py install for mozInstall 16:19:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:19:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:19:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:19:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:19:19 INFO - Running setup.py install for mozleak 16:19:19 INFO - Running setup.py install for mozlog 16:19:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:19:19 INFO - Running setup.py install for moznetwork 16:19:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:19:19 INFO - Running setup.py install for mozprocess 16:19:20 INFO - Running setup.py install for mozprofile 16:19:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:19:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:19:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:19:20 INFO - Running setup.py install for mozrunner 16:19:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:19:20 INFO - Running setup.py install for mozscreenshot 16:19:20 INFO - Running setup.py install for moztest 16:19:20 INFO - Running setup.py install for mozversion 16:19:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:19:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:19:20 INFO - Cleaning up... 16:19:20 INFO - Return code: 0 16:19:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19:20 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')]} 16:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:20 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')]} 16:19:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:20 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 0x10789da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e2d9dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107741d30>, '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 0x107565db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107738b20>, '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.6SVJ1CG7kr/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.PRlpTHwleV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 16:19:20 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 16:19:20 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 16:19:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6SVJ1CG7kr/Render', 16:19:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:20 INFO - 'HOME': '/Users/cltbld', 16:19:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:20 INFO - 'LOGNAME': 'cltbld', 16:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:20 INFO - 'MOZ_NO_REMOTE': '1', 16:19:20 INFO - 'NO_EM_RESTART': '1', 16:19:20 INFO - 'PAGER': '/bin/cat', 16:19:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:20 INFO - 'PWD': '/builds/slave/test', 16:19:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:20 INFO - 'SHELL': '/bin/bash', 16:19:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PRlpTHwleV/Listeners', 16:19:20 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:19:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:20 INFO - 'USER': 'cltbld', 16:19:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:20 INFO - 'XPC_FLAGS': '0x0', 16:19:20 INFO - 'XPC_SERVICE_NAME': '0', 16:19:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:19:21 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-WDWyQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:19:21 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)) 16:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:19:21 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-KbG1a7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:19:21 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)) 16:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:19:21 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Tmh6zB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:19:21 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)) 16:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:19:21 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-_zMoIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:19:21 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)) 16:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:19:21 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-8sZSpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:19:21 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)) 16:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:19:21 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-fB9asf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:19:21 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)) 16:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:19:21 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-48pD8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:19:21 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)) 16:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:19:21 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-HRIyoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:19:21 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)) 16:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:19:21 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-2FRQM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:19:21 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)) 16:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:19:22 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-OOA7BZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:19:22 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)) 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:19:22 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-mCt0pb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:19:22 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)) 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:19:22 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-A169dH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:19:22 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)) 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:19:22 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-nfe0Xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:19:22 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)) 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:19:22 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-FwvdjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:19:22 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)) 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:22 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-WQehh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:22 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)) 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:19:22 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Ih8i_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:19:22 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)) 16:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:19:22 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-tWYg39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:19:22 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)) 16:19:22 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)) 16:19:22 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)) 16:19:22 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)) 16:19:22 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)) 16:19:22 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)) 16:19:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:19:22 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 16:19:22 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 16:19:22 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 16:19:22 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 16:19:22 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 16:19:22 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 16:19:25 INFO - Downloading blessings-1.6.tar.gz 16:19:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:19:25 INFO - Installing collected packages: blessings 16:19:25 INFO - Running setup.py install for blessings 16:19:25 INFO - Successfully installed blessings 16:19:25 INFO - Cleaning up... 16:19:25 INFO - Return code: 0 16:19:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:19:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:19:25 INFO - Reading from file tmpfile_stdout 16:19:25 INFO - Current package versions: 16:19:25 INFO - blessings == 1.6 16:19:25 INFO - blobuploader == 1.2.4 16:19:25 INFO - docopt == 0.6.1 16:19:25 INFO - manifestparser == 1.1 16:19:25 INFO - mozInstall == 1.12 16:19:25 INFO - mozcrash == 0.17 16:19:25 INFO - mozdebug == 0.1 16:19:25 INFO - mozdevice == 0.48 16:19:25 INFO - mozfile == 1.2 16:19:25 INFO - mozhttpd == 0.7 16:19:25 INFO - mozinfo == 0.9 16:19:25 INFO - mozleak == 0.1 16:19:25 INFO - mozlog == 3.1 16:19:25 INFO - moznetwork == 0.27 16:19:25 INFO - mozprocess == 0.22 16:19:25 INFO - mozprofile == 0.28 16:19:25 INFO - mozrunner == 6.11 16:19:25 INFO - mozscreenshot == 0.1 16:19:25 INFO - mozsystemmonitor == 0.0 16:19:25 INFO - moztest == 0.7 16:19:25 INFO - mozversion == 1.4 16:19:25 INFO - psutil == 3.1.1 16:19:25 INFO - requests == 1.2.3 16:19:25 INFO - wsgiref == 0.1.2 16:19:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:25 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 0x10789da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e2d9dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107741d30>, '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 0x107565db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107738b20>, '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.6SVJ1CG7kr/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.PRlpTHwleV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 16:19:25 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 16:19:25 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 16:19:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6SVJ1CG7kr/Render', 16:19:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:25 INFO - 'HOME': '/Users/cltbld', 16:19:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:25 INFO - 'LOGNAME': 'cltbld', 16:19:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:25 INFO - 'MOZ_NO_REMOTE': '1', 16:19:25 INFO - 'NO_EM_RESTART': '1', 16:19:25 INFO - 'PAGER': '/bin/cat', 16:19:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:25 INFO - 'PWD': '/builds/slave/test', 16:19:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:25 INFO - 'SHELL': '/bin/bash', 16:19:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PRlpTHwleV/Listeners', 16:19:25 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:19:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:25 INFO - 'USER': 'cltbld', 16:19:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:25 INFO - 'XPC_FLAGS': '0x0', 16:19:25 INFO - 'XPC_SERVICE_NAME': '0', 16:19:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:26 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)) 16:19:26 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)) 16:19:26 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)) 16:19:26 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)) 16:19:26 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)) 16:19:26 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)) 16:19:26 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)) 16:19:26 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)) 16:19:26 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)) 16:19:26 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)) 16:19:26 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)) 16:19:26 INFO - Cleaning up... 16:19:26 INFO - Return code: 0 16:19:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:26 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 0x10789da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e2d9dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107741d30>, '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 0x107565db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107738b20>, '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.6SVJ1CG7kr/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.PRlpTHwleV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 16:19:26 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 16:19:26 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 16:19:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6SVJ1CG7kr/Render', 16:19:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:26 INFO - 'HOME': '/Users/cltbld', 16:19:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:26 INFO - 'LOGNAME': 'cltbld', 16:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:26 INFO - 'MOZ_NO_REMOTE': '1', 16:19:26 INFO - 'NO_EM_RESTART': '1', 16:19:26 INFO - 'PAGER': '/bin/cat', 16:19:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:26 INFO - 'PWD': '/builds/slave/test', 16:19:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:26 INFO - 'SHELL': '/bin/bash', 16:19:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PRlpTHwleV/Listeners', 16:19:26 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:19:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:26 INFO - 'USER': 'cltbld', 16:19:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:26 INFO - 'XPC_FLAGS': '0x0', 16:19:26 INFO - 'XPC_SERVICE_NAME': '0', 16:19:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:26 INFO - Downloading/unpacking jsonschema 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:19:28 INFO - Downloading/unpacking functools32 (from jsonschema) 16:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:29 INFO - Downloading functools32-3.2.3-2.tar.gz 16:19:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:19:29 INFO - warning: no files found matching '*.txt' 16:19:29 INFO - no previously-included directories found matching 'build' 16:19:29 INFO - no previously-included directories found matching 'dist' 16:19:29 INFO - no previously-included directories found matching '.git*' 16:19:29 INFO - Installing collected packages: jsonschema, functools32 16:19:29 INFO - Running setup.py install for functools32 16:19:29 INFO - warning: no files found matching '*.txt' 16:19:29 INFO - no previously-included directories found matching 'build' 16:19:29 INFO - no previously-included directories found matching 'dist' 16:19:29 INFO - no previously-included directories found matching '.git*' 16:19:29 INFO - Successfully installed jsonschema functools32 16:19:29 INFO - Cleaning up... 16:19:29 INFO - Return code: 0 16:19:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:19: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')]} 16:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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')]} 16:19:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10789da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078a3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92e2d9dd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107741d30>, '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 0x107565db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107738b20>, '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.6SVJ1CG7kr/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.PRlpTHwleV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 16:19: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', 'flake8'] in /builds/slave/test/build 16:19: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 flake8 16:19:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6SVJ1CG7kr/Render', 16:19:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:29 INFO - 'HOME': '/Users/cltbld', 16:19:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:29 INFO - 'LOGNAME': 'cltbld', 16:19:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:29 INFO - 'MOZ_NO_REMOTE': '1', 16:19:29 INFO - 'NO_EM_RESTART': '1', 16:19:29 INFO - 'PAGER': '/bin/cat', 16:19:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:29 INFO - 'PWD': '/builds/slave/test', 16:19:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:29 INFO - 'SHELL': '/bin/bash', 16:19:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PRlpTHwleV/Listeners', 16:19:29 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:19:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:29 INFO - 'USER': 'cltbld', 16:19:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:29 INFO - 'XPC_FLAGS': '0x0', 16:19:29 INFO - 'XPC_SERVICE_NAME': '0', 16:19:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:29 INFO - Downloading/unpacking flake8 16:19:29 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 16:19:29 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 16:19:29 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 16:19:29 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 16:19:29 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 16:19:29 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 16:19:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:19:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:19:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:19:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:19:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:19:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:19:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:19:33 INFO - no previously-included directories found matching 'docs/_build' 16:19:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:19:33 INFO - Running setup.py install for pep8 16:19:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:19:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:19:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:19:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:19:33 INFO - no previously-included directories found matching 'docs/_build' 16:19:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:19:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:19:33 INFO - Cleaning up... 16:19:33 INFO - Return code: 0 16:19:33 INFO - Running post-action listener: _resource_record_post_action 16:19:33 INFO - Running post-action listener: _start_resource_monitoring 16:19:33 INFO - Starting resource monitoring. 16:19:33 INFO - ##### 16:19:33 INFO - ##### Finished create-virtualenv step (success) 16:19:33 INFO - ##### 16:19:33 INFO - ##### 16:19:33 INFO - ##### Running install step. 16:19:33 INFO - ##### 16:19:33 INFO - Running pre-action listener: _resource_record_pre_action 16:19:33 INFO - Running main action method: install 16:19:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:19:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:19:34 INFO - Reading from file tmpfile_stdout 16:19:34 INFO - Detecting whether we're running mozinstall >=1.0... 16:19:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:19:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:19:34 INFO - Reading from file tmpfile_stdout 16:19:34 INFO - Output received: 16:19:34 INFO - Usage: mozinstall [options] installer 16:19:34 INFO - Options: 16:19:34 INFO - -h, --help show this help message and exit 16:19:34 INFO - -d DEST, --destination=DEST 16:19:34 INFO - Directory to install application into. [default: 16:19:34 INFO - "/builds/slave/test"] 16:19:34 INFO - --app=APP Application being installed. [default: firefox] 16:19:34 INFO - mkdir: /builds/slave/test/build/application 16:19:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:19:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:20:07 INFO - Reading from file tmpfile_stdout 16:20:07 INFO - Output received: 16:20:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:20:07 INFO - Running post-action listener: _resource_record_post_action 16:20:07 INFO - ##### 16:20:07 INFO - ##### Finished install step (success) 16:20:07 INFO - ##### 16:20:07 INFO - ##### 16:20:07 INFO - ##### Running run-tests step. 16:20:07 INFO - ##### 16:20:07 INFO - Running pre-action listener: _resource_record_pre_action 16:20:07 INFO - Running main action method: run_tests 16:20:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:20:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:20:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:20:07 INFO - 2016-05-10 16:20:07.948 screenresolution[2287:25509] starting screenresolution argv=screenresolution get 16:20:07 INFO - 2016-05-10 16:20:07.951 screenresolution[2287:25509] Display 0: 1600x1200x32@60 16:20:07 INFO - 2016-05-10 16:20:07.957 screenresolution[2288:25511] starting screenresolution argv=screenresolution list 16:20:07 INFO - Available Modes on Display 0 16:20:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:20:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:20:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:20:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:20:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:20:08 INFO - Intel Iris: 16:20:08 INFO - Chipset Model: Intel Iris 16:20:08 INFO - Type: GPU 16:20:08 INFO - Bus: Built-In 16:20:08 INFO - VRAM (Dynamic, Max): 1536 MB 16:20:08 INFO - Vendor: Intel (0x8086) 16:20:08 INFO - Device ID: 0x0a2e 16:20:08 INFO - Revision ID: 0x0009 16:20:08 INFO - Displays: 16:20:08 INFO - Display: 16:20:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:20:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:20:08 INFO - Display Serial Number: 1600x1200 60 16:20:08 INFO - Main Display: Yes 16:20:08 INFO - Mirror: Off 16:20:08 INFO - Online: Yes 16:20:08 INFO - Rotation: Supported 16:20:08 INFO - Return code: 0 16:20:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:20:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:20:08 INFO - Python 2.7.3 16:20:08 INFO - Return code: 0 16:20:08 INFO - grabbing minidump binary from tooltool 16:20:08 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')]} 16:20:08 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 0x107741d30>, '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 0x107565db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107738b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:20:08 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 16:20:08 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 16:20:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:20:08 INFO - Return code: 0 16:20:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:20:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:20:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:20:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:20:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:20:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:20:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:20:09 INFO - Return code: 0 16:20:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0090 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:20:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6SVJ1CG7kr/Render', 16:20:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:20:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:20:09 INFO - 'HOME': '/Users/cltbld', 16:20:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:20:09 INFO - 'LOGNAME': 'cltbld', 16:20:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:20:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:09 INFO - 'MOZ_NO_REMOTE': '1', 16:20:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:20:09 INFO - 'NO_EM_RESTART': '1', 16:20:09 INFO - 'PAGER': '/bin/cat', 16:20:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:09 INFO - 'PWD': '/builds/slave/test', 16:20:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:20:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:20:09 INFO - 'SHELL': '/bin/bash', 16:20:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PRlpTHwleV/Listeners', 16:20:09 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 16:20:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:20:09 INFO - 'USER': 'cltbld', 16:20:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:20:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:09 INFO - 'XPC_FLAGS': '0x0', 16:20:09 INFO - 'XPC_SERVICE_NAME': '0', 16:20:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:20:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:20:09 INFO - mozversion application_buildid: 20160510151907 16:20:09 INFO - mozversion application_changeset: ee562525573f8896fe4f7a5ac053de3d97ae4ccb 16:20:09 INFO - mozversion application_display_name: Nightly 16:20:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:20:09 INFO - mozversion application_name: Firefox 16:20:09 INFO - mozversion application_remotingname: firefox 16:20:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:20:09 INFO - mozversion application_vendor: Mozilla 16:20:09 INFO - mozversion application_version: 49.0a1 16:20:09 INFO - mozversion platform_buildid: 20160510151907 16:20:09 INFO - mozversion platform_changeset: ee562525573f8896fe4f7a5ac053de3d97ae4ccb 16:20:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:20:09 INFO - mozversion platform_version: 49.0a1 16:20:09 INFO - using testdate: 1462922409 16:20:09 INFO - actual date: 1462922409 16:20:09 INFO - starting webserver on 'localhost:52450' 16:20:09 INFO - SUITE-START | Running 2 tests 16:20:09 INFO - TEST-START | dromaeo_css 16:20:09 INFO - operating with platform_type : mac_ 16:20:09 INFO - Initialising browser for dromaeo_css test... 16:20:09 INFO - TEST-INFO | started process 2306 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpfXYfxl/profile http://localhost:52450/getInfo.html) 16:20:11 INFO - PROCESS | 2306 | __metrics Screen width/height:1600/1200 16:20:11 INFO - PROCESS | 2306 | colorDepth:24 16:20:11 INFO - PROCESS | 2306 | Browser inner width/height: 1024/689 16:20:11 INFO - PROCESS | 2306 | __metrics 16:20:11 INFO - PROCESS | 2306 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:20:11 ERROR - PROCESS | 2306 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:20:12 INFO - TEST-INFO | 2306: exit 0 16:20:12 INFO - Browser initialized. 16:20:12 INFO - Running cycle 1/1 for dromaeo_css test... 16:20:12 INFO - TEST-INFO | started process 2308 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpfXYfxl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:20:24 INFO - PROCESS | 2308 | RSS: Main: 239104000 16:20:24 INFO - PROCESS | 2308 | 16:21:16 INFO - PROCESS | 2308 | 1462922476021 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] 16:23:26 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:52450/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52450/tests/dromaeo/cssquery-ext.html) 16:23:27 INFO - PROCESS | 2308 | RSS: Main: 362995712 16:23:27 INFO - PROCESS | 2308 | 16:26:30 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:52450/tests/dromaeo/cssquery-ext.html (next: http://localhost:52450/tests/dromaeo/cssquery-jquery.html) 16:26:31 INFO - PROCESS | 2308 | RSS: Main: 372088832 16:26:31 INFO - PROCESS | 2308 | 16:29:33 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:52450/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52450/tests/dromaeo/cssquery-mootools.html) 16:29:33 INFO - PROCESS | 2308 | RSS: Main: 370806784 16:29:33 INFO - PROCESS | 2308 | 16:32:36 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:52450/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52450/tests/dromaeo/cssquery-prototype.html) 16:32:36 INFO - PROCESS | 2308 | RSS: Main: 368283648 16:32:36 INFO - PROCESS | 2308 | 16:35:40 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:52450/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52450/tests/dromaeo/cssquery-yui.html) 16:35:40 INFO - PROCESS | 2308 | RSS: Main: 341893120 16:35:40 INFO - PROCESS | 2308 | 16:38:43 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:52450/tests/dromaeo/cssquery-yui.html (next: http://localhost:52450/tests/dromaeo/cssquery-dojo.html) 16:38:43 INFO - PROCESS | 2308 | RSS: Main: 424321024 16:38:43 INFO - PROCESS | 2308 | 16:38:43 INFO - PROCESS | 2308 | __start_tp_report 16:38:43 INFO - PROCESS | 2308 | _x_x_mozilla_page_load 16:38:43 INFO - PROCESS | 2308 | _x_x_mozilla_page_load_details 16:38:43 INFO - PROCESS | 2308 | |i|pagename|runs| 16:38:43 INFO - PROCESS | 2308 | |0;dojo.html;3443.3113377324535;3464.792112473252;3475.669811367921;3482.181687303485;3528.7706299090555;5677.744501497432;5711.600187986843;5715.857103572413;5813.563982701298;5815.825525234244;5766.596338256324;5774.422557744224;5872.73572689229;5887.88224235515;5913.526917846572;4828.396450443694;5162.664426812245;5246.134387826004;5248.71132087735;5348.679079255257;5312.973435132819;5351.357837059539;5364.409914909357;5379.784808607651;5391.892162156754;5135.820246291362;5151.330327057496;5159.993801208766;5212.087884620214;5265.973670131663;4315.762633055181;5204.297419848348;5207.791688332462;5211.895762084775;5282.0756367635895;5201.427842937273;5239.685618862881;5239.711815850127;5255.842324730264;5263.631545791796;5607.747651355658;5641.576881733846;5729.28383952006;5731.799387021476;5736.4550367715;6240.563160578761;6293.087502312139;6314.684265786692;6327.683615819236;6343.429091381798;6280.466160376373;6326.715297811609;6336.556441049129;6341.4292713656;6346.333634968308;5717.228174196472;5790.13148027795;5811.506021988178;5884.852878678067;5890.793822216202;6382.648954307465;6421;6421.711023003976;6427.710753016126;6439.420452159235;5180.870478238075;6249.062640603855;6259.937400626027;6270.843228919313;6276;6180.227471566054;6182.938170618326;6240.157578726905;6248;6314.968425157845;5987.072003839412;6142.355052719489;6150.907736383958;6197.81406557804;6215.409536094064;5180.766865491062;6144.126041557368;6250.874982500324;6253.624782513064;6260.40526150015;4927.28554359623;4947.307376967227;4979.775910084055;4997.675151115239;5010.498950105033;4441.622462090759;4451.621612162938;4466.508684044752;4475.328700694922;4478.104379124123;5674.631148975303;5809.883802324014;5857.20927674767;5862.355140934494;5865.35481097079;6252.718627661766;6259.530535209878;6271.560990730696;6295.150154729053;6313.715882785285;5781.602638823392;5782.971085144632;5795.623284486495;5803.912941305884;5835.2414186156375;5344.625876188629;5458.590605704588;5473.616846820684;5484.8354549363585;5487.314085739283;6321.494280457552;6382.297947225801;6421.068945003042;6430.7749228776765;6521.934780652133;6273.37266273369;6298.08677741734;6315.936840631535;6316.936830631635;6338.271098823509;6255.687215639109;6435.613863168038;6484.221893372825;6520.967395163184;6527.5757075789925;6104.853288833144;6145.924463218829;6173.382661733791;6179.3202747698615;6210.223722034746;6296.181496405438;6298.212723409574;6305.527085468517;6309.873802524015;6312.936870631235;6080.543959202989;6143.017117261216;6173.660448675366;6180.072989051679;6199.690015499297;5970.373110823301;6096.390360963868;6107.947487154961;6130.551011451029;6145.064304743992;6103.603265787887;6108.979583722655;6115.5719099664375;6117.418845209698;6141.692915354125;5985.221921150222;6235.563510554399;6244.344343844013;6250.906236406367;6253.718582663882;5839.124131380157;5980.3421623622235;5982.7008649568215;5987.101934709828;5993.730282137227 16:38:43 INFO - PROCESS | 2308 | |1;ext.html;494.36227266825796;495.7818559833675;497.2963256991357;521.2520033751567;522.0368420264613;31116;32043.67956320436;35941.921742347775;42851.785741071326;43394;42686;42953.57046429535;43170.35244471335;44857.102857942824;44964.77517612415;9694.757631059209;9894.802103957916;9906;9914.504274786268;9914.603415863357;7838.843223135534;11609.651710448657;11900.880991190108;11917.523299068027;12031;11686.766264674701;11762.529498820037;11786.7642647147;11791.941040294787;11794.997425218866;11694.356810375426;11794.35131067791;11817.468213930393;11835.881641183563;11845.466953987005;11794.823077653842;11851.644450666494;11863.288202707865;11889.762204755943;11896.167268291223;40842;41084;41092.58907410917;41241.38137927934;41306.79346603278;3197.936041279185;3211.486162214058;3248.31785325082;3345.8996230112893;3428.5714285714284;27986.86006569954;29170.416591668047;29438.70561294381;30346.696533034607;30977.38045239105;2714.2671478700836;2768.1142034548952;2775.417162395899;2812.0860720265996;2838.630977972871;474228;476800;477746;479648;483647.5817620925;64073.35926640767;64420;64577.354226458076;64734;64811.35188648053;66142;66950.33049669539;67120;67153.32846671568;67192;15757.527274181793;16266.51200463988;16294;16294.837051629569;16303.347866085223;12407.069469789802;12491.000719942384;12506.249625022529;12511.687207819878;12519.749605007848;19460.4161875144;19765;19785.505362366057;19795.901020494806;19824.70262946057;8073;8139.877901831477;8171.9182808172345;8182.558598531909;8425.747227583182;929.6002718830832;1007.450939238117;1020.3418794877263;1023.1712313026323;1070.4166229404993;29166.708332916824;29286.26784330409;29803.85098074496;30050.54924176139;30131.246718831768;920.3189639666598;1017.1557607186018;1055.0609957138154;1152.2798251093068;1177.7938860699344;14420.42318307256;14988.62528436776;15056.924715376354;15175.16536590498;15193.164375959208;666819;669006;676450;676606;679446;60499.39500604937;61064.08403873946;61131.38868611346;61195;61270;1321.8281623389091;1365.8838998685023;1366.43293033394;1480.489231215229;1516.2646116633423;30287.242818929702;30473.390532189675;30922.69077309198;31603.051908442783;32370.190745231557;56516.717416412655;56712.71643641921;57297.14054289106;57369.42630573808;57386.42613573811;571685.1415742894;577977.1101144467;578054;579226.1038694949;579539;23847.64228536608;26478.338041549116;27222.591661125494;27385.726142738316;27765.167044988684;45743.313850292936;46208.30687539757;46417.07165856596;47216.29175562438;47220;29110.7088929108;37551.49794008319;38332.61796148979;38444.61555384522;39017.804910976396;37228.25543489061;37290.62709372872;37547.06132346713;38148.80925595354;38407.80796096002;37599.872003839926;37927.24145517019;38131;38371.04072398212;38582.61417385902;32060.19849503781;33139.005829825146;33513.83243083769;33633.99098027063;33788.32423351568;24794;24898;24925.252242432754;25143.49713005693;25278.368040799127 16:38:43 INFO - PROCESS | 2308 | |2;jquery.html;386.9187470631167;392.69566086283135;394.7276379298286;399.63233824881104;419.3856000958597;14308.499202527888;20202.494937626547;20635.896820515914;21310.041048152852;21351.57296854064;15966.28151733172;18090.185941632626;20381.388558343275;20941.47646308843;20951.05720242589;5805.751763370875;6047.092936059582;6071.210742603477;6086.7869624563145;6106.328303886569;5933.940660593382;5973.193618861442;5987.311459182206;6015.248093988252;6052.394760523956;5826.883462330751;5913.349531551526;5953.3748956359395;5982.820515384546;5997.700114994254;5752.223449834302;5881.14723365114;5958.731857066421;5959.135925290809;5995.790147344844;5162.74186290684;5164.870878228036;5178.533931946105;5191;5193.818216362447;25577.360565985815;26026.089086881868;26431.73568264312;26616.46767064667;27223.04719334814;2106.547092375147;2137.6579747240366;2163.707899433572;2200.5378870437225;2380.702412198475;14515.228831127404;15066.849331506653;15203.515539559767;15205.771913421308;15231.238438078162;1456.5047883719585;1753.4301352060504;1761.5860272835873;1769.9469015929542;1785.0806834480254;116028;119084;119522;120387.39806301;121240.57518849574;9327.95336023329;10823.891761082288;11066.944665276622;11310.94344528272;11359.375234362024;14283.64290892736;14369.425222990963;14385.7842132366;14385.78421323681;14411.85588144126;7672;7707.4604777666145;8578.399512034097;8785.736427907173;8852.645894164161;8305.83388332242;8343.165683431609;8430.32557395407;8567.400281980326;8689.826203475895;6831.555948863308;6943.6875340609795;6948.305169483011;6952.270011648805;6960.756373526902;1854.2768320355071;1895.251375706588;1895.7535520382537;1900.2399040383957;1925.1433112265188;919.8758167647416;972.7033254857337;988.268681175939;1011.3577878047493;1094.1192340166083;17180.484585462455;20737;21570.490065695565;21618;21768;960.3325688646323;970.1365784451843;1009.1321463541349;1009.9343542669703;1038.2887721910515;11171.31888346791;11612.535498580131;11647.30116193031;11747.765044699228;11772.411379430994;595739;612441;622426;624999;627130.8643456753;132398;145301;147074.2646286783;147837;150546.74179887312;1018.3177271228295;1031.2214278219897;1059.2638116508951;1070.2294348069377;1078.730317420645;18136.818631813512;18497.352592659732;18682.532936676693;18857.43427697171;18977.146028428477;54717.72641136928;55371.723141384035;56107.43892561022;56126.59683507945;56489;77850.61074694591;78205;79248.60375698084;79521.20478795138;81556.5922170409;6892.172939247122;7458.627068646654;7544.773656790305;7648.158702542827;7794.727184548514;12431.44058517387;12769.553065642658;12934.676633084247;13149.671258218621;13241.139325943783;9211.953940230256;10257.435841028815;11309.830352544883;11317.603883864025;11641.6507504775;10890.074343680875;10972.506237219184;11155.888441115485;11403.144764142557;11711.414429278331;11135.33188008722;11494.195406321478;11497.54009839597;11569.710757231136;11830.349330786888;7494;7557.773266802004;7629.7329593463965;7639.312461878458;7855.293023627901;9497.287703422133;11097.38964356968;11252.606158784403;11326.886731132912;11524.481398337262 16:38:43 INFO - PROCESS | 2308 | |3;mootools.html;1443.4803470750535;1599.5521254048867;1641.7291146960752;1902.4102528216254;2028.1380413324334;7024.859502809941;8598.097199794025;8920.286377089833;8923.196912277901;8972.775680607989;7463.917731928867;8423;8775.297976161908;8780.034196238408;8883.866741998876;1837.5498002989286;2121.02432880875;2186.212963333199;2408.5303365843665;2466.7533246675366;2210.9778902210933;2226.109556177526;2405.338531903723;2474.319562120413;2475.777180053795;2430.3559556717482;2435.902563897442;2437.31755108569;2438.2685194441683;2438.719547252071;2402.5915594348917;2442.853428794278;2443.621238708003;2448.608222684362;2448.6327050942323;2136.690179923918;2148.291063948893;2391.5695174868515;2406.217979156781;2471.4686342436285;8785.868211976825;8877.955610221972;8888.644454221823;8889.244414224799;8892.510911899843;5335.306410166692;5516.365617953947;5542.722863856831;5544.77820887164;5551.639143455663;3527.3298073365977;3577.3203091412806;3665.505156803824;3679.5952445231005;3702.3705969985167;987.3582171588453;1033.8345864661694;1038.0346277961494;1038.1072277841051;1046.518601443337;11710.473028713728;11979.520819167223;11985.101117416141;11999.700007499796;12035.578754743541;3986;4002.799860006988;4980.327655766425;5005.6996580205305;5020.874478138076;10434.37393756377;10445.68662940113;10446.373217606968;10446.477676116163;10458.215633827342;4571.51999040096;5514.145307477347;5515.034868898007;5520.365158006881;5550.389457159709;4496.325551167286;4859.635527335464;4984.327115839388;5092.567131793839;5104.846854594368;3683.0239986403626;3943.171933893857;4028.234635419261;4047.838086476567;4062.390641403813;1048.501961568249;1215.4469716279073;1222.9082818788627;1227.6010296653624;1233.8766123387588;798.4570492065451;902.5667679513739;912.6075787411344;943.1747221181466;956.5647630328184;5677.205191273225;5867.618604790675;5905.320888097924;5926.4666180044005;6045.425684559959;963.2246041936309;973.6543527047937;981.5926390547985;995.9800803984025;1045.3153184664056;3996.4205190247253;4000.079981604247;4006.5192176938954;4048.736832105904;4076.6534844537955;10605.734856628646;11091.944540277409;11144.05275551571;11166.994970452699;11175.99416052543;10880.12958963281;10955.945220273848;10993;11028.0074793269;11146.777064458665;7836.804079897935;8271.586420679063;8401.99176098872;8411.747647570583;8419.326453883674;5708.143778433268;6025.548083893638;6139.8465038372615;6167.691615419121;6168.568200226121;9922.40465572068;10198.082172604501;10277.355623100268;10289.84565231507;10322;10072.395656260647;10146.391216527032;10325.74185645365;10408.947955260175;10412.427316497666;6959.686814093479;7687.654055567625;7844.607769611611;7847.332976697045;7854.803629909069;7396.96301518489;7797.220277972384;8235.752927412188;8539.701110461352;8566.657333706831;7482;7571.318581327707;7577.393808495197;7687.577183254751;7781.5331080135375;7457.962710186414;7482.102147742088;7507.061617297838;7536.773896783106;7556.2443755624;6985.022096906336;7035.296470352924;7102.573845569283;7105.3960413363375;7143.785686429415;4809.735464549483;4821.7348045856415;4894.14352488306;4916.901661966669;5078.695278283315;4672.509386514367;4880.975595122001;4930.13722598551;5052.722100284373;5184.974075129751 16:38:43 INFO - PROCESS | 2308 | |4;prototype.html;306.9693030696929;311.32597925491314;324.7661683587816;326.7157572911567;328.40394683649174;4488.3043128315085;4702.85891423257;5250.6324557281005;5280.894382112356;5323.494268044535;4152.958470415295;5194.688318700871;5240.13537766269;5246.317978662778;5260.132652908211;3348.7990720556845;3468.4623883298004;3585.8027808470524;4027.738197017199;4040.191961607683;3332.50012498126;3363.4786608075788;4039.333509970851;4045.9797701011603;4046.4739583854207;3612.367835628768;3861.5945325740813;3978.701597380208;3979.7214195006363;3995.9600403996023;3478.4608385700258;3696.4085746280603;3980.4228386883738;3983.442318075443;4062.309407400749;3413.2762954707714;3498.4577390504514;3515.2617950230483;3527.224010717638;3530.452779819132;4226.4928208615165;4728.834490792806;4765.571098601143;5057.822976195852;5085.669431486942;4532.9546704533195;4575.794089265991;4587.7706114694465;4588.655850811169;4609.907801843944;5430.859519500909;5454.009286610399;5454.218348642182;5457.754401051922;5495.203195536625;3272.541844141822;3730.198007428416;3850.614938506127;3872.612738726133;3893.669038131762;4618.792154353062;4790;5293.497117773805;5443;5804.941950580483;3707.7023041935645;3748.325301445766;3771.8868433947027;3926.5237629102844;3939.7045221608496;3497.510348551205;3606.711463082947;3970.7617542947514;3975.900602484903;3989.9601003989587;4277.8288868444915;4378.124375124988;4518.683692141584;4547.499775024745;4565.497795242521;4583.158441766126;4597.402337696145;5087.643864929493;5494.120940649477;5528.806491772769;1163.941802909851;1223.5167108991898;1333.173552233427;1364.7376177036201;1368.8022979892594;768.6541056524475;807.3016840432983;810.9619686800904;822.2229992657001;836.4103307168432;3773.7735735855385;3782.3380908340932;3928.3518219493462;4107.0553772631865;4170.22594551931;4145.398917157056;4194;4552.590266875931;4741.336212930123;4816.83242540851;3914.5106861642294;4008.8396464141106;4015.377616469452;4113.950942509641;4350.891227719269;2103.1797598936428;2145.420736401163;2158.6330323844986;2323.885264409413;2330.5338932213626;4800.975995120002;4866.394089950308;5022;5030.346055012825;5057.243802127776;4100.5489396166395;4334.848280310237;4417.7930008338335;4422.049259409242;4896.902061958812;4479.827283767305;4568;4939.357883475125;5003.74981250936;5061.772220250097;3805.961940380561;4743.525647435229;4756.788093536522;4795.95204047955;4827.42070951474;4496.932546011878;4757.170477765432;5237.004969055867;5379.54273886724;5586.050371436784;4855.223164293696;5859.648421094748;5963.3142188647125;5964.433378829004;5969.761209551743;3473.6005359383994;3820.2741479118877;3909.667678247381;3942.634419348368;3946.506686664167;5384.273123128328;5672.262605861211;5705.258316418839;5726.455986681259;5749.223854779551;4316.827326906889;5052.9494705052475;5249.6062795289745;5329.227262047059;5531.976584331912;5137.020528093772;5202.531772140525;5491.231227628055;5509.173623956439;5525.502704756431;5076.289319495348;5487.286652735279;5501.946419737084;5522.972385138049;5533.695646739468;4760.833370832004;5234.031704134595;5234.947650523446;5257.8159764408065;5641.5204707600315;5026.44709082008;5199.4020687619895;5511.255980442589;5511.696856672921;5525.226468294522 16:38:43 INFO - PROCESS | 2308 | |5;yui.html;566.7562947932389;624.5752888036134;650.6063831382014;668.1848145262778;680.1532092544783;13477;13706.109102908316;13713.382897769612;13925.930370348135;13928.79106813396;12938.93530532346;13606.659833504167;13840.238786866717;13878.028538002343;13966.58100256994;922.9123233292827;923.3951761596165;941.4210260689695;942.5334459442586;944.3531181140941;912.1198043887647;914.2092090341873;915.3729695158804;918.4122161816409;921.2675922641516;979.9265055120859;990.2078337330107;994.8060128274989;995.2833959549149;996.1035068438428;981.8281800684887;982.6658935961735;983.0218932162472;986.9062439068277;988.6243227573548;728.0753443127198;728.4900569601248;728.5155371677828;728.5774250934445;733.534205779334;12422.503099875994;12573.497060117585;12678.55625053128;12679.429425675868;12872.549460768829;10943.890561094368;11022.118230541537;11078.889211107866;11169;11590.536378544932;9163.725088247364;9246.214071803905;9255.167034966818;9256.352055356128;9327.113924177193;3088.1970687621156;3270.787398819069;3366;3373.0892658982184;3374.7300215982664;52613.47558506378;71564;71903;78182;78258;9668.129868311746;9717.951410242904;9742.333848301318;9864.309498335047;10061.144802691853;21778.564428711335;21827.454313641967;21848.45378865541;21853.016614252396;21856.453588660093;7027.61348125858;7220.602866842269;7331.8900216496795;7550.924490755132;7784.571848548271;4032.8991775205855;4055.7566546007333;4057.8173982170874;4089.2230476209425;4409.4267745193565;3362.3107263011025;3425.5718035245595;3439.5872495300723;3484.1463841359027;3484.756067075331;621.8756248750177;625.0436831647586;627.0562802981484;627.6046090962665;639.3798015924546;478.652976591975;550.1829782772579;560.5264591266288;567.943205679437;586.234963372794;7652.196519365498;7725.806854828562;7820.257075577811;7874.921250787534;7912.28789408956;494.6364422149717;538.9380221274525;544.9400565937743;546.5272539253517;572.133218174466;3489.703375213135;3663.4871118043493;3674.889753307405;3854.9036274093373;3869.8839034829;80559.59720201361;82062.58968705237;83290.58354708187;88752;89186.55406722926;72031.63984180045;74517.88223176656;76966.6151669238;77382.6130869342;78225.43956565791;66006.33993660098;66106;66440.33559664438;67428.32571674319;68761.62476750437;8011.278984891388;8195.344372450309;8281.627326770189;8296.170382961656;8454.463660901582;32786.68853245844;39845.60154398419;41164;41294;42548.36177457403;59233.40766592279;59823;60252.46137440101;60518;60762.39237607744;11366.318020918772;11490.94254528722;11518.942405288255;11529.654110376701;12051.51793928233;11728.648140555797;11856.407179641155;11870.169088163746;12367.072469564999;13029.283389413673;11483.425828708698;11560;11815.881841181823;11947.402629868646;12104.213226140273;11493.252938558968;11514;11672.533098675958;11846.111541634584;12180.330081845583;11516.251443656136;11673.94934455903;11753.88246117562;11849.111316651113;12339.383030848243;9596;9605.519724013911;9710.902890971;9728.075832795874;10059.49702514886;8250;9043.32175086858;9891.35706179096;9975.45135017552;9980.800383992135 16:38:43 INFO - PROCESS | 2308 | __end_tp_report 16:38:43 INFO - PROCESS | 2308 | __start_cc_report 16:38:43 INFO - PROCESS | 2308 | _x_x_mozilla_cycle_collect,1336 16:38:43 INFO - PROCESS | 2308 | __end_cc_report 16:38:43 INFO - PROCESS | 2308 | __startTimestamp1462923523854__endTimestamp 16:38:43 INFO - PROCESS | 2308 | 16:38:43 INFO - PROCESS | 2308 | ------- Summary: start ------- 16:38:43 INFO - PROCESS | 2308 | Number of tests: 6 16:38:43 INFO - PROCESS | 2308 | 16:38:43 INFO - PROCESS | 2308 | [#0] dojo.html Cycles:165 Average:5787.47 Median:5987.07 stddev:651.99 (10.9%) stddev-sans-first:627.52 16:38:43 INFO - PROCESS | 2308 | Values: 3443.3 3464.8 3475.7 3482.2 3528.8 5677.7 5711.6 5715.9 5813.6 5815.8 5766.6 5774.4 5872.7 5887.9 5913.5 4828.4 5162.7 5246.1 5248.7 5348.7 5313.0 5351.4 5364.4 5379.8 5391.9 5135.8 5151.3 5160.0 5212.1 5266.0 4315.8 5204.3 5207.8 5211.9 5282.1 5201.4 5239.7 5239.7 5255.8 5263.6 5607.7 5641.6 5729.3 5731.8 5736.5 6240.6 6293.1 6314.7 6327.7 6343.4 6280.5 6326.7 6336.6 6341.4 6346.3 5717.2 5790.1 5811.5 5884.9 5890.8 6382.6 6421.0 6421.7 6427.7 6439.4 5180.9 6249.1 6259.9 6270.8 6276.0 6180.2 6182.9 6240.2 6248.0 6315.0 5987.1 6142.4 6150.9 6197.8 6215.4 5180.8 6144.1 6250.9 6253.6 6260.4 4927.3 4947.3 4979.8 4997.7 5010.5 4441.6 4451.6 4466.5 4475.3 4478.1 5674.6 5809.9 5857.2 5862.4 5865.4 6252.7 6259.5 6271.6 6295.2 6313.7 5781.6 5783.0 5795.6 5803.9 5835.2 5344.6 5458.6 5473.6 5484.8 5487.3 6321.5 6382.3 6421.1 6430.8 6521.9 6273.4 6298.1 6315.9 6316.9 6338.3 6255.7 6435.6 6484.2 6521.0 6527.6 6104.9 6145.9 6173.4 6179.3 6210.2 6296.2 6298.2 6305.5 6309.9 6312.9 6080.5 6143.0 6173.7 6180.1 6199.7 5970.4 6096.4 6107.9 6130.6 6145.1 6103.6 6109.0 6115.6 6117.4 6141.7 5985.2 6235.6 6244.3 6250.9 6253.7 5839.1 5980.3 5982.7 5987.1 5993.7 16:38:43 INFO - PROCESS | 2308 | 16:38:43 INFO - PROCESS | 2308 | [#1] ext.html Cycles:180 Average:71468.12 Median:18429.87 stddev:155538.58 (843.9%) stddev-sans-first:155883.63 16:38:43 INFO - PROCESS | 2308 | Values: 494.4 495.8 497.3 521.3 522.0 31116.0 32043.7 35941.9 42851.8 43394.0 42686.0 42953.6 43170.4 44857.1 44964.8 9694.8 9894.8 9906.0 9914.5 9914.6 7838.8 11609.7 11900.9 11917.5 12031.0 11686.8 11762.5 11786.8 11791.9 11795.0 11694.4 11794.4 11817.5 11835.9 11845.5 11794.8 11851.6 11863.3 11889.8 11896.2 40842.0 41084.0 41092.6 41241.4 41306.8 3197.9 3211.5 3248.3 3345.9 3428.6 27986.9 29170.4 29438.7 30346.7 30977.4 2714.3 2768.1 2775.4 2812.1 2838.6 474228.0 476800.0 477746.0 479648.0 483647.6 64073.4 64420.0 64577.4 64734.0 64811.4 66142.0 66950.3 67120.0 67153.3 67192.0 15757.5 16266.5 16294.0 16294.8 16303.3 12407.1 12491.0 12506.2 12511.7 12519.7 19460.4 19765.0 19785.5 19795.9 19824.7 8073.0 8139.9 8171.9 8182.6 8425.7 929.6 1007.5 1020.3 1023.2 1070.4 29166.7 29286.3 29803.9 30050.5 30131.2 920.3 1017.2 1055.1 1152.3 1177.8 14420.4 14988.6 15056.9 15175.2 15193.2 666819.0 669006.0 676450.0 676606.0 679446.0 60499.4 61064.1 61131.4 61195.0 61270.0 1321.8 1365.9 1366.4 1480.5 1516.3 30287.2 30473.4 30922.7 31603.1 32370.2 56516.7 56712.7 57297.1 57369.4 57386.4 571685.1 577977.1 578054.0 579226.1 579539.0 23847.6 26478.3 27222.6 27385.7 27765.2 45743.3 46208.3 46417.1 47216.3 47220.0 29110.7 37551.5 38332.6 38444.6 39017.8 37228.3 37290.6 37547.1 38148.8 38407.8 37599.9 37927.2 38131.0 38371.0 38582.6 32060.2 33139.0 33513.8 33634.0 33788.3 24794.0 24898.0 24925.3 25143.5 25278.4 16:38:43 INFO - PROCESS | 2308 | 16:38:43 INFO - PROCESS | 2308 | [#2] jquery.html Cycles:180 Average:36279.99 Median:11854.07 stddev:103233.41 (870.9%) stddev-sans-first:103487.83 16:38:43 INFO - PROCESS | 2308 | Values: 386.9 392.7 394.7 399.6 419.4 14308.5 20202.5 20635.9 21310.0 21351.6 15966.3 18090.2 20381.4 20941.5 20951.1 5805.8 6047.1 6071.2 6086.8 6106.3 5933.9 5973.2 5987.3 6015.2 6052.4 5826.9 5913.3 5953.4 5982.8 5997.7 5752.2 5881.1 5958.7 5959.1 5995.8 5162.7 5164.9 5178.5 5191.0 5193.8 25577.4 26026.1 26431.7 26616.5 27223.0 2106.5 2137.7 2163.7 2200.5 2380.7 14515.2 15066.8 15203.5 15205.8 15231.2 1456.5 1753.4 1761.6 1769.9 1785.1 116028.0 119084.0 119522.0 120387.4 121240.6 9328.0 10823.9 11066.9 11310.9 11359.4 14283.6 14369.4 14385.8 14385.8 14411.9 7672.0 7707.5 8578.4 8785.7 8852.6 8305.8 8343.2 8430.3 8567.4 8689.8 6831.6 6943.7 6948.3 6952.3 6960.8 1854.3 1895.3 1895.8 1900.2 1925.1 919.9 972.7 988.3 1011.4 1094.1 17180.5 20737.0 21570.5 21618.0 21768.0 960.3 970.1 1009.1 1009.9 1038.3 11171.3 11612.5 11647.3 11747.8 11772.4 595739.0 612441.0 622426.0 624999.0 627130.9 132398.0 145301.0 147074.3 147837.0 150546.7 1018.3 1031.2 1059.3 1070.2 1078.7 18136.8 18497.4 18682.5 18857.4 18977.1 54717.7 55371.7 56107.4 56126.6 56489.0 77850.6 78205.0 79248.6 79521.2 81556.6 6892.2 7458.6 7544.8 7648.2 7794.7 12431.4 12769.6 12934.7 13149.7 13241.1 9212.0 10257.4 11309.8 11317.6 11641.7 10890.1 10972.5 11155.9 11403.1 11711.4 11135.3 11494.2 11497.5 11569.7 11830.3 7494.0 7557.8 7629.7 7639.3 7855.3 9497.3 11097.4 11252.6 11326.9 11524.5 16:38:43 INFO - PROCESS | 2308 | 16:38:43 INFO - PROCESS | 2308 | [#3] mootools.html Cycles:180 Average:5784.55 Median:4840.69 stddev:3276.37 (67.7%) stddev-sans-first:3269.31 16:38:43 INFO - PROCESS | 2308 | Values: 1443.5 1599.6 1641.7 1902.4 2028.1 7024.9 8598.1 8920.3 8923.2 8972.8 7463.9 8423.0 8775.3 8780.0 8883.9 1837.5 2121.0 2186.2 2408.5 2466.8 2211.0 2226.1 2405.3 2474.3 2475.8 2430.4 2435.9 2437.3 2438.3 2438.7 2402.6 2442.9 2443.6 2448.6 2448.6 2136.7 2148.3 2391.6 2406.2 2471.5 8785.9 8878.0 8888.6 8889.2 8892.5 5335.3 5516.4 5542.7 5544.8 5551.6 3527.3 3577.3 3665.5 3679.6 3702.4 987.4 1033.8 1038.0 1038.1 1046.5 11710.5 11979.5 11985.1 11999.7 12035.6 3986.0 4002.8 4980.3 5005.7 5020.9 10434.4 10445.7 10446.4 10446.5 10458.2 4571.5 5514.1 5515.0 5520.4 5550.4 4496.3 4859.6 4984.3 5092.6 5104.8 3683.0 3943.2 4028.2 4047.8 4062.4 1048.5 1215.4 1222.9 1227.6 1233.9 798.5 902.6 912.6 943.2 956.6 5677.2 5867.6 5905.3 5926.5 6045.4 963.2 973.7 981.6 996.0 1045.3 3996.4 4000.1 4006.5 4048.7 4076.7 10605.7 11091.9 11144.1 11167.0 11176.0 10880.1 10955.9 10993.0 11028.0 11146.8 7836.8 8271.6 8402.0 8411.7 8419.3 5708.1 6025.5 6139.8 6167.7 6168.6 9922.4 10198.1 10277.4 10289.8 10322.0 10072.4 10146.4 10325.7 10408.9 10412.4 6959.7 7687.7 7844.6 7847.3 7854.8 7397.0 7797.2 8235.8 8539.7 8566.7 7482.0 7571.3 7577.4 7687.6 7781.5 7458.0 7482.1 7507.1 7536.8 7556.2 6985.0 7035.3 7102.6 7105.4 7143.8 4809.7 4821.7 4894.1 4916.9 5078.7 4672.5 4881.0 4930.1 5052.7 5185.0 16:38:43 INFO - PROCESS | 2308 | 16:38:43 INFO - PROCESS | 2308 | [#4] prototype.html Cycles:180 Average:4229.81 Median:4571.90 stddev:1303.80 (28.5%) stddev-sans-first:1273.78 16:38:43 INFO - PROCESS | 2308 | Values: 307.0 311.3 324.8 326.7 328.4 4488.3 4702.9 5250.6 5280.9 5323.5 4153.0 5194.7 5240.1 5246.3 5260.1 3348.8 3468.5 3585.8 4027.7 4040.2 3332.5 3363.5 4039.3 4046.0 4046.5 3612.4 3861.6 3978.7 3979.7 3996.0 3478.5 3696.4 3980.4 3983.4 4062.3 3413.3 3498.5 3515.3 3527.2 3530.5 4226.5 4728.8 4765.6 5057.8 5085.7 4533.0 4575.8 4587.8 4588.7 4609.9 5430.9 5454.0 5454.2 5457.8 5495.2 3272.5 3730.2 3850.6 3872.6 3893.7 4618.8 4790.0 5293.5 5443.0 5804.9 3707.7 3748.3 3771.9 3926.5 3939.7 3497.5 3606.7 3970.8 3975.9 3990.0 4277.8 4378.1 4518.7 4547.5 4565.5 4583.2 4597.4 5087.6 5494.1 5528.8 1163.9 1223.5 1333.2 1364.7 1368.8 768.7 807.3 811.0 822.2 836.4 3773.8 3782.3 3928.4 4107.1 4170.2 4145.4 4194.0 4552.6 4741.3 4816.8 3914.5 4008.8 4015.4 4114.0 4350.9 2103.2 2145.4 2158.6 2323.9 2330.5 4801.0 4866.4 5022.0 5030.3 5057.2 4100.5 4334.8 4417.8 4422.0 4896.9 4479.8 4568.0 4939.4 5003.7 5061.8 3806.0 4743.5 4756.8 4796.0 4827.4 4496.9 4757.2 5237.0 5379.5 5586.1 4855.2 5859.6 5963.3 5964.4 5969.8 3473.6 3820.3 3909.7 3942.6 3946.5 5384.3 5672.3 5705.3 5726.5 5749.2 4316.8 5052.9 5249.6 5329.2 5532.0 5137.0 5202.5 5491.2 5509.2 5525.5 5076.3 5487.3 5501.9 5523.0 5533.7 4760.8 5234.0 5234.9 5257.8 5641.5 5026.4 5199.4 5511.3 5511.7 5525.2 16:38:43 INFO - PROCESS | 2308 | 16:38:43 INFO - PROCESS | 2308 | [#5] yui.html Cycles:180 Average:17027.26 Median:60037.73 stddev:23453.23 (39.1%) stddev-sans-first:23486.45 16:38:43 INFO - PROCESS | 2308 | Values: 566.8 624.6 650.6 668.2 680.2 13477.0 13706.1 13713.4 13925.9 13928.8 12938.9 13606.7 13840.2 13878.0 13966.6 922.9 923.4 941.4 942.5 944.4 912.1 914.2 915.4 918.4 921.3 979.9 990.2 994.8 995.3 996.1 981.8 982.7 983.0 986.9 988.6 728.1 728.5 728.5 728.6 733.5 12422.5 12573.5 12678.6 12679.4 12872.5 10943.9 11022.1 11078.9 11169.0 11590.5 9163.7 9246.2 9255.2 9256.4 9327.1 3088.2 3270.8 3366.0 3373.1 3374.7 52613.5 71564.0 71903.0 78182.0 78258.0 9668.1 9718.0 9742.3 9864.3 10061.1 21778.6 21827.5 21848.5 21853.0 21856.5 7027.6 7220.6 7331.9 7550.9 7784.6 4032.9 4055.8 4057.8 4089.2 4409.4 3362.3 3425.6 3439.6 3484.1 3484.8 621.9 625.0 627.1 627.6 639.4 478.7 550.2 560.5 567.9 586.2 7652.2 7725.8 7820.3 7874.9 7912.3 494.6 538.9 544.9 546.5 572.1 3489.7 3663.5 3674.9 3854.9 3869.9 80559.6 82062.6 83290.6 88752.0 89186.6 72031.6 74517.9 76966.6 77382.6 78225.4 66006.3 66106.0 66440.3 67428.3 68761.6 8011.3 8195.3 8281.6 8296.2 8454.5 32786.7 39845.6 41164.0 41294.0 42548.4 59233.4 59823.0 60252.5 60518.0 60762.4 11366.3 11490.9 11518.9 11529.7 12051.5 11728.6 11856.4 11870.2 12367.1 13029.3 11483.4 11560.0 11815.9 11947.4 12104.2 11493.3 11514.0 11672.5 11846.1 12180.3 11516.3 11673.9 11753.9 11849.1 12339.4 9596.0 9605.5 9710.9 9728.1 10059.5 8250.0 9043.3 9891.4 9975.5 9980.8 16:38:43 INFO - PROCESS | 2308 | -------- Summary: end -------- 16:38:43 INFO - PROCESS | 2308 | 16:38:44 INFO - TEST-INFO | 2308: exit 0 16:38:44 INFO - TEST-OK | dromaeo_css | took 1114829ms 16:38:44 INFO - TEST-START | kraken 16:38:44 INFO - operating with platform_type : mac_ 16:38:44 INFO - Initialising browser for kraken test... 16:38:44 INFO - TEST-INFO | started process 2313 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpzk2j4l/profile http://localhost:52450/getInfo.html) 16:38:46 INFO - PROCESS | 2313 | __metrics Screen width/height:1600/1200 16:38:46 INFO - PROCESS | 2313 | colorDepth:24 16:38:46 INFO - PROCESS | 2313 | Browser inner width/height: 1024/689 16:38:46 INFO - PROCESS | 2313 | __metrics 16:38:46 INFO - PROCESS | 2313 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:38:46 ERROR - PROCESS | 2313 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:38:47 INFO - TEST-INFO | 2313: exit 0 16:38:47 INFO - Browser initialized. 16:38:47 INFO - Running cycle 1/1 for kraken test... 16:38:47 INFO - TEST-INFO | started process 2315 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpzk2j4l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:38:59 INFO - PROCESS | 2315 | RSS: Main: 238915584 16:38:59 INFO - PROCESS | 2315 | 16:39:01 INFO - PROCESS | 2315 | Cycle 1(1): loaded http://localhost:52450/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52450/tests/kraken/driver.html?testName=audio-beat-detection) 16:39:02 INFO - PROCESS | 2315 | RSS: Main: 287391744 16:39:02 INFO - PROCESS | 2315 | 16:39:05 INFO - PROCESS | 2315 | Cycle 1(1): loaded http://localhost:52450/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52450/tests/kraken/driver.html?testName=audio-dft) 16:39:06 INFO - PROCESS | 2315 | RSS: Main: 295763968 16:39:06 INFO - PROCESS | 2315 | 16:39:09 INFO - PROCESS | 2315 | Cycle 1(1): loaded http://localhost:52450/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52450/tests/kraken/driver.html?testName=audio-fft) 16:39:10 INFO - PROCESS | 2315 | RSS: Main: 386097152 16:39:10 INFO - PROCESS | 2315 | 16:39:12 INFO - PROCESS | 2315 | Cycle 1(1): loaded http://localhost:52450/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52450/tests/kraken/driver.html?testName=audio-oscillator) 16:39:13 INFO - PROCESS | 2315 | RSS: Main: 284172288 16:39:13 INFO - PROCESS | 2315 | 16:39:15 INFO - PROCESS | 2315 | Cycle 1(1): loaded http://localhost:52450/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52450/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:39:16 INFO - PROCESS | 2315 | RSS: Main: 287883264 16:39:16 INFO - PROCESS | 2315 | 16:39:19 INFO - PROCESS | 2315 | Cycle 1(1): loaded http://localhost:52450/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52450/tests/kraken/driver.html?testName=imaging-darkroom) 16:39:19 INFO - PROCESS | 2315 | RSS: Main: 326225920 16:39:19 INFO - PROCESS | 2315 | 16:39:22 INFO - PROCESS | 2315 | Cycle 1(1): loaded http://localhost:52450/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52450/tests/kraken/driver.html?testName=imaging-desaturate) 16:39:23 INFO - PROCESS | 2315 | RSS: Main: 328683520 16:39:23 INFO - PROCESS | 2315 | 16:39:26 INFO - PROCESS | 2315 | Cycle 1(1): loaded http://localhost:52450/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52450/tests/kraken/driver.html?testName=json-parse-financial) 16:39:27 INFO - PROCESS | 2315 | RSS: Main: 327749632 16:39:27 INFO - PROCESS | 2315 | 16:39:28 INFO - PROCESS | 2315 | Cycle 1(1): loaded http://localhost:52450/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52450/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:39:29 INFO - PROCESS | 2315 | RSS: Main: 316207104 16:39:29 INFO - PROCESS | 2315 | 16:39:31 INFO - PROCESS | 2315 | Cycle 1(1): loaded http://localhost:52450/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52450/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:39:32 INFO - PROCESS | 2315 | RSS: Main: 322740224 16:39:32 INFO - PROCESS | 2315 | 16:39:34 INFO - PROCESS | 2315 | Cycle 1(1): loaded http://localhost:52450/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52450/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:39:35 INFO - PROCESS | 2315 | RSS: Main: 324730880 16:39:35 INFO - PROCESS | 2315 | 16:39:38 INFO - PROCESS | 2315 | Cycle 1(1): loaded http://localhost:52450/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52450/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:39:38 INFO - PROCESS | 2315 | RSS: Main: 329326592 16:39:38 INFO - PROCESS | 2315 | 16:39:41 INFO - PROCESS | 2315 | Cycle 1(1): loaded http://localhost:52450/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52450/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:39:42 INFO - PROCESS | 2315 | RSS: Main: 327004160 16:39:42 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | Cycle 1(1): loaded http://localhost:52450/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52450/tests/kraken/driver.html?testName=ai-astar) 16:39:44 INFO - PROCESS | 2315 | RSS: Main: 373575680 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | __start_tp_report 16:39:44 INFO - PROCESS | 2315 | _x_x_mozilla_page_load 16:39:44 INFO - PROCESS | 2315 | _x_x_mozilla_page_load_details 16:39:44 INFO - PROCESS | 2315 | |i|pagename|runs| 16:39:44 INFO - PROCESS | 2315 | |0;ai-astar;111;105;112;113;112;113;111;113;113;111 16:39:44 INFO - PROCESS | 2315 | |1;audio-beat-detection;158;141;156;193;116;137;135;115;169;115 16:39:44 INFO - PROCESS | 2315 | |2;audio-dft;184;185;182;182;182;182;181;181;182;222 16:39:44 INFO - PROCESS | 2315 | |3;audio-fft;80;73;57;124;58;62;58;73;135;77 16:39:44 INFO - PROCESS | 2315 | |4;audio-oscillator;94;99;133;80;85;77;102;80;77;101 16:39:44 INFO - PROCESS | 2315 | |5;imaging-gaussian-blur;87;84;87;87;134;84;87;85;118;84 16:39:44 INFO - PROCESS | 2315 | |6;imaging-darkroom;93;96;94;93;93;93;94;93;93;91 16:39:44 INFO - PROCESS | 2315 | |7;imaging-desaturate;85;84;83;83;85;83;83;83;86;86 16:39:44 INFO - PROCESS | 2315 | |8;json-parse-financial;60;59;61;57;59;80;87;168;60;57 16:39:44 INFO - PROCESS | 2315 | |9;json-stringify-tinderbox;63;70;69;77;73;70;68;69;69;70 16:39:44 INFO - PROCESS | 2315 | |10;stanford-crypto-aes;85;85;85;85;82;85;82;84;84;86 16:39:44 INFO - PROCESS | 2315 | |11;stanford-crypto-ccm;144;140;140;136;139;133;136;147;250;140 16:39:44 INFO - PROCESS | 2315 | |12;stanford-crypto-pbkdf2;167;168;167;165;175;172;226;213;169;165 16:39:44 INFO - PROCESS | 2315 | |13;stanford-crypto-sha256-iterative;66;66;68;72;82;86;87;74;66;64 16:39:44 INFO - PROCESS | 2315 | __end_tp_report 16:39:44 INFO - PROCESS | 2315 | __start_cc_report 16:39:44 INFO - PROCESS | 2315 | _x_x_mozilla_cycle_collect,2201 16:39:44 INFO - PROCESS | 2315 | __end_cc_report 16:39:44 INFO - PROCESS | 2315 | __startTimestamp1462923584462__endTimestamp 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | ------- Summary: start ------- 16:39:44 INFO - PROCESS | 2315 | Number of tests: 14 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.50 stddev:2.41 (2.1%) stddev-sans-first:2.55 16:39:44 INFO - PROCESS | 2315 | Values: 111.0 105.0 112.0 113.0 112.0 113.0 111.0 113.0 113.0 111.0 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:148.50 stddev:25.75 (17.3%) stddev-sans-first:26.77 16:39:44 INFO - PROCESS | 2315 | Values: 158.0 141.0 156.0 193.0 116.0 137.0 135.0 115.0 169.0 115.0 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | [#2] audio-dft Cycles:10 Average:186.30 Median:182.00 stddev:12.61 (6.9%) stddev-sans-first:13.34 16:39:44 INFO - PROCESS | 2315 | Values: 184.0 185.0 182.0 182.0 182.0 182.0 181.0 181.0 182.0 222.0 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | [#3] audio-fft Cycles:10 Average:79.70 Median:67.50 stddev:27.66 (41.0%) stddev-sans-first:29.34 16:39:44 INFO - PROCESS | 2315 | Values: 80.0 73.0 57.0 124.0 58.0 62.0 58.0 73.0 135.0 77.0 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | [#4] audio-oscillator Cycles:10 Average:92.80 Median:80.00 stddev:17.31 (21.6%) stddev-sans-first:18.35 16:39:44 INFO - PROCESS | 2315 | Values: 94.0 99.0 133.0 80.0 85.0 77.0 102.0 80.0 77.0 101.0 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | [#5] imaging-gaussian-blur Cycles:10 Average:93.70 Median:86.00 stddev:17.49 (20.3%) stddev-sans-first:18.38 16:39:44 INFO - PROCESS | 2315 | Values: 87.0 84.0 87.0 87.0 134.0 84.0 87.0 85.0 118.0 84.0 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.00 stddev:1.25 (1.3%) stddev-sans-first:1.32 16:39:44 INFO - PROCESS | 2315 | Values: 93.0 96.0 94.0 93.0 93.0 93.0 94.0 93.0 93.0 91.0 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.50 stddev:1.29 (1.5%) stddev-sans-first:1.32 16:39:44 INFO - PROCESS | 2315 | Values: 85.0 84.0 83.0 83.0 85.0 83.0 83.0 83.0 86.0 86.0 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | [#8] json-parse-financial Cycles:10 Average:74.80 Median:60.00 stddev:34.36 (57.3%) stddev-sans-first:36.02 16:39:44 INFO - PROCESS | 2315 | Values: 60.0 59.0 61.0 57.0 59.0 80.0 87.0 168.0 60.0 57.0 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:3.55 (5.1%) stddev-sans-first:2.79 16:39:44 INFO - PROCESS | 2315 | Values: 63.0 70.0 69.0 77.0 73.0 70.0 68.0 69.0 69.0 70.0 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:85.00 stddev:1.34 (1.6%) stddev-sans-first:1.39 16:39:44 INFO - PROCESS | 2315 | Values: 85.0 85.0 85.0 85.0 82.0 85.0 82.0 84.0 84.0 86.0 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | [#11] stanford-crypto-ccm Cycles:10 Average:150.50 Median:140.00 stddev:35.19 (25.1%) stddev-sans-first:37.25 16:39:44 INFO - PROCESS | 2315 | Values: 144.0 140.0 140.0 136.0 139.0 133.0 136.0 147.0 250.0 140.0 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.70 Median:170.50 stddev:21.93 (12.9%) stddev-sans-first:22.85 16:39:44 INFO - PROCESS | 2315 | Values: 167.0 168.0 167.0 165.0 175.0 172.0 226.0 213.0 169.0 165.0 16:39:44 INFO - PROCESS | 2315 | 16:39:44 INFO - PROCESS | 2315 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:73.00 stddev:8.82 (12.1%) stddev-sans-first:8.98 16:39:44 INFO - PROCESS | 2315 | Values: 66.0 66.0 68.0 72.0 82.0 86.0 87.0 74.0 66.0 64.0 16:39:44 INFO - PROCESS | 2315 | -------- Summary: end -------- 16:39:44 INFO - PROCESS | 2315 | 16:39:45 INFO - TEST-INFO | 2315: exit 0 16:39:45 INFO - TEST-OK | kraken | took 60891ms 16:39:45 INFO - SUITE-END | took 1175s 16:39:45 INFO - Completed test suite (00:19:36) 16:39:45 INFO - javascript benchmark 16:39:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3443.3113377324535, 3464.792112473252, 3475.669811367921, 3482.181687303485, 3528.7706299090555, 5677.744501497432, 5711.600187986843, 5715.857103572413, 5813.563982701298, 5815.825525234244, 5766.596338256324, 5774.422557744224, 5872.73572689229, 5887.88224235515, 5913.526917846572, 4828.396450443694, 5162.664426812245, 5246.134387826004, 5248.71132087735, 5348.679079255257, 5312.973435132819, 5351.357837059539, 5364.409914909357, 5379.784808607651, 5391.892162156754, 5135.820246291362, 5151.330327057496, 5159.993801208766, 5212.087884620214, 5265.973670131663, 4315.762633055181, 5204.297419848348, 5207.791688332462, 5211.895762084775, 5282.0756367635895, 5201.427842937273, 5239.685618862881, 5239.711815850127, 5255.842324730264, 5263.631545791796, 5607.747651355658, 5641.576881733846, 5729.28383952006, 5731.799387021476, 5736.4550367715, 6240.563160578761, 6293.087502312139, 6314.684265786692, 6327.683615819236, 6343.429091381798, 6280.466160376373, 6326.715297811609, 6336.556441049129, 6341.4292713656, 6346.333634968308, 5717.228174196472, 5790.13148027795, 5811.506021988178, 5884.852878678067, 5890.793822216202, 6382.648954307465, 6421.0, 6421.711023003976, 6427.710753016126, 6439.420452159235, 5180.870478238075, 6249.062640603855, 6259.937400626027, 6270.843228919313, 6276.0, 6180.227471566054, 6182.938170618326, 6240.157578726905, 6248.0, 6314.968425157845, 5987.072003839412, 6142.355052719489, 6150.907736383958, 6197.81406557804, 6215.409536094064, 5180.766865491062, 6144.126041557368, 6250.874982500324, 6253.624782513064, 6260.40526150015, 4927.28554359623, 4947.307376967227, 4979.775910084055, 4997.675151115239, 5010.498950105033, 4441.622462090759, 4451.621612162938, 4466.508684044752, 4475.328700694922, 4478.104379124123, 5674.631148975303, 5809.883802324014, 5857.20927674767, 5862.355140934494, 5865.35481097079, 6252.718627661766, 6259.530535209878, 6271.560990730696, 6295.150154729053, 6313.715882785285, 5781.602638823392, 5782.971085144632, 5795.623284486495, 5803.912941305884, 5835.2414186156375, 5344.625876188629, 5458.590605704588, 5473.616846820684, 5484.8354549363585, 5487.314085739283, 6321.494280457552, 6382.297947225801, 6421.068945003042, 6430.7749228776765, 6521.934780652133, 6273.37266273369, 6298.08677741734, 6315.936840631535, 6316.936830631635, 6338.271098823509, 6255.687215639109, 6435.613863168038, 6484.221893372825, 6520.967395163184, 6527.5757075789925, 6104.853288833144, 6145.924463218829, 6173.382661733791, 6179.3202747698615, 6210.223722034746, 6296.181496405438, 6298.212723409574, 6305.527085468517, 6309.873802524015, 6312.936870631235, 6080.543959202989, 6143.017117261216, 6173.660448675366, 6180.072989051679, 6199.690015499297, 5970.373110823301, 6096.390360963868, 6107.947487154961, 6130.551011451029, 6145.064304743992, 6103.603265787887, 6108.979583722655, 6115.5719099664375, 6117.418845209698, 6141.692915354125, 5985.221921150222, 6235.563510554399, 6244.344343844013, 6250.906236406367, 6253.718582663882, 5839.124131380157, 5980.3421623622235, 5982.7008649568215, 5987.101934709828, 5993.730282137227], "value": 5746.47343850602, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.36227266825796, 495.7818559833675, 497.2963256991357, 521.2520033751567, 522.0368420264613, 31116.0, 32043.67956320436, 35941.921742347775, 42851.785741071326, 43394.0, 42686.0, 42953.57046429535, 43170.35244471335, 44857.102857942824, 44964.77517612415, 9694.757631059209, 9894.802103957916, 9906.0, 9914.504274786268, 9914.603415863357, 7838.843223135534, 11609.651710448657, 11900.880991190108, 11917.523299068027, 12031.0, 11686.766264674701, 11762.529498820037, 11786.7642647147, 11791.941040294787, 11794.997425218866, 11694.356810375426, 11794.35131067791, 11817.468213930393, 11835.881641183563, 11845.466953987005, 11794.823077653842, 11851.644450666494, 11863.288202707865, 11889.762204755943, 11896.167268291223, 40842.0, 41084.0, 41092.58907410917, 41241.38137927934, 41306.79346603278, 3197.936041279185, 3211.486162214058, 3248.31785325082, 3345.8996230112893, 3428.5714285714284, 27986.86006569954, 29170.416591668047, 29438.70561294381, 30346.696533034607, 30977.38045239105, 2714.2671478700836, 2768.1142034548952, 2775.417162395899, 2812.0860720265996, 2838.630977972871, 474228.0, 476800.0, 477746.0, 479648.0, 483647.5817620925, 64073.35926640767, 64420.0, 64577.354226458076, 64734.0, 64811.35188648053, 66142.0, 66950.33049669539, 67120.0, 67153.32846671568, 67192.0, 15757.527274181793, 16266.51200463988, 16294.0, 16294.837051629569, 16303.347866085223, 12407.069469789802, 12491.000719942384, 12506.249625022529, 12511.687207819878, 12519.749605007848, 19460.4161875144, 19765.0, 19785.505362366057, 19795.901020494806, 19824.70262946057, 8073.0, 8139.877901831477, 8171.9182808172345, 8182.558598531909, 8425.747227583182, 929.6002718830832, 1007.450939238117, 1020.3418794877263, 1023.1712313026323, 1070.4166229404993, 29166.708332916824, 29286.26784330409, 29803.85098074496, 30050.54924176139, 30131.246718831768, 920.3189639666598, 1017.1557607186018, 1055.0609957138154, 1152.2798251093068, 1177.7938860699344, 14420.42318307256, 14988.62528436776, 15056.924715376354, 15175.16536590498, 15193.164375959208, 666819.0, 669006.0, 676450.0, 676606.0, 679446.0, 60499.39500604937, 61064.08403873946, 61131.38868611346, 61195.0, 61270.0, 1321.8281623389091, 1365.8838998685023, 1366.43293033394, 1480.489231215229, 1516.2646116633423, 30287.242818929702, 30473.390532189675, 30922.69077309198, 31603.051908442783, 32370.190745231557, 56516.717416412655, 56712.71643641921, 57297.14054289106, 57369.42630573808, 57386.42613573811, 571685.1415742894, 577977.1101144467, 578054.0, 579226.1038694949, 579539.0, 23847.64228536608, 26478.338041549116, 27222.591661125494, 27385.726142738316, 27765.167044988684, 45743.313850292936, 46208.30687539757, 46417.07165856596, 47216.29175562438, 47220.0, 29110.7088929108, 37551.49794008319, 38332.61796148979, 38444.61555384522, 39017.804910976396, 37228.25543489061, 37290.62709372872, 37547.06132346713, 38148.80925595354, 38407.80796096002, 37599.872003839926, 37927.24145517019, 38131.0, 38371.04072398212, 38582.61417385902, 32060.19849503781, 33139.005829825146, 33513.83243083769, 33633.99098027063, 33788.32423351568, 24794.0, 24898.0, 24925.252242432754, 25143.49713005693, 25278.368040799127], "value": 20509.93627924294, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.9187470631167, 392.69566086283135, 394.7276379298286, 399.63233824881104, 419.3856000958597, 14308.499202527888, 20202.494937626547, 20635.896820515914, 21310.041048152852, 21351.57296854064, 15966.28151733172, 18090.185941632626, 20381.388558343275, 20941.47646308843, 20951.05720242589, 5805.751763370875, 6047.092936059582, 6071.210742603477, 6086.7869624563145, 6106.328303886569, 5933.940660593382, 5973.193618861442, 5987.311459182206, 6015.248093988252, 6052.394760523956, 5826.883462330751, 5913.349531551526, 5953.3748956359395, 5982.820515384546, 5997.700114994254, 5752.223449834302, 5881.14723365114, 5958.731857066421, 5959.135925290809, 5995.790147344844, 5162.74186290684, 5164.870878228036, 5178.533931946105, 5191.0, 5193.818216362447, 25577.360565985815, 26026.089086881868, 26431.73568264312, 26616.46767064667, 27223.04719334814, 2106.547092375147, 2137.6579747240366, 2163.707899433572, 2200.5378870437225, 2380.702412198475, 14515.228831127404, 15066.849331506653, 15203.515539559767, 15205.771913421308, 15231.238438078162, 1456.5047883719585, 1753.4301352060504, 1761.5860272835873, 1769.9469015929542, 1785.0806834480254, 116028.0, 119084.0, 119522.0, 120387.39806301, 121240.57518849574, 9327.95336023329, 10823.891761082288, 11066.944665276622, 11310.94344528272, 11359.375234362024, 14283.64290892736, 14369.425222990963, 14385.7842132366, 14385.78421323681, 14411.85588144126, 7672.0, 7707.4604777666145, 8578.399512034097, 8785.736427907173, 8852.645894164161, 8305.83388332242, 8343.165683431609, 8430.32557395407, 8567.400281980326, 8689.826203475895, 6831.555948863308, 6943.6875340609795, 6948.305169483011, 6952.270011648805, 6960.756373526902, 1854.2768320355071, 1895.251375706588, 1895.7535520382537, 1900.2399040383957, 1925.1433112265188, 919.8758167647416, 972.7033254857337, 988.268681175939, 1011.3577878047493, 1094.1192340166083, 17180.484585462455, 20737.0, 21570.490065695565, 21618.0, 21768.0, 960.3325688646323, 970.1365784451843, 1009.1321463541349, 1009.9343542669703, 1038.2887721910515, 11171.31888346791, 11612.535498580131, 11647.30116193031, 11747.765044699228, 11772.411379430994, 595739.0, 612441.0, 622426.0, 624999.0, 627130.8643456753, 132398.0, 145301.0, 147074.2646286783, 147837.0, 150546.74179887312, 1018.3177271228295, 1031.2214278219897, 1059.2638116508951, 1070.2294348069377, 1078.730317420645, 18136.818631813512, 18497.352592659732, 18682.532936676693, 18857.43427697171, 18977.146028428477, 54717.72641136928, 55371.723141384035, 56107.43892561022, 56126.59683507945, 56489.0, 77850.61074694591, 78205.0, 79248.60375698084, 79521.20478795138, 81556.5922170409, 6892.172939247122, 7458.627068646654, 7544.773656790305, 7648.158702542827, 7794.727184548514, 12431.44058517387, 12769.553065642658, 12934.676633084247, 13149.671258218621, 13241.139325943783, 9211.953940230256, 10257.435841028815, 11309.830352544883, 11317.603883864025, 11641.6507504775, 10890.074343680875, 10972.506237219184, 11155.888441115485, 11403.144764142557, 11711.414429278331, 11135.33188008722, 11494.195406321478, 11497.54009839597, 11569.710757231136, 11830.349330786888, 7494.0, 7557.773266802004, 7629.7329593463965, 7639.312461878458, 7855.293023627901, 9497.287703422133, 11097.38964356968, 11252.606158784403, 11326.886731132912, 11524.481398337262], "value": 9817.292175191758, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1443.4803470750535, 1599.5521254048867, 1641.7291146960752, 1902.4102528216254, 2028.1380413324334, 7024.859502809941, 8598.097199794025, 8920.286377089833, 8923.196912277901, 8972.775680607989, 7463.917731928867, 8423.0, 8775.297976161908, 8780.034196238408, 8883.866741998876, 1837.5498002989286, 2121.02432880875, 2186.212963333199, 2408.5303365843665, 2466.7533246675366, 2210.9778902210933, 2226.109556177526, 2405.338531903723, 2474.319562120413, 2475.777180053795, 2430.3559556717482, 2435.902563897442, 2437.31755108569, 2438.2685194441683, 2438.719547252071, 2402.5915594348917, 2442.853428794278, 2443.621238708003, 2448.608222684362, 2448.6327050942323, 2136.690179923918, 2148.291063948893, 2391.5695174868515, 2406.217979156781, 2471.4686342436285, 8785.868211976825, 8877.955610221972, 8888.644454221823, 8889.244414224799, 8892.510911899843, 5335.306410166692, 5516.365617953947, 5542.722863856831, 5544.77820887164, 5551.639143455663, 3527.3298073365977, 3577.3203091412806, 3665.505156803824, 3679.5952445231005, 3702.3705969985167, 987.3582171588453, 1033.8345864661694, 1038.0346277961494, 1038.1072277841051, 1046.518601443337, 11710.473028713728, 11979.520819167223, 11985.101117416141, 11999.700007499796, 12035.578754743541, 3986.0, 4002.799860006988, 4980.327655766425, 5005.6996580205305, 5020.874478138076, 10434.37393756377, 10445.68662940113, 10446.373217606968, 10446.477676116163, 10458.215633827342, 4571.51999040096, 5514.145307477347, 5515.034868898007, 5520.365158006881, 5550.389457159709, 4496.325551167286, 4859.635527335464, 4984.327115839388, 5092.567131793839, 5104.846854594368, 3683.0239986403626, 3943.171933893857, 4028.234635419261, 4047.838086476567, 4062.390641403813, 1048.501961568249, 1215.4469716279073, 1222.9082818788627, 1227.6010296653624, 1233.8766123387588, 798.4570492065451, 902.5667679513739, 912.6075787411344, 943.1747221181466, 956.5647630328184, 5677.205191273225, 5867.618604790675, 5905.320888097924, 5926.4666180044005, 6045.425684559959, 963.2246041936309, 973.6543527047937, 981.5926390547985, 995.9800803984025, 1045.3153184664056, 3996.4205190247253, 4000.079981604247, 4006.5192176938954, 4048.736832105904, 4076.6534844537955, 10605.734856628646, 11091.944540277409, 11144.05275551571, 11166.994970452699, 11175.99416052543, 10880.12958963281, 10955.945220273848, 10993.0, 11028.0074793269, 11146.777064458665, 7836.804079897935, 8271.586420679063, 8401.99176098872, 8411.747647570583, 8419.326453883674, 5708.143778433268, 6025.548083893638, 6139.8465038372615, 6167.691615419121, 6168.568200226121, 9922.40465572068, 10198.082172604501, 10277.355623100268, 10289.84565231507, 10322.0, 10072.395656260647, 10146.391216527032, 10325.74185645365, 10408.947955260175, 10412.427316497666, 6959.686814093479, 7687.654055567625, 7844.607769611611, 7847.332976697045, 7854.803629909069, 7396.96301518489, 7797.220277972384, 8235.752927412188, 8539.701110461352, 8566.657333706831, 7482.0, 7571.318581327707, 7577.393808495197, 7687.577183254751, 7781.5331080135375, 7457.962710186414, 7482.102147742088, 7507.061617297838, 7536.773896783106, 7556.2443755624, 6985.022096906336, 7035.296470352924, 7102.573845569283, 7105.3960413363375, 7143.785686429415, 4809.735464549483, 4821.7348045856415, 4894.14352488306, 4916.901661966669, 5078.695278283315, 4672.509386514367, 4880.975595122001, 4930.13722598551, 5052.722100284373, 5184.974075129751], "value": 4632.6987450490915, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.9693030696929, 311.32597925491314, 324.7661683587816, 326.7157572911567, 328.40394683649174, 4488.3043128315085, 4702.85891423257, 5250.6324557281005, 5280.894382112356, 5323.494268044535, 4152.958470415295, 5194.688318700871, 5240.13537766269, 5246.317978662778, 5260.132652908211, 3348.7990720556845, 3468.4623883298004, 3585.8027808470524, 4027.738197017199, 4040.191961607683, 3332.50012498126, 3363.4786608075788, 4039.333509970851, 4045.9797701011603, 4046.4739583854207, 3612.367835628768, 3861.5945325740813, 3978.701597380208, 3979.7214195006363, 3995.9600403996023, 3478.4608385700258, 3696.4085746280603, 3980.4228386883738, 3983.442318075443, 4062.309407400749, 3413.2762954707714, 3498.4577390504514, 3515.2617950230483, 3527.224010717638, 3530.452779819132, 4226.4928208615165, 4728.834490792806, 4765.571098601143, 5057.822976195852, 5085.669431486942, 4532.9546704533195, 4575.794089265991, 4587.7706114694465, 4588.655850811169, 4609.907801843944, 5430.859519500909, 5454.009286610399, 5454.218348642182, 5457.754401051922, 5495.203195536625, 3272.541844141822, 3730.198007428416, 3850.614938506127, 3872.612738726133, 3893.669038131762, 4618.792154353062, 4790.0, 5293.497117773805, 5443.0, 5804.941950580483, 3707.7023041935645, 3748.325301445766, 3771.8868433947027, 3926.5237629102844, 3939.7045221608496, 3497.510348551205, 3606.711463082947, 3970.7617542947514, 3975.900602484903, 3989.9601003989587, 4277.8288868444915, 4378.124375124988, 4518.683692141584, 4547.499775024745, 4565.497795242521, 4583.158441766126, 4597.402337696145, 5087.643864929493, 5494.120940649477, 5528.806491772769, 1163.941802909851, 1223.5167108991898, 1333.173552233427, 1364.7376177036201, 1368.8022979892594, 768.6541056524475, 807.3016840432983, 810.9619686800904, 822.2229992657001, 836.4103307168432, 3773.7735735855385, 3782.3380908340932, 3928.3518219493462, 4107.0553772631865, 4170.22594551931, 4145.398917157056, 4194.0, 4552.590266875931, 4741.336212930123, 4816.83242540851, 3914.5106861642294, 4008.8396464141106, 4015.377616469452, 4113.950942509641, 4350.891227719269, 2103.1797598936428, 2145.420736401163, 2158.6330323844986, 2323.885264409413, 2330.5338932213626, 4800.975995120002, 4866.394089950308, 5022.0, 5030.346055012825, 5057.243802127776, 4100.5489396166395, 4334.848280310237, 4417.7930008338335, 4422.049259409242, 4896.902061958812, 4479.827283767305, 4568.0, 4939.357883475125, 5003.74981250936, 5061.772220250097, 3805.961940380561, 4743.525647435229, 4756.788093536522, 4795.95204047955, 4827.42070951474, 4496.932546011878, 4757.170477765432, 5237.004969055867, 5379.54273886724, 5586.050371436784, 4855.223164293696, 5859.648421094748, 5963.3142188647125, 5964.433378829004, 5969.761209551743, 3473.6005359383994, 3820.2741479118877, 3909.667678247381, 3942.634419348368, 3946.506686664167, 5384.273123128328, 5672.262605861211, 5705.258316418839, 5726.455986681259, 5749.223854779551, 4316.827326906889, 5052.9494705052475, 5249.6062795289745, 5329.227262047059, 5531.976584331912, 5137.020528093772, 5202.531772140525, 5491.231227628055, 5509.173623956439, 5525.502704756431, 5076.289319495348, 5487.286652735279, 5501.946419737084, 5522.972385138049, 5533.695646739468, 4760.833370832004, 5234.031704134595, 5234.947650523446, 5257.8159764408065, 5641.5204707600315, 5026.44709082008, 5199.4020687619895, 5511.255980442589, 5511.696856672921, 5525.226468294522], "value": 3824.289155107044, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.7562947932389, 624.5752888036134, 650.6063831382014, 668.1848145262778, 680.1532092544783, 13477.0, 13706.109102908316, 13713.382897769612, 13925.930370348135, 13928.79106813396, 12938.93530532346, 13606.659833504167, 13840.238786866717, 13878.028538002343, 13966.58100256994, 922.9123233292827, 923.3951761596165, 941.4210260689695, 942.5334459442586, 944.3531181140941, 912.1198043887647, 914.2092090341873, 915.3729695158804, 918.4122161816409, 921.2675922641516, 979.9265055120859, 990.2078337330107, 994.8060128274989, 995.2833959549149, 996.1035068438428, 981.8281800684887, 982.6658935961735, 983.0218932162472, 986.9062439068277, 988.6243227573548, 728.0753443127198, 728.4900569601248, 728.5155371677828, 728.5774250934445, 733.534205779334, 12422.503099875994, 12573.497060117585, 12678.55625053128, 12679.429425675868, 12872.549460768829, 10943.890561094368, 11022.118230541537, 11078.889211107866, 11169.0, 11590.536378544932, 9163.725088247364, 9246.214071803905, 9255.167034966818, 9256.352055356128, 9327.113924177193, 3088.1970687621156, 3270.787398819069, 3366.0, 3373.0892658982184, 3374.7300215982664, 52613.47558506378, 71564.0, 71903.0, 78182.0, 78258.0, 9668.129868311746, 9717.951410242904, 9742.333848301318, 9864.309498335047, 10061.144802691853, 21778.564428711335, 21827.454313641967, 21848.45378865541, 21853.016614252396, 21856.453588660093, 7027.61348125858, 7220.602866842269, 7331.8900216496795, 7550.924490755132, 7784.571848548271, 4032.8991775205855, 4055.7566546007333, 4057.8173982170874, 4089.2230476209425, 4409.4267745193565, 3362.3107263011025, 3425.5718035245595, 3439.5872495300723, 3484.1463841359027, 3484.756067075331, 621.8756248750177, 625.0436831647586, 627.0562802981484, 627.6046090962665, 639.3798015924546, 478.652976591975, 550.1829782772579, 560.5264591266288, 567.943205679437, 586.234963372794, 7652.196519365498, 7725.806854828562, 7820.257075577811, 7874.921250787534, 7912.28789408956, 494.6364422149717, 538.9380221274525, 544.9400565937743, 546.5272539253517, 572.133218174466, 3489.703375213135, 3663.4871118043493, 3674.889753307405, 3854.9036274093373, 3869.8839034829, 80559.59720201361, 82062.58968705237, 83290.58354708187, 88752.0, 89186.55406722926, 72031.63984180045, 74517.88223176656, 76966.6151669238, 77382.6130869342, 78225.43956565791, 66006.33993660098, 66106.0, 66440.33559664438, 67428.32571674319, 68761.62476750437, 8011.278984891388, 8195.344372450309, 8281.627326770189, 8296.170382961656, 8454.463660901582, 32786.68853245844, 39845.60154398419, 41164.0, 41294.0, 42548.36177457403, 59233.40766592279, 59823.0, 60252.46137440101, 60518.0, 60762.39237607744, 11366.318020918772, 11490.94254528722, 11518.942405288255, 11529.654110376701, 12051.51793928233, 11728.648140555797, 11856.407179641155, 11870.169088163746, 12367.072469564999, 13029.283389413673, 11483.425828708698, 11560.0, 11815.881841181823, 11947.402629868646, 12104.213226140273, 11493.252938558968, 11514.0, 11672.533098675958, 11846.111541634584, 12180.330081845583, 11516.251443656136, 11673.94934455903, 11753.88246117562, 11849.111316651113, 12339.383030848243, 9596.0, 9605.519724013911, 9710.902890971, 9728.075832795874, 10059.49702514886, 8250.0, 9043.32175086858, 9891.35706179096, 9975.45135017552, 9980.800383992135], "value": 6676.523450137408, "unit": "score"}], "name": "dromaeo_css", "value": 7178.887664608118, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 112.0, 113.0, 112.0, 113.0, 111.0, 113.0, 113.0, 111.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 141.0, 156.0, 193.0, 116.0, 137.0, 135.0, 115.0, 169.0, 115.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 182.0, 182.0, 182.0, 182.0, 181.0, 181.0, 182.0, 222.0], "value": 186.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 57.0, 124.0, 58.0, 62.0, 58.0, 73.0, 135.0, 77.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 99.0, 133.0, 80.0, 85.0, 77.0, 102.0, 80.0, 77.0, 101.0], "value": 92.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 87.0, 134.0, 84.0, 87.0, 85.0, 118.0, 84.0], "value": 93.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 94.0, 93.0, 93.0, 93.0, 94.0, 93.0, 93.0, 91.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 83.0, 83.0, 85.0, 83.0, 83.0, 83.0, 86.0, 86.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 61.0, 57.0, 59.0, 80.0, 87.0, 168.0, 60.0, 57.0], "value": 74.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 69.0, 77.0, 73.0, 70.0, 68.0, 69.0, 69.0, 70.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 85.0, 82.0, 85.0, 82.0, 84.0, 84.0, 86.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 140.0, 140.0, 136.0, 139.0, 133.0, 136.0, 147.0, 250.0, 140.0], "value": 150.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 167.0, 165.0, 175.0, 172.0, 226.0, 213.0, 169.0, 165.0], "value": 178.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 68.0, 72.0, 82.0, 86.0, 87.0, 74.0, 66.0, 64.0], "value": 73.1, "unit": "score"}], "name": "kraken", "value": 1516.0, "alertThreshold": 2.0}]} 16:39:45 INFO - Return code: 0 16:39:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:39:45 ERROR - # TBPL SUCCESS # 16:39:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:39:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:39:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:39:45 INFO - 2016-05-10 16:39:45.755 screenresolution[2319:27621] starting screenresolution argv=screenresolution get 16:39:45 INFO - 2016-05-10 16:39:45.759 screenresolution[2319:27621] Display 0: 1600x1200x32@60 16:39:45 INFO - 2016-05-10 16:39:45.765 screenresolution[2320:27624] starting screenresolution argv=screenresolution list 16:39:45 INFO - Available Modes on Display 0 16:39:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:39:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:39:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:39:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:39:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:39:45 INFO - Intel Iris: 16:39:45 INFO - Chipset Model: Intel Iris 16:39:45 INFO - Type: GPU 16:39:45 INFO - Bus: Built-In 16:39:45 INFO - VRAM (Dynamic, Max): 1536 MB 16:39:45 INFO - Vendor: Intel (0x8086) 16:39:45 INFO - Device ID: 0x0a2e 16:39:45 INFO - Revision ID: 0x0009 16:39:45 INFO - Displays: 16:39:45 INFO - Display: 16:39:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:39:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:39:45 INFO - Display Serial Number: 1600x1200 60 16:39:45 INFO - Main Display: Yes 16:39:45 INFO - Mirror: Off 16:39:45 INFO - Online: Yes 16:39:45 INFO - Rotation: Supported 16:39:45 INFO - Return code: 0 16:39:45 INFO - Running post-action listener: _resource_record_post_action 16:39:45 INFO - ##### 16:39:45 INFO - ##### Finished run-tests step (success) 16:39:45 INFO - ##### 16:39:45 INFO - Running post-run listener: _resource_record_post_run 16:39:47 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 479232; Write bytes: 695898112; Read time: 6; Write time: 2348 16:39:47 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 375368192; Write bytes: 370767360; Read time: 28054; Write time: 1017 16:39:47 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 479232; Write bytes: 298191360; Read time: 6; Write time: 1266 16:39:47 INFO - Running post-run listener: _upload_blobber_files 16:39:47 INFO - Blob upload gear active. 16:39:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:39:47 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 16:39:47 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:39:47 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:39:47 INFO - (blobuploader) - INFO - Open directory for files ... 16:39:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:39:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:39:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:48 INFO - (blobuploader) - INFO - Done attempting. 16:39:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:39:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:39:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:49 INFO - (blobuploader) - INFO - Done attempting. 16:39:49 INFO - (blobuploader) - INFO - Iteration through files over. 16:39:49 INFO - Return code: 0 16:39:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:39:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:39:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56868ff4ecc4f4f79501f206691c045107ea98b1df6881467f52769c2cb579035f1eeb764c054b5ccc21dca2049d1a2f697880644585278125ac5d58ad106df1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8dcf985ebd8aaa5c9d6764cca67228aafb8604919d6ccb7404b8ddcc711a05643eef88abf3bd7a42e94b06bffd76e0a226662ba9675e43140efc3608a5dbdca"} 16:39:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:39:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:39:49 INFO - Contents: 16:39:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56868ff4ecc4f4f79501f206691c045107ea98b1df6881467f52769c2cb579035f1eeb764c054b5ccc21dca2049d1a2f697880644585278125ac5d58ad106df1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8dcf985ebd8aaa5c9d6764cca67228aafb8604919d6ccb7404b8ddcc711a05643eef88abf3bd7a42e94b06bffd76e0a226662ba9675e43140efc3608a5dbdca"} 16:39:49 INFO - Running post-run listener: copy_logs_to_upload_dir 16:39:49 INFO - Copying logs to upload dir... 16:39:49 INFO - mkdir: /builds/slave/test/build/upload/logs 16:39:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.441977 ========= master_lag: 0.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-10 16:39:50.102430) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 16:39:50.110047) ========= 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.6SVJ1CG7kr/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.PRlpTHwleV/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56868ff4ecc4f4f79501f206691c045107ea98b1df6881467f52769c2cb579035f1eeb764c054b5ccc21dca2049d1a2f697880644585278125ac5d58ad106df1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8dcf985ebd8aaa5c9d6764cca67228aafb8604919d6ccb7404b8ddcc711a05643eef88abf3bd7a42e94b06bffd76e0a226662ba9675e43140efc3608a5dbdca"} build_url:https://queue.taskcluster.net/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011418 build_url: 'https://queue.taskcluster.net/v1/task/Zb3QTGgSTAaiGvTEtSgupQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56868ff4ecc4f4f79501f206691c045107ea98b1df6881467f52769c2cb579035f1eeb764c054b5ccc21dca2049d1a2f697880644585278125ac5d58ad106df1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8dcf985ebd8aaa5c9d6764cca67228aafb8604919d6ccb7404b8ddcc711a05643eef88abf3bd7a42e94b06bffd76e0a226662ba9675e43140efc3608a5dbdca"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 16:39:50.138595) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 16:39:50.141564) ========= 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.6SVJ1CG7kr/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.PRlpTHwleV/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.005296 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 16:39:50.189149) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 16:39:50.189415) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 16:39:50.192712) ========= ========= Total master_lag: 0.59 =========