builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0344 starttime: 1472708854.53 results: success (0) buildid: 20160831193821 builduid: 17965e42d105432ab4821047bceb5fff revision: 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 22:47:34.528682) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 22:47:34.529174) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 22:47:34.529515) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 22:47:34.565651) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 22:47:34.565968) ========= 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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 22:47:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.3M=0.001s 2016-08-31 22:47:35 (19.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.600792 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 22:47:35.195674) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 22:47:35.196006) ========= 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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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.025828 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 22:47:35.243786) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 22:47:35.244090) ========= 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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 22:47:35,328 truncating revision to first 12 chars 2016-08-31 22:47:35,328 Setting DEBUG logging. 2016-08-31 22:47:35,328 attempt 1/10 2016-08-31 22:47:35,328 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 22:47:36,463 unpacking tar archive at: fx-team-9f33ab9fd502/testing/mozharness/ program finished with exit code 0 elapsedTime=1.393256 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 22:47:36.660127) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 22:47:36.660513) ========= script_repo_revision: 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 22:47:36.660930) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 22:47:36.661202) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 22:47:36.679771) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-08-31 22:47:36.680050) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 22:47:36 INFO - MultiFileLogger online at 20160831 22:47:36 in /builds/slave/test 22:47:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:47:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:47:36 INFO - {'append_to_log': False, 22:47:36 INFO - 'base_work_dir': '/builds/slave/test', 22:47:36 INFO - 'blob_upload_branch': 'Fx-Team', 22:47:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:47:36 INFO - 'branch': 'Fx-Team', 22:47:36 INFO - 'buildbot_json_path': 'buildprops.json', 22:47:36 INFO - 'config_files': ('talos/mac_config.py',), 22:47:36 INFO - 'default_actions': ('clobber', 22:47:36 INFO - 'read-buildbot-config', 22:47:36 INFO - 'download-and-extract', 22:47:36 INFO - 'populate-webroot', 22:47:36 INFO - 'create-virtualenv', 22:47:36 INFO - 'install', 22:47:36 INFO - 'run-tests'), 22:47:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:47:36 INFO - 'download_minidump_stackwalk': True, 22:47:36 INFO - 'download_symbols': 'ondemand', 22:47:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:47:36 INFO - 'tooltool.py': '/tools/tooltool.py', 22:47:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:47:36 INFO - '/tools/misc-python/virtualenv.py')}, 22:47:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:47:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:47:36 INFO - 'installer_path': 'installer.exe', 22:47:36 INFO - 'log_level': 'info', 22:47:36 INFO - 'log_name': 'talos', 22:47:36 INFO - 'log_to_console': True, 22:47:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:47:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:47:36 INFO - 'opt_config_files': (), 22:47:36 INFO - 'pip_index': False, 22:47:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:47:36 INFO - 'cmd': ('bash', 22:47:36 INFO - '-c', 22:47:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:47:36 INFO - 'enabled': True, 22:47:36 INFO - 'halt_on_failure': False, 22:47:36 INFO - 'name': 'check_screen_resolution'},), 22:47:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:47:36 INFO - 'cmd': ('bash', 22:47:36 INFO - '-c', 22:47:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:47:36 INFO - 'enabled': True, 22:47:36 INFO - 'halt_on_failure': False, 22:47:36 INFO - 'name': 'check_screen_resolution'},), 22:47:36 INFO - 'run_cmd_checks_enabled': True, 22:47:36 INFO - 'sps_profile': False, 22:47:36 INFO - 'sps_profile_interval': 0, 22:47:36 INFO - 'suite': 'dromaeojs-e10s', 22:47:36 INFO - 'system_bits': '32', 22:47:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:47:36 INFO - 'title': 't-yosemite-r7-0344', 22:47:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:47:36 INFO - 'use_talos_json': True, 22:47:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:47:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:47:36 INFO - 'work_dir': 'build'} 22:47:36 INFO - [mozharness: 2016-09-01 05:47:36.875316Z] Running clobber step. 22:47:36 INFO - Running pre-action listener: _resource_record_pre_action 22:47:36 INFO - Running main action method: clobber 22:47:36 INFO - rmtree: /builds/slave/test/build 22:47:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:47:38 INFO - Running post-action listener: _resource_record_post_action 22:47:38 INFO - [mozharness: 2016-09-01 05:47:38.929895Z] Finished clobber step (success) 22:47:38 INFO - [mozharness: 2016-09-01 05:47:38.930019Z] Running read-buildbot-config step. 22:47:38 INFO - Running pre-action listener: _resource_record_pre_action 22:47:38 INFO - Running main action method: read_buildbot_config 22:47:38 INFO - Using buildbot properties: 22:47:38 INFO - { 22:47:38 INFO - "project": "", 22:47:38 INFO - "product": "firefox", 22:47:38 INFO - "script_repo_revision": "production", 22:47:38 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 22:47:38 INFO - "repository": "", 22:47:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 22:47:38 INFO - "buildid": "20160831193821", 22:47:38 INFO - "pgo_build": "False", 22:47:38 INFO - "basedir": "/builds/slave/test", 22:47:38 INFO - "buildnumber": 105, 22:47:38 INFO - "slavename": "t-yosemite-r7-0344", 22:47:38 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 22:47:38 INFO - "platform": "yosemite_r7", 22:47:38 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 22:47:38 INFO - "branch": "fx-team", 22:47:38 INFO - "repo_path": "integration/fx-team", 22:47:38 INFO - "revision": "9f33ab9fd502d2a4c38aef7744ab68c0aaa78234", 22:47:38 INFO - "stage_platform": "macosx64", 22:47:38 INFO - "builduid": "17965e42d105432ab4821047bceb5fff", 22:47:38 INFO - "slavebuilddir": "test" 22:47:38 INFO - } 22:47:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 22:47:38 INFO - Running post-action listener: _resource_record_post_action 22:47:38 INFO - [mozharness: 2016-09-01 05:47:38.933239Z] Finished read-buildbot-config step (success) 22:47:38 INFO - [mozharness: 2016-09-01 05:47:38.933349Z] Running download-and-extract step. 22:47:38 INFO - Running pre-action listener: _resource_record_pre_action 22:47:38 INFO - Running main action method: download_and_extract 22:47:38 INFO - mkdir: /builds/slave/test/build/tests 22:47:38 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')]} 22:47:38 INFO - trying https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 22:47:38 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 22:47:38 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 22:47:39 INFO - Downloaded 1199 bytes. 22:47:39 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 22:47:39 INFO - Using the following test package requirements: 22:47:39 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:47:39 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:39 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 22:47:39 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:39 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 22:47:39 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:39 INFO - u'jsshell-mac.zip'], 22:47:39 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:39 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 22:47:39 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:47:39 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:39 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 22:47:39 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:39 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 22:47:39 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:39 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 22:47:39 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:39 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 22:47:39 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:39 INFO - trying https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:47:39 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 22:47:39 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 22:47:40 INFO - Downloaded 16400001 bytes. 22:47:40 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 22:47:47 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 22:47: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')]} 22:47:47 INFO - trying https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:47:47 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 22:47:47 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 22:47:48 INFO - Downloaded 16400001 bytes. 22:47:48 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 22:47:55 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')]} 22:47:55 INFO - trying https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 22:47:55 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 22:47:55 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 22:47:56 INFO - Downloaded 14413998 bytes. 22:47:56 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 22:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:56 INFO - trying https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:47:56 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 22:47:56 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 22:47:59 INFO - Downloaded 111597874 bytes. 22:47:59 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 22:47:59 INFO - mkdir: /builds/slave/test/properties 22:47:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:47:59 INFO - Writing to file /builds/slave/test/properties/build_url 22:47:59 INFO - Contents: 22:47:59 INFO - build_url:https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:47:59 INFO - Running post-action listener: _resource_record_post_action 22:47:59 INFO - Running post-action listener: set_extra_try_arguments 22:47:59 INFO - [mozharness: 2016-09-01 05:47:59.911845Z] Finished download-and-extract step (success) 22:47:59 INFO - [mozharness: 2016-09-01 05:47:59.911986Z] Running populate-webroot step. 22:47:59 INFO - Running pre-action listener: _resource_record_pre_action 22:47:59 INFO - Running main action method: populate_webroot 22:47:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:47:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:47:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:47:59 INFO - '--tppagecycles', 22:47:59 INFO - '7'], 22:47:59 INFO - 'tests': ['tsvgm']}}, 22:47:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:47:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:47:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:47:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:47:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:47:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:47:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:59 INFO - 'talos_options': ['--disable-e10s'], 22:47:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:47:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:47:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:59 INFO - 'talos_options': ['--disable-e10s'], 22:47:59 INFO - 'tests': ['damp', 'tps']}, 22:47:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:59 INFO - 'tests': ['damp', 'tps']}, 22:47:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:47:59 INFO - 'tests': ['dromaeo_dom']}, 22:47:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:47:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:47:59 INFO - 'tests': ['basic_compositor_video']}, 22:47:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:47:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:47:59 INFO - 'tests': ['a11yr', 22:47:59 INFO - 'ts_paint', 22:47:59 INFO - 'tpaint', 22:47:59 INFO - 'sessionrestore', 22:47:59 INFO - 'sessionrestore_no_auto_restore', 22:47:59 INFO - 'tabpaint']}, 22:47:59 INFO - 'other-e10s': {'tests': ['a11yr', 22:47:59 INFO - 'ts_paint', 22:47:59 INFO - 'tpaint', 22:47:59 INFO - 'sessionrestore', 22:47:59 INFO - 'sessionrestore_no_auto_restore', 22:47:59 INFO - 'tabpaint']}, 22:47:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:47:59 INFO - 'ts_paint', 22:47:59 INFO - 'tpaint', 22:47:59 INFO - 'sessionrestore', 22:47:59 INFO - 'sessionrestore_no_auto_restore', 22:47:59 INFO - 'tabpaint']}, 22:47:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:47:59 INFO - 'ts_paint', 22:47:59 INFO - 'tpaint', 22:47:59 INFO - 'sessionrestore', 22:47:59 INFO - 'sessionrestore_no_auto_restore', 22:47:59 INFO - 'tabpaint']}, 22:47:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:47:59 INFO - 'tests': ['a11yr', 22:47:59 INFO - 'ts_paint', 22:47:59 INFO - 'tpaint', 22:47:59 INFO - 'sessionrestore', 22:47:59 INFO - 'sessionrestore_no_auto_restore', 22:47:59 INFO - 'tabpaint']}, 22:47:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:47:59 INFO - 'tests': ['a11yr', 22:47:59 INFO - 'ts_paint', 22:47:59 INFO - 'tpaint', 22:47:59 INFO - 'sessionrestore', 22:47:59 INFO - 'sessionrestore_no_auto_restore', 22:47:59 INFO - 'tabpaint']}, 22:47:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:47:59 INFO - 'tests': ['tsvgx', 22:47:59 INFO - 'tsvgr_opacity', 22:47:59 INFO - 'tart', 22:47:59 INFO - 'tscrollx', 22:47:59 INFO - 'cart']}, 22:47:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:47:59 INFO - 'tsvgr_opacity', 22:47:59 INFO - 'tart', 22:47:59 INFO - 'tscrollx', 22:47:59 INFO - 'cart']}, 22:47:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:59 INFO - 'talos_options': ['--disable-e10s'], 22:47:59 INFO - 'tests': ['tp5o']}, 22:47:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:59 INFO - 'tests': ['tp5o']}, 22:47:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:59 INFO - 'talos_options': ['--disable-e10s', 22:47:59 INFO - '--xperf_path', 22:47:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:47:59 INFO - 'tests': ['tp5n']}, 22:47:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:59 INFO - 'talos_options': ['--xperf_path', 22:47:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:47:59 INFO - 'tests': ['tp5n']}}, 22:47:59 INFO - 'talos.zip': {'path': '', 22:47:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:47:59 INFO - Running post-action listener: _resource_record_post_action 22:47:59 INFO - [mozharness: 2016-09-01 05:47:59.930832Z] Finished populate-webroot step (success) 22:47:59 INFO - [mozharness: 2016-09-01 05:47:59.930939Z] Running create-virtualenv step. 22:47:59 INFO - Running pre-action listener: _resource_record_pre_action 22:47:59 INFO - Running main action method: create_virtualenv 22:47:59 INFO - Creating virtualenv /builds/slave/test/build/venv 22:47:59 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 22:47:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:48:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:48:00 INFO - Using real prefix '/tools/python27' 22:48:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:48:00 INFO - Installing distribute.............................................................................................................................................................................................done. 22:48:03 INFO - Installing pip.................done. 22:48:03 INFO - Return code: 0 22:48:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:48:03 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')]} 22:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:03 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')]} 22:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:03 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 0x104deda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e82618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96535e3900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d51d30>, '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 0x104ab6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d49b20>, '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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 22:48:03 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 22:48:03 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 22:48:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tK7QyIqn8h/Render', 22:48:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:03 INFO - 'HOME': '/Users/cltbld', 22:48:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:03 INFO - 'LOGNAME': 'cltbld', 22:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:03 INFO - 'MOZ_NO_REMOTE': '1', 22:48:03 INFO - 'NO_EM_RESTART': '1', 22:48:03 INFO - 'PAGER': '/bin/cat', 22:48:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:03 INFO - 'PWD': '/builds/slave/test', 22:48:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:03 INFO - 'SHELL': '/bin/bash', 22:48:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEf30HY8Bu/Listeners', 22:48:03 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 22:48:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:03 INFO - 'USER': 'cltbld', 22:48:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:03 INFO - 'XPC_FLAGS': '0x0', 22:48:03 INFO - 'XPC_SERVICE_NAME': '0', 22:48:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:03 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 22:48:03 INFO - Cleaning up... 22:48:03 INFO - Return code: 0 22:48:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:48:03 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')]} 22:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:03 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')]} 22:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:03 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 0x104deda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e82618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96535e3900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d51d30>, '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 0x104ab6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d49b20>, '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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 22:48:03 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 22:48:03 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 22:48:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tK7QyIqn8h/Render', 22:48:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:03 INFO - 'HOME': '/Users/cltbld', 22:48:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:03 INFO - 'LOGNAME': 'cltbld', 22:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:03 INFO - 'MOZ_NO_REMOTE': '1', 22:48:03 INFO - 'NO_EM_RESTART': '1', 22:48:03 INFO - 'PAGER': '/bin/cat', 22:48:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:03 INFO - 'PWD': '/builds/slave/test', 22:48:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:03 INFO - 'SHELL': '/bin/bash', 22:48:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEf30HY8Bu/Listeners', 22:48:03 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 22:48:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:03 INFO - 'USER': 'cltbld', 22:48:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:03 INFO - 'XPC_FLAGS': '0x0', 22:48:03 INFO - 'XPC_SERVICE_NAME': '0', 22:48:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:04 INFO - Downloading/unpacking psutil>=3.1.1 22:48:04 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 22:48:04 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 22:48:04 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 22:48:04 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 22:48:04 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 22:48:04 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 22:48:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:48:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:48:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:48:07 INFO - Installing collected packages: psutil 22:48:07 INFO - Running setup.py install for psutil 22:48:07 INFO - building 'psutil._psutil_osx' extension 22:48:07 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 22:48:08 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 22:48:08 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 22:48:08 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 22:48:08 INFO - building 'psutil._psutil_posix' extension 22:48:08 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 22:48:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:48:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:48:08 INFO - ^ 22:48:08 INFO - 1 warning generated. 22:48:08 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 22:48:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:48:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:48:08 INFO - Successfully installed psutil 22:48:08 INFO - Cleaning up... 22:48:08 INFO - Return code: 0 22:48:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:08 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 0x104deda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e82618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96535e3900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d51d30>, '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 0x104ab6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d49b20>, '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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 22:48:08 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 22:48:08 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 22:48:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tK7QyIqn8h/Render', 22:48:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:08 INFO - 'HOME': '/Users/cltbld', 22:48:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:08 INFO - 'LOGNAME': 'cltbld', 22:48:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:08 INFO - 'MOZ_NO_REMOTE': '1', 22:48:08 INFO - 'NO_EM_RESTART': '1', 22:48:08 INFO - 'PAGER': '/bin/cat', 22:48:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:08 INFO - 'PWD': '/builds/slave/test', 22:48:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:08 INFO - 'SHELL': '/bin/bash', 22:48:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEf30HY8Bu/Listeners', 22:48:08 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 22:48:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:08 INFO - 'USER': 'cltbld', 22:48:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:08 INFO - 'XPC_FLAGS': '0x0', 22:48:08 INFO - 'XPC_SERVICE_NAME': '0', 22:48:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:48:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:48:11 INFO - Installing collected packages: mozsystemmonitor 22:48:11 INFO - Running setup.py install for mozsystemmonitor 22:48:11 INFO - Successfully installed mozsystemmonitor 22:48:11 INFO - Cleaning up... 22:48:11 INFO - Return code: 0 22:48:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:11 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 0x104deda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e82618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96535e3900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d51d30>, '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 0x104ab6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d49b20>, '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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 22:48:11 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 22:48:11 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 22:48:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tK7QyIqn8h/Render', 22:48:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:11 INFO - 'HOME': '/Users/cltbld', 22:48:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:11 INFO - 'LOGNAME': 'cltbld', 22:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:11 INFO - 'MOZ_NO_REMOTE': '1', 22:48:11 INFO - 'NO_EM_RESTART': '1', 22:48:11 INFO - 'PAGER': '/bin/cat', 22:48:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:11 INFO - 'PWD': '/builds/slave/test', 22:48:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:11 INFO - 'SHELL': '/bin/bash', 22:48:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEf30HY8Bu/Listeners', 22:48:11 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 22:48:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:11 INFO - 'USER': 'cltbld', 22:48:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:11 INFO - 'XPC_FLAGS': '0x0', 22:48:11 INFO - 'XPC_SERVICE_NAME': '0', 22:48:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:12 INFO - Downloading/unpacking jsonschema==2.5.1 22:48:12 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 22:48:12 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 22:48:12 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 22:48:12 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 22:48:12 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 22:48:12 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 22:48:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:48:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:48:14 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 22:48:14 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 22:48:14 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 22:48:14 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 22:48:14 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 22:48:14 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 22:48:14 INFO - Downloading functools32-3.2.3-2.tar.gz 22:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:48:14 INFO - warning: no files found matching '*.txt' 22:48:14 INFO - no previously-included directories found matching 'build' 22:48:14 INFO - no previously-included directories found matching 'dist' 22:48:14 INFO - no previously-included directories found matching '.git*' 22:48:14 INFO - Installing collected packages: jsonschema, functools32 22:48:15 INFO - Running setup.py install for functools32 22:48:15 INFO - warning: no files found matching '*.txt' 22:48:15 INFO - no previously-included directories found matching 'build' 22:48:15 INFO - no previously-included directories found matching 'dist' 22:48:15 INFO - no previously-included directories found matching '.git*' 22:48:15 INFO - Successfully installed jsonschema functools32 22:48:15 INFO - Cleaning up... 22:48:15 INFO - Return code: 0 22:48:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:48:15 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')]} 22:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:15 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')]} 22:48:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:15 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 0x104deda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e82618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96535e3900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d51d30>, '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 0x104ab6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d49b20>, '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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 22:48:15 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 22:48:15 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 22:48:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tK7QyIqn8h/Render', 22:48:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:15 INFO - 'HOME': '/Users/cltbld', 22:48:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:15 INFO - 'LOGNAME': 'cltbld', 22:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:15 INFO - 'MOZ_NO_REMOTE': '1', 22:48:15 INFO - 'NO_EM_RESTART': '1', 22:48:15 INFO - 'PAGER': '/bin/cat', 22:48:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:15 INFO - 'PWD': '/builds/slave/test', 22:48:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:15 INFO - 'SHELL': '/bin/bash', 22:48:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEf30HY8Bu/Listeners', 22:48:15 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 22:48:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:15 INFO - 'USER': 'cltbld', 22:48:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:15 INFO - 'XPC_FLAGS': '0x0', 22:48:15 INFO - 'XPC_SERVICE_NAME': '0', 22:48:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:48:15 INFO - Cleaning up... 22:48:15 INFO - Return code: 0 22:48:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:48:15 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')]} 22:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:15 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')]} 22:48:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:15 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 0x104deda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e82618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96535e3900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d51d30>, '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 0x104ab6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d49b20>, '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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 22:48:15 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 22:48:15 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 22:48:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tK7QyIqn8h/Render', 22:48:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:15 INFO - 'HOME': '/Users/cltbld', 22:48:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:15 INFO - 'LOGNAME': 'cltbld', 22:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:15 INFO - 'MOZ_NO_REMOTE': '1', 22:48:15 INFO - 'NO_EM_RESTART': '1', 22:48:15 INFO - 'PAGER': '/bin/cat', 22:48:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:15 INFO - 'PWD': '/builds/slave/test', 22:48:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:15 INFO - 'SHELL': '/bin/bash', 22:48:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEf30HY8Bu/Listeners', 22:48:15 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 22:48:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:15 INFO - 'USER': 'cltbld', 22:48:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:15 INFO - 'XPC_FLAGS': '0x0', 22:48:15 INFO - 'XPC_SERVICE_NAME': '0', 22:48:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:15 INFO - Downloading/unpacking blobuploader==1.2.4 22:48:15 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 22:48:15 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 22:48:15 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 22:48:15 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 22:48:15 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 22:48:15 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 22:48:18 INFO - Downloading blobuploader-1.2.4.tar.gz 22:48:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:48:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:48:18 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 22:48:18 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 22:48:18 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 22:48:18 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 22:48:18 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 22:48:18 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 22:48:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:48:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:48:18 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 22:48:18 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 22:48:18 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 22:48:18 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 22:48:18 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 22:48:18 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 22:48:19 INFO - Downloading docopt-0.6.1.tar.gz 22:48:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:48:19 INFO - Installing collected packages: blobuploader, requests, docopt 22:48:19 INFO - Running setup.py install for blobuploader 22:48:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:48:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:48:19 INFO - Running setup.py install for requests 22:48:20 INFO - Running setup.py install for docopt 22:48:20 INFO - Successfully installed blobuploader requests docopt 22:48:20 INFO - Cleaning up... 22:48:20 INFO - Return code: 0 22:48:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22: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')]} 22:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22: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')]} 22:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:20 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 0x104deda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e82618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96535e3900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d51d30>, '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 0x104ab6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d49b20>, '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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 22:48:20 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 22:48:20 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 22:48:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tK7QyIqn8h/Render', 22:48:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:20 INFO - 'HOME': '/Users/cltbld', 22:48:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:20 INFO - 'LOGNAME': 'cltbld', 22:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:20 INFO - 'MOZ_NO_REMOTE': '1', 22:48:20 INFO - 'NO_EM_RESTART': '1', 22:48:20 INFO - 'PAGER': '/bin/cat', 22:48:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:20 INFO - 'PWD': '/builds/slave/test', 22:48:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:20 INFO - 'SHELL': '/bin/bash', 22:48:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEf30HY8Bu/Listeners', 22:48:20 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 22:48:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:20 INFO - 'USER': 'cltbld', 22:48:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:20 INFO - 'XPC_FLAGS': '0x0', 22:48:20 INFO - 'XPC_SERVICE_NAME': '0', 22:48:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:48:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-aLkU2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:48:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-OnA4on-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:48:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-Hoyb2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:48:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-vyb1vg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:48:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-8vhijp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:48:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-0NzCvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:48:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-w7WrC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:48:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-VM0Ske-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:48:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-Bx2Xmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:48:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-FNzQ1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:48:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-1kLcDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:48:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-zvekHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:48:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-Gj_QyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:48:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-PkcTRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:22 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-Kq1KiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:48:22 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-RvPcS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:48:22 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-yAPbQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:48:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:48:22 INFO - Running setup.py install for manifestparser 22:48:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:48:22 INFO - Running setup.py install for mozcrash 22:48:22 INFO - Running setup.py install for mozdebug 22:48:22 INFO - Running setup.py install for mozdevice 22:48:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:48:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:48:22 INFO - Running setup.py install for mozfile 22:48:23 INFO - Running setup.py install for mozhttpd 22:48:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:48:23 INFO - Running setup.py install for mozinfo 22:48:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:48:23 INFO - Running setup.py install for mozInstall 22:48:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:48:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:48:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:48:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:48:23 INFO - Running setup.py install for mozleak 22:48:23 INFO - Running setup.py install for mozlog 22:48:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:48:23 INFO - Running setup.py install for moznetwork 22:48:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:48:23 INFO - Running setup.py install for mozprocess 22:48:24 INFO - Running setup.py install for mozprofile 22:48:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:48:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:48:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:48:24 INFO - Running setup.py install for mozrunner 22:48:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:48:24 INFO - Running setup.py install for mozscreenshot 22:48:24 INFO - Running setup.py install for moztest 22:48:24 INFO - Running setup.py install for mozversion 22:48:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:48:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:48:24 INFO - Cleaning up... 22:48:24 INFO - Return code: 0 22:48:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:24 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 0x104deda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e82618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96535e3900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d51d30>, '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 0x104ab6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d49b20>, '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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 22:48:24 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 22:48:24 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 22:48:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tK7QyIqn8h/Render', 22:48:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:24 INFO - 'HOME': '/Users/cltbld', 22:48:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:24 INFO - 'LOGNAME': 'cltbld', 22:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:24 INFO - 'MOZ_NO_REMOTE': '1', 22:48:24 INFO - 'NO_EM_RESTART': '1', 22:48:24 INFO - 'PAGER': '/bin/cat', 22:48:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:24 INFO - 'PWD': '/builds/slave/test', 22:48:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:24 INFO - 'SHELL': '/bin/bash', 22:48:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEf30HY8Bu/Listeners', 22:48:24 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 22:48:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:24 INFO - 'USER': 'cltbld', 22:48:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:24 INFO - 'XPC_FLAGS': '0x0', 22:48:24 INFO - 'XPC_SERVICE_NAME': '0', 22:48:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:48:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-GbDn1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:48:25 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)) 22:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:48:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-UQ6kZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:48:25 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)) 22:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:48:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-uRLu0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:48:25 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)) 22:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:48:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-tn1ldu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:48:25 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)) 22:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:48:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-zy6Lao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:48:25 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)) 22:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:48:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-3rwln3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:48:25 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)) 22:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:48:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-j1ci2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:48:25 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)) 22:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:48:25 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-qDe6cD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:48:26 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:48:26 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-DjhsHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:48:26 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:48:26 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-wrfu97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:48:26 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:48:26 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-HD0P4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:48:26 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:48:26 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-geq6Lj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:48:26 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:48:26 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-pc7khn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:48:26 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:48:26 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-hrmqJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:48:26 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:26 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-gBJIZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:26 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:48:26 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-j0V4rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:48:26 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:48:26 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-MQxKiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:48:26 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)) 22:48:26 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)) 22:48:26 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)) 22:48:26 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)) 22:48:26 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)) 22:48:26 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)) 22:48:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:29 INFO - Downloading blessings-1.6.tar.gz 22:48:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:48:29 INFO - Installing collected packages: blessings 22:48:29 INFO - Running setup.py install for blessings 22:48:29 INFO - Successfully installed blessings 22:48:29 INFO - Cleaning up... 22:48:29 INFO - Return code: 0 22:48:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:48:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:48:30 INFO - Reading from file tmpfile_stdout 22:48:30 INFO - Current package versions: 22:48:30 INFO - blessings == 1.6 22:48:30 INFO - blobuploader == 1.2.4 22:48:30 INFO - docopt == 0.6.1 22:48:30 INFO - functools32 == 3.2.3-2 22:48:30 INFO - jsonschema == 2.5.1 22:48:30 INFO - manifestparser == 1.1 22:48:30 INFO - mozInstall == 1.12 22:48:30 INFO - mozcrash == 0.17 22:48:30 INFO - mozdebug == 0.1 22:48:30 INFO - mozdevice == 0.48 22:48:30 INFO - mozfile == 1.2 22:48:30 INFO - mozhttpd == 0.7 22:48:30 INFO - mozinfo == 0.9 22:48:30 INFO - mozleak == 0.1 22:48:30 INFO - mozlog == 3.3 22:48:30 INFO - moznetwork == 0.27 22:48:30 INFO - mozprocess == 0.23 22:48:30 INFO - mozprofile == 0.28 22:48:30 INFO - mozrunner == 6.12 22:48:30 INFO - mozscreenshot == 0.1 22:48:30 INFO - mozsystemmonitor == 0.3 22:48:30 INFO - moztest == 0.7 22:48:30 INFO - mozversion == 1.4 22:48:30 INFO - psutil == 3.1.1 22:48:30 INFO - requests == 1.2.3 22:48:30 INFO - wsgiref == 0.1.2 22:48:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:30 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 0x104deda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e82618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96535e3900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d51d30>, '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 0x104ab6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d49b20>, '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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 22:48:30 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 22:48:30 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 22:48:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tK7QyIqn8h/Render', 22:48:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:30 INFO - 'HOME': '/Users/cltbld', 22:48:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:30 INFO - 'LOGNAME': 'cltbld', 22:48:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:30 INFO - 'MOZ_NO_REMOTE': '1', 22:48:30 INFO - 'NO_EM_RESTART': '1', 22:48:30 INFO - 'PAGER': '/bin/cat', 22:48:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:30 INFO - 'PWD': '/builds/slave/test', 22:48:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:30 INFO - 'SHELL': '/bin/bash', 22:48:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEf30HY8Bu/Listeners', 22:48:30 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 22:48:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:30 INFO - 'USER': 'cltbld', 22:48:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:30 INFO - 'XPC_FLAGS': '0x0', 22:48:30 INFO - 'XPC_SERVICE_NAME': '0', 22:48:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:30 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)) 22:48:30 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)) 22:48:30 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)) 22:48:30 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)) 22:48:30 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)) 22:48:30 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)) 22:48:30 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)) 22:48:30 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)) 22:48:30 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)) 22:48:30 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)) 22:48:30 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)) 22:48:30 INFO - Cleaning up... 22:48:30 INFO - Return code: 0 22:48:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104deda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e82618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96535e3900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d51d30>, '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 0x104ab6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d49b20>, '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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 22:48:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:48:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:48:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tK7QyIqn8h/Render', 22:48:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:30 INFO - 'HOME': '/Users/cltbld', 22:48:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:30 INFO - 'LOGNAME': 'cltbld', 22:48:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:30 INFO - 'MOZ_NO_REMOTE': '1', 22:48:30 INFO - 'NO_EM_RESTART': '1', 22:48:30 INFO - 'PAGER': '/bin/cat', 22:48:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:30 INFO - 'PWD': '/builds/slave/test', 22:48:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:30 INFO - 'SHELL': '/bin/bash', 22:48:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEf30HY8Bu/Listeners', 22:48:30 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 22:48:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:30 INFO - 'USER': 'cltbld', 22:48:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:30 INFO - 'XPC_FLAGS': '0x0', 22:48:30 INFO - 'XPC_SERVICE_NAME': '0', 22:48:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:48:30 INFO - Cleaning up... 22:48:30 INFO - Return code: 0 22:48:30 INFO - Running post-action listener: _resource_record_post_action 22:48:30 INFO - Running post-action listener: _start_resource_monitoring 22:48:30 INFO - Starting resource monitoring. 22:48:30 INFO - [mozharness: 2016-09-01 05:48:30.593402Z] Finished create-virtualenv step (success) 22:48:30 INFO - [mozharness: 2016-09-01 05:48:30.594382Z] Running install step. 22:48:30 INFO - Running pre-action listener: _resource_record_pre_action 22:48:30 INFO - Running main action method: install 22:48:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:48:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:48:30 INFO - Reading from file tmpfile_stdout 22:48:30 INFO - Detecting whether we're running mozinstall >=1.0... 22:48:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:48:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:48:30 INFO - Reading from file tmpfile_stdout 22:48:30 INFO - Output received: 22:48:30 INFO - Usage: mozinstall [options] installer 22:48:30 INFO - Options: 22:48:30 INFO - -h, --help show this help message and exit 22:48:30 INFO - -d DEST, --destination=DEST 22:48:30 INFO - Directory to install application into. [default: 22:48:30 INFO - "/builds/slave/test"] 22:48:30 INFO - --app=APP Application being installed. [default: firefox] 22:48:30 INFO - mkdir: /builds/slave/test/build/application 22:48:30 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'] 22:48:30 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 22:49:05 INFO - Reading from file tmpfile_stdout 22:49:05 INFO - Output received: 22:49:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:49:05 INFO - Running post-action listener: _resource_record_post_action 22:49:05 INFO - [mozharness: 2016-09-01 05:49:05.008796Z] Finished install step (success) 22:49:05 INFO - [mozharness: 2016-09-01 05:49:05.008927Z] Running run-tests step. 22:49:05 INFO - Running pre-action listener: _resource_record_pre_action 22:49:05 INFO - Running main action method: run_tests 22:49:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:49:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:49:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:49:05 INFO - 2016-08-31 22:49:05.022 screenresolution[1637:8248] starting screenresolution argv=screenresolution get 22:49:05 INFO - 2016-08-31 22:49:05.025 screenresolution[1637:8248] Display 0: 1600x1200x32@60 22:49:05 INFO - 2016-08-31 22:49:05.030 screenresolution[1638:8250] starting screenresolution argv=screenresolution list 22:49:05 INFO - Available Modes on Display 0 22:49:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:49:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:49:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:49:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:49:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:49:05 INFO - Intel Iris: 22:49:05 INFO - Chipset Model: Intel Iris 22:49:05 INFO - Type: GPU 22:49:05 INFO - Bus: Built-In 22:49:05 INFO - VRAM (Dynamic, Max): 1536 MB 22:49:05 INFO - Vendor: Intel (0x8086) 22:49:05 INFO - Device ID: 0x0a2e 22:49:05 INFO - Revision ID: 0x0009 22:49:05 INFO - Displays: 22:49:05 INFO - Display: 22:49:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:49:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:49:05 INFO - Display Serial Number: 1600x1200 60 22:49:05 INFO - Main Display: Yes 22:49:05 INFO - Mirror: Off 22:49:05 INFO - Online: Yes 22:49:05 INFO - Rotation: Supported 22:49:05 INFO - Return code: 0 22:49:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:49:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:49:05 INFO - Python 2.7.3 22:49:05 INFO - Return code: 0 22:49:05 INFO - grabbing minidump binary from tooltool 22:49:05 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')]} 22:49:05 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 0x104d51d30>, '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 0x104ab6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d49b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:49:05 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 22:49:05 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 22:49:05 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 22:49:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:49:05 INFO - Return code: 0 22:49:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:49:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:49:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:49:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:49:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:49:05 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-e10s', '--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-0344', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:49:05 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-e10s --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-0344 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:49:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tK7QyIqn8h/Render', 22:49:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:05 INFO - 'HOME': '/Users/cltbld', 22:49:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:05 INFO - 'LOGNAME': 'cltbld', 22:49:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:49:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:05 INFO - 'MOZ_NO_REMOTE': '1', 22:49:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:49:05 INFO - 'NO_EM_RESTART': '1', 22:49:05 INFO - 'PAGER': '/bin/cat', 22:49:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:05 INFO - 'PWD': '/builds/slave/test', 22:49:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:49:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:05 INFO - 'SHELL': '/bin/bash', 22:49:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KEf30HY8Bu/Listeners', 22:49:05 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 22:49:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:05 INFO - 'USER': 'cltbld', 22:49:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:05 INFO - 'XPC_FLAGS': '0x0', 22:49:05 INFO - 'XPC_SERVICE_NAME': '0', 22:49:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--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-0344', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:49:05 INFO - mozversion application_buildid: 20160831193821 22:49:05 INFO - mozversion application_changeset: 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 22:49:05 INFO - mozversion application_display_name: Nightly 22:49:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:49:05 INFO - mozversion application_name: Firefox 22:49:05 INFO - mozversion application_remotingname: firefox 22:49:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:49:05 INFO - mozversion application_vendor: Mozilla 22:49:05 INFO - mozversion application_version: 51.0a1 22:49:05 INFO - mozversion platform_buildid: 20160831193821 22:49:05 INFO - mozversion platform_changeset: 9f33ab9fd502d2a4c38aef7744ab68c0aaa78234 22:49:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:49:05 INFO - mozversion platform_version: 51.0a1 22:49:05 INFO - using testdate: 1472708945 22:49:05 INFO - actual date: 1472708945 22:49:05 INFO - starting webserver on 'localhost:49268' 22:49:05 INFO - SUITE-START | Running 2 tests 22:49:05 INFO - TEST-START | dromaeo_css 22:49:05 INFO - operating with platform_type : mac_ 22:49:05 INFO - Initialising browser for dromaeo_css test... 22:49:05 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmp7qzmuZ/profile http://localhost:49268/getInfo.html) 22:49:08 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 22:49:08 INFO - PROCESS | 1648 | colorDepth:24 22:49:08 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 22:49:08 INFO - PROCESS | 1648 | __metrics 22:49:08 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 22:49:09 INFO - TEST-INFO | 1648: exit 0 22:49:09 INFO - Browser initialized. 22:49:09 INFO - Running cycle 1/1 for dromaeo_css test... 22:49:09 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmp7qzmuZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:49:21 INFO - PROCESS | 1652 | RSS: Main: 245194752 22:49:21 INFO - PROCESS | 1652 | 22:50:10 INFO - PROCESS | 1652 | 1472709010889 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] 22:52:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 22:52:24 INFO - PROCESS | 1652 | RSS: Main: 268718080 22:52:24 INFO - PROCESS | 1652 | 22:55:27 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 22:55:27 INFO - PROCESS | 1652 | RSS: Main: 276905984 22:55:27 INFO - PROCESS | 1652 | 22:58:29 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 22:58:30 INFO - PROCESS | 1652 | RSS: Main: 279232512 22:58:30 INFO - PROCESS | 1652 | 23:01:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 23:01:32 INFO - PROCESS | 1652 | RSS: Main: 273309696 23:01:32 INFO - PROCESS | 1652 | 23:04:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 23:04:36 INFO - PROCESS | 1652 | RSS: Main: 278708224 23:04:36 INFO - PROCESS | 1652 | 23:07:38 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 23:07:38 INFO - PROCESS | 1652 | RSS: Main: 281563136 23:07:39 INFO - PROCESS | 1652 | 23:07:39 INFO - PROCESS | 1652 | __start_tp_report 23:07:39 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 23:07:39 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 23:07:39 INFO - PROCESS | 1652 | |i|pagename|runs| 23:07:39 INFO - PROCESS | 1652 | |0;dojo.html;3160.462721337373;3336.4161271777434;3396.337714145741;3408.028711817131;3417.8974630761086;5534.059209934311;5600.467955544217;5608;5628.409017053202;5759.971200143993;5575.8606034849145;5654.802081927133;5690.345610254822;5698.803251317228;5742.6841523716175;5096.133657278272;5178.637495375326;5182.714950677711;5232.2674825524455;5301.8939621207555;5297.099493086165;5313.335833020872;5344.465553444644;5352.170413585901;5374.516293533581;3724.553053633554;5109.923351149736;5110.897782044375;5209.218617207412;5210.400803907523;4866.489018653026;5229.032628963654;5230.293910322096;5230.424653288135;5279.472052794728;4208.452901122834;4723.36234608327;5203.869903252411;5220.712860792655;5229.189475631283;4595.908081838378;4783.3064205690325;4784.40194975628;5107.668001579886;5714.828555143311;4627.375304333939;5892.587518873681;6146.29317628474;6188.47397971173;6212.943799554087;6241.687915604201;6253.405926436982;6253.78111766086;6269.404406581368;6307.621542707452;5725.341585717612;5747.770089196469;5781.566382521328;5787.826365209051;5846.1523079153285;6257.561970662101;6273.560850740404;6277.748890044439;6281;6313.179286692701;5146.768395422215;5997.640141591519;6131.0803379492545;6146.4775494082605;6149.815505534841;6144.354842741537;6177.104319873593;6205.906911396332;6244.406781355764;6260.311365749764;6105.975331700744;6199.101130336076;6219.129321894939;6231.4391704746795;6236.095766113888;5080.237964305384;6191.380861913863;6261.686915654199;6298.685065746786;6307.747690092437;4845.176320025534;4880.219164933665;4913.557779799835;4951.851444456672;4954.925676114861;4391.429114215131;4402.075564131568;4409.074094440203;4425.911481770347;4456.309272062836;5417.864553386118;5881.529477641779;5883.0881213411985;5887.852803679943;5929.436703513159;6142.191841058483;6151.108089327023;6192.473639740672;6195.57016733029;6290.622562646256;5786.3056433228285;5789.9710501447225;5802.796902108406;5803.158542011384;5832.154337621021;5406.432324605938;5408.8377348679605;5424.7016414096815;5430.592705547021;5432.809851655173;5929.762809487572;6188.412100850322;6261.092141639346;6264.780732674334;6308.779192728232;6261.060840873905;6268.153799237045;6268.216472940882;6279.1209230708655;6283.403076707706;6292.465140463112;6333.841653958688;6351.460125889351;6361.522885783677;6367.777127800505;4836.588889944253;4954.479779623088;5892.5285977122585;5991.520678345635;6098.878022439615;6214.627122372672;6252.093446450148;6256.937430625636;6273.153124328157;6275.905861412174;5904.132297033086;6010.308814486215;6020.488258497901;6044.184035155374;6070.150178974859;5846.386129456346;5919.644821310735;5934.43622855828;6007.519398448027;6015.72929218195;4830.7101573905675;5602.691851948182;5804.158397032322;5817.534597232296;5821.330546986981;5925.525957923442;5940.405959404025;5971.790987315597;5987.64074155552;5999.100134979788;5710.8001219957105;5805.274340707411;5831.0378787499585;5844.415558444121;5850.795222167204 23:07:39 INFO - PROCESS | 1652 | |1;ext.html;519.1304564853868;519.9428062913081;520.3937412913956;539.3393093460511;567.7331654122561;37523;39909.00227494314;39922.40116398249;40084.79957600215;40128.1974360513;40768;41220.96947576312;41285.17429651405;41315;41316.79341603288;9461.763455913606;9601.615935362577;9621.95189024054;9649.469279189641;9652.372595781288;9505.239580833517;9588.664396746115;9602.135807777298;9658.468784216866;9678.080582344666;9566.091221333996;9597.280203984694;9664.80670386592;9704.41773493589;9710.854337184912;9611.134997850191;9653.276004299636;9681.128698417177;9683.225341972626;9717.12545870875;9478.526073696288;9567;9626.75933101671;9626.855597165979;9638.084381983701;39204.60795392067;39306.21387572261;39672.20655586901;39707.40438893419;39708.8232941364;3314.0886256279473;3361.1765117546356;3388.288459423524;3402.931941361159;3779.5653499847595;30604;31058.84470577655;31235.531467028013;31279.687203127905;31883.362332753448;3021.365513242222;3037.8481075946115;3058.9082327529986;3099.318150006995;3147.213196700825;333292;367733;367973.1601342003;368119;368831;33167.83912563049;34024.659753402644;34060.48909266363;48008.27987580189;58828;27997.720022799513;28954.13137605875;30237;35008.12479688028;35222;14500.709985800437;14641.487547935983;14645.26773661334;14657;14658.780118298235;12312.199707019015;12385.1949623276;12415.379231038412;12464.501419943283;12566.748665026647;6432.807015789534;7635.389168866477;7792.493487923267;7869.213078692085;7873.842523149505;3201.839908004637;3250.902472925816;3252.3983063133396;3259.462188738879;3277.3117645294756;927.4110939553432;975.5414954971152;992.6029588164647;1054.6466933577185;1070.7483741320784;30158.698413016027;30246.54630180549;30557.847210763804;30896.69103308983;31001;996.2876543271595;1001.529281237817;1031.7059638003134;1113.4265853085667;1120.2326406411635;13717.931410343084;15827.841721582638;15902.840971590367;15929.840701592835;16061.919690401473;622754;629283.1215063556;637615;638228;641919;57022.42977570254;57098.57253568694;57682.67379946521;57783;57851.132233016535;1338.2305174524492;1352.9864701353058;1366.781314989597;1380.3167432121165;1382.6059573021842;24463.75536244686;29421.705782941895;29691.703082969758;30197.245068873453;30634.08097757071;49330.75334623304;49856.501434985184;50129.74935125447;50216;50247.748761255956;576120;576878;583789;584821;589002;26518.204453866416;26552.601710973966;26571.734282656926;26669.7333026675;26839.060632877754;46073.53926460692;46793.83015424642;47040.52959470498;47203.29195062145;47968;38559;38686.613133869425;38965.232822614686;39857.80071099626;39903.00242493961;38379.424308634836;38589.80705096456;38736.64421745338;39257;39626.60373396229;37007.259854802214;37487.62512374841;37710;37982.620173797906;38433;33031.17422064468;33287.668493260964;34482.482762758074;34653;35149.94550163499;25584;25695.357616059748;25778.871105644354;25844.612330815427;26209.73790262149 23:07:39 INFO - PROCESS | 1652 | |2;jquery.html;373.98691045813405;380.6250842919724;385.1584288329999;389.32452195440925;406.6705968165786;19905.104270307835;21299.893500532475;21695.674564881538;21741.064956230923;22134.003969821355;20645.793542064574;21200.893995530038;21227.832469214227;21527;21527.892360538175;5348.4249824227845;5671.262735844355;6130.141780150782;6171.660558669271;6206.851732429515;4195.7063005589625;6101.969490152543;6138.539609529281;6527.738890444377;6807.591544507321;6863.279355667658;6867.759628413007;6877.346652068046;6882.03651488792;6902.03371527984;4990.950090499103;6174.043023331391;6616.669166541702;6620.23867255267;6621.304762999888;5602.1036634138745;5782;5804.564657650651;5821.214136091616;5841.503472204868;13027.413766380536;13319.200847949154;21271.8936405317;23226.3032109037;26239.344016399744;1828.515443407498;2211.2150186683657;2393.8803059847114;2681.4368982513697;2737.137801592492;9586.856197157049;9723.173530249936;9953.552090155961;9963.302568820185;10008;1514.515355086373;1740.8520275776575;1792.4264235444664;1834.7339635752742;1845.6216475622466;118543.40728296303;130571.04143437857;131819.34090329582;133237;137398.3130084353;10837.620683276047;11172.720681983015;11525;11563.710907227387;11740.647780566596;14352.856471435362;14413.567592972227;14423.49517766873;14425.7836132456;14430.639234019023;8301.501909885426;8497.36269779769;8582.78543036429;8683.52240626759;8705.651773928972;8544.829103417896;8570.95714521436;8586.95706521476;8611;8732.821069155583;4386.714863533924;4415.40392047076;4446.377507149002;4562.908741825145;4604.815807367668;1580.7707882356995;1668.3159904439121;1671.8077421096486;1910.7229451729422;1992.0736857361396;878.6529320918202;930.8138372325562;956.3687965942445;966.2994329111337;973.2165606686685;19977.900110499646;20883.16467341289;21339.57320853574;21372.465688357606;21552.353429397142;915.4827522449795;925.8287216864901;961.110972350573;969.9806003879883;1004.1213937804421;10689;11247.493862776195;11250.774984500264;11268.605598804003;11291.435428228555;611387;612818;617203;622785;625059;114638;116778;117855.2857885692;119933.20434171752;130785.42251021169;897.210454799772;934.7336009237471;937.9108984646448;948.9525523723925;949.0319873728758;17864.57083433348;18572.81427185765;18702.064896754837;19019.524511887314;19173.520661983563;54727.90544189174;55390.72304638451;55628;55862.44137558735;57184.142237867294;71299.64350178216;74187.2581274202;76636.23363766291;80917;82259.17740822515;7090.574565526085;7324.816879578053;7408;8006.239407256301;8135.349172066102;12584.685382865502;12934.805977910528;13463.730725385632;13547.729045419233;13952.441902323793;8031.437799353989;9362.29782766309;10299.948500257451;11110.555577777122;11197.880211979063;9114.407563508616;11269.830952535554;11392.43037848121;11553.306801591596;11626.593069242535;10802.67591972242;11079.113670906147;11142.331460112418;11531.365774882463;11661.76676466483;6792.0830687856505;8077.394195435483;8087.8786818196595;8365.958170209353;8531.573421329032;10605.734856628646;10613.9469302653;10686.625968091079;10973.83539246896;11047.060999815105 23:07:39 INFO - PROCESS | 1652 | |3;mootools.html;1455.8180227471566;1493.4623535527207;1664.0265444714842;1749.2303386509927;1811.538057795262;7950.244726750964;8439.57802109895;8636.481811091338;9006.369554131204;9036.322275829307;8672.918499560108;8853.203211710945;8853.424527405714;8862.822743545124;8885.822283554342;2336.5910965581083;2371.1937941100023;2375.3467796356053;2377.892994815229;2384.1655420602738;2315.9305220843403;2332.0671731307443;2346.296111166652;2381.880905954704;2390.784829365357;2353.329301149173;2367.4199821043867;2368.08828600989;2384.1178763857397;2392.342105920868;2002.6295135399926;2003.3589251439546;2308.538292341522;2362.173239366217;2374.1215750172373;2329.9883500582387;2332.2303639798824;2334.463073493106;2335.112657190257;2340.6254999200046;7662.885056724153;8747.387682862201;8806.207441330247;8855.955720221422;8995.595198216031;5187.391908508374;5266.526012658879;5270.209468579706;5346.27825243591;5353.544948679367;2869.9856500717365;3399.915002124942;3498.055525008259;3586.1214002569536;3689.7417180797356;904.4166512599402;1029.8146333659938;1029.9330543514648;1039.0233180810014;1101.553870682375;11582.6525204244;11742.823857642143;11774.646760597196;11777.941110294394;11781.882181178164;4284.186004659105;4497.685162038626;4592.3800286961505;4813.638977076734;4829.95170048302;9926.2059035277;9930.304878658424;10110.898891010997;10176;10184.796304073878;5072.695638261716;5161.225816127611;5170.896582068338;5175.146100893386;5197.142471492237;4354.042110735633;4712.85861424151;4761.904761904742;4870.683405578626;4929.211326187865;3889.883303500843;3960.940585891214;4005.439238506611;4012.4382586437923;4076.8165432555606;1319.4194554396036;1327.1837819740983;1328.9734205316033;1340.7117469743857;1342.1410297409666;833.2833762963846;892.1390857822232;904.2268860124499;915.4232833315102;935.8816214623596;5171.3535808024;5833.7374818133285;5998.460138587461;6039.2788055404;6231.3145553989025;438.3862592370643;440.6562880952862;446.4374887641595;917.1653795046476;946.1720994130136;3209.6950789674943;3952.960470395259;4014.658754005884;4026.4564283821896;4061.4923134608175;10573.841392379121;10853.945730271298;10858.619948301772;10931.781364372668;10946.890531094587;10640.521176547074;10673.626423075155;10743.623973160902;10878.673639790819;10901.672949811518;7877.606119693993;8201.71294004707;8206.22040906113;8209.507429554365;8217.54803485802;5717.542596592346;5758.1650660654805;5783.392743761844;5846.912296315473;5932.377100404568;9859.802803944025;9873.40759554429;9876.950615246878;9882.209423246266;9911.900880991387;9768;10004;10096.19230461576;10175.135113515435;10219.948900255451;5695.373508914099;6576.243731970884;7002.824929376807;7121.181064177686;7223.097112860893;8400.327973761961;8409.117042710428;8493.065762765968;8501.744947651581;8529.87205191935;6220.564560480723;7432.3682486987045;7500.024996750606;7602.619869006417;7648.847023059619;7354.374878135419;7392.891066340092;7400.814979625337;7413.073365829271;7562.054743157106;6959.478039146983;6969.895451568129;7040.049593305043;7045.647717614202;7054.894176587458;3833.904152396101;5002.299678045149;5014.323066385991;5064.974675126601;5195.506426889439;4969.35398398206;4998.4501704813165;5066.189409694432;5120.3087583177285;5133.307003554472 23:07:39 INFO - PROCESS | 1652 | |4;prototype.html;305.3115225167249;311.7661753684736;317.64423845293265;320.45202703377225;321.65904141609894;5450.890982180364;5529.557635389169;5653.406392328808;5748.396418376073;5819.272590926134;4474.955250447494;5634.239377684012;5649.774009039644;5656.406077361879;5676.460736230062;4078.5717499662555;4291.510923664283;4303.182395344882;4312.827486900536;4332.610065094141;3606;4172.937405938914;4269.871903842874;4299.204647140289;4302.085102556674;4229.513605935311;4231.682623803212;4242.278812601865;4274.273373526493;4285.764282964436;3690.7970061646597;4205.15896820634;4263.232618128735;4268.274393353137;4271.402003719481;3597.5323207983056;3707.7404581679293;3715.10837399025;3718.7025037996896;3724.7392682512236;4079.755214687128;4430.468343798765;5455.481729235717;5551.639143455663;5670.659760414388;3758;3996.540397854255;4269.761769086961;4771.737554434505;4970.701757894538;4722.008378240574;5231.398389185217;5914.497267732248;5971.611845230046;6006.5495087868585;3170.429322721909;4047.190561887605;4049.736767110158;4067.1494470016073;4070.3690927906223;5746.02317606008;6173.475254603364;6301.79437196982;6362.904556431611;6365.840853978688;3815.6756675682877;3977.2301325909693;4126.381042843537;4127.832887733964;4146.37804329353;3981.641652251253;4153.563875793058;4182.456280683553;4187.6440502557625;4195.46865394129;4391.5388884166705;4485.663575231805;4636.721796692209;4818.397700287464;4913.550502601741;5510.6969116698965;6025.548083893726;6070.423309785563;6118.78584249558;6143.416375444325;1335.2055526961442;1339.370495866941;1378.2626294932163;1391.3808355282006;1410.4499245294344;760.3764912771475;817.8936738224112;853.2875049333752;860.3848248502351;862.0861886400311;3757.9060523486582;4409.581089796464;4524.63372845206;4645.372874661877;4718.414005355954;4469.7703686686655;4829.468758436569;4987.912329423967;5170.604985762147;5218.6953261684575;4677.6257899367965;4708.623310135249;4733.100710864925;4734.4081989751285;4738.431388233434;2431.55016321981;3112.4708799504137;3158.7630927680516;3265.755068369882;3287.967120328766;6416.197975253093;6528.445082167974;6557.278699343068;6560.868782624416;6570.934290657032;5341.092014357569;5485.561155107582;5713.457221563945;6090.939090609126;6378.501052252717;6458.806235812839;6468.482521398277;6468.514861385415;6469.611823290524;6560.376764207393;5223.712695801767;5418.566514678895;5730.5129064030025;5881.206037184926;6273.717682704198;5267.9736601316745;5638.5489160867855;6100;6129.816105516842;6161.675239823459;6152.616061166251;6490.7343068103155;6525.445337146396;6526.727288178759;6527.608343499405;3298.5711857458377;3350.9444524974556;3715.2314608961365;3716.2280285509687;3785.5268091488565;4737.034130835665;5367.926414717151;5944.554158438221;6153.569250152446;6234.781782637768;5432.9185062223305;5654.6324488908085;5896.439838215363;5909.52723782105;5962.493188079062;5009.9248511271635;5784.82645520635;5796.76812927495;5814.76740930358;5844.883102337844;4975.208924787146;5019;5728.162131372074;5733.426657334233;5767.7404516796005;4942.912559236962;5461.771573351817;5773.566982476415;5905.734241959036;5937.763918664252;4768.856934291976;5738.9139162913425;5740.856478588069;5741.569382296261;5760.308762948473 23:07:39 INFO - PROCESS | 1652 | |5;yui.html;679.3987321220718;701.0990876723412;702.5363260248236;760.1828034862524;799.604195923018;12944.546940857072;15305.387784488606;15420;15600;15995.440159594402;14476.131432114054;14582.05216660919;14590.562283131496;14622.926885365585;14659.193744344057;662.1259936883316;829.7966998085462;829.929455996241;857.6355049104137;877.6357811508217;815.4984684419069;851.0340763233712;864.9394542382036;867.6659486097866;870.4559650218613;909.195362104539;910.5401772105077;910.8770316007353;915.555955361649;918.8300164469562;857.6226460357454;878.89892662344;904.1997831918734;909.1590278991972;912.6440688131634;668.0913957018452;673.229152620252;678.0439580191905;681.1961884975726;683.3576438148125;13996.72006559863;14728.410863565445;14841.925790371088;14844.851551484455;14863.702725945528;12141.813994985809;13987.370568324348;14420.278986050656;14439.494617688435;14467;11839.408029598486;11909.583164589198;11914.285142891455;11975.700607484796;12083;2885.6825749167574;3726.3851464508325;3863.2466668999555;3976.1053762903402;3997.900052498682;116793.83206167915;117364;117643.82356176415;118688.40655796752;119765;10681.946590267155;10973.945130274298;10993.395363254938;11055.447227638586;11206.943965280121;22776.31671049871;22990.42523936915;23043.539129217323;23049.654255186186;23109;7880;8019.15798841125;8080.878786818203;8102.270795628422;8269.793255168668;4408.977955110204;4432.800523976429;4497.550244975476;4512.616427603625;4652.278896771006;3523.5771707395274;3576.3204991051616;3592.694620957248;3634.1459756957092;3644.3804553225486;642.720416618763;649.8472858878159;650.6616559389091;651.5146216069058;651.7588492257895;613.9079138129227;655.3190225649458;656.2813718977711;660.5376236634376;677.7612080796258;8982.014981685097;9187.770305742277;9517.095875891779;9519;9541.952290238505;487.5319693094669;616.9635013177905;648.7437462202404;649.1528555235369;696.5194016128855;4285.7000009999;4427.671460834433;4447.577480139382;4627.5603817637275;4678.578927896506;134644;136691;137907;138674.30662846824;140234;112230.43884780524;124309;127012;127113;130411.34794325968;95540;102971;107277;108875;115911;9645;9701.9029809701;9812.803743924938;9856.901430985885;9950.950245248727;38973.805130974164;50069.74965125151;59779.1033134512;63417.04874426979;63452.68273658602;82051.58974205091;87531;88092;88645.11354886627;90142.09857901337;12566.492020957177;12622.810657839957;12991.220526768424;13047.739045219232;13199.67000824987;14557.490487832874;14617.342219600356;14673.771770210536;14953.850461495245;15305.6173595661;13573.1856088635;13595.32023398846;13600.115992460458;13789.51736689211;14043.08719933201;13629.522966696119;13759.312034398039;13772.237926563603;13780.379882905092;13990.230537320545;11755.94271002554;12095.455704493143;12431.37843107859;12685.112042156961;12821.935890320488;8426.778117173099;9397.201237894582;10249.180065594586;10372.896271037494;10635.080395258228;8919.331050171393;9043.18611324984;10230.744231394274;11432.14258930567;11445.942770286429 23:07:39 INFO - PROCESS | 1652 | __end_tp_report 23:07:39 INFO - PROCESS | 1652 | __start_cc_report 23:07:39 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,377 23:07:39 INFO - PROCESS | 1652 | __end_cc_report 23:07:39 INFO - PROCESS | 1652 | __startTimestamp1472710058994__endTimestamp 23:07:39 INFO - PROCESS | 1652 | 23:07:39 INFO - PROCESS | 1652 | ------- Summary: start ------- 23:07:39 INFO - PROCESS | 1652 | Number of tests: 6 23:07:39 INFO - PROCESS | 1652 | 23:07:39 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5638.68 Median:5832.15 stddev:684.31 (11.7%) stddev-sans-first:658.21 23:07:39 INFO - PROCESS | 1652 | Values: 3160.5 3336.4 3396.3 3408.0 3417.9 5534.1 5600.5 5608.0 5628.4 5760.0 5575.9 5654.8 5690.3 5698.8 5742.7 5096.1 5178.6 5182.7 5232.3 5301.9 5297.1 5313.3 5344.5 5352.2 5374.5 3724.6 5109.9 5110.9 5209.2 5210.4 4866.5 5229.0 5230.3 5230.4 5279.5 4208.5 4723.4 5203.9 5220.7 5229.2 4595.9 4783.3 4784.4 5107.7 5714.8 4627.4 5892.6 6146.3 6188.5 6212.9 6241.7 6253.4 6253.8 6269.4 6307.6 5725.3 5747.8 5781.6 5787.8 5846.2 6257.6 6273.6 6277.7 6281.0 6313.2 5146.8 5997.6 6131.1 6146.5 6149.8 6144.4 6177.1 6205.9 6244.4 6260.3 6106.0 6199.1 6219.1 6231.4 6236.1 5080.2 6191.4 6261.7 6298.7 6307.7 4845.2 4880.2 4913.6 4951.9 4954.9 4391.4 4402.1 4409.1 4425.9 4456.3 5417.9 5881.5 5883.1 5887.9 5929.4 6142.2 6151.1 6192.5 6195.6 6290.6 5786.3 5790.0 5802.8 5803.2 5832.2 5406.4 5408.8 5424.7 5430.6 5432.8 5929.8 6188.4 6261.1 6264.8 6308.8 6261.1 6268.2 6268.2 6279.1 6283.4 6292.5 6333.8 6351.5 6361.5 6367.8 4836.6 4954.5 5892.5 5991.5 6098.9 6214.6 6252.1 6256.9 6273.2 6275.9 5904.1 6010.3 6020.5 6044.2 6070.2 5846.4 5919.6 5934.4 6007.5 6015.7 4830.7 5602.7 5804.2 5817.5 5821.3 5925.5 5940.4 5971.8 5987.6 5999.1 5710.8 5805.3 5831.0 5844.4 5850.8 23:07:39 INFO - PROCESS | 1652 | 23:07:39 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:64722.66 Median:38496.00 stddev:144478.38 (375.3%) stddev-sans-first:144803.27 23:07:39 INFO - PROCESS | 1652 | Values: 519.1 519.9 520.4 539.3 567.7 37523.0 39909.0 39922.4 40084.8 40128.2 40768.0 41221.0 41285.2 41315.0 41316.8 9461.8 9601.6 9622.0 9649.5 9652.4 9505.2 9588.7 9602.1 9658.5 9678.1 9566.1 9597.3 9664.8 9704.4 9710.9 9611.1 9653.3 9681.1 9683.2 9717.1 9478.5 9567.0 9626.8 9626.9 9638.1 39204.6 39306.2 39672.2 39707.4 39708.8 3314.1 3361.2 3388.3 3402.9 3779.6 30604.0 31058.8 31235.5 31279.7 31883.4 3021.4 3037.8 3058.9 3099.3 3147.2 333292.0 367733.0 367973.2 368119.0 368831.0 33167.8 34024.7 34060.5 48008.3 58828.0 27997.7 28954.1 30237.0 35008.1 35222.0 14500.7 14641.5 14645.3 14657.0 14658.8 12312.2 12385.2 12415.4 12464.5 12566.7 6432.8 7635.4 7792.5 7869.2 7873.8 3201.8 3250.9 3252.4 3259.5 3277.3 927.4 975.5 992.6 1054.6 1070.7 30158.7 30246.5 30557.8 30896.7 31001.0 996.3 1001.5 1031.7 1113.4 1120.2 13717.9 15827.8 15902.8 15929.8 16061.9 622754.0 629283.1 637615.0 638228.0 641919.0 57022.4 57098.6 57682.7 57783.0 57851.1 1338.2 1353.0 1366.8 1380.3 1382.6 24463.8 29421.7 29691.7 30197.2 30634.1 49330.8 49856.5 50129.7 50216.0 50247.7 576120.0 576878.0 583789.0 584821.0 589002.0 26518.2 26552.6 26571.7 26669.7 26839.1 46073.5 46793.8 47040.5 47203.3 47968.0 38559.0 38686.6 38965.2 39857.8 39903.0 38379.4 38589.8 38736.6 39257.0 39626.6 37007.3 37487.6 37710.0 37982.6 38433.0 33031.2 33287.7 34482.5 34653.0 35149.9 25584.0 25695.4 25778.9 25844.6 26209.7 23:07:39 INFO - PROCESS | 1652 | 23:07:39 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:35632.86 Median:382.89 stddev:103077.81 (26920.9%) stddev-sans-first:103332.97 23:07:39 INFO - PROCESS | 1652 | Values: 374.0 380.6 385.2 389.3 406.7 19905.1 21299.9 21695.7 21741.1 22134.0 20645.8 21200.9 21227.8 21527.0 21527.9 5348.4 5671.3 6130.1 6171.7 6206.9 4195.7 6102.0 6138.5 6527.7 6807.6 6863.3 6867.8 6877.3 6882.0 6902.0 4991.0 6174.0 6616.7 6620.2 6621.3 5602.1 5782.0 5804.6 5821.2 5841.5 13027.4 13319.2 21271.9 23226.3 26239.3 1828.5 2211.2 2393.9 2681.4 2737.1 9586.9 9723.2 9953.6 9963.3 10008.0 1514.5 1740.9 1792.4 1834.7 1845.6 118543.4 130571.0 131819.3 133237.0 137398.3 10837.6 11172.7 11525.0 11563.7 11740.6 14352.9 14413.6 14423.5 14425.8 14430.6 8301.5 8497.4 8582.8 8683.5 8705.7 8544.8 8571.0 8587.0 8611.0 8732.8 4386.7 4415.4 4446.4 4562.9 4604.8 1580.8 1668.3 1671.8 1910.7 1992.1 878.7 930.8 956.4 966.3 973.2 19977.9 20883.2 21339.6 21372.5 21552.4 915.5 925.8 961.1 970.0 1004.1 10689.0 11247.5 11250.8 11268.6 11291.4 611387.0 612818.0 617203.0 622785.0 625059.0 114638.0 116778.0 117855.3 119933.2 130785.4 897.2 934.7 937.9 949.0 949.0 17864.6 18572.8 18702.1 19019.5 19173.5 54727.9 55390.7 55628.0 55862.4 57184.1 71299.6 74187.3 76636.2 80917.0 82259.2 7090.6 7324.8 7408.0 8006.2 8135.3 12584.7 12934.8 13463.7 13547.7 13952.4 8031.4 9362.3 10299.9 11110.6 11197.9 9114.4 11269.8 11392.4 11553.3 11626.6 10802.7 11079.1 11142.3 11531.4 11661.8 6792.1 8077.4 8087.9 8366.0 8531.6 10605.7 10613.9 10686.6 10973.8 11047.1 23:07:39 INFO - PROCESS | 1652 | 23:07:39 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5671.16 Median:4983.90 stddev:3227.28 (64.8%) stddev-sans-first:3220.79 23:07:39 INFO - PROCESS | 1652 | Values: 1455.8 1493.5 1664.0 1749.2 1811.5 7950.2 8439.6 8636.5 9006.4 9036.3 8672.9 8853.2 8853.4 8862.8 8885.8 2336.6 2371.2 2375.3 2377.9 2384.2 2315.9 2332.1 2346.3 2381.9 2390.8 2353.3 2367.4 2368.1 2384.1 2392.3 2002.6 2003.4 2308.5 2362.2 2374.1 2330.0 2332.2 2334.5 2335.1 2340.6 7662.9 8747.4 8806.2 8856.0 8995.6 5187.4 5266.5 5270.2 5346.3 5353.5 2870.0 3399.9 3498.1 3586.1 3689.7 904.4 1029.8 1029.9 1039.0 1101.6 11582.7 11742.8 11774.6 11777.9 11781.9 4284.2 4497.7 4592.4 4813.6 4830.0 9926.2 9930.3 10110.9 10176.0 10184.8 5072.7 5161.2 5170.9 5175.1 5197.1 4354.0 4712.9 4761.9 4870.7 4929.2 3889.9 3960.9 4005.4 4012.4 4076.8 1319.4 1327.2 1329.0 1340.7 1342.1 833.3 892.1 904.2 915.4 935.9 5171.4 5833.7 5998.5 6039.3 6231.3 438.4 440.7 446.4 917.2 946.2 3209.7 3953.0 4014.7 4026.5 4061.5 10573.8 10853.9 10858.6 10931.8 10946.9 10640.5 10673.6 10743.6 10878.7 10901.7 7877.6 8201.7 8206.2 8209.5 8217.5 5717.5 5758.2 5783.4 5846.9 5932.4 9859.8 9873.4 9877.0 9882.2 9911.9 9768.0 10004.0 10096.2 10175.1 10219.9 5695.4 6576.2 7002.8 7121.2 7223.1 8400.3 8409.1 8493.1 8501.7 8529.9 6220.6 7432.4 7500.0 7602.6 7648.8 7354.4 7392.9 7400.8 7413.1 7562.1 6959.5 6969.9 7040.0 7045.6 7054.9 3833.9 5002.3 5014.3 5065.0 5195.5 4969.4 4998.5 5066.2 5120.3 5133.3 23:07:39 INFO - PROCESS | 1652 | 23:07:39 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4697.47 Median:4998.92 stddev:1500.30 (30.0%) stddev-sans-first:1467.84 23:07:39 INFO - PROCESS | 1652 | Values: 305.3 311.8 317.6 320.5 321.7 5450.9 5529.6 5653.4 5748.4 5819.3 4475.0 5634.2 5649.8 5656.4 5676.5 4078.6 4291.5 4303.2 4312.8 4332.6 3606.0 4172.9 4269.9 4299.2 4302.1 4229.5 4231.7 4242.3 4274.3 4285.8 3690.8 4205.2 4263.2 4268.3 4271.4 3597.5 3707.7 3715.1 3718.7 3724.7 4079.8 4430.5 5455.5 5551.6 5670.7 3758.0 3996.5 4269.8 4771.7 4970.7 4722.0 5231.4 5914.5 5971.6 6006.5 3170.4 4047.2 4049.7 4067.1 4070.4 5746.0 6173.5 6301.8 6362.9 6365.8 3815.7 3977.2 4126.4 4127.8 4146.4 3981.6 4153.6 4182.5 4187.6 4195.5 4391.5 4485.7 4636.7 4818.4 4913.6 5510.7 6025.5 6070.4 6118.8 6143.4 1335.2 1339.4 1378.3 1391.4 1410.4 760.4 817.9 853.3 860.4 862.1 3757.9 4409.6 4524.6 4645.4 4718.4 4469.8 4829.5 4987.9 5170.6 5218.7 4677.6 4708.6 4733.1 4734.4 4738.4 2431.6 3112.5 3158.8 3265.8 3288.0 6416.2 6528.4 6557.3 6560.9 6570.9 5341.1 5485.6 5713.5 6090.9 6378.5 6458.8 6468.5 6468.5 6469.6 6560.4 5223.7 5418.6 5730.5 5881.2 6273.7 5268.0 5638.5 6100.0 6129.8 6161.7 6152.6 6490.7 6525.4 6526.7 6527.6 3298.6 3350.9 3715.2 3716.2 3785.5 4737.0 5367.9 5944.6 6153.6 6234.8 5432.9 5654.6 5896.4 5909.5 5962.5 5009.9 5784.8 5796.8 5814.8 5844.9 4975.2 5019.0 5728.2 5733.4 5767.7 4942.9 5461.8 5773.6 5905.7 5937.8 4768.9 5738.9 5740.9 5741.6 5760.3 23:07:39 INFO - PROCESS | 1652 | 23:07:39 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:24178.89 Median:3685.38 stddev:38276.44 (1038.6%) stddev-sans-first:38343.15 23:07:39 INFO - PROCESS | 1652 | Values: 679.4 701.1 702.5 760.2 799.6 12944.5 15305.4 15420.0 15600.0 15995.4 14476.1 14582.1 14590.6 14622.9 14659.2 662.1 829.8 829.9 857.6 877.6 815.5 851.0 864.9 867.7 870.5 909.2 910.5 910.9 915.6 918.8 857.6 878.9 904.2 909.2 912.6 668.1 673.2 678.0 681.2 683.4 13996.7 14728.4 14841.9 14844.9 14863.7 12141.8 13987.4 14420.3 14439.5 14467.0 11839.4 11909.6 11914.3 11975.7 12083.0 2885.7 3726.4 3863.2 3976.1 3997.9 116793.8 117364.0 117643.8 118688.4 119765.0 10681.9 10973.9 10993.4 11055.4 11206.9 22776.3 22990.4 23043.5 23049.7 23109.0 7880.0 8019.2 8080.9 8102.3 8269.8 4409.0 4432.8 4497.6 4512.6 4652.3 3523.6 3576.3 3592.7 3634.1 3644.4 642.7 649.8 650.7 651.5 651.8 613.9 655.3 656.3 660.5 677.8 8982.0 9187.8 9517.1 9519.0 9542.0 487.5 617.0 648.7 649.2 696.5 4285.7 4427.7 4447.6 4627.6 4678.6 134644.0 136691.0 137907.0 138674.3 140234.0 112230.4 124309.0 127012.0 127113.0 130411.3 95540.0 102971.0 107277.0 108875.0 115911.0 9645.0 9701.9 9812.8 9856.9 9951.0 38973.8 50069.7 59779.1 63417.0 63452.7 82051.6 87531.0 88092.0 88645.1 90142.1 12566.5 12622.8 12991.2 13047.7 13199.7 14557.5 14617.3 14673.8 14953.9 15305.6 13573.2 13595.3 13600.1 13789.5 14043.1 13629.5 13759.3 13772.2 13780.4 13990.2 11755.9 12095.5 12431.4 12685.1 12821.9 8426.8 9397.2 10249.2 10372.9 10635.1 8919.3 9043.2 10230.7 11432.1 11445.9 23:07:39 INFO - PROCESS | 1652 | -------- Summary: end -------- 23:07:39 INFO - PROCESS | 1652 | 23:07:39 INFO - TEST-INFO | 1652: exit 0 23:07:39 INFO - TEST-OK | dromaeo_css | took 1113966ms 23:07:39 INFO - TEST-START | kraken 23:07:39 INFO - operating with platform_type : mac_ 23:07:39 INFO - Initialising browser for kraken test... 23:07:39 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpcVs3nZ/profile http://localhost:49268/getInfo.html) 23:07:42 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 23:07:42 INFO - PROCESS | 1659 | colorDepth:24 23:07:42 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 23:07:42 INFO - PROCESS | 1659 | __metrics 23:07:42 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 23:07:42 INFO - TEST-INFO | 1659: exit 0 23:07:42 INFO - Browser initialized. 23:07:42 INFO - Running cycle 1/1 for kraken test... 23:07:42 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpcVs3nZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:07:54 INFO - PROCESS | 1661 | RSS: Main: 245440512 23:07:54 INFO - PROCESS | 1661 | 23:07:57 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 23:07:58 INFO - PROCESS | 1661 | RSS: Main: 258240512 23:07:58 INFO - PROCESS | 1661 | 23:08:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 23:08:01 INFO - PROCESS | 1661 | RSS: Main: 267010048 23:08:01 INFO - PROCESS | 1661 | 23:08:05 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 23:08:06 INFO - PROCESS | 1661 | RSS: Main: 263397376 23:08:06 INFO - PROCESS | 1661 | 23:08:07 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 23:08:08 INFO - PROCESS | 1661 | RSS: Main: 265773056 23:08:08 INFO - PROCESS | 1661 | 23:08:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:08:11 INFO - PROCESS | 1661 | RSS: Main: 266461184 23:08:11 INFO - PROCESS | 1661 | 23:08:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 23:08:14 INFO - PROCESS | 1661 | RSS: Main: 267124736 23:08:14 INFO - PROCESS | 1661 | 23:08:17 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 23:08:18 INFO - PROCESS | 1661 | RSS: Main: 267227136 23:08:18 INFO - PROCESS | 1661 | 23:08:21 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 23:08:21 INFO - PROCESS | 1661 | RSS: Main: 267640832 23:08:21 INFO - PROCESS | 1661 | 23:08:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:08:23 INFO - PROCESS | 1661 | RSS: Main: 267096064 23:08:23 INFO - PROCESS | 1661 | 23:08:25 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:08:26 INFO - PROCESS | 1661 | RSS: Main: 266817536 23:08:26 INFO - PROCESS | 1661 | 23:08:28 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:08:29 INFO - PROCESS | 1661 | RSS: Main: 267096064 23:08:29 INFO - PROCESS | 1661 | 23:08:32 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:08:33 INFO - PROCESS | 1661 | RSS: Main: 267108352 23:08:33 INFO - PROCESS | 1661 | 23:08:36 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:08:36 INFO - PROCESS | 1661 | RSS: Main: 267390976 23:08:36 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 23:08:38 INFO - PROCESS | 1661 | RSS: Main: 269520896 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | __start_tp_report 23:08:38 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 23:08:38 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 23:08:38 INFO - PROCESS | 1661 | |i|pagename|runs| 23:08:38 INFO - PROCESS | 1661 | |0;ai-astar;110;118;116;112;115;115;125;165;116;132 23:08:38 INFO - PROCESS | 1661 | |1;audio-beat-detection;142;112;139;159;111;131;129;109;157;112 23:08:38 INFO - PROCESS | 1661 | |2;audio-dft;205;250;210;254;275;202;198;193;196;193 23:08:38 INFO - PROCESS | 1661 | |3;audio-fft;67;79;75;141;56;57;59;64;130;56 23:08:38 INFO - PROCESS | 1661 | |4;audio-oscillator;67;70;109;68;71;93;111;68;74;112 23:08:38 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;91;87;89;87;91;86;89;87;89;86 23:08:38 INFO - PROCESS | 1661 | |6;imaging-darkroom;150;142;96;133;117;94;96;96;95;95 23:08:38 INFO - PROCESS | 1661 | |7;imaging-desaturate;92;98;91;98;95;145;144;91;92;92 23:08:38 INFO - PROCESS | 1661 | |8;json-parse-financial;56;56;56;56;57;58;58;57;63;63 23:08:38 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;68;71;71;74;83;84;105;71;74;72 23:08:38 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;95;107;100;86;88;87;85;90;90;90 23:08:38 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;151;142;150;155;144;139;154;159;191;151 23:08:38 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;164;244;181;174;173;170;172;171;169;172 23:08:38 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;67;67;69;67;67;66;69;68;67;70 23:08:38 INFO - PROCESS | 1661 | __end_tp_report 23:08:38 INFO - PROCESS | 1661 | __start_cc_report 23:08:38 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,948 23:08:38 INFO - PROCESS | 1661 | __end_cc_report 23:08:38 INFO - PROCESS | 1661 | __startTimestamp1472710118597__endTimestamp 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | ------- Summary: start ------- 23:08:38 INFO - PROCESS | 1661 | Number of tests: 14 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:122.40 Median:117.00 stddev:16.28 (13.9%) stddev-sans-first:16.64 23:08:38 INFO - PROCESS | 1661 | Values: 110.0 118.0 116.0 112.0 115.0 115.0 125.0 165.0 116.0 132.0 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:130.10 Median:135.00 stddev:18.99 (14.1%) stddev-sans-first:19.65 23:08:38 INFO - PROCESS | 1661 | Values: 142.0 112.0 139.0 159.0 111.0 131.0 129.0 109.0 157.0 112.0 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:217.60 Median:207.50 stddev:30.17 (14.5%) stddev-sans-first:31.65 23:08:38 INFO - PROCESS | 1661 | Values: 205.0 250.0 210.0 254.0 275.0 202.0 198.0 193.0 196.0 193.0 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:78.40 Median:61.50 stddev:31.21 (50.8%) stddev-sans-first:32.83 23:08:38 INFO - PROCESS | 1661 | Values: 67.0 79.0 75.0 141.0 56.0 57.0 59.0 64.0 130.0 56.0 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:84.30 Median:69.00 stddev:19.68 (28.5%) stddev-sans-first:19.85 23:08:38 INFO - PROCESS | 1661 | Values: 67.0 70.0 109.0 68.0 71.0 93.0 111.0 68.0 74.0 112.0 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:88.20 Median:89.00 stddev:1.87 (2.1%) stddev-sans-first:1.69 23:08:38 INFO - PROCESS | 1661 | Values: 91.0 87.0 89.0 87.0 91.0 86.0 89.0 87.0 89.0 86.0 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:111.40 Median:95.00 stddev:22.30 (23.5%) stddev-sans-first:18.78 23:08:38 INFO - PROCESS | 1661 | Values: 150.0 142.0 96.0 133.0 117.0 94.0 96.0 96.0 95.0 95.0 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:103.80 Median:92.00 stddev:21.61 (23.5%) stddev-sans-first:22.50 23:08:38 INFO - PROCESS | 1661 | Values: 92.0 98.0 91.0 98.0 95.0 145.0 144.0 91.0 92.0 92.0 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:57.50 stddev:2.75 (4.8%) stddev-sans-first:2.82 23:08:38 INFO - PROCESS | 1661 | Values: 56.0 56.0 56.0 56.0 57.0 58.0 58.0 57.0 63.0 63.0 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:77.30 Median:73.00 stddev:11.06 (15.1%) stddev-sans-first:11.20 23:08:38 INFO - PROCESS | 1661 | Values: 68.0 71.0 71.0 74.0 83.0 84.0 105.0 71.0 74.0 72.0 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:91.80 Median:89.00 stddev:6.96 (7.8%) stddev-sans-first:7.28 23:08:38 INFO - PROCESS | 1661 | Values: 95.0 107.0 100.0 86.0 88.0 87.0 85.0 90.0 90.0 90.0 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:153.60 Median:152.50 stddev:14.52 (9.5%) stddev-sans-first:15.37 23:08:38 INFO - PROCESS | 1661 | Values: 151.0 142.0 150.0 155.0 144.0 139.0 154.0 159.0 191.0 151.0 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.00 Median:172.50 stddev:23.23 (13.5%) stddev-sans-first:24.00 23:08:38 INFO - PROCESS | 1661 | Values: 164.0 244.0 181.0 174.0 173.0 170.0 172.0 171.0 169.0 172.0 23:08:38 INFO - PROCESS | 1661 | 23:08:38 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:67.50 stddev:1.25 (1.9%) stddev-sans-first:1.30 23:08:38 INFO - PROCESS | 1661 | Values: 67.0 67.0 69.0 67.0 67.0 66.0 69.0 68.0 67.0 70.0 23:08:38 INFO - PROCESS | 1661 | -------- Summary: end -------- 23:08:38 INFO - PROCESS | 1661 | 23:08:39 INFO - TEST-INFO | 1661: exit 0 23:08:39 INFO - TEST-OK | kraken | took 59830ms 23:08:39 INFO - SUITE-END | took 1173s 23:08:39 INFO - Completed test suite (00:19:34) 23:08:39 INFO - javascript benchmark 23:08:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7234.570930683265, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3160.462721337373, 3336.4161271777434, 3396.337714145741, 3408.028711817131, 3417.8974630761086, 5534.059209934311, 5600.467955544217, 5608.0, 5628.409017053202, 5759.971200143993, 5575.8606034849145, 5654.802081927133, 5690.345610254822, 5698.803251317228, 5742.6841523716175, 5096.133657278272, 5178.637495375326, 5182.714950677711, 5232.2674825524455, 5301.8939621207555, 5297.099493086165, 5313.335833020872, 5344.465553444644, 5352.170413585901, 5374.516293533581, 3724.553053633554, 5109.923351149736, 5110.897782044375, 5209.218617207412, 5210.400803907523, 4866.489018653026, 5229.032628963654, 5230.293910322096, 5230.424653288135, 5279.472052794728, 4208.452901122834, 4723.36234608327, 5203.869903252411, 5220.712860792655, 5229.189475631283, 4595.908081838378, 4783.3064205690325, 4784.40194975628, 5107.668001579886, 5714.828555143311, 4627.375304333939, 5892.587518873681, 6146.29317628474, 6188.47397971173, 6212.943799554087, 6241.687915604201, 6253.405926436982, 6253.78111766086, 6269.404406581368, 6307.621542707452, 5725.341585717612, 5747.770089196469, 5781.566382521328, 5787.826365209051, 5846.1523079153285, 6257.561970662101, 6273.560850740404, 6277.748890044439, 6281.0, 6313.179286692701, 5146.768395422215, 5997.640141591519, 6131.0803379492545, 6146.4775494082605, 6149.815505534841, 6144.354842741537, 6177.104319873593, 6205.906911396332, 6244.406781355764, 6260.311365749764, 6105.975331700744, 6199.101130336076, 6219.129321894939, 6231.4391704746795, 6236.095766113888, 5080.237964305384, 6191.380861913863, 6261.686915654199, 6298.685065746786, 6307.747690092437, 4845.176320025534, 4880.219164933665, 4913.557779799835, 4951.851444456672, 4954.925676114861, 4391.429114215131, 4402.075564131568, 4409.074094440203, 4425.911481770347, 4456.309272062836, 5417.864553386118, 5881.529477641779, 5883.0881213411985, 5887.852803679943, 5929.436703513159, 6142.191841058483, 6151.108089327023, 6192.473639740672, 6195.57016733029, 6290.622562646256, 5786.3056433228285, 5789.9710501447225, 5802.796902108406, 5803.158542011384, 5832.154337621021, 5406.432324605938, 5408.8377348679605, 5424.7016414096815, 5430.592705547021, 5432.809851655173, 5929.762809487572, 6188.412100850322, 6261.092141639346, 6264.780732674334, 6308.779192728232, 6261.060840873905, 6268.153799237045, 6268.216472940882, 6279.1209230708655, 6283.403076707706, 6292.465140463112, 6333.841653958688, 6351.460125889351, 6361.522885783677, 6367.777127800505, 4836.588889944253, 4954.479779623088, 5892.5285977122585, 5991.520678345635, 6098.878022439615, 6214.627122372672, 6252.093446450148, 6256.937430625636, 6273.153124328157, 6275.905861412174, 5904.132297033086, 6010.308814486215, 6020.488258497901, 6044.184035155374, 6070.150178974859, 5846.386129456346, 5919.644821310735, 5934.43622855828, 6007.519398448027, 6015.72929218195, 4830.7101573905675, 5602.691851948182, 5804.158397032322, 5817.534597232296, 5821.330546986981, 5925.525957923442, 5940.405959404025, 5971.790987315597, 5987.64074155552, 5999.100134979788, 5710.8001219957105, 5805.274340707411, 5831.0378787499585, 5844.415558444121, 5850.795222167204], "value": 5596.614078960091, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.1304564853868, 519.9428062913081, 520.3937412913956, 539.3393093460511, 567.7331654122561, 37523.0, 39909.00227494314, 39922.40116398249, 40084.79957600215, 40128.1974360513, 40768.0, 41220.96947576312, 41285.17429651405, 41315.0, 41316.79341603288, 9461.763455913606, 9601.615935362577, 9621.95189024054, 9649.469279189641, 9652.372595781288, 9505.239580833517, 9588.664396746115, 9602.135807777298, 9658.468784216866, 9678.080582344666, 9566.091221333996, 9597.280203984694, 9664.80670386592, 9704.41773493589, 9710.854337184912, 9611.134997850191, 9653.276004299636, 9681.128698417177, 9683.225341972626, 9717.12545870875, 9478.526073696288, 9567.0, 9626.75933101671, 9626.855597165979, 9638.084381983701, 39204.60795392067, 39306.21387572261, 39672.20655586901, 39707.40438893419, 39708.8232941364, 3314.0886256279473, 3361.1765117546356, 3388.288459423524, 3402.931941361159, 3779.5653499847595, 30604.0, 31058.84470577655, 31235.531467028013, 31279.687203127905, 31883.362332753448, 3021.365513242222, 3037.8481075946115, 3058.9082327529986, 3099.318150006995, 3147.213196700825, 333292.0, 367733.0, 367973.1601342003, 368119.0, 368831.0, 33167.83912563049, 34024.659753402644, 34060.48909266363, 48008.27987580189, 58828.0, 27997.720022799513, 28954.13137605875, 30237.0, 35008.12479688028, 35222.0, 14500.709985800437, 14641.487547935983, 14645.26773661334, 14657.0, 14658.780118298235, 12312.199707019015, 12385.1949623276, 12415.379231038412, 12464.501419943283, 12566.748665026647, 6432.807015789534, 7635.389168866477, 7792.493487923267, 7869.213078692085, 7873.842523149505, 3201.839908004637, 3250.902472925816, 3252.3983063133396, 3259.462188738879, 3277.3117645294756, 927.4110939553432, 975.5414954971152, 992.6029588164647, 1054.6466933577185, 1070.7483741320784, 30158.698413016027, 30246.54630180549, 30557.847210763804, 30896.69103308983, 31001.0, 996.2876543271595, 1001.529281237817, 1031.7059638003134, 1113.4265853085667, 1120.2326406411635, 13717.931410343084, 15827.841721582638, 15902.840971590367, 15929.840701592835, 16061.919690401473, 622754.0, 629283.1215063556, 637615.0, 638228.0, 641919.0, 57022.42977570254, 57098.57253568694, 57682.67379946521, 57783.0, 57851.132233016535, 1338.2305174524492, 1352.9864701353058, 1366.781314989597, 1380.3167432121165, 1382.6059573021842, 24463.75536244686, 29421.705782941895, 29691.703082969758, 30197.245068873453, 30634.08097757071, 49330.75334623304, 49856.501434985184, 50129.74935125447, 50216.0, 50247.748761255956, 576120.0, 576878.0, 583789.0, 584821.0, 589002.0, 26518.204453866416, 26552.601710973966, 26571.734282656926, 26669.7333026675, 26839.060632877754, 46073.53926460692, 46793.83015424642, 47040.52959470498, 47203.29195062145, 47968.0, 38559.0, 38686.613133869425, 38965.232822614686, 39857.80071099626, 39903.00242493961, 38379.424308634836, 38589.80705096456, 38736.64421745338, 39257.0, 39626.60373396229, 37007.259854802214, 37487.62512374841, 37710.0, 37982.620173797906, 38433.0, 33031.17422064468, 33287.668493260964, 34482.482762758074, 34653.0, 35149.94550163499, 25584.0, 25695.357616059748, 25778.871105644354, 25844.612330815427, 26209.73790262149], "value": 18265.142963174643, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.98691045813405, 380.6250842919724, 385.1584288329999, 389.32452195440925, 406.6705968165786, 19905.104270307835, 21299.893500532475, 21695.674564881538, 21741.064956230923, 22134.003969821355, 20645.793542064574, 21200.893995530038, 21227.832469214227, 21527.0, 21527.892360538175, 5348.4249824227845, 5671.262735844355, 6130.141780150782, 6171.660558669271, 6206.851732429515, 4195.7063005589625, 6101.969490152543, 6138.539609529281, 6527.738890444377, 6807.591544507321, 6863.279355667658, 6867.759628413007, 6877.346652068046, 6882.03651488792, 6902.03371527984, 4990.950090499103, 6174.043023331391, 6616.669166541702, 6620.23867255267, 6621.304762999888, 5602.1036634138745, 5782.0, 5804.564657650651, 5821.214136091616, 5841.503472204868, 13027.413766380536, 13319.200847949154, 21271.8936405317, 23226.3032109037, 26239.344016399744, 1828.515443407498, 2211.2150186683657, 2393.8803059847114, 2681.4368982513697, 2737.137801592492, 9586.856197157049, 9723.173530249936, 9953.552090155961, 9963.302568820185, 10008.0, 1514.515355086373, 1740.8520275776575, 1792.4264235444664, 1834.7339635752742, 1845.6216475622466, 118543.40728296303, 130571.04143437857, 131819.34090329582, 133237.0, 137398.3130084353, 10837.620683276047, 11172.720681983015, 11525.0, 11563.710907227387, 11740.647780566596, 14352.856471435362, 14413.567592972227, 14423.49517766873, 14425.7836132456, 14430.639234019023, 8301.501909885426, 8497.36269779769, 8582.78543036429, 8683.52240626759, 8705.651773928972, 8544.829103417896, 8570.95714521436, 8586.95706521476, 8611.0, 8732.821069155583, 4386.714863533924, 4415.40392047076, 4446.377507149002, 4562.908741825145, 4604.815807367668, 1580.7707882356995, 1668.3159904439121, 1671.8077421096486, 1910.7229451729422, 1992.0736857361396, 878.6529320918202, 930.8138372325562, 956.3687965942445, 966.2994329111337, 973.2165606686685, 19977.900110499646, 20883.16467341289, 21339.57320853574, 21372.465688357606, 21552.353429397142, 915.4827522449795, 925.8287216864901, 961.110972350573, 969.9806003879883, 1004.1213937804421, 10689.0, 11247.493862776195, 11250.774984500264, 11268.605598804003, 11291.435428228555, 611387.0, 612818.0, 617203.0, 622785.0, 625059.0, 114638.0, 116778.0, 117855.2857885692, 119933.20434171752, 130785.42251021169, 897.210454799772, 934.7336009237471, 937.9108984646448, 948.9525523723925, 949.0319873728758, 17864.57083433348, 18572.81427185765, 18702.064896754837, 19019.524511887314, 19173.520661983563, 54727.90544189174, 55390.72304638451, 55628.0, 55862.44137558735, 57184.142237867294, 71299.64350178216, 74187.2581274202, 76636.23363766291, 80917.0, 82259.17740822515, 7090.574565526085, 7324.816879578053, 7408.0, 8006.239407256301, 8135.349172066102, 12584.685382865502, 12934.805977910528, 13463.730725385632, 13547.729045419233, 13952.441902323793, 8031.437799353989, 9362.29782766309, 10299.948500257451, 11110.555577777122, 11197.880211979063, 9114.407563508616, 11269.830952535554, 11392.43037848121, 11553.306801591596, 11626.593069242535, 10802.67591972242, 11079.113670906147, 11142.331460112418, 11531.365774882463, 11661.76676466483, 6792.0830687856505, 8077.394195435483, 8087.8786818196595, 8365.958170209353, 8531.573421329032, 10605.734856628646, 10613.9469302653, 10686.625968091079, 10973.83539246896, 11047.060999815105], "value": 9547.524844357138, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1455.8180227471566, 1493.4623535527207, 1664.0265444714842, 1749.2303386509927, 1811.538057795262, 7950.244726750964, 8439.57802109895, 8636.481811091338, 9006.369554131204, 9036.322275829307, 8672.918499560108, 8853.203211710945, 8853.424527405714, 8862.822743545124, 8885.822283554342, 2336.5910965581083, 2371.1937941100023, 2375.3467796356053, 2377.892994815229, 2384.1655420602738, 2315.9305220843403, 2332.0671731307443, 2346.296111166652, 2381.880905954704, 2390.784829365357, 2353.329301149173, 2367.4199821043867, 2368.08828600989, 2384.1178763857397, 2392.342105920868, 2002.6295135399926, 2003.3589251439546, 2308.538292341522, 2362.173239366217, 2374.1215750172373, 2329.9883500582387, 2332.2303639798824, 2334.463073493106, 2335.112657190257, 2340.6254999200046, 7662.885056724153, 8747.387682862201, 8806.207441330247, 8855.955720221422, 8995.595198216031, 5187.391908508374, 5266.526012658879, 5270.209468579706, 5346.27825243591, 5353.544948679367, 2869.9856500717365, 3399.915002124942, 3498.055525008259, 3586.1214002569536, 3689.7417180797356, 904.4166512599402, 1029.8146333659938, 1029.9330543514648, 1039.0233180810014, 1101.553870682375, 11582.6525204244, 11742.823857642143, 11774.646760597196, 11777.941110294394, 11781.882181178164, 4284.186004659105, 4497.685162038626, 4592.3800286961505, 4813.638977076734, 4829.95170048302, 9926.2059035277, 9930.304878658424, 10110.898891010997, 10176.0, 10184.796304073878, 5072.695638261716, 5161.225816127611, 5170.896582068338, 5175.146100893386, 5197.142471492237, 4354.042110735633, 4712.85861424151, 4761.904761904742, 4870.683405578626, 4929.211326187865, 3889.883303500843, 3960.940585891214, 4005.439238506611, 4012.4382586437923, 4076.8165432555606, 1319.4194554396036, 1327.1837819740983, 1328.9734205316033, 1340.7117469743857, 1342.1410297409666, 833.2833762963846, 892.1390857822232, 904.2268860124499, 915.4232833315102, 935.8816214623596, 5171.3535808024, 5833.7374818133285, 5998.460138587461, 6039.2788055404, 6231.3145553989025, 438.3862592370643, 440.6562880952862, 446.4374887641595, 917.1653795046476, 946.1720994130136, 3209.6950789674943, 3952.960470395259, 4014.658754005884, 4026.4564283821896, 4061.4923134608175, 10573.841392379121, 10853.945730271298, 10858.619948301772, 10931.781364372668, 10946.890531094587, 10640.521176547074, 10673.626423075155, 10743.623973160902, 10878.673639790819, 10901.672949811518, 7877.606119693993, 8201.71294004707, 8206.22040906113, 8209.507429554365, 8217.54803485802, 5717.542596592346, 5758.1650660654805, 5783.392743761844, 5846.912296315473, 5932.377100404568, 9859.802803944025, 9873.40759554429, 9876.950615246878, 9882.209423246266, 9911.900880991387, 9768.0, 10004.0, 10096.19230461576, 10175.135113515435, 10219.948900255451, 5695.373508914099, 6576.243731970884, 7002.824929376807, 7121.181064177686, 7223.097112860893, 8400.327973761961, 8409.117042710428, 8493.065762765968, 8501.744947651581, 8529.87205191935, 6220.564560480723, 7432.3682486987045, 7500.024996750606, 7602.619869006417, 7648.847023059619, 7354.374878135419, 7392.891066340092, 7400.814979625337, 7413.073365829271, 7562.054743157106, 6959.478039146983, 6969.895451568129, 7040.049593305043, 7045.647717614202, 7054.894176587458, 3833.904152396101, 5002.299678045149, 5014.323066385991, 5064.974675126601, 5195.506426889439, 4969.35398398206, 4998.4501704813165, 5066.189409694432, 5120.3087583177285, 5133.307003554472], "value": 4509.198996763711, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.3115225167249, 311.7661753684736, 317.64423845293265, 320.45202703377225, 321.65904141609894, 5450.890982180364, 5529.557635389169, 5653.406392328808, 5748.396418376073, 5819.272590926134, 4474.955250447494, 5634.239377684012, 5649.774009039644, 5656.406077361879, 5676.460736230062, 4078.5717499662555, 4291.510923664283, 4303.182395344882, 4312.827486900536, 4332.610065094141, 3606.0, 4172.937405938914, 4269.871903842874, 4299.204647140289, 4302.085102556674, 4229.513605935311, 4231.682623803212, 4242.278812601865, 4274.273373526493, 4285.764282964436, 3690.7970061646597, 4205.15896820634, 4263.232618128735, 4268.274393353137, 4271.402003719481, 3597.5323207983056, 3707.7404581679293, 3715.10837399025, 3718.7025037996896, 3724.7392682512236, 4079.755214687128, 4430.468343798765, 5455.481729235717, 5551.639143455663, 5670.659760414388, 3758.0, 3996.540397854255, 4269.761769086961, 4771.737554434505, 4970.701757894538, 4722.008378240574, 5231.398389185217, 5914.497267732248, 5971.611845230046, 6006.5495087868585, 3170.429322721909, 4047.190561887605, 4049.736767110158, 4067.1494470016073, 4070.3690927906223, 5746.02317606008, 6173.475254603364, 6301.79437196982, 6362.904556431611, 6365.840853978688, 3815.6756675682877, 3977.2301325909693, 4126.381042843537, 4127.832887733964, 4146.37804329353, 3981.641652251253, 4153.563875793058, 4182.456280683553, 4187.6440502557625, 4195.46865394129, 4391.5388884166705, 4485.663575231805, 4636.721796692209, 4818.397700287464, 4913.550502601741, 5510.6969116698965, 6025.548083893726, 6070.423309785563, 6118.78584249558, 6143.416375444325, 1335.2055526961442, 1339.370495866941, 1378.2626294932163, 1391.3808355282006, 1410.4499245294344, 760.3764912771475, 817.8936738224112, 853.2875049333752, 860.3848248502351, 862.0861886400311, 3757.9060523486582, 4409.581089796464, 4524.63372845206, 4645.372874661877, 4718.414005355954, 4469.7703686686655, 4829.468758436569, 4987.912329423967, 5170.604985762147, 5218.6953261684575, 4677.6257899367965, 4708.623310135249, 4733.100710864925, 4734.4081989751285, 4738.431388233434, 2431.55016321981, 3112.4708799504137, 3158.7630927680516, 3265.755068369882, 3287.967120328766, 6416.197975253093, 6528.445082167974, 6557.278699343068, 6560.868782624416, 6570.934290657032, 5341.092014357569, 5485.561155107582, 5713.457221563945, 6090.939090609126, 6378.501052252717, 6458.806235812839, 6468.482521398277, 6468.514861385415, 6469.611823290524, 6560.376764207393, 5223.712695801767, 5418.566514678895, 5730.5129064030025, 5881.206037184926, 6273.717682704198, 5267.9736601316745, 5638.5489160867855, 6100.0, 6129.816105516842, 6161.675239823459, 6152.616061166251, 6490.7343068103155, 6525.445337146396, 6526.727288178759, 6527.608343499405, 3298.5711857458377, 3350.9444524974556, 3715.2314608961365, 3716.2280285509687, 3785.5268091488565, 4737.034130835665, 5367.926414717151, 5944.554158438221, 6153.569250152446, 6234.781782637768, 5432.9185062223305, 5654.6324488908085, 5896.439838215363, 5909.52723782105, 5962.493188079062, 5009.9248511271635, 5784.82645520635, 5796.76812927495, 5814.76740930358, 5844.883102337844, 4975.208924787146, 5019.0, 5728.162131372074, 5733.426657334233, 5767.7404516796005, 4942.912559236962, 5461.771573351817, 5773.566982476415, 5905.734241959036, 5937.763918664252, 4768.856934291976, 5738.9139162913425, 5740.856478588069, 5741.569382296261, 5760.308762948473], "value": 4218.281493693534, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.3987321220718, 701.0990876723412, 702.5363260248236, 760.1828034862524, 799.604195923018, 12944.546940857072, 15305.387784488606, 15420.0, 15600.0, 15995.440159594402, 14476.131432114054, 14582.05216660919, 14590.562283131496, 14622.926885365585, 14659.193744344057, 662.1259936883316, 829.7966998085462, 829.929455996241, 857.6355049104137, 877.6357811508217, 815.4984684419069, 851.0340763233712, 864.9394542382036, 867.6659486097866, 870.4559650218613, 909.195362104539, 910.5401772105077, 910.8770316007353, 915.555955361649, 918.8300164469562, 857.6226460357454, 878.89892662344, 904.1997831918734, 909.1590278991972, 912.6440688131634, 668.0913957018452, 673.229152620252, 678.0439580191905, 681.1961884975726, 683.3576438148125, 13996.72006559863, 14728.410863565445, 14841.925790371088, 14844.851551484455, 14863.702725945528, 12141.813994985809, 13987.370568324348, 14420.278986050656, 14439.494617688435, 14467.0, 11839.408029598486, 11909.583164589198, 11914.285142891455, 11975.700607484796, 12083.0, 2885.6825749167574, 3726.3851464508325, 3863.2466668999555, 3976.1053762903402, 3997.900052498682, 116793.83206167915, 117364.0, 117643.82356176415, 118688.40655796752, 119765.0, 10681.946590267155, 10973.945130274298, 10993.395363254938, 11055.447227638586, 11206.943965280121, 22776.31671049871, 22990.42523936915, 23043.539129217323, 23049.654255186186, 23109.0, 7880.0, 8019.15798841125, 8080.878786818203, 8102.270795628422, 8269.793255168668, 4408.977955110204, 4432.800523976429, 4497.550244975476, 4512.616427603625, 4652.278896771006, 3523.5771707395274, 3576.3204991051616, 3592.694620957248, 3634.1459756957092, 3644.3804553225486, 642.720416618763, 649.8472858878159, 650.6616559389091, 651.5146216069058, 651.7588492257895, 613.9079138129227, 655.3190225649458, 656.2813718977711, 660.5376236634376, 677.7612080796258, 8982.014981685097, 9187.770305742277, 9517.095875891779, 9519.0, 9541.952290238505, 487.5319693094669, 616.9635013177905, 648.7437462202404, 649.1528555235369, 696.5194016128855, 4285.7000009999, 4427.671460834433, 4447.577480139382, 4627.5603817637275, 4678.578927896506, 134644.0, 136691.0, 137907.0, 138674.30662846824, 140234.0, 112230.43884780524, 124309.0, 127012.0, 127113.0, 130411.34794325968, 95540.0, 102971.0, 107277.0, 108875.0, 115911.0, 9645.0, 9701.9029809701, 9812.803743924938, 9856.901430985885, 9950.950245248727, 38973.805130974164, 50069.74965125151, 59779.1033134512, 63417.04874426979, 63452.68273658602, 82051.58974205091, 87531.0, 88092.0, 88645.11354886627, 90142.09857901337, 12566.492020957177, 12622.810657839957, 12991.220526768424, 13047.739045219232, 13199.67000824987, 14557.490487832874, 14617.342219600356, 14673.771770210536, 14953.850461495245, 15305.6173595661, 13573.1856088635, 13595.32023398846, 13600.115992460458, 13789.51736689211, 14043.08719933201, 13629.522966696119, 13759.312034398039, 13772.237926563603, 13780.379882905092, 13990.230537320545, 11755.94271002554, 12095.455704493143, 12431.37843107859, 12685.112042156961, 12821.935890320488, 8426.778117173099, 9397.201237894582, 10249.180065594586, 10372.896271037494, 10635.080395258228, 8919.331050171393, 9043.18611324984, 10230.744231394274, 11432.14258930567, 11445.942770286429], "value": 7722.542468708586, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1563.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 118.0, 116.0, 112.0, 115.0, 115.0, 125.0, 165.0, 116.0, 132.0], "value": 122.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 139.0, 159.0, 111.0, 131.0, 129.0, 109.0, 157.0, 112.0], "value": 130.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 250.0, 210.0, 254.0, 275.0, 202.0, 198.0, 193.0, 196.0, 193.0], "value": 217.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 79.0, 75.0, 141.0, 56.0, 57.0, 59.0, 64.0, 130.0, 56.0], "value": 78.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 109.0, 68.0, 71.0, 93.0, 111.0, 68.0, 74.0, 112.0], "value": 84.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 89.0, 87.0, 91.0, 86.0, 89.0, 87.0, 89.0, 86.0], "value": 88.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 142.0, 96.0, 133.0, 117.0, 94.0, 96.0, 96.0, 95.0, 95.0], "value": 111.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 98.0, 91.0, 98.0, 95.0, 145.0, 144.0, 91.0, 92.0, 92.0], "value": 103.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 56.0, 57.0, 58.0, 58.0, 57.0, 63.0, 63.0], "value": 58.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 71.0, 74.0, 83.0, 84.0, 105.0, 71.0, 74.0, 72.0], "value": 77.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 107.0, 100.0, 86.0, 88.0, 87.0, 85.0, 90.0, 90.0, 90.0], "value": 91.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 142.0, 150.0, 155.0, 144.0, 139.0, 154.0, 159.0, 191.0, 151.0], "value": 153.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 244.0, 181.0, 174.0, 173.0, 170.0, 172.0, 171.0, 169.0, 172.0], "value": 179.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 67.0, 67.0, 66.0, 69.0, 68.0, 67.0, 70.0], "value": 67.7, "unit": "score"}]}]} 23:08:39 INFO - Return code: 0 23:08:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:08:39 ERROR - # TBPL SUCCESS # 23:08:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:08:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:08:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:08:39 INFO - 2016-08-31 23:08:39.931 screenresolution[1666:11193] starting screenresolution argv=screenresolution get 23:08:39 INFO - 2016-08-31 23:08:39.934 screenresolution[1666:11193] Display 0: 1600x1200x32@60 23:08:39 INFO - 2016-08-31 23:08:39.940 screenresolution[1667:11196] starting screenresolution argv=screenresolution list 23:08:39 INFO - Available Modes on Display 0 23:08:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:08:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:08:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:08:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:08:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:08:40 INFO - Intel Iris: 23:08:40 INFO - Chipset Model: Intel Iris 23:08:40 INFO - Type: GPU 23:08:40 INFO - Bus: Built-In 23:08:40 INFO - VRAM (Dynamic, Max): 1536 MB 23:08:40 INFO - Vendor: Intel (0x8086) 23:08:40 INFO - Device ID: 0x0a2e 23:08:40 INFO - Revision ID: 0x0009 23:08:40 INFO - Displays: 23:08:40 INFO - Display: 23:08:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:08:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:08:40 INFO - Display Serial Number: 1600x1200 60 23:08:40 INFO - Main Display: Yes 23:08:40 INFO - Mirror: Off 23:08:40 INFO - Online: Yes 23:08:40 INFO - Rotation: Supported 23:08:40 INFO - Return code: 0 23:08:40 INFO - Running post-action listener: _resource_record_post_action 23:08:40 INFO - [mozharness: 2016-09-01 06:08:40.176562Z] Finished run-tests step (success) 23:08:40 INFO - Running post-run listener: _resource_record_post_run 23:08:40 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 31812608; Write bytes: 708199424; Read time: 218; Write time: 1979 23:08:40 INFO - TinderboxPrint: CPU usage
54.6% 23:08:40 INFO - TinderboxPrint: I/O read bytes / time
31,812,608 / 218 23:08:40 INFO - TinderboxPrint: I/O write bytes / time
708,199,424 / 1,979 23:08:40 INFO - TinderboxPrint: CPU idle
2,195.3 (45.4%) 23:08:40 INFO - TinderboxPrint: CPU system
980.4 (20.3%) 23:08:40 INFO - TinderboxPrint: CPU user
1,661.9 (34.4%) 23:08:40 INFO - TinderboxPrint: Swap in / out
433,774,592 / 0 23:08:40 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400962048; Write bytes: 395659264; Read time: 29668; Write time: 799 23:08:40 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 29764608; Write bytes: 297851904; Read time: 199; Write time: 1139 23:08:40 INFO - Running post-run listener: _upload_blobber_files 23:08:40 INFO - Blob upload gear active. 23:08:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:08:40 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 23:08:40 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'] 23:08:40 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 23:08:41 INFO - (blobuploader) - INFO - Open directory for files ... 23:08:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:08:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:08:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:42 INFO - (blobuploader) - INFO - Done attempting. 23:08:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:08:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:08:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:43 INFO - (blobuploader) - INFO - Done attempting. 23:08:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:08:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:08:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:45 INFO - (blobuploader) - INFO - Done attempting. 23:08:45 INFO - (blobuploader) - INFO - Iteration through files over. 23:08:45 INFO - Return code: 0 23:08:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:08:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:08:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b287e27eeb48d5e2db18ff17b2d49c7502bc626d7bbe35584f4998cf75bd6ac5e32625a622cbf1dae18b1a580753fe7fe1b7f315ebe36e95c757b17ab3e672a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4f3546b55bcab2c2397b897c050322ffe680f593c61695b73f794d7490fbe788811d62579ecc2ba228047d7063cdd21d2ca511cd78049a76d63fcab25f9d50d9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63ef7fc535de81306f50a80e896fa6b1e747469afc36cdf5f398312d5edf1a8f840583e9fdb1698fdd18dbd0fc09b9b7e059d230f447e6f4b0315c83c5758119"} 23:08:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:08:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:08:45 INFO - Contents: 23:08:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b287e27eeb48d5e2db18ff17b2d49c7502bc626d7bbe35584f4998cf75bd6ac5e32625a622cbf1dae18b1a580753fe7fe1b7f315ebe36e95c757b17ab3e672a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4f3546b55bcab2c2397b897c050322ffe680f593c61695b73f794d7490fbe788811d62579ecc2ba228047d7063cdd21d2ca511cd78049a76d63fcab25f9d50d9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63ef7fc535de81306f50a80e896fa6b1e747469afc36cdf5f398312d5edf1a8f840583e9fdb1698fdd18dbd0fc09b9b7e059d230f447e6f4b0315c83c5758119"} 23:08:45 INFO - Running post-run listener: copy_logs_to_upload_dir 23:08:45 INFO - Copying logs to upload dir... 23:08:45 INFO - mkdir: /builds/slave/test/build/upload/logs 23:08:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.954131 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-08-31 23:08:45.673438) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 23:08:45.676774) ========= 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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b287e27eeb48d5e2db18ff17b2d49c7502bc626d7bbe35584f4998cf75bd6ac5e32625a622cbf1dae18b1a580753fe7fe1b7f315ebe36e95c757b17ab3e672a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4f3546b55bcab2c2397b897c050322ffe680f593c61695b73f794d7490fbe788811d62579ecc2ba228047d7063cdd21d2ca511cd78049a76d63fcab25f9d50d9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63ef7fc535de81306f50a80e896fa6b1e747469afc36cdf5f398312d5edf1a8f840583e9fdb1698fdd18dbd0fc09b9b7e059d230f447e6f4b0315c83c5758119"} 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.011150 build_url: 'https://queue.taskcluster.net/v1/task/TDgP0PXwR16gSdWPc37_RQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b287e27eeb48d5e2db18ff17b2d49c7502bc626d7bbe35584f4998cf75bd6ac5e32625a622cbf1dae18b1a580753fe7fe1b7f315ebe36e95c757b17ab3e672a4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4f3546b55bcab2c2397b897c050322ffe680f593c61695b73f794d7490fbe788811d62579ecc2ba228047d7063cdd21d2ca511cd78049a76d63fcab25f9d50d9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63ef7fc535de81306f50a80e896fa6b1e747469afc36cdf5f398312d5edf1a8f840583e9fdb1698fdd18dbd0fc09b9b7e059d230f447e6f4b0315c83c5758119"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 23:08:45.709778) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 23:08:45.710313) ========= 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.tK7QyIqn8h/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.KEf30HY8Bu/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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.005274 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 23:08:45.760494) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 23:08:45.760808) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 23:08:45.761138) ========= ========= Total master_lag: 0.18 =========