builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0008 starttime: 1467735468.92 results: success (0) buildid: 20160705071024 builduid: c7459e6d2e8441d9a991e98edcadc3e1 revision: 9b428173a0889f5a25e7a6e855f2d1726207a723 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 09:17:48.921708) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 09:17:48.922093) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-07-05 09:17:48.922361) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-07-05 09:17:52.131657) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-07-05 09:17:52.131964) ========= 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.hbN5XXt7Wb/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.etfc88C5mo/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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-05 09:17:52-- 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% 181M=0s 2016-07-05 09:17:52 (181 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095214 ========= master_lag: 2.96 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-07-05 09:17:55.188696) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:17:55.189040) ========= 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.hbN5XXt7Wb/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.etfc88C5mo/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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.032328 ========= master_lag: 0.71 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:17:55.936017) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 09:17:55.936359) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 9b428173a0889f5a25e7a6e855f2d1726207a723 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 9b428173a0889f5a25e7a6e855f2d1726207a723 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hbN5XXt7Wb/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.etfc88C5mo/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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-05 09:17:56,017 truncating revision to first 12 chars 2016-07-05 09:17:56,017 Setting DEBUG logging. 2016-07-05 09:17:56,017 attempt 1/10 2016-07-05 09:17:56,017 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/9b428173a088?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 09:17:56,721 unpacking tar archive at: autoland-9b428173a088/testing/mozharness/ program finished with exit code 0 elapsedTime=0.987932 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 09:17:56.954709) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 09:17:56.955091) ========= script_repo_revision: 9b428173a0889f5a25e7a6e855f2d1726207a723 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 09:17:56.955484) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 09:17:56.955749) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 09:17:56.976680) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-07-05 09:17:56.976988) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hbN5XXt7Wb/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.etfc88C5mo/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 09:17:57 INFO - MultiFileLogger online at 20160705 09:17:57 in /builds/slave/test 09:17:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 09:17:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:17:57 INFO - {'append_to_log': False, 09:17:57 INFO - 'base_work_dir': '/builds/slave/test', 09:17:57 INFO - 'blob_upload_branch': 'Autoland', 09:17:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:17:57 INFO - 'branch': 'Autoland', 09:17:57 INFO - 'buildbot_json_path': 'buildprops.json', 09:17:57 INFO - 'config_files': ('talos/mac_config.py',), 09:17:57 INFO - 'default_actions': ('clobber', 09:17:57 INFO - 'read-buildbot-config', 09:17:57 INFO - 'download-and-extract', 09:17:57 INFO - 'populate-webroot', 09:17:57 INFO - 'create-virtualenv', 09:17:57 INFO - 'install', 09:17:57 INFO - 'run-tests'), 09:17:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:17:57 INFO - 'download_minidump_stackwalk': True, 09:17:57 INFO - 'download_symbols': 'ondemand', 09:17:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:17:57 INFO - 'tooltool.py': '/tools/tooltool.py', 09:17:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:17:57 INFO - '/tools/misc-python/virtualenv.py')}, 09:17:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:17:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:17:57 INFO - 'installer_path': 'installer.exe', 09:17:57 INFO - 'log_level': 'info', 09:17:57 INFO - 'log_name': 'talos', 09:17:57 INFO - 'log_to_console': True, 09:17:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:17:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:17:57 INFO - 'opt_config_files': (), 09:17:57 INFO - 'pip_index': False, 09:17:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:57 INFO - 'cmd': ('bash', 09:17:57 INFO - '-c', 09:17:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:57 INFO - 'enabled': True, 09:17:57 INFO - 'halt_on_failure': False, 09:17:57 INFO - 'name': 'check_screen_resolution'},), 09:17:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:57 INFO - 'cmd': ('bash', 09:17:57 INFO - '-c', 09:17:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:57 INFO - 'enabled': True, 09:17:57 INFO - 'halt_on_failure': False, 09:17:57 INFO - 'name': 'check_screen_resolution'},), 09:17:57 INFO - 'run_cmd_checks_enabled': True, 09:17:57 INFO - 'sps_profile': False, 09:17:57 INFO - 'sps_profile_interval': 0, 09:17:57 INFO - 'suite': 'dromaeojs', 09:17:57 INFO - 'system_bits': '32', 09:17:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:17:57 INFO - 'title': 't-yosemite-r7-0008', 09:17:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:17:57 INFO - 'use_talos_json': True, 09:17:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:17:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:17:57 INFO - 'work_dir': 'build'} 09:17:57 INFO - ##### 09:17:57 INFO - ##### Running clobber step. 09:17:57 INFO - ##### 09:17:57 INFO - Running pre-action listener: _resource_record_pre_action 09:17:57 INFO - Running main action method: clobber 09:17:57 INFO - rmtree: /builds/slave/test/build 09:17:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:17:59 INFO - Running post-action listener: _resource_record_post_action 09:17:59 INFO - ##### 09:17:59 INFO - ##### Finished clobber step (success) 09:17:59 INFO - ##### 09:17:59 INFO - ##### 09:17:59 INFO - ##### Running read-buildbot-config step. 09:17:59 INFO - ##### 09:17:59 INFO - Running pre-action listener: _resource_record_pre_action 09:17:59 INFO - Running main action method: read_buildbot_config 09:17:59 INFO - Using buildbot properties: 09:17:59 INFO - { 09:17:59 INFO - "project": "", 09:17:59 INFO - "product": "firefox", 09:17:59 INFO - "script_repo_revision": "production", 09:17:59 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 09:17:59 INFO - "repository": "", 09:17:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 09:17:59 INFO - "buildid": "20160705071024", 09:17:59 INFO - "pgo_build": "False", 09:17:59 INFO - "basedir": "/builds/slave/test", 09:17:59 INFO - "buildnumber": 29, 09:17:59 INFO - "slavename": "t-yosemite-r7-0008", 09:17:59 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:17:59 INFO - "platform": "yosemite_r7", 09:17:59 INFO - "scheduler": "tests-autoland-macosx64-talos", 09:17:59 INFO - "branch": "autoland", 09:17:59 INFO - "repo_path": "integration/autoland", 09:17:59 INFO - "revision": "9b428173a0889f5a25e7a6e855f2d1726207a723", 09:17:59 INFO - "stage_platform": "macosx64", 09:17:59 INFO - "builduid": "c7459e6d2e8441d9a991e98edcadc3e1", 09:17:59 INFO - "slavebuilddir": "test" 09:17:59 INFO - } 09:17:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:17:59 INFO - Running post-action listener: _resource_record_post_action 09:17:59 INFO - ##### 09:17:59 INFO - ##### Finished read-buildbot-config step (success) 09:17:59 INFO - ##### 09:17:59 INFO - ##### 09:17:59 INFO - ##### Running download-and-extract step. 09:17:59 INFO - ##### 09:17:59 INFO - Running pre-action listener: _resource_record_pre_action 09:17:59 INFO - Running main action method: download_and_extract 09:17:59 INFO - mkdir: /builds/slave/test/build/tests 09:17:59 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')]} 09:17:59 INFO - https://queue.taskcluster.net/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:17:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:17:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:17:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/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 09:17:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/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 09:18:00 INFO - Downloaded 1199 bytes. 09:18:00 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:18:00 INFO - Using the following test package requirements: 09:18:00 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:18:00 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:18:00 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:18:00 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:18:00 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:18:00 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:18:00 INFO - u'jsshell-mac.zip'], 09:18:00 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:18:00 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:18:00 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:18:00 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:18:00 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:18:00 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:18:00 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:18:00 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:18:00 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:18:00 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:18:00 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:18:00 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:18:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:00 INFO - https://queue.taskcluster.net/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:18:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:18:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:18:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/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 09:18:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/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 09:18:01 INFO - Downloaded 18802230 bytes. 09:18:01 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'] 09:18:01 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 09:18:04 INFO - Return code: 0 09:18:04 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 09:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:04 INFO - https://queue.taskcluster.net/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:18:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:18:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:18:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/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 09:18:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/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 09:18:05 INFO - Downloaded 18802230 bytes. 09:18:05 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'] 09:18:05 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 09:18:09 INFO - Return code: 0 09:18:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:09 INFO - https://queue.taskcluster.net/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:18:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:18:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:18:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/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 09:18:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/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 09:18:10 INFO - Downloaded 14403333 bytes. 09:18:10 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'] 09:18:10 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 09:18:10 INFO - Return code: 0 09:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:10 INFO - https://queue.taskcluster.net/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:18:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:18:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:18:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:18:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z_Nt6300SN6_9LAmGgOuag/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 09:18:15 INFO - Downloaded 112539651 bytes. 09:18:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:18:15 INFO - mkdir: /builds/slave/test/properties 09:18:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:18:15 INFO - Writing to file /builds/slave/test/properties/build_url 09:18:15 INFO - Contents: 09:18:15 INFO - build_url:https://queue.taskcluster.net/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:18:16 INFO - Running post-action listener: _resource_record_post_action 09:18:16 INFO - Running post-action listener: set_extra_try_arguments 09:18:16 INFO - ##### 09:18:16 INFO - ##### Finished download-and-extract step (success) 09:18:16 INFO - ##### 09:18:16 INFO - ##### 09:18:16 INFO - ##### Running populate-webroot step. 09:18:16 INFO - ##### 09:18:16 INFO - Running pre-action listener: _resource_record_pre_action 09:18:16 INFO - Running main action method: populate_webroot 09:18:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:18:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:18:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:18:16 INFO - '--tppagecycles', 09:18:16 INFO - '7'], 09:18:16 INFO - 'tests': ['tsvgm']}}, 09:18:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:18:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:18:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:18:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:18:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:18:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:18:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:16 INFO - 'talos_options': ['--disable-e10s'], 09:18:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:18:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:18:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:16 INFO - 'talos_options': ['--disable-e10s'], 09:18:16 INFO - 'tests': ['damp', 'tps']}, 09:18:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:16 INFO - 'tests': ['damp', 'tps']}, 09:18:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:18:16 INFO - 'tests': ['dromaeo_dom']}, 09:18:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:18:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:18:16 INFO - 'tests': ['basic_compositor_video']}, 09:18:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:18:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:18:16 INFO - 'tests': ['a11yr', 09:18:16 INFO - 'ts_paint', 09:18:16 INFO - 'tpaint', 09:18:16 INFO - 'sessionrestore', 09:18:16 INFO - 'sessionrestore_no_auto_restore', 09:18:16 INFO - 'tabpaint']}, 09:18:16 INFO - 'other-e10s': {'tests': ['a11yr', 09:18:16 INFO - 'ts_paint', 09:18:16 INFO - 'tpaint', 09:18:16 INFO - 'sessionrestore', 09:18:16 INFO - 'sessionrestore_no_auto_restore', 09:18:16 INFO - 'tabpaint']}, 09:18:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:18:16 INFO - 'ts_paint', 09:18:16 INFO - 'tpaint', 09:18:16 INFO - 'sessionrestore', 09:18:16 INFO - 'sessionrestore_no_auto_restore', 09:18:16 INFO - 'tabpaint']}, 09:18:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:18:16 INFO - 'ts_paint', 09:18:16 INFO - 'tpaint', 09:18:16 INFO - 'sessionrestore', 09:18:16 INFO - 'sessionrestore_no_auto_restore', 09:18:16 INFO - 'tabpaint']}, 09:18:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:18:16 INFO - 'tests': ['a11yr', 09:18:16 INFO - 'ts_paint', 09:18:16 INFO - 'tpaint', 09:18:16 INFO - 'sessionrestore', 09:18:16 INFO - 'sessionrestore_no_auto_restore', 09:18:16 INFO - 'tabpaint']}, 09:18:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:18:16 INFO - 'tests': ['a11yr', 09:18:16 INFO - 'ts_paint', 09:18:16 INFO - 'tpaint', 09:18:16 INFO - 'sessionrestore', 09:18:16 INFO - 'sessionrestore_no_auto_restore', 09:18:16 INFO - 'tabpaint']}, 09:18:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:18:16 INFO - 'tests': ['tsvgx', 09:18:16 INFO - 'tsvgr_opacity', 09:18:16 INFO - 'tart', 09:18:16 INFO - 'tscrollx', 09:18:16 INFO - 'cart']}, 09:18:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:18:16 INFO - 'tsvgr_opacity', 09:18:16 INFO - 'tart', 09:18:16 INFO - 'tscrollx', 09:18:16 INFO - 'cart']}, 09:18:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:16 INFO - 'talos_options': ['--disable-e10s'], 09:18:16 INFO - 'tests': ['tp5o']}, 09:18:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:16 INFO - 'tests': ['tp5o']}, 09:18:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:16 INFO - 'talos_options': ['--disable-e10s', 09:18:16 INFO - '--xperf_path', 09:18:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:18:16 INFO - 'tests': ['tp5n']}, 09:18:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:16 INFO - 'talos_options': ['--xperf_path', 09:18:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:18:16 INFO - 'tests': ['tp5n']}}, 09:18:16 INFO - 'talos.zip': {'path': '', 09:18:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:18:16 INFO - Running post-action listener: _resource_record_post_action 09:18:16 INFO - ##### 09:18:16 INFO - ##### Finished populate-webroot step (success) 09:18:16 INFO - ##### 09:18:16 INFO - ##### 09:18:16 INFO - ##### Running create-virtualenv step. 09:18:16 INFO - ##### 09:18:16 INFO - Running pre-action listener: _resource_record_pre_action 09:18:16 INFO - Running main action method: create_virtualenv 09:18:16 INFO - Creating virtualenv /builds/slave/test/build/venv 09:18:16 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 09:18:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:18:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:18:16 INFO - Using real prefix '/tools/python27' 09:18:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:18:16 INFO - Installing distribute.............................................................................................................................................................................................done. 09:18:19 INFO - Installing pip.................done. 09:18:19 INFO - Return code: 0 09:18:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:19 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 0x10a8b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a94c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c43497cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a81bd30>, '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 0x10a580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a813b20>, '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.hbN5XXt7Wb/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.etfc88C5mo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 09:18:19 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 09:18:19 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 09:18:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hbN5XXt7Wb/Render', 09:18:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:19 INFO - 'HOME': '/Users/cltbld', 09:18:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:19 INFO - 'LOGNAME': 'cltbld', 09:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:19 INFO - 'MOZ_NO_REMOTE': '1', 09:18:19 INFO - 'NO_EM_RESTART': '1', 09:18:19 INFO - 'PAGER': '/bin/cat', 09:18:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:19 INFO - 'PWD': '/builds/slave/test', 09:18:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:19 INFO - 'SHELL': '/bin/bash', 09:18:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.etfc88C5mo/Listeners', 09:18:19 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 09:18:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:19 INFO - 'USER': 'cltbld', 09:18:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:19 INFO - 'XPC_FLAGS': '0x0', 09:18:19 INFO - 'XPC_SERVICE_NAME': '0', 09:18:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:20 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 09:18:20 INFO - Cleaning up... 09:18:20 INFO - Return code: 0 09:18:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:20 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 0x10a8b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a94c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c43497cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a81bd30>, '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 0x10a580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a813b20>, '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.hbN5XXt7Wb/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.etfc88C5mo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 09:18:20 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 09:18:20 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 09:18:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hbN5XXt7Wb/Render', 09:18:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:20 INFO - 'HOME': '/Users/cltbld', 09:18:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:20 INFO - 'LOGNAME': 'cltbld', 09:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:20 INFO - 'MOZ_NO_REMOTE': '1', 09:18:20 INFO - 'NO_EM_RESTART': '1', 09:18:20 INFO - 'PAGER': '/bin/cat', 09:18:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:20 INFO - 'PWD': '/builds/slave/test', 09:18:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:20 INFO - 'SHELL': '/bin/bash', 09:18:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.etfc88C5mo/Listeners', 09:18:20 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 09:18:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:20 INFO - 'USER': 'cltbld', 09:18:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:20 INFO - 'XPC_FLAGS': '0x0', 09:18:20 INFO - 'XPC_SERVICE_NAME': '0', 09:18:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:20 INFO - Downloading/unpacking psutil>=3.1.1 09:18:20 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 09:18:20 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 09:18:20 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 09:18:20 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 09:18:20 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 09:18:20 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 09:18:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:18:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:18:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:18:23 INFO - Installing collected packages: psutil 09:18:23 INFO - Running setup.py install for psutil 09:18:23 INFO - building 'psutil._psutil_osx' extension 09:18:23 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 09:18:23 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 09:18:24 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 09:18:24 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 09:18:24 INFO - building 'psutil._psutil_posix' extension 09:18:24 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 09:18:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:18:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:18:24 INFO - ^ 09:18:24 INFO - 1 warning generated. 09:18:24 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 09:18:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:18:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:18:24 INFO - Successfully installed psutil 09:18:24 INFO - Cleaning up... 09:18:24 INFO - Return code: 0 09:18:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:24 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 0x10a8b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a94c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c43497cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a81bd30>, '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 0x10a580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a813b20>, '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.hbN5XXt7Wb/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.etfc88C5mo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 09:18:24 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 09:18:24 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 09:18:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hbN5XXt7Wb/Render', 09:18:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:24 INFO - 'HOME': '/Users/cltbld', 09:18:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:24 INFO - 'LOGNAME': 'cltbld', 09:18:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:24 INFO - 'MOZ_NO_REMOTE': '1', 09:18:24 INFO - 'NO_EM_RESTART': '1', 09:18:24 INFO - 'PAGER': '/bin/cat', 09:18:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:24 INFO - 'PWD': '/builds/slave/test', 09:18:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:24 INFO - 'SHELL': '/bin/bash', 09:18:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.etfc88C5mo/Listeners', 09:18:24 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 09:18:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:24 INFO - 'USER': 'cltbld', 09:18:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:24 INFO - 'XPC_FLAGS': '0x0', 09:18:24 INFO - 'XPC_SERVICE_NAME': '0', 09:18:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:18:24 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 09:18:24 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 09:18:24 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 09:18:24 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 09:18:24 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 09:18:24 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 09:18:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:18:27 INFO - Installing collected packages: mozsystemmonitor 09:18:27 INFO - Running setup.py install for mozsystemmonitor 09:18:27 INFO - Successfully installed mozsystemmonitor 09:18:27 INFO - Cleaning up... 09:18:27 INFO - Return code: 0 09:18:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:18:27 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')]} 09:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:27 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')]} 09:18:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:27 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 0x10a8b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a94c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c43497cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a81bd30>, '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 0x10a580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a813b20>, '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.hbN5XXt7Wb/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.etfc88C5mo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 09:18:27 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 09:18:27 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 09:18:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hbN5XXt7Wb/Render', 09:18:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:27 INFO - 'HOME': '/Users/cltbld', 09:18:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:27 INFO - 'LOGNAME': 'cltbld', 09:18:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:27 INFO - 'MOZ_NO_REMOTE': '1', 09:18:27 INFO - 'NO_EM_RESTART': '1', 09:18:27 INFO - 'PAGER': '/bin/cat', 09:18:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:27 INFO - 'PWD': '/builds/slave/test', 09:18:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:27 INFO - 'SHELL': '/bin/bash', 09:18:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.etfc88C5mo/Listeners', 09:18:27 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 09:18:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:27 INFO - 'USER': 'cltbld', 09:18:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:27 INFO - 'XPC_FLAGS': '0x0', 09:18:27 INFO - 'XPC_SERVICE_NAME': '0', 09:18:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:27 INFO - Downloading/unpacking jsonschema==2.5.1 09:18:27 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 09:18:27 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 09:18:27 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 09:18:27 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 09:18:27 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 09:18:27 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 09:18:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:18:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:31 INFO - Downloading functools32-3.2.3-2.tar.gz 09:18:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:18:31 INFO - warning: no files found matching '*.txt' 09:18:31 INFO - no previously-included directories found matching 'build' 09:18:31 INFO - no previously-included directories found matching 'dist' 09:18:31 INFO - no previously-included directories found matching '.git*' 09:18:31 INFO - Installing collected packages: jsonschema, functools32 09:18:31 INFO - Running setup.py install for functools32 09:18:31 INFO - warning: no files found matching '*.txt' 09:18:31 INFO - no previously-included directories found matching 'build' 09:18:31 INFO - no previously-included directories found matching 'dist' 09:18:31 INFO - no previously-included directories found matching '.git*' 09:18:31 INFO - Successfully installed jsonschema functools32 09:18:31 INFO - Cleaning up... 09:18:31 INFO - Return code: 0 09:18:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a8b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a94c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c43497cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a81bd30>, '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 0x10a580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a813b20>, '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.hbN5XXt7Wb/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.etfc88C5mo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 09:18:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:18:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:18:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hbN5XXt7Wb/Render', 09:18:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:31 INFO - 'HOME': '/Users/cltbld', 09:18:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:31 INFO - 'LOGNAME': 'cltbld', 09:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:31 INFO - 'MOZ_NO_REMOTE': '1', 09:18:31 INFO - 'NO_EM_RESTART': '1', 09:18:31 INFO - 'PAGER': '/bin/cat', 09:18:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:31 INFO - 'PWD': '/builds/slave/test', 09:18:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:31 INFO - 'SHELL': '/bin/bash', 09:18:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.etfc88C5mo/Listeners', 09:18:31 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 09:18:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:31 INFO - 'USER': 'cltbld', 09:18:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:31 INFO - 'XPC_FLAGS': '0x0', 09:18:31 INFO - 'XPC_SERVICE_NAME': '0', 09:18:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:32 INFO - Downloading/unpacking blobuploader==1.2.4 09:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:34 INFO - Downloading blobuploader-1.2.4.tar.gz 09:18:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:18:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:18:34 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 09:18:34 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 09:18:34 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 09:18:34 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 09:18:34 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 09:18:34 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 09:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:18:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:35 INFO - Downloading docopt-0.6.1.tar.gz 09:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:18:35 INFO - Installing collected packages: blobuploader, requests, docopt 09:18:35 INFO - Running setup.py install for blobuploader 09:18:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:18:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:18:35 INFO - Running setup.py install for requests 09:18:36 INFO - Running setup.py install for docopt 09:18:36 INFO - Successfully installed blobuploader requests docopt 09:18:36 INFO - Cleaning up... 09:18:36 INFO - Return code: 0 09:18:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:36 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')]} 09:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:36 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')]} 09:18:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:36 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 0x10a8b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a94c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c43497cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a81bd30>, '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 0x10a580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a813b20>, '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.hbN5XXt7Wb/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.etfc88C5mo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 09:18:36 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 09:18:36 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 09:18:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hbN5XXt7Wb/Render', 09:18:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:36 INFO - 'HOME': '/Users/cltbld', 09:18:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:36 INFO - 'LOGNAME': 'cltbld', 09:18:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:36 INFO - 'MOZ_NO_REMOTE': '1', 09:18:36 INFO - 'NO_EM_RESTART': '1', 09:18:36 INFO - 'PAGER': '/bin/cat', 09:18:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:36 INFO - 'PWD': '/builds/slave/test', 09:18:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:36 INFO - 'SHELL': '/bin/bash', 09:18:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.etfc88C5mo/Listeners', 09:18:36 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 09:18:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:36 INFO - 'USER': 'cltbld', 09:18:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:36 INFO - 'XPC_FLAGS': '0x0', 09:18:36 INFO - 'XPC_SERVICE_NAME': '0', 09:18:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:37 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-RNOSwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:37 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-UaCo0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:37 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-0FWv6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:37 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-VNImOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:37 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Dej7vL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:37 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-d2uAk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:37 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-tn4WyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:37 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-DoTnRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:37 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Ttmw8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:37 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-N7TWBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:38 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-JDkGSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:38 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-mhyMFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:38 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-r4J1Oa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:38 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-8wHB4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:38 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-YL8M0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:38 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-ScTJZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:38 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-VxdqxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:18:38 INFO - Running setup.py install for manifestparser 09:18:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:18:38 INFO - Running setup.py install for mozcrash 09:18:39 INFO - Running setup.py install for mozdebug 09:18:39 INFO - Running setup.py install for mozdevice 09:18:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:18:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:18:39 INFO - Running setup.py install for mozfile 09:18:39 INFO - Running setup.py install for mozhttpd 09:18:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:18:39 INFO - Running setup.py install for mozinfo 09:18:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:18:39 INFO - Running setup.py install for mozInstall 09:18:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:18:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:18:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:18:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:18:39 INFO - Running setup.py install for mozleak 09:18:40 INFO - Running setup.py install for mozlog 09:18:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:18:40 INFO - Running setup.py install for moznetwork 09:18:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:18:40 INFO - Running setup.py install for mozprocess 09:18:40 INFO - Running setup.py install for mozprofile 09:18:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:18:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:18:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:18:40 INFO - Running setup.py install for mozrunner 09:18:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:18:40 INFO - Running setup.py install for mozscreenshot 09:18:41 INFO - Running setup.py install for moztest 09:18:41 INFO - Running setup.py install for mozversion 09:18:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:18:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:18:41 INFO - Cleaning up... 09:18:41 INFO - Return code: 0 09:18:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:41 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')]} 09:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:41 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')]} 09:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:41 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 0x10a8b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a94c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c43497cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a81bd30>, '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 0x10a580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a813b20>, '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.hbN5XXt7Wb/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.etfc88C5mo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 09:18:41 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 09:18:41 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 09:18:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hbN5XXt7Wb/Render', 09:18:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:41 INFO - 'HOME': '/Users/cltbld', 09:18:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:41 INFO - 'LOGNAME': 'cltbld', 09:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:41 INFO - 'MOZ_NO_REMOTE': '1', 09:18:41 INFO - 'NO_EM_RESTART': '1', 09:18:41 INFO - 'PAGER': '/bin/cat', 09:18:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:41 INFO - 'PWD': '/builds/slave/test', 09:18:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:41 INFO - 'SHELL': '/bin/bash', 09:18:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.etfc88C5mo/Listeners', 09:18:41 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 09:18:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:41 INFO - 'USER': 'cltbld', 09:18:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:41 INFO - 'XPC_FLAGS': '0x0', 09:18:41 INFO - 'XPC_SERVICE_NAME': '0', 09:18:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:41 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Z5I9HV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:41 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)) 09:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:41 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-tNd98w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:41 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)) 09:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:41 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-ZpDN67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:41 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)) 09:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:41 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-ZQ7usN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:42 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)) 09:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-m_2X2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:42 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)) 09:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-6Rnotv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:42 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)) 09:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-KsmRis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:42 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)) 09:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-TekUiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:42 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)) 09:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-jvZf86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:42 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)) 09:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Z5c42v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 09:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-sUaRSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:42 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)) 09:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-7RqHL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-kB0LYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:42 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)) 09:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:42 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-GYoL0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:43 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-EzosSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:43 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)) 09:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:43 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-yqvA5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:43 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)) 09:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:43 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-TUGzxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:43 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)) 09:18:43 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)) 09:18:43 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)) 09:18:43 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)) 09:18:43 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)) 09:18:43 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)) 09:18:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:18:43 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 09:18:43 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 09:18:43 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 09:18:43 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 09:18:43 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 09:18:43 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 09:18:45 INFO - Downloading blessings-1.6.tar.gz 09:18:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:18:45 INFO - Installing collected packages: blessings 09:18:45 INFO - Running setup.py install for blessings 09:18:46 INFO - Successfully installed blessings 09:18:46 INFO - Cleaning up... 09:18:46 INFO - Return code: 0 09:18:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:18:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:46 INFO - Reading from file tmpfile_stdout 09:18:46 INFO - Current package versions: 09:18:46 INFO - blessings == 1.6 09:18:46 INFO - blobuploader == 1.2.4 09:18:46 INFO - docopt == 0.6.1 09:18:46 INFO - functools32 == 3.2.3-2 09:18:46 INFO - jsonschema == 2.5.1 09:18:46 INFO - manifestparser == 1.1 09:18:46 INFO - mozInstall == 1.12 09:18:46 INFO - mozcrash == 0.17 09:18:46 INFO - mozdebug == 0.1 09:18:46 INFO - mozdevice == 0.48 09:18:46 INFO - mozfile == 1.2 09:18:46 INFO - mozhttpd == 0.7 09:18:46 INFO - mozinfo == 0.9 09:18:46 INFO - mozleak == 0.1 09:18:46 INFO - mozlog == 3.2 09:18:46 INFO - moznetwork == 0.27 09:18:46 INFO - mozprocess == 0.22 09:18:46 INFO - mozprofile == 0.28 09:18:46 INFO - mozrunner == 6.11 09:18:46 INFO - mozscreenshot == 0.1 09:18:46 INFO - mozsystemmonitor == 0.3 09:18:46 INFO - moztest == 0.7 09:18:46 INFO - mozversion == 1.4 09:18:46 INFO - psutil == 3.1.1 09:18:46 INFO - requests == 1.2.3 09:18:46 INFO - wsgiref == 0.1.2 09:18:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09: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')]} 09:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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')]} 09:18:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:46 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 0x10a8b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a94c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c43497cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a81bd30>, '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 0x10a580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a813b20>, '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.hbN5XXt7Wb/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.etfc88C5mo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 09:18:46 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 09:18:46 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 09:18:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hbN5XXt7Wb/Render', 09:18:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:46 INFO - 'HOME': '/Users/cltbld', 09:18:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:46 INFO - 'LOGNAME': 'cltbld', 09:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:46 INFO - 'MOZ_NO_REMOTE': '1', 09:18:46 INFO - 'NO_EM_RESTART': '1', 09:18:46 INFO - 'PAGER': '/bin/cat', 09:18:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:46 INFO - 'PWD': '/builds/slave/test', 09:18:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:46 INFO - 'SHELL': '/bin/bash', 09:18:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.etfc88C5mo/Listeners', 09:18:46 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 09:18:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:46 INFO - 'USER': 'cltbld', 09:18:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:46 INFO - 'XPC_FLAGS': '0x0', 09:18:46 INFO - 'XPC_SERVICE_NAME': '0', 09:18:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:46 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)) 09:18:46 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)) 09:18:46 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)) 09:18:46 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)) 09:18:46 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)) 09:18:46 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)) 09:18:46 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)) 09:18:46 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)) 09:18:46 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)) 09:18:46 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)) 09:18:46 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)) 09:18:46 INFO - Cleaning up... 09:18:46 INFO - Return code: 0 09:18:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09: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')]} 09:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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')]} 09:18:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a94c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c43497cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a81bd30>, '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 0x10a580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a813b20>, '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.hbN5XXt7Wb/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.etfc88C5mo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 09: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', 'jsonschema'] in /builds/slave/test/build 09: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 jsonschema 09:18:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hbN5XXt7Wb/Render', 09:18:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:46 INFO - 'HOME': '/Users/cltbld', 09:18:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:46 INFO - 'LOGNAME': 'cltbld', 09:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:46 INFO - 'MOZ_NO_REMOTE': '1', 09:18:46 INFO - 'NO_EM_RESTART': '1', 09:18:46 INFO - 'PAGER': '/bin/cat', 09:18:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:46 INFO - 'PWD': '/builds/slave/test', 09:18:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:46 INFO - 'SHELL': '/bin/bash', 09:18:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.etfc88C5mo/Listeners', 09:18:46 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 09:18:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:46 INFO - 'USER': 'cltbld', 09:18:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:46 INFO - 'XPC_FLAGS': '0x0', 09:18:46 INFO - 'XPC_SERVICE_NAME': '0', 09:18:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:18:46 INFO - Cleaning up... 09:18:46 INFO - Return code: 0 09:18:46 INFO - Running post-action listener: _resource_record_post_action 09:18:46 INFO - Running post-action listener: _start_resource_monitoring 09:18:46 INFO - Starting resource monitoring. 09:18:46 INFO - ##### 09:18:46 INFO - ##### Finished create-virtualenv step (success) 09:18:46 INFO - ##### 09:18:46 INFO - ##### 09:18:46 INFO - ##### Running install step. 09:18:46 INFO - ##### 09:18:46 INFO - Running pre-action listener: _resource_record_pre_action 09:18:46 INFO - Running main action method: install 09:18:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:47 INFO - Reading from file tmpfile_stdout 09:18:47 INFO - Detecting whether we're running mozinstall >=1.0... 09:18:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:18:47 INFO - Reading from file tmpfile_stdout 09:18:47 INFO - Output received: 09:18:47 INFO - Usage: mozinstall [options] installer 09:18:47 INFO - Options: 09:18:47 INFO - -h, --help show this help message and exit 09:18:47 INFO - -d DEST, --destination=DEST 09:18:47 INFO - Directory to install application into. [default: 09:18:47 INFO - "/builds/slave/test"] 09:18:47 INFO - --app=APP Application being installed. [default: firefox] 09:18:47 INFO - mkdir: /builds/slave/test/build/application 09:18:47 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'] 09:18:47 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 09:19:21 INFO - Reading from file tmpfile_stdout 09:19:21 INFO - Output received: 09:19:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:19:21 INFO - Running post-action listener: _resource_record_post_action 09:19:21 INFO - ##### 09:19:21 INFO - ##### Finished install step (success) 09:19:21 INFO - ##### 09:19:21 INFO - ##### 09:19:21 INFO - ##### Running run-tests step. 09:19:21 INFO - ##### 09:19:21 INFO - Running pre-action listener: _resource_record_pre_action 09:19:21 INFO - Running main action method: run_tests 09:19:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:19:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:19:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:19:21 INFO - 2016-07-05 09:19:21.836 screenresolution[1642:7725] starting screenresolution argv=screenresolution get 09:19:21 INFO - 2016-07-05 09:19:21.839 screenresolution[1642:7725] Display 0: 1600x1200x32@60 09:19:21 INFO - 2016-07-05 09:19:21.845 screenresolution[1643:7728] starting screenresolution argv=screenresolution list 09:19:21 INFO - Available Modes on Display 0 09:19:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:19:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:19:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:19:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:19:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:19:22 INFO - Intel Iris: 09:19:22 INFO - Chipset Model: Intel Iris 09:19:22 INFO - Type: GPU 09:19:22 INFO - Bus: Built-In 09:19:22 INFO - VRAM (Dynamic, Max): 1536 MB 09:19:22 INFO - Vendor: Intel (0x8086) 09:19:22 INFO - Device ID: 0x0a2e 09:19:22 INFO - Revision ID: 0x0009 09:19:22 INFO - Displays: 09:19:22 INFO - Display: 09:19:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:19:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:19:22 INFO - Display Serial Number: 1600x1200 60 09:19:22 INFO - Main Display: Yes 09:19:22 INFO - Mirror: Off 09:19:22 INFO - Online: Yes 09:19:22 INFO - Rotation: Supported 09:19:22 INFO - Return code: 0 09:19:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:19:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:19:22 INFO - Python 2.7.3 09:19:22 INFO - Return code: 0 09:19:22 INFO - grabbing minidump binary from tooltool 09:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:19:22 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 0x10a81bd30>, '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 0x10a580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a813b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:19:22 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 09:19:22 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 09:19:22 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 09:19:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:19:22 INFO - Return code: 0 09:19:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:19:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:19:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:19:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:19:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:19:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--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 09:19:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0008 --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 09:19:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hbN5XXt7Wb/Render', 09:19:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:22 INFO - 'HOME': '/Users/cltbld', 09:19:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:22 INFO - 'LOGNAME': 'cltbld', 09:19:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:19:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:22 INFO - 'MOZ_NO_REMOTE': '1', 09:19:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:19:22 INFO - 'NO_EM_RESTART': '1', 09:19:22 INFO - 'PAGER': '/bin/cat', 09:19:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:22 INFO - 'PWD': '/builds/slave/test', 09:19:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:19:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:22 INFO - 'SHELL': '/bin/bash', 09:19:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.etfc88C5mo/Listeners', 09:19:22 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 09:19:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:22 INFO - 'USER': 'cltbld', 09:19:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:22 INFO - 'XPC_FLAGS': '0x0', 09:19:22 INFO - 'XPC_SERVICE_NAME': '0', 09:19:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--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 09:19:22 INFO - mozversion application_buildid: 20160705071024 09:19:22 INFO - mozversion application_changeset: 9b428173a0889f5a25e7a6e855f2d1726207a723 09:19:22 INFO - mozversion application_display_name: Nightly 09:19:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:19:22 INFO - mozversion application_name: Firefox 09:19:22 INFO - mozversion application_remotingname: firefox 09:19:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:19:22 INFO - mozversion application_vendor: Mozilla 09:19:22 INFO - mozversion application_version: 50.0a1 09:19:22 INFO - mozversion platform_buildid: 20160705071024 09:19:22 INFO - mozversion platform_changeset: 9b428173a0889f5a25e7a6e855f2d1726207a723 09:19:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:19:22 INFO - mozversion platform_version: 50.0a1 09:19:22 INFO - using testdate: 1467735562 09:19:22 INFO - actual date: 1467735562 09:19:22 INFO - starting webserver on 'localhost:49272' 09:19:22 INFO - SUITE-START | Running 2 tests 09:19:22 INFO - TEST-START | dromaeo_css 09:19:22 INFO - operating with platform_type : mac_ 09:19:22 INFO - Initialising browser for dromaeo_css test... 09:19:22 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpbWrzPI/profile http://localhost:49272/getInfo.html) 09:19:24 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 09:19:24 INFO - PROCESS | 1653 | colorDepth:24 09:19:24 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 09:19:24 INFO - PROCESS | 1653 | __metrics 09:19:24 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" 09:19:24 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:19:25 INFO - TEST-INFO | 1653: exit 0 09:19:25 INFO - Browser initialized. 09:19:25 INFO - Running cycle 1/1 for dromaeo_css test... 09:19:25 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpbWrzPI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:37 INFO - PROCESS | 1655 | RSS: Main: 243716096 09:19:37 INFO - PROCESS | 1655 | 09:20:28 INFO - PROCESS | 1655 | 1467735628453 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] 09:22:40 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 09:22:41 INFO - PROCESS | 1655 | RSS: Main: 345419776 09:22:41 INFO - PROCESS | 1655 | 09:25:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 09:25:45 INFO - PROCESS | 1655 | RSS: Main: 381964288 09:25:45 INFO - PROCESS | 1655 | 09:28:47 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 09:28:48 INFO - PROCESS | 1655 | RSS: Main: 371933184 09:28:48 INFO - PROCESS | 1655 | 09:31:50 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 09:31:51 INFO - PROCESS | 1655 | RSS: Main: 371978240 09:31:51 INFO - PROCESS | 1655 | 09:34:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 09:34:55 INFO - PROCESS | 1655 | RSS: Main: 341803008 09:34:55 INFO - PROCESS | 1655 | 09:37:58 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 09:37:58 INFO - PROCESS | 1655 | RSS: Main: 386064384 09:37:58 INFO - PROCESS | 1655 | 09:37:58 INFO - PROCESS | 1655 | __start_tp_report 09:37:58 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 09:37:58 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 09:37:58 INFO - PROCESS | 1655 | |i|pagename|runs| 09:37:58 INFO - PROCESS | 1655 | |0;dojo.html;3321.186309354208;3339.749518786092;3350.648181940896;3355.647656996017;3405.7786243894143;5556;5597.412271711472;5648.152777083439;5712.800051998186;5716.8856622867515;5592.552595792337;5640.971795141018;5720.0561907285355;5736.885262294752;5776.797812076578;5034.395872495305;5069.214271787879;5126.769295381698;5128.128218202895;5210.296609957645;5241.764120614563;5248.5276325130735;5250.579953603722;5266.605004624649;5290.206469029637;5038.244263360489;5066.69599824008;5094.286799848024;5138.434772175057;5164;5087.95696882142;5112.642115051948;5116.795328186868;5192.1173400522;5233.476652334774;5108.837190738249;5143.974280128613;5146.922796158061;5197.29836472079;5206.9846379955925;5567.8608034799045;5612.214289999403;5705.77176912923;5793.7392817323325;5802.47777700009;6055.005762886994;6082.665453400061;6113.358097399753;6136;6157.2919114301985;6170.167027451282;6237.656928868911;6270.65511396873;6274.529410294246;6278.937210627881;5585.860353491195;5664.980303545319;5708.143778433186;5756.913646295351;5771.047777116771;6263.060540918807;6330.556861019776;6332.1135041094285;6358.586691865014;6361.045843123476;6097.695115244309;6103.95517501633;6104.084387341934;6116.662098522342;6160.691965401712;6099.400579572073;6191.938080619227;6235.438810507076;6274.403931626488;6301.117843501914;6160.661163636043;6176.258848938156;6247.812565623039;6265.592736472206;6270.9372906271265;6222.533310001768;6276.497880169575;6279.623222606659;6297.937020629735;6303.180586523814;4831.251156070814;4848.903021939542;4866.245731911559;4949.282354058641;4961.82633607822;4455.1980643484485;4455.532169122261;4490.191765482245;4494.662900282492;4503.279475283939;5078.136716758087;5642.830715078554;5726.341470730919;5777.422257774189;5823.912641310384;6296.874062518724;6329.240491141092;6346.270178929482;6359.745610175633;6367.3314301997625;5165.863510027788;5775.5668324875805;5775.913361299584;5784.3926387729525;5788.131780232999;5313.734313284321;5362.973185134049;5392.083345831218;5410.729463526808;5434.239206511092;6304.747810087637;6307.558470907176;6338.366163383625;6363.61818290904;6367.713452894538;6284.08880712285;6318.684065796784;6335.4931605472375;6356.904646430215;6370.1718776558755;6258.593191442542;6278.686065696788;6303.6532990685955;6361.586496877688;6374.681265936591;6174.012158054869;6194.194754681863;6199.690015499117;6206.782762603287;6208.782692605737;5141.665791723526;6027.6986150693165;6248.593841400295;6289.716962736779;6320.4311611955145;5841.357450680337;5922.1412895130825;5969.224000879858;6003.939960600513;6014.338422773404;5961;5992.610480318765;6009.399060093956;6009.969950150221;6012.6993650316435;4810.494898035656;5749.195112684144;5835.299764028344;5843.561732869966;5865.354810970875;6041.003234466264;6125.449118208347;6128.717018009349;6132.875646117402;6144.341027922525;5934.258217722785;5938.792142275;5948.196993405835;5960.821175364746;5980.551458640706 09:37:58 INFO - PROCESS | 1655 | |1;ext.html;472.1807663703476;486.6447493329869;522.3027258609756;544.7004147718759;563.1355868741481;25803.870980645093;42877.35683964739;44254.55745442553;44424.999999999956;46727.29909051367;45594.77202613982;46042.07915841681;46065.7696711516;46077.539224607746;46306.30540541895;8815.074417186199;9763.853542196855;9941.701748947507;9951.154151897097;9963.103320701135;8824.117588241157;11945.402729863516;12180.269183848997;12196.939015304912;12442.440090195963;12064.879351206508;12138.635840924786;12146.028317734605;12147.635570932842;12151.210171338878;8813.471191728453;11846.993005594535;11937.46281417334;12115;12161.878381216207;12012.519499220021;12098.81851772224;12101.878981210164;12103.031757459383;12105.757884842342;42904.570954290684;43152.921176970514;43191;43204.78397608023;43290.567094328966;3098.442280389529;3484.407650699387;3555.964440355589;3694.7967861767593;3794.9620503794886;32545.860894868565;32909;33147;33904.83047584771;34187.82906085478;3306.553615261957;3312.2381852174135;3342.699157075875;3346.4478361070396;3366.4277072897667;539078;542070;542898;543989;544246;35329.94010179699;35433.46849797255;65581.68836623241;66859;66905;30055;36178;36291.91124266276;36332.455013174826;36399.63600363962;14949.252537373088;15081.773773393408;15097.094174349575;15107;15112.09327440357;11645.88354116448;11795.587154449553;11831.40842957866;11964.700882478008;12750.744985100246;7849.803754906059;7933;7944.364450843918;8154.2253485918745;8238.09380968093;3271.8854840080485;3288.177955511122;3293.884714034997;3296.9175770605448;3323.6344002159744;932.6455946740331;948.5020364308655;977.9608815647294;1069.4599227390117;1074.6453670288786;31580.842095789372;31849.8407507961;31857;32225;32620;1089.536946797608;1097.8188598881095;1120.394986707169;1129.6723950054557;1155.3241353807948;14983.250837458083;16617.836751427283;16697.49907502777;16708.832911670972;17037.318507259817;707059;714116;715966;716003;716819;64092.679536602016;64349.74775882821;64508.67745661335;64654;64699.67650161719;1273.2487832179058;1383.4120498787975;1416.3838730152418;1439.6904665496759;1443.6607397261425;32515.837420812746;32612.51081233736;32965.67034329626;33353.059062811815;33396.83301583477;53711.46288537221;54510.274486274735;54563.45436545742;54593.727031364586;54720.72639636776;503552.48223758646;513036;514423;515151;516200;23961.921713523283;24245.272641820775;27746;28118.578221327105;28254;49651.50348496613;50875.74562127166;51697.7415112922;51749;51840.74079629729;39149;41634.79182604067;41969.79015105027;42425.5757442434;43251.567484324754;39449.80275098606;41528.456420724266;42199.40476190476;42619.934501637705;42676.93307667333;39569.80215098906;39784.204315914096;40604.78185654435;40665.59334406618;40733.38899916445;35749.1062723434;35844.64155358517;36387.81806090953;36615.63384366122;36744.63255367412;24841.875790620932;25004.499910001334;26119.347016324744;26585.601215981387;26957.595636065867 09:37:58 INFO - PROCESS | 1655 | |2;jquery.html;323.4888875576589;368.4399712437094;390.6815945004821;403.24995508354453;418.74038096380247;15524.22378881057;15789.447369342075;18059.819401805977;20116.994150292463;22429.887850560764;17322.913385433054;21138.788612113873;21553.56892862146;22034.788086655226;22088.88955555224;5931.6441013539325;6117.082437634346;6181.567290289681;6182.598131121463;6190.50475961922;3576.874809381672;4401.339799030139;6157.599756015867;6812.62530560819;6822.3859852613505;6808.8978665320055;6812.965935170318;6829.419499342562;6839.418649414805;6862.107925969617;6457.225132984048;6737.191537015565;6737.797866064026;6741.191057073158;6744.966275168593;3528.788272703646;3900.64894159527;3987.063040185554;4820.3492528508905;5815.534757219412;26388.736112639013;26472;26712.59931101035;26731.465370692673;26814.195574132806;2248.584011957793;2516.5344411283972;2518.445941892781;3015.6531998820196;3077.707617776308;13287.667808304774;14851.405943762236;15860;16109.436169734003;16569;1825.4432398118568;1868.7664041994751;1872.7038029987118;1872.8501719862516;1905.4664693885734;108745;110492;111068.88931110747;112528;112551.43724281408;8939.73180804577;10662.253642245123;10666.839997400046;11127.554897804159;11238.438078096062;12848.807267890988;12990.805137922938;13059.21644701321;13703.794443083361;13714;8498.66005359791;8715.607797649121;8727.781805454915;8789.956050219707;8821.69124080667;7778.844423111506;8070.354371650371;8781.736547903573;8876.334274929406;8921;6750.864982700318;6826.863462730717;6867.862642747217;6876.931230687629;6921.826954326081;1842.240660169535;1876.2682553804027;1879.5301174706324;1886.0852486544015;1886.6226754649126;876.8903887014123;913.6614859231258;965.1554889471712;1007.3603261928581;1051.0593019247883;20550.794492055185;21815.781842181692;23218.651720223872;23227.419314517272;23233.30300091;1005.5324274212526;1007.1992765751245;1065.2064212161988;1076.3380520979542;1089.54239219528;11977.221480603734;12027;12220.022398208122;12296.954758845419;12361.258324500559;598748;603725;608594;614874;623205;100053.49973250087;115194;115954.4202279;116409.41795290969;116982;1006.6879267427121;1018.4602160854907;1029.3360782295379;1035.7151783259512;1036.9014943580348;19949.401517954484;20040.298589549297;20197;20225.59548809045;20297.188112475334;48498.78753031202;49961;50108.49891501039;50190.498095018585;50248.497515024384;66787.66606166938;67750;67980.64038719297;68668.6566567164;69559.65220173867;8084.39367047462;8204.097549269694;8245.257926786619;8283.50298982063;8511.616977236163;12367.257964522158;12775.361231938552;12913.160644558073;13281.203127812361;13372;11398.886011140114;11503.079753619522;11524.711882203012;11551.884481155417;11608.593699220486;10098;10338;11571.421428928688;11573;11783.646490605295;10331.915148909256;11646.010089142517;11674.883251167379;11723.882761172621;11928.522859085539;7212.4951253411755;8241.50550966944;8299.336053115616;8331.208535189147;8530.402871799162;8201.589920503831;10768.246222764332;11341.092712583139;11383.658490245305;11613.954744073075 09:37:58 INFO - PROCESS | 1655 | |3;mootools.html;1389.076264284251;1523.7257293687135;1575.8424157584238;1764.426561367556;1910.1786231920278;7817.530948143109;8282.62728177232;8285.627146778386;8291.95854020729;8292.79268018299;8389;8649.740507784762;8654.048054713992;8784.95607521963;8785.648574057046;2302.896369663365;2381.7499162587937;2384.6899903012586;2386.427257458206;2395.892184851677;2340.32130682102;2363.869987150706;2382.6783384243076;2386.53462574798;2387.689600351952;2338.7193536775612;2367.479154585997;2373.9762602373926;2378.1795280628157;2379.8572085674828;2330.3940975346363;2352.6118190498546;2363.7518060603647;2363.881805909715;2371.2886134159858;2295.104909085458;2332.183735692511;2335.2643917166038;2338.824588155895;2343.5430091132234;7521.962390188069;8770.517621530813;8802.163794439582;8855.158759917798;8862.11378862115;5178;5213.2701421800975;5390.811321603725;5394.973025134849;5398.811041613564;3108.362785628941;3602.6217247189193;3641.963580364189;3727.683146932514;3737.6075512071384;1077.299755159145;1088.88022317545;1095.5289225632976;1110.6334909479851;1117.0114448712948;11506;11628.825567616492;11655.76688466226;11670.824937625943;11671.824922626167;4434.423524941737;4502.144592527409;4523.5024147343765;4606.677532572755;4613.76931153441;9279.211267042241;9279.628814847465;9287.58205880737;9294.024127466653;9298.581563829644;5247.081760691864;5248.763805628756;5261.657992230493;5264.657797243167;5265.7103859287345;4215.473065866767;4382.101669157815;4525.773711314421;4864.051509955525;4934.827281045146;3686.318031164243;3709.684676802448;3734.5518537775097;3756.7039371416818;3761.247750449921;1144.7939370913316;1160.5589875847293;1162.0587324267372;1178.0752109594098;1179.204037274836;907.3739120007306;929.9907000929904;931.7065124485765;941.3551717073826;996.9003099689972;5879.823605291848;5923.078451238706;6051.9697401513595;6082.878342433215;6165.229346331708;399.3390937997619;419.26419134419507;429.83236537750304;445.5277405949704;462.4034994856597;4021.3967904814513;4077.959220407758;4105.85629502966;4169.687273454503;4169.749815011109;10258.58965641381;10789.460526973777;10810.513526891309;10823.621173258898;10867.076298514714;10737.248392612599;10778.78442431147;10805.513751881184;10820.296680715728;10832.18758593093;6379.681015949184;8083.959580202061;8207.95896020516;8342.207490288412;8351.8329633407;5404.891997140656;6003.729832157476;6115.938840611715;6130.785422510191;6190.442860142609;9604.231661467205;10003.449810260505;10008.199344052604;10206.693799186036;10215;9803.509824508603;10194.388336699822;10306;10318.380897146195;10344.431056291976;7013.263607321158;7223.205447400676;7251.492395532262;7441.851162976818;7596.658150383136;8404.747857564284;8481.194286542768;8516.70191543293;8595.570221488775;8621.008584012669;7556.4710470266555;7679.500832445873;7704.614769261627;7820;7822.569758663328;7479.962600186965;7505.6997720090585;7522.623868806647;7683.9231607683205;7701.88447173304;6348.333424990309;6525.934740652722;7023.473239506955;7060.752873649398;7113.288671133052;4905.975470122627;5028.572571331478;5106.157484015096;5110.3356563646485;5118.872028199325;5067.949320506896;5093.134167191661;5093.3193790336945;5122.795088196431;5232.3982741985155 09:37:58 INFO - PROCESS | 1655 | |4;prototype.html;309.0866489523458;311.69298241232394;322.59997602972317;324.7142514587163;327.6003276003275;4938.851834444972;5237.214417837326;5300.787968481256;5338.3860856001575;5352.892942141159;4889.408613434876;5019.824306149286;5028.899422011557;5185.274061631375;5294.973525132378;3368.3263347330508;4056.533498647649;4078.6940979426513;4080.755154690728;4127.649149822269;4071.5114186297687;4076.388541718751;4097.221527909687;4103.281925663012;4114.012636967122;3511.94732079019;3613.602503724588;3655.817209139532;3835.578086410507;4086.468759061318;4021.91408319752;4039.454673619053;4060.20825938942;4065.5731148229306;4067.1458993611377;3178.666240044785;3333.916652083693;3546.386394190637;3557.4130268505664;3562.2697347043795;4477.865664030084;4867.36724225852;5208.374995000624;5209.7916083356995;5229.790808367661;4057.97971010146;4616.53834616539;4683;4687.1328804171335;4713.787879545429;5480.91778623321;5499.4225606311165;5509.531689806371;5568.721563921828;5579.497845193911;2874.7412732853936;3895.182011777502;3905.3360928641914;3919.2945269851416;3924.823382947774;4818.469968303519;5095.044873973086;5891.381404952461;5897.174395584621;5928.318243402021;3878.166194268246;3882.3400021996326;3887.7667339959694;3919.922021444069;3982.6017398260524;3979.3434083376496;3980.5820388859333;3997.3004724173734;3997.340438827561;3999.9400008999887;3850.5956874528333;4083;4348.782560871944;4495;4575.9084818303445;5329.467053294717;5567.858588989247;5602.831915042555;5608.383077861432;5633.521150702236;1060.3054998975683;1316.9341532923315;1341.161773891318;1347.7910923806826;1347.8382594088575;774.5855967057597;880.445319448756;895.7760559860035;901.2339511415375;909.6816114360054;4831.492693267156;5200.503758196087;5203.73981300941;5240.4365031778;5266.210068489757;5726.3700992890745;5749.9712501437225;5760.596758226884;5776.306843178845;5811;4757.738324392122;4933.703977761345;4970.329005584272;5125.128728116155;5241.816536421207;2438.9878050609987;2882.256234977808;3004.864781084813;3007.624046994126;3018.4566777980176;5494.846082322757;5650.519705824969;5894.6463212206545;5962.22491076166;5969;5792.4497172768515;5801.6809075500405;5816.447437493432;5847.976604094267;5929.065112769207;4909.926351104737;5102.85185833192;5593.972030139823;5897.76408943646;5992.700364981733;4677.694812931811;5585.525230355465;5663.32040155184;5683.2950114964515;5710.914336284876;5530.972345138249;5589.329280486368;5591;5601.29983752031;5663.915041274302;5108.259302401206;5942.970285148546;5950.03648795905;5971.0744834550715;5992.400759923973;3956.5054368203973;4000.8399664013114;4003.939940900947;4021.879343619696;4035.878923632296;5028.321176641107;5760.654360738369;5814.825555233349;5827.446392592697;5860.824175274749;4569.702969306984;5471.905618876321;5679.517241034393;5693.605066758671;5703.287089113861;4650.604698600521;5473.397926228191;5585.832425027255;5616.466435688603;5618.213450116905;4639.559241872017;5230.803062713593;5306.938612277637;5515.503604675599;5516.420775818481;4416.22716024688;4639.30410438437;5380.973095134499;5381.919271210857;5670.461306175906;4633.884152896205;5386.7575959082715;5406.864828379322;5557.083081291542;5603.075492543685 09:37:58 INFO - PROCESS | 1655 | |5;yui.html;748.2517482517483;752.3116348541085;752.4995877741299;761.6686741267549;886.960086796094;15100.924495377521;15779.684406311895;16377.590560235985;16435.178241087957;16555.586110347216;14872.925635371836;14905;15088.47190348338;15156.317965691544;15187.316570754316;728.3226599262683;759.5442734359368;895.520896320467;900.558726224152;930.5347326336831;920.1074957291451;927.8608208768705;930.585889279271;931.0131260863507;931.2643012020529;984.6110786239431;987.4371608183338;988.4365911430488;990.697837159666;991.1476130527741;761.0828951113922;944.6504793226482;977.2426369563574;979.9510024498746;981.1856159387692;732.4140687450018;733.5232099135551;734.3244215321863;734.5776178697271;734.7905846833627;14483.927580362137;14510.70978580433;14515.274236288144;14540.70918581633;14588.416463341453;14310.141391516543;14361.497347592784;14409.927950360181;14411.135331880121;14571.271436428136;12153.817692734616;12400.317982510958;12597.99216062722;12598;12739.299338536377;3809.695224382043;4532.138893610204;4685.695429797052;4737.786799594027;4795.544423279783;102004.48997755037;105618;117426;117699.41150294279;118065.81934180635;11647.242929209575;11764.059753954278;11852.822207666892;11953.940230298793;12031.578894738643;22628.868556572434;22890.65664015041;22900;22914.770852291596;22917.656235156486;8107.594620268963;8359.8328033439;8463.195996380335;8479.830403392021;8599.312055035582;4529.252459277547;4601.102784956901;4611.1930412177735;4635.443746750411;4864.951350486521;3702.018964974284;3805.6765174960437;3806.847726090981;3980;4218.599233072853;617.450469082517;663.9900401493982;754.4907187648315;759.51770625652;768.0821418405064;599.4275466929072;620.3238470067648;626.9592476489014;702.3994484715597;712.1667648850857;8585.570721464026;9350.298727595458;9645.90354096464;9851.267718107378;10102.781286375877;675.5203805298294;680.086104468779;690.6753825701912;695.4505940307201;722.479814533535;4666.626669866402;4770.570648641569;4838.153323168502;4849.781759820817;4853.417589889165;158338;159451.20274398554;167590;170161;172051;81617;86129;133364.33317833542;135131.32434337767;136636.31681841527;123499;125947.3702631481;126324.36837815936;127298.36350818186;128461.35769321094;9156.221721153777;9176.862347064933;9212.907870921204;9378.67174648884;9410.153086222273;59308;65718.01422978754;66266;67068.32931670621;67348.58142732158;61039.389606103374;94110.52944735232;94568.52715736377;97009;98124.01875981149;12250.571230006906;12298;12435;12635.368231588567;12923.806142907675;13176.143550669176;13320.267385293902;13345;13345.599632011055;13528.323583820573;12500.562480313145;12541.811872821732;12549.560765373168;12810.167339122927;12994.285314307805;12415.93792031034;12573;12588.874111259136;12626.747465050832;12661.177023493443;12281.324527150735;12311.753764924471;12339.13626046168;12353.38233088324;12376.62870113898;10306.224551461632;10577.31247468912;10716.57133714674;10742;10912.781744365227;8533.445326053787;10347.793044138925;10431.634892778717;10742.624008159677;10760.946195268974 09:37:58 INFO - PROCESS | 1655 | __end_tp_report 09:37:58 INFO - PROCESS | 1655 | __start_cc_report 09:37:58 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1023 09:37:58 INFO - PROCESS | 1655 | __end_cc_report 09:37:58 INFO - PROCESS | 1655 | __startTimestamp1467736678521__endTimestamp 09:37:58 INFO - PROCESS | 1655 | 09:37:58 INFO - PROCESS | 1655 | ------- Summary: start ------- 09:37:58 INFO - PROCESS | 1655 | Number of tests: 6 09:37:58 INFO - PROCESS | 1655 | 09:37:58 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5714.45 Median:5922.14 stddev:654.27 (11.0%) stddev-sans-first:628.76 09:37:58 INFO - PROCESS | 1655 | Values: 3321.2 3339.7 3350.6 3355.6 3405.8 5556.0 5597.4 5648.2 5712.8 5716.9 5592.6 5641.0 5720.1 5736.9 5776.8 5034.4 5069.2 5126.8 5128.1 5210.3 5241.8 5248.5 5250.6 5266.6 5290.2 5038.2 5066.7 5094.3 5138.4 5164.0 5088.0 5112.6 5116.8 5192.1 5233.5 5108.8 5144.0 5146.9 5197.3 5207.0 5567.9 5612.2 5705.8 5793.7 5802.5 6055.0 6082.7 6113.4 6136.0 6157.3 6170.2 6237.7 6270.7 6274.5 6278.9 5585.9 5665.0 5708.1 5756.9 5771.0 6263.1 6330.6 6332.1 6358.6 6361.0 6097.7 6104.0 6104.1 6116.7 6160.7 6099.4 6191.9 6235.4 6274.4 6301.1 6160.7 6176.3 6247.8 6265.6 6270.9 6222.5 6276.5 6279.6 6297.9 6303.2 4831.3 4848.9 4866.2 4949.3 4961.8 4455.2 4455.5 4490.2 4494.7 4503.3 5078.1 5642.8 5726.3 5777.4 5823.9 6296.9 6329.2 6346.3 6359.7 6367.3 5165.9 5775.6 5775.9 5784.4 5788.1 5313.7 5363.0 5392.1 5410.7 5434.2 6304.7 6307.6 6338.4 6363.6 6367.7 6284.1 6318.7 6335.5 6356.9 6370.2 6258.6 6278.7 6303.7 6361.6 6374.7 6174.0 6194.2 6199.7 6206.8 6208.8 5141.7 6027.7 6248.6 6289.7 6320.4 5841.4 5922.1 5969.2 6003.9 6014.3 5961.0 5992.6 6009.4 6010.0 6012.7 4810.5 5749.2 5835.3 5843.6 5865.4 6041.0 6125.4 6128.7 6132.9 6144.3 5934.3 5938.8 5948.2 5960.8 5980.6 09:37:58 INFO - PROCESS | 1655 | 09:37:58 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:71975.60 Median:36312.18 stddev:159647.86 (439.7%) stddev-sans-first:160005.45 09:37:58 INFO - PROCESS | 1655 | Values: 472.2 486.6 522.3 544.7 563.1 25803.9 42877.4 44254.6 44425.0 46727.3 45594.8 46042.1 46065.8 46077.5 46306.3 8815.1 9763.9 9941.7 9951.2 9963.1 8824.1 11945.4 12180.3 12196.9 12442.4 12064.9 12138.6 12146.0 12147.6 12151.2 8813.5 11847.0 11937.5 12115.0 12161.9 12012.5 12098.8 12101.9 12103.0 12105.8 42904.6 43152.9 43191.0 43204.8 43290.6 3098.4 3484.4 3556.0 3694.8 3795.0 32545.9 32909.0 33147.0 33904.8 34187.8 3306.6 3312.2 3342.7 3346.4 3366.4 539078.0 542070.0 542898.0 543989.0 544246.0 35329.9 35433.5 65581.7 66859.0 66905.0 30055.0 36178.0 36291.9 36332.5 36399.6 14949.3 15081.8 15097.1 15107.0 15112.1 11645.9 11795.6 11831.4 11964.7 12750.7 7849.8 7933.0 7944.4 8154.2 8238.1 3271.9 3288.2 3293.9 3296.9 3323.6 932.6 948.5 978.0 1069.5 1074.6 31580.8 31849.8 31857.0 32225.0 32620.0 1089.5 1097.8 1120.4 1129.7 1155.3 14983.3 16617.8 16697.5 16708.8 17037.3 707059.0 714116.0 715966.0 716003.0 716819.0 64092.7 64349.7 64508.7 64654.0 64699.7 1273.2 1383.4 1416.4 1439.7 1443.7 32515.8 32612.5 32965.7 33353.1 33396.8 53711.5 54510.3 54563.5 54593.7 54720.7 503552.5 513036.0 514423.0 515151.0 516200.0 23961.9 24245.3 27746.0 28118.6 28254.0 49651.5 50875.7 51697.7 51749.0 51840.7 39149.0 41634.8 41969.8 42425.6 43251.6 39449.8 41528.5 42199.4 42619.9 42676.9 39569.8 39784.2 40604.8 40665.6 40733.4 35749.1 35844.6 36387.8 36615.6 36744.6 24841.9 25004.5 26119.3 26585.6 26957.6 09:37:58 INFO - PROCESS | 1655 | 09:37:58 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:34694.16 Median:22259.39 stddev:100995.26 (453.7%) stddev-sans-first:101245.61 09:37:58 INFO - PROCESS | 1655 | Values: 323.5 368.4 390.7 403.2 418.7 15524.2 15789.4 18059.8 20117.0 22429.9 17322.9 21138.8 21553.6 22034.8 22088.9 5931.6 6117.1 6181.6 6182.6 6190.5 3576.9 4401.3 6157.6 6812.6 6822.4 6808.9 6813.0 6829.4 6839.4 6862.1 6457.2 6737.2 6737.8 6741.2 6745.0 3528.8 3900.6 3987.1 4820.3 5815.5 26388.7 26472.0 26712.6 26731.5 26814.2 2248.6 2516.5 2518.4 3015.7 3077.7 13287.7 14851.4 15860.0 16109.4 16569.0 1825.4 1868.8 1872.7 1872.9 1905.5 108745.0 110492.0 111068.9 112528.0 112551.4 8939.7 10662.3 10666.8 11127.6 11238.4 12848.8 12990.8 13059.2 13703.8 13714.0 8498.7 8715.6 8727.8 8790.0 8821.7 7778.8 8070.4 8781.7 8876.3 8921.0 6750.9 6826.9 6867.9 6876.9 6921.8 1842.2 1876.3 1879.5 1886.1 1886.6 876.9 913.7 965.2 1007.4 1051.1 20550.8 21815.8 23218.7 23227.4 23233.3 1005.5 1007.2 1065.2 1076.3 1089.5 11977.2 12027.0 12220.0 12297.0 12361.3 598748.0 603725.0 608594.0 614874.0 623205.0 100053.5 115194.0 115954.4 116409.4 116982.0 1006.7 1018.5 1029.3 1035.7 1036.9 19949.4 20040.3 20197.0 20225.6 20297.2 48498.8 49961.0 50108.5 50190.5 50248.5 66787.7 67750.0 67980.6 68668.7 69559.7 8084.4 8204.1 8245.3 8283.5 8511.6 12367.3 12775.4 12913.2 13281.2 13372.0 11398.9 11503.1 11524.7 11551.9 11608.6 10098.0 10338.0 11571.4 11573.0 11783.6 10331.9 11646.0 11674.9 11723.9 11928.5 7212.5 8241.5 8299.3 8331.2 8530.4 8201.6 10768.2 11341.1 11383.7 11614.0 09:37:58 INFO - PROCESS | 1655 | 09:37:58 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5671.28 Median:2663.23 stddev:3196.79 (120.0%) stddev-sans-first:3189.56 09:37:58 INFO - PROCESS | 1655 | Values: 1389.1 1523.7 1575.8 1764.4 1910.2 7817.5 8282.6 8285.6 8292.0 8292.8 8389.0 8649.7 8654.0 8785.0 8785.6 2302.9 2381.7 2384.7 2386.4 2395.9 2340.3 2363.9 2382.7 2386.5 2387.7 2338.7 2367.5 2374.0 2378.2 2379.9 2330.4 2352.6 2363.8 2363.9 2371.3 2295.1 2332.2 2335.3 2338.8 2343.5 7522.0 8770.5 8802.2 8855.2 8862.1 5178.0 5213.3 5390.8 5395.0 5398.8 3108.4 3602.6 3642.0 3727.7 3737.6 1077.3 1088.9 1095.5 1110.6 1117.0 11506.0 11628.8 11655.8 11670.8 11671.8 4434.4 4502.1 4523.5 4606.7 4613.8 9279.2 9279.6 9287.6 9294.0 9298.6 5247.1 5248.8 5261.7 5264.7 5265.7 4215.5 4382.1 4525.8 4864.1 4934.8 3686.3 3709.7 3734.6 3756.7 3761.2 1144.8 1160.6 1162.1 1178.1 1179.2 907.4 930.0 931.7 941.4 996.9 5879.8 5923.1 6052.0 6082.9 6165.2 399.3 419.3 429.8 445.5 462.4 4021.4 4078.0 4105.9 4169.7 4169.7 10258.6 10789.5 10810.5 10823.6 10867.1 10737.2 10778.8 10805.5 10820.3 10832.2 6379.7 8084.0 8208.0 8342.2 8351.8 5404.9 6003.7 6115.9 6130.8 6190.4 9604.2 10003.4 10008.2 10206.7 10215.0 9803.5 10194.4 10306.0 10318.4 10344.4 7013.3 7223.2 7251.5 7441.9 7596.7 8404.7 8481.2 8516.7 8595.6 8621.0 7556.5 7679.5 7704.6 7820.0 7822.6 7480.0 7505.7 7522.6 7683.9 7701.9 6348.3 6525.9 7023.5 7060.8 7113.3 4906.0 5028.6 5106.2 5110.3 5118.9 5067.9 5093.1 5093.3 5122.8 5232.4 09:37:58 INFO - PROCESS | 1655 | 09:37:58 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4527.41 Median:5098.95 stddev:1391.45 (27.3%) stddev-sans-first:1358.85 09:37:58 INFO - PROCESS | 1655 | Values: 309.1 311.7 322.6 324.7 327.6 4938.9 5237.2 5300.8 5338.4 5352.9 4889.4 5019.8 5028.9 5185.3 5295.0 3368.3 4056.5 4078.7 4080.8 4127.6 4071.5 4076.4 4097.2 4103.3 4114.0 3511.9 3613.6 3655.8 3835.6 4086.5 4021.9 4039.5 4060.2 4065.6 4067.1 3178.7 3333.9 3546.4 3557.4 3562.3 4477.9 4867.4 5208.4 5209.8 5229.8 4058.0 4616.5 4683.0 4687.1 4713.8 5480.9 5499.4 5509.5 5568.7 5579.5 2874.7 3895.2 3905.3 3919.3 3924.8 4818.5 5095.0 5891.4 5897.2 5928.3 3878.2 3882.3 3887.8 3919.9 3982.6 3979.3 3980.6 3997.3 3997.3 3999.9 3850.6 4083.0 4348.8 4495.0 4575.9 5329.5 5567.9 5602.8 5608.4 5633.5 1060.3 1316.9 1341.2 1347.8 1347.8 774.6 880.4 895.8 901.2 909.7 4831.5 5200.5 5203.7 5240.4 5266.2 5726.4 5750.0 5760.6 5776.3 5811.0 4757.7 4933.7 4970.3 5125.1 5241.8 2439.0 2882.3 3004.9 3007.6 3018.5 5494.8 5650.5 5894.6 5962.2 5969.0 5792.4 5801.7 5816.4 5848.0 5929.1 4909.9 5102.9 5594.0 5897.8 5992.7 4677.7 5585.5 5663.3 5683.3 5710.9 5531.0 5589.3 5591.0 5601.3 5663.9 5108.3 5943.0 5950.0 5971.1 5992.4 3956.5 4000.8 4003.9 4021.9 4035.9 5028.3 5760.7 5814.8 5827.4 5860.8 4569.7 5471.9 5679.5 5693.6 5703.3 4650.6 5473.4 5585.8 5616.5 5618.2 4639.6 5230.8 5306.9 5515.5 5516.4 4416.2 4639.3 5381.0 5381.9 5670.5 4633.9 5386.8 5406.9 5557.1 5603.1 09:37:58 INFO - PROCESS | 1655 | 09:37:58 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:25456.99 Median:3808.27 stddev:41576.68 (1091.7%) stddev-sans-first:41651.92 09:37:58 INFO - PROCESS | 1655 | Values: 748.3 752.3 752.5 761.7 887.0 15100.9 15779.7 16377.6 16435.2 16555.6 14872.9 14905.0 15088.5 15156.3 15187.3 728.3 759.5 895.5 900.6 930.5 920.1 927.9 930.6 931.0 931.3 984.6 987.4 988.4 990.7 991.1 761.1 944.7 977.2 980.0 981.2 732.4 733.5 734.3 734.6 734.8 14483.9 14510.7 14515.3 14540.7 14588.4 14310.1 14361.5 14409.9 14411.1 14571.3 12153.8 12400.3 12598.0 12598.0 12739.3 3809.7 4532.1 4685.7 4737.8 4795.5 102004.5 105618.0 117426.0 117699.4 118065.8 11647.2 11764.1 11852.8 11953.9 12031.6 22628.9 22890.7 22900.0 22914.8 22917.7 8107.6 8359.8 8463.2 8479.8 8599.3 4529.3 4601.1 4611.2 4635.4 4865.0 3702.0 3805.7 3806.8 3980.0 4218.6 617.5 664.0 754.5 759.5 768.1 599.4 620.3 627.0 702.4 712.2 8585.6 9350.3 9645.9 9851.3 10102.8 675.5 680.1 690.7 695.5 722.5 4666.6 4770.6 4838.2 4849.8 4853.4 158338.0 159451.2 167590.0 170161.0 172051.0 81617.0 86129.0 133364.3 135131.3 136636.3 123499.0 125947.4 126324.4 127298.4 128461.4 9156.2 9176.9 9212.9 9378.7 9410.2 59308.0 65718.0 66266.0 67068.3 67348.6 61039.4 94110.5 94568.5 97009.0 98124.0 12250.6 12298.0 12435.0 12635.4 12923.8 13176.1 13320.3 13345.0 13345.6 13528.3 12500.6 12541.8 12549.6 12810.2 12994.3 12415.9 12573.0 12588.9 12626.7 12661.2 12281.3 12311.8 12339.1 12353.4 12376.6 10306.2 10577.3 10716.6 10742.0 10912.8 8533.4 10347.8 10431.6 10742.6 10760.9 09:37:58 INFO - PROCESS | 1655 | -------- Summary: end -------- 09:37:58 INFO - PROCESS | 1655 | 09:37:59 INFO - TEST-INFO | 1655: exit 0 09:37:59 INFO - TEST-OK | dromaeo_css | took 1116817ms 09:37:59 INFO - TEST-START | kraken 09:37:59 INFO - operating with platform_type : mac_ 09:37:59 INFO - Initialising browser for kraken test... 09:37:59 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmp7J0Eod/profile http://localhost:49272/getInfo.html) 09:38:01 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 09:38:01 INFO - PROCESS | 1663 | colorDepth:24 09:38:01 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 09:38:01 INFO - PROCESS | 1663 | __metrics 09:38:01 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" 09:38:01 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:38:02 INFO - TEST-INFO | 1663: exit 0 09:38:02 INFO - Browser initialized. 09:38:02 INFO - Running cycle 1/1 for kraken test... 09:38:02 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmp7J0Eod/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:14 INFO - PROCESS | 1664 | RSS: Main: 243654656 09:38:14 INFO - PROCESS | 1664 | 09:38:16 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 09:38:17 INFO - PROCESS | 1664 | RSS: Main: 294121472 09:38:17 INFO - PROCESS | 1664 | 09:38:20 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 09:38:20 INFO - PROCESS | 1664 | RSS: Main: 304660480 09:38:20 INFO - PROCESS | 1664 | 09:38:24 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 09:38:24 INFO - PROCESS | 1664 | RSS: Main: 390979584 09:38:24 INFO - PROCESS | 1664 | 09:38:26 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 09:38:27 INFO - PROCESS | 1664 | RSS: Main: 291094528 09:38:27 INFO - PROCESS | 1664 | 09:38:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:38:30 INFO - PROCESS | 1664 | RSS: Main: 295120896 09:38:30 INFO - PROCESS | 1664 | 09:38:32 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 09:38:33 INFO - PROCESS | 1664 | RSS: Main: 333008896 09:38:33 INFO - PROCESS | 1664 | 09:38:36 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 09:38:37 INFO - PROCESS | 1664 | RSS: Main: 334938112 09:38:37 INFO - PROCESS | 1664 | 09:38:39 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 09:38:40 INFO - PROCESS | 1664 | RSS: Main: 334667776 09:38:40 INFO - PROCESS | 1664 | 09:38:42 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:38:42 INFO - PROCESS | 1664 | RSS: Main: 313778176 09:38:42 INFO - PROCESS | 1664 | 09:38:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:38:45 INFO - PROCESS | 1664 | RSS: Main: 321093632 09:38:45 INFO - PROCESS | 1664 | 09:38:47 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:38:48 INFO - PROCESS | 1664 | RSS: Main: 317952000 09:38:48 INFO - PROCESS | 1664 | 09:38:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:38:51 INFO - PROCESS | 1664 | RSS: Main: 327692288 09:38:51 INFO - PROCESS | 1664 | 09:38:54 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:38:55 INFO - PROCESS | 1664 | RSS: Main: 330780672 09:38:55 INFO - PROCESS | 1664 | 09:38:56 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 09:38:57 INFO - PROCESS | 1664 | RSS: Main: 373829632 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | __start_tp_report 09:38:57 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 09:38:57 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 09:38:57 INFO - PROCESS | 1664 | |i|pagename|runs| 09:38:57 INFO - PROCESS | 1664 | |0;ai-astar;101;111;111;112;113;112;112;112;112;112 09:38:57 INFO - PROCESS | 1664 | |1;audio-beat-detection;143;106;107;161;108;111;127;122;135;92 09:38:57 INFO - PROCESS | 1664 | |2;audio-dft;184;184;185;183;183;183;180;185;185;182 09:38:57 INFO - PROCESS | 1664 | |3;audio-fft;79;73;57;86;60;56;65;66;82;57 09:38:57 INFO - PROCESS | 1664 | |4;audio-oscillator;81;91;92;70;69;68;96;69;68;98 09:38:57 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;91;91;89;86;88;90;87;85;86;83 09:38:57 INFO - PROCESS | 1664 | |6;imaging-darkroom;93;128;148;92;93;93;93;93;93;105 09:38:57 INFO - PROCESS | 1664 | |7;imaging-desaturate;83;85;84;83;84;83;80;86;85;86 09:38:57 INFO - PROCESS | 1664 | |8;json-parse-financial;53;55;55;57;55;55;53;54;54;54 09:38:57 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;65;70;80;73;70;71;68;69;68;71 09:38:57 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;84;83;84;82;85;93;100;100;101;85 09:38:57 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;138;135;136;133;137;117;119;143;244;160 09:38:57 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;165;161;219;186;162;163;166;163;164;163 09:38:57 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;66;68;66;65;65;66;65;66;66;68 09:38:57 INFO - PROCESS | 1664 | __end_tp_report 09:38:57 INFO - PROCESS | 1664 | __start_cc_report 09:38:57 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2053 09:38:57 INFO - PROCESS | 1664 | __end_cc_report 09:38:57 INFO - PROCESS | 1664 | __startTimestamp1467736737213__endTimestamp 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | ------- Summary: start ------- 09:38:57 INFO - PROCESS | 1664 | Number of tests: 14 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.00 stddev:3.49 (3.1%) stddev-sans-first:0.60 09:38:57 INFO - PROCESS | 1664 | Values: 101.0 111.0 111.0 112.0 113.0 112.0 112.0 112.0 112.0 112.0 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:121.20 Median:131.00 stddev:20.68 (15.8%) stddev-sans-first:20.37 09:38:57 INFO - PROCESS | 1664 | Values: 143.0 106.0 107.0 161.0 108.0 111.0 127.0 122.0 135.0 92.0 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.58 (0.9%) stddev-sans-first:1.66 09:38:57 INFO - PROCESS | 1664 | Values: 184.0 184.0 185.0 183.0 183.0 183.0 180.0 185.0 185.0 182.0 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:68.10 Median:69.50 stddev:11.20 (16.1%) stddev-sans-first:11.16 09:38:57 INFO - PROCESS | 1664 | Values: 79.0 73.0 57.0 86.0 60.0 56.0 65.0 66.0 82.0 57.0 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:80.20 Median:86.00 stddev:12.80 (14.9%) stddev-sans-first:13.58 09:38:57 INFO - PROCESS | 1664 | Values: 81.0 91.0 92.0 70.0 69.0 68.0 96.0 69.0 68.0 98.0 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.50 stddev:2.67 (3.0%) stddev-sans-first:2.54 09:38:57 INFO - PROCESS | 1664 | Values: 91.0 91.0 89.0 86.0 88.0 90.0 87.0 85.0 86.0 83.0 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:103.10 Median:93.00 stddev:19.36 (20.8%) stddev-sans-first:20.19 09:38:57 INFO - PROCESS | 1664 | Values: 93.0 128.0 148.0 92.0 93.0 93.0 93.0 93.0 93.0 105.0 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:84.50 stddev:1.79 (2.1%) stddev-sans-first:1.87 09:38:57 INFO - PROCESS | 1664 | Values: 83.0 85.0 84.0 83.0 84.0 83.0 80.0 86.0 85.0 86.0 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.18 (2.1%) stddev-sans-first:1.12 09:38:57 INFO - PROCESS | 1664 | Values: 53.0 55.0 55.0 57.0 55.0 55.0 53.0 54.0 54.0 54.0 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:70.50 stddev:3.98 (5.6%) stddev-sans-first:3.69 09:38:57 INFO - PROCESS | 1664 | Values: 65.0 70.0 80.0 73.0 70.0 71.0 68.0 69.0 68.0 71.0 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:84.00 stddev:7.92 (9.4%) stddev-sans-first:8.12 09:38:57 INFO - PROCESS | 1664 | Values: 84.0 83.0 84.0 82.0 85.0 93.0 100.0 100.0 101.0 85.0 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:137.50 stddev:36.38 (26.5%) stddev-sans-first:38.47 09:38:57 INFO - PROCESS | 1664 | Values: 138.0 135.0 136.0 133.0 137.0 117.0 119.0 143.0 244.0 160.0 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.20 Median:164.50 stddev:18.29 (11.1%) stddev-sans-first:19.26 09:38:57 INFO - PROCESS | 1664 | Values: 165.0 161.0 219.0 186.0 162.0 163.0 166.0 163.0 164.0 163.0 09:38:57 INFO - PROCESS | 1664 | 09:38:57 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:1.10 (1.7%) stddev-sans-first:1.17 09:38:57 INFO - PROCESS | 1664 | Values: 66.0 68.0 66.0 65.0 65.0 66.0 65.0 66.0 66.0 68.0 09:38:57 INFO - PROCESS | 1664 | -------- Summary: end -------- 09:38:57 INFO - PROCESS | 1664 | 09:38:58 INFO - TEST-INFO | 1664: exit 0 09:38:58 INFO - TEST-OK | kraken | took 58835ms 09:38:58 INFO - SUITE-END | took 1175s 09:38:58 INFO - Completed test suite (00:19:35) 09:38:58 INFO - javascript benchmark 09:38:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3321.186309354208, 3339.749518786092, 3350.648181940896, 3355.647656996017, 3405.7786243894143, 5556.0, 5597.412271711472, 5648.152777083439, 5712.800051998186, 5716.8856622867515, 5592.552595792337, 5640.971795141018, 5720.0561907285355, 5736.885262294752, 5776.797812076578, 5034.395872495305, 5069.214271787879, 5126.769295381698, 5128.128218202895, 5210.296609957645, 5241.764120614563, 5248.5276325130735, 5250.579953603722, 5266.605004624649, 5290.206469029637, 5038.244263360489, 5066.69599824008, 5094.286799848024, 5138.434772175057, 5164.0, 5087.95696882142, 5112.642115051948, 5116.795328186868, 5192.1173400522, 5233.476652334774, 5108.837190738249, 5143.974280128613, 5146.922796158061, 5197.29836472079, 5206.9846379955925, 5567.8608034799045, 5612.214289999403, 5705.77176912923, 5793.7392817323325, 5802.47777700009, 6055.005762886994, 6082.665453400061, 6113.358097399753, 6136.0, 6157.2919114301985, 6170.167027451282, 6237.656928868911, 6270.65511396873, 6274.529410294246, 6278.937210627881, 5585.860353491195, 5664.980303545319, 5708.143778433186, 5756.913646295351, 5771.047777116771, 6263.060540918807, 6330.556861019776, 6332.1135041094285, 6358.586691865014, 6361.045843123476, 6097.695115244309, 6103.95517501633, 6104.084387341934, 6116.662098522342, 6160.691965401712, 6099.400579572073, 6191.938080619227, 6235.438810507076, 6274.403931626488, 6301.117843501914, 6160.661163636043, 6176.258848938156, 6247.812565623039, 6265.592736472206, 6270.9372906271265, 6222.533310001768, 6276.497880169575, 6279.623222606659, 6297.937020629735, 6303.180586523814, 4831.251156070814, 4848.903021939542, 4866.245731911559, 4949.282354058641, 4961.82633607822, 4455.1980643484485, 4455.532169122261, 4490.191765482245, 4494.662900282492, 4503.279475283939, 5078.136716758087, 5642.830715078554, 5726.341470730919, 5777.422257774189, 5823.912641310384, 6296.874062518724, 6329.240491141092, 6346.270178929482, 6359.745610175633, 6367.3314301997625, 5165.863510027788, 5775.5668324875805, 5775.913361299584, 5784.3926387729525, 5788.131780232999, 5313.734313284321, 5362.973185134049, 5392.083345831218, 5410.729463526808, 5434.239206511092, 6304.747810087637, 6307.558470907176, 6338.366163383625, 6363.61818290904, 6367.713452894538, 6284.08880712285, 6318.684065796784, 6335.4931605472375, 6356.904646430215, 6370.1718776558755, 6258.593191442542, 6278.686065696788, 6303.6532990685955, 6361.586496877688, 6374.681265936591, 6174.012158054869, 6194.194754681863, 6199.690015499117, 6206.782762603287, 6208.782692605737, 5141.665791723526, 6027.6986150693165, 6248.593841400295, 6289.716962736779, 6320.4311611955145, 5841.357450680337, 5922.1412895130825, 5969.224000879858, 6003.939960600513, 6014.338422773404, 5961.0, 5992.610480318765, 6009.399060093956, 6009.969950150221, 6012.6993650316435, 4810.494898035656, 5749.195112684144, 5835.299764028344, 5843.561732869966, 5865.354810970875, 6041.003234466264, 6125.449118208347, 6128.717018009349, 6132.875646117402, 6144.341027922525, 5934.258217722785, 5938.792142275, 5948.196993405835, 5960.821175364746, 5980.551458640706], "value": 5672.317744663975, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.1807663703476, 486.6447493329869, 522.3027258609756, 544.7004147718759, 563.1355868741481, 25803.870980645093, 42877.35683964739, 44254.55745442553, 44424.999999999956, 46727.29909051367, 45594.77202613982, 46042.07915841681, 46065.7696711516, 46077.539224607746, 46306.30540541895, 8815.074417186199, 9763.853542196855, 9941.701748947507, 9951.154151897097, 9963.103320701135, 8824.117588241157, 11945.402729863516, 12180.269183848997, 12196.939015304912, 12442.440090195963, 12064.879351206508, 12138.635840924786, 12146.028317734605, 12147.635570932842, 12151.210171338878, 8813.471191728453, 11846.993005594535, 11937.46281417334, 12115.0, 12161.878381216207, 12012.519499220021, 12098.81851772224, 12101.878981210164, 12103.031757459383, 12105.757884842342, 42904.570954290684, 43152.921176970514, 43191.0, 43204.78397608023, 43290.567094328966, 3098.442280389529, 3484.407650699387, 3555.964440355589, 3694.7967861767593, 3794.9620503794886, 32545.860894868565, 32909.0, 33147.0, 33904.83047584771, 34187.82906085478, 3306.553615261957, 3312.2381852174135, 3342.699157075875, 3346.4478361070396, 3366.4277072897667, 539078.0, 542070.0, 542898.0, 543989.0, 544246.0, 35329.94010179699, 35433.46849797255, 65581.68836623241, 66859.0, 66905.0, 30055.0, 36178.0, 36291.91124266276, 36332.455013174826, 36399.63600363962, 14949.252537373088, 15081.773773393408, 15097.094174349575, 15107.0, 15112.09327440357, 11645.88354116448, 11795.587154449553, 11831.40842957866, 11964.700882478008, 12750.744985100246, 7849.803754906059, 7933.0, 7944.364450843918, 8154.2253485918745, 8238.09380968093, 3271.8854840080485, 3288.177955511122, 3293.884714034997, 3296.9175770605448, 3323.6344002159744, 932.6455946740331, 948.5020364308655, 977.9608815647294, 1069.4599227390117, 1074.6453670288786, 31580.842095789372, 31849.8407507961, 31857.0, 32225.0, 32620.0, 1089.536946797608, 1097.8188598881095, 1120.394986707169, 1129.6723950054557, 1155.3241353807948, 14983.250837458083, 16617.836751427283, 16697.49907502777, 16708.832911670972, 17037.318507259817, 707059.0, 714116.0, 715966.0, 716003.0, 716819.0, 64092.679536602016, 64349.74775882821, 64508.67745661335, 64654.0, 64699.67650161719, 1273.2487832179058, 1383.4120498787975, 1416.3838730152418, 1439.6904665496759, 1443.6607397261425, 32515.837420812746, 32612.51081233736, 32965.67034329626, 33353.059062811815, 33396.83301583477, 53711.46288537221, 54510.274486274735, 54563.45436545742, 54593.727031364586, 54720.72639636776, 503552.48223758646, 513036.0, 514423.0, 515151.0, 516200.0, 23961.921713523283, 24245.272641820775, 27746.0, 28118.578221327105, 28254.0, 49651.50348496613, 50875.74562127166, 51697.7415112922, 51749.0, 51840.74079629729, 39149.0, 41634.79182604067, 41969.79015105027, 42425.5757442434, 43251.567484324754, 39449.80275098606, 41528.456420724266, 42199.40476190476, 42619.934501637705, 42676.93307667333, 39569.80215098906, 39784.204315914096, 40604.78185654435, 40665.59334406618, 40733.38899916445, 35749.1062723434, 35844.64155358517, 36387.81806090953, 36615.63384366122, 36744.63255367412, 24841.875790620932, 25004.499910001334, 26119.347016324744, 26585.601215981387, 26957.595636065867], "value": 19784.79319598264, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.4888875576589, 368.4399712437094, 390.6815945004821, 403.24995508354453, 418.74038096380247, 15524.22378881057, 15789.447369342075, 18059.819401805977, 20116.994150292463, 22429.887850560764, 17322.913385433054, 21138.788612113873, 21553.56892862146, 22034.788086655226, 22088.88955555224, 5931.6441013539325, 6117.082437634346, 6181.567290289681, 6182.598131121463, 6190.50475961922, 3576.874809381672, 4401.339799030139, 6157.599756015867, 6812.62530560819, 6822.3859852613505, 6808.8978665320055, 6812.965935170318, 6829.419499342562, 6839.418649414805, 6862.107925969617, 6457.225132984048, 6737.191537015565, 6737.797866064026, 6741.191057073158, 6744.966275168593, 3528.788272703646, 3900.64894159527, 3987.063040185554, 4820.3492528508905, 5815.534757219412, 26388.736112639013, 26472.0, 26712.59931101035, 26731.465370692673, 26814.195574132806, 2248.584011957793, 2516.5344411283972, 2518.445941892781, 3015.6531998820196, 3077.707617776308, 13287.667808304774, 14851.405943762236, 15860.0, 16109.436169734003, 16569.0, 1825.4432398118568, 1868.7664041994751, 1872.7038029987118, 1872.8501719862516, 1905.4664693885734, 108745.0, 110492.0, 111068.88931110747, 112528.0, 112551.43724281408, 8939.73180804577, 10662.253642245123, 10666.839997400046, 11127.554897804159, 11238.438078096062, 12848.807267890988, 12990.805137922938, 13059.21644701321, 13703.794443083361, 13714.0, 8498.66005359791, 8715.607797649121, 8727.781805454915, 8789.956050219707, 8821.69124080667, 7778.844423111506, 8070.354371650371, 8781.736547903573, 8876.334274929406, 8921.0, 6750.864982700318, 6826.863462730717, 6867.862642747217, 6876.931230687629, 6921.826954326081, 1842.240660169535, 1876.2682553804027, 1879.5301174706324, 1886.0852486544015, 1886.6226754649126, 876.8903887014123, 913.6614859231258, 965.1554889471712, 1007.3603261928581, 1051.0593019247883, 20550.794492055185, 21815.781842181692, 23218.651720223872, 23227.419314517272, 23233.30300091, 1005.5324274212526, 1007.1992765751245, 1065.2064212161988, 1076.3380520979542, 1089.54239219528, 11977.221480603734, 12027.0, 12220.022398208122, 12296.954758845419, 12361.258324500559, 598748.0, 603725.0, 608594.0, 614874.0, 623205.0, 100053.49973250087, 115194.0, 115954.4202279, 116409.41795290969, 116982.0, 1006.6879267427121, 1018.4602160854907, 1029.3360782295379, 1035.7151783259512, 1036.9014943580348, 19949.401517954484, 20040.298589549297, 20197.0, 20225.59548809045, 20297.188112475334, 48498.78753031202, 49961.0, 50108.49891501039, 50190.498095018585, 50248.497515024384, 66787.66606166938, 67750.0, 67980.64038719297, 68668.6566567164, 69559.65220173867, 8084.39367047462, 8204.097549269694, 8245.257926786619, 8283.50298982063, 8511.616977236163, 12367.257964522158, 12775.361231938552, 12913.160644558073, 13281.203127812361, 13372.0, 11398.886011140114, 11503.079753619522, 11524.711882203012, 11551.884481155417, 11608.593699220486, 10098.0, 10338.0, 11571.421428928688, 11573.0, 11783.646490605295, 10331.915148909256, 11646.010089142517, 11674.883251167379, 11723.882761172621, 11928.522859085539, 7212.4951253411755, 8241.50550966944, 8299.336053115616, 8331.208535189147, 8530.402871799162, 8201.589920503831, 10768.246222764332, 11341.092712583139, 11383.658490245305, 11613.954744073075], "value": 9831.98654405562, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.076264284251, 1523.7257293687135, 1575.8424157584238, 1764.426561367556, 1910.1786231920278, 7817.530948143109, 8282.62728177232, 8285.627146778386, 8291.95854020729, 8292.79268018299, 8389.0, 8649.740507784762, 8654.048054713992, 8784.95607521963, 8785.648574057046, 2302.896369663365, 2381.7499162587937, 2384.6899903012586, 2386.427257458206, 2395.892184851677, 2340.32130682102, 2363.869987150706, 2382.6783384243076, 2386.53462574798, 2387.689600351952, 2338.7193536775612, 2367.479154585997, 2373.9762602373926, 2378.1795280628157, 2379.8572085674828, 2330.3940975346363, 2352.6118190498546, 2363.7518060603647, 2363.881805909715, 2371.2886134159858, 2295.104909085458, 2332.183735692511, 2335.2643917166038, 2338.824588155895, 2343.5430091132234, 7521.962390188069, 8770.517621530813, 8802.163794439582, 8855.158759917798, 8862.11378862115, 5178.0, 5213.2701421800975, 5390.811321603725, 5394.973025134849, 5398.811041613564, 3108.362785628941, 3602.6217247189193, 3641.963580364189, 3727.683146932514, 3737.6075512071384, 1077.299755159145, 1088.88022317545, 1095.5289225632976, 1110.6334909479851, 1117.0114448712948, 11506.0, 11628.825567616492, 11655.76688466226, 11670.824937625943, 11671.824922626167, 4434.423524941737, 4502.144592527409, 4523.5024147343765, 4606.677532572755, 4613.76931153441, 9279.211267042241, 9279.628814847465, 9287.58205880737, 9294.024127466653, 9298.581563829644, 5247.081760691864, 5248.763805628756, 5261.657992230493, 5264.657797243167, 5265.7103859287345, 4215.473065866767, 4382.101669157815, 4525.773711314421, 4864.051509955525, 4934.827281045146, 3686.318031164243, 3709.684676802448, 3734.5518537775097, 3756.7039371416818, 3761.247750449921, 1144.7939370913316, 1160.5589875847293, 1162.0587324267372, 1178.0752109594098, 1179.204037274836, 907.3739120007306, 929.9907000929904, 931.7065124485765, 941.3551717073826, 996.9003099689972, 5879.823605291848, 5923.078451238706, 6051.9697401513595, 6082.878342433215, 6165.229346331708, 399.3390937997619, 419.26419134419507, 429.83236537750304, 445.5277405949704, 462.4034994856597, 4021.3967904814513, 4077.959220407758, 4105.85629502966, 4169.687273454503, 4169.749815011109, 10258.58965641381, 10789.460526973777, 10810.513526891309, 10823.621173258898, 10867.076298514714, 10737.248392612599, 10778.78442431147, 10805.513751881184, 10820.296680715728, 10832.18758593093, 6379.681015949184, 8083.959580202061, 8207.95896020516, 8342.207490288412, 8351.8329633407, 5404.891997140656, 6003.729832157476, 6115.938840611715, 6130.785422510191, 6190.442860142609, 9604.231661467205, 10003.449810260505, 10008.199344052604, 10206.693799186036, 10215.0, 9803.509824508603, 10194.388336699822, 10306.0, 10318.380897146195, 10344.431056291976, 7013.263607321158, 7223.205447400676, 7251.492395532262, 7441.851162976818, 7596.658150383136, 8404.747857564284, 8481.194286542768, 8516.70191543293, 8595.570221488775, 8621.008584012669, 7556.4710470266555, 7679.500832445873, 7704.614769261627, 7820.0, 7822.569758663328, 7479.962600186965, 7505.6997720090585, 7522.623868806647, 7683.9231607683205, 7701.88447173304, 6348.333424990309, 6525.934740652722, 7023.473239506955, 7060.752873649398, 7113.288671133052, 4905.975470122627, 5028.572571331478, 5106.157484015096, 5110.3356563646485, 5118.872028199325, 5067.949320506896, 5093.134167191661, 5093.3193790336945, 5122.795088196431, 5232.3982741985155], "value": 4479.140706284932, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.0866489523458, 311.69298241232394, 322.59997602972317, 324.7142514587163, 327.6003276003275, 4938.851834444972, 5237.214417837326, 5300.787968481256, 5338.3860856001575, 5352.892942141159, 4889.408613434876, 5019.824306149286, 5028.899422011557, 5185.274061631375, 5294.973525132378, 3368.3263347330508, 4056.533498647649, 4078.6940979426513, 4080.755154690728, 4127.649149822269, 4071.5114186297687, 4076.388541718751, 4097.221527909687, 4103.281925663012, 4114.012636967122, 3511.94732079019, 3613.602503724588, 3655.817209139532, 3835.578086410507, 4086.468759061318, 4021.91408319752, 4039.454673619053, 4060.20825938942, 4065.5731148229306, 4067.1458993611377, 3178.666240044785, 3333.916652083693, 3546.386394190637, 3557.4130268505664, 3562.2697347043795, 4477.865664030084, 4867.36724225852, 5208.374995000624, 5209.7916083356995, 5229.790808367661, 4057.97971010146, 4616.53834616539, 4683.0, 4687.1328804171335, 4713.787879545429, 5480.91778623321, 5499.4225606311165, 5509.531689806371, 5568.721563921828, 5579.497845193911, 2874.7412732853936, 3895.182011777502, 3905.3360928641914, 3919.2945269851416, 3924.823382947774, 4818.469968303519, 5095.044873973086, 5891.381404952461, 5897.174395584621, 5928.318243402021, 3878.166194268246, 3882.3400021996326, 3887.7667339959694, 3919.922021444069, 3982.6017398260524, 3979.3434083376496, 3980.5820388859333, 3997.3004724173734, 3997.340438827561, 3999.9400008999887, 3850.5956874528333, 4083.0, 4348.782560871944, 4495.0, 4575.9084818303445, 5329.467053294717, 5567.858588989247, 5602.831915042555, 5608.383077861432, 5633.521150702236, 1060.3054998975683, 1316.9341532923315, 1341.161773891318, 1347.7910923806826, 1347.8382594088575, 774.5855967057597, 880.445319448756, 895.7760559860035, 901.2339511415375, 909.6816114360054, 4831.492693267156, 5200.503758196087, 5203.73981300941, 5240.4365031778, 5266.210068489757, 5726.3700992890745, 5749.9712501437225, 5760.596758226884, 5776.306843178845, 5811.0, 4757.738324392122, 4933.703977761345, 4970.329005584272, 5125.128728116155, 5241.816536421207, 2438.9878050609987, 2882.256234977808, 3004.864781084813, 3007.624046994126, 3018.4566777980176, 5494.846082322757, 5650.519705824969, 5894.6463212206545, 5962.22491076166, 5969.0, 5792.4497172768515, 5801.6809075500405, 5816.447437493432, 5847.976604094267, 5929.065112769207, 4909.926351104737, 5102.85185833192, 5593.972030139823, 5897.76408943646, 5992.700364981733, 4677.694812931811, 5585.525230355465, 5663.32040155184, 5683.2950114964515, 5710.914336284876, 5530.972345138249, 5589.329280486368, 5591.0, 5601.29983752031, 5663.915041274302, 5108.259302401206, 5942.970285148546, 5950.03648795905, 5971.0744834550715, 5992.400759923973, 3956.5054368203973, 4000.8399664013114, 4003.939940900947, 4021.879343619696, 4035.878923632296, 5028.321176641107, 5760.654360738369, 5814.825555233349, 5827.446392592697, 5860.824175274749, 4569.702969306984, 5471.905618876321, 5679.517241034393, 5693.605066758671, 5703.287089113861, 4650.604698600521, 5473.397926228191, 5585.832425027255, 5616.466435688603, 5618.213450116905, 4639.559241872017, 5230.803062713593, 5306.938612277637, 5515.503604675599, 5516.420775818481, 4416.22716024688, 4639.30410438437, 5380.973095134499, 5381.919271210857, 5670.461306175906, 4633.884152896205, 5386.7575959082715, 5406.864828379322, 5557.083081291542, 5603.075492543685], "value": 4085.0890880006905, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.2517482517483, 752.3116348541085, 752.4995877741299, 761.6686741267549, 886.960086796094, 15100.924495377521, 15779.684406311895, 16377.590560235985, 16435.178241087957, 16555.586110347216, 14872.925635371836, 14905.0, 15088.47190348338, 15156.317965691544, 15187.316570754316, 728.3226599262683, 759.5442734359368, 895.520896320467, 900.558726224152, 930.5347326336831, 920.1074957291451, 927.8608208768705, 930.585889279271, 931.0131260863507, 931.2643012020529, 984.6110786239431, 987.4371608183338, 988.4365911430488, 990.697837159666, 991.1476130527741, 761.0828951113922, 944.6504793226482, 977.2426369563574, 979.9510024498746, 981.1856159387692, 732.4140687450018, 733.5232099135551, 734.3244215321863, 734.5776178697271, 734.7905846833627, 14483.927580362137, 14510.70978580433, 14515.274236288144, 14540.70918581633, 14588.416463341453, 14310.141391516543, 14361.497347592784, 14409.927950360181, 14411.135331880121, 14571.271436428136, 12153.817692734616, 12400.317982510958, 12597.99216062722, 12598.0, 12739.299338536377, 3809.695224382043, 4532.138893610204, 4685.695429797052, 4737.786799594027, 4795.544423279783, 102004.48997755037, 105618.0, 117426.0, 117699.41150294279, 118065.81934180635, 11647.242929209575, 11764.059753954278, 11852.822207666892, 11953.940230298793, 12031.578894738643, 22628.868556572434, 22890.65664015041, 22900.0, 22914.770852291596, 22917.656235156486, 8107.594620268963, 8359.8328033439, 8463.195996380335, 8479.830403392021, 8599.312055035582, 4529.252459277547, 4601.102784956901, 4611.1930412177735, 4635.443746750411, 4864.951350486521, 3702.018964974284, 3805.6765174960437, 3806.847726090981, 3980.0, 4218.599233072853, 617.450469082517, 663.9900401493982, 754.4907187648315, 759.51770625652, 768.0821418405064, 599.4275466929072, 620.3238470067648, 626.9592476489014, 702.3994484715597, 712.1667648850857, 8585.570721464026, 9350.298727595458, 9645.90354096464, 9851.267718107378, 10102.781286375877, 675.5203805298294, 680.086104468779, 690.6753825701912, 695.4505940307201, 722.479814533535, 4666.626669866402, 4770.570648641569, 4838.153323168502, 4849.781759820817, 4853.417589889165, 158338.0, 159451.20274398554, 167590.0, 170161.0, 172051.0, 81617.0, 86129.0, 133364.33317833542, 135131.32434337767, 136636.31681841527, 123499.0, 125947.3702631481, 126324.36837815936, 127298.36350818186, 128461.35769321094, 9156.221721153777, 9176.862347064933, 9212.907870921204, 9378.67174648884, 9410.153086222273, 59308.0, 65718.01422978754, 66266.0, 67068.32931670621, 67348.58142732158, 61039.389606103374, 94110.52944735232, 94568.52715736377, 97009.0, 98124.01875981149, 12250.571230006906, 12298.0, 12435.0, 12635.368231588567, 12923.806142907675, 13176.143550669176, 13320.267385293902, 13345.0, 13345.599632011055, 13528.323583820573, 12500.562480313145, 12541.811872821732, 12549.560765373168, 12810.167339122927, 12994.285314307805, 12415.93792031034, 12573.0, 12588.874111259136, 12626.747465050832, 12661.177023493443, 12281.324527150735, 12311.753764924471, 12339.13626046168, 12353.38233088324, 12376.62870113898, 10306.224551461632, 10577.31247468912, 10716.57133714674, 10742.0, 10912.781744365227, 8533.445326053787, 10347.793044138925, 10431.634892778717, 10742.624008159677, 10760.946195268974], "value": 8027.776677329153, "unit": "score"}], "name": "dromaeo_css", "value": 7384.062019868304, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 111.0, 112.0, 113.0, 112.0, 112.0, 112.0, 112.0, 112.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 106.0, 107.0, 161.0, 108.0, 111.0, 127.0, 122.0, 135.0, 92.0], "value": 121.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 185.0, 183.0, 183.0, 183.0, 180.0, 185.0, 185.0, 182.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 57.0, 86.0, 60.0, 56.0, 65.0, 66.0, 82.0, 57.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 91.0, 92.0, 70.0, 69.0, 68.0, 96.0, 69.0, 68.0, 98.0], "value": 80.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 89.0, 86.0, 88.0, 90.0, 87.0, 85.0, 86.0, 83.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 128.0, 148.0, 92.0, 93.0, 93.0, 93.0, 93.0, 93.0, 105.0], "value": 103.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 84.0, 83.0, 84.0, 83.0, 80.0, 86.0, 85.0, 86.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 55.0, 57.0, 55.0, 55.0, 53.0, 54.0, 54.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 80.0, 73.0, 70.0, 71.0, 68.0, 69.0, 68.0, 71.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 82.0, 85.0, 93.0, 100.0, 100.0, 101.0, 85.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 136.0, 133.0, 137.0, 117.0, 119.0, 143.0, 244.0, 160.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 161.0, 219.0, 186.0, 162.0, 163.0, 166.0, 163.0, 164.0, 163.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 65.0, 65.0, 66.0, 65.0, 66.0, 66.0, 68.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1436.5, "alertThreshold": 2.0}]} 09:38:58 INFO - Return code: 0 09:38:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:38:58 INFO - # TBPL SUCCESS # 09:38:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:58 INFO - 2016-07-05 09:38:58.544 screenresolution[1668:9862] starting screenresolution argv=screenresolution get 09:38:58 INFO - 2016-07-05 09:38:58.547 screenresolution[1668:9862] Display 0: 1600x1200x32@60 09:38:58 INFO - 2016-07-05 09:38:58.553 screenresolution[1669:9865] starting screenresolution argv=screenresolution list 09:38:58 INFO - Available Modes on Display 0 09:38:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:58 INFO - Intel Iris: 09:38:58 INFO - Chipset Model: Intel Iris 09:38:58 INFO - Type: GPU 09:38:58 INFO - Bus: Built-In 09:38:58 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:58 INFO - Vendor: Intel (0x8086) 09:38:58 INFO - Device ID: 0x0a2e 09:38:58 INFO - Revision ID: 0x0009 09:38:58 INFO - Displays: 09:38:58 INFO - Display: 09:38:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:58 INFO - Display Serial Number: 1600x1200 60 09:38:58 INFO - Main Display: Yes 09:38:58 INFO - Mirror: Off 09:38:58 INFO - Online: Yes 09:38:58 INFO - Rotation: Supported 09:38:58 INFO - Return code: 0 09:38:58 INFO - Running post-action listener: _resource_record_post_action 09:38:58 INFO - ##### 09:38:58 INFO - ##### Finished run-tests step (success) 09:38:58 INFO - ##### 09:38:58 INFO - Running post-run listener: _resource_record_post_run 09:38:58 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37660160; Write bytes: 706093056; Read time: 382; Write time: 2251 09:38:58 INFO - TinderboxPrint: CPU usage
53.4% 09:38:58 INFO - TinderboxPrint: I/O read bytes / time
37,660,160 / 382 09:38:58 INFO - TinderboxPrint: I/O write bytes / time
706,093,056 / 2,251 09:38:58 INFO - TinderboxPrint: CPU idle
2,258.7 (46.6%) 09:38:58 INFO - TinderboxPrint: CPU system
963.5 (19.9%) 09:38:58 INFO - TinderboxPrint: CPU user
1,623.4 (33.5%) 09:38:58 INFO - TinderboxPrint: Swap in / out
444,932,096 / 0 09:38:58 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 395506176; Write bytes: 384931328; Read time: 29176; Write time: 834 09:38:58 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 31526400; Write bytes: 295840256; Read time: 295; Write time: 1363 09:38:59 INFO - Running post-run listener: _upload_blobber_files 09:38:59 INFO - Blob upload gear active. 09:38:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:38:59 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 09:38:59 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:38:59 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:38:59 INFO - (blobuploader) - INFO - Open directory for files ... 09:38:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:38:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:39:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:01 INFO - (blobuploader) - INFO - Done attempting. 09:39:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:39:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:39:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:02 INFO - (blobuploader) - INFO - Done attempting. 09:39:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:39:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:39:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:04 INFO - (blobuploader) - INFO - Done attempting. 09:39:04 INFO - (blobuploader) - INFO - Iteration through files over. 09:39:04 INFO - Return code: 0 09:39:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:39:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:39:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ee138911e5bc1e2a7e9a044968ad4301d9b7824ac258fbcfd823b0cf9d62bcd8d43710e715fef94ad04ec33d338e103dbf9b2b770bb9a48a2fd5ca1abc13222b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cb8fe7db46f6e33e2d09fbd6291d85b1b529f5aff47cc57a805d0552dc3de10b84e28ad7632bada9d1c1f88e82064721038465cb15fcdff1ac4c50ac1b29f6a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c5f38fe5aa57f588f486292a376d53968e4dfaab3865b5d32074b526d278862095c14594b4bc89cbe27610c474d24ee94bba2aaa064908c75f5452eba73e50ba"} 09:39:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:39:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:39:04 INFO - Contents: 09:39:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ee138911e5bc1e2a7e9a044968ad4301d9b7824ac258fbcfd823b0cf9d62bcd8d43710e715fef94ad04ec33d338e103dbf9b2b770bb9a48a2fd5ca1abc13222b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cb8fe7db46f6e33e2d09fbd6291d85b1b529f5aff47cc57a805d0552dc3de10b84e28ad7632bada9d1c1f88e82064721038465cb15fcdff1ac4c50ac1b29f6a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c5f38fe5aa57f588f486292a376d53968e4dfaab3865b5d32074b526d278862095c14594b4bc89cbe27610c474d24ee94bba2aaa064908c75f5452eba73e50ba"} 09:39:04 INFO - Running post-run listener: copy_logs_to_upload_dir 09:39:04 INFO - Copying logs to upload dir... 09:39:04 INFO - mkdir: /builds/slave/test/build/upload/logs 09:39:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.025586 ========= master_lag: 11.70 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-07-05 09:39:16.700674) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 09:39:16.706733) ========= 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.hbN5XXt7Wb/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.etfc88C5mo/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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/Autoland/sha512/ee138911e5bc1e2a7e9a044968ad4301d9b7824ac258fbcfd823b0cf9d62bcd8d43710e715fef94ad04ec33d338e103dbf9b2b770bb9a48a2fd5ca1abc13222b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cb8fe7db46f6e33e2d09fbd6291d85b1b529f5aff47cc57a805d0552dc3de10b84e28ad7632bada9d1c1f88e82064721038465cb15fcdff1ac4c50ac1b29f6a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c5f38fe5aa57f588f486292a376d53968e4dfaab3865b5d32074b526d278862095c14594b4bc89cbe27610c474d24ee94bba2aaa064908c75f5452eba73e50ba"} build_url:https://queue.taskcluster.net/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011325 build_url: 'https://queue.taskcluster.net/v1/task/Z_Nt6300SN6_9LAmGgOuag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ee138911e5bc1e2a7e9a044968ad4301d9b7824ac258fbcfd823b0cf9d62bcd8d43710e715fef94ad04ec33d338e103dbf9b2b770bb9a48a2fd5ca1abc13222b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cb8fe7db46f6e33e2d09fbd6291d85b1b529f5aff47cc57a805d0552dc3de10b84e28ad7632bada9d1c1f88e82064721038465cb15fcdff1ac4c50ac1b29f6a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c5f38fe5aa57f588f486292a376d53968e4dfaab3865b5d32074b526d278862095c14594b4bc89cbe27610c474d24ee94bba2aaa064908c75f5452eba73e50ba"}' ========= master_lag: 0.11 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 09:39:16.831560) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:39:16.831888) ========= 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.hbN5XXt7Wb/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.etfc88C5mo/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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.005091 ========= master_lag: 0.11 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:39:16.944028) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 09:39:16.944351) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 09:39:16.945517) ========= ========= Total master_lag: 15.63 =========