builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0212 starttime: 1469150300.24 results: success (0) buildid: 20160721160114 builduid: 00774abd330942c582dd84638a527c88 revision: 664bd6aa11e1c9e53f5e6e7bca4990265b563802 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:20.236249) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:20.236689) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:20.236961) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:20.358327) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:20.358645) ========= 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.kq6Zui3BEW/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.pl2p2H04dq/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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-07-21 18:18:20-- 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% 33.8M=0s 2016-07-21 18:18:20 (33.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107978 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:20.491295) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:20.491851) ========= 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.kq6Zui3BEW/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.pl2p2H04dq/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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.025556 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:20.540233) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 18:18:20.540588) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 664bd6aa11e1c9e53f5e6e7bca4990265b563802 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 664bd6aa11e1c9e53f5e6e7bca4990265b563802 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kq6Zui3BEW/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.pl2p2H04dq/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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-07-21 18:18:20,621 truncating revision to first 12 chars 2016-07-21 18:18:20,621 Setting DEBUG logging. 2016-07-21 18:18:20,621 attempt 1/10 2016-07-21 18:18:20,621 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/664bd6aa11e1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 18:18:21,461 unpacking tar archive at: fx-team-664bd6aa11e1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.118067 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 18:18:21.681217) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:21.681803) ========= script_repo_revision: 664bd6aa11e1c9e53f5e6e7bca4990265b563802 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:21.682457) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:21.682923) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 18:18:21.701521) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-07-21 18:18:21.701856) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kq6Zui3BEW/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.pl2p2H04dq/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 18:18:21 INFO - MultiFileLogger online at 20160721 18:18:21 in /builds/slave/test 18:18:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:18:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:18:21 INFO - {'append_to_log': False, 18:18:21 INFO - 'base_work_dir': '/builds/slave/test', 18:18:21 INFO - 'blob_upload_branch': 'Fx-Team', 18:18:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:18:21 INFO - 'branch': 'Fx-Team', 18:18:21 INFO - 'buildbot_json_path': 'buildprops.json', 18:18:21 INFO - 'config_files': ('talos/mac_config.py',), 18:18:21 INFO - 'default_actions': ('clobber', 18:18:21 INFO - 'read-buildbot-config', 18:18:21 INFO - 'download-and-extract', 18:18:21 INFO - 'populate-webroot', 18:18:21 INFO - 'create-virtualenv', 18:18:21 INFO - 'install', 18:18:21 INFO - 'run-tests'), 18:18:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:18:21 INFO - 'download_minidump_stackwalk': True, 18:18:21 INFO - 'download_symbols': 'ondemand', 18:18:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:18:21 INFO - 'tooltool.py': '/tools/tooltool.py', 18:18:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:18:21 INFO - '/tools/misc-python/virtualenv.py')}, 18:18:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:18:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:18:21 INFO - 'installer_path': 'installer.exe', 18:18:21 INFO - 'log_level': 'info', 18:18:21 INFO - 'log_name': 'talos', 18:18:21 INFO - 'log_to_console': True, 18:18:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:18:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:18:21 INFO - 'opt_config_files': (), 18:18:21 INFO - 'pip_index': False, 18:18:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:18:21 INFO - 'cmd': ('bash', 18:18:21 INFO - '-c', 18:18:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:18:21 INFO - 'enabled': True, 18:18:21 INFO - 'halt_on_failure': False, 18:18:21 INFO - 'name': 'check_screen_resolution'},), 18:18:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:18:21 INFO - 'cmd': ('bash', 18:18:21 INFO - '-c', 18:18:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:18:21 INFO - 'enabled': True, 18:18:21 INFO - 'halt_on_failure': False, 18:18:21 INFO - 'name': 'check_screen_resolution'},), 18:18:21 INFO - 'run_cmd_checks_enabled': True, 18:18:21 INFO - 'sps_profile': False, 18:18:21 INFO - 'sps_profile_interval': 0, 18:18:21 INFO - 'suite': 'dromaeojs', 18:18:21 INFO - 'system_bits': '32', 18:18:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:18:21 INFO - 'title': 't-yosemite-r7-0212', 18:18:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:18:21 INFO - 'use_talos_json': True, 18:18:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:18:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:18:21 INFO - 'work_dir': 'build'} 18:18:21 INFO - [mozharness: 2016-07-22 01:18:21.891556Z] Running clobber step. 18:18:21 INFO - Running pre-action listener: _resource_record_pre_action 18:18:21 INFO - Running main action method: clobber 18:18:21 INFO - rmtree: /builds/slave/test/build 18:18:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:18:23 INFO - Running post-action listener: _resource_record_post_action 18:18:23 INFO - [mozharness: 2016-07-22 01:18:23.983514Z] Finished clobber step (success) 18:18:23 INFO - [mozharness: 2016-07-22 01:18:23.983642Z] Running read-buildbot-config step. 18:18:23 INFO - Running pre-action listener: _resource_record_pre_action 18:18:23 INFO - Running main action method: read_buildbot_config 18:18:23 INFO - Using buildbot properties: 18:18:23 INFO - { 18:18:23 INFO - "project": "", 18:18:23 INFO - "product": "firefox", 18:18:23 INFO - "script_repo_revision": "production", 18:18:23 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:18:23 INFO - "repository": "", 18:18:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:18:23 INFO - "buildid": "20160721160114", 18:18:23 INFO - "pgo_build": "False", 18:18:23 INFO - "basedir": "/builds/slave/test", 18:18:23 INFO - "buildnumber": 893, 18:18:23 INFO - "slavename": "t-yosemite-r7-0212", 18:18:23 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:18:23 INFO - "platform": "yosemite_r7", 18:18:23 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 18:18:23 INFO - "branch": "fx-team", 18:18:23 INFO - "repo_path": "integration/fx-team", 18:18:23 INFO - "revision": "664bd6aa11e1c9e53f5e6e7bca4990265b563802", 18:18:23 INFO - "stage_platform": "macosx64", 18:18:23 INFO - "builduid": "00774abd330942c582dd84638a527c88", 18:18:23 INFO - "slavebuilddir": "test" 18:18:23 INFO - } 18:18:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 18:18:23 INFO - Running post-action listener: _resource_record_post_action 18:18:23 INFO - [mozharness: 2016-07-22 01:18:23.987120Z] Finished read-buildbot-config step (success) 18:18:23 INFO - [mozharness: 2016-07-22 01:18:23.987268Z] Running download-and-extract step. 18:18:23 INFO - Running pre-action listener: _resource_record_pre_action 18:18:23 INFO - Running main action method: download_and_extract 18:18:23 INFO - mkdir: /builds/slave/test/build/tests 18:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:23 INFO - https://queue.taskcluster.net/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:18:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 18:18:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 18:18:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 18:18:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 18:18:25 INFO - Downloaded 1199 bytes. 18:18:25 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 18:18:25 INFO - Using the following test package requirements: 18:18:25 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 18:18:25 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:18:25 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 18:18:25 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:18:25 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 18:18:25 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:18:25 INFO - u'jsshell-mac.zip'], 18:18:25 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:18:25 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 18:18:25 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 18:18:25 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:18:25 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 18:18:25 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:18:25 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 18:18:25 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:18:25 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 18:18:25 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 18:18:25 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 18:18:25 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:18: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')]} 18:18:25 INFO - https://queue.taskcluster.net/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:18:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:18:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:18:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:18:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:18:27 INFO - Downloaded 18806545 bytes. 18:18:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:18:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:18:30 INFO - Return code: 0 18:18:30 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:30 INFO - https://queue.taskcluster.net/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:18:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:18:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:18:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 18:18:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:18:32 INFO - Downloaded 18806545 bytes. 18:18:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:18:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:18:35 INFO - Return code: 0 18:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:35 INFO - https://queue.taskcluster.net/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:18:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:18:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:18:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 18:18:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:18:37 INFO - Downloaded 14403317 bytes. 18:18:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:18:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:18:37 INFO - Return code: 0 18:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:37 INFO - https://queue.taskcluster.net/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:18:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:18:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:18:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 18:18:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 18:18:40 INFO - Downloaded 112770459 bytes. 18:18:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:18:40 INFO - mkdir: /builds/slave/test/properties 18:18:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:18:40 INFO - Writing to file /builds/slave/test/properties/build_url 18:18:40 INFO - Contents: 18:18:40 INFO - build_url:https://queue.taskcluster.net/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 18:18:42 INFO - Running post-action listener: _resource_record_post_action 18:18:42 INFO - Running post-action listener: set_extra_try_arguments 18:18:42 INFO - [mozharness: 2016-07-22 01:18:42.283068Z] Finished download-and-extract step (success) 18:18:42 INFO - [mozharness: 2016-07-22 01:18:42.283213Z] Running populate-webroot step. 18:18:42 INFO - Running pre-action listener: _resource_record_pre_action 18:18:42 INFO - Running main action method: populate_webroot 18:18:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:18:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:18:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:18:42 INFO - '--tppagecycles', 18:18:42 INFO - '7'], 18:18:42 INFO - 'tests': ['tsvgm']}}, 18:18:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:18:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:18:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:18:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:18:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:18:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:18:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:42 INFO - 'talos_options': ['--disable-e10s'], 18:18:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:42 INFO - 'talos_options': ['--disable-e10s'], 18:18:42 INFO - 'tests': ['damp', 'tps']}, 18:18:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:42 INFO - 'tests': ['damp', 'tps']}, 18:18:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:18:42 INFO - 'tests': ['dromaeo_dom']}, 18:18:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:18:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:18:42 INFO - 'tests': ['basic_compositor_video']}, 18:18:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:18:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:18:42 INFO - 'tests': ['a11yr', 18:18:42 INFO - 'ts_paint', 18:18:42 INFO - 'tpaint', 18:18:42 INFO - 'sessionrestore', 18:18:42 INFO - 'sessionrestore_no_auto_restore', 18:18:42 INFO - 'tabpaint']}, 18:18:42 INFO - 'other-e10s': {'tests': ['a11yr', 18:18:42 INFO - 'ts_paint', 18:18:42 INFO - 'tpaint', 18:18:42 INFO - 'sessionrestore', 18:18:42 INFO - 'sessionrestore_no_auto_restore', 18:18:42 INFO - 'tabpaint']}, 18:18:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:18:42 INFO - 'ts_paint', 18:18:42 INFO - 'tpaint', 18:18:42 INFO - 'sessionrestore', 18:18:42 INFO - 'sessionrestore_no_auto_restore', 18:18:42 INFO - 'tabpaint']}, 18:18:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:18:42 INFO - 'ts_paint', 18:18:42 INFO - 'tpaint', 18:18:42 INFO - 'sessionrestore', 18:18:42 INFO - 'sessionrestore_no_auto_restore', 18:18:42 INFO - 'tabpaint']}, 18:18:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:18:42 INFO - 'tests': ['a11yr', 18:18:42 INFO - 'ts_paint', 18:18:42 INFO - 'tpaint', 18:18:42 INFO - 'sessionrestore', 18:18:42 INFO - 'sessionrestore_no_auto_restore', 18:18:42 INFO - 'tabpaint']}, 18:18:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:18:42 INFO - 'tests': ['a11yr', 18:18:42 INFO - 'ts_paint', 18:18:42 INFO - 'tpaint', 18:18:42 INFO - 'sessionrestore', 18:18:42 INFO - 'sessionrestore_no_auto_restore', 18:18:42 INFO - 'tabpaint']}, 18:18:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:18:42 INFO - 'tests': ['tsvgx', 18:18:42 INFO - 'tsvgr_opacity', 18:18:42 INFO - 'tart', 18:18:42 INFO - 'tscrollx', 18:18:42 INFO - 'cart']}, 18:18:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:18:42 INFO - 'tsvgr_opacity', 18:18:42 INFO - 'tart', 18:18:42 INFO - 'tscrollx', 18:18:42 INFO - 'cart']}, 18:18:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:42 INFO - 'talos_options': ['--disable-e10s'], 18:18:42 INFO - 'tests': ['tp5o']}, 18:18:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:42 INFO - 'tests': ['tp5o']}, 18:18:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:42 INFO - 'talos_options': ['--disable-e10s', 18:18:42 INFO - '--xperf_path', 18:18:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:42 INFO - 'tests': ['tp5n']}, 18:18:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:42 INFO - 'talos_options': ['--xperf_path', 18:18:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:42 INFO - 'tests': ['tp5n']}}, 18:18:42 INFO - 'talos.zip': {'path': '', 18:18:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:18:42 INFO - Running post-action listener: _resource_record_post_action 18:18:42 INFO - [mozharness: 2016-07-22 01:18:42.302232Z] Finished populate-webroot step (success) 18:18:42 INFO - [mozharness: 2016-07-22 01:18:42.302340Z] Running create-virtualenv step. 18:18:42 INFO - Running pre-action listener: _resource_record_pre_action 18:18:42 INFO - Running main action method: create_virtualenv 18:18:42 INFO - Creating virtualenv /builds/slave/test/build/venv 18:18:42 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 18:18:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:18:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:18:42 INFO - Using real prefix '/tools/python27' 18:18:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:18:43 INFO - Installing distribute.............................................................................................................................................................................................done. 18:18:45 INFO - Installing pip.................done. 18:18:45 INFO - Return code: 0 18:18:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:45 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 0x101b69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bfe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd568cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acdd30>, '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 0x101832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ac5b20>, '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.kq6Zui3BEW/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.pl2p2H04dq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 18:18:45 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 18:18:45 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 18:18:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kq6Zui3BEW/Render', 18:18:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:46 INFO - 'HOME': '/Users/cltbld', 18:18:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:46 INFO - 'LOGNAME': 'cltbld', 18:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:46 INFO - 'MOZ_NO_REMOTE': '1', 18:18:46 INFO - 'NO_EM_RESTART': '1', 18:18:46 INFO - 'PAGER': '/bin/cat', 18:18:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:46 INFO - 'PWD': '/builds/slave/test', 18:18:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:46 INFO - 'SHELL': '/bin/bash', 18:18:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pl2p2H04dq/Listeners', 18:18:46 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 18:18:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:46 INFO - 'USER': 'cltbld', 18:18:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:46 INFO - 'XPC_FLAGS': '0x0', 18:18:46 INFO - 'XPC_SERVICE_NAME': '0', 18:18:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:46 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 18:18:46 INFO - Cleaning up... 18:18:46 INFO - Return code: 0 18:18:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18: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')]} 18:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18: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')]} 18:18:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bfe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd568cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acdd30>, '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 0x101832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ac5b20>, '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.kq6Zui3BEW/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.pl2p2H04dq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 18:18:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:18:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:18:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kq6Zui3BEW/Render', 18:18:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:46 INFO - 'HOME': '/Users/cltbld', 18:18:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:46 INFO - 'LOGNAME': 'cltbld', 18:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:46 INFO - 'MOZ_NO_REMOTE': '1', 18:18:46 INFO - 'NO_EM_RESTART': '1', 18:18:46 INFO - 'PAGER': '/bin/cat', 18:18:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:46 INFO - 'PWD': '/builds/slave/test', 18:18:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:46 INFO - 'SHELL': '/bin/bash', 18:18:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pl2p2H04dq/Listeners', 18:18:46 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 18:18:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:46 INFO - 'USER': 'cltbld', 18:18:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:46 INFO - 'XPC_FLAGS': '0x0', 18:18:46 INFO - 'XPC_SERVICE_NAME': '0', 18:18:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:46 INFO - Downloading/unpacking psutil>=3.1.1 18:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:18:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:49 INFO - Installing collected packages: psutil 18:18:49 INFO - Running setup.py install for psutil 18:18:49 INFO - building 'psutil._psutil_osx' extension 18:18:49 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 18:18:50 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 18:18:50 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 18:18:50 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 18:18:50 INFO - building 'psutil._psutil_posix' extension 18:18:50 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 18:18:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:18:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:18:50 INFO - ^ 18:18:50 INFO - 1 warning generated. 18:18:50 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 18:18:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:50 INFO - Successfully installed psutil 18:18:50 INFO - Cleaning up... 18:18:50 INFO - Return code: 0 18:18:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18: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')]} 18:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18: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')]} 18:18:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:50 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bfe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd568cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acdd30>, '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 0x101832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ac5b20>, '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.kq6Zui3BEW/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.pl2p2H04dq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 18:18:50 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.3'] in /builds/slave/test/build 18:18:50 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.3 18:18:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kq6Zui3BEW/Render', 18:18:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:50 INFO - 'HOME': '/Users/cltbld', 18:18:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:50 INFO - 'LOGNAME': 'cltbld', 18:18:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:50 INFO - 'MOZ_NO_REMOTE': '1', 18:18:50 INFO - 'NO_EM_RESTART': '1', 18:18:50 INFO - 'PAGER': '/bin/cat', 18:18:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:50 INFO - 'PWD': '/builds/slave/test', 18:18:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:50 INFO - 'SHELL': '/bin/bash', 18:18:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pl2p2H04dq/Listeners', 18:18:50 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 18:18:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:50 INFO - 'USER': 'cltbld', 18:18:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:50 INFO - 'XPC_FLAGS': '0x0', 18:18:50 INFO - 'XPC_SERVICE_NAME': '0', 18:18:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:18:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:18:53 INFO - Installing collected packages: mozsystemmonitor 18:18:53 INFO - Running setup.py install for mozsystemmonitor 18:18:53 INFO - Successfully installed mozsystemmonitor 18:18:53 INFO - Cleaning up... 18:18:53 INFO - Return code: 0 18:18:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:18:53 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')]} 18:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:53 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')]} 18:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:53 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bfe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd568cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acdd30>, '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 0x101832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ac5b20>, '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.kq6Zui3BEW/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.pl2p2H04dq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 18:18:53 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==2.5.1'] in /builds/slave/test/build 18:18:53 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==2.5.1 18:18:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kq6Zui3BEW/Render', 18:18:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:53 INFO - 'HOME': '/Users/cltbld', 18:18:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:53 INFO - 'LOGNAME': 'cltbld', 18:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:53 INFO - 'MOZ_NO_REMOTE': '1', 18:18:53 INFO - 'NO_EM_RESTART': '1', 18:18:53 INFO - 'PAGER': '/bin/cat', 18:18:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:53 INFO - 'PWD': '/builds/slave/test', 18:18:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:53 INFO - 'SHELL': '/bin/bash', 18:18:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pl2p2H04dq/Listeners', 18:18:53 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 18:18:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:53 INFO - 'USER': 'cltbld', 18:18:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:53 INFO - 'XPC_FLAGS': '0x0', 18:18:53 INFO - 'XPC_SERVICE_NAME': '0', 18:18:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:53 INFO - Downloading/unpacking jsonschema==2.5.1 18:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:18:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:56 INFO - Downloading functools32-3.2.3-2.tar.gz 18:18:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:18:56 INFO - warning: no files found matching '*.txt' 18:18:56 INFO - no previously-included directories found matching 'build' 18:18:56 INFO - no previously-included directories found matching 'dist' 18:18:56 INFO - no previously-included directories found matching '.git*' 18:18:56 INFO - Installing collected packages: jsonschema, functools32 18:18:56 INFO - Running setup.py install for functools32 18:18:57 INFO - warning: no files found matching '*.txt' 18:18:57 INFO - no previously-included directories found matching 'build' 18:18:57 INFO - no previously-included directories found matching 'dist' 18:18:57 INFO - no previously-included directories found matching '.git*' 18:18:57 INFO - Successfully installed jsonschema functools32 18:18:57 INFO - Cleaning up... 18:18:57 INFO - Return code: 0 18:18:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:18:57 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')]} 18:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:57 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')]} 18:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:57 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 0x101b69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bfe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd568cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acdd30>, '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 0x101832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ac5b20>, '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.kq6Zui3BEW/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.pl2p2H04dq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 18:18:57 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 18:18:57 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 18:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kq6Zui3BEW/Render', 18:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:57 INFO - 'HOME': '/Users/cltbld', 18:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:57 INFO - 'LOGNAME': 'cltbld', 18:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:57 INFO - 'MOZ_NO_REMOTE': '1', 18:18:57 INFO - 'NO_EM_RESTART': '1', 18:18:57 INFO - 'PAGER': '/bin/cat', 18:18:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:57 INFO - 'PWD': '/builds/slave/test', 18:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:57 INFO - 'SHELL': '/bin/bash', 18:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pl2p2H04dq/Listeners', 18:18:57 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 18:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:57 INFO - 'USER': 'cltbld', 18:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:57 INFO - 'XPC_FLAGS': '0x0', 18:18:57 INFO - 'XPC_SERVICE_NAME': '0', 18:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:57 INFO - Downloading/unpacking blobuploader==1.2.4 18:18:57 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 18:18:57 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 18:18:57 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 18:18:57 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 18:18:57 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 18:18:57 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 18:18:59 INFO - Downloading blobuploader-1.2.4.tar.gz 18:18:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:19:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:19:00 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 18:19:00 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 18:19:00 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 18:19:00 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 18:19:00 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 18:19:00 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 18:19:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:19:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:19:00 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 18:19:00 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 18:19:00 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 18:19:00 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 18:19:00 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 18:19:00 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 18:19:01 INFO - Downloading docopt-0.6.1.tar.gz 18:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:19:01 INFO - Installing collected packages: blobuploader, requests, docopt 18:19:01 INFO - Running setup.py install for blobuploader 18:19:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:19:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:19:01 INFO - Running setup.py install for requests 18:19:01 INFO - Running setup.py install for docopt 18:19:02 INFO - Successfully installed blobuploader requests docopt 18:19:02 INFO - Cleaning up... 18:19:02 INFO - Return code: 0 18:19:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:19:02 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')]} 18:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:02 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')]} 18:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:02 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 0x101b69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bfe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd568cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acdd30>, '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 0x101832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ac5b20>, '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.kq6Zui3BEW/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.pl2p2H04dq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 18:19:02 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 18:19:02 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 18:19:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kq6Zui3BEW/Render', 18:19:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:02 INFO - 'HOME': '/Users/cltbld', 18:19:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:02 INFO - 'LOGNAME': 'cltbld', 18:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:02 INFO - 'MOZ_NO_REMOTE': '1', 18:19:02 INFO - 'NO_EM_RESTART': '1', 18:19:02 INFO - 'PAGER': '/bin/cat', 18:19:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:02 INFO - 'PWD': '/builds/slave/test', 18:19:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:02 INFO - 'SHELL': '/bin/bash', 18:19:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pl2p2H04dq/Listeners', 18:19:02 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 18:19:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:02 INFO - 'USER': 'cltbld', 18:19:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:02 INFO - 'XPC_FLAGS': '0x0', 18:19:02 INFO - 'XPC_SERVICE_NAME': '0', 18:19:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:19:02 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-cxcW6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:19:02 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-pADFVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:19:02 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-dLbvnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:19:03 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-czJ3Ic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:19:03 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-RMFPdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:19:03 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-1bOEgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:19:03 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-HdKIUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:19:03 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-RqgWKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:19:03 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-0qPiEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:19:03 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-gZBDej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:19:03 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-6uv8BU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:19:03 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-1fUwwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:19:04 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-t9DaGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:19:04 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-OOivrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:19:04 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-zeCGOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:19:04 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-uVcFjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:19:04 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-mGPSan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:19:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:19:04 INFO - Running setup.py install for manifestparser 18:19:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:19:04 INFO - Running setup.py install for mozcrash 18:19:04 INFO - Running setup.py install for mozdebug 18:19:04 INFO - Running setup.py install for mozdevice 18:19:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:19:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:19:05 INFO - Running setup.py install for mozfile 18:19:05 INFO - Running setup.py install for mozhttpd 18:19:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:19:05 INFO - Running setup.py install for mozinfo 18:19:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:19:05 INFO - Running setup.py install for mozInstall 18:19:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:19:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:19:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:19:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:19:05 INFO - Running setup.py install for mozleak 18:19:05 INFO - Running setup.py install for mozlog 18:19:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:19:06 INFO - Running setup.py install for moznetwork 18:19:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:19:06 INFO - Running setup.py install for mozprocess 18:19:06 INFO - Running setup.py install for mozprofile 18:19:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:19:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:19:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:19:06 INFO - Running setup.py install for mozrunner 18:19:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:19:06 INFO - Running setup.py install for mozscreenshot 18:19:06 INFO - Running setup.py install for moztest 18:19:06 INFO - Running setup.py install for mozversion 18:19:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:19:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:19:07 INFO - Cleaning up... 18:19:07 INFO - Return code: 0 18:19:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:19:07 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')]} 18:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:07 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')]} 18:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:07 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 0x101b69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bfe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd568cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acdd30>, '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 0x101832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ac5b20>, '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.kq6Zui3BEW/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.pl2p2H04dq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 18:19:07 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 18:19:07 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 18:19:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kq6Zui3BEW/Render', 18:19:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:07 INFO - 'HOME': '/Users/cltbld', 18:19:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:07 INFO - 'LOGNAME': 'cltbld', 18:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:07 INFO - 'MOZ_NO_REMOTE': '1', 18:19:07 INFO - 'NO_EM_RESTART': '1', 18:19:07 INFO - 'PAGER': '/bin/cat', 18:19:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:07 INFO - 'PWD': '/builds/slave/test', 18:19:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:07 INFO - 'SHELL': '/bin/bash', 18:19:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pl2p2H04dq/Listeners', 18:19:07 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 18:19:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:07 INFO - 'USER': 'cltbld', 18:19:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:07 INFO - 'XPC_FLAGS': '0x0', 18:19:07 INFO - 'XPC_SERVICE_NAME': '0', 18:19:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:19:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-503hTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:19:07 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)) 18:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:19:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Ef9bUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:19:07 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)) 18:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:19:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-oCj3aP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:19:07 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)) 18:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:19:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-JYgeH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:19:07 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)) 18:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:19:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-p96ZvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:19:07 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)) 18:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:19:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-pFZvS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:19:08 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)) 18:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:19:08 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-pNibPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:19:08 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)) 18:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:19:08 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-1PZEn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:19:08 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)) 18:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:19:08 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-b1IcOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:19:08 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)) 18:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:19:08 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-BIm1wU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 18:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:19:08 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Tx1tR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:19:08 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)) 18:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:19:08 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-ENj_Q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 18:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:19:08 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-dcTEkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:19:08 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)) 18:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:19:08 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-BfWT5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 18:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:19:08 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Hg93gv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:19:09 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)) 18:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:19:09 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-ing272-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:19:09 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)) 18:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:19:09 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-xMRDuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:19:09 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)) 18:19:09 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)) 18:19:09 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)) 18:19:09 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)) 18:19:09 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)) 18:19:09 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)) 18:19:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:19:09 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 18:19:09 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 18:19:09 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 18:19:09 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 18:19:09 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 18:19:09 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 18:19:12 INFO - Downloading blessings-1.6.tar.gz 18:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:19:12 INFO - Installing collected packages: blessings 18:19:12 INFO - Running setup.py install for blessings 18:19:12 INFO - Successfully installed blessings 18:19:12 INFO - Cleaning up... 18:19:12 INFO - Return code: 0 18:19:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:19:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:19:12 INFO - Reading from file tmpfile_stdout 18:19:12 INFO - Current package versions: 18:19:12 INFO - blessings == 1.6 18:19:12 INFO - blobuploader == 1.2.4 18:19:12 INFO - docopt == 0.6.1 18:19:12 INFO - functools32 == 3.2.3-2 18:19:12 INFO - jsonschema == 2.5.1 18:19:12 INFO - manifestparser == 1.1 18:19:12 INFO - mozInstall == 1.12 18:19:12 INFO - mozcrash == 0.17 18:19:12 INFO - mozdebug == 0.1 18:19:12 INFO - mozdevice == 0.48 18:19:12 INFO - mozfile == 1.2 18:19:12 INFO - mozhttpd == 0.7 18:19:12 INFO - mozinfo == 0.9 18:19:12 INFO - mozleak == 0.1 18:19:12 INFO - mozlog == 3.3 18:19:12 INFO - moznetwork == 0.27 18:19:12 INFO - mozprocess == 0.23 18:19:12 INFO - mozprofile == 0.28 18:19:12 INFO - mozrunner == 6.12 18:19:12 INFO - mozscreenshot == 0.1 18:19:12 INFO - mozsystemmonitor == 0.3 18:19:12 INFO - moztest == 0.7 18:19:12 INFO - mozversion == 1.4 18:19:12 INFO - psutil == 3.1.1 18:19:12 INFO - requests == 1.2.3 18:19:12 INFO - wsgiref == 0.1.2 18:19:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:12 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 0x101b69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bfe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd568cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acdd30>, '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 0x101832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ac5b20>, '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.kq6Zui3BEW/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.pl2p2H04dq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 18:19:12 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 18:19:12 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 18:19:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kq6Zui3BEW/Render', 18:19:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:12 INFO - 'HOME': '/Users/cltbld', 18:19:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:12 INFO - 'LOGNAME': 'cltbld', 18:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:12 INFO - 'MOZ_NO_REMOTE': '1', 18:19:12 INFO - 'NO_EM_RESTART': '1', 18:19:12 INFO - 'PAGER': '/bin/cat', 18:19:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:12 INFO - 'PWD': '/builds/slave/test', 18:19:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:12 INFO - 'SHELL': '/bin/bash', 18:19:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pl2p2H04dq/Listeners', 18:19:12 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 18:19:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:12 INFO - 'USER': 'cltbld', 18:19:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:12 INFO - 'XPC_FLAGS': '0x0', 18:19:12 INFO - 'XPC_SERVICE_NAME': '0', 18:19:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:12 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)) 18:19:12 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)) 18:19:12 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)) 18:19:12 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)) 18:19:12 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)) 18:19:12 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)) 18:19:12 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)) 18:19:12 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)) 18:19:12 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)) 18:19:12 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)) 18:19:12 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)) 18:19:12 INFO - Cleaning up... 18:19:13 INFO - Return code: 0 18:19:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:19:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bfe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd568cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acdd30>, '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 0x101832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ac5b20>, '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.kq6Zui3BEW/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.pl2p2H04dq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 18:19:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:19:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:19:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kq6Zui3BEW/Render', 18:19:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:13 INFO - 'HOME': '/Users/cltbld', 18:19:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:13 INFO - 'LOGNAME': 'cltbld', 18:19:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:13 INFO - 'MOZ_NO_REMOTE': '1', 18:19:13 INFO - 'NO_EM_RESTART': '1', 18:19:13 INFO - 'PAGER': '/bin/cat', 18:19:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:13 INFO - 'PWD': '/builds/slave/test', 18:19:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:13 INFO - 'SHELL': '/bin/bash', 18:19:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pl2p2H04dq/Listeners', 18:19:13 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 18:19:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:13 INFO - 'USER': 'cltbld', 18:19:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:13 INFO - 'XPC_FLAGS': '0x0', 18:19:13 INFO - 'XPC_SERVICE_NAME': '0', 18:19:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:19:13 INFO - Cleaning up... 18:19:13 INFO - Return code: 0 18:19:13 INFO - Running post-action listener: _resource_record_post_action 18:19:13 INFO - Running post-action listener: _start_resource_monitoring 18:19:13 INFO - Starting resource monitoring. 18:19:13 INFO - [mozharness: 2016-07-22 01:19:13.294225Z] Finished create-virtualenv step (success) 18:19:13 INFO - [mozharness: 2016-07-22 01:19:13.295197Z] Running install step. 18:19:13 INFO - Running pre-action listener: _resource_record_pre_action 18:19:13 INFO - Running main action method: install 18:19:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:19:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:19:13 INFO - Reading from file tmpfile_stdout 18:19:13 INFO - Detecting whether we're running mozinstall >=1.0... 18:19:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:19:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:19:13 INFO - Reading from file tmpfile_stdout 18:19:13 INFO - Output received: 18:19:13 INFO - Usage: mozinstall [options] installer 18:19:13 INFO - Options: 18:19:13 INFO - -h, --help show this help message and exit 18:19:13 INFO - -d DEST, --destination=DEST 18:19:13 INFO - Directory to install application into. [default: 18:19:13 INFO - "/builds/slave/test"] 18:19:13 INFO - --app=APP Application being installed. [default: firefox] 18:19:13 INFO - mkdir: /builds/slave/test/build/application 18:19:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:19:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:19:48 INFO - Reading from file tmpfile_stdout 18:19:48 INFO - Output received: 18:19:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:19:48 INFO - Running post-action listener: _resource_record_post_action 18:19:48 INFO - [mozharness: 2016-07-22 01:19:48.797977Z] Finished install step (success) 18:19:48 INFO - [mozharness: 2016-07-22 01:19:48.798149Z] Running run-tests step. 18:19:48 INFO - Running pre-action listener: _resource_record_pre_action 18:19:48 INFO - Running main action method: run_tests 18:19:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:19:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:19:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:19:48 INFO - 2016-07-21 18:19:48.812 screenresolution[1642:7532] starting screenresolution argv=screenresolution get 18:19:48 INFO - 2016-07-21 18:19:48.815 screenresolution[1642:7532] Display 0: 1600x1200x32@60 18:19:48 INFO - 2016-07-21 18:19:48.821 screenresolution[1643:7534] starting screenresolution argv=screenresolution list 18:19:48 INFO - Available Modes on Display 0 18:19:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:19:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:19:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:19:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:19:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:19:49 INFO - Intel Iris: 18:19:49 INFO - Chipset Model: Intel Iris 18:19:49 INFO - Type: GPU 18:19:49 INFO - Bus: Built-In 18:19:49 INFO - VRAM (Dynamic, Max): 1536 MB 18:19:49 INFO - Vendor: Intel (0x8086) 18:19:49 INFO - Device ID: 0x0a2e 18:19:49 INFO - Revision ID: 0x0009 18:19:49 INFO - Displays: 18:19:49 INFO - Display: 18:19:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:19:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:19:49 INFO - Display Serial Number: 1600x1200 60 18:19:49 INFO - Main Display: Yes 18:19:49 INFO - Mirror: Off 18:19:49 INFO - Online: Yes 18:19:49 INFO - Rotation: Supported 18:19:49 INFO - Return code: 0 18:19:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:19:49 INFO - Python 2.7.3 18:19:49 INFO - Return code: 0 18:19:49 INFO - grabbing minidump binary from tooltool 18:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:49 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101acdd30>, '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 0x101832db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ac5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:19:49 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 18:19:49 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 18:19:49 INFO - Calling ['/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'] with output_timeout 600 18:19:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:19:49 INFO - Return code: 0 18:19:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:19:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:19:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:19:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:19:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:19:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0212', '--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 18:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0212 --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 18:19:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kq6Zui3BEW/Render', 18:19:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:49 INFO - 'HOME': '/Users/cltbld', 18:19:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:49 INFO - 'LOGNAME': 'cltbld', 18:19:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:19:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:49 INFO - 'MOZ_NO_REMOTE': '1', 18:19:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:19:49 INFO - 'NO_EM_RESTART': '1', 18:19:49 INFO - 'PAGER': '/bin/cat', 18:19:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:49 INFO - 'PWD': '/builds/slave/test', 18:19:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:19:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:49 INFO - 'SHELL': '/bin/bash', 18:19:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pl2p2H04dq/Listeners', 18:19:49 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 18:19:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:49 INFO - 'USER': 'cltbld', 18:19:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:49 INFO - 'XPC_FLAGS': '0x0', 18:19:49 INFO - 'XPC_SERVICE_NAME': '0', 18:19:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0212', '--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 18:19:49 INFO - mozversion application_buildid: 20160721160114 18:19:49 INFO - mozversion application_changeset: 664bd6aa11e1c9e53f5e6e7bca4990265b563802 18:19:49 INFO - mozversion application_display_name: Nightly 18:19:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:19:49 INFO - mozversion application_name: Firefox 18:19:49 INFO - mozversion application_remotingname: firefox 18:19:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:19:49 INFO - mozversion application_vendor: Mozilla 18:19:49 INFO - mozversion application_version: 50.0a1 18:19:49 INFO - mozversion platform_buildid: 20160721160114 18:19:49 INFO - mozversion platform_changeset: 664bd6aa11e1c9e53f5e6e7bca4990265b563802 18:19:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:19:49 INFO - mozversion platform_version: 50.0a1 18:19:49 INFO - using testdate: 1469150389 18:19:49 INFO - actual date: 1469150389 18:19:49 INFO - starting webserver on 'localhost:49267' 18:19:49 INFO - SUITE-START | Running 2 tests 18:19:49 INFO - TEST-START | dromaeo_css 18:19:49 INFO - operating with platform_type : mac_ 18:19:49 INFO - Initialising browser for dromaeo_css test... 18:19:49 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmp3eZ0tl/profile http://localhost:49267/getInfo.html) 18:19:51 INFO - PROCESS | 1653 | JavaScript error: , line 0: uncaught exception: undefined 18:19:51 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 18:19:51 INFO - PROCESS | 1653 | colorDepth:24 18:19:51 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 18:19:51 INFO - PROCESS | 1653 | __metrics 18:19:52 INFO - PROCESS | 1653 | 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" 18:19:52 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:19:52 INFO - TEST-INFO | 1653: exit 0 18:19:52 INFO - Browser initialized. 18:19:52 INFO - Running cycle 1/1 for dromaeo_css test... 18:19:52 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmp3eZ0tl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:20:04 INFO - PROCESS | 1656 | RSS: Main: 245321728 18:20:04 INFO - PROCESS | 1656 | 18:20:56 INFO - PROCESS | 1656 | 1469150456499 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] 18:23:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 18:23:08 INFO - PROCESS | 1656 | RSS: Main: 352747520 18:23:08 INFO - PROCESS | 1656 | 18:26:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 18:26:12 INFO - PROCESS | 1656 | RSS: Main: 380444672 18:26:12 INFO - PROCESS | 1656 | 18:29:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 18:29:15 INFO - PROCESS | 1656 | RSS: Main: 379453440 18:29:15 INFO - PROCESS | 1656 | 18:32:17 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 18:32:18 INFO - PROCESS | 1656 | RSS: Main: 379441152 18:32:18 INFO - PROCESS | 1656 | 18:35:21 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 18:35:22 INFO - PROCESS | 1656 | RSS: Main: 344870912 18:35:22 INFO - PROCESS | 1656 | 18:38:25 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 18:38:25 INFO - PROCESS | 1656 | RSS: Main: 455946240 18:38:25 INFO - PROCESS | 1656 | 18:38:25 INFO - PROCESS | 1656 | __start_tp_report 18:38:25 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 18:38:25 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 18:38:25 INFO - PROCESS | 1656 | |i|pagename|runs| 18:38:25 INFO - PROCESS | 1656 | |0;dojo.html;3173.2542852429683;3344.615369232539;3363.915902102449;3387.728981681465;3397.6602339766027;5510.972445137779;5627.493525582707;5637.91543126853;5739.655620662758;5741.397153298901;5633.380328163898;5651.632643878155;5670.546356291497;5671.177679236508;5674;5145.099607568679;5176.404713457944;5192.1432963560965;5202.167653175493;5247.003069416799;4410.448693913261;5287;5334.91997620036;5351.491608297206;5358.705271210082;5259.421463638997;5265.18389649603;5278.683279003253;5285.02227087988;5339.439358867321;5352.33095863019;5352.5182733553775;5360.544353729938;5375.247465354853;5383.596230282706;5127.743612819344;5142.177251639757;5176.559992400613;5262.184361423985;5263.763130659129;5646.802361917313;5678.602497825113;5684.261046064026;5692.829215123553;5712.6286791358425;6247;6251;6257.624542527463;6263.1544741459775;6267.905981410283;6136.631802091889;6184.783532576338;6280;6305.779297724557;6326.177596912372;5878.147668588073;5878.2358293421585;5910.261217347832;5915.763369465173;5921.763129474859;6321.399467050623;6329.177206963065;6330.493560515148;6347.96826015867;6350.206224221973;6093.756249750049;6097.457563710582;6113.050656958994;6154.815355539341;6205.503559715302;6268.09112678659;6281.842953926097;6284.685765711696;6294.370562943669;6301.653409062454;6199.380061993855;6248.5313601479165;6248.687565621701;6273.780417685359;6296.0555916612875;6112.655464441461;6131.651934473353;6216.564840461215;6288.654124023223;6299.874002519924;4970.552650261494;5002.474740152232;5006.549410553068;5016.097102521581;5019.573336266385;4441.133978874153;4475.820967161342;4513.887152821206;4551.180787458289;4561.475430325555;5711.200431939532;5711.571632127607;5719.2279042328755;5724;5780.42195780427;6210.689465526705;6252.932778227051;6312.368763123743;6316.178896743486;6325.5255855811;5561.63849349791;5631.958087753778;5674.205611214434;5738.110592858114;5780.335261444988;5471.534919531805;5475.260839786658;5476.561875049986;5484.780608775604;5526.861828454241;6310.873782524323;6338.873222535523;6374.235091789015;6393.6163830170335;6395.8720825582295;6181.567290289637;6210.347913469202;6259.029850373199;6297.4017468340435;6338.778142764981;6286.18279623646;6346.90479642815;6359.077933699495;6373.330800266091;6386.776462823779;6221.2845522763655;6224.253089629274;6225.284092329259;6232.532560057924;6239.968800156152;6075.119107729266;6203.751849925952;6289.308176100717;6291.276503202191;6299.779507717208;6106.096658337741;6152.384761523812;6163.476104531165;6203.937960620336;6204.503639708722;6186.536009799193;6195.96902015487;6200.56596038273;6206.627602343874;6208.534359922934;5781.219535362672;5946.791862284799;6011.969940150271;6020.4280593343565;6043.184170136975;5977.46202841729;6123.357047509948;6182.6599537025895;6207.379262073757;6213.844653883508;5961.016432288624;6002.909956350571;6012.549058820693;6023.397660233942;6024.7288872001745 18:38:25 INFO - PROCESS | 1656 | |1;ext.html;505.906407314647;512.8897287083279;517.4153206876229;523.4477626104459;534.0333995468205;31230.68769312306;43415.565844341545;44625.553744462544;44694.55305446945;47131.76434117825;45631.0873782525;46094.0781184377;46185;46193.07613847721;46228.306575401395;9808.313418060721;10006.649767258148;10011.24915631327;10017.749556261115;10025;8886.86669699955;9105.226055785266;10399.636012739556;12142.332171730552;12385.071119666016;12320.445579948924;12429.564965226218;12435.440405181744;12439.875601244008;12449.937750311236;8870.246029087535;12307.4461649226;12382.566610168691;12387.876121238762;12402.441890114966;8859.069797671216;9070.319726020509;9081.68214112503;11873.94063029688;12171.817422738666;44000.779996099816;44257.89355266112;44304;44324.89187770299;44338.556614433764;3452.5511683481236;3699.445083237536;4257.233697934371;4263.317869140923;4452.487963884162;31872.6812731872;32854.68581256747;33784.15539611505;33895.83052084724;33934.83032584821;3256.453125232547;3363.1928337199142;3395.2869897321593;3415.0949998250485;3441.6730410610953;528515;529519;529956;530318;530355;67269.66365168143;67747;67768.66115669488;67791.66104169447;68023.65988170127;71585.2841471589;71693;71751;71834.28165718276;71854.64072679603;16592.502224933272;16982.150892455327;16986.660266794595;17004;17008.404705835237;11919.52321907114;12127.514899403925;12207.267563946192;12216.26702397859;12641.494340226289;7877.803054923558;7956.880646790303;7965.48224365426;14382.990907388974;19095.522611934535;8001.5599142046585;8073.9192608074345;8130.837383252302;8162.347012239006;8177.386695997824;918.7381596245039;985.049427302642;990.6730778842965;991.9156871665989;1070.3256948122632;26929.730702693116;31855.362892742014;32253.709851605672;32390.35219295601;32965.430446377795;926.3746970794687;1050.1703654113164;1053.3416614615865;1074.7044562745307;1119.2892513254142;13847.792283115761;16971.575710607085;17042.74435883463;17196.07716047842;17209.397671081453;639288;646683;647462;647679;647724;64208.67895660492;65182.999999999054;65188.34811651918;65191.67404163044;65196.02205966914;1281.9166754161135;1367.7743172376645;1417.7093695792337;1434.2040167707023;1440.9711805763825;31237.375252495276;32795.50806737949;33355.33289334151;33897.830510848275;34092.044024528775;55972;56623.15065274107;56645.43354566402;56647.433525664215;56753.432465676466;585563;587496;587756;590891;591458;28278.010269641287;28475.572866406605;28548.714512854607;29030;29047.983320583677;51480.74259628828;51554.74222628863;51676.74161629168;52180;53195.202071969725;32854;42357.36463952981;42529.36205956851;43868.56131438645;44097.55902441063;41980.79009604932;42245.788771055944;42286;43516.34725479057;43718.56281437272;40505.392419113145;41174.58825411827;41458.58541414547;41579;41708;31958.68041319557;36609.45085823768;37503.249935001695;37671.434928475544;37767.811160945115;22927.88536057309;25639;26620;26821.731782681924;27330.726692733613 18:38:25 INFO - PROCESS | 1656 | |2;jquery.html;352.44841822547727;377.92819364320775;378.98484060637577;383.7045474984263;408.47918903398164;15618.609534761623;15839.841601583981;20443.38669839907;20842.95785210737;20994.79005209949;21236.619619724708;21456.678149827763;21499.140034398646;21799.891000544976;22343.32970010898;5827.980103481895;5832.192020473645;6027.63834169949;6093.451589356957;6098.390160983889;5836.445537673914;5935.317438494563;5949.762009519614;5950.256217972754;5959.404059594028;4095.5290141633795;4861.978984413277;6581.5721978071515;6625.17185351831;6672.999050142494;6423.96788016057;6434.356564343575;6625.0724898514245;6626.503012274099;6634.635095069769;5698.43015698431;5702.6293290935955;5716.085426331767;5720.313562372542;5724.084146536534;20717.68923466149;25461.872690636614;25972.74027259741;26314.73685263142;26726.5991010135;2293.415179129328;2307.8384513084093;2422.454947636785;2761.4891245119716;3125.9531107033413;17048.82951170497;17816.55458613532;18041.368552100746;18134.90932545329;18412.81587184138;1891.8675692701515;1921.9135138918782;1997.7103320018662;2012.4465771912694;2140.5932872754283;137136;148642;148949;149210.50789492074;152726.7090993636;21407.16169770592;21565;21800.563988720136;21980.560388792135;22168.002439890242;35198.472022919166;35799.82100089483;35823.82088089543;35900;36268.455973160424;10483.842742358871;10697.465126743788;10838.566457341618;10844.373343998306;10967.177461690406;7190.352868241884;8871.689490867917;8906.854615809896;9041;9116.544172791333;7943.703363104362;9945.303828732063;9997.250206234561;10185.643502477378;10204.897951020395;3457.11843479911;3581.116119510171;3859.3246181918053;3861.9034524137123;3870.4774855394726;876.268315956171;891.5987805487556;936.6581197862868;966.6568368229318;995.7460847483845;20434.795652043587;21669.35383852164;21817.454563636034;21858.23496177658;21885.686858788522;900.6802585082332;952.3048174832411;953.6614501851882;983.7211624887617;986.4673076538588;11060.723481913017;11360.829587556193;11573.942130289295;11616;11754.23597466162;608616;612475;627503;630776;631917;55082.44917550773;64239.678801605696;74385.88421173686;116817.41591292158;144422.55577444303;978.1148061004802;1003.6186249225393;1049.5277125293649;1052.7894421115654;1053.536443964653;16195.59511012187;17735.46793596194;20055;20554.486137846674;20604.58790824205;51387.743061284455;55820;58988.410115900006;59482.70258648679;61102.69448652728;80051.59974200092;80192;80286.59856700679;80625.59687201762;80762.34663573494;7335.963320183579;7356.190819009797;7572.242775722384;7807.726729564438;7867.881981770163;12310.81533777012;13196.670083247996;13240.668983275495;13618.863811362156;13796.241206733726;10039.745031871016;10436.425996570262;11425.942870285595;11720.88279117198;11878.643640690792;10205.84691229647;10816.567337306735;11211.831822522832;11263.66209013731;11773.882261177621;10629.521671524646;11160.609378671708;11549.88450115488;11831;12138.635840924786;6387;7386.926130738839;7651.4261430391825;7788.532688038736;7931.5637639930355;9772.609095636095;9975.351602145838;10998.835017474905;11411.657650270505;11430.371329576954 18:38:25 INFO - PROCESS | 1656 | |3;mootools.html;1545.7681347797832;1590.6262028423318;1823.2069049963268;1908.6755251607226;1979.4655443030392;8465.365097617672;8917.152870477303;8921.866172007425;8936.374453788228;8953.015168331478;7720.845583088335;7990.520568765878;8436.831263374728;8705.608247628856;8705.869411958825;2345.7771511706405;2374.833761636686;2381.0951838301507;2385.4155731845635;2387.248016874679;2325.0815927708545;2356.988215058931;2358.6697862299293;2363.7163540375177;2381.4879800842814;2315.073970411832;2348.1898744933055;2352.6706261123372;2357.5520651076326;2375.58427275227;2313.306008197534;2335.9649605255936;2340.391498210469;2345.753695861927;2349.77677120675;2336.8714720690355;2340.707411573553;2345.3315804995495;2346.5306938612175;2350.659154422599;8774.210321071134;8908.465492070496;8914.732558023205;8920.687775927876;8921.375503714742;5171.4052883918075;5297.947020529784;5335.973320133413;5345.625806193568;5353.9196912046355;3387.305602351537;3499.860005599773;3573.3746594345885;3607.6572725591027;3625.8912232633065;1036.6009086501751;1051.4900273367412;1061.5010944855906;1067.631667074858;1078.0997866781247;11429.771194617053;11550.306981581132;11572.305661660328;11592.072634189244;11632.59285924997;4537;4593.770311484479;4757.952420475751;4770.928436073462;4780.617550595944;9897.851532227023;9903.45530995802;9903.900960990442;9912.55393507294;9951.25365597583;5310.60170487215;5315.6013299002725;5316.0696878046965;5329.786808527693;5330.040592693274;3668.5781135169264;4097;4503.842365517191;4827.444843843003;4841.515848415201;3938.8030598469963;3948.5656577776995;3988.9058244421913;4023.5976402360116;4047.817848196838;1075.8977897099762;1254.799232122856;1258.6224132760135;1276.24063682109;1281.2120545864414;912.8539433690711;931.725141083382;933.4026223217146;936;951.2485136742034;5707.68607726579;5771.903338365697;5933.465988061095;6011.128386384037;6086.908696369558;381.86443812446737;429.96990210684953;434.7847815331368;435.0819770284697;436.11468718501493;3923.588023257574;4006.979965100156;4022.0347116691783;4043.130726893732;4077.4699289092227;10315.381077135396;10546.736331591617;10651;10654.414007229521;10723.177059899836;10628.893711062945;10776.407297598551;10782.4069676167;10789.136869050457;10814.459277036116;7691.115521714963;7991.8401631967035;8115.797105072303;8237.50574965492;8307.293880020266;5135.845924622267;5903.0555111182;6010.338862725184;6030.427109424598;6107.358727333566;8363.372747044117;9483.430994140374;9911.45486998222;9988.900110998797;10003.949980250052;9132.452052876848;9470.905290947003;9920.652777152765;10063;10205;7036.19083805369;7359.705611775469;7523.8495230093995;7550.584717840571;7571.734989275349;7042.823929401601;8268.173182681683;8288.83422331562;8363.372747043873;8367.790805229675;7523.586202758901;7613.5431874087735;7649.273319034905;7664.80837979055;7688.077430708351;7249.818754530968;7320.231375705474;7367.300106489874;7434.628268586657;7493.363064139609;6793.898091528532;7074.50478466523;7115.146182458138;7127.073480447509;7156.069710937545;4812.759362031815;4817.181079216612;4822.638302127425;4838.419389673261;4988.625853060962;4527.660425468037;4839;4932.8520144395725;4985;5067.290579318972 18:38:25 INFO - PROCESS | 1656 | |4;prototype.html;265.4982083861501;297.8361900954476;298.85206822622797;306.92940623656557;317.6331337305413;5130.974345128278;5288.206768984654;5304.893902121965;5359.196120581914;5384.999999999995;5163.845084647466;5242.606804489669;5245.213218017299;5284.920726189101;5316.3354580677415;4092.7135100542973;4096.713230073896;4104.958950410495;4113.984147767602;4135.255653982282;3419.316136772643;3842.385218365062;4088.5298190708154;4148.481439820022;4161.521425036114;3333.6998570557503;4038.6970977176684;4048.1094159285058;4050.6759459243335;4052.2503336882623;4049.392591111342;4076.368162934735;4087.0804069084516;4089.5705950875326;4112.609302116294;3029.5304227844717;3356.781809182395;3492.2491664292297;3499.4400895856543;3503.754737168399;5176.2235664650225;5188.507091826271;5189.351331083614;5198.246254293144;5201.791928322863;4605.83879564217;4616.169089563878;4624.28323609841;4649.930251046237;4651.3255577941345;5443.537299329561;5534.979732397645;5627.662340259598;5643.5203007743985;5672.978863804514;3337.8998630041137;3676.8785520416263;3822;3857.324968130594;3872.9790763204746;5249.711265880375;5811.663317436971;5972.820815375546;5977.402259774031;5983.371745966654;3461.6326551012207;3575.213453040327;3771.849126034983;3909.92180156401;3912.9435052535655;3287.6383597804224;3826.445165450994;3961.3265744823448;3974.3839704845795;3981.7212795104642;3819.4652748615217;4604.332371806069;4615.653825963066;4627.514111018295;4642.328761645805;5086.952889666367;5391.056565101092;5533.667979921218;5663.640726225676;5732.541396688255;1096.8409580610767;1283.550757234979;1341.9865801341862;1342.5032737887045;1350.6150747037047;737.317980867706;794.8456053557693;814.861473549498;827.6096158453855;836.4646626159263;3725.6088110747983;3912.6478616924614;4111.198316328288;4424.659382166444;4437.977810110993;4133.565975572582;4199.391088292242;4365.9126817463475;4738.715677059387;4832.4684284728655;3802.6957843372466;3900.3759398496104;4051.473308469939;4094.315116395268;4416.403785488982;2183.705199798007;2285.0859656137345;2335.708036495438;2392.4975755091277;2610.5953577195564;4170.143451735571;4366;4704.63565565982;4714.430635303767;5022.723750193774;4083.8979025524004;4437.600615944581;4561.794719237642;4749.572538471485;4968.30443737877;4243.915121697549;4301;4797.736124513115;4976.751162441863;5038.345015148007;4924.655274130777;4927.339236812081;4930.9506904930495;4968.385274785637;4981.676191047571;4052.817623207022;4465.767116441779;4765.9046819064115;4864.075825593126;4889.511048895082;4652.114797000019;4775.641826863069;4776.617870570415;5043.697378157322;5072.594192464668;3393.728501719906;3625.184333524936;3755.6432138946757;3773.8301776420676;3866.845326186921;5433.266509021231;5691.573132015198;5724.856878578069;5761.423857614205;5768.971155144365;4882;5125.974370128126;5418.593605479526;5627.606067575394;5629.8592535186945;4642.37327960721;4838.516148385133;5323.961827443612;5490.423505531861;5578.832635020955;5454.082066094779;5458.9454105458435;5469;5489.917651235155;5494.642848215013;5238.869028274324;5581.744107575925;5605.5795815313195;5610.635308704921;5721.685307308138;4914.99242655262;5205.739713014258;5300.946990530046;5535.501804837584;5586.770910399706 18:38:25 INFO - PROCESS | 1656 | |5;yui.html;740.0564280542311;740.6370878269648;778.1595876453431;780.6370037932364;834.3492076180579;14917;15992.520224393258;15994.680106397866;16017.958832675868;16059.036457812539;14671.486497972573;14831.703365932675;14850.1089934604;14856.257187140654;14862.256887155627;714.4284572342132;731.6634348199834;759.7530802489185;887.6227603268617;897.6140259688332;889.2930120554175;900.0504467786483;900.9684661036865;901.7565257380536;901.9098090190961;953.3326671330062;954.5799848066829;955.044955044955;955.2739917662592;955.7180631713661;744.1368013104826;765.4182821055982;867.9522626255585;885.1590988560861;948.0424770981358;569.7464628240434;571.1432850723914;571.7341436232131;696.1020283833848;712.3375261007261;14563.781543276858;14597.416103355854;14640.780388294184;14660.41358345665;14683.412663493447;12918.41867115982;13598.524051658243;13710.314484275747;13894.861051389458;14225.14649121056;12313;12421.93789031058;12620.432080556306;13006.089573729843;13119.409626566827;4459.9496521239125;4567.771611419415;4569.748663823489;4630.490646028934;4738.597219236369;106880.46559767151;107127.28727127303;107768.46115769372;108749;109749;10823.930465292397;10999.23005389631;11025.228234023702;11101.83347249792;11168.66494005181;21389.893050534647;21392.57214855694;21396.78603213979;21417.57164856694;21475.46311342229;7871.803204919808;7980.162082981319;8041.3968952328805;8065.6370463329295;8073.394495412867;4275.187714334266;4370.060437006059;4376.124775045004;4389.517153113155;4456.554344565518;2876.6979467156066;3329.783564068376;3424.8630054798027;3435.0725304167736;3441.9655803442147;609.3936533149545;609.6342194683155;619.1548536248079;634.3751404866204;634.6414275934083;544.616045687797;610.8992016317346;622.2097935621736;645.4513663386084;647.6146692717825;8650.826983460296;8972.596233169523;9002.414843035182;9043.728688139367;9048.50233237165;587.5710731166187;614.3902974207516;626.4706323156925;640.7565125252374;642.1138828416848;3919.7060220483577;4174.1565780456895;4268.701190916606;4336.306191009423;4474.798634061475;130254;133114;137504;151365.16126451746;154710.45289547186;121942.17086743706;124018.37990810169;124788;129188.354058231;131144.3442782799;105551.4722426383;111384.88615113907;111722.44138779417;113492;117771;8059.959700201696;9369.437833729999;9461.810763784824;9510.809783804423;10005.999400059936;60630;61908.38091619026;63207.73584528376;65466.67266663636;65634;92311.53844230736;96285.51857240668;96475;97926.16590672871;99991.50004249932;13430.731385372042;13485;13571.728565428833;13670.453181873012;13731.874512220904;15154.772678409612;15257.208788903432;15380.38478460849;15673.059616423052;15996;14192.574222773334;14453;14513.564593062225;14532;14936.925315373353;14311.284435777961;14484.275786210857;14697.338619761922;14789;14926.77609835875;13875.583732488041;14233;14247.786283205553;14553.199574023107;14723.411063557765;11122.277051991594;11378.146639002274;11387.772244555228;11491.885081149416;11993.160478766751;10116.797664046531;10561.524731386953;11461.770764584495;11484.253523520945;11762.176647634584 18:38:25 INFO - PROCESS | 1656 | __end_tp_report 18:38:25 INFO - PROCESS | 1656 | __start_cc_report 18:38:25 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1272 18:38:25 INFO - PROCESS | 1656 | __end_cc_report 18:38:25 INFO - PROCESS | 1656 | __startTimestamp1469151505674__endTimestamp 18:38:25 INFO - PROCESS | 1656 | 18:38:25 INFO - PROCESS | 1656 | ------- Summary: start ------- 18:38:25 INFO - PROCESS | 1656 | Number of tests: 6 18:38:25 INFO - PROCESS | 1656 | 18:38:25 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5779.67 Median:6020.43 stddev:646.58 (10.7%) stddev-sans-first:615.38 18:38:25 INFO - PROCESS | 1656 | Values: 3173.3 3344.6 3363.9 3387.7 3397.7 5511.0 5627.5 5637.9 5739.7 5741.4 5633.4 5651.6 5670.5 5671.2 5674.0 5145.1 5176.4 5192.1 5202.2 5247.0 4410.4 5287.0 5334.9 5351.5 5358.7 5259.4 5265.2 5278.7 5285.0 5339.4 5352.3 5352.5 5360.5 5375.2 5383.6 5127.7 5142.2 5176.6 5262.2 5263.8 5646.8 5678.6 5684.3 5692.8 5712.6 6247.0 6251.0 6257.6 6263.2 6267.9 6136.6 6184.8 6280.0 6305.8 6326.2 5878.1 5878.2 5910.3 5915.8 5921.8 6321.4 6329.2 6330.5 6348.0 6350.2 6093.8 6097.5 6113.1 6154.8 6205.5 6268.1 6281.8 6284.7 6294.4 6301.7 6199.4 6248.5 6248.7 6273.8 6296.1 6112.7 6131.7 6216.6 6288.7 6299.9 4970.6 5002.5 5006.5 5016.1 5019.6 4441.1 4475.8 4513.9 4551.2 4561.5 5711.2 5711.6 5719.2 5724.0 5780.4 6210.7 6252.9 6312.4 6316.2 6325.5 5561.6 5632.0 5674.2 5738.1 5780.3 5471.5 5475.3 5476.6 5484.8 5526.9 6310.9 6338.9 6374.2 6393.6 6395.9 6181.6 6210.3 6259.0 6297.4 6338.8 6286.2 6346.9 6359.1 6373.3 6386.8 6221.3 6224.3 6225.3 6232.5 6240.0 6075.1 6203.8 6289.3 6291.3 6299.8 6106.1 6152.4 6163.5 6203.9 6204.5 6186.5 6196.0 6200.6 6206.6 6208.5 5781.2 5946.8 6012.0 6020.4 6043.2 5977.5 6123.4 6182.7 6207.4 6213.8 5961.0 6002.9 6012.5 6023.4 6024.7 18:38:25 INFO - PROCESS | 1656 | 18:38:25 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:73740.68 Median:42265.89 stddev:157401.09 (372.4%) stddev-sans-first:157746.60 18:38:25 INFO - PROCESS | 1656 | Values: 505.9 512.9 517.4 523.4 534.0 31230.7 43415.6 44625.6 44694.6 47131.8 45631.1 46094.1 46185.0 46193.1 46228.3 9808.3 10006.6 10011.2 10017.7 10025.0 8886.9 9105.2 10399.6 12142.3 12385.1 12320.4 12429.6 12435.4 12439.9 12449.9 8870.2 12307.4 12382.6 12387.9 12402.4 8859.1 9070.3 9081.7 11873.9 12171.8 44000.8 44257.9 44304.0 44324.9 44338.6 3452.6 3699.4 4257.2 4263.3 4452.5 31872.7 32854.7 33784.2 33895.8 33934.8 3256.5 3363.2 3395.3 3415.1 3441.7 528515.0 529519.0 529956.0 530318.0 530355.0 67269.7 67747.0 67768.7 67791.7 68023.7 71585.3 71693.0 71751.0 71834.3 71854.6 16592.5 16982.2 16986.7 17004.0 17008.4 11919.5 12127.5 12207.3 12216.3 12641.5 7877.8 7956.9 7965.5 14383.0 19095.5 8001.6 8073.9 8130.8 8162.3 8177.4 918.7 985.0 990.7 991.9 1070.3 26929.7 31855.4 32253.7 32390.4 32965.4 926.4 1050.2 1053.3 1074.7 1119.3 13847.8 16971.6 17042.7 17196.1 17209.4 639288.0 646683.0 647462.0 647679.0 647724.0 64208.7 65183.0 65188.3 65191.7 65196.0 1281.9 1367.8 1417.7 1434.2 1441.0 31237.4 32795.5 33355.3 33897.8 34092.0 55972.0 56623.2 56645.4 56647.4 56753.4 585563.0 587496.0 587756.0 590891.0 591458.0 28278.0 28475.6 28548.7 29030.0 29048.0 51480.7 51554.7 51676.7 52180.0 53195.2 32854.0 42357.4 42529.4 43868.6 44097.6 41980.8 42245.8 42286.0 43516.3 43718.6 40505.4 41174.6 41458.6 41579.0 41708.0 31958.7 36609.5 37503.2 37671.4 37767.8 22927.9 25639.0 26620.0 26821.7 27330.7 18:38:25 INFO - PROCESS | 1656 | 18:38:25 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:37067.82 Median:3291.54 stddev:103640.96 (3148.7%) stddev-sans-first:103895.04 18:38:25 INFO - PROCESS | 1656 | Values: 352.4 377.9 379.0 383.7 408.5 15618.6 15839.8 20443.4 20843.0 20994.8 21236.6 21456.7 21499.1 21799.9 22343.3 5828.0 5832.2 6027.6 6093.5 6098.4 5836.4 5935.3 5949.8 5950.3 5959.4 4095.5 4862.0 6581.6 6625.2 6673.0 6424.0 6434.4 6625.1 6626.5 6634.6 5698.4 5702.6 5716.1 5720.3 5724.1 20717.7 25461.9 25972.7 26314.7 26726.6 2293.4 2307.8 2422.5 2761.5 3126.0 17048.8 17816.6 18041.4 18134.9 18412.8 1891.9 1921.9 1997.7 2012.4 2140.6 137136.0 148642.0 148949.0 149210.5 152726.7 21407.2 21565.0 21800.6 21980.6 22168.0 35198.5 35799.8 35823.8 35900.0 36268.5 10483.8 10697.5 10838.6 10844.4 10967.2 7190.4 8871.7 8906.9 9041.0 9116.5 7943.7 9945.3 9997.3 10185.6 10204.9 3457.1 3581.1 3859.3 3861.9 3870.5 876.3 891.6 936.7 966.7 995.7 20434.8 21669.4 21817.5 21858.2 21885.7 900.7 952.3 953.7 983.7 986.5 11060.7 11360.8 11573.9 11616.0 11754.2 608616.0 612475.0 627503.0 630776.0 631917.0 55082.4 64239.7 74385.9 116817.4 144422.6 978.1 1003.6 1049.5 1052.8 1053.5 16195.6 17735.5 20055.0 20554.5 20604.6 51387.7 55820.0 58988.4 59482.7 61102.7 80051.6 80192.0 80286.6 80625.6 80762.3 7336.0 7356.2 7572.2 7807.7 7867.9 12310.8 13196.7 13240.7 13618.9 13796.2 10039.7 10436.4 11425.9 11720.9 11878.6 10205.8 10816.6 11211.8 11263.7 11773.9 10629.5 11160.6 11549.9 11831.0 12138.6 6387.0 7386.9 7651.4 7788.5 7931.6 9772.6 9975.4 10998.8 11411.7 11430.4 18:38:25 INFO - PROCESS | 1656 | 18:38:25 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5656.50 Median:4832.93 stddev:3180.24 (65.8%) stddev-sans-first:3174.16 18:38:25 INFO - PROCESS | 1656 | Values: 1545.8 1590.6 1823.2 1908.7 1979.5 8465.4 8917.2 8921.9 8936.4 8953.0 7720.8 7990.5 8436.8 8705.6 8705.9 2345.8 2374.8 2381.1 2385.4 2387.2 2325.1 2357.0 2358.7 2363.7 2381.5 2315.1 2348.2 2352.7 2357.6 2375.6 2313.3 2336.0 2340.4 2345.8 2349.8 2336.9 2340.7 2345.3 2346.5 2350.7 8774.2 8908.5 8914.7 8920.7 8921.4 5171.4 5297.9 5336.0 5345.6 5353.9 3387.3 3499.9 3573.4 3607.7 3625.9 1036.6 1051.5 1061.5 1067.6 1078.1 11429.8 11550.3 11572.3 11592.1 11632.6 4537.0 4593.8 4758.0 4770.9 4780.6 9897.9 9903.5 9903.9 9912.6 9951.3 5310.6 5315.6 5316.1 5329.8 5330.0 3668.6 4097.0 4503.8 4827.4 4841.5 3938.8 3948.6 3988.9 4023.6 4047.8 1075.9 1254.8 1258.6 1276.2 1281.2 912.9 931.7 933.4 936.0 951.2 5707.7 5771.9 5933.5 6011.1 6086.9 381.9 430.0 434.8 435.1 436.1 3923.6 4007.0 4022.0 4043.1 4077.5 10315.4 10546.7 10651.0 10654.4 10723.2 10628.9 10776.4 10782.4 10789.1 10814.5 7691.1 7991.8 8115.8 8237.5 8307.3 5135.8 5903.1 6010.3 6030.4 6107.4 8363.4 9483.4 9911.5 9988.9 10003.9 9132.5 9470.9 9920.7 10063.0 10205.0 7036.2 7359.7 7523.8 7550.6 7571.7 7042.8 8268.2 8288.8 8363.4 8367.8 7523.6 7613.5 7649.3 7664.8 7688.1 7249.8 7320.2 7367.3 7434.6 7493.4 6793.9 7074.5 7115.1 7127.1 7156.1 4812.8 4817.2 4822.6 4838.4 4988.6 4527.7 4839.0 4932.9 4985.0 5067.3 18:38:25 INFO - PROCESS | 1656 | 18:38:25 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4282.40 Median:4646.15 stddev:1318.85 (28.4%) stddev-sans-first:1287.63 18:38:25 INFO - PROCESS | 1656 | Values: 265.5 297.8 298.9 306.9 317.6 5131.0 5288.2 5304.9 5359.2 5385.0 5163.8 5242.6 5245.2 5284.9 5316.3 4092.7 4096.7 4105.0 4114.0 4135.3 3419.3 3842.4 4088.5 4148.5 4161.5 3333.7 4038.7 4048.1 4050.7 4052.3 4049.4 4076.4 4087.1 4089.6 4112.6 3029.5 3356.8 3492.2 3499.4 3503.8 5176.2 5188.5 5189.4 5198.2 5201.8 4605.8 4616.2 4624.3 4649.9 4651.3 5443.5 5535.0 5627.7 5643.5 5673.0 3337.9 3676.9 3822.0 3857.3 3873.0 5249.7 5811.7 5972.8 5977.4 5983.4 3461.6 3575.2 3771.8 3909.9 3912.9 3287.6 3826.4 3961.3 3974.4 3981.7 3819.5 4604.3 4615.7 4627.5 4642.3 5087.0 5391.1 5533.7 5663.6 5732.5 1096.8 1283.6 1342.0 1342.5 1350.6 737.3 794.8 814.9 827.6 836.5 3725.6 3912.6 4111.2 4424.7 4438.0 4133.6 4199.4 4365.9 4738.7 4832.5 3802.7 3900.4 4051.5 4094.3 4416.4 2183.7 2285.1 2335.7 2392.5 2610.6 4170.1 4366.0 4704.6 4714.4 5022.7 4083.9 4437.6 4561.8 4749.6 4968.3 4243.9 4301.0 4797.7 4976.8 5038.3 4924.7 4927.3 4931.0 4968.4 4981.7 4052.8 4465.8 4765.9 4864.1 4889.5 4652.1 4775.6 4776.6 5043.7 5072.6 3393.7 3625.2 3755.6 3773.8 3866.8 5433.3 5691.6 5724.9 5761.4 5769.0 4882.0 5126.0 5418.6 5627.6 5629.9 4642.4 4838.5 5324.0 5490.4 5578.8 5454.1 5458.9 5469.0 5489.9 5494.6 5238.9 5581.7 5605.6 5610.6 5721.7 4915.0 5205.7 5300.9 5535.5 5586.8 18:38:25 INFO - PROCESS | 1656 | 18:38:25 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:24896.05 Median:12176.08 stddev:39029.32 (320.5%) stddev-sans-first:39096.67 18:38:25 INFO - PROCESS | 1656 | Values: 740.1 740.6 778.2 780.6 834.3 14917.0 15992.5 15994.7 16018.0 16059.0 14671.5 14831.7 14850.1 14856.3 14862.3 714.4 731.7 759.8 887.6 897.6 889.3 900.1 901.0 901.8 901.9 953.3 954.6 955.0 955.3 955.7 744.1 765.4 868.0 885.2 948.0 569.7 571.1 571.7 696.1 712.3 14563.8 14597.4 14640.8 14660.4 14683.4 12918.4 13598.5 13710.3 13894.9 14225.1 12313.0 12421.9 12620.4 13006.1 13119.4 4459.9 4567.8 4569.7 4630.5 4738.6 106880.5 107127.3 107768.5 108749.0 109749.0 10823.9 10999.2 11025.2 11101.8 11168.7 21389.9 21392.6 21396.8 21417.6 21475.5 7871.8 7980.2 8041.4 8065.6 8073.4 4275.2 4370.1 4376.1 4389.5 4456.6 2876.7 3329.8 3424.9 3435.1 3442.0 609.4 609.6 619.2 634.4 634.6 544.6 610.9 622.2 645.5 647.6 8650.8 8972.6 9002.4 9043.7 9048.5 587.6 614.4 626.5 640.8 642.1 3919.7 4174.2 4268.7 4336.3 4474.8 130254.0 133114.0 137504.0 151365.2 154710.5 121942.2 124018.4 124788.0 129188.4 131144.3 105551.5 111384.9 111722.4 113492.0 117771.0 8060.0 9369.4 9461.8 9510.8 10006.0 60630.0 61908.4 63207.7 65466.7 65634.0 92311.5 96285.5 96475.0 97926.2 99991.5 13430.7 13485.0 13571.7 13670.5 13731.9 15154.8 15257.2 15380.4 15673.1 15996.0 14192.6 14453.0 14513.6 14532.0 14936.9 14311.3 14484.3 14697.3 14789.0 14926.8 13875.6 14233.0 14247.8 14553.2 14723.4 11122.3 11378.1 11387.8 11491.9 11993.2 10116.8 10561.5 11461.8 11484.3 11762.2 18:38:25 INFO - PROCESS | 1656 | -------- Summary: end -------- 18:38:25 INFO - PROCESS | 1656 | 18:38:26 INFO - TEST-INFO | 1656: exit 0 18:38:26 INFO - TEST-OK | dromaeo_css | took 1116896ms 18:38:26 INFO - TEST-START | kraken 18:38:26 INFO - operating with platform_type : mac_ 18:38:26 INFO - Initialising browser for kraken test... 18:38:26 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmp_kuxko/profile http://localhost:49267/getInfo.html) 18:38:28 INFO - PROCESS | 1663 | JavaScript error: , line 0: uncaught exception: undefined 18:38:28 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 18:38:28 INFO - PROCESS | 1663 | colorDepth:24 18:38:28 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 18:38:28 INFO - PROCESS | 1663 | __metrics 18:38:28 INFO - PROCESS | 1663 | 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" 18:38:28 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:38:29 INFO - TEST-INFO | 1663: exit 0 18:38:29 INFO - Browser initialized. 18:38:29 INFO - Running cycle 1/1 for kraken test... 18:38:29 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmp_kuxko/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:38:41 INFO - PROCESS | 1664 | RSS: Main: 245497856 18:38:41 INFO - PROCESS | 1664 | 18:38:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 18:38:45 INFO - PROCESS | 1664 | RSS: Main: 323919872 18:38:45 INFO - PROCESS | 1664 | 18:38:47 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 18:38:48 INFO - PROCESS | 1664 | RSS: Main: 335110144 18:38:48 INFO - PROCESS | 1664 | 18:38:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 18:38:52 INFO - PROCESS | 1664 | RSS: Main: 425472000 18:38:52 INFO - PROCESS | 1664 | 18:38:54 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 18:38:55 INFO - PROCESS | 1664 | RSS: Main: 328540160 18:38:55 INFO - PROCESS | 1664 | 18:38:57 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:38:58 INFO - PROCESS | 1664 | RSS: Main: 332599296 18:38:58 INFO - PROCESS | 1664 | 18:39:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 18:39:01 INFO - PROCESS | 1664 | RSS: Main: 370053120 18:39:01 INFO - PROCESS | 1664 | 18:39:04 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 18:39:05 INFO - PROCESS | 1664 | RSS: Main: 374116352 18:39:05 INFO - PROCESS | 1664 | 18:39:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 18:39:08 INFO - PROCESS | 1664 | RSS: Main: 370196480 18:39:08 INFO - PROCESS | 1664 | 18:39:10 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:39:11 INFO - PROCESS | 1664 | RSS: Main: 350412800 18:39:11 INFO - PROCESS | 1664 | 18:39:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:39:13 INFO - PROCESS | 1664 | RSS: Main: 356847616 18:39:13 INFO - PROCESS | 1664 | 18:39:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:39:16 INFO - PROCESS | 1664 | RSS: Main: 354619392 18:39:16 INFO - PROCESS | 1664 | 18:39:19 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:39:19 INFO - PROCESS | 1664 | RSS: Main: 363659264 18:39:19 INFO - PROCESS | 1664 | 18:39:22 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:39:23 INFO - PROCESS | 1664 | RSS: Main: 366149632 18:39:23 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 18:39:25 INFO - PROCESS | 1664 | RSS: Main: 409411584 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | __start_tp_report 18:39:25 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 18:39:25 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 18:39:25 INFO - PROCESS | 1664 | |i|pagename|runs| 18:39:25 INFO - PROCESS | 1664 | |0;ai-astar;101;111;112;112;112;114;111;112;111;111 18:39:25 INFO - PROCESS | 1664 | |1;audio-beat-detection;141;117;107;166;109;110;131;105;142;107 18:39:25 INFO - PROCESS | 1664 | |2;audio-dft;185;184;184;231;264;201;182;185;183;184 18:39:25 INFO - PROCESS | 1664 | |3;audio-fft;78;58;60;122;81;81;78;70;84;57 18:39:25 INFO - PROCESS | 1664 | |4;audio-oscillator;85;69;101;71;72;68;116;99;98;135 18:39:25 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;89;86;89;88;89;85;88;86;89;86 18:39:25 INFO - PROCESS | 1664 | |6;imaging-darkroom;96;96;95;94;109;146;115;96;96;94 18:39:25 INFO - PROCESS | 1664 | |7;imaging-desaturate;84;86;82;85;85;83;84;99;142;131 18:39:25 INFO - PROCESS | 1664 | |8;json-parse-financial;54;52;52;54;52;53;52;53;52;53 18:39:25 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;69;67;71;82;76;74;71;75;71;71 18:39:25 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;85;83;83;83;85;82;80;84;86;86 18:39:25 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;137;137;138;137;135;135;134;136;226;137 18:39:25 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;165;164;164;166;217;203;167;169;164;163 18:39:25 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;67;67;68;67;67;66;67;66;65;67 18:39:25 INFO - PROCESS | 1664 | __end_tp_report 18:39:25 INFO - PROCESS | 1664 | __start_cc_report 18:39:25 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2076 18:39:25 INFO - PROCESS | 1664 | __end_cc_report 18:39:25 INFO - PROCESS | 1664 | __startTimestamp1469151565503__endTimestamp 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | ------- Summary: start ------- 18:39:25 INFO - PROCESS | 1664 | Number of tests: 14 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:3.53 (3.2%) stddev-sans-first:0.97 18:39:25 INFO - PROCESS | 1664 | Values: 101.0 111.0 112.0 112.0 112.0 114.0 111.0 112.0 111.0 111.0 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:124.00 stddev:20.64 (16.6%) stddev-sans-first:20.89 18:39:25 INFO - PROCESS | 1664 | Values: 141.0 117.0 107.0 166.0 109.0 110.0 131.0 105.0 142.0 107.0 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:198.30 Median:185.00 stddev:27.61 (14.9%) stddev-sans-first:28.86 18:39:25 INFO - PROCESS | 1664 | Values: 185.0 184.0 184.0 231.0 264.0 201.0 182.0 185.0 183.0 184.0 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:76.90 Median:78.00 stddev:18.88 (24.2%) stddev-sans-first:20.02 18:39:25 INFO - PROCESS | 1664 | Values: 78.0 58.0 60.0 122.0 81.0 81.0 78.0 70.0 84.0 57.0 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:71.50 stddev:22.56 (31.6%) stddev-sans-first:23.81 18:39:25 INFO - PROCESS | 1664 | Values: 85.0 69.0 101.0 71.0 72.0 68.0 116.0 99.0 98.0 135.0 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:87.50 Median:88.50 stddev:1.58 (1.8%) stddev-sans-first:1.58 18:39:25 INFO - PROCESS | 1664 | Values: 89.0 86.0 89.0 88.0 89.0 85.0 88.0 86.0 89.0 86.0 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:103.70 Median:95.50 stddev:16.49 (17.3%) stddev-sans-first:17.25 18:39:25 INFO - PROCESS | 1664 | Values: 96.0 96.0 95.0 94.0 109.0 146.0 115.0 96.0 96.0 94.0 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:84.50 stddev:21.97 (26.0%) stddev-sans-first:22.86 18:39:25 INFO - PROCESS | 1664 | Values: 84.0 86.0 82.0 85.0 85.0 83.0 84.0 99.0 142.0 131.0 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:0.82 (1.6%) stddev-sans-first:0.73 18:39:25 INFO - PROCESS | 1664 | Values: 54.0 52.0 52.0 54.0 52.0 53.0 52.0 53.0 52.0 53.0 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:72.70 Median:72.50 stddev:4.24 (5.9%) stddev-sans-first:4.28 18:39:25 INFO - PROCESS | 1664 | Values: 69.0 67.0 71.0 82.0 76.0 74.0 71.0 75.0 71.0 71.0 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.50 stddev:1.89 (2.2%) stddev-sans-first:1.94 18:39:25 INFO - PROCESS | 1664 | Values: 85.0 83.0 83.0 83.0 85.0 82.0 80.0 84.0 86.0 86.0 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:137.00 stddev:28.42 (20.7%) stddev-sans-first:29.99 18:39:25 INFO - PROCESS | 1664 | Values: 137.0 137.0 138.0 137.0 135.0 135.0 134.0 136.0 226.0 137.0 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.20 Median:166.50 stddev:19.23 (11.6%) stddev-sans-first:20.11 18:39:25 INFO - PROCESS | 1664 | Values: 165.0 164.0 164.0 166.0 217.0 203.0 167.0 169.0 164.0 163.0 18:39:25 INFO - PROCESS | 1664 | 18:39:25 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 18:39:25 INFO - PROCESS | 1664 | Values: 67.0 67.0 68.0 67.0 67.0 66.0 67.0 66.0 65.0 67.0 18:39:25 INFO - PROCESS | 1664 | -------- Summary: end -------- 18:39:25 INFO - PROCESS | 1664 | 18:39:26 INFO - TEST-INFO | 1664: exit 0 18:39:26 INFO - TEST-OK | kraken | took 60131ms 18:39:26 INFO - SUITE-END | took 1177s 18:39:26 INFO - Completed test suite (00:19:37) 18:39:26 INFO - javascript benchmark 18:39:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3173.2542852429683, 3344.615369232539, 3363.915902102449, 3387.728981681465, 3397.6602339766027, 5510.972445137779, 5627.493525582707, 5637.91543126853, 5739.655620662758, 5741.397153298901, 5633.380328163898, 5651.632643878155, 5670.546356291497, 5671.177679236508, 5674.0, 5145.099607568679, 5176.404713457944, 5192.1432963560965, 5202.167653175493, 5247.003069416799, 4410.448693913261, 5287.0, 5334.91997620036, 5351.491608297206, 5358.705271210082, 5259.421463638997, 5265.18389649603, 5278.683279003253, 5285.02227087988, 5339.439358867321, 5352.33095863019, 5352.5182733553775, 5360.544353729938, 5375.247465354853, 5383.596230282706, 5127.743612819344, 5142.177251639757, 5176.559992400613, 5262.184361423985, 5263.763130659129, 5646.802361917313, 5678.602497825113, 5684.261046064026, 5692.829215123553, 5712.6286791358425, 6247.0, 6251.0, 6257.624542527463, 6263.1544741459775, 6267.905981410283, 6136.631802091889, 6184.783532576338, 6280.0, 6305.779297724557, 6326.177596912372, 5878.147668588073, 5878.2358293421585, 5910.261217347832, 5915.763369465173, 5921.763129474859, 6321.399467050623, 6329.177206963065, 6330.493560515148, 6347.96826015867, 6350.206224221973, 6093.756249750049, 6097.457563710582, 6113.050656958994, 6154.815355539341, 6205.503559715302, 6268.09112678659, 6281.842953926097, 6284.685765711696, 6294.370562943669, 6301.653409062454, 6199.380061993855, 6248.5313601479165, 6248.687565621701, 6273.780417685359, 6296.0555916612875, 6112.655464441461, 6131.651934473353, 6216.564840461215, 6288.654124023223, 6299.874002519924, 4970.552650261494, 5002.474740152232, 5006.549410553068, 5016.097102521581, 5019.573336266385, 4441.133978874153, 4475.820967161342, 4513.887152821206, 4551.180787458289, 4561.475430325555, 5711.200431939532, 5711.571632127607, 5719.2279042328755, 5724.0, 5780.42195780427, 6210.689465526705, 6252.932778227051, 6312.368763123743, 6316.178896743486, 6325.5255855811, 5561.63849349791, 5631.958087753778, 5674.205611214434, 5738.110592858114, 5780.335261444988, 5471.534919531805, 5475.260839786658, 5476.561875049986, 5484.780608775604, 5526.861828454241, 6310.873782524323, 6338.873222535523, 6374.235091789015, 6393.6163830170335, 6395.8720825582295, 6181.567290289637, 6210.347913469202, 6259.029850373199, 6297.4017468340435, 6338.778142764981, 6286.18279623646, 6346.90479642815, 6359.077933699495, 6373.330800266091, 6386.776462823779, 6221.2845522763655, 6224.253089629274, 6225.284092329259, 6232.532560057924, 6239.968800156152, 6075.119107729266, 6203.751849925952, 6289.308176100717, 6291.276503202191, 6299.779507717208, 6106.096658337741, 6152.384761523812, 6163.476104531165, 6203.937960620336, 6204.503639708722, 6186.536009799193, 6195.96902015487, 6200.56596038273, 6206.627602343874, 6208.534359922934, 5781.219535362672, 5946.791862284799, 6011.969940150271, 6020.4280593343565, 6043.184170136975, 5977.46202841729, 6123.357047509948, 6182.6599537025895, 6207.379262073757, 6213.844653883508, 5961.016432288624, 6002.909956350571, 6012.549058820693, 6023.397660233942, 6024.7288872001745], "value": 5737.18853991979, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.906407314647, 512.8897287083279, 517.4153206876229, 523.4477626104459, 534.0333995468205, 31230.68769312306, 43415.565844341545, 44625.553744462544, 44694.55305446945, 47131.76434117825, 45631.0873782525, 46094.0781184377, 46185.0, 46193.07613847721, 46228.306575401395, 9808.313418060721, 10006.649767258148, 10011.24915631327, 10017.749556261115, 10025.0, 8886.86669699955, 9105.226055785266, 10399.636012739556, 12142.332171730552, 12385.071119666016, 12320.445579948924, 12429.564965226218, 12435.440405181744, 12439.875601244008, 12449.937750311236, 8870.246029087535, 12307.4461649226, 12382.566610168691, 12387.876121238762, 12402.441890114966, 8859.069797671216, 9070.319726020509, 9081.68214112503, 11873.94063029688, 12171.817422738666, 44000.779996099816, 44257.89355266112, 44304.0, 44324.89187770299, 44338.556614433764, 3452.5511683481236, 3699.445083237536, 4257.233697934371, 4263.317869140923, 4452.487963884162, 31872.6812731872, 32854.68581256747, 33784.15539611505, 33895.83052084724, 33934.83032584821, 3256.453125232547, 3363.1928337199142, 3395.2869897321593, 3415.0949998250485, 3441.6730410610953, 528515.0, 529519.0, 529956.0, 530318.0, 530355.0, 67269.66365168143, 67747.0, 67768.66115669488, 67791.66104169447, 68023.65988170127, 71585.2841471589, 71693.0, 71751.0, 71834.28165718276, 71854.64072679603, 16592.502224933272, 16982.150892455327, 16986.660266794595, 17004.0, 17008.404705835237, 11919.52321907114, 12127.514899403925, 12207.267563946192, 12216.26702397859, 12641.494340226289, 7877.803054923558, 7956.880646790303, 7965.48224365426, 14382.990907388974, 19095.522611934535, 8001.5599142046585, 8073.9192608074345, 8130.837383252302, 8162.347012239006, 8177.386695997824, 918.7381596245039, 985.049427302642, 990.6730778842965, 991.9156871665989, 1070.3256948122632, 26929.730702693116, 31855.362892742014, 32253.709851605672, 32390.35219295601, 32965.430446377795, 926.3746970794687, 1050.1703654113164, 1053.3416614615865, 1074.7044562745307, 1119.2892513254142, 13847.792283115761, 16971.575710607085, 17042.74435883463, 17196.07716047842, 17209.397671081453, 639288.0, 646683.0, 647462.0, 647679.0, 647724.0, 64208.67895660492, 65182.999999999054, 65188.34811651918, 65191.67404163044, 65196.02205966914, 1281.9166754161135, 1367.7743172376645, 1417.7093695792337, 1434.2040167707023, 1440.9711805763825, 31237.375252495276, 32795.50806737949, 33355.33289334151, 33897.830510848275, 34092.044024528775, 55972.0, 56623.15065274107, 56645.43354566402, 56647.433525664215, 56753.432465676466, 585563.0, 587496.0, 587756.0, 590891.0, 591458.0, 28278.010269641287, 28475.572866406605, 28548.714512854607, 29030.0, 29047.983320583677, 51480.74259628828, 51554.74222628863, 51676.74161629168, 52180.0, 53195.202071969725, 32854.0, 42357.36463952981, 42529.36205956851, 43868.56131438645, 44097.55902441063, 41980.79009604932, 42245.788771055944, 42286.0, 43516.34725479057, 43718.56281437272, 40505.392419113145, 41174.58825411827, 41458.58541414547, 41579.0, 41708.0, 31958.68041319557, 36609.45085823768, 37503.249935001695, 37671.434928475544, 37767.811160945115, 22927.88536057309, 25639.0, 26620.0, 26821.731782681924, 27330.726692733613], "value": 21183.05689907108, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.44841822547727, 377.92819364320775, 378.98484060637577, 383.7045474984263, 408.47918903398164, 15618.609534761623, 15839.841601583981, 20443.38669839907, 20842.95785210737, 20994.79005209949, 21236.619619724708, 21456.678149827763, 21499.140034398646, 21799.891000544976, 22343.32970010898, 5827.980103481895, 5832.192020473645, 6027.63834169949, 6093.451589356957, 6098.390160983889, 5836.445537673914, 5935.317438494563, 5949.762009519614, 5950.256217972754, 5959.404059594028, 4095.5290141633795, 4861.978984413277, 6581.5721978071515, 6625.17185351831, 6672.999050142494, 6423.96788016057, 6434.356564343575, 6625.0724898514245, 6626.503012274099, 6634.635095069769, 5698.43015698431, 5702.6293290935955, 5716.085426331767, 5720.313562372542, 5724.084146536534, 20717.68923466149, 25461.872690636614, 25972.74027259741, 26314.73685263142, 26726.5991010135, 2293.415179129328, 2307.8384513084093, 2422.454947636785, 2761.4891245119716, 3125.9531107033413, 17048.82951170497, 17816.55458613532, 18041.368552100746, 18134.90932545329, 18412.81587184138, 1891.8675692701515, 1921.9135138918782, 1997.7103320018662, 2012.4465771912694, 2140.5932872754283, 137136.0, 148642.0, 148949.0, 149210.50789492074, 152726.7090993636, 21407.16169770592, 21565.0, 21800.563988720136, 21980.560388792135, 22168.002439890242, 35198.472022919166, 35799.82100089483, 35823.82088089543, 35900.0, 36268.455973160424, 10483.842742358871, 10697.465126743788, 10838.566457341618, 10844.373343998306, 10967.177461690406, 7190.352868241884, 8871.689490867917, 8906.854615809896, 9041.0, 9116.544172791333, 7943.703363104362, 9945.303828732063, 9997.250206234561, 10185.643502477378, 10204.897951020395, 3457.11843479911, 3581.116119510171, 3859.3246181918053, 3861.9034524137123, 3870.4774855394726, 876.268315956171, 891.5987805487556, 936.6581197862868, 966.6568368229318, 995.7460847483845, 20434.795652043587, 21669.35383852164, 21817.454563636034, 21858.23496177658, 21885.686858788522, 900.6802585082332, 952.3048174832411, 953.6614501851882, 983.7211624887617, 986.4673076538588, 11060.723481913017, 11360.829587556193, 11573.942130289295, 11616.0, 11754.23597466162, 608616.0, 612475.0, 627503.0, 630776.0, 631917.0, 55082.44917550773, 64239.678801605696, 74385.88421173686, 116817.41591292158, 144422.55577444303, 978.1148061004802, 1003.6186249225393, 1049.5277125293649, 1052.7894421115654, 1053.536443964653, 16195.59511012187, 17735.46793596194, 20055.0, 20554.486137846674, 20604.58790824205, 51387.743061284455, 55820.0, 58988.410115900006, 59482.70258648679, 61102.69448652728, 80051.59974200092, 80192.0, 80286.59856700679, 80625.59687201762, 80762.34663573494, 7335.963320183579, 7356.190819009797, 7572.242775722384, 7807.726729564438, 7867.881981770163, 12310.81533777012, 13196.670083247996, 13240.668983275495, 13618.863811362156, 13796.241206733726, 10039.745031871016, 10436.425996570262, 11425.942870285595, 11720.88279117198, 11878.643640690792, 10205.84691229647, 10816.567337306735, 11211.831822522832, 11263.66209013731, 11773.882261177621, 10629.521671524646, 11160.609378671708, 11549.88450115488, 11831.0, 12138.635840924786, 6387.0, 7386.926130738839, 7651.4261430391825, 7788.532688038736, 7931.5637639930355, 9772.609095636095, 9975.351602145838, 10998.835017474905, 11411.657650270505, 11430.371329576954], "value": 10744.94405937139, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1545.7681347797832, 1590.6262028423318, 1823.2069049963268, 1908.6755251607226, 1979.4655443030392, 8465.365097617672, 8917.152870477303, 8921.866172007425, 8936.374453788228, 8953.015168331478, 7720.845583088335, 7990.520568765878, 8436.831263374728, 8705.608247628856, 8705.869411958825, 2345.7771511706405, 2374.833761636686, 2381.0951838301507, 2385.4155731845635, 2387.248016874679, 2325.0815927708545, 2356.988215058931, 2358.6697862299293, 2363.7163540375177, 2381.4879800842814, 2315.073970411832, 2348.1898744933055, 2352.6706261123372, 2357.5520651076326, 2375.58427275227, 2313.306008197534, 2335.9649605255936, 2340.391498210469, 2345.753695861927, 2349.77677120675, 2336.8714720690355, 2340.707411573553, 2345.3315804995495, 2346.5306938612175, 2350.659154422599, 8774.210321071134, 8908.465492070496, 8914.732558023205, 8920.687775927876, 8921.375503714742, 5171.4052883918075, 5297.947020529784, 5335.973320133413, 5345.625806193568, 5353.9196912046355, 3387.305602351537, 3499.860005599773, 3573.3746594345885, 3607.6572725591027, 3625.8912232633065, 1036.6009086501751, 1051.4900273367412, 1061.5010944855906, 1067.631667074858, 1078.0997866781247, 11429.771194617053, 11550.306981581132, 11572.305661660328, 11592.072634189244, 11632.59285924997, 4537.0, 4593.770311484479, 4757.952420475751, 4770.928436073462, 4780.617550595944, 9897.851532227023, 9903.45530995802, 9903.900960990442, 9912.55393507294, 9951.25365597583, 5310.60170487215, 5315.6013299002725, 5316.0696878046965, 5329.786808527693, 5330.040592693274, 3668.5781135169264, 4097.0, 4503.842365517191, 4827.444843843003, 4841.515848415201, 3938.8030598469963, 3948.5656577776995, 3988.9058244421913, 4023.5976402360116, 4047.817848196838, 1075.8977897099762, 1254.799232122856, 1258.6224132760135, 1276.24063682109, 1281.2120545864414, 912.8539433690711, 931.725141083382, 933.4026223217146, 936.0, 951.2485136742034, 5707.68607726579, 5771.903338365697, 5933.465988061095, 6011.128386384037, 6086.908696369558, 381.86443812446737, 429.96990210684953, 434.7847815331368, 435.0819770284697, 436.11468718501493, 3923.588023257574, 4006.979965100156, 4022.0347116691783, 4043.130726893732, 4077.4699289092227, 10315.381077135396, 10546.736331591617, 10651.0, 10654.414007229521, 10723.177059899836, 10628.893711062945, 10776.407297598551, 10782.4069676167, 10789.136869050457, 10814.459277036116, 7691.115521714963, 7991.8401631967035, 8115.797105072303, 8237.50574965492, 8307.293880020266, 5135.845924622267, 5903.0555111182, 6010.338862725184, 6030.427109424598, 6107.358727333566, 8363.372747044117, 9483.430994140374, 9911.45486998222, 9988.900110998797, 10003.949980250052, 9132.452052876848, 9470.905290947003, 9920.652777152765, 10063.0, 10205.0, 7036.19083805369, 7359.705611775469, 7523.8495230093995, 7550.584717840571, 7571.734989275349, 7042.823929401601, 8268.173182681683, 8288.83422331562, 8363.372747043873, 8367.790805229675, 7523.586202758901, 7613.5431874087735, 7649.273319034905, 7664.80837979055, 7688.077430708351, 7249.818754530968, 7320.231375705474, 7367.300106489874, 7434.628268586657, 7493.363064139609, 6793.898091528532, 7074.50478466523, 7115.146182458138, 7127.073480447509, 7156.069710937545, 4812.759362031815, 4817.181079216612, 4822.638302127425, 4838.419389673261, 4988.625853060962, 4527.660425468037, 4839.0, 4932.8520144395725, 4985.0, 5067.290579318972], "value": 4474.786561218279, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.4982083861501, 297.8361900954476, 298.85206822622797, 306.92940623656557, 317.6331337305413, 5130.974345128278, 5288.206768984654, 5304.893902121965, 5359.196120581914, 5384.999999999995, 5163.845084647466, 5242.606804489669, 5245.213218017299, 5284.920726189101, 5316.3354580677415, 4092.7135100542973, 4096.713230073896, 4104.958950410495, 4113.984147767602, 4135.255653982282, 3419.316136772643, 3842.385218365062, 4088.5298190708154, 4148.481439820022, 4161.521425036114, 3333.6998570557503, 4038.6970977176684, 4048.1094159285058, 4050.6759459243335, 4052.2503336882623, 4049.392591111342, 4076.368162934735, 4087.0804069084516, 4089.5705950875326, 4112.609302116294, 3029.5304227844717, 3356.781809182395, 3492.2491664292297, 3499.4400895856543, 3503.754737168399, 5176.2235664650225, 5188.507091826271, 5189.351331083614, 5198.246254293144, 5201.791928322863, 4605.83879564217, 4616.169089563878, 4624.28323609841, 4649.930251046237, 4651.3255577941345, 5443.537299329561, 5534.979732397645, 5627.662340259598, 5643.5203007743985, 5672.978863804514, 3337.8998630041137, 3676.8785520416263, 3822.0, 3857.324968130594, 3872.9790763204746, 5249.711265880375, 5811.663317436971, 5972.820815375546, 5977.402259774031, 5983.371745966654, 3461.6326551012207, 3575.213453040327, 3771.849126034983, 3909.92180156401, 3912.9435052535655, 3287.6383597804224, 3826.445165450994, 3961.3265744823448, 3974.3839704845795, 3981.7212795104642, 3819.4652748615217, 4604.332371806069, 4615.653825963066, 4627.514111018295, 4642.328761645805, 5086.952889666367, 5391.056565101092, 5533.667979921218, 5663.640726225676, 5732.541396688255, 1096.8409580610767, 1283.550757234979, 1341.9865801341862, 1342.5032737887045, 1350.6150747037047, 737.317980867706, 794.8456053557693, 814.861473549498, 827.6096158453855, 836.4646626159263, 3725.6088110747983, 3912.6478616924614, 4111.198316328288, 4424.659382166444, 4437.977810110993, 4133.565975572582, 4199.391088292242, 4365.9126817463475, 4738.715677059387, 4832.4684284728655, 3802.6957843372466, 3900.3759398496104, 4051.473308469939, 4094.315116395268, 4416.403785488982, 2183.705199798007, 2285.0859656137345, 2335.708036495438, 2392.4975755091277, 2610.5953577195564, 4170.143451735571, 4366.0, 4704.63565565982, 4714.430635303767, 5022.723750193774, 4083.8979025524004, 4437.600615944581, 4561.794719237642, 4749.572538471485, 4968.30443737877, 4243.915121697549, 4301.0, 4797.736124513115, 4976.751162441863, 5038.345015148007, 4924.655274130777, 4927.339236812081, 4930.9506904930495, 4968.385274785637, 4981.676191047571, 4052.817623207022, 4465.767116441779, 4765.9046819064115, 4864.075825593126, 4889.511048895082, 4652.114797000019, 4775.641826863069, 4776.617870570415, 5043.697378157322, 5072.594192464668, 3393.728501719906, 3625.184333524936, 3755.6432138946757, 3773.8301776420676, 3866.845326186921, 5433.266509021231, 5691.573132015198, 5724.856878578069, 5761.423857614205, 5768.971155144365, 4882.0, 5125.974370128126, 5418.593605479526, 5627.606067575394, 5629.8592535186945, 4642.37327960721, 4838.516148385133, 5323.961827443612, 5490.423505531861, 5578.832635020955, 5454.082066094779, 5458.9454105458435, 5469.0, 5489.917651235155, 5494.642848215013, 5238.869028274324, 5581.744107575925, 5605.5795815313195, 5610.635308704921, 5721.685307308138, 4914.99242655262, 5205.739713014258, 5300.946990530046, 5535.501804837584, 5586.770910399706], "value": 3861.02243035618, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.0564280542311, 740.6370878269648, 778.1595876453431, 780.6370037932364, 834.3492076180579, 14917.0, 15992.520224393258, 15994.680106397866, 16017.958832675868, 16059.036457812539, 14671.486497972573, 14831.703365932675, 14850.1089934604, 14856.257187140654, 14862.256887155627, 714.4284572342132, 731.6634348199834, 759.7530802489185, 887.6227603268617, 897.6140259688332, 889.2930120554175, 900.0504467786483, 900.9684661036865, 901.7565257380536, 901.9098090190961, 953.3326671330062, 954.5799848066829, 955.044955044955, 955.2739917662592, 955.7180631713661, 744.1368013104826, 765.4182821055982, 867.9522626255585, 885.1590988560861, 948.0424770981358, 569.7464628240434, 571.1432850723914, 571.7341436232131, 696.1020283833848, 712.3375261007261, 14563.781543276858, 14597.416103355854, 14640.780388294184, 14660.41358345665, 14683.412663493447, 12918.41867115982, 13598.524051658243, 13710.314484275747, 13894.861051389458, 14225.14649121056, 12313.0, 12421.93789031058, 12620.432080556306, 13006.089573729843, 13119.409626566827, 4459.9496521239125, 4567.771611419415, 4569.748663823489, 4630.490646028934, 4738.597219236369, 106880.46559767151, 107127.28727127303, 107768.46115769372, 108749.0, 109749.0, 10823.930465292397, 10999.23005389631, 11025.228234023702, 11101.83347249792, 11168.66494005181, 21389.893050534647, 21392.57214855694, 21396.78603213979, 21417.57164856694, 21475.46311342229, 7871.803204919808, 7980.162082981319, 8041.3968952328805, 8065.6370463329295, 8073.394495412867, 4275.187714334266, 4370.060437006059, 4376.124775045004, 4389.517153113155, 4456.554344565518, 2876.6979467156066, 3329.783564068376, 3424.8630054798027, 3435.0725304167736, 3441.9655803442147, 609.3936533149545, 609.6342194683155, 619.1548536248079, 634.3751404866204, 634.6414275934083, 544.616045687797, 610.8992016317346, 622.2097935621736, 645.4513663386084, 647.6146692717825, 8650.826983460296, 8972.596233169523, 9002.414843035182, 9043.728688139367, 9048.50233237165, 587.5710731166187, 614.3902974207516, 626.4706323156925, 640.7565125252374, 642.1138828416848, 3919.7060220483577, 4174.1565780456895, 4268.701190916606, 4336.306191009423, 4474.798634061475, 130254.0, 133114.0, 137504.0, 151365.16126451746, 154710.45289547186, 121942.17086743706, 124018.37990810169, 124788.0, 129188.354058231, 131144.3442782799, 105551.4722426383, 111384.88615113907, 111722.44138779417, 113492.0, 117771.0, 8059.959700201696, 9369.437833729999, 9461.810763784824, 9510.809783804423, 10005.999400059936, 60630.0, 61908.38091619026, 63207.73584528376, 65466.67266663636, 65634.0, 92311.53844230736, 96285.51857240668, 96475.0, 97926.16590672871, 99991.50004249932, 13430.731385372042, 13485.0, 13571.728565428833, 13670.453181873012, 13731.874512220904, 15154.772678409612, 15257.208788903432, 15380.38478460849, 15673.059616423052, 15996.0, 14192.574222773334, 14453.0, 14513.564593062225, 14532.0, 14936.925315373353, 14311.284435777961, 14484.275786210857, 14697.338619761922, 14789.0, 14926.77609835875, 13875.583732488041, 14233.0, 14247.786283205553, 14553.199574023107, 14723.411063557765, 11122.277051991594, 11378.146639002274, 11387.772244555228, 11491.885081149416, 11993.160478766751, 10116.797664046531, 10561.524731386953, 11461.770764584495, 11484.253523520945, 11762.176647634584], "value": 7880.445468095443, "unit": "score"}], "name": "dromaeo_css", "value": 7498.858904269207, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 112.0, 112.0, 112.0, 114.0, 111.0, 112.0, 111.0, 111.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 117.0, 107.0, 166.0, 109.0, 110.0, 131.0, 105.0, 142.0, 107.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 231.0, 264.0, 201.0, 182.0, 185.0, 183.0, 184.0], "value": 198.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 60.0, 122.0, 81.0, 81.0, 78.0, 70.0, 84.0, 57.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 69.0, 101.0, 71.0, 72.0, 68.0, 116.0, 99.0, 98.0, 135.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 88.0, 89.0, 85.0, 88.0, 86.0, 89.0, 86.0], "value": 87.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 94.0, 109.0, 146.0, 115.0, 96.0, 96.0, 94.0], "value": 103.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 82.0, 85.0, 85.0, 83.0, 84.0, 99.0, 142.0, 131.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 54.0, 52.0, 53.0, 52.0, 53.0, 52.0, 53.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 71.0, 82.0, 76.0, 74.0, 71.0, 75.0, 71.0, 71.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 83.0, 83.0, 85.0, 82.0, 80.0, 84.0, 86.0, 86.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 138.0, 137.0, 135.0, 135.0, 134.0, 136.0, 226.0, 137.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 164.0, 166.0, 217.0, 203.0, 167.0, 169.0, 164.0, 163.0], "value": 174.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 67.0, 66.0, 67.0, 66.0, 65.0, 67.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1483.3000000000002, "alertThreshold": 2.0}]} 18:39:26 INFO - Return code: 0 18:39:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:39:26 INFO - # TBPL SUCCESS # 18:39:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:39:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:39:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:39:26 INFO - 2016-07-21 18:39:26.800 screenresolution[1668:9653] starting screenresolution argv=screenresolution get 18:39:26 INFO - 2016-07-21 18:39:26.803 screenresolution[1668:9653] Display 0: 1600x1200x32@60 18:39:26 INFO - 2016-07-21 18:39:26.809 screenresolution[1669:9656] starting screenresolution argv=screenresolution list 18:39:26 INFO - Available Modes on Display 0 18:39:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:39:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:39:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:39:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:39:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:39:27 INFO - Intel Iris: 18:39:27 INFO - Chipset Model: Intel Iris 18:39:27 INFO - Type: GPU 18:39:27 INFO - Bus: Built-In 18:39:27 INFO - VRAM (Dynamic, Max): 1536 MB 18:39:27 INFO - Vendor: Intel (0x8086) 18:39:27 INFO - Device ID: 0x0a2e 18:39:27 INFO - Revision ID: 0x0009 18:39:27 INFO - Displays: 18:39:27 INFO - Display: 18:39:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:39:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:39:27 INFO - Display Serial Number: 1600x1200 60 18:39:27 INFO - Main Display: Yes 18:39:27 INFO - Mirror: Off 18:39:27 INFO - Online: Yes 18:39:27 INFO - Rotation: Supported 18:39:27 INFO - Return code: 0 18:39:27 INFO - Running post-action listener: _resource_record_post_action 18:39:27 INFO - [mozharness: 2016-07-22 01:39:27.048780Z] Finished run-tests step (success) 18:39:27 INFO - Running post-run listener: _resource_record_post_run 18:39:27 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 36398080; Write bytes: 707050496; Read time: 244; Write time: 1996 18:39:27 INFO - TinderboxPrint: CPU usage
53.3% 18:39:27 INFO - TinderboxPrint: I/O read bytes / time
36,398,080 / 244 18:39:27 INFO - TinderboxPrint: I/O write bytes / time
707,050,496 / 1,996 18:39:27 INFO - TinderboxPrint: CPU idle
2,265.5 (46.7%) 18:39:27 INFO - TinderboxPrint: CPU system
965.1 (19.9%) 18:39:27 INFO - TinderboxPrint: CPU user
1,623.0 (33.4%) 18:39:27 INFO - TinderboxPrint: Swap in / out
458,571,776 / 0 18:39:27 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 404816384; Write bytes: 394495488; Read time: 30327; Write time: 855 18:39:27 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 28986368; Write bytes: 294888960; Read time: 176; Write time: 1096 18:39:27 INFO - Running post-run listener: _upload_blobber_files 18:39:27 INFO - Blob upload gear active. 18:39:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:39:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:39:27 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:39:27 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:39:27 INFO - (blobuploader) - INFO - Open directory for files ... 18:39:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:39:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:39:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:28 INFO - (blobuploader) - INFO - Done attempting. 18:39:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:39:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:39:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:29 INFO - (blobuploader) - INFO - Done attempting. 18:39:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:39:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:39:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:35 INFO - (blobuploader) - INFO - Done attempting. 18:39:35 INFO - (blobuploader) - INFO - Iteration through files over. 18:39:35 INFO - Return code: 0 18:39:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:39:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:39:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/648307186aa97ebdc6ba30d6d419d8a7a1904e74f59991f04bb123205de42e86754eb7f78f477f264c38c87c398cbcb4e35358d0a0838c36e0aeef7ae7c5a297", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9e3c7aa5ae5c3545c7dd6f22e765bba35f6f1dae4658493ac9dbc83f75fd466fa6d5a1c1078acfc08a6ef7e826b8f813e6d28247244bf6e930adf8050c945c80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c39e961a680557ba02d55c9289333aacc65783255ef27af2205cda1ba406c6ca5b908a1731d7450d7cbfcc407fd579c5f14950c19531c376589b40505dd9332"} 18:39:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:39:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:39:35 INFO - Contents: 18:39:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/648307186aa97ebdc6ba30d6d419d8a7a1904e74f59991f04bb123205de42e86754eb7f78f477f264c38c87c398cbcb4e35358d0a0838c36e0aeef7ae7c5a297", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9e3c7aa5ae5c3545c7dd6f22e765bba35f6f1dae4658493ac9dbc83f75fd466fa6d5a1c1078acfc08a6ef7e826b8f813e6d28247244bf6e930adf8050c945c80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c39e961a680557ba02d55c9289333aacc65783255ef27af2205cda1ba406c6ca5b908a1731d7450d7cbfcc407fd579c5f14950c19531c376589b40505dd9332"} 18:39:35 INFO - Running post-run listener: copy_logs_to_upload_dir 18:39:35 INFO - Copying logs to upload dir... 18:39:35 INFO - mkdir: /builds/slave/test/build/upload/logs 18:39:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.527795 ========= master_lag: 0.31 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-07-21 18:39:35.540949) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 18:39:35.544750) ========= 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.kq6Zui3BEW/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.pl2p2H04dq/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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/Fx-Team/sha512/648307186aa97ebdc6ba30d6d419d8a7a1904e74f59991f04bb123205de42e86754eb7f78f477f264c38c87c398cbcb4e35358d0a0838c36e0aeef7ae7c5a297", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9e3c7aa5ae5c3545c7dd6f22e765bba35f6f1dae4658493ac9dbc83f75fd466fa6d5a1c1078acfc08a6ef7e826b8f813e6d28247244bf6e930adf8050c945c80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c39e961a680557ba02d55c9289333aacc65783255ef27af2205cda1ba406c6ca5b908a1731d7450d7cbfcc407fd579c5f14950c19531c376589b40505dd9332"} build_url:https://queue.taskcluster.net/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011023 build_url: 'https://queue.taskcluster.net/v1/task/Q9vxGbjSScKB-DkA8tpNaw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/648307186aa97ebdc6ba30d6d419d8a7a1904e74f59991f04bb123205de42e86754eb7f78f477f264c38c87c398cbcb4e35358d0a0838c36e0aeef7ae7c5a297", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9e3c7aa5ae5c3545c7dd6f22e765bba35f6f1dae4658493ac9dbc83f75fd466fa6d5a1c1078acfc08a6ef7e826b8f813e6d28247244bf6e930adf8050c945c80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c39e961a680557ba02d55c9289333aacc65783255ef27af2205cda1ba406c6ca5b908a1731d7450d7cbfcc407fd579c5f14950c19531c376589b40505dd9332"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 18:39:35.581533) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:39:35.581878) ========= 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.kq6Zui3BEW/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.pl2p2H04dq/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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.004932 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 18:39:35.630896) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 18:39:35.631195) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 18:39:35.631533) ========= ========= Total master_lag: 0.45 =========