builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0112 starttime: 1472705269.09 results: success (0) buildid: 20160831193821 builduid: 17965e42d105432ab4821047bceb5fff revision: 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 21:47:49.085990) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 21:47:49.086473) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 21:47:49.086749) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 21:47:49.112426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 21:47:49.112703) ========= 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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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-31 21:47:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 156M=0s 2016-08-31 21:47:49 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.471302 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 21:47:49.624188) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 21:47:49.624504) ========= 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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.020377 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 21:47:49.688610) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 21:47:49.688944) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 --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 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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-31 21:47:49,788 truncating revision to first 12 chars 2016-08-31 21:47:49,788 Setting DEBUG logging. 2016-08-31 21:47:49,788 attempt 1/10 2016-08-31 21:47:49,788 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9f33ab9fd502?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 21:47:50,447 unpacking tar archive at: fx-team-9f33ab9fd502/testing/mozharness/ program finished with exit code 0 elapsedTime=0.907530 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 21:47:50.626026) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 21:47:50.626368) ========= script_repo_revision: 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 21:47:50.626762) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 21:47:50.627036) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 21:47:50.645085) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-31 21:47:50.645382) ========= /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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:47:50 INFO - MultiFileLogger online at 20160831 21:47:50 in /builds/slave/test 21:47:50 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 21:47:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:47:50 INFO - {'append_to_log': False, 21:47:50 INFO - 'base_work_dir': '/builds/slave/test', 21:47:50 INFO - 'blob_upload_branch': 'Fx-Team', 21:47:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:47:50 INFO - 'branch': 'Fx-Team', 21:47:50 INFO - 'buildbot_json_path': 'buildprops.json', 21:47:50 INFO - 'config_files': ('talos/mac_config.py',), 21:47:50 INFO - 'default_actions': ('clobber', 21:47:50 INFO - 'read-buildbot-config', 21:47:50 INFO - 'download-and-extract', 21:47:50 INFO - 'populate-webroot', 21:47:50 INFO - 'create-virtualenv', 21:47:50 INFO - 'install', 21:47:50 INFO - 'run-tests'), 21:47:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:47:50 INFO - 'download_minidump_stackwalk': True, 21:47:50 INFO - 'download_symbols': 'ondemand', 21:47:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:47:50 INFO - 'tooltool.py': '/tools/tooltool.py', 21:47:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:47:50 INFO - '/tools/misc-python/virtualenv.py')}, 21:47:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:47:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:47:50 INFO - 'installer_path': 'installer.exe', 21:47:50 INFO - 'log_level': 'info', 21:47:50 INFO - 'log_name': 'talos', 21:47:50 INFO - 'log_to_console': True, 21:47:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:47:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:47:50 INFO - 'opt_config_files': (), 21:47:50 INFO - 'pip_index': False, 21:47:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:47:50 INFO - 'cmd': ('bash', 21:47:50 INFO - '-c', 21:47:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:47:50 INFO - 'enabled': True, 21:47:50 INFO - 'halt_on_failure': False, 21:47:50 INFO - 'name': 'check_screen_resolution'},), 21:47:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:47:50 INFO - 'cmd': ('bash', 21:47:50 INFO - '-c', 21:47:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:47:50 INFO - 'enabled': True, 21:47:50 INFO - 'halt_on_failure': False, 21:47:50 INFO - 'name': 'check_screen_resolution'},), 21:47:50 INFO - 'run_cmd_checks_enabled': True, 21:47:50 INFO - 'sps_profile': False, 21:47:50 INFO - 'sps_profile_interval': 0, 21:47:50 INFO - 'suite': 'dromaeojs', 21:47:50 INFO - 'system_bits': '32', 21:47:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:47:50 INFO - 'title': 't-yosemite-r7-0112', 21:47:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:47:50 INFO - 'use_talos_json': True, 21:47:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:47:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:47:50 INFO - 'work_dir': 'build'} 21:47:50 INFO - [mozharness: 2016-09-01 04:47:50.857254Z] Running clobber step. 21:47:50 INFO - Running pre-action listener: _resource_record_pre_action 21:47:50 INFO - Running main action method: clobber 21:47:50 INFO - rmtree: /builds/slave/test/build 21:47:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:47:52 INFO - Running post-action listener: _resource_record_post_action 21:47:52 INFO - [mozharness: 2016-09-01 04:47:52.452752Z] Finished clobber step (success) 21:47:52 INFO - [mozharness: 2016-09-01 04:47:52.452881Z] Running read-buildbot-config step. 21:47:52 INFO - Running pre-action listener: _resource_record_pre_action 21:47:52 INFO - Running main action method: read_buildbot_config 21:47:52 INFO - Using buildbot properties: 21:47:52 INFO - { 21:47:52 INFO - "project": "", 21:47:52 INFO - "product": "firefox", 21:47:52 INFO - "script_repo_revision": "production", 21:47:52 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 21:47:52 INFO - "repository": "", 21:47:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 21:47:52 INFO - "buildid": "20160831193821", 21:47:52 INFO - "pgo_build": "False", 21:47:52 INFO - "basedir": "/builds/slave/test", 21:47:52 INFO - "buildnumber": 130, 21:47:52 INFO - "slavename": "t-yosemite-r7-0112", 21:47:52 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 21:47:52 INFO - "platform": "yosemite_r7", 21:47:52 INFO - "scheduler": "tests-fx-team-macosx64-talos", 21:47:52 INFO - "branch": "fx-team", 21:47:52 INFO - "repo_path": "integration/fx-team", 21:47:52 INFO - "revision": "9f33ab9fd502d2a4c38aef7744ab68c0aaa78234", 21:47:52 INFO - "stage_platform": "macosx64", 21:47:52 INFO - "builduid": "17965e42d105432ab4821047bceb5fff", 21:47:52 INFO - "slavebuilddir": "test" 21:47:52 INFO - } 21:47:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 21:47:52 INFO - Running post-action listener: _resource_record_post_action 21:47:52 INFO - [mozharness: 2016-09-01 04:47:52.456004Z] Finished read-buildbot-config step (success) 21:47:52 INFO - [mozharness: 2016-09-01 04:47:52.456114Z] Running download-and-extract step. 21:47:52 INFO - Running pre-action listener: _resource_record_pre_action 21:47:52 INFO - Running main action method: download_and_extract 21:47:52 INFO - mkdir: /builds/slave/test/build/tests 21:47:52 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')]} 21:47:52 INFO - trying https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 21:47:52 INFO - Downloading https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/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 21:47:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/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 21:47:53 INFO - Downloaded 1199 bytes. 21:47:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 21:47:53 INFO - Using the following test package requirements: 21:47:53 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:47:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:47:53 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 21:47:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:47:53 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 21:47:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:47:53 INFO - u'jsshell-mac.zip'], 21:47:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:47:53 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 21:47:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:47:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:47:53 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 21:47:53 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:47:53 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 21:47:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:47:53 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 21:47:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:47:53 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 21:47:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:47: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')]} 21:47:53 INFO - trying https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:47:53 INFO - Downloading https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/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 21:47:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/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 21:47:55 INFO - Downloaded 16400001 bytes. 21:47: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 21:48: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 21:48: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')]} 21:48:02 INFO - trying https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:48:02 INFO - Downloading https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/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 21:48:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/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 21:48:03 INFO - Downloaded 16400001 bytes. 21:48: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 21:48:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:10 INFO - trying https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 21:48:10 INFO - Downloading https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/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 21:48:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/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 21:48:11 INFO - Downloaded 14413998 bytes. 21:48: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 21:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:12 INFO - trying https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:48:12 INFO - Downloading https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 21:48:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/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 21:48:15 INFO - Downloaded 111597874 bytes. 21:48:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:48:15 INFO - mkdir: /builds/slave/test/properties 21:48:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:48:15 INFO - Writing to file /builds/slave/test/properties/build_url 21:48:15 INFO - Contents: 21:48:15 INFO - build_url:https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:48:16 INFO - Running post-action listener: _resource_record_post_action 21:48:16 INFO - Running post-action listener: set_extra_try_arguments 21:48:16 INFO - [mozharness: 2016-09-01 04:48:16.260374Z] Finished download-and-extract step (success) 21:48:16 INFO - [mozharness: 2016-09-01 04:48:16.260544Z] Running populate-webroot step. 21:48:16 INFO - Running pre-action listener: _resource_record_pre_action 21:48:16 INFO - Running main action method: populate_webroot 21:48:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:48:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:48:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:48:16 INFO - '--tppagecycles', 21:48:16 INFO - '7'], 21:48:16 INFO - 'tests': ['tsvgm']}}, 21:48:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:48:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:48:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:48:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:48:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:48:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:48:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:16 INFO - 'talos_options': ['--disable-e10s'], 21:48:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:48:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:48:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:16 INFO - 'talos_options': ['--disable-e10s'], 21:48:16 INFO - 'tests': ['damp', 'tps']}, 21:48:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:16 INFO - 'tests': ['damp', 'tps']}, 21:48:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:48:16 INFO - 'tests': ['dromaeo_dom']}, 21:48:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:48:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:48:16 INFO - 'tests': ['basic_compositor_video']}, 21:48:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:48:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:48:16 INFO - 'tests': ['a11yr', 21:48:16 INFO - 'ts_paint', 21:48:16 INFO - 'tpaint', 21:48:16 INFO - 'sessionrestore', 21:48:16 INFO - 'sessionrestore_no_auto_restore', 21:48:16 INFO - 'tabpaint']}, 21:48:16 INFO - 'other-e10s': {'tests': ['a11yr', 21:48:16 INFO - 'ts_paint', 21:48:16 INFO - 'tpaint', 21:48:16 INFO - 'sessionrestore', 21:48:16 INFO - 'sessionrestore_no_auto_restore', 21:48:16 INFO - 'tabpaint']}, 21:48:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:48:16 INFO - 'ts_paint', 21:48:16 INFO - 'tpaint', 21:48:16 INFO - 'sessionrestore', 21:48:16 INFO - 'sessionrestore_no_auto_restore', 21:48:16 INFO - 'tabpaint']}, 21:48:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:48:16 INFO - 'ts_paint', 21:48:16 INFO - 'tpaint', 21:48:16 INFO - 'sessionrestore', 21:48:16 INFO - 'sessionrestore_no_auto_restore', 21:48:16 INFO - 'tabpaint']}, 21:48:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:48:16 INFO - 'tests': ['a11yr', 21:48:16 INFO - 'ts_paint', 21:48:16 INFO - 'tpaint', 21:48:16 INFO - 'sessionrestore', 21:48:16 INFO - 'sessionrestore_no_auto_restore', 21:48:16 INFO - 'tabpaint']}, 21:48:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:48:16 INFO - 'tests': ['a11yr', 21:48:16 INFO - 'ts_paint', 21:48:16 INFO - 'tpaint', 21:48:16 INFO - 'sessionrestore', 21:48:16 INFO - 'sessionrestore_no_auto_restore', 21:48:16 INFO - 'tabpaint']}, 21:48:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:48:16 INFO - 'tests': ['tsvgx', 21:48:16 INFO - 'tsvgr_opacity', 21:48:16 INFO - 'tart', 21:48:16 INFO - 'tscrollx', 21:48:16 INFO - 'cart']}, 21:48:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:48:16 INFO - 'tsvgr_opacity', 21:48:16 INFO - 'tart', 21:48:16 INFO - 'tscrollx', 21:48:16 INFO - 'cart']}, 21:48:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:16 INFO - 'talos_options': ['--disable-e10s'], 21:48:16 INFO - 'tests': ['tp5o']}, 21:48:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:16 INFO - 'tests': ['tp5o']}, 21:48:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:16 INFO - 'talos_options': ['--disable-e10s', 21:48:16 INFO - '--xperf_path', 21:48:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:48:16 INFO - 'tests': ['tp5n']}, 21:48:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:48:16 INFO - 'talos_options': ['--xperf_path', 21:48:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:48:16 INFO - 'tests': ['tp5n']}}, 21:48:16 INFO - 'talos.zip': {'path': '', 21:48:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:48:16 INFO - Running post-action listener: _resource_record_post_action 21:48:16 INFO - [mozharness: 2016-09-01 04:48:16.279231Z] Finished populate-webroot step (success) 21:48:16 INFO - [mozharness: 2016-09-01 04:48:16.279364Z] Running create-virtualenv step. 21:48:16 INFO - Running pre-action listener: _resource_record_pre_action 21:48:16 INFO - Running main action method: create_virtualenv 21:48:16 INFO - Creating virtualenv /builds/slave/test/build/venv 21:48:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:48:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:48:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:48:16 INFO - Using real prefix '/tools/python27' 21:48:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:48:17 INFO - Installing distribute.............................................................................................................................................................................................done. 21:48:19 INFO - Installing pip.................done. 21:48:20 INFO - Return code: 0 21:48:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:48: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')]} 21:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48: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')]} 21:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ffda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a092618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd611d72240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f61d30>, '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 0x109cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f59b20>, '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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:48:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:48:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQSoY7FOlb/Render', 21:48:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:20 INFO - 'HOME': '/Users/cltbld', 21:48:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:20 INFO - 'LOGNAME': 'cltbld', 21:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:20 INFO - 'MOZ_NO_REMOTE': '1', 21:48:20 INFO - 'NO_EM_RESTART': '1', 21:48:20 INFO - 'PAGER': '/bin/cat', 21:48:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:20 INFO - 'PWD': '/builds/slave/test', 21:48:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:20 INFO - 'SHELL': '/bin/bash', 21:48:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BCIWRUpUeQ/Listeners', 21:48:20 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:48:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:20 INFO - 'USER': 'cltbld', 21:48:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:20 INFO - 'XPC_FLAGS': '0x0', 21:48:20 INFO - 'XPC_SERVICE_NAME': '0', 21:48:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 21:48:20 INFO - Cleaning up... 21:48:20 INFO - Return code: 0 21:48:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:48: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')]} 21:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48: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')]} 21:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ffda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a092618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd611d72240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f61d30>, '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 0x109cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f59b20>, '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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:48:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:48:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQSoY7FOlb/Render', 21:48:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:20 INFO - 'HOME': '/Users/cltbld', 21:48:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:20 INFO - 'LOGNAME': 'cltbld', 21:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:20 INFO - 'MOZ_NO_REMOTE': '1', 21:48:20 INFO - 'NO_EM_RESTART': '1', 21:48:20 INFO - 'PAGER': '/bin/cat', 21:48:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:20 INFO - 'PWD': '/builds/slave/test', 21:48:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:20 INFO - 'SHELL': '/bin/bash', 21:48:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BCIWRUpUeQ/Listeners', 21:48:20 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:48:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:20 INFO - 'USER': 'cltbld', 21:48:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:20 INFO - 'XPC_FLAGS': '0x0', 21:48:20 INFO - 'XPC_SERVICE_NAME': '0', 21:48:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:20 INFO - Downloading/unpacking psutil>=3.1.1 21:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:48:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:48:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:48:24 INFO - Installing collected packages: psutil 21:48:24 INFO - Running setup.py install for psutil 21:48:24 INFO - building 'psutil._psutil_osx' extension 21:48:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 21:48:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 21:48:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 21:48:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 21:48:25 INFO - building 'psutil._psutil_posix' extension 21:48:25 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 21:48:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:48:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:48:25 INFO - ^ 21:48:25 INFO - 1 warning generated. 21:48:25 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 21:48:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:48:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:48:25 INFO - Successfully installed psutil 21:48:25 INFO - Cleaning up... 21:48:25 INFO - Return code: 0 21:48:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:25 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 0x109ffda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a092618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd611d72240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f61d30>, '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 0x109cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f59b20>, '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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:48:25 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 21:48:25 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 21:48:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQSoY7FOlb/Render', 21:48:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:25 INFO - 'HOME': '/Users/cltbld', 21:48:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:25 INFO - 'LOGNAME': 'cltbld', 21:48:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:25 INFO - 'MOZ_NO_REMOTE': '1', 21:48:25 INFO - 'NO_EM_RESTART': '1', 21:48:25 INFO - 'PAGER': '/bin/cat', 21:48:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:25 INFO - 'PWD': '/builds/slave/test', 21:48:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:25 INFO - 'SHELL': '/bin/bash', 21:48:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BCIWRUpUeQ/Listeners', 21:48:25 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:48:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:25 INFO - 'USER': 'cltbld', 21:48:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:25 INFO - 'XPC_FLAGS': '0x0', 21:48:25 INFO - 'XPC_SERVICE_NAME': '0', 21:48:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:48:25 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 21:48:25 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 21:48:25 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 21:48:25 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 21:48:25 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 21:48:25 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 21:48:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:48:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:48:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:48:28 INFO - Installing collected packages: mozsystemmonitor 21:48:28 INFO - Running setup.py install for mozsystemmonitor 21:48:28 INFO - Successfully installed mozsystemmonitor 21:48:28 INFO - Cleaning up... 21:48:28 INFO - Return code: 0 21:48:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:48:28 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')]} 21:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:28 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')]} 21:48:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:28 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 0x109ffda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a092618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd611d72240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f61d30>, '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 0x109cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f59b20>, '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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:48:28 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 21:48:28 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 21:48:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQSoY7FOlb/Render', 21:48:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:28 INFO - 'HOME': '/Users/cltbld', 21:48:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:28 INFO - 'LOGNAME': 'cltbld', 21:48:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:28 INFO - 'MOZ_NO_REMOTE': '1', 21:48:28 INFO - 'NO_EM_RESTART': '1', 21:48:28 INFO - 'PAGER': '/bin/cat', 21:48:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:28 INFO - 'PWD': '/builds/slave/test', 21:48:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:28 INFO - 'SHELL': '/bin/bash', 21:48:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BCIWRUpUeQ/Listeners', 21:48:28 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:48:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:28 INFO - 'USER': 'cltbld', 21:48:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:28 INFO - 'XPC_FLAGS': '0x0', 21:48:28 INFO - 'XPC_SERVICE_NAME': '0', 21:48:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:28 INFO - Downloading/unpacking jsonschema==2.5.1 21:48: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 21:48: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 21:48: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 21:48: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 21:48: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 21:48: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 21:48:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:48:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:48: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 21:48: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 21:48: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 21:48: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 21:48: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 21:48: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 21:48:31 INFO - Downloading functools32-3.2.3-2.tar.gz 21:48:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:48:31 INFO - warning: no files found matching '*.txt' 21:48:31 INFO - no previously-included directories found matching 'build' 21:48:31 INFO - no previously-included directories found matching 'dist' 21:48:31 INFO - no previously-included directories found matching '.git*' 21:48:31 INFO - Installing collected packages: jsonschema, functools32 21:48:31 INFO - Running setup.py install for functools32 21:48:31 INFO - warning: no files found matching '*.txt' 21:48:31 INFO - no previously-included directories found matching 'build' 21:48:31 INFO - no previously-included directories found matching 'dist' 21:48:31 INFO - no previously-included directories found matching '.git*' 21:48:31 INFO - Successfully installed jsonschema functools32 21:48:31 INFO - Cleaning up... 21:48:31 INFO - Return code: 0 21:48:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109ffda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a092618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd611d72240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f61d30>, '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 0x109cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f59b20>, '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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:48:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQSoY7FOlb/Render', 21:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:31 INFO - 'HOME': '/Users/cltbld', 21:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:31 INFO - 'LOGNAME': 'cltbld', 21:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:31 INFO - 'MOZ_NO_REMOTE': '1', 21:48:31 INFO - 'NO_EM_RESTART': '1', 21:48:31 INFO - 'PAGER': '/bin/cat', 21:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:31 INFO - 'PWD': '/builds/slave/test', 21:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:31 INFO - 'SHELL': '/bin/bash', 21:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BCIWRUpUeQ/Listeners', 21:48:31 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:31 INFO - 'USER': 'cltbld', 21:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:31 INFO - 'XPC_FLAGS': '0x0', 21:48:31 INFO - 'XPC_SERVICE_NAME': '0', 21:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:48:31 INFO - Cleaning up... 21:48:31 INFO - Return code: 0 21:48:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ffda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a092618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd611d72240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f61d30>, '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 0x109cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f59b20>, '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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:48:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQSoY7FOlb/Render', 21:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:31 INFO - 'HOME': '/Users/cltbld', 21:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:31 INFO - 'LOGNAME': 'cltbld', 21:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:31 INFO - 'MOZ_NO_REMOTE': '1', 21:48:31 INFO - 'NO_EM_RESTART': '1', 21:48:31 INFO - 'PAGER': '/bin/cat', 21:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:31 INFO - 'PWD': '/builds/slave/test', 21:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:31 INFO - 'SHELL': '/bin/bash', 21:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BCIWRUpUeQ/Listeners', 21:48:31 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:31 INFO - 'USER': 'cltbld', 21:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:31 INFO - 'XPC_FLAGS': '0x0', 21:48:31 INFO - 'XPC_SERVICE_NAME': '0', 21:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:32 INFO - Downloading/unpacking blobuploader==1.2.4 21:48: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 21:48: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 21:48: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 21:48: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 21:48: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 21:48: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 21:48:34 INFO - Downloading blobuploader-1.2.4.tar.gz 21:48:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:48:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:48:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:35 INFO - Downloading docopt-0.6.1.tar.gz 21:48:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:48:36 INFO - Installing collected packages: blobuploader, requests, docopt 21:48:36 INFO - Running setup.py install for blobuploader 21:48:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:48:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:48:36 INFO - Running setup.py install for requests 21:48:36 INFO - Running setup.py install for docopt 21:48:36 INFO - Successfully installed blobuploader requests docopt 21:48:36 INFO - Cleaning up... 21:48:37 INFO - Return code: 0 21:48:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:37 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 0x109ffda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a092618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd611d72240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f61d30>, '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 0x109cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f59b20>, '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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:48:37 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 21:48:37 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 21:48:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQSoY7FOlb/Render', 21:48:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:37 INFO - 'HOME': '/Users/cltbld', 21:48:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:37 INFO - 'LOGNAME': 'cltbld', 21:48:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:37 INFO - 'MOZ_NO_REMOTE': '1', 21:48:37 INFO - 'NO_EM_RESTART': '1', 21:48:37 INFO - 'PAGER': '/bin/cat', 21:48:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:37 INFO - 'PWD': '/builds/slave/test', 21:48:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:37 INFO - 'SHELL': '/bin/bash', 21:48:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BCIWRUpUeQ/Listeners', 21:48:37 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:48:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:37 INFO - 'USER': 'cltbld', 21:48:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:37 INFO - 'XPC_FLAGS': '0x0', 21:48:37 INFO - 'XPC_SERVICE_NAME': '0', 21:48:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:48:37 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-D4VJmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:48:37 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-hJLR_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:48:37 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-4M253i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:48:37 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Sma0U4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:48:37 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-b6_lkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:48:37 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Go85OZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:48:37 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-jC73i6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:48:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-xf22mX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:48:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-n0KEm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:48:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-09UfSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:48:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-gRg8J2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:48:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-5YPKSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:48:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-tf_4R_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:48:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-7jR6pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-26fTO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:48:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-EdsQWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:48:38 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-lQnvGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:48:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:48:39 INFO - Running setup.py install for manifestparser 21:48:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:48:39 INFO - Running setup.py install for mozcrash 21:48:39 INFO - Running setup.py install for mozdebug 21:48:39 INFO - Running setup.py install for mozdevice 21:48:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:48:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:48:39 INFO - Running setup.py install for mozfile 21:48:39 INFO - Running setup.py install for mozhttpd 21:48:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:48:39 INFO - Running setup.py install for mozinfo 21:48:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:48:40 INFO - Running setup.py install for mozInstall 21:48:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:48:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:48:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:48:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:48:40 INFO - Running setup.py install for mozleak 21:48:40 INFO - Running setup.py install for mozlog 21:48:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:48:40 INFO - Running setup.py install for moznetwork 21:48:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:48:40 INFO - Running setup.py install for mozprocess 21:48:40 INFO - Running setup.py install for mozprofile 21:48:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:48:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:48:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:48:41 INFO - Running setup.py install for mozrunner 21:48:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:48:41 INFO - Running setup.py install for mozscreenshot 21:48:41 INFO - Running setup.py install for moztest 21:48:41 INFO - Running setup.py install for mozversion 21:48:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:48:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:48:41 INFO - Cleaning up... 21:48:41 INFO - Return code: 0 21:48:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ffda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a092618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd611d72240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f61d30>, '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 0x109cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f59b20>, '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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:48:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 21:48:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:48:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQSoY7FOlb/Render', 21:48:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:41 INFO - 'HOME': '/Users/cltbld', 21:48:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:41 INFO - 'LOGNAME': 'cltbld', 21:48:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:41 INFO - 'MOZ_NO_REMOTE': '1', 21:48:41 INFO - 'NO_EM_RESTART': '1', 21:48:41 INFO - 'PAGER': '/bin/cat', 21:48:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:41 INFO - 'PWD': '/builds/slave/test', 21:48:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:41 INFO - 'SHELL': '/bin/bash', 21:48:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BCIWRUpUeQ/Listeners', 21:48:41 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:48:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:41 INFO - 'USER': 'cltbld', 21:48:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:41 INFO - 'XPC_FLAGS': '0x0', 21:48:41 INFO - 'XPC_SERVICE_NAME': '0', 21:48:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:48:41 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-SrmO5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:48:42 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)) 21:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:48:42 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-N16rBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:48:42 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)) 21:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:48:42 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-T9Zj_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:48:42 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)) 21:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:48:42 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Kd58Jx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:48:42 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-QfHleE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 21:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:48:42 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-6KNiGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 21:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:48:42 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-rgf0yV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:48:42 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-NNOtiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:48:42 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Ui1Gjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 21:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:48:42 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-4CKy9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:48:42 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)) 21:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:48:42 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-5382V0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:48:43 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)) 21:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:48:43 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-1ckCcb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:48:43 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)) 21:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:48:43 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-rtm0sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:48:43 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)) 21:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:48:43 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-USnj29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:48:43 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)) 21:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:43 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-VGtA6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 21:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:48:43 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Zs831V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 21:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:48:43 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-OLqDak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 21:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 21:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:48:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:48:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:48:46 INFO - Downloading blessings-1.6.tar.gz 21:48:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:48:46 INFO - Installing collected packages: blessings 21:48:46 INFO - Running setup.py install for blessings 21:48:46 INFO - Successfully installed blessings 21:48:46 INFO - Cleaning up... 21:48:46 INFO - Return code: 0 21:48:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:48:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:48:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:48:46 INFO - Reading from file tmpfile_stdout 21:48:46 INFO - Current package versions: 21:48:46 INFO - blessings == 1.6 21:48:46 INFO - blobuploader == 1.2.4 21:48:46 INFO - docopt == 0.6.1 21:48:46 INFO - functools32 == 3.2.3-2 21:48:46 INFO - jsonschema == 2.5.1 21:48:46 INFO - manifestparser == 1.1 21:48:46 INFO - mozInstall == 1.12 21:48:46 INFO - mozcrash == 0.17 21:48:46 INFO - mozdebug == 0.1 21:48:46 INFO - mozdevice == 0.48 21:48:46 INFO - mozfile == 1.2 21:48:46 INFO - mozhttpd == 0.7 21:48:46 INFO - mozinfo == 0.9 21:48:46 INFO - mozleak == 0.1 21:48:46 INFO - mozlog == 3.3 21:48:46 INFO - moznetwork == 0.27 21:48:46 INFO - mozprocess == 0.23 21:48:46 INFO - mozprofile == 0.28 21:48:46 INFO - mozrunner == 6.12 21:48:46 INFO - mozscreenshot == 0.1 21:48:46 INFO - mozsystemmonitor == 0.3 21:48:46 INFO - moztest == 0.7 21:48:46 INFO - mozversion == 1.4 21:48:46 INFO - psutil == 3.1.1 21:48:46 INFO - requests == 1.2.3 21:48:46 INFO - wsgiref == 0.1.2 21:48:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:48:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ffda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a092618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd611d72240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f61d30>, '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 0x109cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f59b20>, '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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:48:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 21:48:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:48:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQSoY7FOlb/Render', 21:48:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:46 INFO - 'HOME': '/Users/cltbld', 21:48:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:46 INFO - 'LOGNAME': 'cltbld', 21:48:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:46 INFO - 'MOZ_NO_REMOTE': '1', 21:48:46 INFO - 'NO_EM_RESTART': '1', 21:48:46 INFO - 'PAGER': '/bin/cat', 21:48:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:46 INFO - 'PWD': '/builds/slave/test', 21:48:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:46 INFO - 'SHELL': '/bin/bash', 21:48:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BCIWRUpUeQ/Listeners', 21:48:46 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:48:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:46 INFO - 'USER': 'cltbld', 21:48:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:46 INFO - 'XPC_FLAGS': '0x0', 21:48:46 INFO - 'XPC_SERVICE_NAME': '0', 21:48:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:47 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)) 21:48:47 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)) 21:48:47 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)) 21:48:47 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)) 21:48:47 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)) 21:48:47 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)) 21:48:47 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)) 21:48:47 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)) 21:48:47 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)) 21:48:47 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)) 21:48:47 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)) 21:48:47 INFO - Cleaning up... 21:48:47 INFO - Return code: 0 21:48:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:48:47 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')]} 21:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:48:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:47 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')]} 21:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:48:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:48:47 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 0x109ffda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a092618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd611d72240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f61d30>, '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 0x109cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f59b20>, '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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 21:48:47 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 21:48:47 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 21:48:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQSoY7FOlb/Render', 21:48:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:48:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:48:47 INFO - 'HOME': '/Users/cltbld', 21:48:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:48:47 INFO - 'LOGNAME': 'cltbld', 21:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:48:47 INFO - 'MOZ_NO_REMOTE': '1', 21:48:47 INFO - 'NO_EM_RESTART': '1', 21:48:47 INFO - 'PAGER': '/bin/cat', 21:48:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:48:47 INFO - 'PWD': '/builds/slave/test', 21:48:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:48:47 INFO - 'SHELL': '/bin/bash', 21:48:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BCIWRUpUeQ/Listeners', 21:48:47 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:48:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:48:47 INFO - 'USER': 'cltbld', 21:48:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:48:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:48:47 INFO - 'XPC_FLAGS': '0x0', 21:48:47 INFO - 'XPC_SERVICE_NAME': '0', 21:48:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:48:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:48:47 INFO - Cleaning up... 21:48:47 INFO - Return code: 0 21:48:47 INFO - Running post-action listener: _resource_record_post_action 21:48:47 INFO - Running post-action listener: _start_resource_monitoring 21:48:47 INFO - Starting resource monitoring. 21:48:47 INFO - [mozharness: 2016-09-01 04:48:47.375485Z] Finished create-virtualenv step (success) 21:48:47 INFO - [mozharness: 2016-09-01 04:48:47.376542Z] Running install step. 21:48:47 INFO - Running pre-action listener: _resource_record_pre_action 21:48:47 INFO - Running main action method: install 21:48:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:48:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:48:47 INFO - Reading from file tmpfile_stdout 21:48:47 INFO - Detecting whether we're running mozinstall >=1.0... 21:48:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:48:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:48:47 INFO - Reading from file tmpfile_stdout 21:48:47 INFO - Output received: 21:48:47 INFO - Usage: mozinstall [options] installer 21:48:47 INFO - Options: 21:48:47 INFO - -h, --help show this help message and exit 21:48:47 INFO - -d DEST, --destination=DEST 21:48:47 INFO - Directory to install application into. [default: 21:48:47 INFO - "/builds/slave/test"] 21:48:47 INFO - --app=APP Application being installed. [default: firefox] 21:48:47 INFO - mkdir: /builds/slave/test/build/application 21:48:47 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'] 21:48:47 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 21:49:21 INFO - Reading from file tmpfile_stdout 21:49:21 INFO - Output received: 21:49:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:49:21 INFO - Running post-action listener: _resource_record_post_action 21:49:21 INFO - [mozharness: 2016-09-01 04:49:21.935517Z] Finished install step (success) 21:49:21 INFO - [mozharness: 2016-09-01 04:49:21.935678Z] Running run-tests step. 21:49:21 INFO - Running pre-action listener: _resource_record_pre_action 21:49:21 INFO - Running main action method: run_tests 21:49:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:49:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:49:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:49:21 INFO - 2016-08-31 21:49:21.950 screenresolution[1920:14553] starting screenresolution argv=screenresolution get 21:49:21 INFO - 2016-08-31 21:49:21.952 screenresolution[1920:14553] Display 0: 1600x1200x32@60 21:49:21 INFO - 2016-08-31 21:49:21.958 screenresolution[1921:14555] starting screenresolution argv=screenresolution list 21:49:21 INFO - Available Modes on Display 0 21:49:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:49:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:49:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:49:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:49:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:49:22 INFO - Intel Iris: 21:49:22 INFO - Chipset Model: Intel Iris 21:49:22 INFO - Type: GPU 21:49:22 INFO - Bus: Built-In 21:49:22 INFO - VRAM (Dynamic, Max): 1536 MB 21:49:22 INFO - Vendor: Intel (0x8086) 21:49:22 INFO - Device ID: 0x0a2e 21:49:22 INFO - Revision ID: 0x0009 21:49:22 INFO - Displays: 21:49:22 INFO - Display: 21:49:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:49:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:49:22 INFO - Display Serial Number: 1600x1200 60 21:49:22 INFO - Main Display: Yes 21:49:22 INFO - Mirror: Off 21:49:22 INFO - Online: Yes 21:49:22 INFO - Rotation: Supported 21:49:22 INFO - Return code: 0 21:49:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:49:22 INFO - Python 2.7.3 21:49:22 INFO - Return code: 0 21:49:22 INFO - grabbing minidump binary from tooltool 21:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:49:22 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f61d30>, '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 0x109cc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109f59b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:49:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:49:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 21:49:22 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:49:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:49:22 INFO - Return code: 0 21:49:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:49:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:49:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:49:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:49:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:49:22 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/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--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 21:49:22 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/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0112 --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 21:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uQSoY7FOlb/Render', 21:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:49:22 INFO - 'HOME': '/Users/cltbld', 21:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:49:22 INFO - 'LOGNAME': 'cltbld', 21:49:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:49:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:49:22 INFO - 'MOZ_NO_REMOTE': '1', 21:49:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:49:22 INFO - 'NO_EM_RESTART': '1', 21:49:22 INFO - 'PAGER': '/bin/cat', 21:49:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:49:22 INFO - 'PWD': '/builds/slave/test', 21:49:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:49:22 INFO - 'SHELL': '/bin/bash', 21:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BCIWRUpUeQ/Listeners', 21:49:22 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 21:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:49:22 INFO - 'USER': 'cltbld', 21:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:49:22 INFO - 'XPC_FLAGS': '0x0', 21:49:22 INFO - 'XPC_SERVICE_NAME': '0', 21:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:49:22 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/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--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 21:49:22 INFO - mozversion application_buildid: 20160831193821 21:49:22 INFO - mozversion application_changeset: 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 21:49:22 INFO - mozversion application_display_name: Nightly 21:49:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:49:22 INFO - mozversion application_name: Firefox 21:49:22 INFO - mozversion application_remotingname: firefox 21:49:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:49:22 INFO - mozversion application_vendor: Mozilla 21:49:22 INFO - mozversion application_version: 51.0a1 21:49:22 INFO - mozversion platform_buildid: 20160831193821 21:49:22 INFO - mozversion platform_changeset: 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 21:49:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:49:22 INFO - mozversion platform_version: 51.0a1 21:49:22 INFO - using testdate: 1472705362 21:49:22 INFO - actual date: 1472705362 21:49:22 INFO - starting webserver on 'localhost:50726' 21:49:22 INFO - SUITE-START | Running 2 tests 21:49:22 INFO - TEST-START | dromaeo_css 21:49:22 INFO - operating with platform_type : mac_ 21:49:22 INFO - Initialising browser for dromaeo_css test... 21:49:22 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpdtbrdZ/profile http://localhost:50726/getInfo.html) 21:49:25 INFO - PROCESS | 1931 | __metrics Screen width/height:1600/1200 21:49:25 INFO - PROCESS | 1931 | colorDepth:24 21:49:25 INFO - PROCESS | 1931 | Browser inner width/height: 1024/689 21:49:25 INFO - PROCESS | 1931 | __metrics 21:49:25 INFO - PROCESS | 1931 | 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" 21:49:25 INFO - PROCESS | 1931 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:49:25 INFO - TEST-INFO | 1931: exit 0 21:49:25 INFO - Browser initialized. 21:49:25 INFO - Running cycle 1/1 for dromaeo_css test... 21:49:25 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpdtbrdZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:49:37 INFO - PROCESS | 1933 | RSS: Main: 233648128 21:49:37 INFO - PROCESS | 1933 | 21:50:29 INFO - PROCESS | 1933 | 1472705429452 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] 21:52:41 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50726/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50726/tests/dromaeo/cssquery-ext.html) 21:52:42 INFO - PROCESS | 1933 | RSS: Main: 356671488 21:52:42 INFO - PROCESS | 1933 | 21:55:45 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50726/tests/dromaeo/cssquery-ext.html (next: http://localhost:50726/tests/dromaeo/cssquery-jquery.html) 21:55:46 INFO - PROCESS | 1933 | RSS: Main: 391667712 21:55:46 INFO - PROCESS | 1933 | 21:58:48 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50726/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50726/tests/dromaeo/cssquery-mootools.html) 21:58:49 INFO - PROCESS | 1933 | RSS: Main: 341647360 21:58:49 INFO - PROCESS | 1933 | 22:01:51 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50726/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50726/tests/dromaeo/cssquery-prototype.html) 22:01:52 INFO - PROCESS | 1933 | RSS: Main: 330858496 22:01:52 INFO - PROCESS | 1933 | 22:04:55 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50726/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50726/tests/dromaeo/cssquery-yui.html) 22:04:56 INFO - PROCESS | 1933 | RSS: Main: 323784704 22:04:56 INFO - PROCESS | 1933 | 22:07:59 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:50726/tests/dromaeo/cssquery-yui.html (next: http://localhost:50726/tests/dromaeo/cssquery-dojo.html) 22:08:00 INFO - PROCESS | 1933 | RSS: Main: 375767040 22:08:00 INFO - PROCESS | 1933 | 22:08:00 INFO - PROCESS | 1933 | __start_tp_report 22:08:00 INFO - PROCESS | 1933 | _x_x_mozilla_page_load 22:08:00 INFO - PROCESS | 1933 | _x_x_mozilla_page_load_details 22:08:00 INFO - PROCESS | 1933 | |i|pagename|runs| 22:08:00 INFO - PROCESS | 1933 | |0;dojo.html;2984.403119376125;3346.7991920484765;3351.9944016794957;3398;3424.349373619012;5665.490105890469;5705.771769129235;5731.083026715726;5824.796132135376;5850.180974663551;5740.282464691913;5752.568557358194;5834.708264586764;5867.941320586793;5941.64350138992;5151.922721159186;5190.06578815813;5193.428722840485;5197.818076367328;5238.790448382079;5333.466653334654;5346.171343441753;5350.956563470125;5365.168398898158;5378.46215378463;5175.508326708957;5188.896222075575;5210.97394513025;5256.159014557672;5284.867878303035;5251.684898906078;5258.92111618326;5263.973680131613;5264.368275806889;5278.894422111536;4734.171519983989;4854.150523658381;5184.885249671301;5218.217267409881;5252.973735131338;5732.828015159552;5734.856628584277;5750.424957504258;5760.913586296209;5767.192593036978;6171.197744293258;6236.282827474808;6253.593516421418;6305.558610897239;6309.432151106376;6104.20645316106;6241.095041219043;6268.247810262752;6270.529710271748;6273.3726627337355;5754.165645981309;5931.406859314034;5935.821925342289;5939.109133629903;5941.0197317443;6191.84520386994;6210.347913469021;6210.875782484325;6239.844003899848;6324.272708638474;6211.534134939898;6228.314885362606;6233.750649974041;6233.937660623426;6247.9062814057825;6144.41628045335;6275.215598050244;6292.685365731695;6309.116723658692;6310.211223597051;6167.321594624587;6202.937970620326;6239.937600624026;6302.1177035215105;6310.6213627182515;6296.181496405438;6303.243610766645;6309.684515774285;6313.494920406357;6318.589291696025;4885.1206782779445;4924.990376972783;4973.459611803301;4988.9750551247735;5031.295618613397;3647.835847386874;4418.955810441855;4460.77696115193;4476.686631935733;4547.454305483363;5797.36229014808;5844.327902291291;5853.502452291517;5853.853653658693;5869.618474799125;6283.748650054038;6291.276503202099;6298.905516417258;6301.747930082837;6305.747770089237;5734.971325143348;5849.268841394825;5864.354920958691;5878.970605147032;5983.730732117066;5506.724663766876;5507.339119305709;5510.421405752419;5513.889722205614;5525.309336332958;6312.95836187024;6366.840828979312;6369.585976911486;6383.2978372379;6383.840403989751;6193.628382297076;6198.132261483486;6200.441960223601;6255.155554000152;6292.433680968552;6354.364563543609;6361.491080713439;6372.203474565679;6376.107344971615;6386.105945167773;6242;6265;6265.780697675559;6272.6236425814595;6274.843128921813;6117.096718007379;6174.598651087665;6188.288346840171;6195.256569211723;6212.378762123933;6139.492138333381;6170.6914654268;6172.321044684992;6181.320054794059;6188.628682279078;6090.025595904634;6100.23747031621;6174.38256174379;6178.783742568989;6187.443130118311;5841.32824725145;5936.22829032223;5970.297620230956;5988.371221021905;6095.8780824382375;6150.907736383869;6174.691265436621;6214.533909956681;6221.253449586078;6221.875562488815;6026.548008899263;6042.0332746760305;6044.939550604614;6057.363976782374;6101.633901965896 22:08:00 INFO - PROCESS | 1933 | |1;ext.html;479.4797644555661;507.1505228741857;509.0582422518344;510.92960248279854;520.8437468759371;30634.693653063463;31974.040778776645;39859.80070099646;40857;40991.79504102483;42251.36622950651;42607.14785704292;42723;42731.93170170747;42797;9525.237980961523;9716.514174291256;9723.805523889518;9728.902710972905;9746.51267436629;8803.911960880374;8934.642614295453;8935.910640893573;9058.954705226464;9071.637134514645;11897.643070707893;12202.572909948154;12217.022638188968;12402.503899843996;12430.192037517578;12102.124170753546;12184.20802647825;12221.2667239965;12222.144449888512;12231.755364892697;11949.402529873472;12024.69938251542;12038.53210091929;12041.337726425043;12060;39458.81623551298;39744.20511589752;39755.60244397548;39816;39870.60129398727;3140.874365025419;3266.1508007917873;3281.196106953788;3359.6136444308727;3472.0451875734125;30524.69475305241;30979.535306970414;31373.84313078443;31897;32006.359872802415;3210.2616398228306;3352.044667269841;3361.8487168077495;3420.811855347955;3421.81180035098;479870;490633;491081;491250;502700.48649756884;64687.560930492655;65390.34609653842;65422.672886635264;65625.67187164034;65702.67148664322;66383.66808165929;66863.66568167128;66874.66562667156;66972;67038;16519.587010324838;16770.58073548171;16786;16823.49529514092;16832.24254908532;12766.68083297925;12883.613491595079;12883.742325153444;12899.742005160031;12914.289714065817;15954.312728339339;17132.914335428493;19375.42356531935;19962.600747984958;20584.27955021567;8395.286400656012;8411.242988131098;8412.537310447984;8414.284785793276;8526.868282929268;841;900.7883147460342;901.8106197698426;906.7098528470958;1032.6643840751785;26855.194344169708;29472.26319341991;30199.396012079636;30248.69751302503;30721.924732634696;1019.1439191079529;1050.5797680927537;1113.1984970820995;1124.4040658451033;1177.2583272538245;15382.384704611914;15550.611234718996;15762.921185394;15766.931426662277;15861.822726055456;640987;641264;641325;642479;642707;48423.75788121037;48685.026299473815;48722.5127748718;52088;56961;1220.8412906322121;1322.7552902713028;1360.857110003455;1382.4884792626592;1388.0838646493266;26829.195124146307;29992.85003574968;30990.69009309878;31256.374872502878;32048.67951320457;49504.75247623739;50102.997940040266;50116;50198.74900625474;50288.245676315615;511357;512330;513222;516563;517094;26895.865520672272;27236;27489.037883673976;27958.860205699653;28169;46530.53469465262;46769.363072293665;47156;47705.04589908113;48023;38187.42718859275;38293.80853095717;38630.039813968404;39021.80489097536;39603.80198098991;38549.03627409337;38626;39903.60096399115;39906.80046599748;40415.39376909407;37608.43587346247;37640.43539346857;38509.614903850605;38527.807360963016;38591.80704096462;31069.68930310668;33440.832795835864;33930.49104263487;34393.140171495914;34427.655723443444;22193.22323718662;24983.625245620966;25168.370790729645;25172.496550069263;25346.305006120972 22:08:00 INFO - PROCESS | 1933 | |2;jquery.html;298.4895828133891;355.1387884380377;378.8749712594842;388.48525703442925;410.7843382224332;15677;17219.741703874435;20366.796332036676;21053.789462105375;21144.471388215283;20130.295439659578;20486.385408437734;21017.894910525425;21056.89471552644;21103.788962110375;5449.836504904849;5642.097264437671;5657.038303488417;5688.914666280009;6131.693415329216;3941.586133455989;6084.908726369108;6199.597026193283;6202.5968312059795;6213.130161777332;5070.783012077111;6202.069689546582;6204.4416002559765;6255.718492667841;6264.624122552661;5988.161657367972;6133.907991380132;6171.814845554641;6234.501239900797;6257.280412752546;5348.946510534884;5756.560859785054;6037.577369584131;6039.214902062704;6042.516598672096;13114.409851556607;13155.934220328932;13306.667333316647;13441.596752097452;13484.932575337061;2244.2145249162827;2463.3964678653842;2500.374906273432;2547.261294224673;2776.3336799168255;10173.643922462677;10209.2853500255;10223.488825558692;10786.244962852603;14744.3365048572;1838.8966620027877;1932.5168707823045;1942.873713208637;1960.7549056367955;1983.8512111591688;95306.52346738221;116649;120120.99999999913;120828.39585802103;121856;8773.824523509495;11331.943340283411;11438.94280528592;11449.885501144881;11491.310521368745;14545.709085818224;14554.636134096732;14560.49038283655;14560.781588276184;14572.417103315749;8351.3736469765;8527.23254907049;8536.615852286663;8539;8678.305735541142;8461.45000574973;8475.152484751476;8628.78428039304;8676.956615216883;8686.218240358397;4413.1394378095965;4498.280275155959;4499.707519011254;4519.857882287705;4596.126735920165;1862.7392165096896;1895.4218963216351;1907.904604769756;1910.5701217226012;1917.769867615867;838.6142374507673;898.7797989492616;899.325505870597;930.4463844012802;952.4142652268805;17483.912580437016;20377;21018;21694.674579881314;21812.781872181393;935.695898832882;941.1811723800338;960.1550635440907;964.9324547281764;1021.7445638590352;9980.900190997998;10491.632792852366;11381.601643942422;11407.828882566768;11418.657440276806;577736;592765;596595;596800;597745;99239;113058;120242;120627.19059214118;122241;782.4210084537401;907.9092090790868;982.9950850245846;998.7103739915343;1023.7542989682422;15163.317650705965;18858.434246972614;19219.615607688047;19284.325048623232;19298.517537061685;41296;49372.012559747884;52045.576126029715;53519.732401337744;55758.72120639371;75240;76941;77415.61292193503;78028;79561.20438795538;7936.120506293923;8024.157463466252;8136.674533018613;8324.167583241628;8373.539455330016;12735.23588584688;13296.601101966957;13489.527866525017;13894.513692020731;13979.650508737363;10475.42385168823;11536.868165052038;11637.418129093343;11797.941010294893;11827.231229970024;11397.373144476802;11620.012298954345;11656.242344247936;11783.823242651195;11816.645500634995;11483.598074067368;11573.074154067486;11810.996065334542;11894.881051189377;11988.520459181535;8158.388120891075;8204.097549269694;8320.292775113941;8423.410361274897;8472.322214222972;8672.479651220947;11188.328700278009;11282.661520154408;11353.829692554453;11612.361320127475 22:08:00 INFO - PROCESS | 1933 | |3;mootools.html;1344.2405041151742;1499.3552772307908;1549.883758718096;1733.1940647598872;1897.9145938432769;7824.765257042291;8552.102029286922;8900;8902;8951.686690965818;8756.299496040318;8856;8860.734177974655;8879.334049946263;8883.111688831113;2368.834181607284;2395.2933884504114;2397.7961873240797;2398.4843258699375;2403.0748161957686;2344.5193735284224;2377.465070359173;2403.495265994143;2406.097713357491;2417.3352328109822;2368.5736567417857;2383.2373640435067;2395.6406539019113;2409.361519197414;2412.4089598048413;2345.5074434368803;2363.9881800590974;2367.065009321317;2372.240882917467;2392.7487613800477;2338.286822519131;2350.5063936573342;2385.666006759038;2392.820538459605;2402.699662542182;8758.299336053164;8812.911870881337;8920.509371984539;8945.821083578292;8947.463152210888;5241.344831896013;5439.048166570834;5441;5442.047641662693;5443.836684899459;3600.153963818527;3627.292677927805;3655.0131464504434;3674.4120940649636;3690.7785532868115;1073.4471747050275;1084.3981590217427;1087.190043417651;1101.7576133250673;1111.9666410007712;10137.695869123938;11796.410179490991;11896.464659090361;11915.2255103418;11944.163908526407;4381.18947994622;4485.932711009337;4633.536646335407;4681.227597446451;4681.578657920803;9294.814103717888;9305;9310.556863686004;9312;9322.114399132071;5231.189165679326;5237.16205407133;5243.344581927259;5243.501867322599;5247.239150323182;4291.527931927485;4383.276759334674;4567.406237189144;4862.78117484714;4892;3769;3791.4881490998364;3878.0498777799057;3896.1428485733095;3896.9610303897166;1141.3608379307614;1165.9125565582447;1177.3583397048458;1179.097990037622;1182.7102359921703;758.427387322579;889.3196704521047;917.4357769971419;919.4483310014072;941.3457646935315;5491.588130890199;5948.881022379528;5978.701064946736;6010.939890601126;6103.969480152571;418.47063464717155;418.4852631263502;433.49281340831305;435.50570102932767;437.2108344438319;3932;4057.594240575919;4073.796310184479;4104.979475102606;4158.958410415918;10619.256652034283;10854.891451085547;11015.394153321644;11016.779664406828;11037.448127593589;10934.617288394868;10977.560897564008;10996.175286853359;11024.614138505114;11036.448177591088;6396.872062558723;8156.469829461066;8179.550124743197;8410.705625303084;8431.831363372698;6012;6062.363451837494;6129.632222066691;6215.81352559424;6265.8120256392385;10058.396496210251;10262.692119236435;10270.948645256725;10278.743031424274;10309.74225644365;8103.797405064921;9834.459104749307;10312.639057632947;10508.684739457829;10518.894811051792;7365.963170184329;7684.462087654034;7688.769336919901;7695.461317707706;7697.999260096151;7204.56772593646;8139.877901831595;8296.792580185544;8425.62084706202;8432.19894110083;7506.136794268729;7511.737089201852;7539.434542409451;7609.88585171234;7655.004849369547;7196.856062878818;7331.920861806452;7424.814379640552;7527;7542.170361260367;7082.114735658043;7091.290870912868;7121.394681452135;7164.355208031302;7174.641267936478;4503.707259028152;5042.39491261051;5043.293938848491;5166.844994650167;5170.6380553360905;4969.428515720739;5034.798608055637;5068.265101560327;5145.716985565829;5236.214567814858 22:08:00 INFO - PROCESS | 1933 | |4;prototype.html;295.1073004162407;302.1283596823165;309.89463582381995;312.2225658111301;313.7348940145576;5107.208382700678;5197.68813871168;5263.184206447997;5279.392869819972;5279.815206467774;4267.146570685867;4451.798014536073;4674.929876051853;5015.222640490721;5098.541131298182;3301.620313663929;3651.6165802590745;3999.860004899829;4005.7796821174825;4030.891504836164;3295.9835200823964;3753.5871054184017;3942.989075571465;4023.8330884043594;4042.43405923171;3655.287218992284;3954.209158168378;3971.3518889660754;3981.641652251297;4022;3498.7375946803836;3605.891823245307;3966.9884179534324;3973.9599206606968;3978.6021397860272;3481.5899560677967;3490.127468132967;3498.5801703795455;3499.9825000874835;3520.4476754353145;4339.978300108479;4599.816007359702;4902.264660300948;5035.471275516092;5129.948700513021;4146.896327591804;4216.03031302799;4589.2657174851865;4606.124836281096;4617.215073437524;5464.497263252598;5484.396716361197;5511.456792098219;5527.170924361338;5533.280673512458;3650.3976843820737;3768.4724138620613;3809.676177524914;3833.5974722654;3852.9807350963065;5666.433356664342;5739.684317362543;5769.8557536061935;5775.653460792324;5784.537237021028;3881.5924327945722;3886.8639597613947;3897.7271590988357;3912.68698504119;3917.804109794499;3802.6310528209824;3848.2869576948183;3931.744436611611;3945.8027098644952;3969.900752481211;3398.065531978676;3755.816917670925;4074.409210664437;4303.870883873489;4558;4659.5573420525;5499.84503254321;5539.806106786243;5573.693446860461;5631.915521267184;1123.842662027317;1206.4993027893502;1305.1907817153426;1319.9274039927705;1337.7993301004926;815.995920020408;820.6758330459435;843.6625349859983;872.8173325144464;876.5179151466796;3701.8704345348324;4276.144771045803;4322.5677432256525;4333.783310834446;4335.978320108379;4301.91396172081;4744.596709279681;4785.009369144284;4791.161546729309;4843.636727245471;3402.213090068291;4245.11332294043;4264.594863487963;4289.292266775947;4326.826926922888;2138.145575656516;2320.3619004773823;2337.590921588749;2482.5601151332185;2503.4116982509095;3991.1263889999154;4015.377616469452;4158.045718512294;4671.789769460383;4761.880962971513;4675.906481870344;4707.340972263886;4793.760311984387;5161.845144645667;5799.797007104731;5888.882222355529;5915.556333274935;5951.821445356646;5952.970235148797;5963.552733544915;4740;5047.697138171721;5747.626404283708;5755.251817263729;5812.622179558315;4800.423949126127;4948.826791062295;5439.972800136133;5620.85947851307;5713.771449141987;5588.692621905834;5673.723437203275;5741.655500669973;5920.141579470866;5950.613210141327;3408.2842603053637;3412.5051867479574;3443.708609271523;3743.981280093582;4009.9574110732005;5344.732763361894;5632.690202038927;5638.5489160867855;5686.009896536179;5714.4856612996755;5516.668999860021;5525.392206857323;5525.889482210253;5547.945890280834;5553.305836770403;4882.389701287339;5282.550983166474;5389.434109418455;5518.117101263939;5525.861853453695;4122.505299364096;4373.037931655031;4578.359029735773;5370.704611246419;5452.945470545244;4589.495155532955;5453.291072160594;5459.945400545944;5463.7814487420055;5486.423925487926;4239.618434340924;5480.616356854982;5500.257465242301;5533.6956467393065;5550.306211723535 22:08:00 INFO - PROCESS | 1933 | |5;yui.html;703.2475251480915;725.9128904531457;743.7619961612282;795.5942469340638;813.8413009463158;15622.453214137493;16189.028658280511;16189.75715364269;16229.350825966976;16241.837581624195;14275.21486318252;14345.497907573237;14357.856421435783;14366.425342986295;14785.112893226415;678.396227357652;703.2440126863617;843.3843294395095;843.5908584336607;870.6169285514385;855.632078206371;862.0086900064913;866.0473479172891;868.1058509734952;872.9650813967434;919.2186641354862;921.3320342751485;922.5710044829153;922.898481167071;923.5705396990398;709.0286307758404;916.8624706294042;919.384012711485;919.7930465645243;919.8712180294731;667.0494545272967;682.0519477925637;682.757621044527;683.967209513638;686.6429456682497;14663.92668036653;14708.48520301795;14713.558593242113;14754.778678319833;14779.778303325458;11263.605773797959;13942.163470191821;14198.290085495788;14230.715385692332;14560.854391456056;11302.660920172408;11584.884151158549;11680.0655947524;12454.439550220262;13548.322583870768;3746.4193050077283;3854.1713531590844;3949.4273330366937;4599.264117741145;4753.738544380058;97862;98456;103307;103909;105309;9128.81742365149;11003.284786488852;11073.944630276797;11127.332360058423;11140.888591114148;18442.26230950747;21605.35183944484;21632.242871499737;21697.457563561038;21705;7724.073111226577;7938.56337901421;7998.440109192417;8027.5584842834205;8085.474444161111;4382.912341753211;4441.133978874153;4441.822327106945;4491.708038977456;4538;3485.8431370588382;3622.855085796539;3623.4927110204594;3628.564572251347;3640.4539319102346;583.4107551373144;591.520868096843;628.7704987679464;636.424036247197;668.7258224128182;502.5602597726989;572.4447286132445;575.6172145523263;630.4861537846732;672.284017521348;8890.288776897833;8904.095943899798;9546;9622.759431014281;9712.17446517054;614.9600275982046;641.7593402474072;649.808306549569;666.9366410191024;671.1040760584674;4468.308950513159;4568.200564901129;4659.487456379796;4717.080169366941;4760.619150467955;118842;148134;151717;152292;154106.22946885193;125358.3732081352;126686.3665681684;130838;132987.67012329947;134970;96199;98200.50899745456;98946.09485146227;104234.47882760537;116912;8803.251723603309;8880.245179159843;9037.50293733851;9046.728598142066;9136.908630913871;45919.62241132771;50854.71153798014;56699.433005669416;61241.081383778386;61468.07797882946;70067;85320.57339713261;91050.54474727584;91601.62597561175;92764.08538719246;11317.151213658844;11532.019778318936;11639.59261425846;11831.467583958914;12112.15214934946;12834.55079072228;12977.545785897448;12981.350932453528;13121.737565248832;13147.276899770604;11023.83464248021;11052.834207486734;11398.886011139783;11402.372869492257;11506.597269095542;11636.534538618698;11794.941025294818;11824.349660768741;11838.822417663914;11931.019462648921;11768.470418831002;11956.701082473008;12011.219270747373;12051.216670916363;12496.12527123129;8902.643894244158;9202.401843880394;9271.165595096474;9642.276829237695;9717.805643887225;10042.11948423496;10289;10302.278840481094;10343.94828025855;10369.844452333373 22:08:00 INFO - PROCESS | 1933 | __end_tp_report 22:08:00 INFO - PROCESS | 1933 | __start_cc_report 22:08:00 INFO - PROCESS | 1933 | _x_x_mozilla_cycle_collect,1198 22:08:00 INFO - PROCESS | 1933 | __end_cc_report 22:08:00 INFO - PROCESS | 1933 | __startTimestamp1472706480208__endTimestamp 22:08:00 INFO - PROCESS | 1933 | 22:08:00 INFO - PROCESS | 1933 | ------- Summary: start ------- 22:08:00 INFO - PROCESS | 1933 | Number of tests: 6 22:08:00 INFO - PROCESS | 1933 | 22:08:00 INFO - PROCESS | 1933 | [#0] dojo.html Cycles:165 Average:5791.34 Median:6057.36 stddev:668.05 (11.0%) stddev-sans-first:632.77 22:08:00 INFO - PROCESS | 1933 | Values: 2984.4 3346.8 3352.0 3398.0 3424.3 5665.5 5705.8 5731.1 5824.8 5850.2 5740.3 5752.6 5834.7 5867.9 5941.6 5151.9 5190.1 5193.4 5197.8 5238.8 5333.5 5346.2 5351.0 5365.2 5378.5 5175.5 5188.9 5211.0 5256.2 5284.9 5251.7 5258.9 5264.0 5264.4 5278.9 4734.2 4854.2 5184.9 5218.2 5253.0 5732.8 5734.9 5750.4 5760.9 5767.2 6171.2 6236.3 6253.6 6305.6 6309.4 6104.2 6241.1 6268.2 6270.5 6273.4 5754.2 5931.4 5935.8 5939.1 5941.0 6191.8 6210.3 6210.9 6239.8 6324.3 6211.5 6228.3 6233.8 6233.9 6247.9 6144.4 6275.2 6292.7 6309.1 6310.2 6167.3 6202.9 6239.9 6302.1 6310.6 6296.2 6303.2 6309.7 6313.5 6318.6 4885.1 4925.0 4973.5 4989.0 5031.3 3647.8 4419.0 4460.8 4476.7 4547.5 5797.4 5844.3 5853.5 5853.9 5869.6 6283.7 6291.3 6298.9 6301.7 6305.7 5735.0 5849.3 5864.4 5879.0 5983.7 5506.7 5507.3 5510.4 5513.9 5525.3 6313.0 6366.8 6369.6 6383.3 6383.8 6193.6 6198.1 6200.4 6255.2 6292.4 6354.4 6361.5 6372.2 6376.1 6386.1 6242.0 6265.0 6265.8 6272.6 6274.8 6117.1 6174.6 6188.3 6195.3 6212.4 6139.5 6170.7 6172.3 6181.3 6188.6 6090.0 6100.2 6174.4 6178.8 6187.4 5841.3 5936.2 5970.3 5988.4 6095.9 6150.9 6174.7 6214.5 6221.3 6221.9 6026.5 6042.0 6044.9 6057.4 6101.6 22:08:00 INFO - PROCESS | 1933 | 22:08:00 INFO - PROCESS | 1933 | [#1] ext.html Cycles:180 Average:68822.56 Median:38240.62 stddev:147538.15 (385.8%) stddev-sans-first:147862.80 22:08:00 INFO - PROCESS | 1933 | Values: 479.5 507.2 509.1 510.9 520.8 30634.7 31974.0 39859.8 40857.0 40991.8 42251.4 42607.1 42723.0 42731.9 42797.0 9525.2 9716.5 9723.8 9728.9 9746.5 8803.9 8934.6 8935.9 9059.0 9071.6 11897.6 12202.6 12217.0 12402.5 12430.2 12102.1 12184.2 12221.3 12222.1 12231.8 11949.4 12024.7 12038.5 12041.3 12060.0 39458.8 39744.2 39755.6 39816.0 39870.6 3140.9 3266.2 3281.2 3359.6 3472.0 30524.7 30979.5 31373.8 31897.0 32006.4 3210.3 3352.0 3361.8 3420.8 3421.8 479870.0 490633.0 491081.0 491250.0 502700.5 64687.6 65390.3 65422.7 65625.7 65702.7 66383.7 66863.7 66874.7 66972.0 67038.0 16519.6 16770.6 16786.0 16823.5 16832.2 12766.7 12883.6 12883.7 12899.7 12914.3 15954.3 17132.9 19375.4 19962.6 20584.3 8395.3 8411.2 8412.5 8414.3 8526.9 841.0 900.8 901.8 906.7 1032.7 26855.2 29472.3 30199.4 30248.7 30721.9 1019.1 1050.6 1113.2 1124.4 1177.3 15382.4 15550.6 15762.9 15766.9 15861.8 640987.0 641264.0 641325.0 642479.0 642707.0 48423.8 48685.0 48722.5 52088.0 56961.0 1220.8 1322.8 1360.9 1382.5 1388.1 26829.2 29992.9 30990.7 31256.4 32048.7 49504.8 50103.0 50116.0 50198.7 50288.2 511357.0 512330.0 513222.0 516563.0 517094.0 26895.9 27236.0 27489.0 27958.9 28169.0 46530.5 46769.4 47156.0 47705.0 48023.0 38187.4 38293.8 38630.0 39021.8 39603.8 38549.0 38626.0 39903.6 39906.8 40415.4 37608.4 37640.4 38509.6 38527.8 38591.8 31069.7 33440.8 33930.5 34393.1 34427.7 22193.2 24983.6 25168.4 25172.5 25346.3 22:08:00 INFO - PROCESS | 1933 | 22:08:00 INFO - PROCESS | 1933 | [#2] jquery.html Cycles:180 Average:34056.10 Median:2661.80 stddev:98574.81 (3703.3%) stddev-sans-first:98818.75 22:08:00 INFO - PROCESS | 1933 | Values: 298.5 355.1 378.9 388.5 410.8 15677.0 17219.7 20366.8 21053.8 21144.5 20130.3 20486.4 21017.9 21056.9 21103.8 5449.8 5642.1 5657.0 5688.9 6131.7 3941.6 6084.9 6199.6 6202.6 6213.1 5070.8 6202.1 6204.4 6255.7 6264.6 5988.2 6133.9 6171.8 6234.5 6257.3 5348.9 5756.6 6037.6 6039.2 6042.5 13114.4 13155.9 13306.7 13441.6 13484.9 2244.2 2463.4 2500.4 2547.3 2776.3 10173.6 10209.3 10223.5 10786.2 14744.3 1838.9 1932.5 1942.9 1960.8 1983.9 95306.5 116649.0 120121.0 120828.4 121856.0 8773.8 11331.9 11438.9 11449.9 11491.3 14545.7 14554.6 14560.5 14560.8 14572.4 8351.4 8527.2 8536.6 8539.0 8678.3 8461.5 8475.2 8628.8 8677.0 8686.2 4413.1 4498.3 4499.7 4519.9 4596.1 1862.7 1895.4 1907.9 1910.6 1917.8 838.6 898.8 899.3 930.4 952.4 17483.9 20377.0 21018.0 21694.7 21812.8 935.7 941.2 960.2 964.9 1021.7 9980.9 10491.6 11381.6 11407.8 11418.7 577736.0 592765.0 596595.0 596800.0 597745.0 99239.0 113058.0 120242.0 120627.2 122241.0 782.4 907.9 983.0 998.7 1023.8 15163.3 18858.4 19219.6 19284.3 19298.5 41296.0 49372.0 52045.6 53519.7 55758.7 75240.0 76941.0 77415.6 78028.0 79561.2 7936.1 8024.2 8136.7 8324.2 8373.5 12735.2 13296.6 13489.5 13894.5 13979.7 10475.4 11536.9 11637.4 11797.9 11827.2 11397.4 11620.0 11656.2 11783.8 11816.6 11483.6 11573.1 11811.0 11894.9 11988.5 8158.4 8204.1 8320.3 8423.4 8472.3 8672.5 11188.3 11282.7 11353.8 11612.4 22:08:00 INFO - PROCESS | 1933 | 22:08:00 INFO - PROCESS | 1933 | [#3] mootools.html Cycles:180 Average:5708.96 Median:4681.40 stddev:3227.00 (68.9%) stddev-sans-first:3219.38 22:08:00 INFO - PROCESS | 1933 | Values: 1344.2 1499.4 1549.9 1733.2 1897.9 7824.8 8552.1 8900.0 8902.0 8951.7 8756.3 8856.0 8860.7 8879.3 8883.1 2368.8 2395.3 2397.8 2398.5 2403.1 2344.5 2377.5 2403.5 2406.1 2417.3 2368.6 2383.2 2395.6 2409.4 2412.4 2345.5 2364.0 2367.1 2372.2 2392.7 2338.3 2350.5 2385.7 2392.8 2402.7 8758.3 8812.9 8920.5 8945.8 8947.5 5241.3 5439.0 5441.0 5442.0 5443.8 3600.2 3627.3 3655.0 3674.4 3690.8 1073.4 1084.4 1087.2 1101.8 1112.0 10137.7 11796.4 11896.5 11915.2 11944.2 4381.2 4485.9 4633.5 4681.2 4681.6 9294.8 9305.0 9310.6 9312.0 9322.1 5231.2 5237.2 5243.3 5243.5 5247.2 4291.5 4383.3 4567.4 4862.8 4892.0 3769.0 3791.5 3878.0 3896.1 3897.0 1141.4 1165.9 1177.4 1179.1 1182.7 758.4 889.3 917.4 919.4 941.3 5491.6 5948.9 5978.7 6010.9 6104.0 418.5 418.5 433.5 435.5 437.2 3932.0 4057.6 4073.8 4105.0 4159.0 10619.3 10854.9 11015.4 11016.8 11037.4 10934.6 10977.6 10996.2 11024.6 11036.4 6396.9 8156.5 8179.6 8410.7 8431.8 6012.0 6062.4 6129.6 6215.8 6265.8 10058.4 10262.7 10270.9 10278.7 10309.7 8103.8 9834.5 10312.6 10508.7 10518.9 7366.0 7684.5 7688.8 7695.5 7698.0 7204.6 8139.9 8296.8 8425.6 8432.2 7506.1 7511.7 7539.4 7609.9 7655.0 7196.9 7331.9 7424.8 7527.0 7542.2 7082.1 7091.3 7121.4 7164.4 7174.6 4503.7 5042.4 5043.3 5166.8 5170.6 4969.4 5034.8 5068.3 5145.7 5236.2 22:08:00 INFO - PROCESS | 1933 | 22:08:00 INFO - PROCESS | 1933 | [#4] prototype.html Cycles:180 Average:4310.00 Median:4602.97 stddev:1360.61 (29.6%) stddev-sans-first:1330.63 22:08:00 INFO - PROCESS | 1933 | Values: 295.1 302.1 309.9 312.2 313.7 5107.2 5197.7 5263.2 5279.4 5279.8 4267.1 4451.8 4674.9 5015.2 5098.5 3301.6 3651.6 3999.9 4005.8 4030.9 3296.0 3753.6 3943.0 4023.8 4042.4 3655.3 3954.2 3971.4 3981.6 4022.0 3498.7 3605.9 3967.0 3974.0 3978.6 3481.6 3490.1 3498.6 3500.0 3520.4 4340.0 4599.8 4902.3 5035.5 5129.9 4146.9 4216.0 4589.3 4606.1 4617.2 5464.5 5484.4 5511.5 5527.2 5533.3 3650.4 3768.5 3809.7 3833.6 3853.0 5666.4 5739.7 5769.9 5775.7 5784.5 3881.6 3886.9 3897.7 3912.7 3917.8 3802.6 3848.3 3931.7 3945.8 3969.9 3398.1 3755.8 4074.4 4303.9 4558.0 4659.6 5499.8 5539.8 5573.7 5631.9 1123.8 1206.5 1305.2 1319.9 1337.8 816.0 820.7 843.7 872.8 876.5 3701.9 4276.1 4322.6 4333.8 4336.0 4301.9 4744.6 4785.0 4791.2 4843.6 3402.2 4245.1 4264.6 4289.3 4326.8 2138.1 2320.4 2337.6 2482.6 2503.4 3991.1 4015.4 4158.0 4671.8 4761.9 4675.9 4707.3 4793.8 5161.8 5799.8 5888.9 5915.6 5951.8 5953.0 5963.6 4740.0 5047.7 5747.6 5755.3 5812.6 4800.4 4948.8 5440.0 5620.9 5713.8 5588.7 5673.7 5741.7 5920.1 5950.6 3408.3 3412.5 3443.7 3744.0 4010.0 5344.7 5632.7 5638.5 5686.0 5714.5 5516.7 5525.4 5525.9 5547.9 5553.3 4882.4 5282.6 5389.4 5518.1 5525.9 4122.5 4373.0 4578.4 5370.7 5452.9 4589.5 5453.3 5459.9 5463.8 5486.4 4239.6 5480.6 5500.3 5533.7 5550.3 22:08:00 INFO - PROCESS | 1933 | 22:08:00 INFO - PROCESS | 1933 | [#5] yui.html Cycles:180 Average:23703.05 Median:4480.01 stddev:38231.12 (853.4%) stddev-sans-first:38299.37 22:08:00 INFO - PROCESS | 1933 | Values: 703.2 725.9 743.8 795.6 813.8 15622.5 16189.0 16189.8 16229.4 16241.8 14275.2 14345.5 14357.9 14366.4 14785.1 678.4 703.2 843.4 843.6 870.6 855.6 862.0 866.0 868.1 873.0 919.2 921.3 922.6 922.9 923.6 709.0 916.9 919.4 919.8 919.9 667.0 682.1 682.8 684.0 686.6 14663.9 14708.5 14713.6 14754.8 14779.8 11263.6 13942.2 14198.3 14230.7 14560.9 11302.7 11584.9 11680.1 12454.4 13548.3 3746.4 3854.2 3949.4 4599.3 4753.7 97862.0 98456.0 103307.0 103909.0 105309.0 9128.8 11003.3 11073.9 11127.3 11140.9 18442.3 21605.4 21632.2 21697.5 21705.0 7724.1 7938.6 7998.4 8027.6 8085.5 4382.9 4441.1 4441.8 4491.7 4538.0 3485.8 3622.9 3623.5 3628.6 3640.5 583.4 591.5 628.8 636.4 668.7 502.6 572.4 575.6 630.5 672.3 8890.3 8904.1 9546.0 9622.8 9712.2 615.0 641.8 649.8 666.9 671.1 4468.3 4568.2 4659.5 4717.1 4760.6 118842.0 148134.0 151717.0 152292.0 154106.2 125358.4 126686.4 130838.0 132987.7 134970.0 96199.0 98200.5 98946.1 104234.5 116912.0 8803.3 8880.2 9037.5 9046.7 9136.9 45919.6 50854.7 56699.4 61241.1 61468.1 70067.0 85320.6 91050.5 91601.6 92764.1 11317.2 11532.0 11639.6 11831.5 12112.2 12834.6 12977.5 12981.4 13121.7 13147.3 11023.8 11052.8 11398.9 11402.4 11506.6 11636.5 11794.9 11824.3 11838.8 11931.0 11768.5 11956.7 12011.2 12051.2 12496.1 8902.6 9202.4 9271.2 9642.3 9717.8 10042.1 10289.0 10302.3 10343.9 10369.8 22:08:00 INFO - PROCESS | 1933 | -------- Summary: end -------- 22:08:00 INFO - PROCESS | 1933 | 22:08:00 INFO - TEST-INFO | 1933: exit 0 22:08:00 INFO - TEST-OK | dromaeo_css | took 1118352ms 22:08:00 INFO - TEST-START | kraken 22:08:00 INFO - operating with platform_type : mac_ 22:08:00 INFO - Initialising browser for kraken test... 22:08:00 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpC7CxjW/profile http://localhost:50726/getInfo.html) 22:08:03 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 22:08:03 INFO - PROCESS | 1936 | colorDepth:24 22:08:03 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 22:08:03 INFO - PROCESS | 1936 | __metrics 22:08:03 INFO - PROCESS | 1936 | 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" 22:08:03 INFO - PROCESS | 1936 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:08:04 INFO - TEST-INFO | 1936: exit 0 22:08:04 INFO - Browser initialized. 22:08:04 INFO - Running cycle 1/1 for kraken test... 22:08:04 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpC7CxjW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:08:15 INFO - PROCESS | 1937 | RSS: Main: 234250240 22:08:15 INFO - PROCESS | 1937 | 22:08:18 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50726/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50726/tests/kraken/driver.html?testName=audio-beat-detection) 22:08:19 INFO - PROCESS | 1937 | RSS: Main: 292872192 22:08:19 INFO - PROCESS | 1937 | 22:08:21 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50726/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50726/tests/kraken/driver.html?testName=audio-dft) 22:08:22 INFO - PROCESS | 1937 | RSS: Main: 289603584 22:08:22 INFO - PROCESS | 1937 | 22:08:26 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50726/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50726/tests/kraken/driver.html?testName=audio-fft) 22:08:26 INFO - PROCESS | 1937 | RSS: Main: 378646528 22:08:26 INFO - PROCESS | 1937 | 22:08:28 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50726/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50726/tests/kraken/driver.html?testName=audio-oscillator) 22:08:29 INFO - PROCESS | 1937 | RSS: Main: 281419776 22:08:29 INFO - PROCESS | 1937 | 22:08:31 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50726/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50726/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:08:32 INFO - PROCESS | 1937 | RSS: Main: 284684288 22:08:32 INFO - PROCESS | 1937 | 22:08:34 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50726/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50726/tests/kraken/driver.html?testName=imaging-darkroom) 22:08:35 INFO - PROCESS | 1937 | RSS: Main: 323801088 22:08:35 INFO - PROCESS | 1937 | 22:08:38 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50726/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50726/tests/kraken/driver.html?testName=imaging-desaturate) 22:08:39 INFO - PROCESS | 1937 | RSS: Main: 325337088 22:08:39 INFO - PROCESS | 1937 | 22:08:41 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50726/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50726/tests/kraken/driver.html?testName=json-parse-financial) 22:08:42 INFO - PROCESS | 1937 | RSS: Main: 324063232 22:08:42 INFO - PROCESS | 1937 | 22:08:44 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50726/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50726/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:08:44 INFO - PROCESS | 1937 | RSS: Main: 297398272 22:08:44 INFO - PROCESS | 1937 | 22:08:46 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50726/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50726/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:08:47 INFO - PROCESS | 1937 | RSS: Main: 302477312 22:08:47 INFO - PROCESS | 1937 | 22:08:49 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50726/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50726/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:08:50 INFO - PROCESS | 1937 | RSS: Main: 301580288 22:08:50 INFO - PROCESS | 1937 | 22:08:53 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50726/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50726/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:08:53 INFO - PROCESS | 1937 | RSS: Main: 321368064 22:08:53 INFO - PROCESS | 1937 | 22:08:56 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50726/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50726/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:08:57 INFO - PROCESS | 1937 | RSS: Main: 317485056 22:08:57 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:50726/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50726/tests/kraken/driver.html?testName=ai-astar) 22:08:59 INFO - PROCESS | 1937 | RSS: Main: 361123840 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | __start_tp_report 22:08:59 INFO - PROCESS | 1937 | _x_x_mozilla_page_load 22:08:59 INFO - PROCESS | 1937 | _x_x_mozilla_page_load_details 22:08:59 INFO - PROCESS | 1937 | |i|pagename|runs| 22:08:59 INFO - PROCESS | 1937 | |0;ai-astar;112;114;115;111;112;113;112;112;112;121 22:08:59 INFO - PROCESS | 1937 | |1;audio-beat-detection;146;115;128;161;108;129;128;108;162;111 22:08:59 INFO - PROCESS | 1937 | |2;audio-dft;185;186;186;184;185;184;184;183;180;224 22:08:59 INFO - PROCESS | 1937 | |3;audio-fft;76;78;76;153;58;58;58;65;138;57 22:08:59 INFO - PROCESS | 1937 | |4;audio-oscillator;82;83;134;69;83;80;123;68;70;118 22:08:59 INFO - PROCESS | 1937 | |5;imaging-gaussian-blur;87;84;88;85;88;84;88;84;88;84 22:08:59 INFO - PROCESS | 1937 | |6;imaging-darkroom;113;149;110;97;93;93;94;112;147;117 22:08:59 INFO - PROCESS | 1937 | |7;imaging-desaturate;85;86;83;86;85;85;88;84;83;86 22:08:59 INFO - PROCESS | 1937 | |8;json-parse-financial;54;55;56;55;56;54;53;54;56;53 22:08:59 INFO - PROCESS | 1937 | |9;json-stringify-tinderbox;64;70;66;73;77;78;73;70;70;69 22:08:59 INFO - PROCESS | 1937 | |10;stanford-crypto-aes;76;86;99;104;105;105;80;86;85;90 22:08:59 INFO - PROCESS | 1937 | |11;stanford-crypto-ccm;136;137;136;144;153;156;132;148;197;140 22:08:59 INFO - PROCESS | 1937 | |12;stanford-crypto-pbkdf2;168;166;168;166;168;167;169;169;169;169 22:08:59 INFO - PROCESS | 1937 | |13;stanford-crypto-sha256-iterative;68;67;66;68;68;67;65;68;66;67 22:08:59 INFO - PROCESS | 1937 | __end_tp_report 22:08:59 INFO - PROCESS | 1937 | __start_cc_report 22:08:59 INFO - PROCESS | 1937 | _x_x_mozilla_cycle_collect,2037 22:08:59 INFO - PROCESS | 1937 | __end_cc_report 22:08:59 INFO - PROCESS | 1937 | __startTimestamp1472706539262__endTimestamp 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | ------- Summary: start ------- 22:08:59 INFO - PROCESS | 1937 | Number of tests: 14 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | [#0] ai-astar Cycles:10 Average:113.40 Median:112.50 stddev:2.91 (2.6%) stddev-sans-first:3.05 22:08:59 INFO - PROCESS | 1937 | Values: 112.0 114.0 115.0 111.0 112.0 113.0 112.0 112.0 112.0 121.0 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:128.50 stddev:20.55 (16.0%) stddev-sans-first:20.93 22:08:59 INFO - PROCESS | 1937 | Values: 146.0 115.0 128.0 161.0 108.0 129.0 128.0 108.0 162.0 111.0 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | [#2] audio-dft Cycles:10 Average:188.10 Median:185.00 stddev:12.73 (6.9%) stddev-sans-first:13.45 22:08:59 INFO - PROCESS | 1937 | Values: 185.0 186.0 186.0 184.0 185.0 184.0 184.0 183.0 180.0 224.0 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | [#3] audio-fft Cycles:10 Average:81.70 Median:61.50 stddev:34.81 (56.6%) stddev-sans-first:36.86 22:08:59 INFO - PROCESS | 1937 | Values: 76.0 78.0 76.0 153.0 58.0 58.0 58.0 65.0 138.0 57.0 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:75.00 stddev:24.46 (32.6%) stddev-sans-first:25.73 22:08:59 INFO - PROCESS | 1937 | Values: 82.0 83.0 134.0 69.0 83.0 80.0 123.0 68.0 70.0 118.0 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.50 stddev:1.94 (2.2%) stddev-sans-first:2.03 22:08:59 INFO - PROCESS | 1937 | Values: 87.0 84.0 88.0 85.0 88.0 84.0 88.0 84.0 88.0 84.0 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | [#6] imaging-darkroom Cycles:10 Average:112.50 Median:121.00 stddev:20.80 (17.2%) stddev-sans-first:22.06 22:08:59 INFO - PROCESS | 1937 | Values: 113.0 149.0 110.0 97.0 93.0 93.0 94.0 112.0 147.0 117.0 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.52 (1.8%) stddev-sans-first:1.62 22:08:59 INFO - PROCESS | 1937 | Values: 85.0 86.0 83.0 86.0 85.0 85.0 88.0 84.0 83.0 86.0 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.17 (2.1%) stddev-sans-first:1.22 22:08:59 INFO - PROCESS | 1937 | Values: 54.0 55.0 56.0 55.0 56.0 54.0 53.0 54.0 56.0 53.0 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.50 stddev:4.40 (6.1%) stddev-sans-first:3.87 22:08:59 INFO - PROCESS | 1937 | Values: 64.0 70.0 66.0 73.0 77.0 78.0 73.0 70.0 70.0 69.0 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:85.50 stddev:10.82 (12.7%) stddev-sans-first:9.90 22:08:59 INFO - PROCESS | 1937 | Values: 76.0 86.0 99.0 104.0 105.0 105.0 80.0 86.0 85.0 90.0 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:146.00 stddev:18.96 (13.0%) stddev-sans-first:19.61 22:08:59 INFO - PROCESS | 1937 | Values: 136.0 137.0 136.0 144.0 153.0 156.0 132.0 148.0 197.0 140.0 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:168.50 stddev:1.20 (0.7%) stddev-sans-first:1.27 22:08:59 INFO - PROCESS | 1937 | Values: 168.0 166.0 168.0 166.0 168.0 167.0 169.0 169.0 169.0 169.0 22:08:59 INFO - PROCESS | 1937 | 22:08:59 INFO - PROCESS | 1937 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.50 stddev:1.05 (1.6%) stddev-sans-first:1.05 22:08:59 INFO - PROCESS | 1937 | Values: 68.0 67.0 66.0 68.0 68.0 67.0 65.0 68.0 66.0 67.0 22:08:59 INFO - PROCESS | 1937 | -------- Summary: end -------- 22:08:59 INFO - PROCESS | 1937 | 22:09:00 INFO - TEST-INFO | 1937: exit 0 22:09:00 INFO - TEST-OK | kraken | took 59251ms 22:09:00 INFO - SUITE-END | took 1177s 22:09:00 INFO - Completed test suite (00:19:37) 22:09:00 INFO - javascript benchmark 22:09:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2984.403119376125, 3346.7991920484765, 3351.9944016794957, 3398.0, 3424.349373619012, 5665.490105890469, 5705.771769129235, 5731.083026715726, 5824.796132135376, 5850.180974663551, 5740.282464691913, 5752.568557358194, 5834.708264586764, 5867.941320586793, 5941.64350138992, 5151.922721159186, 5190.06578815813, 5193.428722840485, 5197.818076367328, 5238.790448382079, 5333.466653334654, 5346.171343441753, 5350.956563470125, 5365.168398898158, 5378.46215378463, 5175.508326708957, 5188.896222075575, 5210.97394513025, 5256.159014557672, 5284.867878303035, 5251.684898906078, 5258.92111618326, 5263.973680131613, 5264.368275806889, 5278.894422111536, 4734.171519983989, 4854.150523658381, 5184.885249671301, 5218.217267409881, 5252.973735131338, 5732.828015159552, 5734.856628584277, 5750.424957504258, 5760.913586296209, 5767.192593036978, 6171.197744293258, 6236.282827474808, 6253.593516421418, 6305.558610897239, 6309.432151106376, 6104.20645316106, 6241.095041219043, 6268.247810262752, 6270.529710271748, 6273.3726627337355, 5754.165645981309, 5931.406859314034, 5935.821925342289, 5939.109133629903, 5941.0197317443, 6191.84520386994, 6210.347913469021, 6210.875782484325, 6239.844003899848, 6324.272708638474, 6211.534134939898, 6228.314885362606, 6233.750649974041, 6233.937660623426, 6247.9062814057825, 6144.41628045335, 6275.215598050244, 6292.685365731695, 6309.116723658692, 6310.211223597051, 6167.321594624587, 6202.937970620326, 6239.937600624026, 6302.1177035215105, 6310.6213627182515, 6296.181496405438, 6303.243610766645, 6309.684515774285, 6313.494920406357, 6318.589291696025, 4885.1206782779445, 4924.990376972783, 4973.459611803301, 4988.9750551247735, 5031.295618613397, 3647.835847386874, 4418.955810441855, 4460.77696115193, 4476.686631935733, 4547.454305483363, 5797.36229014808, 5844.327902291291, 5853.502452291517, 5853.853653658693, 5869.618474799125, 6283.748650054038, 6291.276503202099, 6298.905516417258, 6301.747930082837, 6305.747770089237, 5734.971325143348, 5849.268841394825, 5864.354920958691, 5878.970605147032, 5983.730732117066, 5506.724663766876, 5507.339119305709, 5510.421405752419, 5513.889722205614, 5525.309336332958, 6312.95836187024, 6366.840828979312, 6369.585976911486, 6383.2978372379, 6383.840403989751, 6193.628382297076, 6198.132261483486, 6200.441960223601, 6255.155554000152, 6292.433680968552, 6354.364563543609, 6361.491080713439, 6372.203474565679, 6376.107344971615, 6386.105945167773, 6242.0, 6265.0, 6265.780697675559, 6272.6236425814595, 6274.843128921813, 6117.096718007379, 6174.598651087665, 6188.288346840171, 6195.256569211723, 6212.378762123933, 6139.492138333381, 6170.6914654268, 6172.321044684992, 6181.320054794059, 6188.628682279078, 6090.025595904634, 6100.23747031621, 6174.38256174379, 6178.783742568989, 6187.443130118311, 5841.32824725145, 5936.22829032223, 5970.297620230956, 5988.371221021905, 6095.8780824382375, 6150.907736383869, 6174.691265436621, 6214.533909956681, 6221.253449586078, 6221.875562488815, 6026.548008899263, 6042.0332746760305, 6044.939550604614, 6057.363976782374, 6101.633901965896], "value": 5745.613505160261, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.4797644555661, 507.1505228741857, 509.0582422518344, 510.92960248279854, 520.8437468759371, 30634.693653063463, 31974.040778776645, 39859.80070099646, 40857.0, 40991.79504102483, 42251.36622950651, 42607.14785704292, 42723.0, 42731.93170170747, 42797.0, 9525.237980961523, 9716.514174291256, 9723.805523889518, 9728.902710972905, 9746.51267436629, 8803.911960880374, 8934.642614295453, 8935.910640893573, 9058.954705226464, 9071.637134514645, 11897.643070707893, 12202.572909948154, 12217.022638188968, 12402.503899843996, 12430.192037517578, 12102.124170753546, 12184.20802647825, 12221.2667239965, 12222.144449888512, 12231.755364892697, 11949.402529873472, 12024.69938251542, 12038.53210091929, 12041.337726425043, 12060.0, 39458.81623551298, 39744.20511589752, 39755.60244397548, 39816.0, 39870.60129398727, 3140.874365025419, 3266.1508007917873, 3281.196106953788, 3359.6136444308727, 3472.0451875734125, 30524.69475305241, 30979.535306970414, 31373.84313078443, 31897.0, 32006.359872802415, 3210.2616398228306, 3352.044667269841, 3361.8487168077495, 3420.811855347955, 3421.81180035098, 479870.0, 490633.0, 491081.0, 491250.0, 502700.48649756884, 64687.560930492655, 65390.34609653842, 65422.672886635264, 65625.67187164034, 65702.67148664322, 66383.66808165929, 66863.66568167128, 66874.66562667156, 66972.0, 67038.0, 16519.587010324838, 16770.58073548171, 16786.0, 16823.49529514092, 16832.24254908532, 12766.68083297925, 12883.613491595079, 12883.742325153444, 12899.742005160031, 12914.289714065817, 15954.312728339339, 17132.914335428493, 19375.42356531935, 19962.600747984958, 20584.27955021567, 8395.286400656012, 8411.242988131098, 8412.537310447984, 8414.284785793276, 8526.868282929268, 841.0, 900.7883147460342, 901.8106197698426, 906.7098528470958, 1032.6643840751785, 26855.194344169708, 29472.26319341991, 30199.396012079636, 30248.69751302503, 30721.924732634696, 1019.1439191079529, 1050.5797680927537, 1113.1984970820995, 1124.4040658451033, 1177.2583272538245, 15382.384704611914, 15550.611234718996, 15762.921185394, 15766.931426662277, 15861.822726055456, 640987.0, 641264.0, 641325.0, 642479.0, 642707.0, 48423.75788121037, 48685.026299473815, 48722.5127748718, 52088.0, 56961.0, 1220.8412906322121, 1322.7552902713028, 1360.857110003455, 1382.4884792626592, 1388.0838646493266, 26829.195124146307, 29992.85003574968, 30990.69009309878, 31256.374872502878, 32048.67951320457, 49504.75247623739, 50102.997940040266, 50116.0, 50198.74900625474, 50288.245676315615, 511357.0, 512330.0, 513222.0, 516563.0, 517094.0, 26895.865520672272, 27236.0, 27489.037883673976, 27958.860205699653, 28169.0, 46530.53469465262, 46769.363072293665, 47156.0, 47705.04589908113, 48023.0, 38187.42718859275, 38293.80853095717, 38630.039813968404, 39021.80489097536, 39603.80198098991, 38549.03627409337, 38626.0, 39903.60096399115, 39906.80046599748, 40415.39376909407, 37608.43587346247, 37640.43539346857, 38509.614903850605, 38527.807360963016, 38591.80704096462, 31069.68930310668, 33440.832795835864, 33930.49104263487, 34393.140171495914, 34427.655723443444, 22193.22323718662, 24983.625245620966, 25168.370790729645, 25172.496550069263, 25346.305006120972], "value": 20323.968241784045, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.4895828133891, 355.1387884380377, 378.8749712594842, 388.48525703442925, 410.7843382224332, 15677.0, 17219.741703874435, 20366.796332036676, 21053.789462105375, 21144.471388215283, 20130.295439659578, 20486.385408437734, 21017.894910525425, 21056.89471552644, 21103.788962110375, 5449.836504904849, 5642.097264437671, 5657.038303488417, 5688.914666280009, 6131.693415329216, 3941.586133455989, 6084.908726369108, 6199.597026193283, 6202.5968312059795, 6213.130161777332, 5070.783012077111, 6202.069689546582, 6204.4416002559765, 6255.718492667841, 6264.624122552661, 5988.161657367972, 6133.907991380132, 6171.814845554641, 6234.501239900797, 6257.280412752546, 5348.946510534884, 5756.560859785054, 6037.577369584131, 6039.214902062704, 6042.516598672096, 13114.409851556607, 13155.934220328932, 13306.667333316647, 13441.596752097452, 13484.932575337061, 2244.2145249162827, 2463.3964678653842, 2500.374906273432, 2547.261294224673, 2776.3336799168255, 10173.643922462677, 10209.2853500255, 10223.488825558692, 10786.244962852603, 14744.3365048572, 1838.8966620027877, 1932.5168707823045, 1942.873713208637, 1960.7549056367955, 1983.8512111591688, 95306.52346738221, 116649.0, 120120.99999999913, 120828.39585802103, 121856.0, 8773.824523509495, 11331.943340283411, 11438.94280528592, 11449.885501144881, 11491.310521368745, 14545.709085818224, 14554.636134096732, 14560.49038283655, 14560.781588276184, 14572.417103315749, 8351.3736469765, 8527.23254907049, 8536.615852286663, 8539.0, 8678.305735541142, 8461.45000574973, 8475.152484751476, 8628.78428039304, 8676.956615216883, 8686.218240358397, 4413.1394378095965, 4498.280275155959, 4499.707519011254, 4519.857882287705, 4596.126735920165, 1862.7392165096896, 1895.4218963216351, 1907.904604769756, 1910.5701217226012, 1917.769867615867, 838.6142374507673, 898.7797989492616, 899.325505870597, 930.4463844012802, 952.4142652268805, 17483.912580437016, 20377.0, 21018.0, 21694.674579881314, 21812.781872181393, 935.695898832882, 941.1811723800338, 960.1550635440907, 964.9324547281764, 1021.7445638590352, 9980.900190997998, 10491.632792852366, 11381.601643942422, 11407.828882566768, 11418.657440276806, 577736.0, 592765.0, 596595.0, 596800.0, 597745.0, 99239.0, 113058.0, 120242.0, 120627.19059214118, 122241.0, 782.4210084537401, 907.9092090790868, 982.9950850245846, 998.7103739915343, 1023.7542989682422, 15163.317650705965, 18858.434246972614, 19219.615607688047, 19284.325048623232, 19298.517537061685, 41296.0, 49372.012559747884, 52045.576126029715, 53519.732401337744, 55758.72120639371, 75240.0, 76941.0, 77415.61292193503, 78028.0, 79561.20438795538, 7936.120506293923, 8024.157463466252, 8136.674533018613, 8324.167583241628, 8373.539455330016, 12735.23588584688, 13296.601101966957, 13489.527866525017, 13894.513692020731, 13979.650508737363, 10475.42385168823, 11536.868165052038, 11637.418129093343, 11797.941010294893, 11827.231229970024, 11397.373144476802, 11620.012298954345, 11656.242344247936, 11783.823242651195, 11816.645500634995, 11483.598074067368, 11573.074154067486, 11810.996065334542, 11894.881051189377, 11988.520459181535, 8158.388120891075, 8204.097549269694, 8320.292775113941, 8423.410361274897, 8472.322214222972, 8672.479651220947, 11188.328700278009, 11282.661520154408, 11353.829692554453, 11612.361320127475], "value": 9425.022664942599, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.2405041151742, 1499.3552772307908, 1549.883758718096, 1733.1940647598872, 1897.9145938432769, 7824.765257042291, 8552.102029286922, 8900.0, 8902.0, 8951.686690965818, 8756.299496040318, 8856.0, 8860.734177974655, 8879.334049946263, 8883.111688831113, 2368.834181607284, 2395.2933884504114, 2397.7961873240797, 2398.4843258699375, 2403.0748161957686, 2344.5193735284224, 2377.465070359173, 2403.495265994143, 2406.097713357491, 2417.3352328109822, 2368.5736567417857, 2383.2373640435067, 2395.6406539019113, 2409.361519197414, 2412.4089598048413, 2345.5074434368803, 2363.9881800590974, 2367.065009321317, 2372.240882917467, 2392.7487613800477, 2338.286822519131, 2350.5063936573342, 2385.666006759038, 2392.820538459605, 2402.699662542182, 8758.299336053164, 8812.911870881337, 8920.509371984539, 8945.821083578292, 8947.463152210888, 5241.344831896013, 5439.048166570834, 5441.0, 5442.047641662693, 5443.836684899459, 3600.153963818527, 3627.292677927805, 3655.0131464504434, 3674.4120940649636, 3690.7785532868115, 1073.4471747050275, 1084.3981590217427, 1087.190043417651, 1101.7576133250673, 1111.9666410007712, 10137.695869123938, 11796.410179490991, 11896.464659090361, 11915.2255103418, 11944.163908526407, 4381.18947994622, 4485.932711009337, 4633.536646335407, 4681.227597446451, 4681.578657920803, 9294.814103717888, 9305.0, 9310.556863686004, 9312.0, 9322.114399132071, 5231.189165679326, 5237.16205407133, 5243.344581927259, 5243.501867322599, 5247.239150323182, 4291.527931927485, 4383.276759334674, 4567.406237189144, 4862.78117484714, 4892.0, 3769.0, 3791.4881490998364, 3878.0498777799057, 3896.1428485733095, 3896.9610303897166, 1141.3608379307614, 1165.9125565582447, 1177.3583397048458, 1179.097990037622, 1182.7102359921703, 758.427387322579, 889.3196704521047, 917.4357769971419, 919.4483310014072, 941.3457646935315, 5491.588130890199, 5948.881022379528, 5978.701064946736, 6010.939890601126, 6103.969480152571, 418.47063464717155, 418.4852631263502, 433.49281340831305, 435.50570102932767, 437.2108344438319, 3932.0, 4057.594240575919, 4073.796310184479, 4104.979475102606, 4158.958410415918, 10619.256652034283, 10854.891451085547, 11015.394153321644, 11016.779664406828, 11037.448127593589, 10934.617288394868, 10977.560897564008, 10996.175286853359, 11024.614138505114, 11036.448177591088, 6396.872062558723, 8156.469829461066, 8179.550124743197, 8410.705625303084, 8431.831363372698, 6012.0, 6062.363451837494, 6129.632222066691, 6215.81352559424, 6265.8120256392385, 10058.396496210251, 10262.692119236435, 10270.948645256725, 10278.743031424274, 10309.74225644365, 8103.797405064921, 9834.459104749307, 10312.639057632947, 10508.684739457829, 10518.894811051792, 7365.963170184329, 7684.462087654034, 7688.769336919901, 7695.461317707706, 7697.999260096151, 7204.56772593646, 8139.877901831595, 8296.792580185544, 8425.62084706202, 8432.19894110083, 7506.136794268729, 7511.737089201852, 7539.434542409451, 7609.88585171234, 7655.004849369547, 7196.856062878818, 7331.920861806452, 7424.814379640552, 7527.0, 7542.170361260367, 7082.114735658043, 7091.290870912868, 7121.394681452135, 7164.355208031302, 7174.641267936478, 4503.707259028152, 5042.39491261051, 5043.293938848491, 5166.844994650167, 5170.6380553360905, 4969.428515720739, 5034.798608055637, 5068.265101560327, 5145.716985565829, 5236.214567814858], "value": 4497.9861281542935, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.1073004162407, 302.1283596823165, 309.89463582381995, 312.2225658111301, 313.7348940145576, 5107.208382700678, 5197.68813871168, 5263.184206447997, 5279.392869819972, 5279.815206467774, 4267.146570685867, 4451.798014536073, 4674.929876051853, 5015.222640490721, 5098.541131298182, 3301.620313663929, 3651.6165802590745, 3999.860004899829, 4005.7796821174825, 4030.891504836164, 3295.9835200823964, 3753.5871054184017, 3942.989075571465, 4023.8330884043594, 4042.43405923171, 3655.287218992284, 3954.209158168378, 3971.3518889660754, 3981.641652251297, 4022.0, 3498.7375946803836, 3605.891823245307, 3966.9884179534324, 3973.9599206606968, 3978.6021397860272, 3481.5899560677967, 3490.127468132967, 3498.5801703795455, 3499.9825000874835, 3520.4476754353145, 4339.978300108479, 4599.816007359702, 4902.264660300948, 5035.471275516092, 5129.948700513021, 4146.896327591804, 4216.03031302799, 4589.2657174851865, 4606.124836281096, 4617.215073437524, 5464.497263252598, 5484.396716361197, 5511.456792098219, 5527.170924361338, 5533.280673512458, 3650.3976843820737, 3768.4724138620613, 3809.676177524914, 3833.5974722654, 3852.9807350963065, 5666.433356664342, 5739.684317362543, 5769.8557536061935, 5775.653460792324, 5784.537237021028, 3881.5924327945722, 3886.8639597613947, 3897.7271590988357, 3912.68698504119, 3917.804109794499, 3802.6310528209824, 3848.2869576948183, 3931.744436611611, 3945.8027098644952, 3969.900752481211, 3398.065531978676, 3755.816917670925, 4074.409210664437, 4303.870883873489, 4558.0, 4659.5573420525, 5499.84503254321, 5539.806106786243, 5573.693446860461, 5631.915521267184, 1123.842662027317, 1206.4993027893502, 1305.1907817153426, 1319.9274039927705, 1337.7993301004926, 815.995920020408, 820.6758330459435, 843.6625349859983, 872.8173325144464, 876.5179151466796, 3701.8704345348324, 4276.144771045803, 4322.5677432256525, 4333.783310834446, 4335.978320108379, 4301.91396172081, 4744.596709279681, 4785.009369144284, 4791.161546729309, 4843.636727245471, 3402.213090068291, 4245.11332294043, 4264.594863487963, 4289.292266775947, 4326.826926922888, 2138.145575656516, 2320.3619004773823, 2337.590921588749, 2482.5601151332185, 2503.4116982509095, 3991.1263889999154, 4015.377616469452, 4158.045718512294, 4671.789769460383, 4761.880962971513, 4675.906481870344, 4707.340972263886, 4793.760311984387, 5161.845144645667, 5799.797007104731, 5888.882222355529, 5915.556333274935, 5951.821445356646, 5952.970235148797, 5963.552733544915, 4740.0, 5047.697138171721, 5747.626404283708, 5755.251817263729, 5812.622179558315, 4800.423949126127, 4948.826791062295, 5439.972800136133, 5620.85947851307, 5713.771449141987, 5588.692621905834, 5673.723437203275, 5741.655500669973, 5920.141579470866, 5950.613210141327, 3408.2842603053637, 3412.5051867479574, 3443.708609271523, 3743.981280093582, 4009.9574110732005, 5344.732763361894, 5632.690202038927, 5638.5489160867855, 5686.009896536179, 5714.4856612996755, 5516.668999860021, 5525.392206857323, 5525.889482210253, 5547.945890280834, 5553.305836770403, 4882.389701287339, 5282.550983166474, 5389.434109418455, 5518.117101263939, 5525.861853453695, 4122.505299364096, 4373.037931655031, 4578.359029735773, 5370.704611246419, 5452.945470545244, 4589.495155532955, 5453.291072160594, 5459.945400545944, 5463.7814487420055, 5486.423925487926, 4239.618434340924, 5480.616356854982, 5500.257465242301, 5533.6956467393065, 5550.306211723535], "value": 3884.207192802381, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.2475251480915, 725.9128904531457, 743.7619961612282, 795.5942469340638, 813.8413009463158, 15622.453214137493, 16189.028658280511, 16189.75715364269, 16229.350825966976, 16241.837581624195, 14275.21486318252, 14345.497907573237, 14357.856421435783, 14366.425342986295, 14785.112893226415, 678.396227357652, 703.2440126863617, 843.3843294395095, 843.5908584336607, 870.6169285514385, 855.632078206371, 862.0086900064913, 866.0473479172891, 868.1058509734952, 872.9650813967434, 919.2186641354862, 921.3320342751485, 922.5710044829153, 922.898481167071, 923.5705396990398, 709.0286307758404, 916.8624706294042, 919.384012711485, 919.7930465645243, 919.8712180294731, 667.0494545272967, 682.0519477925637, 682.757621044527, 683.967209513638, 686.6429456682497, 14663.92668036653, 14708.48520301795, 14713.558593242113, 14754.778678319833, 14779.778303325458, 11263.605773797959, 13942.163470191821, 14198.290085495788, 14230.715385692332, 14560.854391456056, 11302.660920172408, 11584.884151158549, 11680.0655947524, 12454.439550220262, 13548.322583870768, 3746.4193050077283, 3854.1713531590844, 3949.4273330366937, 4599.264117741145, 4753.738544380058, 97862.0, 98456.0, 103307.0, 103909.0, 105309.0, 9128.81742365149, 11003.284786488852, 11073.944630276797, 11127.332360058423, 11140.888591114148, 18442.26230950747, 21605.35183944484, 21632.242871499737, 21697.457563561038, 21705.0, 7724.073111226577, 7938.56337901421, 7998.440109192417, 8027.5584842834205, 8085.474444161111, 4382.912341753211, 4441.133978874153, 4441.822327106945, 4491.708038977456, 4538.0, 3485.8431370588382, 3622.855085796539, 3623.4927110204594, 3628.564572251347, 3640.4539319102346, 583.4107551373144, 591.520868096843, 628.7704987679464, 636.424036247197, 668.7258224128182, 502.5602597726989, 572.4447286132445, 575.6172145523263, 630.4861537846732, 672.284017521348, 8890.288776897833, 8904.095943899798, 9546.0, 9622.759431014281, 9712.17446517054, 614.9600275982046, 641.7593402474072, 649.808306549569, 666.9366410191024, 671.1040760584674, 4468.308950513159, 4568.200564901129, 4659.487456379796, 4717.080169366941, 4760.619150467955, 118842.0, 148134.0, 151717.0, 152292.0, 154106.22946885193, 125358.3732081352, 126686.3665681684, 130838.0, 132987.67012329947, 134970.0, 96199.0, 98200.50899745456, 98946.09485146227, 104234.47882760537, 116912.0, 8803.251723603309, 8880.245179159843, 9037.50293733851, 9046.728598142066, 9136.908630913871, 45919.62241132771, 50854.71153798014, 56699.433005669416, 61241.081383778386, 61468.07797882946, 70067.0, 85320.57339713261, 91050.54474727584, 91601.62597561175, 92764.08538719246, 11317.151213658844, 11532.019778318936, 11639.59261425846, 11831.467583958914, 12112.15214934946, 12834.55079072228, 12977.545785897448, 12981.350932453528, 13121.737565248832, 13147.276899770604, 11023.83464248021, 11052.834207486734, 11398.886011139783, 11402.372869492257, 11506.597269095542, 11636.534538618698, 11794.941025294818, 11824.349660768741, 11838.822417663914, 11931.019462648921, 11768.470418831002, 11956.701082473008, 12011.219270747373, 12051.216670916363, 12496.12527123129, 8902.643894244158, 9202.401843880394, 9271.165595096474, 9642.276829237695, 9717.805643887225, 10042.11948423496, 10289.0, 10302.278840481094, 10343.94828025855, 10369.844452333373], "value": 7546.129084255926, "unit": "score"}], "name": "dromaeo_css", "value": 7249.147244195556, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 115.0, 111.0, 112.0, 113.0, 112.0, 112.0, 112.0, 121.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 128.0, 161.0, 108.0, 129.0, 128.0, 108.0, 162.0, 111.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 186.0, 184.0, 185.0, 184.0, 184.0, 183.0, 180.0, 224.0], "value": 188.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 76.0, 153.0, 58.0, 58.0, 58.0, 65.0, 138.0, 57.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 134.0, 69.0, 83.0, 80.0, 123.0, 68.0, 70.0, 118.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 85.0, 88.0, 84.0, 88.0, 84.0, 88.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 149.0, 110.0, 97.0, 93.0, 93.0, 94.0, 112.0, 147.0, 117.0], "value": 112.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 86.0, 85.0, 85.0, 88.0, 84.0, 83.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 56.0, 55.0, 56.0, 54.0, 53.0, 54.0, 56.0, 53.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 70.0, 66.0, 73.0, 77.0, 78.0, 73.0, 70.0, 70.0, 69.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 86.0, 99.0, 104.0, 105.0, 105.0, 80.0, 86.0, 85.0, 90.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 136.0, 144.0, 153.0, 156.0, 132.0, 148.0, 197.0, 140.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 168.0, 166.0, 168.0, 167.0, 169.0, 169.0, 169.0, 169.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 68.0, 68.0, 67.0, 65.0, 68.0, 66.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1487.4000000000003, "alertThreshold": 2.0}]} 22:09:00 INFO - Return code: 0 22:09:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:09:00 INFO - # TBPL SUCCESS # 22:09:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:09:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:09:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:09:00 INFO - 2016-08-31 22:09:00.587 screenresolution[1941:16569] starting screenresolution argv=screenresolution get 22:09:00 INFO - 2016-08-31 22:09:00.590 screenresolution[1941:16569] Display 0: 1600x1200x32@60 22:09:00 INFO - 2016-08-31 22:09:00.596 screenresolution[1942:16572] starting screenresolution argv=screenresolution list 22:09:00 INFO - Available Modes on Display 0 22:09:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:09:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:09:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:09:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:09:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:09:00 INFO - Intel Iris: 22:09:00 INFO - Chipset Model: Intel Iris 22:09:00 INFO - Type: GPU 22:09:00 INFO - Bus: Built-In 22:09:00 INFO - VRAM (Dynamic, Max): 1536 MB 22:09:00 INFO - Vendor: Intel (0x8086) 22:09:00 INFO - Device ID: 0x0a2e 22:09:00 INFO - Revision ID: 0x0009 22:09:00 INFO - Displays: 22:09:00 INFO - Display: 22:09:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:09:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:09:00 INFO - Display Serial Number: 1600x1200 60 22:09:00 INFO - Main Display: Yes 22:09:00 INFO - Mirror: Off 22:09:00 INFO - Online: Yes 22:09:00 INFO - Rotation: Supported 22:09:00 INFO - Return code: 0 22:09:00 INFO - Running post-action listener: _resource_record_post_action 22:09:00 INFO - [mozharness: 2016-09-01 05:09:00.831299Z] Finished run-tests step (success) 22:09:00 INFO - Running post-run listener: _resource_record_post_run 22:09:00 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 655360; Write bytes: 705189376; Read time: 9; Write time: 2019 22:09:00 INFO - TinderboxPrint: CPU usage
53.6% 22:09:00 INFO - TinderboxPrint: I/O read bytes / time
655,360 / 9 22:09:00 INFO - TinderboxPrint: I/O write bytes / time
705,189,376 / 2,019 22:09:00 INFO - TinderboxPrint: CPU idle
2,251.3 (46.4%) 22:09:00 INFO - TinderboxPrint: CPU system
971.1 (20.0%) 22:09:00 INFO - TinderboxPrint: CPU user
1,627.3 (33.6%) 22:09:00 INFO - TinderboxPrint: Swap in / out
406,196,224 / 0 22:09:00 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396538368; Write bytes: 390694400; Read time: 29811; Write time: 799 22:09:00 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 647168; Write bytes: 294549504; Read time: 8; Write time: 1173 22:09:01 INFO - Running post-run listener: _upload_blobber_files 22:09:01 INFO - Blob upload gear active. 22:09:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:09:01 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 22:09:01 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'] 22:09:01 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 22:09:01 INFO - (blobuploader) - INFO - Open directory for files ... 22:09:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:09:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:09:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:02 INFO - (blobuploader) - INFO - Done attempting. 22:09:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:09:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:09:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:03 INFO - (blobuploader) - INFO - Done attempting. 22:09:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:09:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:09:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:09:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:09:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:09:06 INFO - (blobuploader) - INFO - Done attempting. 22:09:06 INFO - (blobuploader) - INFO - Iteration through files over. 22:09:06 INFO - Return code: 0 22:09:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:09:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:09:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bdbc0f4acb464154d39460e91edea04fa0d95452b66cac9eb5b86f3ee2179cb98e5af9c52af9c70cb86dc6e106f3b48c508d2c36083b3c62e38b5cd670b18843", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6180fe9187d709540027a9f4970857ba0534388ced2141f0c1429208ed96764554ab77a4fe5fba64e316e89ee70c6d1702190497418e48b2bdc5a29a3500077", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c47560165439ee0722a56fce4044e4acae47efa423804a47218e02391f6ca6719f8f9ce9316dd6fb15b438e46cc58d7a829ec63bbee2b3689e4ad4723a1bafe8"} 22:09:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:09:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:09:06 INFO - Contents: 22:09:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bdbc0f4acb464154d39460e91edea04fa0d95452b66cac9eb5b86f3ee2179cb98e5af9c52af9c70cb86dc6e106f3b48c508d2c36083b3c62e38b5cd670b18843", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6180fe9187d709540027a9f4970857ba0534388ced2141f0c1429208ed96764554ab77a4fe5fba64e316e89ee70c6d1702190497418e48b2bdc5a29a3500077", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c47560165439ee0722a56fce4044e4acae47efa423804a47218e02391f6ca6719f8f9ce9316dd6fb15b438e46cc58d7a829ec63bbee2b3689e4ad4723a1bafe8"} 22:09:06 INFO - Running post-run listener: copy_logs_to_upload_dir 22:09:06 INFO - Copying logs to upload dir... 22:09:06 INFO - mkdir: /builds/slave/test/build/upload/logs 22:09:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.098687 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-31 22:09:06.782034) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 22:09:06.785611) ========= 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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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/bdbc0f4acb464154d39460e91edea04fa0d95452b66cac9eb5b86f3ee2179cb98e5af9c52af9c70cb86dc6e106f3b48c508d2c36083b3c62e38b5cd670b18843", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6180fe9187d709540027a9f4970857ba0534388ced2141f0c1429208ed96764554ab77a4fe5fba64e316e89ee70c6d1702190497418e48b2bdc5a29a3500077", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c47560165439ee0722a56fce4044e4acae47efa423804a47218e02391f6ca6719f8f9ce9316dd6fb15b438e46cc58d7a829ec63bbee2b3689e4ad4723a1bafe8"} build_url:https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011467 build_url: 'https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/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/bdbc0f4acb464154d39460e91edea04fa0d95452b66cac9eb5b86f3ee2179cb98e5af9c52af9c70cb86dc6e106f3b48c508d2c36083b3c62e38b5cd670b18843", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6180fe9187d709540027a9f4970857ba0534388ced2141f0c1429208ed96764554ab77a4fe5fba64e316e89ee70c6d1702190497418e48b2bdc5a29a3500077", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c47560165439ee0722a56fce4044e4acae47efa423804a47218e02391f6ca6719f8f9ce9316dd6fb15b438e46cc58d7a829ec63bbee2b3689e4ad4723a1bafe8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 22:09:06.819552) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 22:09:06.819863) ========= 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.uQSoY7FOlb/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.BCIWRUpUeQ/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.005159 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 22:09:06.867803) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 22:09:06.868101) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 22:09:06.868417) ========= ========= Total master_lag: 0.22 =========