builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0103 starttime: 1471876544.62 results: success (0) buildid: 20160822051814 builduid: 8bbb730db49f4ddf9a36b1237b0ab0c1 revision: 662270014cb72f82af415385f2f0bef5b135ec04 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 07:35:44.619569) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 07:35:44.620028) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 07:35:44.620305) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 07:35:44.677873) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 07:35:44.678263) ========= 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.JGgGOnQuS0/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.O8baxWCIdw/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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-08-22 07:35:44-- 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% 15.5M=0.001s 2016-08-22 07:35:45 (15.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.475699 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 07:35:45.178467) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 07:35:45.178797) ========= 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.JGgGOnQuS0/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.O8baxWCIdw/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.027148 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 07:35:45.227559) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-08-22 07:35:45.227934) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 662270014cb72f82af415385f2f0bef5b135ec04 --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 662270014cb72f82af415385f2f0bef5b135ec04 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JGgGOnQuS0/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.O8baxWCIdw/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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-08-22 07:35:45,304 truncating revision to first 12 chars 2016-08-22 07:35:45,304 Setting DEBUG logging. 2016-08-22 07:35:45,304 attempt 1/10 2016-08-22 07:35:45,304 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/662270014cb7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 07:35:46,649 unpacking tar archive at: fx-team-662270014cb7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.586764 ========= master_lag: 4.45 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-08-22 07:35:51.264635) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 07:35:51.264928) ========= script_repo_revision: 662270014cb72f82af415385f2f0bef5b135ec04 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 07:35:51.265329) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 07:35:51.265618) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 07:35:51.408559) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-22 07:35:51.408826) ========= /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.JGgGOnQuS0/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.O8baxWCIdw/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 07:35:51 INFO - MultiFileLogger online at 20160822 07:35:51 in /builds/slave/test 07:35:51 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 07:35:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:35:51 INFO - {'append_to_log': False, 07:35:51 INFO - 'base_work_dir': '/builds/slave/test', 07:35:51 INFO - 'blob_upload_branch': 'Fx-Team', 07:35:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:35:51 INFO - 'branch': 'Fx-Team', 07:35:51 INFO - 'buildbot_json_path': 'buildprops.json', 07:35:51 INFO - 'config_files': ('talos/mac_config.py',), 07:35:51 INFO - 'default_actions': ('clobber', 07:35:51 INFO - 'read-buildbot-config', 07:35:51 INFO - 'download-and-extract', 07:35:51 INFO - 'populate-webroot', 07:35:51 INFO - 'create-virtualenv', 07:35:51 INFO - 'install', 07:35:51 INFO - 'run-tests'), 07:35:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:35:51 INFO - 'download_minidump_stackwalk': True, 07:35:51 INFO - 'download_symbols': 'ondemand', 07:35:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:35:51 INFO - 'tooltool.py': '/tools/tooltool.py', 07:35:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:35:51 INFO - '/tools/misc-python/virtualenv.py')}, 07:35:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:35:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:35:51 INFO - 'installer_path': 'installer.exe', 07:35:51 INFO - 'log_level': 'info', 07:35:51 INFO - 'log_name': 'talos', 07:35:51 INFO - 'log_to_console': True, 07:35:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:35:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:35:51 INFO - 'opt_config_files': (), 07:35:51 INFO - 'pip_index': False, 07:35:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:35:51 INFO - 'cmd': ('bash', 07:35:51 INFO - '-c', 07:35:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:35:51 INFO - 'enabled': True, 07:35:51 INFO - 'halt_on_failure': False, 07:35:51 INFO - 'name': 'check_screen_resolution'},), 07:35:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:35:51 INFO - 'cmd': ('bash', 07:35:51 INFO - '-c', 07:35:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:35:51 INFO - 'enabled': True, 07:35:51 INFO - 'halt_on_failure': False, 07:35:51 INFO - 'name': 'check_screen_resolution'},), 07:35:51 INFO - 'run_cmd_checks_enabled': True, 07:35:51 INFO - 'sps_profile': False, 07:35:51 INFO - 'sps_profile_interval': 0, 07:35:51 INFO - 'suite': 'dromaeojs', 07:35:51 INFO - 'system_bits': '32', 07:35:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:35:51 INFO - 'title': 't-yosemite-r7-0103', 07:35:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:35:51 INFO - 'use_talos_json': True, 07:35:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:35:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:35:51 INFO - 'work_dir': 'build'} 07:35:51 INFO - [mozharness: 2016-08-22 14:35:51.608303Z] Running clobber step. 07:35:51 INFO - Running pre-action listener: _resource_record_pre_action 07:35:51 INFO - Running main action method: clobber 07:35:51 INFO - rmtree: /builds/slave/test/build 07:35:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:35:53 INFO - Running post-action listener: _resource_record_post_action 07:35:53 INFO - [mozharness: 2016-08-22 14:35:53.712940Z] Finished clobber step (success) 07:35:53 INFO - [mozharness: 2016-08-22 14:35:53.713074Z] Running read-buildbot-config step. 07:35:53 INFO - Running pre-action listener: _resource_record_pre_action 07:35:53 INFO - Running main action method: read_buildbot_config 07:35:53 INFO - Using buildbot properties: 07:35:53 INFO - { 07:35:53 INFO - "project": "", 07:35:53 INFO - "product": "firefox", 07:35:53 INFO - "script_repo_revision": "production", 07:35:53 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:35:53 INFO - "repository": "", 07:35:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:35:53 INFO - "buildid": "20160822051814", 07:35:53 INFO - "pgo_build": "False", 07:35:53 INFO - "basedir": "/builds/slave/test", 07:35:53 INFO - "buildnumber": 95, 07:35:53 INFO - "slavename": "t-yosemite-r7-0103", 07:35:53 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:35:53 INFO - "platform": "yosemite_r7", 07:35:53 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:35:53 INFO - "branch": "fx-team", 07:35:53 INFO - "repo_path": "integration/fx-team", 07:35:53 INFO - "revision": "662270014cb72f82af415385f2f0bef5b135ec04", 07:35:53 INFO - "stage_platform": "macosx64", 07:35:53 INFO - "builduid": "8bbb730db49f4ddf9a36b1237b0ab0c1", 07:35:53 INFO - "slavebuilddir": "test" 07:35:53 INFO - } 07:35:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 07:35:53 INFO - Running post-action listener: _resource_record_post_action 07:35:53 INFO - [mozharness: 2016-08-22 14:35:53.716299Z] Finished read-buildbot-config step (success) 07:35:53 INFO - [mozharness: 2016-08-22 14:35:53.716430Z] Running download-and-extract step. 07:35:53 INFO - Running pre-action listener: _resource_record_pre_action 07:35:53 INFO - Running main action method: download_and_extract 07:35:53 INFO - mkdir: /builds/slave/test/build/tests 07:35:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:53 INFO - trying https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 07:35:53 INFO - Downloading https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 07:35:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 07:35:54 INFO - Downloaded 1199 bytes. 07:35:54 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 07:35:54 INFO - Using the following test package requirements: 07:35:54 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:35:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:35:54 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 07:35:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:35:54 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 07:35:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:35:54 INFO - u'jsshell-mac.zip'], 07:35:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:35:54 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 07:35:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:35:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:35:54 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 07:35:54 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:35:54 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 07:35:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:35:54 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 07:35:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:35:54 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 07:35:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:35:54 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:54 INFO - trying https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:35:54 INFO - Downloading https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:35:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:35:55 INFO - Downloaded 16351892 bytes. 07:35:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 07:36:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:02 INFO - trying https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:36:02 INFO - Downloading https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:36:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:36:03 INFO - Downloaded 16351892 bytes. 07:36:03 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 07:36:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:09 INFO - trying https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 07:36:09 INFO - Downloading https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 07:36:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:36:11 INFO - Downloaded 14413981 bytes. 07:36:11 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 07:36:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:11 INFO - trying https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:36:11 INFO - Downloading https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 07:36:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 07:36:14 INFO - Downloaded 111476631 bytes. 07:36:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:36:14 INFO - mkdir: /builds/slave/test/properties 07:36:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:36:14 INFO - Writing to file /builds/slave/test/properties/build_url 07:36:14 INFO - Contents: 07:36:14 INFO - build_url:https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:36:15 INFO - Running post-action listener: _resource_record_post_action 07:36:15 INFO - Running post-action listener: set_extra_try_arguments 07:36:15 INFO - [mozharness: 2016-08-22 14:36:15.206224Z] Finished download-and-extract step (success) 07:36:15 INFO - [mozharness: 2016-08-22 14:36:15.206341Z] Running populate-webroot step. 07:36:15 INFO - Running pre-action listener: _resource_record_pre_action 07:36:15 INFO - Running main action method: populate_webroot 07:36:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:36:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:36:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:36:15 INFO - '--tppagecycles', 07:36:15 INFO - '7'], 07:36:15 INFO - 'tests': ['tsvgm']}}, 07:36:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:36:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:36:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:36:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:36:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:36:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:36:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:15 INFO - 'talos_options': ['--disable-e10s'], 07:36:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:36:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:36:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:15 INFO - 'talos_options': ['--disable-e10s'], 07:36:15 INFO - 'tests': ['damp', 'tps']}, 07:36:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:15 INFO - 'tests': ['damp', 'tps']}, 07:36:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:36:15 INFO - 'tests': ['dromaeo_dom']}, 07:36:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:36:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:36:15 INFO - 'tests': ['basic_compositor_video']}, 07:36:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:36:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:36:15 INFO - 'tests': ['a11yr', 07:36:15 INFO - 'ts_paint', 07:36:15 INFO - 'tpaint', 07:36:15 INFO - 'sessionrestore', 07:36:15 INFO - 'sessionrestore_no_auto_restore', 07:36:15 INFO - 'tabpaint']}, 07:36:15 INFO - 'other-e10s': {'tests': ['a11yr', 07:36:15 INFO - 'ts_paint', 07:36:15 INFO - 'tpaint', 07:36:15 INFO - 'sessionrestore', 07:36:15 INFO - 'sessionrestore_no_auto_restore', 07:36:15 INFO - 'tabpaint']}, 07:36:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:36:15 INFO - 'ts_paint', 07:36:15 INFO - 'tpaint', 07:36:15 INFO - 'sessionrestore', 07:36:15 INFO - 'sessionrestore_no_auto_restore', 07:36:15 INFO - 'tabpaint']}, 07:36:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:36:15 INFO - 'ts_paint', 07:36:15 INFO - 'tpaint', 07:36:15 INFO - 'sessionrestore', 07:36:15 INFO - 'sessionrestore_no_auto_restore', 07:36:15 INFO - 'tabpaint']}, 07:36:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:36:15 INFO - 'tests': ['a11yr', 07:36:15 INFO - 'ts_paint', 07:36:15 INFO - 'tpaint', 07:36:15 INFO - 'sessionrestore', 07:36:15 INFO - 'sessionrestore_no_auto_restore', 07:36:15 INFO - 'tabpaint']}, 07:36:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:36:15 INFO - 'tests': ['a11yr', 07:36:15 INFO - 'ts_paint', 07:36:15 INFO - 'tpaint', 07:36:15 INFO - 'sessionrestore', 07:36:15 INFO - 'sessionrestore_no_auto_restore', 07:36:15 INFO - 'tabpaint']}, 07:36:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:36:15 INFO - 'tests': ['tsvgx', 07:36:15 INFO - 'tsvgr_opacity', 07:36:15 INFO - 'tart', 07:36:15 INFO - 'tscrollx', 07:36:15 INFO - 'cart']}, 07:36:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:36:15 INFO - 'tsvgr_opacity', 07:36:15 INFO - 'tart', 07:36:15 INFO - 'tscrollx', 07:36:15 INFO - 'cart']}, 07:36:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:15 INFO - 'talos_options': ['--disable-e10s'], 07:36:15 INFO - 'tests': ['tp5o']}, 07:36:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:15 INFO - 'tests': ['tp5o']}, 07:36:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:15 INFO - 'talos_options': ['--disable-e10s', 07:36:15 INFO - '--xperf_path', 07:36:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:36:15 INFO - 'tests': ['tp5n']}, 07:36:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:15 INFO - 'talos_options': ['--xperf_path', 07:36:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:36:15 INFO - 'tests': ['tp5n']}}, 07:36:15 INFO - 'talos.zip': {'path': '', 07:36:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:36:15 INFO - Running post-action listener: _resource_record_post_action 07:36:15 INFO - [mozharness: 2016-08-22 14:36:15.225366Z] Finished populate-webroot step (success) 07:36:15 INFO - [mozharness: 2016-08-22 14:36:15.225483Z] Running create-virtualenv step. 07:36:15 INFO - Running pre-action listener: _resource_record_pre_action 07:36:15 INFO - Running main action method: create_virtualenv 07:36:15 INFO - Creating virtualenv /builds/slave/test/build/venv 07:36:15 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 07:36:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:36:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:36:15 INFO - Using real prefix '/tools/python27' 07:36:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:36:16 INFO - Installing distribute.............................................................................................................................................................................................done. 07:36:18 INFO - Installing pip.................done. 07:36:18 INFO - Return code: 0 07:36:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:36:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:18 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 0x10cba3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc38618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef934a0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb07d30>, '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 0x10c86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10caffb20>, '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.JGgGOnQuS0/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.O8baxWCIdw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 07:36:18 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 07:36:18 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 07:36:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JGgGOnQuS0/Render', 07:36:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:36:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:36:18 INFO - 'HOME': '/Users/cltbld', 07:36:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:36:18 INFO - 'LOGNAME': 'cltbld', 07:36:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:18 INFO - 'MOZ_NO_REMOTE': '1', 07:36:18 INFO - 'NO_EM_RESTART': '1', 07:36:18 INFO - 'PAGER': '/bin/cat', 07:36:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:36:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:18 INFO - 'PWD': '/builds/slave/test', 07:36:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:36:18 INFO - 'SHELL': '/bin/bash', 07:36:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8baxWCIdw/Listeners', 07:36:18 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 07:36:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:36:18 INFO - 'USER': 'cltbld', 07:36:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:36:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:36:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:18 INFO - 'XPC_FLAGS': '0x0', 07:36:18 INFO - 'XPC_SERVICE_NAME': '0', 07:36:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:36:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:19 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 07:36:19 INFO - Cleaning up... 07:36:19 INFO - Return code: 0 07:36:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cba3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc38618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef934a0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb07d30>, '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 0x10c86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10caffb20>, '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.JGgGOnQuS0/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.O8baxWCIdw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 07:36:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:36:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:36:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JGgGOnQuS0/Render', 07:36:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:36:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:36:19 INFO - 'HOME': '/Users/cltbld', 07:36:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:36:19 INFO - 'LOGNAME': 'cltbld', 07:36:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:19 INFO - 'MOZ_NO_REMOTE': '1', 07:36:19 INFO - 'NO_EM_RESTART': '1', 07:36:19 INFO - 'PAGER': '/bin/cat', 07:36:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:36:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:19 INFO - 'PWD': '/builds/slave/test', 07:36:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:36:19 INFO - 'SHELL': '/bin/bash', 07:36:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8baxWCIdw/Listeners', 07:36:19 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 07:36:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:36:19 INFO - 'USER': 'cltbld', 07:36:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:36:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:36:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:19 INFO - 'XPC_FLAGS': '0x0', 07:36:19 INFO - 'XPC_SERVICE_NAME': '0', 07:36:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:36:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:19 INFO - Downloading/unpacking psutil>=3.1.1 07:36:19 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 07:36:19 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 07:36:19 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 07:36:19 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 07:36:19 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 07:36:19 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 07:36:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:36:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:36:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:36:22 INFO - Installing collected packages: psutil 07:36:22 INFO - Running setup.py install for psutil 07:36:22 INFO - building 'psutil._psutil_osx' extension 07:36:22 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 07:36:22 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 07:36:22 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 07:36:22 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 07:36:22 INFO - building 'psutil._psutil_posix' extension 07:36:22 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 07:36:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:36:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:36:22 INFO - ^ 07:36:23 INFO - 1 warning generated. 07:36:23 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 07:36:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:36:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:36:23 INFO - Successfully installed psutil 07:36:23 INFO - Cleaning up... 07:36:23 INFO - Return code: 0 07:36:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:23 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 0x10cba3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc38618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef934a0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb07d30>, '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 0x10c86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10caffb20>, '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.JGgGOnQuS0/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.O8baxWCIdw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 07:36:23 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 07:36:23 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 07:36:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JGgGOnQuS0/Render', 07:36:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:36:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:36:23 INFO - 'HOME': '/Users/cltbld', 07:36:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:36:23 INFO - 'LOGNAME': 'cltbld', 07:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:23 INFO - 'MOZ_NO_REMOTE': '1', 07:36:23 INFO - 'NO_EM_RESTART': '1', 07:36:23 INFO - 'PAGER': '/bin/cat', 07:36:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:23 INFO - 'PWD': '/builds/slave/test', 07:36:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:36:23 INFO - 'SHELL': '/bin/bash', 07:36:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8baxWCIdw/Listeners', 07:36:23 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 07:36:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:36:23 INFO - 'USER': 'cltbld', 07:36:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:36:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:23 INFO - 'XPC_FLAGS': '0x0', 07:36:23 INFO - 'XPC_SERVICE_NAME': '0', 07:36:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:36:23 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 07:36:23 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 07:36:23 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 07:36:23 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 07:36:23 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 07:36:23 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 07:36:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:36:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:36:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:36:26 INFO - Installing collected packages: mozsystemmonitor 07:36:26 INFO - Running setup.py install for mozsystemmonitor 07:36:26 INFO - Successfully installed mozsystemmonitor 07:36:26 INFO - Cleaning up... 07:36:26 INFO - Return code: 0 07:36:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc38618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef934a0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb07d30>, '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 0x10c86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10caffb20>, '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.JGgGOnQuS0/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.O8baxWCIdw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 07:36:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:36:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:36:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JGgGOnQuS0/Render', 07:36:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:36:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:36:26 INFO - 'HOME': '/Users/cltbld', 07:36:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:36:26 INFO - 'LOGNAME': 'cltbld', 07:36:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:26 INFO - 'MOZ_NO_REMOTE': '1', 07:36:26 INFO - 'NO_EM_RESTART': '1', 07:36:26 INFO - 'PAGER': '/bin/cat', 07:36:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:36:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:26 INFO - 'PWD': '/builds/slave/test', 07:36:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:36:26 INFO - 'SHELL': '/bin/bash', 07:36:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8baxWCIdw/Listeners', 07:36:26 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 07:36:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:36:26 INFO - 'USER': 'cltbld', 07:36:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:36:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:36:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:26 INFO - 'XPC_FLAGS': '0x0', 07:36:26 INFO - 'XPC_SERVICE_NAME': '0', 07:36:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:36:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:26 INFO - Downloading/unpacking jsonschema==2.5.1 07:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:36:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:29 INFO - Downloading functools32-3.2.3-2.tar.gz 07:36:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:36:29 INFO - warning: no files found matching '*.txt' 07:36:29 INFO - no previously-included directories found matching 'build' 07:36:29 INFO - no previously-included directories found matching 'dist' 07:36:29 INFO - no previously-included directories found matching '.git*' 07:36:29 INFO - Installing collected packages: jsonschema, functools32 07:36:29 INFO - Running setup.py install for functools32 07:36:29 INFO - warning: no files found matching '*.txt' 07:36:29 INFO - no previously-included directories found matching 'build' 07:36:29 INFO - no previously-included directories found matching 'dist' 07:36:29 INFO - no previously-included directories found matching '.git*' 07:36:29 INFO - Successfully installed jsonschema functools32 07:36:29 INFO - Cleaning up... 07:36:29 INFO - Return code: 0 07:36:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36: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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc38618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef934a0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb07d30>, '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 0x10c86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10caffb20>, '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.JGgGOnQuS0/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.O8baxWCIdw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 07:36: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:36: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 functools32==3.2.3-2 07:36:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JGgGOnQuS0/Render', 07:36:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:36:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:36:29 INFO - 'HOME': '/Users/cltbld', 07:36:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:36:29 INFO - 'LOGNAME': 'cltbld', 07:36:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:29 INFO - 'MOZ_NO_REMOTE': '1', 07:36:29 INFO - 'NO_EM_RESTART': '1', 07:36:29 INFO - 'PAGER': '/bin/cat', 07:36:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:36:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:29 INFO - 'PWD': '/builds/slave/test', 07:36:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:36:29 INFO - 'SHELL': '/bin/bash', 07:36:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8baxWCIdw/Listeners', 07:36:29 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 07:36:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:36:29 INFO - 'USER': 'cltbld', 07:36:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:36:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:36:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:29 INFO - 'XPC_FLAGS': '0x0', 07:36:29 INFO - 'XPC_SERVICE_NAME': '0', 07:36:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:36:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:36:30 INFO - Cleaning up... 07:36:30 INFO - Return code: 0 07:36:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36: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', '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 0x10cba3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc38618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef934a0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb07d30>, '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 0x10c86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10caffb20>, '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.JGgGOnQuS0/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.O8baxWCIdw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 07:36: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:36: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 blobuploader==1.2.4 07:36:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JGgGOnQuS0/Render', 07:36:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:36:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:36:30 INFO - 'HOME': '/Users/cltbld', 07:36:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:36:30 INFO - 'LOGNAME': 'cltbld', 07:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:30 INFO - 'MOZ_NO_REMOTE': '1', 07:36:30 INFO - 'NO_EM_RESTART': '1', 07:36:30 INFO - 'PAGER': '/bin/cat', 07:36:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:36:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:30 INFO - 'PWD': '/builds/slave/test', 07:36:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:36:30 INFO - 'SHELL': '/bin/bash', 07:36:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8baxWCIdw/Listeners', 07:36:30 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 07:36:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:36:30 INFO - 'USER': 'cltbld', 07:36:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:36:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:30 INFO - 'XPC_FLAGS': '0x0', 07:36:30 INFO - 'XPC_SERVICE_NAME': '0', 07:36:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:36:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:30 INFO - Downloading/unpacking blobuploader==1.2.4 07:36: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 07:36: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 07:36: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 07:36: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 07:36: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 07:36: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 07:36:32 INFO - Downloading blobuploader-1.2.4.tar.gz 07:36:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:36:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:36:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:33 INFO - Downloading docopt-0.6.1.tar.gz 07:36:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:36:34 INFO - Installing collected packages: blobuploader, requests, docopt 07:36:34 INFO - Running setup.py install for blobuploader 07:36:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:36:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:36:34 INFO - Running setup.py install for requests 07:36:34 INFO - Running setup.py install for docopt 07:36:34 INFO - Successfully installed blobuploader requests docopt 07:36:34 INFO - Cleaning up... 07:36:34 INFO - Return code: 0 07:36:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:34 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 0x10cba3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc38618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef934a0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb07d30>, '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 0x10c86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10caffb20>, '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.JGgGOnQuS0/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.O8baxWCIdw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 07:36:34 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 07:36:34 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 07:36:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JGgGOnQuS0/Render', 07:36:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:36:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:36:34 INFO - 'HOME': '/Users/cltbld', 07:36:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:36:34 INFO - 'LOGNAME': 'cltbld', 07:36:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:34 INFO - 'MOZ_NO_REMOTE': '1', 07:36:34 INFO - 'NO_EM_RESTART': '1', 07:36:34 INFO - 'PAGER': '/bin/cat', 07:36:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:36:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:34 INFO - 'PWD': '/builds/slave/test', 07:36:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:36:34 INFO - 'SHELL': '/bin/bash', 07:36:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8baxWCIdw/Listeners', 07:36:34 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 07:36:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:36:34 INFO - 'USER': 'cltbld', 07:36:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:36:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:36:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:34 INFO - 'XPC_FLAGS': '0x0', 07:36:34 INFO - 'XPC_SERVICE_NAME': '0', 07:36:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:36:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:36:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-fsptwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:36:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-wlOnvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:36:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-TtHG2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:36:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-2MEOiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:36:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-qSvChn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:36:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-kNnQqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:36:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-JOn_yr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:36:35 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-2USlNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:36:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-ezNGXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:36:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-zWTdTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:36:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-hmNIPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:36:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-Ay90Op-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:36:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-KG5i8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:36:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-LOcXSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:36:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-x5F989-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:36:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-0aIde6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:36:36 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-Gcp4Ky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:36:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:36:36 INFO - Running setup.py install for manifestparser 07:36:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:36:37 INFO - Running setup.py install for mozcrash 07:36:37 INFO - Running setup.py install for mozdebug 07:36:37 INFO - Running setup.py install for mozdevice 07:36:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:36:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:36:37 INFO - Running setup.py install for mozfile 07:36:37 INFO - Running setup.py install for mozhttpd 07:36:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:36:37 INFO - Running setup.py install for mozinfo 07:36:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:36:37 INFO - Running setup.py install for mozInstall 07:36:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:36:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:36:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:36:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:36:38 INFO - Running setup.py install for mozleak 07:36:38 INFO - Running setup.py install for mozlog 07:36:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:36:38 INFO - Running setup.py install for moznetwork 07:36:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:36:38 INFO - Running setup.py install for mozprocess 07:36:38 INFO - Running setup.py install for mozprofile 07:36:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:36:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:36:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:36:38 INFO - Running setup.py install for mozrunner 07:36:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:36:39 INFO - Running setup.py install for mozscreenshot 07:36:39 INFO - Running setup.py install for moztest 07:36:39 INFO - Running setup.py install for mozversion 07:36:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:36:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:36:39 INFO - Cleaning up... 07:36:39 INFO - Return code: 0 07:36:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:36:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:39 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 0x10cba3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc38618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef934a0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb07d30>, '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 0x10c86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10caffb20>, '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.JGgGOnQuS0/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.O8baxWCIdw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 07:36:39 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 07:36:39 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 07:36:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JGgGOnQuS0/Render', 07:36:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:36:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:36:39 INFO - 'HOME': '/Users/cltbld', 07:36:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:36:39 INFO - 'LOGNAME': 'cltbld', 07:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:39 INFO - 'MOZ_NO_REMOTE': '1', 07:36:39 INFO - 'NO_EM_RESTART': '1', 07:36:39 INFO - 'PAGER': '/bin/cat', 07:36:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:39 INFO - 'PWD': '/builds/slave/test', 07:36:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:36:39 INFO - 'SHELL': '/bin/bash', 07:36:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8baxWCIdw/Listeners', 07:36:39 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 07:36:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:36:39 INFO - 'USER': 'cltbld', 07:36:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:36:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:39 INFO - 'XPC_FLAGS': '0x0', 07:36:39 INFO - 'XPC_SERVICE_NAME': '0', 07:36:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:36:39 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-hjrBmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:36:39 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)) 07:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:36:39 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-IYFDhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:36:40 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)) 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:36:40 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-OyvKI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:36:40 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)) 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:36:40 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-9WeDPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:36:40 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)) 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:36:40 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-9gUyBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:36:40 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)) 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:36:40 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-SqOoJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:36:40 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)) 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:36:40 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-mgoezm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:36:40 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)) 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:36:40 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-lBXR4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:36:40 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)) 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:36:40 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-Yqmk3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:36:40 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)) 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:36:40 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-C8WyBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:36:40 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-w0se5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:36:40 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)) 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:36:41 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-trsp2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:36:41 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-UZaJi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:36:41 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)) 07:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:36:41 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-5InQsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:36:41 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-jx7wxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:36:41 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)) 07:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:36:41 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-uaZYI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:36:41 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)) 07:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:36:41 INFO - Running setup.py (path:/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/pip-GIARiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:36:41 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)) 07:36:41 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)) 07:36:41 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)) 07:36:41 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)) 07:36:41 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)) 07:36:41 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)) 07:36:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:36: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 07:36: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 07:36: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 07:36: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 07:36: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 07:36: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 07:36:44 INFO - Downloading blessings-1.6.tar.gz 07:36:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:36:44 INFO - Installing collected packages: blessings 07:36:44 INFO - Running setup.py install for blessings 07:36:44 INFO - Successfully installed blessings 07:36:44 INFO - Cleaning up... 07:36:44 INFO - Return code: 0 07:36:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:36:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:36:44 INFO - Reading from file tmpfile_stdout 07:36:44 INFO - Current package versions: 07:36:44 INFO - blessings == 1.6 07:36:44 INFO - blobuploader == 1.2.4 07:36:44 INFO - docopt == 0.6.1 07:36:44 INFO - functools32 == 3.2.3-2 07:36:44 INFO - jsonschema == 2.5.1 07:36:44 INFO - manifestparser == 1.1 07:36:44 INFO - mozInstall == 1.12 07:36:44 INFO - mozcrash == 0.17 07:36:44 INFO - mozdebug == 0.1 07:36:44 INFO - mozdevice == 0.48 07:36:44 INFO - mozfile == 1.2 07:36:44 INFO - mozhttpd == 0.7 07:36:44 INFO - mozinfo == 0.9 07:36:44 INFO - mozleak == 0.1 07:36:44 INFO - mozlog == 3.3 07:36:44 INFO - moznetwork == 0.27 07:36:44 INFO - mozprocess == 0.23 07:36:44 INFO - mozprofile == 0.28 07:36:44 INFO - mozrunner == 6.12 07:36:44 INFO - mozscreenshot == 0.1 07:36:44 INFO - mozsystemmonitor == 0.3 07:36:44 INFO - moztest == 0.7 07:36:44 INFO - mozversion == 1.4 07:36:44 INFO - psutil == 3.1.1 07:36:44 INFO - requests == 1.2.3 07:36:44 INFO - wsgiref == 0.1.2 07:36:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:36:44 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:44 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:44 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 0x10cba3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc38618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef934a0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb07d30>, '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 0x10c86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10caffb20>, '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.JGgGOnQuS0/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.O8baxWCIdw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 07:36:44 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 07:36:44 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 07:36:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JGgGOnQuS0/Render', 07:36:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:36:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:36:44 INFO - 'HOME': '/Users/cltbld', 07:36:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:36:44 INFO - 'LOGNAME': 'cltbld', 07:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:44 INFO - 'MOZ_NO_REMOTE': '1', 07:36:44 INFO - 'NO_EM_RESTART': '1', 07:36:44 INFO - 'PAGER': '/bin/cat', 07:36:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:36:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:44 INFO - 'PWD': '/builds/slave/test', 07:36:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:36:44 INFO - 'SHELL': '/bin/bash', 07:36:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8baxWCIdw/Listeners', 07:36:44 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 07:36:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:36:44 INFO - 'USER': 'cltbld', 07:36:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:36:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:44 INFO - 'XPC_FLAGS': '0x0', 07:36:44 INFO - 'XPC_SERVICE_NAME': '0', 07:36:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:36:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:44 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)) 07:36:44 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)) 07:36:44 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)) 07:36:44 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)) 07:36:44 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)) 07:36:44 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)) 07:36:44 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)) 07:36:44 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)) 07:36:44 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)) 07:36:44 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)) 07:36:44 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)) 07:36:44 INFO - Cleaning up... 07:36:45 INFO - Return code: 0 07:36:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc38618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef934a0240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb07d30>, '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 0x10c86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10caffb20>, '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.JGgGOnQuS0/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.O8baxWCIdw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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 07:36:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:36:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JGgGOnQuS0/Render', 07:36:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:36:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:36:45 INFO - 'HOME': '/Users/cltbld', 07:36:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:36:45 INFO - 'LOGNAME': 'cltbld', 07:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:45 INFO - 'MOZ_NO_REMOTE': '1', 07:36:45 INFO - 'NO_EM_RESTART': '1', 07:36:45 INFO - 'PAGER': '/bin/cat', 07:36:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:45 INFO - 'PWD': '/builds/slave/test', 07:36:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:36:45 INFO - 'SHELL': '/bin/bash', 07:36:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8baxWCIdw/Listeners', 07:36:45 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 07:36:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:36:45 INFO - 'USER': 'cltbld', 07:36:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:36:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:45 INFO - 'XPC_FLAGS': '0x0', 07:36:45 INFO - 'XPC_SERVICE_NAME': '0', 07:36:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:36:45 INFO - Cleaning up... 07:36:45 INFO - Return code: 0 07:36:45 INFO - Running post-action listener: _resource_record_post_action 07:36:45 INFO - Running post-action listener: _start_resource_monitoring 07:36:45 INFO - Starting resource monitoring. 07:36:45 INFO - [mozharness: 2016-08-22 14:36:45.296961Z] Finished create-virtualenv step (success) 07:36:45 INFO - [mozharness: 2016-08-22 14:36:45.297920Z] Running install step. 07:36:45 INFO - Running pre-action listener: _resource_record_pre_action 07:36:45 INFO - Running main action method: install 07:36:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:36:45 INFO - Reading from file tmpfile_stdout 07:36:45 INFO - Detecting whether we're running mozinstall >=1.0... 07:36:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:36:45 INFO - Reading from file tmpfile_stdout 07:36:45 INFO - Output received: 07:36:45 INFO - Usage: mozinstall [options] installer 07:36:45 INFO - Options: 07:36:45 INFO - -h, --help show this help message and exit 07:36:45 INFO - -d DEST, --destination=DEST 07:36:45 INFO - Directory to install application into. [default: 07:36:45 INFO - "/builds/slave/test"] 07:36:45 INFO - --app=APP Application being installed. [default: firefox] 07:36:45 INFO - mkdir: /builds/slave/test/build/application 07:36:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:37:19 INFO - Reading from file tmpfile_stdout 07:37:19 INFO - Output received: 07:37:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:37:19 INFO - Running post-action listener: _resource_record_post_action 07:37:19 INFO - [mozharness: 2016-08-22 14:37:19.943301Z] Finished install step (success) 07:37:19 INFO - [mozharness: 2016-08-22 14:37:19.943443Z] Running run-tests step. 07:37:19 INFO - Running pre-action listener: _resource_record_pre_action 07:37:19 INFO - Running main action method: run_tests 07:37:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:37:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:37:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:37:19 INFO - 2016-08-22 07:37:19.957 screenresolution[1643:7823] starting screenresolution argv=screenresolution get 07:37:19 INFO - 2016-08-22 07:37:19.960 screenresolution[1643:7823] Display 0: 1600x1200x32@60 07:37:19 INFO - 2016-08-22 07:37:19.966 screenresolution[1644:7825] starting screenresolution argv=screenresolution list 07:37:19 INFO - Available Modes on Display 0 07:37:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:37:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:37:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:37:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:37:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:37:20 INFO - Intel Iris: 07:37:20 INFO - Chipset Model: Intel Iris 07:37:20 INFO - Type: GPU 07:37:20 INFO - Bus: Built-In 07:37:20 INFO - VRAM (Dynamic, Max): 1536 MB 07:37:20 INFO - Vendor: Intel (0x8086) 07:37:20 INFO - Device ID: 0x0a2e 07:37:20 INFO - Revision ID: 0x0009 07:37:20 INFO - Displays: 07:37:20 INFO - Display: 07:37:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:37:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:37:20 INFO - Display Serial Number: 1600x1200 60 07:37:20 INFO - Main Display: Yes 07:37:20 INFO - Mirror: Off 07:37:20 INFO - Online: Yes 07:37:20 INFO - Rotation: Supported 07:37:20 INFO - Return code: 0 07:37:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:37:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:37:20 INFO - Python 2.7.3 07:37:20 INFO - Return code: 0 07:37:20 INFO - grabbing minidump binary from tooltool 07:37:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:20 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 0x10cb07d30>, '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 0x10c86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10caffb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:37:20 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 07:37:20 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 07:37:20 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 07:37:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:37:20 INFO - Return code: 0 07:37:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:37:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:37:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:37:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:37:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:37:20 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/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--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 07:37:20 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/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0103 --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 07:37:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JGgGOnQuS0/Render', 07:37:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:37:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:37:20 INFO - 'HOME': '/Users/cltbld', 07:37:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:37:20 INFO - 'LOGNAME': 'cltbld', 07:37:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:37:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:37:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:20 INFO - 'MOZ_NO_REMOTE': '1', 07:37:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:37:20 INFO - 'NO_EM_RESTART': '1', 07:37:20 INFO - 'PAGER': '/bin/cat', 07:37:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:37:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:20 INFO - 'PWD': '/builds/slave/test', 07:37:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:37:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:37:20 INFO - 'SHELL': '/bin/bash', 07:37:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O8baxWCIdw/Listeners', 07:37:20 INFO - 'TMPDIR': '/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/', 07:37:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:37:20 INFO - 'USER': 'cltbld', 07:37:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:37:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:37:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:20 INFO - 'XPC_FLAGS': '0x0', 07:37:20 INFO - 'XPC_SERVICE_NAME': '0', 07:37:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:37:20 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/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0103', '--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 07:37:20 INFO - mozversion application_buildid: 20160822051814 07:37:20 INFO - mozversion application_changeset: 662270014cb72f82af415385f2f0bef5b135ec04 07:37:20 INFO - mozversion application_display_name: Nightly 07:37:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:37:20 INFO - mozversion application_name: Firefox 07:37:20 INFO - mozversion application_remotingname: firefox 07:37:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:37:20 INFO - mozversion application_vendor: Mozilla 07:37:20 INFO - mozversion application_version: 51.0a1 07:37:20 INFO - mozversion platform_buildid: 20160822051814 07:37:20 INFO - mozversion platform_changeset: 662270014cb72f82af415385f2f0bef5b135ec04 07:37:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:37:20 INFO - mozversion platform_version: 51.0a1 07:37:20 INFO - using testdate: 1471876640 07:37:20 INFO - actual date: 1471876640 07:37:20 INFO - starting webserver on 'localhost:49267' 07:37:20 INFO - SUITE-START | Running 2 tests 07:37:20 INFO - TEST-START | dromaeo_css 07:37:20 INFO - operating with platform_type : mac_ 07:37:20 INFO - Initialising browser for dromaeo_css test... 07:37:20 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmp4FbyaJ/profile http://localhost:49267/getInfo.html) 07:37:23 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 07:37:23 INFO - PROCESS | 1654 | colorDepth:24 07:37:23 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 07:37:23 INFO - PROCESS | 1654 | __metrics 07:37:23 INFO - PROCESS | 1654 | 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" 07:37:23 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:37:23 INFO - TEST-INFO | 1654: exit 0 07:37:23 INFO - Browser initialized. 07:37:23 INFO - Running cycle 1/1 for dromaeo_css test... 07:37:23 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmp4FbyaJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:37:35 INFO - PROCESS | 1657 | RSS: Main: 228544512 07:37:35 INFO - PROCESS | 1657 | 07:38:27 INFO - PROCESS | 1657 | 1471876707338 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] 07:40:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 07:40:39 INFO - PROCESS | 1657 | RSS: Main: 337985536 07:40:39 INFO - PROCESS | 1657 | 07:43:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 07:43:43 INFO - PROCESS | 1657 | RSS: Main: 372682752 07:43:43 INFO - PROCESS | 1657 | 07:46:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 07:46:46 INFO - PROCESS | 1657 | RSS: Main: 345075712 07:46:46 INFO - PROCESS | 1657 | 07:49:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 07:49:49 INFO - PROCESS | 1657 | RSS: Main: 338944000 07:49:49 INFO - PROCESS | 1657 | 07:52:52 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 07:52:53 INFO - PROCESS | 1657 | RSS: Main: 331739136 07:52:53 INFO - PROCESS | 1657 | 07:55:56 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 07:55:57 INFO - PROCESS | 1657 | RSS: Main: 378982400 07:55:57 INFO - PROCESS | 1657 | 07:55:57 INFO - PROCESS | 1657 | __start_tp_report 07:55:57 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 07:55:57 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 07:55:57 INFO - PROCESS | 1657 | |i|pagename|runs| 07:55:57 INFO - PROCESS | 1657 | |0;dojo.html;2744.547149720296;3128.1553980425288;3350.6314305426386;3362.512435696824;3395.6604339566047;5571.303587051619;5651.886962260753;5670.971645141779;5726.770929162833;5763.711814409284;4668.276417155338;4968.900621987558;5679.602427830064;5777.82666520004;5784.088014822289;5138.563222126124;5153.484651534835;5225.738713064332;5233.21501774733;5311.044012077825;5259.211118332261;5319.707416092114;5340.25236466895;5343;5345.919811202835;5100.285959965608;5148.073346797575;5148.845534633967;5213.582913366921;5263.3683957925105;5250.7637156328055;5260.500252476008;5261.158214685632;5261.842144735626;5261.947380526222;5183.403908550527;5200.427952925175;5251.422343542207;5254.579633629301;5283.86790330241;5559.8888022239325;5649.293838270216;5705.74324155414;5735.827925162252;5748.223989761371;6293.024581189923;6298.622082675054;6300.747970081191;6302.991521356606;6308.747650093991;6274.058891166316;6339;6343.238811342622;6347.047942808569;6359.20509936258;5621.915671264935;5684.37471878093;5719;5753.165790960371;5797.884042319172;6252.531060170505;6279.811605651837;6287.49700023997;6297.1813664223355;6301.495880329563;6107.175531303306;6128.846778830476;6178.783742568989;6183.876322473526;6195.287541932646;6145.047517634774;6166.506679465632;6250.406211409909;6299.905501417483;6325.778597749057;6206.100115483229;6207.534434917399;6216.129741836146;6226.750929962751;6226.782062627877;6264.498840092781;6265.342139075331;6272.247330320391;6297.4962003039645;6298.8425289367215;4803.807847686054;4825.5898248648555;4865.659403841768;4933.4819843915875;4969.602431805447;4343.69594128408;4433.202023635777;4433.756143412079;4460.710053846489;4468.9721364086445;5689.146628005832;5761.683107429048;5764.682942438206;5768.682722450221;5776.595638305278;6347.52393570484;6361;6363.077353783676;6370.522210834113;6376.553641245068;5527.39198688144;5653.406392328829;5719.628224165416;5740.425957404226;5762.827115186551;5474.808381706621;5479.424660410679;5485.890282194334;5492;5501.834944951658;6348.301686814447;6368.5860419072615;6375.489960803217;6380.393862583047;6383.2978372379;6072.574919755575;6215.875682486235;6235.939890218583;6300.802847458968;6341.746330146742;5311.203319502106;6318.557700960889;6368.0766288886925;6371.649559274284;6378.521610879296;6214.5028397728975;6244.718987645476;6251.312355640966;6252.093446450148;6256.468200203034;6098.512119030555;6189.412005859436;6222.346653601488;6223.253209614694;6309.968450157719;6116.66358350295;6125.754969801158;6128.571000029955;6132.264128304632;6141.785037523844;6091.786787462417;6098.298695650057;6119;6121.663308517896;6130.356312587292;5741.770329186786;5831.096180092092;5896.823095307148;5903.852403689943;5963.52291816645;6086.786962456293;6146.323904370426;6146.569740118149;6164.4143806338325;6171.938280617136;5370.248165256789;5937.079752638348;5950.940490595039;5961.344252132175;5971.58198926071 07:55:57 INFO - PROCESS | 1657 | |1;ext.html;481.6267392770602;512.6078549909322;529.6371985190145;538.8248819133781;542.9755660995255;30686.38627227454;40017.79991100044;40902.79548602253;41042.58957410425;41094.58905410945;35271.647283527156;42455;42855.78572107143;42879.35680964788;42887.78556107215;9685.51572421378;9917.206623470141;9934.801303973916;9941.353812002233;9946.254030947672;8897.466152030867;9115.908840911572;11990.340531270776;12209.877901221007;12224.816627750592;12252;12351.876481235207;12355.938220308886;12364.938175309111;12376;8956.417832840845;12236.81644775329;12275.815862762065;12319.815202771966;12341.938290308492;11767.352795596238;12078.939605302005;12164.878351216463;12313.56902508408;12335;40465.98835029148;40843.18313633711;40859.795701021605;40912.97717557055;41003.1799364011;3422.7261819054415;3430.6054803697643;3448.861196816725;3459.498372735939;3559.341521818472;30555.69444305551;31342;31545.949513281565;32673.836630816695;32746.836265818758;3067.6932306769368;3322.1196382958537;3357.9832100839585;3367.124547617637;3378.560787097686;496003.39966003475;507242;508705;508735;520628.3968580171;57990.55023624443;61150.77698446095;64187.679061604395;65039.67480162569;65163.34836651668;68680.6565967177;68941;68973.65513172402;69432;70287;16343.42798002088;16552.503424897273;16606;16609.252583633766;16620.83379166217;11362.261453005529;11670.883291167149;12555.246685198917;12655.873441265654;12680.365981701063;17482.825171748118;17633.7354939676;17662;17757.11214439273;17758.75514608537;7033.437325014089;8471.830563388698;8533.274671652978;8594.527300998505;8795.560221988875;786.2059320086754;968.6680813880914;994.9154321882577;1021.2085633633844;1038.5949479702922;30190.396192076034;30296;30321;31079.533806992913;31194.376112477625;895.5119459894373;1064.6060957445795;1073.0878753059837;1136.6135513925306;1153.6077733570626;14875.70248595022;15372.461963831212;15623.218839058001;15643.84356156424;16208.594785130466;686048;692386;692737;693748;693814;58078;58554.12168817471;58557.41442585605;58587.12119318214;58826.82346353049;1235.9134860559675;1357.7080927600416;1380.2684577174114;1404.8595140486075;1417.4046900301691;29754.553681695226;30724.385512290075;31380.686193137775;32002.679973200902;32244.677553224166;47177;47540.28689569728;47705.522944770106;47729.761351193025;47742.5225747752;458540;461248;462638;463206;463316;27418.725812741617;27828.72171278261;27834.74743636582;28798.280042999093;42283.731488055404;47251.291230632254;47278.527214727415;47304.7634761824;47617.28574071461;48350;39067.804660976515;39172.608273918035;39265.48037025225;39588.20823583454;40737.79631101825;39281.803590981865;39783.40324895071;40104.59633912799;40604.18791624092;41010;38287.80856095702;38915.416268756555;38921.221575568896;39135.21729565336;39198;31584;33542.99371018873;34176.65823341735;34612.48081278833;35039.299214016086;23196.652050219596;23893;24965.50068998646;25511;25806.225813225632 07:55:57 INFO - PROCESS | 1657 | |2;jquery.html;358.8815690822029;370.8961490782582;373.4715377740497;388.2119297825415;406.62184168723076;15277.923610381946;16695;21852.45368865779;22108.668369974424;22174.223902163427;20682.89658551709;21030.369088927317;21474;21676;21732.45668858279;6028.427299406571;6268.404501572343;7585.506942048777;7947.920520794776;8042.517448953082;7793.298603125718;8030.955975723148;8057.234562716532;8105.06791718951;8108.472949258279;8105.1084380718075;8112.75661730149;8120.9848768903885;8127.796805079891;8181.100078991306;7911.090224624184;8088.150744171866;8147.307478864303;8148.144444833267;8157.592120394016;7554.320111190019;7768.456208065438;7852.528848269122;7853.371730261565;7856.489328193649;24340.269791906096;28446.288842779097;29068.709312906813;29707.554386684216;30522.54216186759;1968.793276705954;2595.9480810383875;3006.353633968707;3385.086026772782;3424.8630054797777;16964.491065268063;17169.82830171695;18036.90981545084;18043.639127217382;18118.184681689356;1657.395050806447;2026.8175864172294;2046.1406209392092;2053.0761157479196;2128.521082756383;110213;121171;123597;123991;126946.73053269534;19548.413547593595;20673.586528269352;21113.894430527747;21150;21426.357209283746;34468.621255150014;34504.82747586296;34566.7901623447;34619.653803461646;34641.82679086639;8548.529830859237;9488.667896623585;10007.698999130067;10604;10748.623798167184;8635.272945410943;8870.2460290876;8875.378723489293;9048;9098.590563424661;7855;9478.478683672327;9626.374285671409;9725.443928971472;9735.172510336559;2896.8551572421293;2961.9111426657237;3330.6169790474405;3753.699704023726;3833.271678381099;345.7856129199862;387.3009218360829;403.8747988123691;833.204289903141;888.9066648001867;11800.291982481078;11823.586174483851;11985.580504682468;12215.572454964034;12268;359.91901822090347;423.3142309458635;423.97880105995193;435.5861931165405;459.02457278283646;6078.483328917003;6089.1779609753;6151.630902145886;6254.843628909313;6264.248290205113;590599;590926;604541;605731;608069;134073.65926340612;135919.6408035907;137981;139032.30483847516;141253;444.91991441540836;454.0465023450793;455.17840298261297;455.7971702592381;456.5959126173294;11324.830127548259;11358.318500889973;11404.657860264206;11636.709082273012;11671.066314694974;51783.2232516505;53268.73365633147;53707;54288.72855635697;55159.89680206453;60613.69693151506;77949.61025194838;83373;83618;85648.57175714082;7783.299503044753;8045.919540804517;8368.916310837058;8400.28597569189;8524.957375213084;9180.946863184801;13115.606531804062;13388.866111338762;13416.12795168311;13586.45654173822;11395.99723673167;11607.129465290305;11700.765984680089;11799.76400472003;11816;11116.833247501132;11210.159238057015;11438.542458301576;11620.825687614863;11946.223495472766;11490.597829075943;11708.824367634323;11911.702207444883;12004.939975300067;12078.27530348182;8265.297449716601;8319.168083191633;8390.28682561989;8458.06961234276;8490.193431623986;9656.806863862825;11527.308361498337;11740.647780566596;11856.466459009192;11888.524459021542 07:55:57 INFO - PROCESS | 1657 | |3;mootools.html;1540.8844336674752;1548.0556860315207;1751.8861274017188;1884.1803815340336;1899.5536049028485;7931.048274207096;8370.121137280588;8372.95813520933;8375.413721039522;8385.11956244594;7721.884171737428;7958.204179582039;8296.626651800669;8550.358723095776;8605.741827745162;2237.630790919496;2286.6112760830615;2287.3252390544744;2288.2677543186187;2288.553732022256;2349.2012715676665;2363.1020212319295;2381.452265978817;2388.605880029793;2400.9039638414442;2368.455255291284;2385.42749740062;2394.521095780851;2396.9161079362225;2410.8312418130736;2344.1443872986406;2364.550735360276;2372.323887692017;2372.430616652008;2379.155399833069;2325.2442956039354;2368.289513146049;2375.8099352051795;2376.869272190029;2378.702662167229;8673.176048275403;8735.43219690718;8746.081661425585;8753.124687531259;8757.737267881974;5196.402413722432;5368.4363141869935;5376.1398176291605;5376.704281264529;5376.892462150735;3553.502509648651;3582.3193593217466;3703.5555733311908;3714.888553343404;3717.3494638438438;1103.5420300575252;1134.3080720760329;1783.8126996665424;1853.87949783265;1901.5531350132708;10930.617428390047;11187.832182517268;11211.607593734261;11255.54977800887;11350.318980861175;4411.889702757392;4458.866234012985;4643.489216186217;4647.581717645428;4660.207764680013;8983.865242021375;8987.011428742833;8988.505632190161;8988.730338089737;8988.86516702237;5233.450487698813;5236.16221404581;5237.16205407133;5242.842714718488;5246.291750613695;3665.7617254878346;4478.275863792398;4773.809047638056;4917.041176970456;4918.5573298403315;3713.3687273163628;3729.2355067211156;3830.8084595770656;3853.4412510185866;3854.036490877281;1147.6270212180907;1167.4104577188466;1173.12015988009;1180.4510902430238;1181.5214837990627;898.3756289378949;918.5958178401482;925.3799954030816;942.6473011228909;981.74474636595;5172.431032586413;5370.91943620846;5502.697351645618;5682;5889.7042650616795;449.53473155284127;456.79444250087596;466.22839201122196;467.1591135955267;467.81988934260556;3802.429635554689;4011.2779699654343;4053.6959728020515;4126.42230087788;4199.559046300094;8967.686130985385;10530.57877684884;10710.518026688818;10719.19606029551;10800;9934.701958941243;10208.489575521195;10501.894981050245;10633.46832658364;10713.982171693677;7310.501347223806;7414.258574142543;7887.44787864844;8068.636911339004;8113.215092679601;4853.95146048535;5040.773165207648;5543.667379957215;5820.796272130455;5955.225820643289;9916.950415247878;9991;9998.000199979944;10001.799964000826;10007.249456290661;9633.470159141314;9848.113669769755;9900.306978511435;10141.492925353556;10147.64483243083;6936.341047600672;7143.428525718034;7249.855002899807;7505.249475052451;7581.962090189514;7827.25641064098;7893.210678932061;7896.881546776688;7924.247196516321;8079.515229086273;7089.61007144612;7387.593682347522;7407.8148046299275;7432.145303290191;7510.361619262424;7310.378617817545;7311.561306321638;7312.96343518279;7339.522931009467;7379.8155046124275;6834.487413444111;6848.178218613798;6924.688389022405;6931.653417329214;6935;4358.956410435855;4947.431045429822;4954.455009948831;4969.4782047884455;5033.320501732219;4942.851714448572;4981.825636102719;5006.949930500794;5068.087744206078;5102.158143906214 07:55:57 INFO - PROCESS | 1657 | |4;prototype.html;293.54353979561785;300.9157435917943;309.33801664438107;309.5125177844892;313.34510872276934;4646.953530464694;4993.901341704828;5260.447652996438;5295.841124766258;5383.81156659517;4002.879913602589;4819.78310976006;5103.040236662736;5258.947410525893;5354.946450535494;4046.696497762665;4060.9187816243657;4088.550259471456;4088.570700076501;4097.3239415496555;3910;4066.5323487798837;4077.9592204077876;4094.075784381351;4112.38314252863;3999.680025597945;4009.719319647626;4029.1264561978674;4037.1538065663785;4068.247374235761;3326.451135562629;4020.979895100535;4026;4032.2338755636333;4067.8983025424304;2947.7052294770565;3137.403893260296;3217.9887570268584;3489.6510348965153;3520.1903562180587;4369.7059044001835;4891.975540122312;5234.97382513085;5271.051210782058;5321.414644389153;3828.7511311764647;4221.092465120014;4502.279635258343;4569.520200378945;4592.563706447882;4869.561739443467;5468.863278418032;5552.250446189753;5617.831465056055;5625.212470254168;3242.3353212591364;3245.7078862902454;3547.8048707321086;3811.7712937223855;3821.942446043154;5136.018062479389;5481.547389941668;5859.538993622688;5859.769448415837;5867.589268751189;3150.070729134911;3356.5133055706788;3528.100334414708;3858.5369755629504;3875.5349358076646;3770.717955895009;3912.0024393779404;3924.372100463912;3935.2916475034767;3947.2697550953167;3594.856205751793;4520.344550040291;4539.182947069559;4542.454905411372;4564.497905230422;4728.95271047292;5377.300950876361;5652.9152062719095;5670.489655930904;5679.346875109332;1258.8489381274305;1270.8093785932185;1289.5035411366691;1304.7194853106628;1312.4750099960092;753.7512620835112;876.6361959786744;883.7392969074139;889.6574818694849;892.3218354050967;5236.73816309183;5254.264402983586;5265.341832270966;5267.868303292448;5284;5585.888282234332;5634.943650563442;5723.855228954226;5736.885262294731;5752.712364381764;5112.974435127875;5152.9694061187165;5177.301064356264;5189.7405129743365;5209.6874187548865;2357.927143149863;2415.3237093613816;2426.332758491429;2439.7194322652767;2523.233736384538;4426.782634775398;4569.8521694803385;4744.312074749142;4947.431045429822;4958.330625365529;4396.636573021641;4827.710337379768;4843.543939575599;4851.385717640381;4880.438749543777;4270.871873843789;4439.357437747989;4782.641301902371;4889.217725163886;4922.409310882717;4197.601227883346;4732;4771.570558649738;5142.742862856917;5741.995150848668;4734.786934588021;4993.675411098267;5611.84957083805;5688.886222275614;5746;5272.393674727456;5987.2815262168815;6014.548908831767;6036;6061.636301821904;3570.803605801602;3723.888283351504;3798.9240215196096;3830.9464897153507;3851.768893866377;5696.8006119785605;5707.771689132388;5742.59801813869;5748.856278593069;5765.365809761007;4722.504137065559;4873.6832105913;5658.349289831722;5688.5733569981585;5709.601147718836;4459.576340247671;5077.822276220315;5480.3971563128825;5531.446855314436;5553.444655534414;4619.376384188092;5161.329027226437;5366.785328586813;5450.146950037007;5497.697626630574;4676.9243074092465;5297.708626025606;5446.754896029609;5626.465485778845;5636.577256705846;5219.243209734491;5421.349438067457;5517.26895186885;5523.917141242804;5559.58303127259 07:55:57 INFO - PROCESS | 1657 | |5;yui.html;723.5043994863519;764.2433990349556;784.1922819495921;817.6867026435548;837.5142417397908;15441.613959651015;16185.676286474263;16481.752773708387;16513.25690343936;16601.750973735405;15028.624284392896;15084.6983060339;15309.311081001353;15333;15340.923295383507;702.1503980183984;861.5132450165652;877.5173654490037;882.0385779500343;882.3691060891462;716.373173473211;728.9890651640229;890.3945317184312;894.579547612621;900.7928176519372;933.593886659302;938.3290946972913;939.6429356844424;941.6233506597347;945.9290553208502;739.0540108660923;922.8892532896095;928.7353104365291;929.2333824594702;932.9207017403529;560.2324815003471;563.3943510725945;565.8924804287172;672.6804767735335;697.9930200697979;14845.960782745213;14863.851361486355;14883.10701357922;14892.62768430787;14907.329170187368;14080.859191408057;14280.642983925381;14533.34599943005;14710.55868323952;14789.408423663148;12899.613011609667;13094.410751516205;13308.467661293536;13381.53164639233;13808.1024733392;4563.292689633112;4671.602913752336;4705.623550116016;4717.599004084657;4728.810847566127;122300;122503;122516.07291052195;122541.38729306296;122615;9356.625734970661;11425.371604561662;11519;12018.338991355384;12053.156279060557;22790.43023924382;22833.543329133325;22835.543289134122;22853.971571279333;22883.31350059501;8201.71294004707;8291.751247462586;8292.17078292178;8295.792605184919;8297.751067467985;4394.758288294174;4469.329600559877;4699.577038066522;4735.573798358096;4750.738709371018;3429.6570342965506;3570.071781336871;3570.232400034005;3572.499850020999;3588.6052534221217;567.324883388766;659.2319947261483;668.6088638146641;669.1501792723215;670.7819958513405;594.2542109652359;607.2106261859565;619.2599843187438;650.9641969691712;664.2095905871997;7804.726834560764;7996.572612785007;8312.625931833081;9778.951105244429;10982.560697572007;532.3072629039691;543.8912217556505;658.887989041864;717.6196615793533;731.2451010547029;4095.3242714952294;4589.5181005994555;4680.882977925511;4742.407199100113;4778.904421911542;167503.32496674877;167798.16100919663;169323;172026;175690.12154939398;97805;98635;98879;102004;103131;70282.64858675879;104781.95218047875;110442;110449.44775276072;112002;9659.372140810825;9938.354006989523;10000.099991000845;10021.148202402877;10045.748856278651;50292.74853625709;61452.31846019247;61791.382086178564;62269.06596400967;63860.361396385444;90602.64096038433;94723.36936414384;95852.52073739587;97209;99821.50089249507;12637.873621264038;13167.473301068234;13275.867241327463;13297;13368.398422071225;14691.338889749773;14821.004048421672;14944.925275373553;15038.398464061314;15454.768178477107;11073.335599864035;11212;14166.433342666178;14348.426062957782;14593.343299551334;14000.929995350365;14042.78935815984;14211.218382989035;14551.708965820835;14752.852471475577;13502;13695.726085478434;13857.584272471842;13897.444102235797;13935.651608709863;11019.559217631206;11043.723906902393;11298.09615230796;11746.412679365827;11956.820647690118;11445.828312575484;11508;11533.826992595286;11569.247998880046;11794.52821887115 07:55:57 INFO - PROCESS | 1657 | __end_tp_report 07:55:57 INFO - PROCESS | 1657 | __start_cc_report 07:55:57 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1135 07:55:57 INFO - PROCESS | 1657 | __end_cc_report 07:55:57 INFO - PROCESS | 1657 | __startTimestamp1471877757030__endTimestamp 07:55:57 INFO - PROCESS | 1657 | 07:55:57 INFO - PROCESS | 1657 | ------- Summary: start ------- 07:55:57 INFO - PROCESS | 1657 | Number of tests: 6 07:55:57 INFO - PROCESS | 1657 | 07:55:57 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5743.77 Median:5950.94 stddev:676.83 (11.4%) stddev-sans-first:636.70 07:55:57 INFO - PROCESS | 1657 | Values: 2744.5 3128.2 3350.6 3362.5 3395.7 5571.3 5651.9 5671.0 5726.8 5763.7 4668.3 4968.9 5679.6 5777.8 5784.1 5138.6 5153.5 5225.7 5233.2 5311.0 5259.2 5319.7 5340.3 5343.0 5345.9 5100.3 5148.1 5148.8 5213.6 5263.4 5250.8 5260.5 5261.2 5261.8 5261.9 5183.4 5200.4 5251.4 5254.6 5283.9 5559.9 5649.3 5705.7 5735.8 5748.2 6293.0 6298.6 6300.7 6303.0 6308.7 6274.1 6339.0 6343.2 6347.0 6359.2 5621.9 5684.4 5719.0 5753.2 5797.9 6252.5 6279.8 6287.5 6297.2 6301.5 6107.2 6128.8 6178.8 6183.9 6195.3 6145.0 6166.5 6250.4 6299.9 6325.8 6206.1 6207.5 6216.1 6226.8 6226.8 6264.5 6265.3 6272.2 6297.5 6298.8 4803.8 4825.6 4865.7 4933.5 4969.6 4343.7 4433.2 4433.8 4460.7 4469.0 5689.1 5761.7 5764.7 5768.7 5776.6 6347.5 6361.0 6363.1 6370.5 6376.6 5527.4 5653.4 5719.6 5740.4 5762.8 5474.8 5479.4 5485.9 5492.0 5501.8 6348.3 6368.6 6375.5 6380.4 6383.3 6072.6 6215.9 6235.9 6300.8 6341.7 5311.2 6318.6 6368.1 6371.6 6378.5 6214.5 6244.7 6251.3 6252.1 6256.5 6098.5 6189.4 6222.3 6223.3 6310.0 6116.7 6125.8 6128.6 6132.3 6141.8 6091.8 6098.3 6119.0 6121.7 6130.4 5741.8 5831.1 5896.8 5903.9 5963.5 6086.8 6146.3 6146.6 6164.4 6171.9 5370.2 5937.1 5950.9 5961.3 5971.6 07:55:57 INFO - PROCESS | 1657 | 07:55:57 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:69654.15 Median:38918.32 stddev:150341.21 (386.3%) stddev-sans-first:150673.25 07:55:57 INFO - PROCESS | 1657 | Values: 481.6 512.6 529.6 538.8 543.0 30686.4 40017.8 40902.8 41042.6 41094.6 35271.6 42455.0 42855.8 42879.4 42887.8 9685.5 9917.2 9934.8 9941.4 9946.3 8897.5 9115.9 11990.3 12209.9 12224.8 12252.0 12351.9 12355.9 12364.9 12376.0 8956.4 12236.8 12275.8 12319.8 12341.9 11767.4 12078.9 12164.9 12313.6 12335.0 40466.0 40843.2 40859.8 40913.0 41003.2 3422.7 3430.6 3448.9 3459.5 3559.3 30555.7 31342.0 31545.9 32673.8 32746.8 3067.7 3322.1 3358.0 3367.1 3378.6 496003.4 507242.0 508705.0 508735.0 520628.4 57990.6 61150.8 64187.7 65039.7 65163.3 68680.7 68941.0 68973.7 69432.0 70287.0 16343.4 16552.5 16606.0 16609.3 16620.8 11362.3 11670.9 12555.2 12655.9 12680.4 17482.8 17633.7 17662.0 17757.1 17758.8 7033.4 8471.8 8533.3 8594.5 8795.6 786.2 968.7 994.9 1021.2 1038.6 30190.4 30296.0 30321.0 31079.5 31194.4 895.5 1064.6 1073.1 1136.6 1153.6 14875.7 15372.5 15623.2 15643.8 16208.6 686048.0 692386.0 692737.0 693748.0 693814.0 58078.0 58554.1 58557.4 58587.1 58826.8 1235.9 1357.7 1380.3 1404.9 1417.4 29754.6 30724.4 31380.7 32002.7 32244.7 47177.0 47540.3 47705.5 47729.8 47742.5 458540.0 461248.0 462638.0 463206.0 463316.0 27418.7 27828.7 27834.7 28798.3 42283.7 47251.3 47278.5 47304.8 47617.3 48350.0 39067.8 39172.6 39265.5 39588.2 40737.8 39281.8 39783.4 40104.6 40604.2 41010.0 38287.8 38915.4 38921.2 39135.2 39198.0 31584.0 33543.0 34176.7 34612.5 35039.3 23196.7 23893.0 24965.5 25511.0 25806.2 07:55:57 INFO - PROCESS | 1657 | 07:55:57 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:36540.90 Median:359.40 stddev:100287.56 (27904.1%) stddev-sans-first:100532.09 07:55:57 INFO - PROCESS | 1657 | Values: 358.9 370.9 373.5 388.2 406.6 15277.9 16695.0 21852.5 22108.7 22174.2 20682.9 21030.4 21474.0 21676.0 21732.5 6028.4 6268.4 7585.5 7947.9 8042.5 7793.3 8031.0 8057.2 8105.1 8108.5 8105.1 8112.8 8121.0 8127.8 8181.1 7911.1 8088.2 8147.3 8148.1 8157.6 7554.3 7768.5 7852.5 7853.4 7856.5 24340.3 28446.3 29068.7 29707.6 30522.5 1968.8 2595.9 3006.4 3385.1 3424.9 16964.5 17169.8 18036.9 18043.6 18118.2 1657.4 2026.8 2046.1 2053.1 2128.5 110213.0 121171.0 123597.0 123991.0 126946.7 19548.4 20673.6 21113.9 21150.0 21426.4 34468.6 34504.8 34566.8 34619.7 34641.8 8548.5 9488.7 10007.7 10604.0 10748.6 8635.3 8870.2 8875.4 9048.0 9098.6 7855.0 9478.5 9626.4 9725.4 9735.2 2896.9 2961.9 3330.6 3753.7 3833.3 345.8 387.3 403.9 833.2 888.9 11800.3 11823.6 11985.6 12215.6 12268.0 359.9 423.3 424.0 435.6 459.0 6078.5 6089.2 6151.6 6254.8 6264.2 590599.0 590926.0 604541.0 605731.0 608069.0 134073.7 135919.6 137981.0 139032.3 141253.0 444.9 454.0 455.2 455.8 456.6 11324.8 11358.3 11404.7 11636.7 11671.1 51783.2 53268.7 53707.0 54288.7 55159.9 60613.7 77949.6 83373.0 83618.0 85648.6 7783.3 8045.9 8368.9 8400.3 8525.0 9180.9 13115.6 13388.9 13416.1 13586.5 11396.0 11607.1 11700.8 11799.8 11816.0 11116.8 11210.2 11438.5 11620.8 11946.2 11490.6 11708.8 11911.7 12004.9 12078.3 8265.3 8319.2 8390.3 8458.1 8490.2 9656.8 11527.3 11740.6 11856.5 11888.5 07:55:57 INFO - PROCESS | 1657 | 07:55:57 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5563.45 Median:4950.94 stddev:3068.62 (62.0%) stddev-sans-first:3062.34 07:55:57 INFO - PROCESS | 1657 | Values: 1540.9 1548.1 1751.9 1884.2 1899.6 7931.0 8370.1 8373.0 8375.4 8385.1 7721.9 7958.2 8296.6 8550.4 8605.7 2237.6 2286.6 2287.3 2288.3 2288.6 2349.2 2363.1 2381.5 2388.6 2400.9 2368.5 2385.4 2394.5 2396.9 2410.8 2344.1 2364.6 2372.3 2372.4 2379.2 2325.2 2368.3 2375.8 2376.9 2378.7 8673.2 8735.4 8746.1 8753.1 8757.7 5196.4 5368.4 5376.1 5376.7 5376.9 3553.5 3582.3 3703.6 3714.9 3717.3 1103.5 1134.3 1783.8 1853.9 1901.6 10930.6 11187.8 11211.6 11255.5 11350.3 4411.9 4458.9 4643.5 4647.6 4660.2 8983.9 8987.0 8988.5 8988.7 8988.9 5233.5 5236.2 5237.2 5242.8 5246.3 3665.8 4478.3 4773.8 4917.0 4918.6 3713.4 3729.2 3830.8 3853.4 3854.0 1147.6 1167.4 1173.1 1180.5 1181.5 898.4 918.6 925.4 942.6 981.7 5172.4 5370.9 5502.7 5682.0 5889.7 449.5 456.8 466.2 467.2 467.8 3802.4 4011.3 4053.7 4126.4 4199.6 8967.7 10530.6 10710.5 10719.2 10800.0 9934.7 10208.5 10501.9 10633.5 10714.0 7310.5 7414.3 7887.4 8068.6 8113.2 4854.0 5040.8 5543.7 5820.8 5955.2 9917.0 9991.0 9998.0 10001.8 10007.2 9633.5 9848.1 9900.3 10141.5 10147.6 6936.3 7143.4 7249.9 7505.2 7582.0 7827.3 7893.2 7896.9 7924.2 8079.5 7089.6 7387.6 7407.8 7432.1 7510.4 7310.4 7311.6 7313.0 7339.5 7379.8 6834.5 6848.2 6924.7 6931.7 6935.0 4359.0 4947.4 4954.5 4969.5 5033.3 4942.9 4981.8 5006.9 5068.1 5102.2 07:55:57 INFO - PROCESS | 1657 | 07:55:57 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4385.22 Median:4835.63 stddev:1366.53 (28.3%) stddev-sans-first:1335.41 07:55:57 INFO - PROCESS | 1657 | Values: 293.5 300.9 309.3 309.5 313.3 4647.0 4993.9 5260.4 5295.8 5383.8 4002.9 4819.8 5103.0 5258.9 5354.9 4046.7 4060.9 4088.6 4088.6 4097.3 3910.0 4066.5 4078.0 4094.1 4112.4 3999.7 4009.7 4029.1 4037.2 4068.2 3326.5 4021.0 4026.0 4032.2 4067.9 2947.7 3137.4 3218.0 3489.7 3520.2 4369.7 4892.0 5235.0 5271.1 5321.4 3828.8 4221.1 4502.3 4569.5 4592.6 4869.6 5468.9 5552.3 5617.8 5625.2 3242.3 3245.7 3547.8 3811.8 3821.9 5136.0 5481.5 5859.5 5859.8 5867.6 3150.1 3356.5 3528.1 3858.5 3875.5 3770.7 3912.0 3924.4 3935.3 3947.3 3594.9 4520.3 4539.2 4542.5 4564.5 4729.0 5377.3 5652.9 5670.5 5679.3 1258.8 1270.8 1289.5 1304.7 1312.5 753.8 876.6 883.7 889.7 892.3 5236.7 5254.3 5265.3 5267.9 5284.0 5585.9 5634.9 5723.9 5736.9 5752.7 5113.0 5153.0 5177.3 5189.7 5209.7 2357.9 2415.3 2426.3 2439.7 2523.2 4426.8 4569.9 4744.3 4947.4 4958.3 4396.6 4827.7 4843.5 4851.4 4880.4 4270.9 4439.4 4782.6 4889.2 4922.4 4197.6 4732.0 4771.6 5142.7 5742.0 4734.8 4993.7 5611.8 5688.9 5746.0 5272.4 5987.3 6014.5 6036.0 6061.6 3570.8 3723.9 3798.9 3830.9 3851.8 5696.8 5707.8 5742.6 5748.9 5765.4 4722.5 4873.7 5658.3 5688.6 5709.6 4459.6 5077.8 5480.4 5531.4 5553.4 4619.4 5161.3 5366.8 5450.1 5497.7 4676.9 5297.7 5446.8 5626.5 5636.6 5219.2 5421.3 5517.3 5523.9 5559.6 07:55:57 INFO - PROCESS | 1657 | 07:55:57 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:25059.63 Median:3570.15 stddev:40302.10 (1128.9%) stddev-sans-first:40373.73 07:55:57 INFO - PROCESS | 1657 | Values: 723.5 764.2 784.2 817.7 837.5 15441.6 16185.7 16481.8 16513.3 16601.8 15028.6 15084.7 15309.3 15333.0 15340.9 702.2 861.5 877.5 882.0 882.4 716.4 729.0 890.4 894.6 900.8 933.6 938.3 939.6 941.6 945.9 739.1 922.9 928.7 929.2 932.9 560.2 563.4 565.9 672.7 698.0 14846.0 14863.9 14883.1 14892.6 14907.3 14080.9 14280.6 14533.3 14710.6 14789.4 12899.6 13094.4 13308.5 13381.5 13808.1 4563.3 4671.6 4705.6 4717.6 4728.8 122300.0 122503.0 122516.1 122541.4 122615.0 9356.6 11425.4 11519.0 12018.3 12053.2 22790.4 22833.5 22835.5 22854.0 22883.3 8201.7 8291.8 8292.2 8295.8 8297.8 4394.8 4469.3 4699.6 4735.6 4750.7 3429.7 3570.1 3570.2 3572.5 3588.6 567.3 659.2 668.6 669.2 670.8 594.3 607.2 619.3 651.0 664.2 7804.7 7996.6 8312.6 9779.0 10982.6 532.3 543.9 658.9 717.6 731.2 4095.3 4589.5 4680.9 4742.4 4778.9 167503.3 167798.2 169323.0 172026.0 175690.1 97805.0 98635.0 98879.0 102004.0 103131.0 70282.6 104782.0 110442.0 110449.4 112002.0 9659.4 9938.4 10000.1 10021.1 10045.7 50292.7 61452.3 61791.4 62269.1 63860.4 90602.6 94723.4 95852.5 97209.0 99821.5 12637.9 13167.5 13275.9 13297.0 13368.4 14691.3 14821.0 14944.9 15038.4 15454.8 11073.3 11212.0 14166.4 14348.4 14593.3 14000.9 14042.8 14211.2 14551.7 14752.9 13502.0 13695.7 13857.6 13897.4 13935.7 11019.6 11043.7 11298.1 11746.4 11956.8 11445.8 11508.0 11533.8 11569.2 11794.5 07:55:57 INFO - PROCESS | 1657 | -------- Summary: end -------- 07:55:57 INFO - PROCESS | 1657 | 07:55:57 INFO - TEST-INFO | 1657: exit 0 07:55:57 INFO - TEST-OK | dromaeo_css | took 1117037ms 07:55:57 INFO - TEST-START | kraken 07:55:57 INFO - operating with platform_type : mac_ 07:55:57 INFO - Initialising browser for kraken test... 07:55:57 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpVT54we/profile http://localhost:49267/getInfo.html) 07:55:59 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 07:55:59 INFO - PROCESS | 1662 | colorDepth:24 07:55:59 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 07:55:59 INFO - PROCESS | 1662 | __metrics 07:55:59 INFO - PROCESS | 1662 | 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" 07:55:59 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:56:00 INFO - TEST-INFO | 1662: exit 0 07:56:00 INFO - Browser initialized. 07:56:00 INFO - Running cycle 1/1 for kraken test... 07:56:00 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/T/tmpVT54we/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:56:12 INFO - PROCESS | 1663 | RSS: Main: 228667392 07:56:12 INFO - PROCESS | 1663 | 07:56:15 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 07:56:16 INFO - PROCESS | 1663 | RSS: Main: 295612416 07:56:16 INFO - PROCESS | 1663 | 07:56:18 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 07:56:19 INFO - PROCESS | 1663 | RSS: Main: 295768064 07:56:19 INFO - PROCESS | 1663 | 07:56:23 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 07:56:23 INFO - PROCESS | 1663 | RSS: Main: 378740736 07:56:23 INFO - PROCESS | 1663 | 07:56:25 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 07:56:26 INFO - PROCESS | 1663 | RSS: Main: 281329664 07:56:26 INFO - PROCESS | 1663 | 07:56:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:56:29 INFO - PROCESS | 1663 | RSS: Main: 285831168 07:56:29 INFO - PROCESS | 1663 | 07:56:31 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 07:56:32 INFO - PROCESS | 1663 | RSS: Main: 325074944 07:56:32 INFO - PROCESS | 1663 | 07:56:35 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 07:56:35 INFO - PROCESS | 1663 | RSS: Main: 324313088 07:56:35 INFO - PROCESS | 1663 | 07:56:38 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 07:56:39 INFO - PROCESS | 1663 | RSS: Main: 323305472 07:56:39 INFO - PROCESS | 1663 | 07:56:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:56:41 INFO - PROCESS | 1663 | RSS: Main: 302563328 07:56:41 INFO - PROCESS | 1663 | 07:56:43 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:56:44 INFO - PROCESS | 1663 | RSS: Main: 303325184 07:56:44 INFO - PROCESS | 1663 | 07:56:46 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:56:46 INFO - PROCESS | 1663 | RSS: Main: 302313472 07:56:46 INFO - PROCESS | 1663 | 07:56:49 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:56:50 INFO - PROCESS | 1663 | RSS: Main: 312528896 07:56:50 INFO - PROCESS | 1663 | 07:56:53 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:56:54 INFO - PROCESS | 1663 | RSS: Main: 313896960 07:56:54 INFO - PROCESS | 1663 | 07:56:55 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 07:56:56 INFO - PROCESS | 1663 | RSS: Main: 357625856 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | __start_tp_report 07:56:56 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 07:56:56 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 07:56:56 INFO - PROCESS | 1663 | |i|pagename|runs| 07:56:56 INFO - PROCESS | 1663 | |0;ai-astar;97;113;107;115;156;164;116;113;113;111 07:56:56 INFO - PROCESS | 1663 | |1;audio-beat-detection;172;138;133;170;134;162;127;111;197;138 07:56:56 INFO - PROCESS | 1663 | |2;audio-dft;249;256;187;184;181;186;186;183;183;185 07:56:56 INFO - PROCESS | 1663 | |3;audio-fft;80;73;107;92;57;61;63;85;89;57 07:56:56 INFO - PROCESS | 1663 | |4;audio-oscillator;84;83;133;69;72;73;115;69;68;115 07:56:56 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;87;84;87;84;87;83;87;84;87;82 07:56:56 INFO - PROCESS | 1663 | |6;imaging-darkroom;93;92;91;94;94;93;92;94;92;92 07:56:56 INFO - PROCESS | 1663 | |7;imaging-desaturate;84;86;86;86;83;84;86;84;84;86 07:56:56 INFO - PROCESS | 1663 | |8;json-parse-financial;54;54;56;56;56;55;54;54;53;55 07:56:56 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;66;66;87;112;106;80;69;67;68;67 07:56:56 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;86;83;84;86;87;84;82;85;85;90 07:56:56 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;139;136;139;184;156;152;140;137;148;148 07:56:56 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;170;168;168;230;191;170;168;167;198;232 07:56:56 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;68;68;70;68;69;67;67;66;67;67 07:56:56 INFO - PROCESS | 1663 | __end_tp_report 07:56:56 INFO - PROCESS | 1663 | __start_cc_report 07:56:56 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2107 07:56:56 INFO - PROCESS | 1663 | __end_cc_report 07:56:56 INFO - PROCESS | 1663 | __startTimestamp1471877816220__endTimestamp 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | ------- Summary: start ------- 07:56:56 INFO - PROCESS | 1663 | Number of tests: 14 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:120.50 Median:115.50 stddev:21.59 (18.7%) stddev-sans-first:21.16 07:56:56 INFO - PROCESS | 1663 | Values: 97.0 113.0 107.0 115.0 156.0 164.0 116.0 113.0 113.0 111.0 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:148.20 Median:150.00 stddev:26.01 (17.3%) stddev-sans-first:26.12 07:56:56 INFO - PROCESS | 1663 | Values: 172.0 138.0 133.0 170.0 134.0 162.0 127.0 111.0 197.0 138.0 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:198.00 Median:186.00 stddev:28.83 (15.5%) stddev-sans-first:23.95 07:56:56 INFO - PROCESS | 1663 | Values: 249.0 256.0 187.0 184.0 181.0 186.0 186.0 183.0 183.0 185.0 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:76.40 Median:76.50 stddev:17.02 (22.2%) stddev-sans-first:18.00 07:56:56 INFO - PROCESS | 1663 | Values: 80.0 73.0 107.0 92.0 57.0 61.0 63.0 85.0 89.0 57.0 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:88.10 Median:70.50 stddev:23.87 (33.9%) stddev-sans-first:25.27 07:56:56 INFO - PROCESS | 1663 | Values: 84.0 83.0 133.0 69.0 72.0 73.0 115.0 69.0 68.0 115.0 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:85.20 Median:87.00 stddev:1.99 (2.3%) stddev-sans-first:2.00 07:56:56 INFO - PROCESS | 1663 | Values: 87.0 84.0 87.0 84.0 87.0 83.0 87.0 84.0 87.0 82.0 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:92.70 Median:93.00 stddev:1.06 (1.1%) stddev-sans-first:1.12 07:56:56 INFO - PROCESS | 1663 | Values: 93.0 92.0 91.0 94.0 94.0 93.0 92.0 94.0 92.0 92.0 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:86.00 stddev:1.20 (1.4%) stddev-sans-first:1.22 07:56:56 INFO - PROCESS | 1663 | Values: 84.0 86.0 86.0 86.0 83.0 84.0 86.0 84.0 84.0 86.0 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.06 (1.9%) stddev-sans-first:1.09 07:56:56 INFO - PROCESS | 1663 | Values: 54.0 54.0 56.0 56.0 56.0 55.0 54.0 54.0 53.0 55.0 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:78.80 Median:67.50 stddev:17.42 (25.8%) stddev-sans-first:17.85 07:56:56 INFO - PROCESS | 1663 | Values: 66.0 66.0 87.0 112.0 106.0 80.0 69.0 67.0 68.0 67.0 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:85.50 stddev:2.25 (2.6%) stddev-sans-first:2.37 07:56:56 INFO - PROCESS | 1663 | Values: 86.0 83.0 84.0 86.0 87.0 84.0 82.0 85.0 85.0 90.0 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:148.00 stddev:14.40 (9.7%) stddev-sans-first:14.91 07:56:56 INFO - PROCESS | 1663 | Values: 139.0 136.0 139.0 184.0 156.0 152.0 140.0 137.0 148.0 148.0 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.20 Median:180.50 stddev:25.96 (14.4%) stddev-sans-first:26.87 07:56:56 INFO - PROCESS | 1663 | Values: 170.0 168.0 168.0 230.0 191.0 170.0 168.0 167.0 198.0 232.0 07:56:56 INFO - PROCESS | 1663 | 07:56:56 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.16 (1.7%) stddev-sans-first:1.22 07:56:56 INFO - PROCESS | 1663 | Values: 68.0 68.0 70.0 68.0 69.0 67.0 67.0 66.0 67.0 67.0 07:56:56 INFO - PROCESS | 1663 | -------- Summary: end -------- 07:56:56 INFO - PROCESS | 1663 | 07:56:57 INFO - TEST-INFO | 1663: exit 0 07:56:57 INFO - TEST-OK | kraken | took 59456ms 07:56:57 INFO - SUITE-END | took 1176s 07:56:57 INFO - Completed test suite (00:19:36) 07:56:57 INFO - javascript benchmark 07:56:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2744.547149720296, 3128.1553980425288, 3350.6314305426386, 3362.512435696824, 3395.6604339566047, 5571.303587051619, 5651.886962260753, 5670.971645141779, 5726.770929162833, 5763.711814409284, 4668.276417155338, 4968.900621987558, 5679.602427830064, 5777.82666520004, 5784.088014822289, 5138.563222126124, 5153.484651534835, 5225.738713064332, 5233.21501774733, 5311.044012077825, 5259.211118332261, 5319.707416092114, 5340.25236466895, 5343.0, 5345.919811202835, 5100.285959965608, 5148.073346797575, 5148.845534633967, 5213.582913366921, 5263.3683957925105, 5250.7637156328055, 5260.500252476008, 5261.158214685632, 5261.842144735626, 5261.947380526222, 5183.403908550527, 5200.427952925175, 5251.422343542207, 5254.579633629301, 5283.86790330241, 5559.8888022239325, 5649.293838270216, 5705.74324155414, 5735.827925162252, 5748.223989761371, 6293.024581189923, 6298.622082675054, 6300.747970081191, 6302.991521356606, 6308.747650093991, 6274.058891166316, 6339.0, 6343.238811342622, 6347.047942808569, 6359.20509936258, 5621.915671264935, 5684.37471878093, 5719.0, 5753.165790960371, 5797.884042319172, 6252.531060170505, 6279.811605651837, 6287.49700023997, 6297.1813664223355, 6301.495880329563, 6107.175531303306, 6128.846778830476, 6178.783742568989, 6183.876322473526, 6195.287541932646, 6145.047517634774, 6166.506679465632, 6250.406211409909, 6299.905501417483, 6325.778597749057, 6206.100115483229, 6207.534434917399, 6216.129741836146, 6226.750929962751, 6226.782062627877, 6264.498840092781, 6265.342139075331, 6272.247330320391, 6297.4962003039645, 6298.8425289367215, 4803.807847686054, 4825.5898248648555, 4865.659403841768, 4933.4819843915875, 4969.602431805447, 4343.69594128408, 4433.202023635777, 4433.756143412079, 4460.710053846489, 4468.9721364086445, 5689.146628005832, 5761.683107429048, 5764.682942438206, 5768.682722450221, 5776.595638305278, 6347.52393570484, 6361.0, 6363.077353783676, 6370.522210834113, 6376.553641245068, 5527.39198688144, 5653.406392328829, 5719.628224165416, 5740.425957404226, 5762.827115186551, 5474.808381706621, 5479.424660410679, 5485.890282194334, 5492.0, 5501.834944951658, 6348.301686814447, 6368.5860419072615, 6375.489960803217, 6380.393862583047, 6383.2978372379, 6072.574919755575, 6215.875682486235, 6235.939890218583, 6300.802847458968, 6341.746330146742, 5311.203319502106, 6318.557700960889, 6368.0766288886925, 6371.649559274284, 6378.521610879296, 6214.5028397728975, 6244.718987645476, 6251.312355640966, 6252.093446450148, 6256.468200203034, 6098.512119030555, 6189.412005859436, 6222.346653601488, 6223.253209614694, 6309.968450157719, 6116.66358350295, 6125.754969801158, 6128.571000029955, 6132.264128304632, 6141.785037523844, 6091.786787462417, 6098.298695650057, 6119.0, 6121.663308517896, 6130.356312587292, 5741.770329186786, 5831.096180092092, 5896.823095307148, 5903.852403689943, 5963.52291816645, 6086.786962456293, 6146.323904370426, 6146.569740118149, 6164.4143806338325, 6171.938280617136, 5370.248165256789, 5937.079752638348, 5950.940490595039, 5961.344252132175, 5971.58198926071], "value": 5697.430209010437, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.6267392770602, 512.6078549909322, 529.6371985190145, 538.8248819133781, 542.9755660995255, 30686.38627227454, 40017.79991100044, 40902.79548602253, 41042.58957410425, 41094.58905410945, 35271.647283527156, 42455.0, 42855.78572107143, 42879.35680964788, 42887.78556107215, 9685.51572421378, 9917.206623470141, 9934.801303973916, 9941.353812002233, 9946.254030947672, 8897.466152030867, 9115.908840911572, 11990.340531270776, 12209.877901221007, 12224.816627750592, 12252.0, 12351.876481235207, 12355.938220308886, 12364.938175309111, 12376.0, 8956.417832840845, 12236.81644775329, 12275.815862762065, 12319.815202771966, 12341.938290308492, 11767.352795596238, 12078.939605302005, 12164.878351216463, 12313.56902508408, 12335.0, 40465.98835029148, 40843.18313633711, 40859.795701021605, 40912.97717557055, 41003.1799364011, 3422.7261819054415, 3430.6054803697643, 3448.861196816725, 3459.498372735939, 3559.341521818472, 30555.69444305551, 31342.0, 31545.949513281565, 32673.836630816695, 32746.836265818758, 3067.6932306769368, 3322.1196382958537, 3357.9832100839585, 3367.124547617637, 3378.560787097686, 496003.39966003475, 507242.0, 508705.0, 508735.0, 520628.3968580171, 57990.55023624443, 61150.77698446095, 64187.679061604395, 65039.67480162569, 65163.34836651668, 68680.6565967177, 68941.0, 68973.65513172402, 69432.0, 70287.0, 16343.42798002088, 16552.503424897273, 16606.0, 16609.252583633766, 16620.83379166217, 11362.261453005529, 11670.883291167149, 12555.246685198917, 12655.873441265654, 12680.365981701063, 17482.825171748118, 17633.7354939676, 17662.0, 17757.11214439273, 17758.75514608537, 7033.437325014089, 8471.830563388698, 8533.274671652978, 8594.527300998505, 8795.560221988875, 786.2059320086754, 968.6680813880914, 994.9154321882577, 1021.2085633633844, 1038.5949479702922, 30190.396192076034, 30296.0, 30321.0, 31079.533806992913, 31194.376112477625, 895.5119459894373, 1064.6060957445795, 1073.0878753059837, 1136.6135513925306, 1153.6077733570626, 14875.70248595022, 15372.461963831212, 15623.218839058001, 15643.84356156424, 16208.594785130466, 686048.0, 692386.0, 692737.0, 693748.0, 693814.0, 58078.0, 58554.12168817471, 58557.41442585605, 58587.12119318214, 58826.82346353049, 1235.9134860559675, 1357.7080927600416, 1380.2684577174114, 1404.8595140486075, 1417.4046900301691, 29754.553681695226, 30724.385512290075, 31380.686193137775, 32002.679973200902, 32244.677553224166, 47177.0, 47540.28689569728, 47705.522944770106, 47729.761351193025, 47742.5225747752, 458540.0, 461248.0, 462638.0, 463206.0, 463316.0, 27418.725812741617, 27828.72171278261, 27834.74743636582, 28798.280042999093, 42283.731488055404, 47251.291230632254, 47278.527214727415, 47304.7634761824, 47617.28574071461, 48350.0, 39067.804660976515, 39172.608273918035, 39265.48037025225, 39588.20823583454, 40737.79631101825, 39281.803590981865, 39783.40324895071, 40104.59633912799, 40604.18791624092, 41010.0, 38287.80856095702, 38915.416268756555, 38921.221575568896, 39135.21729565336, 39198.0, 31584.0, 33542.99371018873, 34176.65823341735, 34612.48081278833, 35039.299214016086, 23196.652050219596, 23893.0, 24965.50068998646, 25511.0, 25806.225813225632], "value": 20631.45703580876, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.8815690822029, 370.8961490782582, 373.4715377740497, 388.2119297825415, 406.62184168723076, 15277.923610381946, 16695.0, 21852.45368865779, 22108.668369974424, 22174.223902163427, 20682.89658551709, 21030.369088927317, 21474.0, 21676.0, 21732.45668858279, 6028.427299406571, 6268.404501572343, 7585.506942048777, 7947.920520794776, 8042.517448953082, 7793.298603125718, 8030.955975723148, 8057.234562716532, 8105.06791718951, 8108.472949258279, 8105.1084380718075, 8112.75661730149, 8120.9848768903885, 8127.796805079891, 8181.100078991306, 7911.090224624184, 8088.150744171866, 8147.307478864303, 8148.144444833267, 8157.592120394016, 7554.320111190019, 7768.456208065438, 7852.528848269122, 7853.371730261565, 7856.489328193649, 24340.269791906096, 28446.288842779097, 29068.709312906813, 29707.554386684216, 30522.54216186759, 1968.793276705954, 2595.9480810383875, 3006.353633968707, 3385.086026772782, 3424.8630054797777, 16964.491065268063, 17169.82830171695, 18036.90981545084, 18043.639127217382, 18118.184681689356, 1657.395050806447, 2026.8175864172294, 2046.1406209392092, 2053.0761157479196, 2128.521082756383, 110213.0, 121171.0, 123597.0, 123991.0, 126946.73053269534, 19548.413547593595, 20673.586528269352, 21113.894430527747, 21150.0, 21426.357209283746, 34468.621255150014, 34504.82747586296, 34566.7901623447, 34619.653803461646, 34641.82679086639, 8548.529830859237, 9488.667896623585, 10007.698999130067, 10604.0, 10748.623798167184, 8635.272945410943, 8870.2460290876, 8875.378723489293, 9048.0, 9098.590563424661, 7855.0, 9478.478683672327, 9626.374285671409, 9725.443928971472, 9735.172510336559, 2896.8551572421293, 2961.9111426657237, 3330.6169790474405, 3753.699704023726, 3833.271678381099, 345.7856129199862, 387.3009218360829, 403.8747988123691, 833.204289903141, 888.9066648001867, 11800.291982481078, 11823.586174483851, 11985.580504682468, 12215.572454964034, 12268.0, 359.91901822090347, 423.3142309458635, 423.97880105995193, 435.5861931165405, 459.02457278283646, 6078.483328917003, 6089.1779609753, 6151.630902145886, 6254.843628909313, 6264.248290205113, 590599.0, 590926.0, 604541.0, 605731.0, 608069.0, 134073.65926340612, 135919.6408035907, 137981.0, 139032.30483847516, 141253.0, 444.91991441540836, 454.0465023450793, 455.17840298261297, 455.7971702592381, 456.5959126173294, 11324.830127548259, 11358.318500889973, 11404.657860264206, 11636.709082273012, 11671.066314694974, 51783.2232516505, 53268.73365633147, 53707.0, 54288.72855635697, 55159.89680206453, 60613.69693151506, 77949.61025194838, 83373.0, 83618.0, 85648.57175714082, 7783.299503044753, 8045.919540804517, 8368.916310837058, 8400.28597569189, 8524.957375213084, 9180.946863184801, 13115.606531804062, 13388.866111338762, 13416.12795168311, 13586.45654173822, 11395.99723673167, 11607.129465290305, 11700.765984680089, 11799.76400472003, 11816.0, 11116.833247501132, 11210.159238057015, 11438.542458301576, 11620.825687614863, 11946.223495472766, 11490.597829075943, 11708.824367634323, 11911.702207444883, 12004.939975300067, 12078.27530348182, 8265.297449716601, 8319.168083191633, 8390.28682561989, 8458.06961234276, 8490.193431623986, 9656.806863862825, 11527.308361498337, 11740.647780566596, 11856.466459009192, 11888.524459021542], "value": 10132.349051620513, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1540.8844336674752, 1548.0556860315207, 1751.8861274017188, 1884.1803815340336, 1899.5536049028485, 7931.048274207096, 8370.121137280588, 8372.95813520933, 8375.413721039522, 8385.11956244594, 7721.884171737428, 7958.204179582039, 8296.626651800669, 8550.358723095776, 8605.741827745162, 2237.630790919496, 2286.6112760830615, 2287.3252390544744, 2288.2677543186187, 2288.553732022256, 2349.2012715676665, 2363.1020212319295, 2381.452265978817, 2388.605880029793, 2400.9039638414442, 2368.455255291284, 2385.42749740062, 2394.521095780851, 2396.9161079362225, 2410.8312418130736, 2344.1443872986406, 2364.550735360276, 2372.323887692017, 2372.430616652008, 2379.155399833069, 2325.2442956039354, 2368.289513146049, 2375.8099352051795, 2376.869272190029, 2378.702662167229, 8673.176048275403, 8735.43219690718, 8746.081661425585, 8753.124687531259, 8757.737267881974, 5196.402413722432, 5368.4363141869935, 5376.1398176291605, 5376.704281264529, 5376.892462150735, 3553.502509648651, 3582.3193593217466, 3703.5555733311908, 3714.888553343404, 3717.3494638438438, 1103.5420300575252, 1134.3080720760329, 1783.8126996665424, 1853.87949783265, 1901.5531350132708, 10930.617428390047, 11187.832182517268, 11211.607593734261, 11255.54977800887, 11350.318980861175, 4411.889702757392, 4458.866234012985, 4643.489216186217, 4647.581717645428, 4660.207764680013, 8983.865242021375, 8987.011428742833, 8988.505632190161, 8988.730338089737, 8988.86516702237, 5233.450487698813, 5236.16221404581, 5237.16205407133, 5242.842714718488, 5246.291750613695, 3665.7617254878346, 4478.275863792398, 4773.809047638056, 4917.041176970456, 4918.5573298403315, 3713.3687273163628, 3729.2355067211156, 3830.8084595770656, 3853.4412510185866, 3854.036490877281, 1147.6270212180907, 1167.4104577188466, 1173.12015988009, 1180.4510902430238, 1181.5214837990627, 898.3756289378949, 918.5958178401482, 925.3799954030816, 942.6473011228909, 981.74474636595, 5172.431032586413, 5370.91943620846, 5502.697351645618, 5682.0, 5889.7042650616795, 449.53473155284127, 456.79444250087596, 466.22839201122196, 467.1591135955267, 467.81988934260556, 3802.429635554689, 4011.2779699654343, 4053.6959728020515, 4126.42230087788, 4199.559046300094, 8967.686130985385, 10530.57877684884, 10710.518026688818, 10719.19606029551, 10800.0, 9934.701958941243, 10208.489575521195, 10501.894981050245, 10633.46832658364, 10713.982171693677, 7310.501347223806, 7414.258574142543, 7887.44787864844, 8068.636911339004, 8113.215092679601, 4853.95146048535, 5040.773165207648, 5543.667379957215, 5820.796272130455, 5955.225820643289, 9916.950415247878, 9991.0, 9998.000199979944, 10001.799964000826, 10007.249456290661, 9633.470159141314, 9848.113669769755, 9900.306978511435, 10141.492925353556, 10147.64483243083, 6936.341047600672, 7143.428525718034, 7249.855002899807, 7505.249475052451, 7581.962090189514, 7827.25641064098, 7893.210678932061, 7896.881546776688, 7924.247196516321, 8079.515229086273, 7089.61007144612, 7387.593682347522, 7407.8148046299275, 7432.145303290191, 7510.361619262424, 7310.378617817545, 7311.561306321638, 7312.96343518279, 7339.522931009467, 7379.8155046124275, 6834.487413444111, 6848.178218613798, 6924.688389022405, 6931.653417329214, 6935.0, 4358.956410435855, 4947.431045429822, 4954.455009948831, 4969.4782047884455, 5033.320501732219, 4942.851714448572, 4981.825636102719, 5006.949930500794, 5068.087744206078, 5102.158143906214], "value": 4466.016762046928, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.54353979561785, 300.9157435917943, 309.33801664438107, 309.5125177844892, 313.34510872276934, 4646.953530464694, 4993.901341704828, 5260.447652996438, 5295.841124766258, 5383.81156659517, 4002.879913602589, 4819.78310976006, 5103.040236662736, 5258.947410525893, 5354.946450535494, 4046.696497762665, 4060.9187816243657, 4088.550259471456, 4088.570700076501, 4097.3239415496555, 3910.0, 4066.5323487798837, 4077.9592204077876, 4094.075784381351, 4112.38314252863, 3999.680025597945, 4009.719319647626, 4029.1264561978674, 4037.1538065663785, 4068.247374235761, 3326.451135562629, 4020.979895100535, 4026.0, 4032.2338755636333, 4067.8983025424304, 2947.7052294770565, 3137.403893260296, 3217.9887570268584, 3489.6510348965153, 3520.1903562180587, 4369.7059044001835, 4891.975540122312, 5234.97382513085, 5271.051210782058, 5321.414644389153, 3828.7511311764647, 4221.092465120014, 4502.279635258343, 4569.520200378945, 4592.563706447882, 4869.561739443467, 5468.863278418032, 5552.250446189753, 5617.831465056055, 5625.212470254168, 3242.3353212591364, 3245.7078862902454, 3547.8048707321086, 3811.7712937223855, 3821.942446043154, 5136.018062479389, 5481.547389941668, 5859.538993622688, 5859.769448415837, 5867.589268751189, 3150.070729134911, 3356.5133055706788, 3528.100334414708, 3858.5369755629504, 3875.5349358076646, 3770.717955895009, 3912.0024393779404, 3924.372100463912, 3935.2916475034767, 3947.2697550953167, 3594.856205751793, 4520.344550040291, 4539.182947069559, 4542.454905411372, 4564.497905230422, 4728.95271047292, 5377.300950876361, 5652.9152062719095, 5670.489655930904, 5679.346875109332, 1258.8489381274305, 1270.8093785932185, 1289.5035411366691, 1304.7194853106628, 1312.4750099960092, 753.7512620835112, 876.6361959786744, 883.7392969074139, 889.6574818694849, 892.3218354050967, 5236.73816309183, 5254.264402983586, 5265.341832270966, 5267.868303292448, 5284.0, 5585.888282234332, 5634.943650563442, 5723.855228954226, 5736.885262294731, 5752.712364381764, 5112.974435127875, 5152.9694061187165, 5177.301064356264, 5189.7405129743365, 5209.6874187548865, 2357.927143149863, 2415.3237093613816, 2426.332758491429, 2439.7194322652767, 2523.233736384538, 4426.782634775398, 4569.8521694803385, 4744.312074749142, 4947.431045429822, 4958.330625365529, 4396.636573021641, 4827.710337379768, 4843.543939575599, 4851.385717640381, 4880.438749543777, 4270.871873843789, 4439.357437747989, 4782.641301902371, 4889.217725163886, 4922.409310882717, 4197.601227883346, 4732.0, 4771.570558649738, 5142.742862856917, 5741.995150848668, 4734.786934588021, 4993.675411098267, 5611.84957083805, 5688.886222275614, 5746.0, 5272.393674727456, 5987.2815262168815, 6014.548908831767, 6036.0, 6061.636301821904, 3570.803605801602, 3723.888283351504, 3798.9240215196096, 3830.9464897153507, 3851.768893866377, 5696.8006119785605, 5707.771689132388, 5742.59801813869, 5748.856278593069, 5765.365809761007, 4722.504137065559, 4873.6832105913, 5658.349289831722, 5688.5733569981585, 5709.601147718836, 4459.576340247671, 5077.822276220315, 5480.3971563128825, 5531.446855314436, 5553.444655534414, 4619.376384188092, 5161.329027226437, 5366.785328586813, 5450.146950037007, 5497.697626630574, 4676.9243074092465, 5297.708626025606, 5446.754896029609, 5626.465485778845, 5636.577256705846, 5219.243209734491, 5421.349438067457, 5517.26895186885, 5523.917141242804, 5559.58303127259], "value": 3952.6984494754925, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.5043994863519, 764.2433990349556, 784.1922819495921, 817.6867026435548, 837.5142417397908, 15441.613959651015, 16185.676286474263, 16481.752773708387, 16513.25690343936, 16601.750973735405, 15028.624284392896, 15084.6983060339, 15309.311081001353, 15333.0, 15340.923295383507, 702.1503980183984, 861.5132450165652, 877.5173654490037, 882.0385779500343, 882.3691060891462, 716.373173473211, 728.9890651640229, 890.3945317184312, 894.579547612621, 900.7928176519372, 933.593886659302, 938.3290946972913, 939.6429356844424, 941.6233506597347, 945.9290553208502, 739.0540108660923, 922.8892532896095, 928.7353104365291, 929.2333824594702, 932.9207017403529, 560.2324815003471, 563.3943510725945, 565.8924804287172, 672.6804767735335, 697.9930200697979, 14845.960782745213, 14863.851361486355, 14883.10701357922, 14892.62768430787, 14907.329170187368, 14080.859191408057, 14280.642983925381, 14533.34599943005, 14710.55868323952, 14789.408423663148, 12899.613011609667, 13094.410751516205, 13308.467661293536, 13381.53164639233, 13808.1024733392, 4563.292689633112, 4671.602913752336, 4705.623550116016, 4717.599004084657, 4728.810847566127, 122300.0, 122503.0, 122516.07291052195, 122541.38729306296, 122615.0, 9356.625734970661, 11425.371604561662, 11519.0, 12018.338991355384, 12053.156279060557, 22790.43023924382, 22833.543329133325, 22835.543289134122, 22853.971571279333, 22883.31350059501, 8201.71294004707, 8291.751247462586, 8292.17078292178, 8295.792605184919, 8297.751067467985, 4394.758288294174, 4469.329600559877, 4699.577038066522, 4735.573798358096, 4750.738709371018, 3429.6570342965506, 3570.071781336871, 3570.232400034005, 3572.499850020999, 3588.6052534221217, 567.324883388766, 659.2319947261483, 668.6088638146641, 669.1501792723215, 670.7819958513405, 594.2542109652359, 607.2106261859565, 619.2599843187438, 650.9641969691712, 664.2095905871997, 7804.726834560764, 7996.572612785007, 8312.625931833081, 9778.951105244429, 10982.560697572007, 532.3072629039691, 543.8912217556505, 658.887989041864, 717.6196615793533, 731.2451010547029, 4095.3242714952294, 4589.5181005994555, 4680.882977925511, 4742.407199100113, 4778.904421911542, 167503.32496674877, 167798.16100919663, 169323.0, 172026.0, 175690.12154939398, 97805.0, 98635.0, 98879.0, 102004.0, 103131.0, 70282.64858675879, 104781.95218047875, 110442.0, 110449.44775276072, 112002.0, 9659.372140810825, 9938.354006989523, 10000.099991000845, 10021.148202402877, 10045.748856278651, 50292.74853625709, 61452.31846019247, 61791.382086178564, 62269.06596400967, 63860.361396385444, 90602.64096038433, 94723.36936414384, 95852.52073739587, 97209.0, 99821.50089249507, 12637.873621264038, 13167.473301068234, 13275.867241327463, 13297.0, 13368.398422071225, 14691.338889749773, 14821.004048421672, 14944.925275373553, 15038.398464061314, 15454.768178477107, 11073.335599864035, 11212.0, 14166.433342666178, 14348.426062957782, 14593.343299551334, 14000.929995350365, 14042.78935815984, 14211.218382989035, 14551.708965820835, 14752.852471475577, 13502.0, 13695.726085478434, 13857.584272471842, 13897.444102235797, 13935.651608709863, 11019.559217631206, 11043.723906902393, 11298.09615230796, 11746.412679365827, 11956.820647690118, 11445.828312575484, 11508.0, 11533.826992595286, 11569.247998880046, 11794.52821887115], "value": 7967.924984791428, "unit": "score"}], "name": "dromaeo_css", "value": 7424.858591009697, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 113.0, 107.0, 115.0, 156.0, 164.0, 116.0, 113.0, 113.0, 111.0], "value": 120.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 138.0, 133.0, 170.0, 134.0, 162.0, 127.0, 111.0, 197.0, 138.0], "value": 148.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [249.0, 256.0, 187.0, 184.0, 181.0, 186.0, 186.0, 183.0, 183.0, 185.0], "value": 198.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 107.0, 92.0, 57.0, 61.0, 63.0, 85.0, 89.0, 57.0], "value": 76.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 133.0, 69.0, 72.0, 73.0, 115.0, 69.0, 68.0, 115.0], "value": 88.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 87.0, 83.0, 87.0, 84.0, 87.0, 82.0], "value": 85.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 91.0, 94.0, 94.0, 93.0, 92.0, 94.0, 92.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 86.0, 86.0, 83.0, 84.0, 86.0, 84.0, 84.0, 86.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 56.0, 56.0, 56.0, 55.0, 54.0, 54.0, 53.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 87.0, 112.0, 106.0, 80.0, 69.0, 67.0, 68.0, 67.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 84.0, 86.0, 87.0, 84.0, 82.0, 85.0, 85.0, 90.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 139.0, 184.0, 156.0, 152.0, 140.0, 137.0, 148.0, 148.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 168.0, 230.0, 191.0, 170.0, 168.0, 167.0, 198.0, 232.0], "value": 186.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 68.0, 69.0, 67.0, 67.0, 66.0, 67.0, 67.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1514.5000000000005, "alertThreshold": 2.0}]} 07:56:57 INFO - Return code: 0 07:56:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:56:57 INFO - # TBPL SUCCESS # 07:56:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:56:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:56:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:56:57 INFO - 2016-08-22 07:56:57.558 screenresolution[1667:9983] starting screenresolution argv=screenresolution get 07:56:57 INFO - 2016-08-22 07:56:57.560 screenresolution[1667:9983] Display 0: 1600x1200x32@60 07:56:57 INFO - 2016-08-22 07:56:57.566 screenresolution[1668:9986] starting screenresolution argv=screenresolution list 07:56:57 INFO - Available Modes on Display 0 07:56:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:56:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:56:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:56:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:56:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:56:57 INFO - Intel Iris: 07:56:57 INFO - Chipset Model: Intel Iris 07:56:57 INFO - Type: GPU 07:56:57 INFO - Bus: Built-In 07:56:57 INFO - VRAM (Dynamic, Max): 1536 MB 07:56:57 INFO - Vendor: Intel (0x8086) 07:56:57 INFO - Device ID: 0x0a2e 07:56:57 INFO - Revision ID: 0x0009 07:56:57 INFO - Displays: 07:56:57 INFO - Display: 07:56:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:56:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:56:57 INFO - Display Serial Number: 1600x1200 60 07:56:57 INFO - Main Display: Yes 07:56:57 INFO - Mirror: Off 07:56:57 INFO - Online: Yes 07:56:57 INFO - Rotation: Supported 07:56:57 INFO - Return code: 0 07:56:57 INFO - Running post-action listener: _resource_record_post_action 07:56:57 INFO - [mozharness: 2016-08-22 14:56:57.801556Z] Finished run-tests step (success) 07:56:57 INFO - Running post-run listener: _resource_record_post_run 07:56:57 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 33037312; Write bytes: 706365952; Read time: 308; Write time: 1971 07:56:57 INFO - TinderboxPrint: CPU usage
53.6% 07:56:57 INFO - TinderboxPrint: I/O read bytes / time
33,037,312 / 308 07:56:57 INFO - TinderboxPrint: I/O write bytes / time
706,365,952 / 1,971 07:56:57 INFO - TinderboxPrint: CPU idle
2,248.9 (46.4%) 07:56:57 INFO - TinderboxPrint: CPU system
973.8 (20.1%) 07:56:57 INFO - TinderboxPrint: CPU user
1,627.1 (33.5%) 07:56:57 INFO - TinderboxPrint: Swap in / out
435,048,448 / 0 07:56:57 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393347584; Write bytes: 387581952; Read time: 29525; Write time: 697 07:56:57 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31226880; Write bytes: 296665600; Read time: 290; Write time: 1226 07:56:58 INFO - Running post-run listener: _upload_blobber_files 07:56:58 INFO - Blob upload gear active. 07:56:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:56:58 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 07:56:58 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'] 07:56:58 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 07:56:58 INFO - (blobuploader) - INFO - Open directory for files ... 07:56:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:56:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:56:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:59 INFO - (blobuploader) - INFO - Done attempting. 07:56:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:56:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:57:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:57:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:57:00 INFO - (blobuploader) - INFO - Done attempting. 07:57:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:57:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:57:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:57:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:57:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:57:02 INFO - (blobuploader) - INFO - Done attempting. 07:57:02 INFO - (blobuploader) - INFO - Iteration through files over. 07:57:02 INFO - Return code: 0 07:57:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:57:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:57:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e39ec486060290db259b92ae59d3e54e5eb4f345229ed6212e58a4787e6526e3cda7715a9a4b64aceb021dd91133daf7222b39b4c1d15b224bf8e18a13f9015c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1bd13884ce5e930debce552e8589530aae3afbd88efd01bec4150ba21782d985212cd2276d335f35493c7d43cdd768f7f94ff17a401d6d0864af39dd85fe865c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3754d69ddda6fd9bc68d8c2a68b926873ab58f2bacf0cb82ce7c827e4e0f8812002ee76414fcd79c7557af7900f503e05292f79a657001b8da6ad6f1b1371c47"} 07:57:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:57:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:57:02 INFO - Contents: 07:57:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e39ec486060290db259b92ae59d3e54e5eb4f345229ed6212e58a4787e6526e3cda7715a9a4b64aceb021dd91133daf7222b39b4c1d15b224bf8e18a13f9015c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1bd13884ce5e930debce552e8589530aae3afbd88efd01bec4150ba21782d985212cd2276d335f35493c7d43cdd768f7f94ff17a401d6d0864af39dd85fe865c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3754d69ddda6fd9bc68d8c2a68b926873ab58f2bacf0cb82ce7c827e4e0f8812002ee76414fcd79c7557af7900f503e05292f79a657001b8da6ad6f1b1371c47"} 07:57:02 INFO - Running post-run listener: copy_logs_to_upload_dir 07:57:02 INFO - Copying logs to upload dir... 07:57:02 INFO - mkdir: /builds/slave/test/build/upload/logs 07:57:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.237800 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-22 07:57:02.687967) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 07:57:02.691038) ========= 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.JGgGOnQuS0/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.O8baxWCIdw/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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/e39ec486060290db259b92ae59d3e54e5eb4f345229ed6212e58a4787e6526e3cda7715a9a4b64aceb021dd91133daf7222b39b4c1d15b224bf8e18a13f9015c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1bd13884ce5e930debce552e8589530aae3afbd88efd01bec4150ba21782d985212cd2276d335f35493c7d43cdd768f7f94ff17a401d6d0864af39dd85fe865c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3754d69ddda6fd9bc68d8c2a68b926873ab58f2bacf0cb82ce7c827e4e0f8812002ee76414fcd79c7557af7900f503e05292f79a657001b8da6ad6f1b1371c47"} build_url:https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010831 build_url: 'https://queue.taskcluster.net/v1/task/fa6-kE6iRkaO9eNB7PerUg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e39ec486060290db259b92ae59d3e54e5eb4f345229ed6212e58a4787e6526e3cda7715a9a4b64aceb021dd91133daf7222b39b4c1d15b224bf8e18a13f9015c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1bd13884ce5e930debce552e8589530aae3afbd88efd01bec4150ba21782d985212cd2276d335f35493c7d43cdd768f7f94ff17a401d6d0864af39dd85fe865c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3754d69ddda6fd9bc68d8c2a68b926873ab58f2bacf0cb82ce7c827e4e0f8812002ee76414fcd79c7557af7900f503e05292f79a657001b8da6ad6f1b1371c47"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 07:57:02.724050) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 07:57:02.724443) ========= 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.JGgGOnQuS0/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.O8baxWCIdw/Listeners TMPDIR=/var/folders/cn/kbjph2xn3s502v35v9_fg26800000w/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.005177 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 07:57:02.773830) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 07:57:02.774189) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 07:57:02.774568) ========= ========= Total master_lag: 4.60 =========