builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0034 starttime: 1467139503.5 results: success (0) buildid: 20160628091623 builduid: bad89d10b3d449fbb95a01a45dc1f0ce revision: 101de7dd28aa446f0c23cc701647d6399ed7490b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 11:45:03.504352) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 11:45:03.504904) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 11:45:03.505186) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 11:45:03.888481) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 11:45:03.888847) ========= 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.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-06-28 11:45:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.96M=0.001s 2016-06-28 11:45:04 (7.96 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.464932 ========= master_lag: 0.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 11:45:04.546541) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 11:45:04.546858) ========= 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.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.021068 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 11:45:04.593788) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 11:45:04.594172) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 101de7dd28aa446f0c23cc701647d6399ed7490b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 101de7dd28aa446f0c23cc701647d6399ed7490b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-06-28 11:45:04,674 truncating revision to first 12 chars 2016-06-28 11:45:04,674 Setting DEBUG logging. 2016-06-28 11:45:04,674 attempt 1/10 2016-06-28 11:45:04,674 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/101de7dd28aa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-28 11:45:05,335 unpacking tar archive at: fx-team-101de7dd28aa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.944014 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 11:45:05.562189) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 11:45:05.562501) ========= script_repo_revision: 101de7dd28aa446f0c23cc701647d6399ed7490b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 11:45:05.562916) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 11:45:05.563178) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 11:45:05.582012) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 14 secs) (at 2016-06-28 11:45:05.582280) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 11:45:05 INFO - MultiFileLogger online at 20160628 11:45:05 in /builds/slave/test 11:45:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:45:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:45:05 INFO - {'append_to_log': False, 11:45:05 INFO - 'base_work_dir': '/builds/slave/test', 11:45:05 INFO - 'blob_upload_branch': 'Fx-Team', 11:45:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:45:05 INFO - 'branch': 'Fx-Team', 11:45:05 INFO - 'buildbot_json_path': 'buildprops.json', 11:45:05 INFO - 'config_files': ('talos/mac_config.py',), 11:45:05 INFO - 'default_actions': ('clobber', 11:45:05 INFO - 'read-buildbot-config', 11:45:05 INFO - 'download-and-extract', 11:45:05 INFO - 'populate-webroot', 11:45:05 INFO - 'create-virtualenv', 11:45:05 INFO - 'install', 11:45:05 INFO - 'run-tests'), 11:45:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:45:05 INFO - 'download_minidump_stackwalk': True, 11:45:05 INFO - 'download_symbols': 'ondemand', 11:45:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:45:05 INFO - 'tooltool.py': '/tools/tooltool.py', 11:45:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:45:05 INFO - '/tools/misc-python/virtualenv.py')}, 11:45:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:45:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:45:05 INFO - 'installer_path': 'installer.exe', 11:45:05 INFO - 'log_level': 'info', 11:45:05 INFO - 'log_name': 'talos', 11:45:05 INFO - 'log_to_console': True, 11:45:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:45:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:45:05 INFO - 'opt_config_files': (), 11:45:05 INFO - 'pip_index': False, 11:45:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:45:05 INFO - 'cmd': ('bash', 11:45:05 INFO - '-c', 11:45:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:45:05 INFO - 'enabled': True, 11:45:05 INFO - 'halt_on_failure': False, 11:45:05 INFO - 'name': 'check_screen_resolution'},), 11:45:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:45:05 INFO - 'cmd': ('bash', 11:45:05 INFO - '-c', 11:45:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:45:05 INFO - 'enabled': True, 11:45:05 INFO - 'halt_on_failure': False, 11:45:05 INFO - 'name': 'check_screen_resolution'},), 11:45:05 INFO - 'run_cmd_checks_enabled': True, 11:45:05 INFO - 'sps_profile': False, 11:45:05 INFO - 'sps_profile_interval': 0, 11:45:05 INFO - 'suite': 'dromaeojs', 11:45:05 INFO - 'system_bits': '32', 11:45:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:45:05 INFO - 'title': 't-yosemite-r7-0034', 11:45:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:45:05 INFO - 'use_talos_json': True, 11:45:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:45:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:45:05 INFO - 'work_dir': 'build'} 11:45:05 INFO - ##### 11:45:05 INFO - ##### Running clobber step. 11:45:05 INFO - ##### 11:45:05 INFO - Running pre-action listener: _resource_record_pre_action 11:45:05 INFO - Running main action method: clobber 11:45:05 INFO - rmtree: /builds/slave/test/build 11:45:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:45:06 INFO - Running post-action listener: _resource_record_post_action 11:45:06 INFO - ##### 11:45:06 INFO - ##### Finished clobber step (success) 11:45:06 INFO - ##### 11:45:06 INFO - ##### 11:45:06 INFO - ##### Running read-buildbot-config step. 11:45:06 INFO - ##### 11:45:06 INFO - Running pre-action listener: _resource_record_pre_action 11:45:06 INFO - Running main action method: read_buildbot_config 11:45:06 INFO - Using buildbot properties: 11:45:06 INFO - { 11:45:06 INFO - "project": "", 11:45:06 INFO - "product": "firefox", 11:45:06 INFO - "script_repo_revision": "production", 11:45:06 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 11:45:06 INFO - "repository": "", 11:45:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 11:45:06 INFO - "buildid": "20160628091623", 11:45:06 INFO - "pgo_build": "False", 11:45:06 INFO - "basedir": "/builds/slave/test", 11:45:06 INFO - "buildnumber": 59, 11:45:06 INFO - "slavename": "t-yosemite-r7-0034", 11:45:06 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 11:45:06 INFO - "platform": "yosemite_r7", 11:45:06 INFO - "scheduler": "tests-fx-team-macosx64-talos", 11:45:06 INFO - "branch": "fx-team", 11:45:06 INFO - "repo_path": "integration/fx-team", 11:45:06 INFO - "revision": "101de7dd28aa446f0c23cc701647d6399ed7490b", 11:45:06 INFO - "stage_platform": "macosx64", 11:45:06 INFO - "builduid": "bad89d10b3d449fbb95a01a45dc1f0ce", 11:45:06 INFO - "slavebuilddir": "test" 11:45:06 INFO - } 11:45:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 11:45:06 INFO - Running post-action listener: _resource_record_post_action 11:45:06 INFO - ##### 11:45:06 INFO - ##### Finished read-buildbot-config step (success) 11:45:06 INFO - ##### 11:45:06 INFO - ##### 11:45:06 INFO - ##### Running download-and-extract step. 11:45:06 INFO - ##### 11:45:06 INFO - Running pre-action listener: _resource_record_pre_action 11:45:06 INFO - Running main action method: download_and_extract 11:45:06 INFO - mkdir: /builds/slave/test/build/tests 11:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:45:06 INFO - https://queue.taskcluster.net/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:45:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 11:45:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 11:45:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/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 11:45:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/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 11:45:08 INFO - Downloaded 1199 bytes. 11:45:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 11:45:08 INFO - Using the following test package requirements: 11:45:08 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 11:45:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:45:08 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 11:45:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:45:08 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 11:45:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:45:08 INFO - u'jsshell-mac.zip'], 11:45:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:45:08 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 11:45:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 11:45:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:45:08 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 11:45:08 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:45:08 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 11:45:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:45:08 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 11:45:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:45:08 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 11:45:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:45:08 INFO - https://queue.taskcluster.net/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:45:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:45:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:45:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/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 11:45:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/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 11:45:10 INFO - Downloaded 18786896 bytes. 11:45:10 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'] 11:45:10 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 11:45:13 INFO - Return code: 0 11:45:13 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 11:45:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:45:13 INFO - https://queue.taskcluster.net/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:45:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:45:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:45:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/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 11:45:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/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 11:45:27 INFO - Downloaded 18786896 bytes. 11:45:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:45:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:45:30 INFO - Return code: 0 11:45:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:45:30 INFO - https://queue.taskcluster.net/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:45:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:45:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:45:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/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 11:45:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/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 11:45:32 INFO - Downloaded 14405245 bytes. 11:45:32 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'] 11:45:32 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 11:45:32 INFO - Return code: 0 11:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:45:32 INFO - https://queue.taskcluster.net/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:45:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:45:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:45:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 11:45:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/B3rSNSMRRJCv6pbwAi6uJw/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 11:51:23 INFO - Downloaded 112374252 bytes. 11:51:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:51:23 INFO - mkdir: /builds/slave/test/properties 11:51:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:51:23 INFO - Writing to file /builds/slave/test/properties/build_url 11:51:23 INFO - Contents: 11:51:23 INFO - build_url:https://queue.taskcluster.net/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:51:25 INFO - Running post-action listener: _resource_record_post_action 11:51:25 INFO - Running post-action listener: set_extra_try_arguments 11:51:25 INFO - ##### 11:51:25 INFO - ##### Finished download-and-extract step (success) 11:51:25 INFO - ##### 11:51:25 INFO - ##### 11:51:25 INFO - ##### Running populate-webroot step. 11:51:25 INFO - ##### 11:51:25 INFO - Running pre-action listener: _resource_record_pre_action 11:51:25 INFO - Running main action method: populate_webroot 11:51:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:51:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:51:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:51:25 INFO - '--tppagecycles', 11:51:25 INFO - '7'], 11:51:25 INFO - 'tests': ['tsvgm']}}, 11:51:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:51:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:51:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:51:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:51:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:51:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:51:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:25 INFO - 'talos_options': ['--disable-e10s'], 11:51:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:51:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:51:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:25 INFO - 'talos_options': ['--disable-e10s'], 11:51:25 INFO - 'tests': ['damp', 'tps']}, 11:51:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:25 INFO - 'tests': ['damp', 'tps']}, 11:51:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:51:25 INFO - 'tests': ['dromaeo_dom']}, 11:51:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:51:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:51:25 INFO - 'tests': ['basic_compositor_video']}, 11:51:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:51:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:51:25 INFO - 'tests': ['a11yr', 11:51:25 INFO - 'ts_paint', 11:51:25 INFO - 'tpaint', 11:51:25 INFO - 'sessionrestore', 11:51:25 INFO - 'sessionrestore_no_auto_restore', 11:51:25 INFO - 'tabpaint']}, 11:51:25 INFO - 'other-e10s': {'tests': ['a11yr', 11:51:25 INFO - 'ts_paint', 11:51:25 INFO - 'tpaint', 11:51:25 INFO - 'sessionrestore', 11:51:25 INFO - 'sessionrestore_no_auto_restore', 11:51:25 INFO - 'tabpaint']}, 11:51:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:51:25 INFO - 'ts_paint', 11:51:25 INFO - 'tpaint', 11:51:25 INFO - 'sessionrestore', 11:51:25 INFO - 'sessionrestore_no_auto_restore', 11:51:25 INFO - 'tabpaint']}, 11:51:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:51:25 INFO - 'ts_paint', 11:51:25 INFO - 'tpaint', 11:51:25 INFO - 'sessionrestore', 11:51:25 INFO - 'sessionrestore_no_auto_restore', 11:51:25 INFO - 'tabpaint']}, 11:51:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:51:25 INFO - 'tests': ['a11yr', 11:51:25 INFO - 'ts_paint', 11:51:25 INFO - 'tpaint', 11:51:25 INFO - 'sessionrestore', 11:51:25 INFO - 'sessionrestore_no_auto_restore', 11:51:25 INFO - 'tabpaint']}, 11:51:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:51:25 INFO - 'tests': ['a11yr', 11:51:25 INFO - 'ts_paint', 11:51:25 INFO - 'tpaint', 11:51:25 INFO - 'sessionrestore', 11:51:25 INFO - 'sessionrestore_no_auto_restore', 11:51:25 INFO - 'tabpaint']}, 11:51:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:51:25 INFO - 'tests': ['tsvgx', 11:51:25 INFO - 'tsvgr_opacity', 11:51:25 INFO - 'tart', 11:51:25 INFO - 'tscrollx', 11:51:25 INFO - 'cart']}, 11:51:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:51:25 INFO - 'tsvgr_opacity', 11:51:25 INFO - 'tart', 11:51:25 INFO - 'tscrollx', 11:51:25 INFO - 'cart']}, 11:51:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:25 INFO - 'talos_options': ['--disable-e10s'], 11:51:25 INFO - 'tests': ['tp5o']}, 11:51:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:25 INFO - 'tests': ['tp5o']}, 11:51:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:25 INFO - 'talos_options': ['--disable-e10s', 11:51:25 INFO - '--xperf_path', 11:51:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:51:25 INFO - 'tests': ['tp5n']}, 11:51:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:25 INFO - 'talos_options': ['--xperf_path', 11:51:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:51:25 INFO - 'tests': ['tp5n']}}, 11:51:25 INFO - 'talos.zip': {'path': '', 11:51:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:51:25 INFO - Running post-action listener: _resource_record_post_action 11:51:25 INFO - ##### 11:51:25 INFO - ##### Finished populate-webroot step (success) 11:51:25 INFO - ##### 11:51:25 INFO - ##### 11:51:25 INFO - ##### Running create-virtualenv step. 11:51:25 INFO - ##### 11:51:25 INFO - Running pre-action listener: _resource_record_pre_action 11:51:25 INFO - Running main action method: create_virtualenv 11:51:25 INFO - Creating virtualenv /builds/slave/test/build/venv 11:51:25 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 11:51:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:51:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:51:25 INFO - Using real prefix '/tools/python27' 11:51:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:51:26 INFO - Installing distribute.............................................................................................................................................................................................done. 11:51:29 INFO - Installing pip.................done. 11:51:29 INFO - Return code: 0 11:51:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10355fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe88d9a220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3d30>, '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 0x103228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034bbb20>, '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.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 11:51:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:51:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:51:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mNX6uTZQ8N/Render', 11:51:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:29 INFO - 'HOME': '/Users/cltbld', 11:51:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:29 INFO - 'LOGNAME': 'cltbld', 11:51:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:29 INFO - 'MOZ_NO_REMOTE': '1', 11:51:29 INFO - 'NO_EM_RESTART': '1', 11:51:29 INFO - 'PAGER': '/bin/cat', 11:51:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:29 INFO - 'PWD': '/builds/slave/test', 11:51:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:29 INFO - 'SHELL': '/bin/bash', 11:51:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9vZVwjaKd/Listeners', 11:51:29 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 11:51:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:29 INFO - 'USER': 'cltbld', 11:51:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:29 INFO - 'XPC_FLAGS': '0x0', 11:51:29 INFO - 'XPC_SERVICE_NAME': '0', 11:51:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:30 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 11:51:30 INFO - Cleaning up... 11:51:30 INFO - Return code: 0 11:51:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:30 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 0x10355fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe88d9a220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3d30>, '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 0x103228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034bbb20>, '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.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 11:51:30 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 11:51:30 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 11:51:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mNX6uTZQ8N/Render', 11:51:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:30 INFO - 'HOME': '/Users/cltbld', 11:51:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:30 INFO - 'LOGNAME': 'cltbld', 11:51:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:30 INFO - 'MOZ_NO_REMOTE': '1', 11:51:30 INFO - 'NO_EM_RESTART': '1', 11:51:30 INFO - 'PAGER': '/bin/cat', 11:51:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:30 INFO - 'PWD': '/builds/slave/test', 11:51:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:30 INFO - 'SHELL': '/bin/bash', 11:51:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9vZVwjaKd/Listeners', 11:51:30 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 11:51:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:30 INFO - 'USER': 'cltbld', 11:51:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:30 INFO - 'XPC_FLAGS': '0x0', 11:51:30 INFO - 'XPC_SERVICE_NAME': '0', 11:51:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:30 INFO - Downloading/unpacking psutil>=3.1.1 11:51: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 11:51: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 11:51: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 11:51: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 11:51: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 11:51: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 11:51:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:51:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:51:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:51:33 INFO - Installing collected packages: psutil 11:51:33 INFO - Running setup.py install for psutil 11:51:33 INFO - building 'psutil._psutil_osx' extension 11:51:33 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 11:51:34 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 11:51:34 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 11:51:34 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 11:51:34 INFO - building 'psutil._psutil_posix' extension 11:51:34 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 11:51:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:51:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:51:34 INFO - ^ 11:51:34 INFO - 1 warning generated. 11:51:34 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 11:51:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:51:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:51:34 INFO - Successfully installed psutil 11:51:34 INFO - Cleaning up... 11:51:34 INFO - Return code: 0 11:51:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:34 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 0x10355fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe88d9a220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3d30>, '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 0x103228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034bbb20>, '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.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 11:51:34 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 11:51:34 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 11:51:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mNX6uTZQ8N/Render', 11:51:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:34 INFO - 'HOME': '/Users/cltbld', 11:51:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:34 INFO - 'LOGNAME': 'cltbld', 11:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:34 INFO - 'MOZ_NO_REMOTE': '1', 11:51:34 INFO - 'NO_EM_RESTART': '1', 11:51:34 INFO - 'PAGER': '/bin/cat', 11:51:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:34 INFO - 'PWD': '/builds/slave/test', 11:51:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:34 INFO - 'SHELL': '/bin/bash', 11:51:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9vZVwjaKd/Listeners', 11:51:34 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 11:51:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:34 INFO - 'USER': 'cltbld', 11:51:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:34 INFO - 'XPC_FLAGS': '0x0', 11:51:34 INFO - 'XPC_SERVICE_NAME': '0', 11:51:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:51: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 11:51: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 11:51: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 11:51: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 11:51: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 11:51: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 11:51:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:51:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:51:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:51:37 INFO - Installing collected packages: mozsystemmonitor 11:51:37 INFO - Running setup.py install for mozsystemmonitor 11:51:37 INFO - Successfully installed mozsystemmonitor 11:51:37 INFO - Cleaning up... 11:51:37 INFO - Return code: 0 11:51:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:51:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:37 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 0x10355fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe88d9a220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3d30>, '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 0x103228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034bbb20>, '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.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 11:51:37 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 11:51:37 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 11:51:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mNX6uTZQ8N/Render', 11:51:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:37 INFO - 'HOME': '/Users/cltbld', 11:51:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:37 INFO - 'LOGNAME': 'cltbld', 11:51:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:37 INFO - 'MOZ_NO_REMOTE': '1', 11:51:37 INFO - 'NO_EM_RESTART': '1', 11:51:37 INFO - 'PAGER': '/bin/cat', 11:51:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:37 INFO - 'PWD': '/builds/slave/test', 11:51:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:37 INFO - 'SHELL': '/bin/bash', 11:51:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9vZVwjaKd/Listeners', 11:51:37 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 11:51:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:37 INFO - 'USER': 'cltbld', 11:51:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:37 INFO - 'XPC_FLAGS': '0x0', 11:51:37 INFO - 'XPC_SERVICE_NAME': '0', 11:51:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:37 INFO - Downloading/unpacking jsonschema==2.5.1 11:51:37 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 11:51:37 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 11:51:37 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 11:51:37 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 11:51:37 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 11:51:37 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 11:51:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:51:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:51:40 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 11:51:40 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 11:51:40 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 11:51:40 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 11:51:40 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 11:51:40 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 11:51:40 INFO - Downloading functools32-3.2.3-2.tar.gz 11:51:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:51:40 INFO - warning: no files found matching '*.txt' 11:51:40 INFO - no previously-included directories found matching 'build' 11:51:40 INFO - no previously-included directories found matching 'dist' 11:51:40 INFO - no previously-included directories found matching '.git*' 11:51:40 INFO - Installing collected packages: jsonschema, functools32 11:51:40 INFO - Running setup.py install for functools32 11:51:41 INFO - warning: no files found matching '*.txt' 11:51:41 INFO - no previously-included directories found matching 'build' 11:51:41 INFO - no previously-included directories found matching 'dist' 11:51:41 INFO - no previously-included directories found matching '.git*' 11:51:41 INFO - Successfully installed jsonschema functools32 11:51:41 INFO - Cleaning up... 11:51:41 INFO - Return code: 0 11:51:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:51: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')]} 11:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51: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')]} 11:51:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:41 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 0x10355fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe88d9a220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3d30>, '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 0x103228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034bbb20>, '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.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 11:51:41 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 11:51:41 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 11:51:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mNX6uTZQ8N/Render', 11:51:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:41 INFO - 'HOME': '/Users/cltbld', 11:51:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:41 INFO - 'LOGNAME': 'cltbld', 11:51:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:41 INFO - 'MOZ_NO_REMOTE': '1', 11:51:41 INFO - 'NO_EM_RESTART': '1', 11:51:41 INFO - 'PAGER': '/bin/cat', 11:51:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:41 INFO - 'PWD': '/builds/slave/test', 11:51:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:41 INFO - 'SHELL': '/bin/bash', 11:51:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9vZVwjaKd/Listeners', 11:51:41 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 11:51:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:41 INFO - 'USER': 'cltbld', 11:51:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:41 INFO - 'XPC_FLAGS': '0x0', 11:51:41 INFO - 'XPC_SERVICE_NAME': '0', 11:51:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:41 INFO - Downloading/unpacking blobuploader==1.2.4 11:51:41 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 11:51:41 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 11:51:41 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 11:51:41 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 11:51:41 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 11:51:41 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 11:51:43 INFO - Downloading blobuploader-1.2.4.tar.gz 11:51:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:51:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:51:44 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 11:51:44 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 11:51:44 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 11:51:44 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 11:51:44 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 11:51:44 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 11:51:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:51:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:51:44 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 11:51:44 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 11:51:44 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 11:51:44 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 11:51:44 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 11:51:44 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 11:51:45 INFO - Downloading docopt-0.6.1.tar.gz 11:51:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:51:45 INFO - Installing collected packages: blobuploader, requests, docopt 11:51:45 INFO - Running setup.py install for blobuploader 11:51:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:51:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:51:45 INFO - Running setup.py install for requests 11:51:45 INFO - Running setup.py install for docopt 11:51:46 INFO - Successfully installed blobuploader requests docopt 11:51:46 INFO - Cleaning up... 11:51:46 INFO - Return code: 0 11:51:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:51: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')]} 11:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51: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')]} 11:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:46 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 0x10355fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe88d9a220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3d30>, '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 0x103228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034bbb20>, '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.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 11:51:46 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 11:51:46 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 11:51:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mNX6uTZQ8N/Render', 11:51:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:46 INFO - 'HOME': '/Users/cltbld', 11:51:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:46 INFO - 'LOGNAME': 'cltbld', 11:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:46 INFO - 'MOZ_NO_REMOTE': '1', 11:51:46 INFO - 'NO_EM_RESTART': '1', 11:51:46 INFO - 'PAGER': '/bin/cat', 11:51:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:46 INFO - 'PWD': '/builds/slave/test', 11:51:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:46 INFO - 'SHELL': '/bin/bash', 11:51:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9vZVwjaKd/Listeners', 11:51:46 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 11:51:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:46 INFO - 'USER': 'cltbld', 11:51:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:46 INFO - 'XPC_FLAGS': '0x0', 11:51:46 INFO - 'XPC_SERVICE_NAME': '0', 11:51:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:51:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-UWE2jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:51:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-MNh67J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:51:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-mekqzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:51:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-bWqXKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:51:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-LZwP78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:51:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-DAcXiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:51:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-8wcUXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:51:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-gxBNGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:51:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-o3ueyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:51:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-gG0xZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:51:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-5ZWhFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:51:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-JRpUCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:51:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-QEEAIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:51:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-pSSKVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:51:48 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-1Ufmw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:51:48 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Ofdrg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:51:48 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-MfyPwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:51:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:51:48 INFO - Running setup.py install for manifestparser 11:51:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:51:48 INFO - Running setup.py install for mozcrash 11:51:48 INFO - Running setup.py install for mozdebug 11:51:48 INFO - Running setup.py install for mozdevice 11:51:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:51:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:51:49 INFO - Running setup.py install for mozfile 11:51:49 INFO - Running setup.py install for mozhttpd 11:51:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:51:49 INFO - Running setup.py install for mozinfo 11:51:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:51:49 INFO - Running setup.py install for mozInstall 11:51:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:51:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:51:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:51:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:51:49 INFO - Running setup.py install for mozleak 11:51:49 INFO - Running setup.py install for mozlog 11:51:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:51:49 INFO - Running setup.py install for moznetwork 11:51:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:51:50 INFO - Running setup.py install for mozprocess 11:51:50 INFO - Running setup.py install for mozprofile 11:51:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:51:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:51:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:51:50 INFO - Running setup.py install for mozrunner 11:51:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:51:50 INFO - Running setup.py install for mozscreenshot 11:51:50 INFO - Running setup.py install for moztest 11:51:50 INFO - Running setup.py install for mozversion 11:51:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:51:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:51:51 INFO - Cleaning up... 11:51:51 INFO - Return code: 0 11:51:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:51:51 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')]} 11:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:51 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')]} 11:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:51 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 0x10355fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe88d9a220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3d30>, '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 0x103228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034bbb20>, '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.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 11:51:51 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 11:51:51 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 11:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mNX6uTZQ8N/Render', 11:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:51 INFO - 'HOME': '/Users/cltbld', 11:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:51 INFO - 'LOGNAME': 'cltbld', 11:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:51 INFO - 'MOZ_NO_REMOTE': '1', 11:51:51 INFO - 'NO_EM_RESTART': '1', 11:51:51 INFO - 'PAGER': '/bin/cat', 11:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:51 INFO - 'PWD': '/builds/slave/test', 11:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:51 INFO - 'SHELL': '/bin/bash', 11:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9vZVwjaKd/Listeners', 11:51:51 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 11:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:51 INFO - 'USER': 'cltbld', 11:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:51 INFO - 'XPC_FLAGS': '0x0', 11:51:51 INFO - 'XPC_SERVICE_NAME': '0', 11:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:51:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-6JXjAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:51:51 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)) 11:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:51:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ABdhcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:51:51 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)) 11:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:51:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-osJAGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:51:51 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)) 11:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:51:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-bheaHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:51:51 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)) 11:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:51:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-S00fzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:51:51 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)) 11:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:51:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-qVXoRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:51:52 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)) 11:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:51:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-VG3mRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:51:52 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)) 11:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:51:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-lb3yPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:51:52 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)) 11:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:51:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-zWXOfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:51:52 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)) 11:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:51:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-QLoHMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:51:52 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)) 11:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:51:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-LcRGtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:51:52 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)) 11:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:51:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-df0cLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:51:52 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)) 11:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:51:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-sN2Kyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:51:52 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)) 11:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:51:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-0_DwSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:51:52 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)) 11:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:51:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-j1QNG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:51:53 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)) 11:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:51:53 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-lHqAjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:51:53 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)) 11:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:51:53 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-S4LexE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:51:53 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)) 11:51:53 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)) 11:51:53 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)) 11:51:53 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)) 11:51:53 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)) 11:51:53 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)) 11:51:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:51:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:55 INFO - Downloading blessings-1.6.tar.gz 11:51:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:51:55 INFO - Installing collected packages: blessings 11:51:55 INFO - Running setup.py install for blessings 11:51:56 INFO - Successfully installed blessings 11:51:56 INFO - Cleaning up... 11:51:56 INFO - Return code: 0 11:51:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:51:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:51:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:51:56 INFO - Reading from file tmpfile_stdout 11:51:56 INFO - Current package versions: 11:51:56 INFO - blessings == 1.6 11:51:56 INFO - blobuploader == 1.2.4 11:51:56 INFO - docopt == 0.6.1 11:51:56 INFO - functools32 == 3.2.3-2 11:51:56 INFO - jsonschema == 2.5.1 11:51:56 INFO - manifestparser == 1.1 11:51:56 INFO - mozInstall == 1.12 11:51:56 INFO - mozcrash == 0.17 11:51:56 INFO - mozdebug == 0.1 11:51:56 INFO - mozdevice == 0.48 11:51:56 INFO - mozfile == 1.2 11:51:56 INFO - mozhttpd == 0.7 11:51:56 INFO - mozinfo == 0.9 11:51:56 INFO - mozleak == 0.1 11:51:56 INFO - mozlog == 3.2 11:51:56 INFO - moznetwork == 0.27 11:51:56 INFO - mozprocess == 0.22 11:51:56 INFO - mozprofile == 0.28 11:51:56 INFO - mozrunner == 6.11 11:51:56 INFO - mozscreenshot == 0.1 11:51:56 INFO - mozsystemmonitor == 0.3 11:51:56 INFO - moztest == 0.7 11:51:56 INFO - mozversion == 1.4 11:51:56 INFO - psutil == 3.1.1 11:51:56 INFO - requests == 1.2.3 11:51:56 INFO - wsgiref == 0.1.2 11:51:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:51:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:56 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 0x10355fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe88d9a220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3d30>, '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 0x103228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034bbb20>, '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.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 11:51:56 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 11:51:56 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 11:51:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mNX6uTZQ8N/Render', 11:51:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:56 INFO - 'HOME': '/Users/cltbld', 11:51:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:56 INFO - 'LOGNAME': 'cltbld', 11:51:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:56 INFO - 'MOZ_NO_REMOTE': '1', 11:51:56 INFO - 'NO_EM_RESTART': '1', 11:51:56 INFO - 'PAGER': '/bin/cat', 11:51:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:56 INFO - 'PWD': '/builds/slave/test', 11:51:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:56 INFO - 'SHELL': '/bin/bash', 11:51:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9vZVwjaKd/Listeners', 11:51:56 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 11:51:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:56 INFO - 'USER': 'cltbld', 11:51:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:56 INFO - 'XPC_FLAGS': '0x0', 11:51:56 INFO - 'XPC_SERVICE_NAME': '0', 11:51:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:56 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)) 11:51:56 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)) 11:51:56 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)) 11:51:56 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)) 11:51:56 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)) 11:51:56 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)) 11:51:56 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)) 11:51:56 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)) 11:51:56 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)) 11:51:56 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)) 11:51:56 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)) 11:51:56 INFO - Cleaning up... 11:51:56 INFO - Return code: 0 11:51:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:51:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:56 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 0x10355fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe88d9a220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3d30>, '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 0x103228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034bbb20>, '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.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 11:51:56 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 11:51:56 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 11:51:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mNX6uTZQ8N/Render', 11:51:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:56 INFO - 'HOME': '/Users/cltbld', 11:51:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:56 INFO - 'LOGNAME': 'cltbld', 11:51:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:56 INFO - 'MOZ_NO_REMOTE': '1', 11:51:56 INFO - 'NO_EM_RESTART': '1', 11:51:56 INFO - 'PAGER': '/bin/cat', 11:51:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:56 INFO - 'PWD': '/builds/slave/test', 11:51:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:56 INFO - 'SHELL': '/bin/bash', 11:51:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9vZVwjaKd/Listeners', 11:51:56 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 11:51:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:56 INFO - 'USER': 'cltbld', 11:51:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:56 INFO - 'XPC_FLAGS': '0x0', 11:51:56 INFO - 'XPC_SERVICE_NAME': '0', 11:51:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:51:56 INFO - Cleaning up... 11:51:56 INFO - Return code: 0 11:51:56 INFO - Running post-action listener: _resource_record_post_action 11:51:56 INFO - Running post-action listener: _start_resource_monitoring 11:51:57 INFO - Starting resource monitoring. 11:51:57 INFO - ##### 11:51:57 INFO - ##### Finished create-virtualenv step (success) 11:51:57 INFO - ##### 11:51:57 INFO - ##### 11:51:57 INFO - ##### Running install step. 11:51:57 INFO - ##### 11:51:57 INFO - Running pre-action listener: _resource_record_pre_action 11:51:57 INFO - Running main action method: install 11:51:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:51:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:51:57 INFO - Reading from file tmpfile_stdout 11:51:57 INFO - Detecting whether we're running mozinstall >=1.0... 11:51:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:51:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:51:57 INFO - Reading from file tmpfile_stdout 11:51:57 INFO - Output received: 11:51:57 INFO - Usage: mozinstall [options] installer 11:51:57 INFO - Options: 11:51:57 INFO - -h, --help show this help message and exit 11:51:57 INFO - -d DEST, --destination=DEST 11:51:57 INFO - Directory to install application into. [default: 11:51:57 INFO - "/builds/slave/test"] 11:51:57 INFO - --app=APP Application being installed. [default: firefox] 11:51:57 INFO - mkdir: /builds/slave/test/build/application 11:51:57 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'] 11:51:57 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 11:52:33 INFO - Reading from file tmpfile_stdout 11:52:33 INFO - Output received: 11:52:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:52:33 INFO - Running post-action listener: _resource_record_post_action 11:52:33 INFO - ##### 11:52:33 INFO - ##### Finished install step (success) 11:52:33 INFO - ##### 11:52:33 INFO - ##### 11:52:33 INFO - ##### Running run-tests step. 11:52:33 INFO - ##### 11:52:33 INFO - Running pre-action listener: _resource_record_pre_action 11:52:33 INFO - Running main action method: run_tests 11:52:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:52:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:52:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:52:33 INFO - 2016-06-28 11:52:33.190 screenresolution[6676:114068] starting screenresolution argv=screenresolution get 11:52:33 INFO - 2016-06-28 11:52:33.193 screenresolution[6676:114068] Display 0: 1600x1200x32@60 11:52:33 INFO - 2016-06-28 11:52:33.199 screenresolution[6677:114070] starting screenresolution argv=screenresolution list 11:52:33 INFO - Available Modes on Display 0 11:52:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:52:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:52:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:52:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:52:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:52:33 INFO - Intel Iris: 11:52:33 INFO - Chipset Model: Intel Iris 11:52:33 INFO - Type: GPU 11:52:33 INFO - Bus: Built-In 11:52:33 INFO - VRAM (Dynamic, Max): 1536 MB 11:52:33 INFO - Vendor: Intel (0x8086) 11:52:33 INFO - Device ID: 0x0a2e 11:52:33 INFO - Revision ID: 0x0009 11:52:33 INFO - Displays: 11:52:33 INFO - Display: 11:52:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:52:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:52:33 INFO - Display Serial Number: 1600x1200 60 11:52:33 INFO - Main Display: Yes 11:52:33 INFO - Mirror: Off 11:52:33 INFO - Online: Yes 11:52:33 INFO - Rotation: Supported 11:52:33 INFO - Return code: 0 11:52:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:52:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:52:33 INFO - Python 2.7.3 11:52:33 INFO - Return code: 0 11:52:33 INFO - grabbing minidump binary from tooltool 11:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:33 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 0x1034c3d30>, '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 0x103228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034bbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:52:33 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 11:52:33 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 11:52:33 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 11:52:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:52:33 INFO - Return code: 0 11:52:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:52:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:52:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:52:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:52:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:52:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 11:52:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0034 --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 11:52:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mNX6uTZQ8N/Render', 11:52:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:33 INFO - 'HOME': '/Users/cltbld', 11:52:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:33 INFO - 'LOGNAME': 'cltbld', 11:52:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:52:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:33 INFO - 'MOZ_NO_REMOTE': '1', 11:52:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:52:33 INFO - 'NO_EM_RESTART': '1', 11:52:33 INFO - 'PAGER': '/bin/cat', 11:52:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:33 INFO - 'PWD': '/builds/slave/test', 11:52:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:52:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:33 INFO - 'SHELL': '/bin/bash', 11:52:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9vZVwjaKd/Listeners', 11:52:33 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 11:52:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:33 INFO - 'USER': 'cltbld', 11:52:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:33 INFO - 'XPC_FLAGS': '0x0', 11:52:33 INFO - 'XPC_SERVICE_NAME': '0', 11:52:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 11:52:33 INFO - mozversion application_buildid: 20160628091623 11:52:33 INFO - mozversion application_changeset: 101de7dd28aa446f0c23cc701647d6399ed7490b 11:52:33 INFO - mozversion application_display_name: Nightly 11:52:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:52:33 INFO - mozversion application_name: Firefox 11:52:33 INFO - mozversion application_remotingname: firefox 11:52:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:52:33 INFO - mozversion application_vendor: Mozilla 11:52:33 INFO - mozversion application_version: 50.0a1 11:52:33 INFO - mozversion platform_buildid: 20160628091623 11:52:33 INFO - mozversion platform_changeset: 101de7dd28aa446f0c23cc701647d6399ed7490b 11:52:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:52:33 INFO - mozversion platform_version: 50.0a1 11:52:33 INFO - using testdate: 1467139953 11:52:33 INFO - actual date: 1467139953 11:52:33 INFO - starting webserver on 'localhost:57287' 11:52:33 INFO - SUITE-START | Running 2 tests 11:52:33 INFO - TEST-START | dromaeo_css 11:52:33 INFO - operating with platform_type : mac_ 11:52:33 INFO - Initialising browser for dromaeo_css test... 11:52:33 INFO - TEST-INFO | started process 6687 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmp7kL830/profile http://localhost:57287/getInfo.html) 11:52:36 INFO - PROCESS | 6687 | __metrics Screen width/height:1600/1200 11:52:36 INFO - PROCESS | 6687 | colorDepth:24 11:52:36 INFO - PROCESS | 6687 | Browser inner width/height: 1024/689 11:52:36 INFO - PROCESS | 6687 | __metrics 11:52:36 INFO - PROCESS | 6687 | 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" 11:52:36 INFO - PROCESS | 6687 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:52:37 INFO - TEST-INFO | 6687: exit 0 11:52:37 INFO - Browser initialized. 11:52:37 INFO - Running cycle 1/1 for dromaeo_css test... 11:52:37 INFO - TEST-INFO | started process 6689 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmp7kL830/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:52:49 INFO - PROCESS | 6689 | RSS: Main: 244330496 11:52:49 INFO - PROCESS | 6689 | 11:53:40 INFO - PROCESS | 6689 | 1467140020144 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] 11:55:50 INFO - PROCESS | 6689 | Cycle 1(1): loaded http://localhost:57287/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57287/tests/dromaeo/cssquery-ext.html) 11:55:51 INFO - PROCESS | 6689 | RSS: Main: 343519232 11:55:51 INFO - PROCESS | 6689 | 11:58:54 INFO - PROCESS | 6689 | Cycle 1(1): loaded http://localhost:57287/tests/dromaeo/cssquery-ext.html (next: http://localhost:57287/tests/dromaeo/cssquery-jquery.html) 11:58:54 INFO - PROCESS | 6689 | RSS: Main: 376643584 11:58:54 INFO - PROCESS | 6689 | 12:01:57 INFO - PROCESS | 6689 | Cycle 1(1): loaded http://localhost:57287/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57287/tests/dromaeo/cssquery-mootools.html) 12:01:58 INFO - PROCESS | 6689 | RSS: Main: 373751808 12:01:58 INFO - PROCESS | 6689 | 12:05:00 INFO - PROCESS | 6689 | Cycle 1(1): loaded http://localhost:57287/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57287/tests/dromaeo/cssquery-prototype.html) 12:05:01 INFO - PROCESS | 6689 | RSS: Main: 373366784 12:05:01 INFO - PROCESS | 6689 | 12:08:04 INFO - PROCESS | 6689 | Cycle 1(1): loaded http://localhost:57287/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57287/tests/dromaeo/cssquery-yui.html) 12:08:05 INFO - PROCESS | 6689 | RSS: Main: 341176320 12:08:05 INFO - PROCESS | 6689 | 12:11:08 INFO - PROCESS | 6689 | Cycle 1(1): loaded http://localhost:57287/tests/dromaeo/cssquery-yui.html (next: http://localhost:57287/tests/dromaeo/cssquery-dojo.html) 12:11:08 INFO - PROCESS | 6689 | RSS: Main: 426684416 12:11:08 INFO - PROCESS | 6689 | 12:11:08 INFO - PROCESS | 6689 | __start_tp_report 12:11:08 INFO - PROCESS | 6689 | _x_x_mozilla_page_load 12:11:08 INFO - PROCESS | 6689 | _x_x_mozilla_page_load_details 12:11:08 INFO - PROCESS | 6689 | |i|pagename|runs| 12:11:08 INFO - PROCESS | 6689 | |0;dojo.html;2784.262170524811;2827.406244688615;2839.2475993861644;2864.47007303649;2901.9274518137045;4613.8615841524725;4686.671932964694;4733.55031272029;4790.616750659948;4831.985034379139;4627.190241707704;4696.694714843533;4753.8098476061;4790;4852.975735121319;4342.54403287655;4403.955960440403;4406.383106365111;4450.354698568722;4600.953990460086;4542.023464955034;4584.816607335702;4589.885252868688;4636.313297848458;4654.534546545352;4291.742495450268;4369.563043695637;4380.3210502372185;4475.328700694889;4499.212637788374;4309.202797482476;4317.136572685476;4325.329573916048;4342.826286948518;4495.820167193308;4345.826166953318;4410.0077482566485;4412.139632771611;4447.08834688888;4454.376387305748;4605.332226827125;4636.698614590041;4642.930356044628;4664.790084446208;4696.882577935579;5192.688438693691;5231.136862417697;5239.318888544503;5241.73791310433;5312.2828418163435;4433.9113217735785;5249.42256351801;5307.416184219733;5354.303940487712;5366.973165134149;4788.832390866338;4928.0390323886495;4971.726555039471;4989.625778066623;5019.623528235397;5469.343678758495;5471.316085489314;5479.232907392969;5489.58828087887;5523.420040895729;5192.92210616841;5207.609429292818;5217.530422262014;5218.817341393033;5324.760385782649;5100.362454693163;5140.614453915971;5259.947400525945;5295.205719142159;5308.946910530923;5156.123459011977;5173.3533308336455;5193.480651934777;5225.372955245395;5244.501772331622;5269.0252303324005;5297.735113244323;5311.7078560678765;5323.600729945269;5336.03951288764;4183.288840897055;4240.1731662326165;4245.469316335458;4251.829926802893;4318.028443600165;3806.4861243731743;3852.730308878408;3855.978165786069;3872.7095467840027;3906.277338692351;4669.976650116796;4698.201305778026;4720.504347043511;4762.166620841339;4773.188557945157;5130.4100028497205;5219.956008798255;5223.373195216598;5245.370555533361;5268;4645.605123564535;4667.13657973276;4696.060787842445;4722.598579120813;4769.59458446029;4700.88247793801;4713.151632706145;4714.740689262055;4717.646176536773;4733.313669517969;5283.128283833278;5511.696856672841;5519.806806761744;5535.778568857281;5571.442855714477;5097.61767867404;5234.607404444606;5326.0679381107375;5333.7333133343955;5382.192671099367;5572.86067848307;5573;5574;5596.4123767003875;5611.607187496836;5037.622178336713;5248.553872920845;5273.987654074435;5288;5330.9466905332;5093.18509038552;5113.769880355468;5236.816711415082;5267.157254839208;5294.364676238876;3957.980210098931;5048.419431765394;5055.69665820052;5101.387833460009;5161.716105614228;4206.747595144178;4942.57988071018;5092.9745351273;5094.286799848099;5113.28888292229;4963.329950456642;5068.138416469283;5068.239764035424;5069.112905241494;5131.281620573197;5179.430262671028;5199;5208.791648334176;5219.947800522098;5224.373075230997;4926.113299605962;4947.455779864284;5035.370578677665;5054.469280725618;5058.418281897629 12:11:08 INFO - PROCESS | 6689 | |1;ext.html;400.42938812192625;414.7656574035669;441.2719013627514;455.62411010916003;457.25467487994564;30162.698373016265;40439.797801011024;40512.594874051254;41148;41234.38148427776;39827.80086099565;40493;41777.373339399936;41827.79086104565;41831.790841045826;8451.95774021129;9270.490123043246;9487.430754154739;9501.952490237574;9533.904660953405;11364.090872730203;11643.883561164364;11682.883171168307;11705.53177872884;11768.646940591752;11579.305241685484;11641.941790291037;11666.708332291675;11671.649850504498;11708.004819590346;11486.138539609521;11578.305301681841;11586.362750048744;11600.941995290053;11610.883891161107;11229.494672739746;11381.772364552746;11384.97535221834;11389.48747306365;11393.316401015965;33275;39917.80041099805;39947.600523994675;39956;40059.799701001306;3317.6350601433824;3341.081202669261;3380.4422270325367;3406.931861362784;3807.5803200358196;30647.233819154477;31242.218944526343;31464.213394665087;31547.053588392384;31649.52525712116;3166.857491412869;3175.364927014606;3189.1867573768773;3221.166811494314;3274.983625081883;489777;492668;494189;494190.9999999964;494967;55383.723081384334;55833.60415989551;55853.72073139608;55889.72055139698;55955.72022139863;59507.10739338913;59766.70116649389;59767;59833.401665983656;60143;15615.531534053996;15812.841871581137;15836.049837009816;15874.682506349809;15874.920625396799;12349.259044457362;12444.128910976326;12445.688857778629;12461.75076498483;12477.876991070847;14066.85933140676;14679.853201468062;14702.382737898823;14792.334344954288;14981.250937453084;6286.245650521967;6390.744097127466;6449.742010319628;6451.451626611791;6479.611223326521;755.712829124929;775.9137207908973;880.859062549989;920.9501168667724;931.3527098666498;29123.708762912524;29503.38025213252;29748.40503189924;29806.40387192244;29947.59418681851;938.9154976052188;1019.8011387779312;1031.6904928521415;1076.520948178069;1105.4638500327335;13273.867261327456;14734.557963261119;15370.462033828762;15450.150241736588;15610.45363412275;626801;637401;640641;641300;641483;58097.70951145217;58222;58257.70871145702;58316.708416457644;58463;1305.0473154597198;1340.1557019077918;1346.2730125732191;1361.9114757540926;1373.2790285100402;26862.328441789112;28937.56593651139;29084.27289317784;29691.703082968892;29764.10707678773;48952.265716015;49556.008879822926;49585;49603.50396496133;49724.50275497199;569713;587917;588193;588302;588951;33568.66431335655;34460;39551;40143.795686129466;40540.9729513529;44799.32801008053;47815;47959;48078.5192148088;48624;38207;38909.10908909087;38959;39504.407433889086;39569.406458903715;38015.61984380121;38193.61806382012;38474.807625961694;38539.42190867195;38913.2217355657;29254.268643283238;36881.44677829781;37310;37312.44031339478;37444.81277593595;28515.714842851306;32786;33627.49558756669;34167.65832341744;34520.13699657529;21457.678134828304;24248.63627045631;24481.877590611934;24561.754382455947;25306.746932531176 12:11:08 INFO - PROCESS | 6689 | |2;jquery.html;325.2227177046857;343.5276494819623;353.39569336434704;354.42936871636647;366.98165091745403;14944.953853230278;17948.46154615362;19923.701144482828;20019.999000049975;20052;16247.025178489299;19160.808391916078;20532.281370152046;20840.270590529333;21267;5504.697241651707;5774.913376299359;5813.476787089161;5817.360090390053;5819.388964158766;5278.656887302313;5779.566532510057;5785.971070144665;5795.507381872546;5805.100209467518;5704.857378565527;5740.454656807597;5742.770289188427;5746.741396637161;5749.942500574983;4814.518548145193;5505.174223866432;5512.4211957744665;5632.845266720313;5917.58576899615;5040.974795126038;5487.780488780444;5628.04323265046;5731.283589551306;5803.245578074865;12918;13075.934620326932;13172.946164306832;13175.34123293841;14394.640133996629;2130.488682716137;2217.268301460514;2316.316686577447;2503.8372505787247;3013.3671928894737;9351.859722104175;9508.572114254875;9833.4099954003;10855.51150198243;15982.961107527974;1779.7953235377968;1817.1731862002757;1837.8070302618166;1870.962580748391;1871.840893524052;89330.5533472335;104914;118318;123469.38265308616;126954.3652281742;9023.729288121367;10838.837417438745;10896.455177241265;10987.285826421257;11197.66407007791;12095;13573.25347105899;14039.157650541;14066.43734250639;14075.085119467201;8139.511629302261;8372.413931024892;8387.245147936594;8430.45202061864;8437.662493500315;7389.935535455478;7877.778944263649;8338.291245244101;8340.582970851434;8482.448640838325;4326.074896670952;4356.978215108968;4358.237308471068;4366.388705581221;4437.955620443819;1681.058148240579;1718.8109307976113;1728.2309372329194;1731.3334366269078;1739.5390221591288;657.1063353838775;714.2464699741719;720.1574158234878;818.5866137600474;831.6673330667782;15545.533633990999;18086.638267234583;20475.795242047687;20831.37505874826;20910.059047342907;855.935750014945;862.8921384826897;870.3428911172151;876.1063715010654;909.3498148823622;10634.73413164677;10805.621803236849;10860.45697715111;10887.618933337453;10941.179411544166;539582.3020884871;620870;631642;637348;637390;79658.61024169285;123756.38121809333;134767.56627036014;139694.30152849172;148104.5189548091;893.2452077994085;942.1050002497792;957.5595226195888;958.6932181701789;961.5048250151178;15159.469418570297;18293.81706182921;18544.25822967066;18547.72178417298;18645.90677046606;53329.20006199832;53910.92178156493;54427.425309341146;57176;57329.42670573241;84779.15220847713;84799;85270.57364713385;85557.57221214104;86298;6382.393672601095;7753.4960227585025;7804.2585954334245;7834.138244793182;8008.4783891060515;11802.52789888395;12319.63041108768;12432.378381081091;12532;12535.310557919402;10669.093127084285;10793.892061079288;10927.672169834917;10932.01611854905;11082.33505989611;10442.63450779219;10615.628453004108;10696.679099627023;10771.569137234736;10871.456427178451;10784;10798.892011079788;10918.344899306067;11101.611443599435;11165.441727913409;7749.573773442515;7756.1468238494845;7804.9609751950875;7983.5209887406945;7993.400494963017;9891.604335826487;10842.78314433691;10850.0777433919;10972.23194376386;10985.066269367193 12:11:08 INFO - PROCESS | 6689 | |3;mootools.html;1292.0180662696348;1458.3801884199213;1486.814148231471;1657.187977890833;1849.426677729903;7519.849603007937;8269.875951860728;8509.702160424371;8539.871901921477;8646.52444115575;8304.95847520763;8390.874136887951;8489.91510084899;8507.319414446845;8524.977002759677;1660.3192690996693;2069.8447616428753;2217.170834061901;2254.3124347074136;2264.116994372196;2069.141306357867;2089.9863566170397;2093.3510611710344;2184.715986921698;2298.643710224918;2250.707408036953;2257.4582100295975;2261.705978222837;2265.8187345012443;2268.5689718953427;2211.6682497625407;2228.55428914217;2260.2202240227093;2265.76209498002;2269.2511471214625;1947.1821834829263;2156.1267686586957;2204;2242.091952759135;2251.313349428424;8526.976762788505;8616.181462761027;8617.784555386103;8627.741167764978;8628.439151455135;4336.631386332134;5072.467390923974;5092.134337162691;5114.539691427789;5132.948670513284;3297.0768184908266;3412.1128506588216;3491.3017396520545;3500.422430298998;3536.3634545781747;1008.9545970431349;1025.3796453145815;1034.4310629153936;1040.849076883855;1047.5704960966043;11159.330440173535;11443.771124577544;11481.253718508271;11482.598109066226;11487.023602993755;3849.4418309345547;4267.786610669454;4395.516493185747;4500.122476117192;4564.292534657134;9471;9523.04769523056;9532.857007144898;9533.285003624756;9534.618615255313;5079.87300317495;5081.390233172043;5082.644214904995;5094.133997220481;5094.898102037939;4573.199690054227;4574.085182963354;4673.859784206479;4690.577847993697;4704.317873908332;2981.4782413077623;3629.546306711661;3689.0224090616007;3692.5199724036243;3704.6480584344445;1170.5142365918218;1196.5094311332487;1237.208186760474;1253.9059570532247;1255.711186427127;769.9759320104248;783.6904422752981;810.4934415990007;830.5847076461769;848.8981322241247;5209.4009188943755;5590.496855282962;5600.299962504687;5888.882222355529;5889.204957330706;785.6935795039939;895.3285036222833;902.1609902790336;902.4810733827944;913.6573784830689;3419.965800341965;3678.576963649161;3856.267309211241;3903.902402439962;3920.882373528799;10050.447225402528;10540.683779486628;10549.314294570828;10610.734731631617;10650.254482186172;10326.535305911102;10371.377717336984;10531.315464494783;10542.42016689074;10548.894511054945;7776.688932442752;7777.300042996045;7854.136045035156;7955.204479552114;7966.123726390092;5629.127485239795;5672.517835983987;5685.658860468385;5692.829215123553;5882.558808089325;8337.874931876147;9343.018983006687;9707.126358627474;9754.60981560758;9808.921018687795;8850.73447796567;9962;9971.650992215238;9983.800323993624;9987;6787.66061696923;6941.305869413019;6977.406920411822;6979.092717946634;6980.895286570807;7270.600116993616;7672.693092276247;7970.4022198336515;8031.759047228593;8161.673533058611;6280.497560195082;6887.311268873073;6976.895346569704;7173.461990350849;7405.148407933157;7015.403690686348;7022.08712867324;7219.747308844166;7255.056842610426;7257.745978890714;6711.194656641234;6763.492738044568;6847.383735463832;6852.211995620568;6866.622335771581;4664.4869064403565;4822.638302127284;4897.240927656219;4899.975500122477;4928.162212423825;4507.75207363585;4861.368022157097;4882.926756098733;4973.204287314013;5059 12:11:08 INFO - PROCESS | 6689 | |4;prototype.html;267.82189843753906;273.3480648652963;280.08552077467067;284.54880111431714;285.6757580146533;3646.562412510496;3920.980395098024;4042.737222080563;4070.1859628074394;4070.9389359159563;2926.89755858545;3785.470034195208;3943.7042221833403;3996.3805610130403;4189.748615083097;3153.5269709543636;3256.544083828266;3258.094015001227;3271.509273608966;3284.8193349365774;2803.4252978139534;2822.562502812057;3242.837858107097;3252.7397808175406;3305.0910999475095;2583.6641236639302;2914.591957126004;3103.0845900459417;3117.360941007088;3274.590676165479;2586.5602847515966;2986.850657467118;3093.566900633913;3220.7423406127687;3224.596925384327;2611.386324213808;2641.379275870169;2891.8288093322026;2895.3496506991023;2927.838968856712;3245.464498357768;3704.573973992998;3877.955804693302;3915.158240978175;3987.282289187974;3232.4968889466277;3388.423967925459;3461.826908654557;3703.5555733312176;3760.868369607078;4063.6342729154517;4310.849120280806;4346.56534346566;4357.803898824561;4358.324459708719;3100.891468798604;3135.764817638686;3145.858436370369;3148.6379066407426;3151.4169878572307;3837.712171587142;4024.812680259301;4374.8906277343;4449.888752781207;4532.546745325441;2880.76953843692;3058.6788387219467;3096.909586029421;3111.2066423061974;3143.685631436838;2813.366992426688;3189.2505261263586;3201.199700074981;3218.11501836992;3222.4199644063824;3170.397624451347;3685.447182922583;3697.6672099511175;3698.0754811297174;3722.1253005543676;4148.79256037197;4199.559046300155;4204.843667991266;4428.402165707653;4465.642748580106;955.0067929353534;1018.1244130048152;1064.1858977881927;1104.8232282834708;1108.952040321888;701.2041333086983;724.57250222368;729.4930023633524;753.2881427051384;759.8252401947473;3269.558609587723;3875.457435958968;3875.7093218008763;3913.158670885773;3926.8415817333953;3700.8149592520804;3996.300647386696;4249.873783447389;4409.713368631029;4496.820127194941;3560.9821950890077;3678.3011227867155;3786.444150198605;3849.287881741887;3855.1711382052995;1997.7103320018807;2194.967075493869;2296.622026783916;2305.204704376987;2318.6406107053435;3862.9227415451533;4051.6556092731857;4505.752183629932;4512.571305725951;4575.931361029588;4178.91642167155;4187.434696315959;4418.381426600278;4517.593416592523;4541.5231400703115;4208.747475151501;4299.398084268205;4547.272436410225;4561.63506919439;4567.269236922143;4347.7173983690955;4381.255186618218;4395.978020109879;4405.691601587858;4467.486239082417;3828.5788563258575;4071.75569465833;4202.285611446;4368.978155109204;4379.343098535118;3703.370427027358;3995.320795464836;4544.909101818012;4581.587657110743;4583.679142459996;2691.9596206057317;3039.027511196396;3180.348028654074;3184.6910989532403;3193.409219294345;4523.5250298718165;4547.977260113678;4550.407357424875;4551.589007407714;4555.020670555847;3689.6494832990825;4425.030861266834;4449.57729015743;4463.9956009898815;4470.731756094645;3951.1702542466405;4047.696422768363;4272.487301523837;4310.374995625554;4455.821767129408;3667.009907325061;4302.290122129939;4326.3943047973935;4334.1981733379425;4339.587739164775;3588.156783155904;4013.0769922917307;4227.6406505447385;4416.394327346752;4423.34599240466;3413.1637748751714;3677.42999835026;3760.8871733848027;4299.861101396061;4304.289792184254 12:11:08 INFO - PROCESS | 6689 | |5;yui.html;592.6266452135154;728.4379384808292;730.660242987011;734.9191588925218;736.2416710787891;14346.282685865717;15083.849161508382;15100.622484437894;15109.244537773122;15204.543863684094;13187.208767473958;14018;14045.297735113229;14062.929685351559;14063.859361406383;687.1547995964966;871.1984973824082;882.0297672560197;891.2513488669516;891.4740303221098;705.3334598804132;842.1410161635129;878.5343767803074;881.4623079921242;881.6870011146048;937.2267878999852;937.995310023449;941.5668792355491;943.306669597845;943.9952800235989;715.1775458223033;737.7712908998174;937.0067728208053;937.4328531238605;938.4322484896608;664.3854434647932;688.4114082459525;689.975850845223;691.2845205212581;692.19705142035;14721.337539810735;14833.92583037078;14847.703045939128;14867.776983345258;14887.627809304746;13271.270080145589;13534.984876134231;13590.59228223145;13592.86407135926;13746.725065498635;12017.45921433529;12034.699132521757;12185.451654675473;12637.936810315981;12678.87321126786;3831.7892515911612;4445.422095127613;4467.709598876095;4625.421822272216;4644.581987621096;100063.49968250185;102018;105498.47250763774;106072;106093;9888.307818452784;10509.632162874263;10732.409717465614;10913.454327283604;10975.176861735401;20841.583168336852;20942.162313507593;21187.36437906865;21214.893925530272;21277.936103194777;7760.34037106841;7826.02174728159;7841.882371764428;7853.371730261565;7931.206879312138;4315.611594956469;4428.247197976351;4440.844570440019;4451;4673.485916549177;3656.6470405949967;3911.9217615647526;3913.099987002948;3916.2559113768293;3948.506436695413;646.1438593863151;703.6833424958687;708.397861817451;723.5875550936021;723.6490302203532;518.3883018038675;520.4512560223618;521.5175962234918;557.8214971209175;617.3425302053299;8451.619677114544;8692.783999760111;9139.314551408672;9579.377340472847;9607.375520591138;466.7269647256393;566.8164634418304;592.7273454211111;624.1168746224133;628.4061561824033;3856.9228615427533;3907.882763517099;4052.696047796427;4570.771461426915;5006.74966251686;147002.26498867437;148741;150563;154799;157444;119038.4048079754;122186;123656.38171809263;126159.99999999817;126578.36710816571;109058;112190;116148.99999999831;117959;124760.37619812206;9723.513824308899;9739.805203896025;9744.12302892743;9797;9892.653757118467;56653.15020274781;58200;60134;60265.69867150636;61969;71318;81071;89507;90025;92200.53899730458;13139.868601313865;13174.47302107905;13326.800097998344;13345.532906348231;13386.866131338562;14751.631209219855;14930.776038359216;14936.103833770008;15577.766333505233;15644.843551564338;13748.65628359299;14020.859791402363;14168.645783855485;14495.927520362331;14547.272636367927;13869.514566990107;13913.443462261395;13928.303584820922;14141.505047323295;14276.357563909442;13505.12216705911;13835.86164138386;14204.36080376365;14313.928430357782;14328.785068223777;10081.848772268568;10127.454701640283;10660.89339106599;11210.775784484102;11337.26307789991;9821.901780982098;10462.04274157707;10501.579936802442;11176.776464470502;11183.944080279873 12:11:08 INFO - PROCESS | 6689 | __end_tp_report 12:11:08 INFO - PROCESS | 6689 | __start_cc_report 12:11:08 INFO - PROCESS | 6689 | _x_x_mozilla_cycle_collect,1144 12:11:08 INFO - PROCESS | 6689 | __end_cc_report 12:11:08 INFO - PROCESS | 6689 | __startTimestamp1467141068795__endTimestamp 12:11:08 INFO - PROCESS | 6689 | 12:11:08 INFO - PROCESS | 6689 | ------- Summary: start ------- 12:11:08 INFO - PROCESS | 6689 | Number of tests: 6 12:11:08 INFO - PROCESS | 6689 | 12:11:08 INFO - PROCESS | 6689 | [#0] dojo.html Cycles:165 Average:4855.44 Median:5035.37 stddev:549.72 (10.9%) stddev-sans-first:526.85 12:11:08 INFO - PROCESS | 6689 | Values: 2784.3 2827.4 2839.2 2864.5 2901.9 4613.9 4686.7 4733.6 4790.6 4832.0 4627.2 4696.7 4753.8 4790.0 4853.0 4342.5 4404.0 4406.4 4450.4 4601.0 4542.0 4584.8 4589.9 4636.3 4654.5 4291.7 4369.6 4380.3 4475.3 4499.2 4309.2 4317.1 4325.3 4342.8 4495.8 4345.8 4410.0 4412.1 4447.1 4454.4 4605.3 4636.7 4642.9 4664.8 4696.9 5192.7 5231.1 5239.3 5241.7 5312.3 4433.9 5249.4 5307.4 5354.3 5367.0 4788.8 4928.0 4971.7 4989.6 5019.6 5469.3 5471.3 5479.2 5489.6 5523.4 5192.9 5207.6 5217.5 5218.8 5324.8 5100.4 5140.6 5259.9 5295.2 5308.9 5156.1 5173.4 5193.5 5225.4 5244.5 5269.0 5297.7 5311.7 5323.6 5336.0 4183.3 4240.2 4245.5 4251.8 4318.0 3806.5 3852.7 3856.0 3872.7 3906.3 4670.0 4698.2 4720.5 4762.2 4773.2 5130.4 5220.0 5223.4 5245.4 5268.0 4645.6 4667.1 4696.1 4722.6 4769.6 4700.9 4713.2 4714.7 4717.6 4733.3 5283.1 5511.7 5519.8 5535.8 5571.4 5097.6 5234.6 5326.1 5333.7 5382.2 5572.9 5573.0 5574.0 5596.4 5611.6 5037.6 5248.6 5274.0 5288.0 5330.9 5093.2 5113.8 5236.8 5267.2 5294.4 3958.0 5048.4 5055.7 5101.4 5161.7 4206.7 4942.6 5093.0 5094.3 5113.3 4963.3 5068.1 5068.2 5069.1 5131.3 5179.4 5199.0 5208.8 5219.9 5224.4 4926.1 4947.5 5035.4 5054.5 5058.4 12:11:08 INFO - PROCESS | 6689 | 12:11:08 INFO - PROCESS | 6689 | [#1] ext.html Cycles:180 Average:70328.82 Median:37378.63 stddev:153590.72 (410.9%) stddev-sans-first:153931.84 12:11:08 INFO - PROCESS | 6689 | Values: 400.4 414.8 441.3 455.6 457.3 30162.7 40439.8 40512.6 41148.0 41234.4 39827.8 40493.0 41777.4 41827.8 41831.8 8452.0 9270.5 9487.4 9502.0 9533.9 11364.1 11643.9 11682.9 11705.5 11768.6 11579.3 11641.9 11666.7 11671.6 11708.0 11486.1 11578.3 11586.4 11600.9 11610.9 11229.5 11381.8 11385.0 11389.5 11393.3 33275.0 39917.8 39947.6 39956.0 40059.8 3317.6 3341.1 3380.4 3406.9 3807.6 30647.2 31242.2 31464.2 31547.1 31649.5 3166.9 3175.4 3189.2 3221.2 3275.0 489777.0 492668.0 494189.0 494191.0 494967.0 55383.7 55833.6 55853.7 55889.7 55955.7 59507.1 59766.7 59767.0 59833.4 60143.0 15615.5 15812.8 15836.0 15874.7 15874.9 12349.3 12444.1 12445.7 12461.8 12477.9 14066.9 14679.9 14702.4 14792.3 14981.3 6286.2 6390.7 6449.7 6451.5 6479.6 755.7 775.9 880.9 921.0 931.4 29123.7 29503.4 29748.4 29806.4 29947.6 938.9 1019.8 1031.7 1076.5 1105.5 13273.9 14734.6 15370.5 15450.2 15610.5 626801.0 637401.0 640641.0 641300.0 641483.0 58097.7 58222.0 58257.7 58316.7 58463.0 1305.0 1340.2 1346.3 1361.9 1373.3 26862.3 28937.6 29084.3 29691.7 29764.1 48952.3 49556.0 49585.0 49603.5 49724.5 569713.0 587917.0 588193.0 588302.0 588951.0 33568.7 34460.0 39551.0 40143.8 40541.0 44799.3 47815.0 47959.0 48078.5 48624.0 38207.0 38909.1 38959.0 39504.4 39569.4 38015.6 38193.6 38474.8 38539.4 38913.2 29254.3 36881.4 37310.0 37312.4 37444.8 28515.7 32786.0 33627.5 34167.7 34520.1 21457.7 24248.6 24481.9 24561.8 25306.7 12:11:08 INFO - PROCESS | 6689 | 12:11:08 INFO - PROCESS | 6689 | [#2] jquery.html Cycles:180 Average:34867.54 Median:353.91 stddev:102555.14 (28977.5%) stddev-sans-first:102810.03 12:11:08 INFO - PROCESS | 6689 | Values: 325.2 343.5 353.4 354.4 367.0 14945.0 17948.5 19923.7 20020.0 20052.0 16247.0 19160.8 20532.3 20840.3 21267.0 5504.7 5774.9 5813.5 5817.4 5819.4 5278.7 5779.6 5786.0 5795.5 5805.1 5704.9 5740.5 5742.8 5746.7 5749.9 4814.5 5505.2 5512.4 5632.8 5917.6 5041.0 5487.8 5628.0 5731.3 5803.2 12918.0 13075.9 13172.9 13175.3 14394.6 2130.5 2217.3 2316.3 2503.8 3013.4 9351.9 9508.6 9833.4 10855.5 15983.0 1779.8 1817.2 1837.8 1871.0 1871.8 89330.6 104914.0 118318.0 123469.4 126954.4 9023.7 10838.8 10896.5 10987.3 11197.7 12095.0 13573.3 14039.2 14066.4 14075.1 8139.5 8372.4 8387.2 8430.5 8437.7 7389.9 7877.8 8338.3 8340.6 8482.4 4326.1 4357.0 4358.2 4366.4 4438.0 1681.1 1718.8 1728.2 1731.3 1739.5 657.1 714.2 720.2 818.6 831.7 15545.5 18086.6 20475.8 20831.4 20910.1 855.9 862.9 870.3 876.1 909.3 10634.7 10805.6 10860.5 10887.6 10941.2 539582.3 620870.0 631642.0 637348.0 637390.0 79658.6 123756.4 134767.6 139694.3 148104.5 893.2 942.1 957.6 958.7 961.5 15159.5 18293.8 18544.3 18547.7 18645.9 53329.2 53910.9 54427.4 57176.0 57329.4 84779.2 84799.0 85270.6 85557.6 86298.0 6382.4 7753.5 7804.3 7834.1 8008.5 11802.5 12319.6 12432.4 12532.0 12535.3 10669.1 10793.9 10927.7 10932.0 11082.3 10442.6 10615.6 10696.7 10771.6 10871.5 10784.0 10798.9 10918.3 11101.6 11165.4 7749.6 7756.1 7805.0 7983.5 7993.4 9891.6 10842.8 10850.1 10972.2 10985.1 12:11:08 INFO - PROCESS | 6689 | 12:11:08 INFO - PROCESS | 6689 | [#3] mootools.html Cycles:180 Average:5474.23 Median:5065.73 stddev:3117.23 (61.5%) stddev-sans-first:3110.13 12:11:08 INFO - PROCESS | 6689 | Values: 1292.0 1458.4 1486.8 1657.2 1849.4 7519.8 8269.9 8509.7 8539.9 8646.5 8305.0 8390.9 8489.9 8507.3 8525.0 1660.3 2069.8 2217.2 2254.3 2264.1 2069.1 2090.0 2093.4 2184.7 2298.6 2250.7 2257.5 2261.7 2265.8 2268.6 2211.7 2228.6 2260.2 2265.8 2269.3 1947.2 2156.1 2204.0 2242.1 2251.3 8527.0 8616.2 8617.8 8627.7 8628.4 4336.6 5072.5 5092.1 5114.5 5132.9 3297.1 3412.1 3491.3 3500.4 3536.4 1009.0 1025.4 1034.4 1040.8 1047.6 11159.3 11443.8 11481.3 11482.6 11487.0 3849.4 4267.8 4395.5 4500.1 4564.3 9471.0 9523.0 9532.9 9533.3 9534.6 5079.9 5081.4 5082.6 5094.1 5094.9 4573.2 4574.1 4673.9 4690.6 4704.3 2981.5 3629.5 3689.0 3692.5 3704.6 1170.5 1196.5 1237.2 1253.9 1255.7 770.0 783.7 810.5 830.6 848.9 5209.4 5590.5 5600.3 5888.9 5889.2 785.7 895.3 902.2 902.5 913.7 3420.0 3678.6 3856.3 3903.9 3920.9 10050.4 10540.7 10549.3 10610.7 10650.3 10326.5 10371.4 10531.3 10542.4 10548.9 7776.7 7777.3 7854.1 7955.2 7966.1 5629.1 5672.5 5685.7 5692.8 5882.6 8337.9 9343.0 9707.1 9754.6 9808.9 8850.7 9962.0 9971.7 9983.8 9987.0 6787.7 6941.3 6977.4 6979.1 6980.9 7270.6 7672.7 7970.4 8031.8 8161.7 6280.5 6887.3 6976.9 7173.5 7405.1 7015.4 7022.1 7219.7 7255.1 7257.7 6711.2 6763.5 6847.4 6852.2 6866.6 4664.5 4822.6 4897.2 4900.0 4928.2 4507.8 4861.4 4882.9 4973.2 5059.0 12:11:08 INFO - PROCESS | 6689 | 12:11:08 INFO - PROCESS | 6689 | [#4] prototype.html Cycles:180 Average:3503.35 Median:3869.19 stddev:1047.96 (27.1%) stddev-sans-first:1022.38 12:11:08 INFO - PROCESS | 6689 | Values: 267.8 273.3 280.1 284.5 285.7 3646.6 3921.0 4042.7 4070.2 4070.9 2926.9 3785.5 3943.7 3996.4 4189.7 3153.5 3256.5 3258.1 3271.5 3284.8 2803.4 2822.6 3242.8 3252.7 3305.1 2583.7 2914.6 3103.1 3117.4 3274.6 2586.6 2986.9 3093.6 3220.7 3224.6 2611.4 2641.4 2891.8 2895.3 2927.8 3245.5 3704.6 3878.0 3915.2 3987.3 3232.5 3388.4 3461.8 3703.6 3760.9 4063.6 4310.8 4346.6 4357.8 4358.3 3100.9 3135.8 3145.9 3148.6 3151.4 3837.7 4024.8 4374.9 4449.9 4532.5 2880.8 3058.7 3096.9 3111.2 3143.7 2813.4 3189.3 3201.2 3218.1 3222.4 3170.4 3685.4 3697.7 3698.1 3722.1 4148.8 4199.6 4204.8 4428.4 4465.6 955.0 1018.1 1064.2 1104.8 1109.0 701.2 724.6 729.5 753.3 759.8 3269.6 3875.5 3875.7 3913.2 3926.8 3700.8 3996.3 4249.9 4409.7 4496.8 3561.0 3678.3 3786.4 3849.3 3855.2 1997.7 2195.0 2296.6 2305.2 2318.6 3862.9 4051.7 4505.8 4512.6 4575.9 4178.9 4187.4 4418.4 4517.6 4541.5 4208.7 4299.4 4547.3 4561.6 4567.3 4347.7 4381.3 4396.0 4405.7 4467.5 3828.6 4071.8 4202.3 4369.0 4379.3 3703.4 3995.3 4544.9 4581.6 4583.7 2692.0 3039.0 3180.3 3184.7 3193.4 4523.5 4548.0 4550.4 4551.6 4555.0 3689.6 4425.0 4449.6 4464.0 4470.7 3951.2 4047.7 4272.5 4310.4 4455.8 3667.0 4302.3 4326.4 4334.2 4339.6 3588.2 4013.1 4227.6 4416.4 4423.3 3413.2 3677.4 3760.9 4299.9 4304.3 12:11:08 INFO - PROCESS | 6689 | 12:11:08 INFO - PROCESS | 6689 | [#5] yui.html Cycles:180 Average:24531.68 Median:3882.40 stddev:39125.42 (1007.8%) stddev-sans-first:39193.89 12:11:08 INFO - PROCESS | 6689 | Values: 592.6 728.4 730.7 734.9 736.2 14346.3 15083.8 15100.6 15109.2 15204.5 13187.2 14018.0 14045.3 14062.9 14063.9 687.2 871.2 882.0 891.3 891.5 705.3 842.1 878.5 881.5 881.7 937.2 938.0 941.6 943.3 944.0 715.2 737.8 937.0 937.4 938.4 664.4 688.4 690.0 691.3 692.2 14721.3 14833.9 14847.7 14867.8 14887.6 13271.3 13535.0 13590.6 13592.9 13746.7 12017.5 12034.7 12185.5 12637.9 12678.9 3831.8 4445.4 4467.7 4625.4 4644.6 100063.5 102018.0 105498.5 106072.0 106093.0 9888.3 10509.6 10732.4 10913.5 10975.2 20841.6 20942.2 21187.4 21214.9 21277.9 7760.3 7826.0 7841.9 7853.4 7931.2 4315.6 4428.2 4440.8 4451.0 4673.5 3656.6 3911.9 3913.1 3916.3 3948.5 646.1 703.7 708.4 723.6 723.6 518.4 520.5 521.5 557.8 617.3 8451.6 8692.8 9139.3 9579.4 9607.4 466.7 566.8 592.7 624.1 628.4 3856.9 3907.9 4052.7 4570.8 5006.7 147002.3 148741.0 150563.0 154799.0 157444.0 119038.4 122186.0 123656.4 126160.0 126578.4 109058.0 112190.0 116149.0 117959.0 124760.4 9723.5 9739.8 9744.1 9797.0 9892.7 56653.2 58200.0 60134.0 60265.7 61969.0 71318.0 81071.0 89507.0 90025.0 92200.5 13139.9 13174.5 13326.8 13345.5 13386.9 14751.6 14930.8 14936.1 15577.8 15644.8 13748.7 14020.9 14168.6 14495.9 14547.3 13869.5 13913.4 13928.3 14141.5 14276.4 13505.1 13835.9 14204.4 14313.9 14328.8 10081.8 10127.5 10660.9 11210.8 11337.3 9821.9 10462.0 10501.6 11176.8 11183.9 12:11:08 INFO - PROCESS | 6689 | -------- Summary: end -------- 12:11:08 INFO - PROCESS | 6689 | 12:11:09 INFO - TEST-INFO | 6689: exit 0 12:11:09 INFO - TEST-OK | dromaeo_css | took 1115651ms 12:11:09 INFO - TEST-START | kraken 12:11:09 INFO - operating with platform_type : mac_ 12:11:09 INFO - Initialising browser for kraken test... 12:11:09 INFO - TEST-INFO | started process 6693 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpcWMyjr/profile http://localhost:57287/getInfo.html) 12:11:11 INFO - PROCESS | 6693 | __metrics Screen width/height:1600/1200 12:11:11 INFO - PROCESS | 6693 | colorDepth:24 12:11:11 INFO - PROCESS | 6693 | Browser inner width/height: 1024/689 12:11:11 INFO - PROCESS | 6693 | __metrics 12:11:11 INFO - PROCESS | 6693 | 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" 12:11:11 INFO - PROCESS | 6693 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:11:12 INFO - TEST-INFO | 6693: exit 0 12:11:12 INFO - Browser initialized. 12:11:12 INFO - Running cycle 1/1 for kraken test... 12:11:12 INFO - TEST-INFO | started process 6694 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpcWMyjr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:11:24 INFO - PROCESS | 6694 | RSS: Main: 243335168 12:11:24 INFO - PROCESS | 6694 | 12:11:27 INFO - PROCESS | 6694 | Cycle 1(1): loaded http://localhost:57287/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57287/tests/kraken/driver.html?testName=audio-beat-detection) 12:11:28 INFO - PROCESS | 6694 | RSS: Main: 294215680 12:11:28 INFO - PROCESS | 6694 | 12:11:31 INFO - PROCESS | 6694 | Cycle 1(1): loaded http://localhost:57287/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57287/tests/kraken/driver.html?testName=audio-dft) 12:11:32 INFO - PROCESS | 6694 | RSS: Main: 303722496 12:11:32 INFO - PROCESS | 6694 | 12:11:36 INFO - PROCESS | 6694 | Cycle 1(1): loaded http://localhost:57287/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57287/tests/kraken/driver.html?testName=audio-fft) 12:11:36 INFO - PROCESS | 6694 | RSS: Main: 391839744 12:11:36 INFO - PROCESS | 6694 | 12:11:38 INFO - PROCESS | 6694 | Cycle 1(1): loaded http://localhost:57287/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57287/tests/kraken/driver.html?testName=audio-oscillator) 12:11:39 INFO - PROCESS | 6694 | RSS: Main: 293773312 12:11:39 INFO - PROCESS | 6694 | 12:11:41 INFO - PROCESS | 6694 | Cycle 1(1): loaded http://localhost:57287/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57287/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:11:42 INFO - PROCESS | 6694 | RSS: Main: 296980480 12:11:42 INFO - PROCESS | 6694 | 12:11:45 INFO - PROCESS | 6694 | Cycle 1(1): loaded http://localhost:57287/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57287/tests/kraken/driver.html?testName=imaging-darkroom) 12:11:46 INFO - PROCESS | 6694 | RSS: Main: 334225408 12:11:46 INFO - PROCESS | 6694 | 12:11:48 INFO - PROCESS | 6694 | Cycle 1(1): loaded http://localhost:57287/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57287/tests/kraken/driver.html?testName=imaging-desaturate) 12:11:49 INFO - PROCESS | 6694 | RSS: Main: 334753792 12:11:49 INFO - PROCESS | 6694 | 12:11:52 INFO - PROCESS | 6694 | Cycle 1(1): loaded http://localhost:57287/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57287/tests/kraken/driver.html?testName=json-parse-financial) 12:11:53 INFO - PROCESS | 6694 | RSS: Main: 332247040 12:11:53 INFO - PROCESS | 6694 | 12:11:54 INFO - PROCESS | 6694 | Cycle 1(1): loaded http://localhost:57287/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57287/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:11:55 INFO - PROCESS | 6694 | RSS: Main: 312393728 12:11:55 INFO - PROCESS | 6694 | 12:11:57 INFO - PROCESS | 6694 | Cycle 1(1): loaded http://localhost:57287/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57287/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:11:58 INFO - PROCESS | 6694 | RSS: Main: 320290816 12:11:58 INFO - PROCESS | 6694 | 12:12:00 INFO - PROCESS | 6694 | Cycle 1(1): loaded http://localhost:57287/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57287/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:12:01 INFO - PROCESS | 6694 | RSS: Main: 322723840 12:12:01 INFO - PROCESS | 6694 | 12:12:04 INFO - PROCESS | 6694 | Cycle 1(1): loaded http://localhost:57287/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57287/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:12:04 INFO - PROCESS | 6694 | RSS: Main: 325505024 12:12:04 INFO - PROCESS | 6694 | 12:12:07 INFO - PROCESS | 6694 | Cycle 1(1): loaded http://localhost:57287/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57287/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:12:08 INFO - PROCESS | 6694 | RSS: Main: 326180864 12:12:08 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | Cycle 1(1): loaded http://localhost:57287/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57287/tests/kraken/driver.html?testName=ai-astar) 12:12:10 INFO - PROCESS | 6694 | RSS: Main: 370319360 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | __start_tp_report 12:12:10 INFO - PROCESS | 6694 | _x_x_mozilla_page_load 12:12:10 INFO - PROCESS | 6694 | _x_x_mozilla_page_load_details 12:12:10 INFO - PROCESS | 6694 | |i|pagename|runs| 12:12:10 INFO - PROCESS | 6694 | |0;ai-astar;140;171;118;123;169;151;114;117;114;117 12:12:10 INFO - PROCESS | 6694 | |1;audio-beat-detection;167;134;126;181;127;124;150;134;213;147 12:12:10 INFO - PROCESS | 6694 | |2;audio-dft;288;285;215;212;213;215;212;211;214;211 12:12:10 INFO - PROCESS | 6694 | |3;audio-fft;84;79;60;114;59;58;66;67;82;49 12:12:10 INFO - PROCESS | 6694 | |4;audio-oscillator;90;89;97;73;72;72;98;72;70;106 12:12:10 INFO - PROCESS | 6694 | |5;imaging-gaussian-blur;94;90;92;90;92;90;93;89;103;131 12:12:10 INFO - PROCESS | 6694 | |6;imaging-darkroom;97;98;98;98;99;97;98;98;98;97 12:12:10 INFO - PROCESS | 6694 | |7;imaging-desaturate;104;101;101;101;101;105;105;102;101;104 12:12:10 INFO - PROCESS | 6694 | |8;json-parse-financial;58;57;59;57;59;57;58;58;53;62 12:12:10 INFO - PROCESS | 6694 | |9;json-stringify-tinderbox;71;80;118;115;78;76;77;78;77;79 12:12:10 INFO - PROCESS | 6694 | |10;stanford-crypto-aes;88;90;90;90;89;88;92;90;106;90 12:12:10 INFO - PROCESS | 6694 | |11;stanford-crypto-ccm;150;159;148;146;149;130;155;151;164;153 12:12:10 INFO - PROCESS | 6694 | |12;stanford-crypto-pbkdf2;174;174;176;173;174;173;171;172;170;170 12:12:10 INFO - PROCESS | 6694 | |13;stanford-crypto-sha256-iterative;69;69;69;69;70;70;69;68;67;66 12:12:10 INFO - PROCESS | 6694 | __end_tp_report 12:12:10 INFO - PROCESS | 6694 | __start_cc_report 12:12:10 INFO - PROCESS | 6694 | _x_x_mozilla_cycle_collect,2313 12:12:10 INFO - PROCESS | 6694 | __end_cc_report 12:12:10 INFO - PROCESS | 6694 | __startTimestamp1467141130509__endTimestamp 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | ------- Summary: start ------- 12:12:10 INFO - PROCESS | 6694 | Number of tests: 14 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | [#0] ai-astar Cycles:10 Average:133.40 Median:131.50 stddev:22.78 (17.3%) stddev-sans-first:24.04 12:12:10 INFO - PROCESS | 6694 | Values: 140.0 171.0 118.0 123.0 169.0 151.0 114.0 117.0 114.0 117.0 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | [#1] audio-beat-detection Cycles:10 Average:150.30 Median:148.50 stddev:28.91 (19.5%) stddev-sans-first:30.02 12:12:10 INFO - PROCESS | 6694 | Values: 167.0 134.0 126.0 181.0 127.0 124.0 150.0 134.0 213.0 147.0 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | [#2] audio-dft Cycles:10 Average:227.60 Median:214.50 stddev:31.08 (14.5%) stddev-sans-first:24.09 12:12:10 INFO - PROCESS | 6694 | Values: 288.0 285.0 215.0 212.0 213.0 215.0 212.0 211.0 214.0 211.0 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | [#3] audio-fft Cycles:10 Average:71.80 Median:66.50 stddev:18.72 (28.2%) stddev-sans-first:19.33 12:12:10 INFO - PROCESS | 6694 | Values: 84.0 79.0 60.0 114.0 59.0 58.0 66.0 67.0 82.0 49.0 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | [#4] audio-oscillator Cycles:10 Average:83.90 Median:81.00 stddev:13.58 (16.8%) stddev-sans-first:14.22 12:12:10 INFO - PROCESS | 6694 | Values: 90.0 89.0 97.0 73.0 72.0 72.0 98.0 72.0 70.0 106.0 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | [#5] imaging-gaussian-blur Cycles:10 Average:96.40 Median:91.00 stddev:12.80 (14.1%) stddev-sans-first:13.55 12:12:10 INFO - PROCESS | 6694 | Values: 94.0 90.0 92.0 90.0 92.0 90.0 93.0 89.0 103.0 131.0 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | [#6] imaging-darkroom Cycles:10 Average:97.80 Median:98.00 stddev:0.63 (0.6%) stddev-sans-first:0.60 12:12:10 INFO - PROCESS | 6694 | Values: 97.0 98.0 98.0 98.0 99.0 97.0 98.0 98.0 98.0 97.0 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | [#7] imaging-desaturate Cycles:10 Average:102.50 Median:103.00 stddev:1.78 (1.7%) stddev-sans-first:1.80 12:12:10 INFO - PROCESS | 6694 | Values: 104.0 101.0 101.0 101.0 101.0 105.0 105.0 102.0 101.0 104.0 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | [#8] json-parse-financial Cycles:10 Average:57.80 Median:58.00 stddev:2.25 (3.9%) stddev-sans-first:2.39 12:12:10 INFO - PROCESS | 6694 | Values: 58.0 57.0 59.0 57.0 59.0 57.0 58.0 58.0 53.0 62.0 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | [#9] json-stringify-tinderbox Cycles:10 Average:84.90 Median:77.50 stddev:16.84 (21.7%) stddev-sans-first:17.10 12:12:10 INFO - PROCESS | 6694 | Values: 71.0 80.0 118.0 115.0 78.0 76.0 77.0 78.0 77.0 79.0 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | [#10] stanford-crypto-aes Cycles:10 Average:91.30 Median:90.00 stddev:5.29 (5.9%) stddev-sans-first:5.48 12:12:10 INFO - PROCESS | 6694 | Values: 88.0 90.0 90.0 90.0 89.0 88.0 92.0 90.0 106.0 90.0 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | [#11] stanford-crypto-ccm Cycles:10 Average:150.50 Median:152.00 stddev:9.01 (5.9%) stddev-sans-first:9.55 12:12:10 INFO - PROCESS | 6694 | Values: 150.0 159.0 148.0 146.0 149.0 130.0 155.0 151.0 164.0 153.0 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.70 Median:173.50 stddev:1.95 (1.1%) stddev-sans-first:2.01 12:12:10 INFO - PROCESS | 6694 | Values: 174.0 174.0 176.0 173.0 174.0 173.0 171.0 172.0 170.0 170.0 12:12:10 INFO - PROCESS | 6694 | 12:12:10 INFO - PROCESS | 6694 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:1.26 (1.8%) stddev-sans-first:1.33 12:12:10 INFO - PROCESS | 6694 | Values: 69.0 69.0 69.0 69.0 70.0 70.0 69.0 68.0 67.0 66.0 12:12:10 INFO - PROCESS | 6694 | -------- Summary: end -------- 12:12:10 INFO - PROCESS | 6694 | 12:12:11 INFO - TEST-INFO | 6694: exit 0 12:12:11 INFO - TEST-OK | kraken | took 61959ms 12:12:11 INFO - SUITE-END | took 1177s 12:12:11 INFO - Completed test suite (00:19:37) 12:12:11 INFO - javascript benchmark 12:12:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2784.262170524811, 2827.406244688615, 2839.2475993861644, 2864.47007303649, 2901.9274518137045, 4613.8615841524725, 4686.671932964694, 4733.55031272029, 4790.616750659948, 4831.985034379139, 4627.190241707704, 4696.694714843533, 4753.8098476061, 4790.0, 4852.975735121319, 4342.54403287655, 4403.955960440403, 4406.383106365111, 4450.354698568722, 4600.953990460086, 4542.023464955034, 4584.816607335702, 4589.885252868688, 4636.313297848458, 4654.534546545352, 4291.742495450268, 4369.563043695637, 4380.3210502372185, 4475.328700694889, 4499.212637788374, 4309.202797482476, 4317.136572685476, 4325.329573916048, 4342.826286948518, 4495.820167193308, 4345.826166953318, 4410.0077482566485, 4412.139632771611, 4447.08834688888, 4454.376387305748, 4605.332226827125, 4636.698614590041, 4642.930356044628, 4664.790084446208, 4696.882577935579, 5192.688438693691, 5231.136862417697, 5239.318888544503, 5241.73791310433, 5312.2828418163435, 4433.9113217735785, 5249.42256351801, 5307.416184219733, 5354.303940487712, 5366.973165134149, 4788.832390866338, 4928.0390323886495, 4971.726555039471, 4989.625778066623, 5019.623528235397, 5469.343678758495, 5471.316085489314, 5479.232907392969, 5489.58828087887, 5523.420040895729, 5192.92210616841, 5207.609429292818, 5217.530422262014, 5218.817341393033, 5324.760385782649, 5100.362454693163, 5140.614453915971, 5259.947400525945, 5295.205719142159, 5308.946910530923, 5156.123459011977, 5173.3533308336455, 5193.480651934777, 5225.372955245395, 5244.501772331622, 5269.0252303324005, 5297.735113244323, 5311.7078560678765, 5323.600729945269, 5336.03951288764, 4183.288840897055, 4240.1731662326165, 4245.469316335458, 4251.829926802893, 4318.028443600165, 3806.4861243731743, 3852.730308878408, 3855.978165786069, 3872.7095467840027, 3906.277338692351, 4669.976650116796, 4698.201305778026, 4720.504347043511, 4762.166620841339, 4773.188557945157, 5130.4100028497205, 5219.956008798255, 5223.373195216598, 5245.370555533361, 5268.0, 4645.605123564535, 4667.13657973276, 4696.060787842445, 4722.598579120813, 4769.59458446029, 4700.88247793801, 4713.151632706145, 4714.740689262055, 4717.646176536773, 4733.313669517969, 5283.128283833278, 5511.696856672841, 5519.806806761744, 5535.778568857281, 5571.442855714477, 5097.61767867404, 5234.607404444606, 5326.0679381107375, 5333.7333133343955, 5382.192671099367, 5572.86067848307, 5573.0, 5574.0, 5596.4123767003875, 5611.607187496836, 5037.622178336713, 5248.553872920845, 5273.987654074435, 5288.0, 5330.9466905332, 5093.18509038552, 5113.769880355468, 5236.816711415082, 5267.157254839208, 5294.364676238876, 3957.980210098931, 5048.419431765394, 5055.69665820052, 5101.387833460009, 5161.716105614228, 4206.747595144178, 4942.57988071018, 5092.9745351273, 5094.286799848099, 5113.28888292229, 4963.329950456642, 5068.138416469283, 5068.239764035424, 5069.112905241494, 5131.281620573197, 5179.430262671028, 5199.0, 5208.791648334176, 5219.947800522098, 5224.373075230997, 4926.113299605962, 4947.455779864284, 5035.370578677665, 5054.469280725618, 5058.418281897629], "value": 4821.061961733941, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.42938812192625, 414.7656574035669, 441.2719013627514, 455.62411010916003, 457.25467487994564, 30162.698373016265, 40439.797801011024, 40512.594874051254, 41148.0, 41234.38148427776, 39827.80086099565, 40493.0, 41777.373339399936, 41827.79086104565, 41831.790841045826, 8451.95774021129, 9270.490123043246, 9487.430754154739, 9501.952490237574, 9533.904660953405, 11364.090872730203, 11643.883561164364, 11682.883171168307, 11705.53177872884, 11768.646940591752, 11579.305241685484, 11641.941790291037, 11666.708332291675, 11671.649850504498, 11708.004819590346, 11486.138539609521, 11578.305301681841, 11586.362750048744, 11600.941995290053, 11610.883891161107, 11229.494672739746, 11381.772364552746, 11384.97535221834, 11389.48747306365, 11393.316401015965, 33275.0, 39917.80041099805, 39947.600523994675, 39956.0, 40059.799701001306, 3317.6350601433824, 3341.081202669261, 3380.4422270325367, 3406.931861362784, 3807.5803200358196, 30647.233819154477, 31242.218944526343, 31464.213394665087, 31547.053588392384, 31649.52525712116, 3166.857491412869, 3175.364927014606, 3189.1867573768773, 3221.166811494314, 3274.983625081883, 489777.0, 492668.0, 494189.0, 494190.9999999964, 494967.0, 55383.723081384334, 55833.60415989551, 55853.72073139608, 55889.72055139698, 55955.72022139863, 59507.10739338913, 59766.70116649389, 59767.0, 59833.401665983656, 60143.0, 15615.531534053996, 15812.841871581137, 15836.049837009816, 15874.682506349809, 15874.920625396799, 12349.259044457362, 12444.128910976326, 12445.688857778629, 12461.75076498483, 12477.876991070847, 14066.85933140676, 14679.853201468062, 14702.382737898823, 14792.334344954288, 14981.250937453084, 6286.245650521967, 6390.744097127466, 6449.742010319628, 6451.451626611791, 6479.611223326521, 755.712829124929, 775.9137207908973, 880.859062549989, 920.9501168667724, 931.3527098666498, 29123.708762912524, 29503.38025213252, 29748.40503189924, 29806.40387192244, 29947.59418681851, 938.9154976052188, 1019.8011387779312, 1031.6904928521415, 1076.520948178069, 1105.4638500327335, 13273.867261327456, 14734.557963261119, 15370.462033828762, 15450.150241736588, 15610.45363412275, 626801.0, 637401.0, 640641.0, 641300.0, 641483.0, 58097.70951145217, 58222.0, 58257.70871145702, 58316.708416457644, 58463.0, 1305.0473154597198, 1340.1557019077918, 1346.2730125732191, 1361.9114757540926, 1373.2790285100402, 26862.328441789112, 28937.56593651139, 29084.27289317784, 29691.703082968892, 29764.10707678773, 48952.265716015, 49556.008879822926, 49585.0, 49603.50396496133, 49724.50275497199, 569713.0, 587917.0, 588193.0, 588302.0, 588951.0, 33568.66431335655, 34460.0, 39551.0, 40143.795686129466, 40540.9729513529, 44799.32801008053, 47815.0, 47959.0, 48078.5192148088, 48624.0, 38207.0, 38909.10908909087, 38959.0, 39504.407433889086, 39569.406458903715, 38015.61984380121, 38193.61806382012, 38474.807625961694, 38539.42190867195, 38913.2217355657, 29254.268643283238, 36881.44677829781, 37310.0, 37312.44031339478, 37444.81277593595, 28515.714842851306, 32786.0, 33627.49558756669, 34167.65832341744, 34520.13699657529, 21457.678134828304, 24248.63627045631, 24481.877590611934, 24561.754382455947, 25306.746932531176], "value": 19893.21435173051, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.2227177046857, 343.5276494819623, 353.39569336434704, 354.42936871636647, 366.98165091745403, 14944.953853230278, 17948.46154615362, 19923.701144482828, 20019.999000049975, 20052.0, 16247.025178489299, 19160.808391916078, 20532.281370152046, 20840.270590529333, 21267.0, 5504.697241651707, 5774.913376299359, 5813.476787089161, 5817.360090390053, 5819.388964158766, 5278.656887302313, 5779.566532510057, 5785.971070144665, 5795.507381872546, 5805.100209467518, 5704.857378565527, 5740.454656807597, 5742.770289188427, 5746.741396637161, 5749.942500574983, 4814.518548145193, 5505.174223866432, 5512.4211957744665, 5632.845266720313, 5917.58576899615, 5040.974795126038, 5487.780488780444, 5628.04323265046, 5731.283589551306, 5803.245578074865, 12918.0, 13075.934620326932, 13172.946164306832, 13175.34123293841, 14394.640133996629, 2130.488682716137, 2217.268301460514, 2316.316686577447, 2503.8372505787247, 3013.3671928894737, 9351.859722104175, 9508.572114254875, 9833.4099954003, 10855.51150198243, 15982.961107527974, 1779.7953235377968, 1817.1731862002757, 1837.8070302618166, 1870.962580748391, 1871.840893524052, 89330.5533472335, 104914.0, 118318.0, 123469.38265308616, 126954.3652281742, 9023.729288121367, 10838.837417438745, 10896.455177241265, 10987.285826421257, 11197.66407007791, 12095.0, 13573.25347105899, 14039.157650541, 14066.43734250639, 14075.085119467201, 8139.511629302261, 8372.413931024892, 8387.245147936594, 8430.45202061864, 8437.662493500315, 7389.935535455478, 7877.778944263649, 8338.291245244101, 8340.582970851434, 8482.448640838325, 4326.074896670952, 4356.978215108968, 4358.237308471068, 4366.388705581221, 4437.955620443819, 1681.058148240579, 1718.8109307976113, 1728.2309372329194, 1731.3334366269078, 1739.5390221591288, 657.1063353838775, 714.2464699741719, 720.1574158234878, 818.5866137600474, 831.6673330667782, 15545.533633990999, 18086.638267234583, 20475.795242047687, 20831.37505874826, 20910.059047342907, 855.935750014945, 862.8921384826897, 870.3428911172151, 876.1063715010654, 909.3498148823622, 10634.73413164677, 10805.621803236849, 10860.45697715111, 10887.618933337453, 10941.179411544166, 539582.3020884871, 620870.0, 631642.0, 637348.0, 637390.0, 79658.61024169285, 123756.38121809333, 134767.56627036014, 139694.30152849172, 148104.5189548091, 893.2452077994085, 942.1050002497792, 957.5595226195888, 958.6932181701789, 961.5048250151178, 15159.469418570297, 18293.81706182921, 18544.25822967066, 18547.72178417298, 18645.90677046606, 53329.20006199832, 53910.92178156493, 54427.425309341146, 57176.0, 57329.42670573241, 84779.15220847713, 84799.0, 85270.57364713385, 85557.57221214104, 86298.0, 6382.393672601095, 7753.4960227585025, 7804.2585954334245, 7834.138244793182, 8008.4783891060515, 11802.52789888395, 12319.63041108768, 12432.378381081091, 12532.0, 12535.310557919402, 10669.093127084285, 10793.892061079288, 10927.672169834917, 10932.01611854905, 11082.33505989611, 10442.63450779219, 10615.628453004108, 10696.679099627023, 10771.569137234736, 10871.456427178451, 10784.0, 10798.892011079788, 10918.344899306067, 11101.611443599435, 11165.441727913409, 7749.573773442515, 7756.1468238494845, 7804.9609751950875, 7983.5209887406945, 7993.400494963017, 9891.604335826487, 10842.78314433691, 10850.0777433919, 10972.23194376386, 10985.066269367193], "value": 9093.360243119952, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1292.0180662696348, 1458.3801884199213, 1486.814148231471, 1657.187977890833, 1849.426677729903, 7519.849603007937, 8269.875951860728, 8509.702160424371, 8539.871901921477, 8646.52444115575, 8304.95847520763, 8390.874136887951, 8489.91510084899, 8507.319414446845, 8524.977002759677, 1660.3192690996693, 2069.8447616428753, 2217.170834061901, 2254.3124347074136, 2264.116994372196, 2069.141306357867, 2089.9863566170397, 2093.3510611710344, 2184.715986921698, 2298.643710224918, 2250.707408036953, 2257.4582100295975, 2261.705978222837, 2265.8187345012443, 2268.5689718953427, 2211.6682497625407, 2228.55428914217, 2260.2202240227093, 2265.76209498002, 2269.2511471214625, 1947.1821834829263, 2156.1267686586957, 2204.0, 2242.091952759135, 2251.313349428424, 8526.976762788505, 8616.181462761027, 8617.784555386103, 8627.741167764978, 8628.439151455135, 4336.631386332134, 5072.467390923974, 5092.134337162691, 5114.539691427789, 5132.948670513284, 3297.0768184908266, 3412.1128506588216, 3491.3017396520545, 3500.422430298998, 3536.3634545781747, 1008.9545970431349, 1025.3796453145815, 1034.4310629153936, 1040.849076883855, 1047.5704960966043, 11159.330440173535, 11443.771124577544, 11481.253718508271, 11482.598109066226, 11487.023602993755, 3849.4418309345547, 4267.786610669454, 4395.516493185747, 4500.122476117192, 4564.292534657134, 9471.0, 9523.04769523056, 9532.857007144898, 9533.285003624756, 9534.618615255313, 5079.87300317495, 5081.390233172043, 5082.644214904995, 5094.133997220481, 5094.898102037939, 4573.199690054227, 4574.085182963354, 4673.859784206479, 4690.577847993697, 4704.317873908332, 2981.4782413077623, 3629.546306711661, 3689.0224090616007, 3692.5199724036243, 3704.6480584344445, 1170.5142365918218, 1196.5094311332487, 1237.208186760474, 1253.9059570532247, 1255.711186427127, 769.9759320104248, 783.6904422752981, 810.4934415990007, 830.5847076461769, 848.8981322241247, 5209.4009188943755, 5590.496855282962, 5600.299962504687, 5888.882222355529, 5889.204957330706, 785.6935795039939, 895.3285036222833, 902.1609902790336, 902.4810733827944, 913.6573784830689, 3419.965800341965, 3678.576963649161, 3856.267309211241, 3903.902402439962, 3920.882373528799, 10050.447225402528, 10540.683779486628, 10549.314294570828, 10610.734731631617, 10650.254482186172, 10326.535305911102, 10371.377717336984, 10531.315464494783, 10542.42016689074, 10548.894511054945, 7776.688932442752, 7777.300042996045, 7854.136045035156, 7955.204479552114, 7966.123726390092, 5629.127485239795, 5672.517835983987, 5685.658860468385, 5692.829215123553, 5882.558808089325, 8337.874931876147, 9343.018983006687, 9707.126358627474, 9754.60981560758, 9808.921018687795, 8850.73447796567, 9962.0, 9971.650992215238, 9983.800323993624, 9987.0, 6787.66061696923, 6941.305869413019, 6977.406920411822, 6979.092717946634, 6980.895286570807, 7270.600116993616, 7672.693092276247, 7970.4022198336515, 8031.759047228593, 8161.673533058611, 6280.497560195082, 6887.311268873073, 6976.895346569704, 7173.461990350849, 7405.148407933157, 7015.403690686348, 7022.08712867324, 7219.747308844166, 7255.056842610426, 7257.745978890714, 6711.194656641234, 6763.492738044568, 6847.383735463832, 6852.211995620568, 6866.622335771581, 4664.4869064403565, 4822.638302127284, 4897.240927656219, 4899.975500122477, 4928.162212423825, 4507.75207363585, 4861.368022157097, 4882.926756098733, 4973.204287314013, 5059.0], "value": 4370.080165388721, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [267.82189843753906, 273.3480648652963, 280.08552077467067, 284.54880111431714, 285.6757580146533, 3646.562412510496, 3920.980395098024, 4042.737222080563, 4070.1859628074394, 4070.9389359159563, 2926.89755858545, 3785.470034195208, 3943.7042221833403, 3996.3805610130403, 4189.748615083097, 3153.5269709543636, 3256.544083828266, 3258.094015001227, 3271.509273608966, 3284.8193349365774, 2803.4252978139534, 2822.562502812057, 3242.837858107097, 3252.7397808175406, 3305.0910999475095, 2583.6641236639302, 2914.591957126004, 3103.0845900459417, 3117.360941007088, 3274.590676165479, 2586.5602847515966, 2986.850657467118, 3093.566900633913, 3220.7423406127687, 3224.596925384327, 2611.386324213808, 2641.379275870169, 2891.8288093322026, 2895.3496506991023, 2927.838968856712, 3245.464498357768, 3704.573973992998, 3877.955804693302, 3915.158240978175, 3987.282289187974, 3232.4968889466277, 3388.423967925459, 3461.826908654557, 3703.5555733312176, 3760.868369607078, 4063.6342729154517, 4310.849120280806, 4346.56534346566, 4357.803898824561, 4358.324459708719, 3100.891468798604, 3135.764817638686, 3145.858436370369, 3148.6379066407426, 3151.4169878572307, 3837.712171587142, 4024.812680259301, 4374.8906277343, 4449.888752781207, 4532.546745325441, 2880.76953843692, 3058.6788387219467, 3096.909586029421, 3111.2066423061974, 3143.685631436838, 2813.366992426688, 3189.2505261263586, 3201.199700074981, 3218.11501836992, 3222.4199644063824, 3170.397624451347, 3685.447182922583, 3697.6672099511175, 3698.0754811297174, 3722.1253005543676, 4148.79256037197, 4199.559046300155, 4204.843667991266, 4428.402165707653, 4465.642748580106, 955.0067929353534, 1018.1244130048152, 1064.1858977881927, 1104.8232282834708, 1108.952040321888, 701.2041333086983, 724.57250222368, 729.4930023633524, 753.2881427051384, 759.8252401947473, 3269.558609587723, 3875.457435958968, 3875.7093218008763, 3913.158670885773, 3926.8415817333953, 3700.8149592520804, 3996.300647386696, 4249.873783447389, 4409.713368631029, 4496.820127194941, 3560.9821950890077, 3678.3011227867155, 3786.444150198605, 3849.287881741887, 3855.1711382052995, 1997.7103320018807, 2194.967075493869, 2296.622026783916, 2305.204704376987, 2318.6406107053435, 3862.9227415451533, 4051.6556092731857, 4505.752183629932, 4512.571305725951, 4575.931361029588, 4178.91642167155, 4187.434696315959, 4418.381426600278, 4517.593416592523, 4541.5231400703115, 4208.747475151501, 4299.398084268205, 4547.272436410225, 4561.63506919439, 4567.269236922143, 4347.7173983690955, 4381.255186618218, 4395.978020109879, 4405.691601587858, 4467.486239082417, 3828.5788563258575, 4071.75569465833, 4202.285611446, 4368.978155109204, 4379.343098535118, 3703.370427027358, 3995.320795464836, 4544.909101818012, 4581.587657110743, 4583.679142459996, 2691.9596206057317, 3039.027511196396, 3180.348028654074, 3184.6910989532403, 3193.409219294345, 4523.5250298718165, 4547.977260113678, 4550.407357424875, 4551.589007407714, 4555.020670555847, 3689.6494832990825, 4425.030861266834, 4449.57729015743, 4463.9956009898815, 4470.731756094645, 3951.1702542466405, 4047.696422768363, 4272.487301523837, 4310.374995625554, 4455.821767129408, 3667.009907325061, 4302.290122129939, 4326.3943047973935, 4334.1981733379425, 4339.587739164775, 3588.156783155904, 4013.0769922917307, 4227.6406505447385, 4416.394327346752, 4423.34599240466, 3413.1637748751714, 3677.42999835026, 3760.8871733848027, 4299.861101396061, 4304.289792184254], "value": 3185.9932390197446, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.6266452135154, 728.4379384808292, 730.660242987011, 734.9191588925218, 736.2416710787891, 14346.282685865717, 15083.849161508382, 15100.622484437894, 15109.244537773122, 15204.543863684094, 13187.208767473958, 14018.0, 14045.297735113229, 14062.929685351559, 14063.859361406383, 687.1547995964966, 871.1984973824082, 882.0297672560197, 891.2513488669516, 891.4740303221098, 705.3334598804132, 842.1410161635129, 878.5343767803074, 881.4623079921242, 881.6870011146048, 937.2267878999852, 937.995310023449, 941.5668792355491, 943.306669597845, 943.9952800235989, 715.1775458223033, 737.7712908998174, 937.0067728208053, 937.4328531238605, 938.4322484896608, 664.3854434647932, 688.4114082459525, 689.975850845223, 691.2845205212581, 692.19705142035, 14721.337539810735, 14833.92583037078, 14847.703045939128, 14867.776983345258, 14887.627809304746, 13271.270080145589, 13534.984876134231, 13590.59228223145, 13592.86407135926, 13746.725065498635, 12017.45921433529, 12034.699132521757, 12185.451654675473, 12637.936810315981, 12678.87321126786, 3831.7892515911612, 4445.422095127613, 4467.709598876095, 4625.421822272216, 4644.581987621096, 100063.49968250185, 102018.0, 105498.47250763774, 106072.0, 106093.0, 9888.307818452784, 10509.632162874263, 10732.409717465614, 10913.454327283604, 10975.176861735401, 20841.583168336852, 20942.162313507593, 21187.36437906865, 21214.893925530272, 21277.936103194777, 7760.34037106841, 7826.02174728159, 7841.882371764428, 7853.371730261565, 7931.206879312138, 4315.611594956469, 4428.247197976351, 4440.844570440019, 4451.0, 4673.485916549177, 3656.6470405949967, 3911.9217615647526, 3913.099987002948, 3916.2559113768293, 3948.506436695413, 646.1438593863151, 703.6833424958687, 708.397861817451, 723.5875550936021, 723.6490302203532, 518.3883018038675, 520.4512560223618, 521.5175962234918, 557.8214971209175, 617.3425302053299, 8451.619677114544, 8692.783999760111, 9139.314551408672, 9579.377340472847, 9607.375520591138, 466.7269647256393, 566.8164634418304, 592.7273454211111, 624.1168746224133, 628.4061561824033, 3856.9228615427533, 3907.882763517099, 4052.696047796427, 4570.771461426915, 5006.74966251686, 147002.26498867437, 148741.0, 150563.0, 154799.0, 157444.0, 119038.4048079754, 122186.0, 123656.38171809263, 126159.99999999817, 126578.36710816571, 109058.0, 112190.0, 116148.99999999831, 117959.0, 124760.37619812206, 9723.513824308899, 9739.805203896025, 9744.12302892743, 9797.0, 9892.653757118467, 56653.15020274781, 58200.0, 60134.0, 60265.69867150636, 61969.0, 71318.0, 81071.0, 89507.0, 90025.0, 92200.53899730458, 13139.868601313865, 13174.47302107905, 13326.800097998344, 13345.532906348231, 13386.866131338562, 14751.631209219855, 14930.776038359216, 14936.103833770008, 15577.766333505233, 15644.843551564338, 13748.65628359299, 14020.859791402363, 14168.645783855485, 14495.927520362331, 14547.272636367927, 13869.514566990107, 13913.443462261395, 13928.303584820922, 14141.505047323295, 14276.357563909442, 13505.12216705911, 13835.86164138386, 14204.36080376365, 14313.928430357782, 14328.785068223777, 10081.848772268568, 10127.454701640283, 10660.89339106599, 11210.775784484102, 11337.26307789991, 9821.901780982098, 10462.04274157707, 10501.579936802442, 11176.776464470502, 11183.944080279873], "value": 7774.960002604937, "unit": "score"}], "name": "dromaeo_css", "value": 6748.049458675388, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 171.0, 118.0, 123.0, 169.0, 151.0, 114.0, 117.0, 114.0, 117.0], "value": 133.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 134.0, 126.0, 181.0, 127.0, 124.0, 150.0, 134.0, 213.0, 147.0], "value": 150.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [288.0, 285.0, 215.0, 212.0, 213.0, 215.0, 212.0, 211.0, 214.0, 211.0], "value": 227.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 79.0, 60.0, 114.0, 59.0, 58.0, 66.0, 67.0, 82.0, 49.0], "value": 71.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 97.0, 73.0, 72.0, 72.0, 98.0, 72.0, 70.0, 106.0], "value": 83.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 90.0, 92.0, 90.0, 92.0, 90.0, 93.0, 89.0, 103.0, 131.0], "value": 96.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 98.0, 98.0, 99.0, 97.0, 98.0, 98.0, 98.0, 97.0], "value": 97.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 101.0, 101.0, 101.0, 101.0, 105.0, 105.0, 102.0, 101.0, 104.0], "value": 102.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 59.0, 57.0, 59.0, 57.0, 58.0, 58.0, 53.0, 62.0], "value": 57.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 80.0, 118.0, 115.0, 78.0, 76.0, 77.0, 78.0, 77.0, 79.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 90.0, 90.0, 89.0, 88.0, 92.0, 90.0, 106.0, 90.0], "value": 91.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 159.0, 148.0, 146.0, 149.0, 130.0, 155.0, 151.0, 164.0, 153.0], "value": 150.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 176.0, 173.0, 174.0, 173.0, 171.0, 172.0, 170.0, 170.0], "value": 172.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 69.0, 70.0, 70.0, 69.0, 68.0, 67.0, 66.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1589.4999999999998, "alertThreshold": 2.0}]} 12:12:11 INFO - Return code: 0 12:12:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:12:11 INFO - # TBPL SUCCESS # 12:12:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:12:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:12:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:12:11 INFO - 2016-06-28 12:12:11.791 screenresolution[6698:116119] starting screenresolution argv=screenresolution get 12:12:11 INFO - 2016-06-28 12:12:11.795 screenresolution[6698:116119] Display 0: 1600x1200x32@60 12:12:11 INFO - 2016-06-28 12:12:11.801 screenresolution[6699:116122] starting screenresolution argv=screenresolution list 12:12:11 INFO - Available Modes on Display 0 12:12:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:12:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:12:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:12:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:12:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:12:12 INFO - Intel Iris: 12:12:12 INFO - Chipset Model: Intel Iris 12:12:12 INFO - Type: GPU 12:12:12 INFO - Bus: Built-In 12:12:12 INFO - VRAM (Dynamic, Max): 1536 MB 12:12:12 INFO - Vendor: Intel (0x8086) 12:12:12 INFO - Device ID: 0x0a2e 12:12:12 INFO - Revision ID: 0x0009 12:12:12 INFO - Displays: 12:12:12 INFO - Display: 12:12:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:12:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:12:12 INFO - Display Serial Number: 1600x1200 60 12:12:12 INFO - Main Display: Yes 12:12:12 INFO - Mirror: Off 12:12:12 INFO - Online: Yes 12:12:12 INFO - Rotation: Supported 12:12:12 INFO - Return code: 0 12:12:12 INFO - Running post-action listener: _resource_record_post_action 12:12:12 INFO - ##### 12:12:12 INFO - ##### Finished run-tests step (success) 12:12:12 INFO - ##### 12:12:12 INFO - Running post-run listener: _resource_record_post_run 12:12:12 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 1007616; Write bytes: 706321408; Read time: 9; Write time: 1818 12:12:12 INFO - TinderboxPrint: CPU usage
53.9% 12:12:12 INFO - TinderboxPrint: I/O read bytes / time
1,007,616 / 9 12:12:12 INFO - TinderboxPrint: I/O write bytes / time
706,321,408 / 1,818 12:12:12 INFO - TinderboxPrint: CPU idle
2,240.9 (46.1%) 12:12:12 INFO - TinderboxPrint: CPU system
977.1 (20.1%) 12:12:12 INFO - TinderboxPrint: CPU user
1,639.6 (33.8%) 12:12:12 INFO - TinderboxPrint: Swap in / out
407,773,184 / 0 12:12:12 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 407536128; Write bytes: 408655872; Read time: 31674; Write time: 715 12:12:12 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1007616; Write bytes: 294403072; Read time: 9; Write time: 1058 12:12:12 INFO - Running post-run listener: _upload_blobber_files 12:12:12 INFO - Blob upload gear active. 12:12:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:12:12 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 12:12:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:12:12 INFO - (blobuploader) - INFO - Open directory for files ... 12:12:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:12:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:12:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:15 INFO - (blobuploader) - INFO - Done attempting. 12:12:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:12:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:12:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:16 INFO - (blobuploader) - INFO - Done attempting. 12:12:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:12:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:12:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:19 INFO - (blobuploader) - INFO - Done attempting. 12:12:19 INFO - (blobuploader) - INFO - Iteration through files over. 12:12:19 INFO - Return code: 0 12:12:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:12:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:12:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7532733aed1ac79067ed98f26d4eed7a976545ce49ab05baee516244c222e5a370eca3f3e523ff21800386168b61b3ad7dd7c50a07592af80d9c9a46ca318890", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/02e30c1f298ca0d469a0a4fadd22065e122d3b21aca8637d123314b2d3848c244cb3a2bb95a29240658ae12cdffaa5c82971ac7a4430666cbad0d67cb346ed42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d4514dd071e4c3fbab5437b233239d428960e8dd89b7ecdc7c8e1e896dc4bc09dd49937380ad9c190335e74b62c631a544aed0b89d5c30366ed45db4a6aaafa0"} 12:12:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:12:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:12:19 INFO - Contents: 12:12:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7532733aed1ac79067ed98f26d4eed7a976545ce49ab05baee516244c222e5a370eca3f3e523ff21800386168b61b3ad7dd7c50a07592af80d9c9a46ca318890", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/02e30c1f298ca0d469a0a4fadd22065e122d3b21aca8637d123314b2d3848c244cb3a2bb95a29240658ae12cdffaa5c82971ac7a4430666cbad0d67cb346ed42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d4514dd071e4c3fbab5437b233239d428960e8dd89b7ecdc7c8e1e896dc4bc09dd49937380ad9c190335e74b62c631a544aed0b89d5c30366ed45db4a6aaafa0"} 12:12:19 INFO - Running post-run listener: copy_logs_to_upload_dir 12:12:19 INFO - Copying logs to upload dir... 12:12:19 INFO - mkdir: /builds/slave/test/build/upload/logs 12:12:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1634.116462 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 14 secs) (at 2016-06-28 12:12:19.742902) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-28 12:12:19.746165) ========= 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.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7532733aed1ac79067ed98f26d4eed7a976545ce49ab05baee516244c222e5a370eca3f3e523ff21800386168b61b3ad7dd7c50a07592af80d9c9a46ca318890", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/02e30c1f298ca0d469a0a4fadd22065e122d3b21aca8637d123314b2d3848c244cb3a2bb95a29240658ae12cdffaa5c82971ac7a4430666cbad0d67cb346ed42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d4514dd071e4c3fbab5437b233239d428960e8dd89b7ecdc7c8e1e896dc4bc09dd49937380ad9c190335e74b62c631a544aed0b89d5c30366ed45db4a6aaafa0"} build_url:https://queue.taskcluster.net/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.012534 build_url: 'https://queue.taskcluster.net/v1/task/B3rSNSMRRJCv6pbwAi6uJw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7532733aed1ac79067ed98f26d4eed7a976545ce49ab05baee516244c222e5a370eca3f3e523ff21800386168b61b3ad7dd7c50a07592af80d9c9a46ca318890", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/02e30c1f298ca0d469a0a4fadd22065e122d3b21aca8637d123314b2d3848c244cb3a2bb95a29240658ae12cdffaa5c82971ac7a4430666cbad0d67cb346ed42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d4514dd071e4c3fbab5437b233239d428960e8dd89b7ecdc7c8e1e896dc4bc09dd49937380ad9c190335e74b62c631a544aed0b89d5c30366ed45db4a6aaafa0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-28 12:12:19.786301) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 12:12:19.786655) ========= 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.mNX6uTZQ8N/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.z9vZVwjaKd/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.005688 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 12:12:19.838917) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 12:12:19.839744) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 12:12:19.840615) ========= ========= Total master_lag: 0.36 =========