builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0066 starttime: 1471282056.97 results: success (0) buildid: 20160815090438 builduid: dabe91a012bb4b23a7005650fce9654f revision: e3c089287bf6092ac4dc6ea9e42a0273520f214c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 10:27:36.971730) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 10:27:36.972155) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 10:27:36.972498) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 10:27:37.093789) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 10:27:37.094049) ========= 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.oKNCgV4vFf/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.t7NdtqAs7A/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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-15 10:27:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 41.1M=0s 2016-08-15 10:27:37 (41.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101446 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 10:27:37.242647) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 10:27:37.242961) ========= 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.oKNCgV4vFf/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.t7NdtqAs7A/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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.020463 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 10:27:37.324533) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-15 10:27:37.324848) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev e3c089287bf6092ac4dc6ea9e42a0273520f214c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev e3c089287bf6092ac4dc6ea9e42a0273520f214c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oKNCgV4vFf/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.t7NdtqAs7A/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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-15 10:27:37,398 truncating revision to first 12 chars 2016-08-15 10:27:37,398 Setting DEBUG logging. 2016-08-15 10:27:37,398 attempt 1/10 2016-08-15 10:27:37,398 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/e3c089287bf6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-15 10:27:38,914 unpacking tar archive at: autoland-e3c089287bf6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.768472 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-15 10:27:39.115257) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 10:27:39.115526) ========= script_repo_revision: e3c089287bf6092ac4dc6ea9e42a0273520f214c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 10:27:39.115891) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 10:27:39.116151) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 10:27:39.135052) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-15 10:27:39.135378) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oKNCgV4vFf/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.t7NdtqAs7A/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 10:27:39 INFO - MultiFileLogger online at 20160815 10:27:39 in /builds/slave/test 10:27:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 10:27:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:27:39 INFO - {'append_to_log': False, 10:27:39 INFO - 'base_work_dir': '/builds/slave/test', 10:27:39 INFO - 'blob_upload_branch': 'Autoland', 10:27:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:27:39 INFO - 'branch': 'Autoland', 10:27:39 INFO - 'buildbot_json_path': 'buildprops.json', 10:27:39 INFO - 'config_files': ('talos/mac_config.py',), 10:27:39 INFO - 'default_actions': ('clobber', 10:27:39 INFO - 'read-buildbot-config', 10:27:39 INFO - 'download-and-extract', 10:27:39 INFO - 'populate-webroot', 10:27:39 INFO - 'create-virtualenv', 10:27:39 INFO - 'install', 10:27:39 INFO - 'run-tests'), 10:27:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:27:39 INFO - 'download_minidump_stackwalk': True, 10:27:39 INFO - 'download_symbols': 'ondemand', 10:27:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:27:39 INFO - 'tooltool.py': '/tools/tooltool.py', 10:27:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:27:39 INFO - '/tools/misc-python/virtualenv.py')}, 10:27:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:27:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:27:39 INFO - 'installer_path': 'installer.exe', 10:27:39 INFO - 'log_level': 'info', 10:27:39 INFO - 'log_name': 'talos', 10:27:39 INFO - 'log_to_console': True, 10:27:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:27:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:27:39 INFO - 'opt_config_files': (), 10:27:39 INFO - 'pip_index': False, 10:27:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:27:39 INFO - 'cmd': ('bash', 10:27:39 INFO - '-c', 10:27:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:27:39 INFO - 'enabled': True, 10:27:39 INFO - 'halt_on_failure': False, 10:27:39 INFO - 'name': 'check_screen_resolution'},), 10:27:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:27:39 INFO - 'cmd': ('bash', 10:27:39 INFO - '-c', 10:27:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:27:39 INFO - 'enabled': True, 10:27:39 INFO - 'halt_on_failure': False, 10:27:39 INFO - 'name': 'check_screen_resolution'},), 10:27:39 INFO - 'run_cmd_checks_enabled': True, 10:27:39 INFO - 'sps_profile': False, 10:27:39 INFO - 'sps_profile_interval': 0, 10:27:39 INFO - 'suite': 'dromaeojs', 10:27:39 INFO - 'system_bits': '32', 10:27:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:27:39 INFO - 'title': 't-yosemite-r7-0066', 10:27:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:27:39 INFO - 'use_talos_json': True, 10:27:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:27:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:27:39 INFO - 'work_dir': 'build'} 10:27:39 INFO - [mozharness: 2016-08-15 17:27:39.325963Z] Running clobber step. 10:27:39 INFO - Running pre-action listener: _resource_record_pre_action 10:27:39 INFO - Running main action method: clobber 10:27:39 INFO - rmtree: /builds/slave/test/build 10:27:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:27:40 INFO - Running post-action listener: _resource_record_post_action 10:27:40 INFO - [mozharness: 2016-08-15 17:27:40.740431Z] Finished clobber step (success) 10:27:40 INFO - [mozharness: 2016-08-15 17:27:40.740619Z] Running read-buildbot-config step. 10:27:40 INFO - Running pre-action listener: _resource_record_pre_action 10:27:40 INFO - Running main action method: read_buildbot_config 10:27:40 INFO - Using buildbot properties: 10:27:40 INFO - { 10:27:40 INFO - "project": "", 10:27:40 INFO - "product": "firefox", 10:27:40 INFO - "script_repo_revision": "production", 10:27:40 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 10:27:40 INFO - "repository": "", 10:27:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 10:27:40 INFO - "buildid": "20160815090438", 10:27:40 INFO - "pgo_build": "False", 10:27:40 INFO - "basedir": "/builds/slave/test", 10:27:40 INFO - "buildnumber": 115, 10:27:40 INFO - "slavename": "t-yosemite-r7-0066", 10:27:40 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:27:40 INFO - "platform": "yosemite_r7", 10:27:40 INFO - "scheduler": "tests-autoland-macosx64-talos", 10:27:40 INFO - "branch": "autoland", 10:27:40 INFO - "repo_path": "integration/autoland", 10:27:40 INFO - "revision": "e3c089287bf6092ac4dc6ea9e42a0273520f214c", 10:27:40 INFO - "stage_platform": "macosx64", 10:27:40 INFO - "builduid": "dabe91a012bb4b23a7005650fce9654f", 10:27:40 INFO - "slavebuilddir": "test" 10:27:40 INFO - } 10:27:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:27:40 INFO - Running post-action listener: _resource_record_post_action 10:27:40 INFO - [mozharness: 2016-08-15 17:27:40.743935Z] Finished read-buildbot-config step (success) 10:27:40 INFO - [mozharness: 2016-08-15 17:27:40.744054Z] Running download-and-extract step. 10:27:40 INFO - Running pre-action listener: _resource_record_pre_action 10:27:40 INFO - Running main action method: download_and_extract 10:27:40 INFO - mkdir: /builds/slave/test/build/tests 10:27:40 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')]} 10:27:40 INFO - trying https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:27:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/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 10:27:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/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 10:27:41 INFO - Downloaded 1199 bytes. 10:27:41 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:27:41 INFO - Using the following test package requirements: 10:27:41 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:27:41 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:27:41 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:27:41 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:27:41 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:27:41 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:27:41 INFO - u'jsshell-mac.zip'], 10:27:41 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:27:41 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:27:41 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:27:41 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:27:41 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:27:41 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:27:41 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:27:41 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:27:41 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:27:41 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:27:41 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:27:41 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:27:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:27:41 INFO - trying https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:27:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/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 10:27:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/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 10:27:43 INFO - Downloaded 16331501 bytes. 10:27:43 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 10:27:50 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 10:27:50 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')]} 10:27:50 INFO - trying https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:27:50 INFO - Downloading https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/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 10:27:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/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 10:27:51 INFO - Downloaded 16331501 bytes. 10:27:51 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 10:27:57 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')]} 10:27:57 INFO - trying https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:27:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/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 10:27:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/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 10:27:59 INFO - Downloaded 14413719 bytes. 10:27:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 10:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:00 INFO - trying https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:28:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:28:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/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 10:28:03 INFO - Downloaded 111028047 bytes. 10:28:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:28:03 INFO - mkdir: /builds/slave/test/properties 10:28:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:28:03 INFO - Writing to file /builds/slave/test/properties/build_url 10:28:03 INFO - Contents: 10:28:03 INFO - build_url:https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:28:04 INFO - Running post-action listener: _resource_record_post_action 10:28:04 INFO - Running post-action listener: set_extra_try_arguments 10:28:04 INFO - [mozharness: 2016-08-15 17:28:04.374253Z] Finished download-and-extract step (success) 10:28:04 INFO - [mozharness: 2016-08-15 17:28:04.374406Z] Running populate-webroot step. 10:28:04 INFO - Running pre-action listener: _resource_record_pre_action 10:28:04 INFO - Running main action method: populate_webroot 10:28:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:28:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:28:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:28:04 INFO - '--tppagecycles', 10:28:04 INFO - '7'], 10:28:04 INFO - 'tests': ['tsvgm']}}, 10:28:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:28:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:28:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:28:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:28:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:28:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:28:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:04 INFO - 'talos_options': ['--disable-e10s'], 10:28:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:28:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:28:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:04 INFO - 'talos_options': ['--disable-e10s'], 10:28:04 INFO - 'tests': ['damp', 'tps']}, 10:28:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:04 INFO - 'tests': ['damp', 'tps']}, 10:28:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:28:04 INFO - 'tests': ['dromaeo_dom']}, 10:28:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:28:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:28:04 INFO - 'tests': ['basic_compositor_video']}, 10:28:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:28:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:28:04 INFO - 'tests': ['a11yr', 10:28:04 INFO - 'ts_paint', 10:28:04 INFO - 'tpaint', 10:28:04 INFO - 'sessionrestore', 10:28:04 INFO - 'sessionrestore_no_auto_restore', 10:28:04 INFO - 'tabpaint']}, 10:28:04 INFO - 'other-e10s': {'tests': ['a11yr', 10:28:04 INFO - 'ts_paint', 10:28:04 INFO - 'tpaint', 10:28:04 INFO - 'sessionrestore', 10:28:04 INFO - 'sessionrestore_no_auto_restore', 10:28:04 INFO - 'tabpaint']}, 10:28:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:28:04 INFO - 'ts_paint', 10:28:04 INFO - 'tpaint', 10:28:04 INFO - 'sessionrestore', 10:28:04 INFO - 'sessionrestore_no_auto_restore', 10:28:04 INFO - 'tabpaint']}, 10:28:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:28:04 INFO - 'ts_paint', 10:28:04 INFO - 'tpaint', 10:28:04 INFO - 'sessionrestore', 10:28:04 INFO - 'sessionrestore_no_auto_restore', 10:28:04 INFO - 'tabpaint']}, 10:28:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:28:04 INFO - 'tests': ['a11yr', 10:28:04 INFO - 'ts_paint', 10:28:04 INFO - 'tpaint', 10:28:04 INFO - 'sessionrestore', 10:28:04 INFO - 'sessionrestore_no_auto_restore', 10:28:04 INFO - 'tabpaint']}, 10:28:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:28:04 INFO - 'tests': ['a11yr', 10:28:04 INFO - 'ts_paint', 10:28:04 INFO - 'tpaint', 10:28:04 INFO - 'sessionrestore', 10:28:04 INFO - 'sessionrestore_no_auto_restore', 10:28:04 INFO - 'tabpaint']}, 10:28:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:28:04 INFO - 'tests': ['tsvgx', 10:28:04 INFO - 'tsvgr_opacity', 10:28:04 INFO - 'tart', 10:28:04 INFO - 'tscrollx', 10:28:04 INFO - 'cart']}, 10:28:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:28:04 INFO - 'tsvgr_opacity', 10:28:04 INFO - 'tart', 10:28:04 INFO - 'tscrollx', 10:28:04 INFO - 'cart']}, 10:28:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:04 INFO - 'talos_options': ['--disable-e10s'], 10:28:04 INFO - 'tests': ['tp5o']}, 10:28:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:04 INFO - 'tests': ['tp5o']}, 10:28:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:04 INFO - 'talos_options': ['--disable-e10s', 10:28:04 INFO - '--xperf_path', 10:28:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:28:04 INFO - 'tests': ['tp5n']}, 10:28:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:04 INFO - 'talos_options': ['--xperf_path', 10:28:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:28:04 INFO - 'tests': ['tp5n']}}, 10:28:04 INFO - 'talos.zip': {'path': '', 10:28:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:28:04 INFO - Running post-action listener: _resource_record_post_action 10:28:04 INFO - [mozharness: 2016-08-15 17:28:04.393382Z] Finished populate-webroot step (success) 10:28:04 INFO - [mozharness: 2016-08-15 17:28:04.393497Z] Running create-virtualenv step. 10:28:04 INFO - Running pre-action listener: _resource_record_pre_action 10:28:04 INFO - Running main action method: create_virtualenv 10:28:04 INFO - Creating virtualenv /builds/slave/test/build/venv 10:28:04 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 10:28:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:28:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:28:04 INFO - Using real prefix '/tools/python27' 10:28:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:28:05 INFO - Installing distribute.............................................................................................................................................................................................done. 10:28:08 INFO - Installing pip.................done. 10:28:08 INFO - Return code: 0 10:28:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:28: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')]} 10:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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')]} 10:28:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108afba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b90618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea30cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5fd30>, '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 0x1087c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a57b20>, '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.oKNCgV4vFf/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.t7NdtqAs7A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 10:28: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', 'pip>=1.5'] in /builds/slave/test/build 10:28: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 pip>=1.5 10:28:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKNCgV4vFf/Render', 10:28:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:08 INFO - 'HOME': '/Users/cltbld', 10:28:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:08 INFO - 'LOGNAME': 'cltbld', 10:28:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:08 INFO - 'MOZ_NO_REMOTE': '1', 10:28:08 INFO - 'NO_EM_RESTART': '1', 10:28:08 INFO - 'PAGER': '/bin/cat', 10:28:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:08 INFO - 'PWD': '/builds/slave/test', 10:28:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:08 INFO - 'SHELL': '/bin/bash', 10:28:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7NdtqAs7A/Listeners', 10:28:08 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 10:28:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:08 INFO - 'USER': 'cltbld', 10:28:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:08 INFO - 'XPC_FLAGS': '0x0', 10:28:08 INFO - 'XPC_SERVICE_NAME': '0', 10:28:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:08 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 10:28:08 INFO - Cleaning up... 10:28:08 INFO - Return code: 0 10:28:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:28: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')]} 10:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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')]} 10:28:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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', '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 0x108afba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b90618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea30cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5fd30>, '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 0x1087c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a57b20>, '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.oKNCgV4vFf/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.t7NdtqAs7A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 10:28: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', 'psutil>=3.1.1'] in /builds/slave/test/build 10:28: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 psutil>=3.1.1 10:28:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKNCgV4vFf/Render', 10:28:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:08 INFO - 'HOME': '/Users/cltbld', 10:28:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:08 INFO - 'LOGNAME': 'cltbld', 10:28:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:08 INFO - 'MOZ_NO_REMOTE': '1', 10:28:08 INFO - 'NO_EM_RESTART': '1', 10:28:08 INFO - 'PAGER': '/bin/cat', 10:28:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:08 INFO - 'PWD': '/builds/slave/test', 10:28:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:08 INFO - 'SHELL': '/bin/bash', 10:28:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7NdtqAs7A/Listeners', 10:28:08 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 10:28:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:08 INFO - 'USER': 'cltbld', 10:28:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:08 INFO - 'XPC_FLAGS': '0x0', 10:28:08 INFO - 'XPC_SERVICE_NAME': '0', 10:28:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:08 INFO - Downloading/unpacking psutil>=3.1.1 10:28:08 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 10:28:08 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 10:28:08 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 10:28:08 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 10:28:08 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 10:28:08 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 10:28:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:28:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:28:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:28:12 INFO - Installing collected packages: psutil 10:28:12 INFO - Running setup.py install for psutil 10:28:12 INFO - building 'psutil._psutil_osx' extension 10:28:12 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 10:28:12 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 10:28:12 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 10:28:12 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 10:28:12 INFO - building 'psutil._psutil_posix' extension 10:28:12 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 10:28:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:28:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:28:12 INFO - ^ 10:28:12 INFO - 1 warning generated. 10:28:12 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 10:28:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:28:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:28:12 INFO - Successfully installed psutil 10:28:12 INFO - Cleaning up... 10:28:12 INFO - Return code: 0 10:28:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:28:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:12 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 0x108afba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b90618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea30cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5fd30>, '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 0x1087c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a57b20>, '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.oKNCgV4vFf/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.t7NdtqAs7A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 10:28:12 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 10:28:12 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 10:28:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKNCgV4vFf/Render', 10:28:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:12 INFO - 'HOME': '/Users/cltbld', 10:28:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:12 INFO - 'LOGNAME': 'cltbld', 10:28:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:12 INFO - 'MOZ_NO_REMOTE': '1', 10:28:12 INFO - 'NO_EM_RESTART': '1', 10:28:12 INFO - 'PAGER': '/bin/cat', 10:28:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:12 INFO - 'PWD': '/builds/slave/test', 10:28:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:12 INFO - 'SHELL': '/bin/bash', 10:28:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7NdtqAs7A/Listeners', 10:28:12 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 10:28:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:12 INFO - 'USER': 'cltbld', 10:28:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:12 INFO - 'XPC_FLAGS': '0x0', 10:28:12 INFO - 'XPC_SERVICE_NAME': '0', 10:28:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:28:13 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 10:28:13 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 10:28:13 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 10:28:13 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 10:28:13 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 10:28:13 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 10:28:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:28:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:28:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:28:15 INFO - Installing collected packages: mozsystemmonitor 10:28:15 INFO - Running setup.py install for mozsystemmonitor 10:28:15 INFO - Successfully installed mozsystemmonitor 10:28:15 INFO - Cleaning up... 10:28:15 INFO - Return code: 0 10:28:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:28: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')]} 10:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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')]} 10:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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', '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 0x108afba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b90618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea30cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5fd30>, '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 0x1087c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a57b20>, '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.oKNCgV4vFf/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.t7NdtqAs7A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 10:28: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:28: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 jsonschema==2.5.1 10:28:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKNCgV4vFf/Render', 10:28:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:15 INFO - 'HOME': '/Users/cltbld', 10:28:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:15 INFO - 'LOGNAME': 'cltbld', 10:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:15 INFO - 'MOZ_NO_REMOTE': '1', 10:28:15 INFO - 'NO_EM_RESTART': '1', 10:28:15 INFO - 'PAGER': '/bin/cat', 10:28:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:15 INFO - 'PWD': '/builds/slave/test', 10:28:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:15 INFO - 'SHELL': '/bin/bash', 10:28:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7NdtqAs7A/Listeners', 10:28:15 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 10:28:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:15 INFO - 'USER': 'cltbld', 10:28:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:15 INFO - 'XPC_FLAGS': '0x0', 10:28:15 INFO - 'XPC_SERVICE_NAME': '0', 10:28:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:16 INFO - Downloading/unpacking jsonschema==2.5.1 10:28:16 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 10:28:16 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 10:28:16 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 10:28:16 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 10:28:16 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 10:28:16 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 10:28:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:28:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:28: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 10:28: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 10:28: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 10:28: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 10:28: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 10:28: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 10:28:18 INFO - Downloading functools32-3.2.3-2.tar.gz 10:28:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:28:18 INFO - warning: no files found matching '*.txt' 10:28:18 INFO - no previously-included directories found matching 'build' 10:28:18 INFO - no previously-included directories found matching 'dist' 10:28:18 INFO - no previously-included directories found matching '.git*' 10:28:18 INFO - Installing collected packages: jsonschema, functools32 10:28:19 INFO - Running setup.py install for functools32 10:28:19 INFO - warning: no files found matching '*.txt' 10:28:19 INFO - no previously-included directories found matching 'build' 10:28:19 INFO - no previously-included directories found matching 'dist' 10:28:19 INFO - no previously-included directories found matching '.git*' 10:28:19 INFO - Successfully installed jsonschema functools32 10:28:19 INFO - Cleaning up... 10:28:19 INFO - Return code: 0 10:28:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:28:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108afba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b90618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea30cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5fd30>, '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 0x1087c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a57b20>, '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.oKNCgV4vFf/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.t7NdtqAs7A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 10:28:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:28:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:28:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKNCgV4vFf/Render', 10:28:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:19 INFO - 'HOME': '/Users/cltbld', 10:28:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:19 INFO - 'LOGNAME': 'cltbld', 10:28:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:19 INFO - 'MOZ_NO_REMOTE': '1', 10:28:19 INFO - 'NO_EM_RESTART': '1', 10:28:19 INFO - 'PAGER': '/bin/cat', 10:28:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:19 INFO - 'PWD': '/builds/slave/test', 10:28:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:19 INFO - 'SHELL': '/bin/bash', 10:28:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7NdtqAs7A/Listeners', 10:28:19 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 10:28:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:19 INFO - 'USER': 'cltbld', 10:28:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:19 INFO - 'XPC_FLAGS': '0x0', 10:28:19 INFO - 'XPC_SERVICE_NAME': '0', 10:28:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:28:19 INFO - Cleaning up... 10:28:19 INFO - Return code: 0 10:28:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:28:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108afba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b90618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea30cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5fd30>, '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 0x1087c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a57b20>, '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.oKNCgV4vFf/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.t7NdtqAs7A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 10:28:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:28:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:28:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKNCgV4vFf/Render', 10:28:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:19 INFO - 'HOME': '/Users/cltbld', 10:28:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:19 INFO - 'LOGNAME': 'cltbld', 10:28:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:19 INFO - 'MOZ_NO_REMOTE': '1', 10:28:19 INFO - 'NO_EM_RESTART': '1', 10:28:19 INFO - 'PAGER': '/bin/cat', 10:28:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:19 INFO - 'PWD': '/builds/slave/test', 10:28:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:19 INFO - 'SHELL': '/bin/bash', 10:28:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7NdtqAs7A/Listeners', 10:28:19 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 10:28:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:19 INFO - 'USER': 'cltbld', 10:28:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:19 INFO - 'XPC_FLAGS': '0x0', 10:28:19 INFO - 'XPC_SERVICE_NAME': '0', 10:28:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:19 INFO - Downloading/unpacking blobuploader==1.2.4 10:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:22 INFO - Downloading blobuploader-1.2.4.tar.gz 10:28:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:28:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:28:22 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 10:28:22 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 10:28:22 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 10:28:22 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 10:28:22 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 10:28:22 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 10:28:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:28:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:28:22 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 10:28:22 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 10:28:22 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 10:28:22 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 10:28:22 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 10:28:22 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 10:28:23 INFO - Downloading docopt-0.6.1.tar.gz 10:28:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:28:23 INFO - Installing collected packages: blobuploader, requests, docopt 10:28:23 INFO - Running setup.py install for blobuploader 10:28:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:28:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:28:23 INFO - Running setup.py install for requests 10:28:24 INFO - Running setup.py install for docopt 10:28:24 INFO - Successfully installed blobuploader requests docopt 10:28:24 INFO - Cleaning up... 10:28:24 INFO - Return code: 0 10:28:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:28: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')]} 10:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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')]} 10:28:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:24 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 0x108afba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b90618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea30cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5fd30>, '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 0x1087c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a57b20>, '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.oKNCgV4vFf/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.t7NdtqAs7A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 10:28:24 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 10:28:24 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 10:28:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKNCgV4vFf/Render', 10:28:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:24 INFO - 'HOME': '/Users/cltbld', 10:28:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:24 INFO - 'LOGNAME': 'cltbld', 10:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:24 INFO - 'MOZ_NO_REMOTE': '1', 10:28:24 INFO - 'NO_EM_RESTART': '1', 10:28:24 INFO - 'PAGER': '/bin/cat', 10:28:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:24 INFO - 'PWD': '/builds/slave/test', 10:28:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:24 INFO - 'SHELL': '/bin/bash', 10:28:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7NdtqAs7A/Listeners', 10:28:24 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 10:28:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:24 INFO - 'USER': 'cltbld', 10:28:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:24 INFO - 'XPC_FLAGS': '0x0', 10:28:24 INFO - 'XPC_SERVICE_NAME': '0', 10:28:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:28:24 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-v4p4qY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:28:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:28:24 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-PXyHyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:28:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:28:24 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-EgwUzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:28:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:28:24 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-zMVDw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:28:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-zsljBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:28:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-V8HMrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:28:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-v5tRXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:28:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-tJes1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:28:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-5KKevg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:28:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-YSSZ9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:28:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-vSINJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:28:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-nKyCrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:28:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-tpoCj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:28:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-DBOR0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:28:26 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-J4Q8Bg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:28:26 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-wZYtNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:28:26 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-b8bfha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:28:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:28:26 INFO - Running setup.py install for manifestparser 10:28:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:28:26 INFO - Running setup.py install for mozcrash 10:28:26 INFO - Running setup.py install for mozdebug 10:28:26 INFO - Running setup.py install for mozdevice 10:28:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:28:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:28:26 INFO - Running setup.py install for mozfile 10:28:27 INFO - Running setup.py install for mozhttpd 10:28:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:28:27 INFO - Running setup.py install for mozinfo 10:28:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:28:27 INFO - Running setup.py install for mozInstall 10:28:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:28:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:28:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:28:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:28:27 INFO - Running setup.py install for mozleak 10:28:27 INFO - Running setup.py install for mozlog 10:28:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:28:27 INFO - Running setup.py install for moznetwork 10:28:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:28:28 INFO - Running setup.py install for mozprocess 10:28:28 INFO - Running setup.py install for mozprofile 10:28:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:28:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:28:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:28:28 INFO - Running setup.py install for mozrunner 10:28:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:28:28 INFO - Running setup.py install for mozscreenshot 10:28:28 INFO - Running setup.py install for moztest 10:28:28 INFO - Running setup.py install for mozversion 10:28:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:28:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:28:28 INFO - Cleaning up... 10:28:28 INFO - Return code: 0 10:28:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:28:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:28 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 0x108afba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b90618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea30cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5fd30>, '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 0x1087c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a57b20>, '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.oKNCgV4vFf/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.t7NdtqAs7A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 10:28:28 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 10:28:28 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 10:28:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKNCgV4vFf/Render', 10:28:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:28 INFO - 'HOME': '/Users/cltbld', 10:28:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:28 INFO - 'LOGNAME': 'cltbld', 10:28:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:28 INFO - 'MOZ_NO_REMOTE': '1', 10:28:28 INFO - 'NO_EM_RESTART': '1', 10:28:28 INFO - 'PAGER': '/bin/cat', 10:28:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:28 INFO - 'PWD': '/builds/slave/test', 10:28:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:28 INFO - 'SHELL': '/bin/bash', 10:28:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7NdtqAs7A/Listeners', 10:28:28 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 10:28:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:28 INFO - 'USER': 'cltbld', 10:28:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:28 INFO - 'XPC_FLAGS': '0x0', 10:28:28 INFO - 'XPC_SERVICE_NAME': '0', 10:28:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:28:29 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-x7eGJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:28:29 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)) 10:28:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:28:29 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-AAvFg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:28:29 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)) 10:28:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:28:29 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-eD7nnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:28:29 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)) 10:28:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:28:29 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-MsA41a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:28:29 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)) 10:28:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:28:29 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-xlsyk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:28:29 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)) 10:28:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:28:29 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-foUgzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:28:29 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)) 10:28:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:28:29 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-mPp1vy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:28:29 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)) 10:28:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:28:29 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-B_MLP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:28:30 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)) 10:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:28:30 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-_oNPRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:28:30 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)) 10:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:28:30 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-eum71j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:28:30 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)) 10:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:28:30 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-YwzUtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:28:30 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)) 10:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:28:30 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-aBK_5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:28:30 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)) 10:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:28:30 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-OMS50B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:28:30 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)) 10:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:28:30 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-3qmkX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:28:30 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)) 10:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:28:30 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-TkEDo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:28:30 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)) 10:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:28:30 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-96Ogiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:28:30 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)) 10:28:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:28:30 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-TXLDed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:28:31 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)) 10:28:31 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)) 10:28:31 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)) 10:28:31 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)) 10:28:31 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)) 10:28:31 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)) 10:28:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:28:31 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 10:28:31 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 10:28:31 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 10:28:31 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 10:28:31 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 10:28:31 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 10:28:33 INFO - Downloading blessings-1.6.tar.gz 10:28:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:28:33 INFO - Installing collected packages: blessings 10:28:33 INFO - Running setup.py install for blessings 10:28:33 INFO - Successfully installed blessings 10:28:33 INFO - Cleaning up... 10:28:33 INFO - Return code: 0 10:28:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:28:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:28:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:28:34 INFO - Reading from file tmpfile_stdout 10:28:34 INFO - Current package versions: 10:28:34 INFO - blessings == 1.6 10:28:34 INFO - blobuploader == 1.2.4 10:28:34 INFO - docopt == 0.6.1 10:28:34 INFO - functools32 == 3.2.3-2 10:28:34 INFO - jsonschema == 2.5.1 10:28:34 INFO - manifestparser == 1.1 10:28:34 INFO - mozInstall == 1.12 10:28:34 INFO - mozcrash == 0.17 10:28:34 INFO - mozdebug == 0.1 10:28:34 INFO - mozdevice == 0.48 10:28:34 INFO - mozfile == 1.2 10:28:34 INFO - mozhttpd == 0.7 10:28:34 INFO - mozinfo == 0.9 10:28:34 INFO - mozleak == 0.1 10:28:34 INFO - mozlog == 3.3 10:28:34 INFO - moznetwork == 0.27 10:28:34 INFO - mozprocess == 0.23 10:28:34 INFO - mozprofile == 0.28 10:28:34 INFO - mozrunner == 6.12 10:28:34 INFO - mozscreenshot == 0.1 10:28:34 INFO - mozsystemmonitor == 0.3 10:28:34 INFO - moztest == 0.7 10:28:34 INFO - mozversion == 1.4 10:28:34 INFO - psutil == 3.1.1 10:28:34 INFO - requests == 1.2.3 10:28:34 INFO - wsgiref == 0.1.2 10:28:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:28:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:34 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 0x108afba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b90618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea30cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5fd30>, '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 0x1087c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a57b20>, '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.oKNCgV4vFf/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.t7NdtqAs7A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 10:28:34 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 10:28:34 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 10:28:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKNCgV4vFf/Render', 10:28:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:34 INFO - 'HOME': '/Users/cltbld', 10:28:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:34 INFO - 'LOGNAME': 'cltbld', 10:28:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:34 INFO - 'MOZ_NO_REMOTE': '1', 10:28:34 INFO - 'NO_EM_RESTART': '1', 10:28:34 INFO - 'PAGER': '/bin/cat', 10:28:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:34 INFO - 'PWD': '/builds/slave/test', 10:28:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:34 INFO - 'SHELL': '/bin/bash', 10:28:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7NdtqAs7A/Listeners', 10:28:34 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 10:28:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:34 INFO - 'USER': 'cltbld', 10:28:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:34 INFO - 'XPC_FLAGS': '0x0', 10:28:34 INFO - 'XPC_SERVICE_NAME': '0', 10:28:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:34 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)) 10:28:34 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)) 10:28:34 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)) 10:28:34 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)) 10:28:34 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)) 10:28:34 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)) 10:28:34 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)) 10:28:34 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)) 10:28:34 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)) 10:28:34 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)) 10:28:34 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)) 10:28:34 INFO - Cleaning up... 10:28:34 INFO - Return code: 0 10:28:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:28:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108afba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b90618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea30cb9280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a5fd30>, '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 0x1087c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a57b20>, '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.oKNCgV4vFf/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.t7NdtqAs7A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 10:28:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:28:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:28:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKNCgV4vFf/Render', 10:28:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:34 INFO - 'HOME': '/Users/cltbld', 10:28:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:34 INFO - 'LOGNAME': 'cltbld', 10:28:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:34 INFO - 'MOZ_NO_REMOTE': '1', 10:28:34 INFO - 'NO_EM_RESTART': '1', 10:28:34 INFO - 'PAGER': '/bin/cat', 10:28:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:34 INFO - 'PWD': '/builds/slave/test', 10:28:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:34 INFO - 'SHELL': '/bin/bash', 10:28:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7NdtqAs7A/Listeners', 10:28:34 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 10:28:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:34 INFO - 'USER': 'cltbld', 10:28:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:34 INFO - 'XPC_FLAGS': '0x0', 10:28:34 INFO - 'XPC_SERVICE_NAME': '0', 10:28:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:28:34 INFO - Cleaning up... 10:28:34 INFO - Return code: 0 10:28:34 INFO - Running post-action listener: _resource_record_post_action 10:28:34 INFO - Running post-action listener: _start_resource_monitoring 10:28:34 INFO - Starting resource monitoring. 10:28:34 INFO - [mozharness: 2016-08-15 17:28:34.630268Z] Finished create-virtualenv step (success) 10:28:34 INFO - [mozharness: 2016-08-15 17:28:34.631299Z] Running install step. 10:28:34 INFO - Running pre-action listener: _resource_record_pre_action 10:28:34 INFO - Running main action method: install 10:28:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:28:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:28:34 INFO - Reading from file tmpfile_stdout 10:28:34 INFO - Detecting whether we're running mozinstall >=1.0... 10:28:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:28:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:28:34 INFO - Reading from file tmpfile_stdout 10:28:34 INFO - Output received: 10:28:34 INFO - Usage: mozinstall [options] installer 10:28:34 INFO - Options: 10:28:34 INFO - -h, --help show this help message and exit 10:28:34 INFO - -d DEST, --destination=DEST 10:28:34 INFO - Directory to install application into. [default: 10:28:34 INFO - "/builds/slave/test"] 10:28:34 INFO - --app=APP Application being installed. [default: firefox] 10:28:34 INFO - mkdir: /builds/slave/test/build/application 10:28:34 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'] 10:28:34 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 10:29:09 INFO - Reading from file tmpfile_stdout 10:29:09 INFO - Output received: 10:29:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:29:09 INFO - Running post-action listener: _resource_record_post_action 10:29:09 INFO - [mozharness: 2016-08-15 17:29:09.517239Z] Finished install step (success) 10:29:09 INFO - [mozharness: 2016-08-15 17:29:09.517447Z] Running run-tests step. 10:29:09 INFO - Running pre-action listener: _resource_record_pre_action 10:29:09 INFO - Running main action method: run_tests 10:29:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:29:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:29:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:29:09 INFO - 2016-08-15 10:29:09.532 screenresolution[1917:17749] starting screenresolution argv=screenresolution get 10:29:09 INFO - 2016-08-15 10:29:09.534 screenresolution[1917:17749] Display 0: 1600x1200x32@60 10:29:09 INFO - 2016-08-15 10:29:09.540 screenresolution[1918:17751] starting screenresolution argv=screenresolution list 10:29:09 INFO - Available Modes on Display 0 10:29:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:29:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:29:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:29:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:29:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:29:09 INFO - Intel Iris: 10:29:09 INFO - Chipset Model: Intel Iris 10:29:09 INFO - Type: GPU 10:29:09 INFO - Bus: Built-In 10:29:09 INFO - VRAM (Dynamic, Max): 1536 MB 10:29:09 INFO - Vendor: Intel (0x8086) 10:29:09 INFO - Device ID: 0x0a2e 10:29:09 INFO - Revision ID: 0x0009 10:29:09 INFO - Displays: 10:29:09 INFO - Display: 10:29:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:29:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:29:09 INFO - Display Serial Number: 1600x1200 60 10:29:09 INFO - Main Display: Yes 10:29:09 INFO - Mirror: Off 10:29:09 INFO - Online: Yes 10:29:09 INFO - Rotation: Supported 10:29:09 INFO - Return code: 0 10:29:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:29:09 INFO - Python 2.7.3 10:29:09 INFO - Return code: 0 10:29:09 INFO - grabbing minidump binary from tooltool 10:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:09 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 0x108a5fd30>, '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 0x1087c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a57b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:29:09 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 10:29:09 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 10:29:09 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 10:29:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:29:09 INFO - Return code: 0 10:29:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:29:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:29:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:29:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:29:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:29:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0066 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKNCgV4vFf/Render', 10:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:09 INFO - 'HOME': '/Users/cltbld', 10:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:09 INFO - 'LOGNAME': 'cltbld', 10:29:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:29:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:09 INFO - 'MOZ_NO_REMOTE': '1', 10:29:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:29:09 INFO - 'NO_EM_RESTART': '1', 10:29:09 INFO - 'PAGER': '/bin/cat', 10:29:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:09 INFO - 'PWD': '/builds/slave/test', 10:29:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:09 INFO - 'SHELL': '/bin/bash', 10:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7NdtqAs7A/Listeners', 10:29:09 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 10:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:09 INFO - 'USER': 'cltbld', 10:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:09 INFO - 'XPC_FLAGS': '0x0', 10:29:09 INFO - 'XPC_SERVICE_NAME': '0', 10:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:29:10 INFO - mozversion application_buildid: 20160815090438 10:29:10 INFO - mozversion application_changeset: e3c089287bf6092ac4dc6ea9e42a0273520f214c 10:29:10 INFO - mozversion application_display_name: Nightly 10:29:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:29:10 INFO - mozversion application_name: Firefox 10:29:10 INFO - mozversion application_remotingname: firefox 10:29:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:29:10 INFO - mozversion application_vendor: Mozilla 10:29:10 INFO - mozversion application_version: 51.0a1 10:29:10 INFO - mozversion platform_buildid: 20160815090438 10:29:10 INFO - mozversion platform_changeset: e3c089287bf6092ac4dc6ea9e42a0273520f214c 10:29:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:29:10 INFO - mozversion platform_version: 51.0a1 10:29:10 INFO - using testdate: 1471282150 10:29:10 INFO - actual date: 1471282150 10:29:10 INFO - starting webserver on 'localhost:52570' 10:29:10 INFO - SUITE-START | Running 2 tests 10:29:10 INFO - TEST-START | dromaeo_css 10:29:10 INFO - operating with platform_type : mac_ 10:29:10 INFO - Initialising browser for dromaeo_css test... 10:29:10 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmp6u2c1i/profile http://localhost:52570/getInfo.html) 10:29:12 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 10:29:12 INFO - PROCESS | 1928 | colorDepth:24 10:29:12 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 10:29:12 INFO - PROCESS | 1928 | __metrics 10:29:12 INFO - PROCESS | 1928 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:29:12 INFO - PROCESS | 1928 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:29:13 INFO - TEST-INFO | 1928: exit 0 10:29:13 INFO - Browser initialized. 10:29:13 INFO - Running cycle 1/1 for dromaeo_css test... 10:29:13 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmp6u2c1i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:29:25 INFO - PROCESS | 1930 | RSS: Main: 228507648 10:29:25 INFO - PROCESS | 1930 | 10:30:16 INFO - PROCESS | 1930 | 1471282216520 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] 10:32:28 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52570/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52570/tests/dromaeo/cssquery-ext.html) 10:32:29 INFO - PROCESS | 1930 | RSS: Main: 343531520 10:32:29 INFO - PROCESS | 1930 | 10:35:32 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52570/tests/dromaeo/cssquery-ext.html (next: http://localhost:52570/tests/dromaeo/cssquery-jquery.html) 10:35:33 INFO - PROCESS | 1930 | RSS: Main: 368377856 10:35:33 INFO - PROCESS | 1930 | 10:38:35 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52570/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52570/tests/dromaeo/cssquery-mootools.html) 10:38:36 INFO - PROCESS | 1930 | RSS: Main: 343810048 10:38:36 INFO - PROCESS | 1930 | 10:41:38 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52570/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52570/tests/dromaeo/cssquery-prototype.html) 10:41:39 INFO - PROCESS | 1930 | RSS: Main: 352997376 10:41:39 INFO - PROCESS | 1930 | 10:44:42 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52570/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52570/tests/dromaeo/cssquery-yui.html) 10:44:43 INFO - PROCESS | 1930 | RSS: Main: 335773696 10:44:43 INFO - PROCESS | 1930 | 10:47:46 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:52570/tests/dromaeo/cssquery-yui.html (next: http://localhost:52570/tests/dromaeo/cssquery-dojo.html) 10:47:47 INFO - PROCESS | 1930 | RSS: Main: 530862080 10:47:47 INFO - PROCESS | 1930 | 10:47:47 INFO - PROCESS | 1930 | __start_tp_report 10:47:47 INFO - PROCESS | 1930 | _x_x_mozilla_page_load 10:47:47 INFO - PROCESS | 1930 | _x_x_mozilla_page_load_details 10:47:47 INFO - PROCESS | 1930 | |i|pagename|runs| 10:47:47 INFO - PROCESS | 1930 | |0;dojo.html;2861.871215795289;3324.019414272789;3350.994701589523;3350.9999999999986;3357.815320157393;4872.780724867381;5346.930613877225;5510.8071217507395;5615.859603509914;5628.958642651102;4819.638527110464;5703.486686198241;5712.771489140439;5765.423457654243;5827.708614569276;4845.685030473026;4937.654364194508;5193.350831146106;5202.531772140506;5216.00895829793;5242.397124330712;5299.5230429261355;5304.761285742132;5315.920261196085;5337.06601344765;5150.227465880111;5189.818356357547;5195.844124676266;5203.609729270301;5260.789568417259;5215.400228973679;5218.843434696965;5223.947760522384;5227.581793456515;5231.947680523184;5114.43741188469;5164.793408263665;5178.818741344035;5179.766910489037;5186.766595503212;5599.524040456566;5687.061634830248;5728.627639203439;5753.1370294456165;5795.652260864362;5183.352080989876;6103.779244151143;6210.534209934228;6238.469730072904;6251.093591429263;6155.87688246237;6200.751969921198;6235.407636274546;6243.375662433766;6253.87492250157;5368.570514358842;5402.51377376038;5414.945850541523;5459.808906688327;5519.917201241985;6264.9373506265265;6266.060090986389;6279.309275979638;6282;6283.3088360280335;6080.014298906775;6098.420650038239;6120.938790612036;6121.847543219036;6128.514979093687;6124.816255512342;6164.4143806338325;6225.62646241227;6229.626222426669;6238.220222472191;6220.688965551704;6231.9688401557705;6233.501319894398;6236.438720515084;6242.781502647385;6242.937570624235;6244.594101383395;6251.718672659741;6253.249610046823;6263.060540918807;4843.782029808667;4938.802447902115;4944.703317800871;4969.453360130383;4998.6750861193905;4396.912061758747;4438.090191510797;4474.6420286377015;4489.820407183742;4579.358889755437;5654.575906807006;5670.716464176775;5671.744771485293;5674.631148975386;5718.085106383042;6217.906731399033;6250.124982502453;6260.655663938436;6276.968615156987;6282.4974002079725;4753.809847606057;5470.863228419322;5615.859603509945;5675.063614503643;5730.19777231188;5412.350517937873;5414.269073675082;5417.3770016447825;5424.755885985141;5426.240326354314;6249.562530622904;6328.493720502349;6332;6339.302676705559;6343.9682801585695;6140.657627863787;6141.07883817431;6175.44421002112;6207.037909124093;6282.560220784501;6268.404501572343;6275.184226050585;6282.937170628235;6289.716962736779;6296.212973378328;6113.938860611515;6121.204243448323;6135.294441139326;6141.815745527641;6172.907406388818;4768.403949506312;6120.571559990758;6148.678034222664;6184.165137706352;6292.27638821523;6064.272287325549;6083;6150.907736384047;6169.167162433014;6172.22847144107;6117.571769976059;6136.079588061826;6150.846228844314;6160.10678451631;6164.907526387197;5762.222100016612;5775.8556036099435;5823.7379317931645;5847.327557330961;5862.794802181903;5952.613080149777;5961.314448838439;5970.283565972111;5971.761129554943;5992.760289588542;5762.4525670061275;5800.01399762033;5818.156367326798;5819.534437244925;5821.679807610622 10:47:47 INFO - PROCESS | 1930 | |1;ext.html;492.7437732379165;496.2556165751373;501.05300981145643;523.4922125538229;526.2159382520045;30472.54291185631;39732.00669983252;41022.794886025564;41090.38364424536;43393.91515212121;42515.57484425163;42815.57184428155;42878.78560607193;42883.14233715332;42958.35562466558;9710.320277580573;9880.802383952352;9895.109440150385;9899.50502474877;9918.05778451046;9589.280803939697;9710.271729620272;9714.757131071745;9716.61133554657;9751.951240243789;8478.109798471165;9662.71011869645;9717.46553939533;9738.756531086745;9739.561719722595;9606.087421694929;9655.758606034835;9669.613215471372;9675.806483870283;9689.079537443931;9652.27607929408;9732.464714440703;9733.124018838338;9746.756331091708;9762.121409073146;39723.40414893779;40105.79947100275;40142.799286003676;40212;40226;3498.3353162898966;3820.9426858596867;3910.061585219527;4104.261232978063;4117.3206420940505;30961.535576966362;31256.531152032738;31694.524582131286;32117.678823211936;32369.6763032369;3206.887758928426;3226.8547915343865;3257.7393808495262;3356.6978971892645;3402.319536092791;484176;490110;491413;491922;492622.5368873133;56141.7192914041;56519.71740141355;56550.151747723816;56670;56685.71657141688;34361.14097147541;34870.12824679403;34943.65056349404;35811.4628280576;60393.698031509564;12564.246145231315;16536.503904882873;16792.748108778378;16811.41160059392;16818.663626727397;12737.044721645914;12940.547080852126;12943.352832358345;12945.89959853423;12965.416556254992;13707.383167757474;15787.28957196929;15813.525594232191;15830.68338633244;16018.475935294216;7783.48222096697;7927.643256053491;7943.682252709942;7966.2033796621035;7982.241687039722;840.920112589303;929.8609203725382;961.4759955824093;987.1461186073994;988.6935050134481;28569.857150714113;29800.403991920473;30045.399092018037;30479.69520304813;30481.933132340262;1119.8992090711874;1135.6820090374708;1148.8276758486295;1172.1267655596635;1209.2200530657676;14635.780463293058;14654.340554675066;15494.922525387301;15511.922440387725;15757.684846303238;722860;729137.354313225;729773;730355;730748;57295.713521432124;57673.4232657668;57820.421795781505;57910;58211.70894145502;1272.7454509098218;1312.8424589049184;1313.9408726607517;1315.3423288355823;1414.4342263094845;24811.890989266114;29356.412871742872;30514.237144071576;31820.5226921601;32266.35467290594;52877;54270;54333.82664693368;54345;54353.91292174055;615419;621150;621490.528589218;621904;622963.8851805712;23053.65419518742;23702.52594948057;26168.73831261663;26529.469410612066;26537.203883883514;44330;46772.29841552312;47097;48914.51085489242;49514.76213094701;38720.80639596784;38776.6122338773;39193.2161356777;39732.60935867231;41418.58581414268;38453.61546384612;38705.806470967465;39349;40141;40796.79601601973;30894.691053090082;32325;38115.42826857544;38722.6447074351;38938.80530597442;31733.5239971396;33864.984050478524;34885.12787180341;34924.825375872955;35265.823670882506;22428.738539677408;25237.864296106352;25532.170174473977;25591.616125757755;25946.481070378864 10:47:47 INFO - PROCESS | 1930 | |2;jquery.html;349.3292877674864;360.8628721085989;371.3742344150107;380.15605356109427;397.884613462096;15779.684406311866;16416.753748693765;21140.57718845626;21141.894290528566;21311.68032479512;19281.4215573533;21555;21681.56636867262;21725.565488690216;21849.235276765277;5846.473817356437;5847.1521629363715;5916.526677865761;6023.096535519685;6128.9080663789855;5232.424433312332;5870.26621672291;5914.9112763308585;5959.88080238395;5974.253218347707;4176.874693759192;4277.9263168860025;6398;6657.367550082734;6770.492213084014;5792.102224155221;6090;6741.089952856375;6745.730170793162;6748.7637932672615;5980.13288073227;6004.279486461609;6007.879842403171;6013.128096425994;6015.819525414245;14281.428742850274;26310.6053409199;26461.603075953877;26671.666416679087;26673;2169.5443956769095;2233.129079658934;2286.542691461714;2416.6375043743406;2953.51267040938;9701.951490242574;10117.291789574732;10153.441560714158;10618.840717389245;10703.357798532114;1462.8317743459424;1886.4906475251605;1896.2794138227384;1908.1222637586727;1956.6086782643386;76743;110438;112924.43537782259;116874.41562792216;121075;11201.551937922555;11377.66757814646;11534.423278836024;11557.76884462306;11740.647780566596;14661.120332780067;14678.339474723663;14682;14682.632934176518;14693.191874446795;6932.722691092401;8025.518468891885;8635.913640863511;8661.653533858702;8730.69442569507;8553.572321383906;8562.400631955828;8568.400211985227;8726.69456569017;8771.824563508822;4541.545845415498;4541.659375546781;4555.316702494586;4575.757878639442;4706.623470122382;1605.4359322304465;1662.393226472329;1831.4871835885974;1897.7722673279295;1937.302571074412;764.9655765490567;836.5733475927199;906.9863952040724;949.2311227905419;1051.7896420715888;20295.4926126848;21344.826034567937;21386;22503.54992900133;22618.886905565367;886.8004698942815;891.3315013739696;934.8083642853198;943.0663642993525;962.9951850240844;10126.493675316353;11653.292129791056;11672.416379181008;11831.645050648494;12050.096242781827;589796;605986;606261;614269;626941;92157.53921230351;101658.49170754247;117277;145496.27251863672;151891;915.2906497464518;923.0584803500392;959.1319855530755;960.6925783749273;962.3888830592484;19023.33418330352;20151.899240503702;20546.383608491768;20587.79412205859;20637.793622063586;56929.86140277254;58069;59900;60647.696761517676;60731;80046.5997670008;80625.5499560022;82183.58908205421;82285;85444.57277713572;7087.751928682472;8055.516668999879;8184.2224988625985;8280.83438331242;8476.957615211884;11917.940410297893;12363.505459781867;12472.314022728837;12757.362131893553;13342.466301348226;11590.014848737952;11688.006519445602;11688.649340519458;11888.9894358977;11919.284842909106;11035.503402346752;11455.942720286344;11532.25040372373;11602.18784685064;11900.28598284071;11273.830892536782;11629.59296424621;11911.225770324902;11925.940370298093;12030.939845300718;7870.803229919298;8060.355171586141;8076.878846817185;8110.513369197631;8364;11274.436278185894;11510;11752.529898803952;11814.586489472827;11923.940380298043 10:47:47 INFO - PROCESS | 1930 | |3;mootools.html;1318.2024874950655;1522.6650136969863;1650.4141029934374;1799.2892807341093;1856.3595559531966;8318.292945099667;8869.42348747331;8876.822463550734;8878.86681699775;8895.955520222406;7088.326608972152;7826.686932522693;8050.95974520128;8557.529335886524;8579.356548258875;2357.823163262758;2391.485830546432;2391.6053851114634;2393.5212957408503;2393.724721657006;2327.55776402483;2366.5976783946685;2375.429896824758;2375.643653451979;2386.427257458206;2365.396823809926;2365.4914193448403;2375.798057165143;2393.7366889642212;2400.8919598618104;2329.906803727849;2340.8946597403074;2353.97646023541;2358.5282943411385;2373;2253.5154941687615;2254.2786308381324;2255.8082562982167;2259.5819773341987;2261;8726.912730872673;8731;8829.293656507463;8839.02770695223;8861.291096712312;5193.376794784613;5269.841904742864;5366.838994830161;5379.892402151974;5388.326459192601;3406.8466918988706;3440.6559344065395;3558.323918455511;3650.5071815305123;3651.032476393758;1057.550541020071;1099.5766629847567;1111.4609414433994;1120.5293776613842;1124.9043831274269;11482.253653512495;11542.192046556744;11587.942060289728;11639.94180029103;11708;4439.467263928349;4656.3713898623255;4720.693154944986;4827.396575428072;4856.053069651384;9815;9817.656382026595;9880.45657488845;9897.208223342259;9920.355176913621;5292.470752924677;5308.0710875596615;5311.601629877775;5318.175682769177;5332.33345831771;4385.429894113745;4548.317752337176;4770.379850619467;4862.124817532807;4886.682365646222;3867;3937.586553411908;4022.9195416091516;4046.6964977626794;4069.064115253449;1293.489071816627;1295.9805602915965;1301.3883474766844;1309.4173092973726;1317.8682131786745;762.4929421934462;860.350435421253;893.6827426263712;928.1553786054793;951.1822618437592;4489.6408287336935;5293.629445938747;5736.827895163068;5947.286325640951;6160.537959653044;885.6147575804417;939.3987847777428;953.5613617735919;961.2166084640995;980.7302991677345;3569.1434055826935;3913.62820532049;3926.6662333701956;4023.5372932112596;4026.315526360526;10501.369917804956;10774.138068954464;10810.891891081146;10817.405042722727;10830.566777328977;10700.678979630624;10709.892901071045;10715.624953126604;10721.624743133953;10723.89276107229;8047.718329858427;8192.508449493049;8193.426460147852;8198.713045043514;8214.301784348278;5445.910817836528;5571.9442805571425;5671.574631902707;5873.295204575478;5883.676397798162;8904.86642700373;9801.31390802637;9957.0540798624;10084.344517606332;10111.54498047575;9778.55996480146;10226.977302269714;10264.230182736175;10332.793344132924;10338.622116913002;6760.492963027694;7019.4735394844565;7021.613811240431;7176.425885929218;7682.769516914502;6786.76246331376;7839.294463498312;8007.399445041528;8023.799405014921;8385.916140838513;6991.510594258353;7426.925730742623;7490.475666703279;7513.924860751323;7579.279968402993;7320.816979575553;7329.984254329888;7374.078240219973;7385.852282954418;7474.850502990019;6854.520183587101;6990.755323563651;7041;7080.681369338495;7085;4919.286703428054;4969.254611808257;4996.15065438869;5026.120428924851;5120.359955005624;4878.341423907727;4887.486813884491;4972.875678107932;4988.55103040728;5169.922451163311 10:47:47 INFO - PROCESS | 1930 | |4;prototype.html;267.77105574733605;286.7562571813958;307.49263714870455;312.788867514428;314.065654677335;4576.313552967056;4686.531346865312;5068.670536415139;5298.91372268685;5315.309009828727;4730.97634511827;5109.313926404287;5163.179247356957;5180.8704782380455;5224.764885580148;3242.481203007519;3752.418375151856;4033.737807042533;4078.6547735052313;4094.406311084891;3891.280113179071;4026.4161696553983;4070.084231048005;4075.5924407559155;4101.8974525636795;3624.275144971003;3750.699944004487;3999.160176362953;4011.8165141283243;4045.896134010313;3282.8030318180986;3989.3417586098403;4005.978475488761;4016.6786657067364;4036.4147198656324;3466.8559375406057;3470.6529347065343;3502.8423720932365;3512.5071844466256;3512.947305790415;4271.722338048017;4730.763461826895;4743.335932969387;4799.208130658437;4824.927626085611;3808.9619103808886;3840.5391353037535;4473.46318441789;4496.55034496551;4500.964778101023;4933.7912211508055;5430.185472179205;5434.891302173974;5480.314960629921;5515.558755299607;3064.555639432292;3208.5187221916667;3441.4149594568985;3656.945145822815;3695.7043436525014;4820.276958456259;4910.912862033621;5254.7635356409055;5806.854828629233;5820.214271073393;3841.769493830379;3850.8074596270076;3852.325842977468;3854.807259637007;3862.845486180521;3911.2177564487215;3917.2948869203246;3924.47019652349;3931.1744533647684;3949.763014219156;4157.3140431829015;4282.743035417885;4476.239039363316;4565.178267911741;4583.679142460062;4881.341018962466;5083.339165908408;5312.787488500494;5337.466253374631;5592.384837667853;1251.380566619511;1286.7748144074749;1295.8704129586965;1300.4603089717662;1301.297299458282;749.5802350683525;844.3582877013391;850.4047166983137;866.6966561703354;873.214107303432;4997.875902741321;5177.430482646906;5191.755153029357;5211.973940130275;5222.1383471727495;5453;5682.77268909239;5693.202951586781;5707.800226992035;5714.342850572238;4881.116996051423;4994.9750251248515;5097.949020509822;5145.9416233506145;5147.6654017488745;2943.8822447101875;2976.345204055104;2987.9551806722916;2991.7008299170343;3013.789034767545;5120.948790512121;5911.438413350725;5920.5559583031445;5932.8813423732145;5942.405759424109;5528.55771538276;5805.748780682831;5842.824715258549;5850.9414905851245;5864.70676466175;5872.765089396376;5894.233749612523;5904.6714489239575;5936.139259807217;5939.613925094855;5363.356397232357;5597.916031259616;5709.115087161496;5723.567483819277;5764.567657425626;5164.896702065863;5643.125315576092;5649.491545760901;5652.745626446737;5724.427557244242;5149.948500515096;5862.530997520103;5907.312172961207;5918.556108291981;5962.55280853929;3195.936081278408;3260.6902344277255;3287.8356082196274;3532.3994920863;3821.980890095532;4673.299005149255;4894.437139728977;5416.102385223886;5499.587530935116;5570.4151064139305;4554.608830364085;5317.813876514303;5519.917201241904;5542.418046105262;5591.748371323219;5275.287836142225;5502.532284755841;5507.311586051744;5517.59301378146;5571.247881535941;4070.5115386153852;4947.282644016525;5195.636305458582;5371.570274377962;5454.454554544673;5051.105835311758;5498.422665620213;5504.4495550444635;5512.7519261634125;5524.779008839601;4723.50403207672;4937.753112344297;5382.327209098863;5482.725863706719;5488.917666235089 10:47:47 INFO - PROCESS | 1930 | |5;yui.html;726.9018682477865;732.8497657980112;759.2938567132564;780.6955287437901;837.2339309531778;15259.618509537268;16101.114438705867;16123;16141.354345826183;16148.83851161488;14547.709045819078;14667.486637967673;14686.706265874676;14694.485693000746;14718.779218311734;669.4242951062083;685.4687617096765;691.3466773898667;694.1565997313263;824.0811495182858;694.7221111555368;826.6445428465757;852.7953291210127;853.3599800149888;857.5369300577712;746.4476287547203;902.6840605787987;906.8095699903029;907.0340087806497;907.3557773980482;898.1961144775422;901.3420203251598;901.5086777706166;901.544719916438;901.7655409593552;527.1776029394119;537.9731013449351;667.2293501006345;670.4837275297994;670.8557660103102;12414.131010829245;12518.937405313007;12530;13475;13975.09161904473;14302.713945721027;14824.551320175759;14934.850651493454;14952.850471495363;15001.399944002227;11929.343886086263;11972.58095966637;12055.758884822255;12268.81596776049;12499.312537810416;3447.7588068295395;3853.460515527828;3947.4078888167005;3951.486306780129;4096.959030409687;105243;106523;107570;108059.45970270177;108158.37762433491;11224.438778061227;11301.773964520664;11569;11585.942070289762;11652.766944661229;23196.7680323198;23219.883900580728;23229.186978455673;23232.3030309091;23284.185053523044;6821.965890170517;7156.534825236343;7956.204379562113;8288.171182881664;8356.498610083292;3638.3632864248652;4640.629899313612;4661.137689527448;4666.230072038144;4798.328234047236;3398.2693720850134;3464.6881780639865;3496.30073985204;3511.3328467591077;3515.8593656253465;620.7082671144555;624.7688355308474;636.7325723196269;642.5919541091347;647.7214797636967;613.8680183760424;615.8922188616974;654.404491912367;667.9131712877295;685.5920727167328;8617.396782225183;8925.241354484811;9524.238060955107;9658.082482164324;10029.19099249371;668.9866202675919;676.6921050921819;685.7359916432109;687.621808005595;695.9373656370951;4448.733076015449;4756.5956893664425;4762.690425122356;4763.666543342002;4810.326554282403;94663.58004629936;135957.32021340026;143298;164751;168983.41182469917;133565;138717.0475395322;139688;142373;147518;99117;100352;117360.32995875516;117737.82262177269;119703;9088.772780680272;9105.499197543933;9126.771830704285;9206.355555111346;9408.717738467856;63383.04925426214;64092;64867.918491410295;67050.65898682097;67575.66212168908;68571.65714171597;84828;100218;100462;102637;11894.405279735805;12156.149069565046;12172.817407739069;12184.015703311217;12853.614391568268;13140.802887956497;13241.801372979606;13299.80050299227;13322.800157997444;13546.848517876091;11585.07319414428;12174;12445.875541244472;12457;12841.678958026125;11394.202405831513;11895.750946150529;12075.215111017756;12570.742925707356;12732.745345093232;12054.337011464453;12125.514979400725;12264.202826816228;12650.367481626066;12667.873321267038;9605.711828645151;10439;10473.738156546147;10511.842322365324;10535.209859260432;8485.321174305916;9319.673811416567;9463.905360946303;11037.833739074922;11640.767184656428 10:47:47 INFO - PROCESS | 1930 | __end_tp_report 10:47:47 INFO - PROCESS | 1930 | __start_cc_report 10:47:47 INFO - PROCESS | 1930 | _x_x_mozilla_cycle_collect,1312 10:47:47 INFO - PROCESS | 1930 | __end_cc_report 10:47:47 INFO - PROCESS | 1930 | __startTimestamp1471283267251__endTimestamp 10:47:47 INFO - PROCESS | 1930 | 10:47:47 INFO - PROCESS | 1930 | ------- Summary: start ------- 10:47:47 INFO - PROCESS | 1930 | Number of tests: 6 10:47:47 INFO - PROCESS | 1930 | 10:47:47 INFO - PROCESS | 1930 | [#0] dojo.html Cycles:165 Average:5682.30 Median:5821.68 stddev:660.62 (11.3%) stddev-sans-first:624.50 10:47:47 INFO - PROCESS | 1930 | Values: 2861.9 3324.0 3351.0 3351.0 3357.8 4872.8 5346.9 5510.8 5615.9 5629.0 4819.6 5703.5 5712.8 5765.4 5827.7 4845.7 4937.7 5193.4 5202.5 5216.0 5242.4 5299.5 5304.8 5315.9 5337.1 5150.2 5189.8 5195.8 5203.6 5260.8 5215.4 5218.8 5223.9 5227.6 5231.9 5114.4 5164.8 5178.8 5179.8 5186.8 5599.5 5687.1 5728.6 5753.1 5795.7 5183.4 6103.8 6210.5 6238.5 6251.1 6155.9 6200.8 6235.4 6243.4 6253.9 5368.6 5402.5 5414.9 5459.8 5519.9 6264.9 6266.1 6279.3 6282.0 6283.3 6080.0 6098.4 6120.9 6121.8 6128.5 6124.8 6164.4 6225.6 6229.6 6238.2 6220.7 6232.0 6233.5 6236.4 6242.8 6242.9 6244.6 6251.7 6253.2 6263.1 4843.8 4938.8 4944.7 4969.5 4998.7 4396.9 4438.1 4474.6 4489.8 4579.4 5654.6 5670.7 5671.7 5674.6 5718.1 6217.9 6250.1 6260.7 6277.0 6282.5 4753.8 5470.9 5615.9 5675.1 5730.2 5412.4 5414.3 5417.4 5424.8 5426.2 6249.6 6328.5 6332.0 6339.3 6344.0 6140.7 6141.1 6175.4 6207.0 6282.6 6268.4 6275.2 6282.9 6289.7 6296.2 6113.9 6121.2 6135.3 6141.8 6172.9 4768.4 6120.6 6148.7 6184.2 6292.3 6064.3 6083.0 6150.9 6169.2 6172.2 6117.6 6136.1 6150.8 6160.1 6164.9 5762.2 5775.9 5823.7 5847.3 5862.8 5952.6 5961.3 5970.3 5971.8 5992.8 5762.5 5800.0 5818.2 5819.5 5821.7 10:47:47 INFO - PROCESS | 1930 | 10:47:47 INFO - PROCESS | 1930 | [#1] ext.html Cycles:180 Average:73175.56 Median:40219.00 stddev:166532.32 (414.1%) stddev-sans-first:166910.08 10:47:47 INFO - PROCESS | 1930 | Values: 492.7 496.3 501.1 523.5 526.2 30472.5 39732.0 41022.8 41090.4 43393.9 42515.6 42815.6 42878.8 42883.1 42958.4 9710.3 9880.8 9895.1 9899.5 9918.1 9589.3 9710.3 9714.8 9716.6 9752.0 8478.1 9662.7 9717.5 9738.8 9739.6 9606.1 9655.8 9669.6 9675.8 9689.1 9652.3 9732.5 9733.1 9746.8 9762.1 39723.4 40105.8 40142.8 40212.0 40226.0 3498.3 3820.9 3910.1 4104.3 4117.3 30961.5 31256.5 31694.5 32117.7 32369.7 3206.9 3226.9 3257.7 3356.7 3402.3 484176.0 490110.0 491413.0 491922.0 492622.5 56141.7 56519.7 56550.2 56670.0 56685.7 34361.1 34870.1 34943.7 35811.5 60393.7 12564.2 16536.5 16792.7 16811.4 16818.7 12737.0 12940.5 12943.4 12945.9 12965.4 13707.4 15787.3 15813.5 15830.7 16018.5 7783.5 7927.6 7943.7 7966.2 7982.2 840.9 929.9 961.5 987.1 988.7 28569.9 29800.4 30045.4 30479.7 30481.9 1119.9 1135.7 1148.8 1172.1 1209.2 14635.8 14654.3 15494.9 15511.9 15757.7 722860.0 729137.4 729773.0 730355.0 730748.0 57295.7 57673.4 57820.4 57910.0 58211.7 1272.7 1312.8 1313.9 1315.3 1414.4 24811.9 29356.4 30514.2 31820.5 32266.4 52877.0 54270.0 54333.8 54345.0 54353.9 615419.0 621150.0 621490.5 621904.0 622963.9 23053.7 23702.5 26168.7 26529.5 26537.2 44330.0 46772.3 47097.0 48914.5 49514.8 38720.8 38776.6 39193.2 39732.6 41418.6 38453.6 38705.8 39349.0 40141.0 40796.8 30894.7 32325.0 38115.4 38722.6 38938.8 31733.5 33865.0 34885.1 34924.8 35265.8 22428.7 25237.9 25532.2 25591.6 25946.5 10:47:47 INFO - PROCESS | 1930 | 10:47:47 INFO - PROCESS | 1930 | [#2] jquery.html Cycles:180 Average:35257.07 Median:26672.33 stddev:101302.85 (379.8%) stddev-sans-first:101553.12 10:47:47 INFO - PROCESS | 1930 | Values: 349.3 360.9 371.4 380.2 397.9 15779.7 16416.8 21140.6 21141.9 21311.7 19281.4 21555.0 21681.6 21725.6 21849.2 5846.5 5847.2 5916.5 6023.1 6128.9 5232.4 5870.3 5914.9 5959.9 5974.3 4176.9 4277.9 6398.0 6657.4 6770.5 5792.1 6090.0 6741.1 6745.7 6748.8 5980.1 6004.3 6007.9 6013.1 6015.8 14281.4 26310.6 26461.6 26671.7 26673.0 2169.5 2233.1 2286.5 2416.6 2953.5 9702.0 10117.3 10153.4 10618.8 10703.4 1462.8 1886.5 1896.3 1908.1 1956.6 76743.0 110438.0 112924.4 116874.4 121075.0 11201.6 11377.7 11534.4 11557.8 11740.6 14661.1 14678.3 14682.0 14682.6 14693.2 6932.7 8025.5 8635.9 8661.7 8730.7 8553.6 8562.4 8568.4 8726.7 8771.8 4541.5 4541.7 4555.3 4575.8 4706.6 1605.4 1662.4 1831.5 1897.8 1937.3 765.0 836.6 907.0 949.2 1051.8 20295.5 21344.8 21386.0 22503.5 22618.9 886.8 891.3 934.8 943.1 963.0 10126.5 11653.3 11672.4 11831.6 12050.1 589796.0 605986.0 606261.0 614269.0 626941.0 92157.5 101658.5 117277.0 145496.3 151891.0 915.3 923.1 959.1 960.7 962.4 19023.3 20151.9 20546.4 20587.8 20637.8 56929.9 58069.0 59900.0 60647.7 60731.0 80046.6 80625.5 82183.6 82285.0 85444.6 7087.8 8055.5 8184.2 8280.8 8477.0 11917.9 12363.5 12472.3 12757.4 13342.5 11590.0 11688.0 11688.6 11889.0 11919.3 11035.5 11455.9 11532.3 11602.2 11900.3 11273.8 11629.6 11911.2 11925.9 12030.9 7870.8 8060.4 8076.9 8110.5 8364.0 11274.4 11510.0 11752.5 11814.6 11923.9 10:47:47 INFO - PROCESS | 1930 | 10:47:47 INFO - PROCESS | 1930 | [#3] mootools.html Cycles:180 Average:5655.78 Median:4971.07 stddev:3165.14 (63.7%) stddev-sans-first:3157.23 10:47:47 INFO - PROCESS | 1930 | Values: 1318.2 1522.7 1650.4 1799.3 1856.4 8318.3 8869.4 8876.8 8878.9 8896.0 7088.3 7826.7 8051.0 8557.5 8579.4 2357.8 2391.5 2391.6 2393.5 2393.7 2327.6 2366.6 2375.4 2375.6 2386.4 2365.4 2365.5 2375.8 2393.7 2400.9 2329.9 2340.9 2354.0 2358.5 2373.0 2253.5 2254.3 2255.8 2259.6 2261.0 8726.9 8731.0 8829.3 8839.0 8861.3 5193.4 5269.8 5366.8 5379.9 5388.3 3406.8 3440.7 3558.3 3650.5 3651.0 1057.6 1099.6 1111.5 1120.5 1124.9 11482.3 11542.2 11587.9 11639.9 11708.0 4439.5 4656.4 4720.7 4827.4 4856.1 9815.0 9817.7 9880.5 9897.2 9920.4 5292.5 5308.1 5311.6 5318.2 5332.3 4385.4 4548.3 4770.4 4862.1 4886.7 3867.0 3937.6 4022.9 4046.7 4069.1 1293.5 1296.0 1301.4 1309.4 1317.9 762.5 860.4 893.7 928.2 951.2 4489.6 5293.6 5736.8 5947.3 6160.5 885.6 939.4 953.6 961.2 980.7 3569.1 3913.6 3926.7 4023.5 4026.3 10501.4 10774.1 10810.9 10817.4 10830.6 10700.7 10709.9 10715.6 10721.6 10723.9 8047.7 8192.5 8193.4 8198.7 8214.3 5445.9 5571.9 5671.6 5873.3 5883.7 8904.9 9801.3 9957.1 10084.3 10111.5 9778.6 10227.0 10264.2 10332.8 10338.6 6760.5 7019.5 7021.6 7176.4 7682.8 6786.8 7839.3 8007.4 8023.8 8385.9 6991.5 7426.9 7490.5 7513.9 7579.3 7320.8 7330.0 7374.1 7385.9 7474.9 6854.5 6990.8 7041.0 7080.7 7085.0 4919.3 4969.3 4996.2 5026.1 5120.4 4878.3 4887.5 4972.9 4988.6 5169.9 10:47:47 INFO - PROCESS | 1930 | 10:47:47 INFO - PROCESS | 1930 | [#4] prototype.html Cycles:180 Average:4453.83 Median:4996.43 stddev:1385.50 (27.7%) stddev-sans-first:1353.30 10:47:47 INFO - PROCESS | 1930 | Values: 267.8 286.8 307.5 312.8 314.1 4576.3 4686.5 5068.7 5298.9 5315.3 4731.0 5109.3 5163.2 5180.9 5224.8 3242.5 3752.4 4033.7 4078.7 4094.4 3891.3 4026.4 4070.1 4075.6 4101.9 3624.3 3750.7 3999.2 4011.8 4045.9 3282.8 3989.3 4006.0 4016.7 4036.4 3466.9 3470.7 3502.8 3512.5 3512.9 4271.7 4730.8 4743.3 4799.2 4824.9 3809.0 3840.5 4473.5 4496.6 4501.0 4933.8 5430.2 5434.9 5480.3 5515.6 3064.6 3208.5 3441.4 3656.9 3695.7 4820.3 4910.9 5254.8 5806.9 5820.2 3841.8 3850.8 3852.3 3854.8 3862.8 3911.2 3917.3 3924.5 3931.2 3949.8 4157.3 4282.7 4476.2 4565.2 4583.7 4881.3 5083.3 5312.8 5337.5 5592.4 1251.4 1286.8 1295.9 1300.5 1301.3 749.6 844.4 850.4 866.7 873.2 4997.9 5177.4 5191.8 5212.0 5222.1 5453.0 5682.8 5693.2 5707.8 5714.3 4881.1 4995.0 5097.9 5145.9 5147.7 2943.9 2976.3 2988.0 2991.7 3013.8 5120.9 5911.4 5920.6 5932.9 5942.4 5528.6 5805.7 5842.8 5850.9 5864.7 5872.8 5894.2 5904.7 5936.1 5939.6 5363.4 5597.9 5709.1 5723.6 5764.6 5164.9 5643.1 5649.5 5652.7 5724.4 5149.9 5862.5 5907.3 5918.6 5962.6 3195.9 3260.7 3287.8 3532.4 3822.0 4673.3 4894.4 5416.1 5499.6 5570.4 4554.6 5317.8 5519.9 5542.4 5591.7 5275.3 5502.5 5507.3 5517.6 5571.2 4070.5 4947.3 5195.6 5371.6 5454.5 5051.1 5498.4 5504.4 5512.8 5524.8 4723.5 4937.8 5382.3 5482.7 5488.9 10:47:47 INFO - PROCESS | 1930 | 10:47:47 INFO - PROCESS | 1930 | [#5] yui.html Cycles:180 Average:24831.59 Median:3480.49 stddev:40205.95 (1155.2%) stddev-sans-first:40278.01 10:47:47 INFO - PROCESS | 1930 | Values: 726.9 732.8 759.3 780.7 837.2 15259.6 16101.1 16123.0 16141.4 16148.8 14547.7 14667.5 14686.7 14694.5 14718.8 669.4 685.5 691.3 694.2 824.1 694.7 826.6 852.8 853.4 857.5 746.4 902.7 906.8 907.0 907.4 898.2 901.3 901.5 901.5 901.8 527.2 538.0 667.2 670.5 670.9 12414.1 12518.9 12530.0 13475.0 13975.1 14302.7 14824.6 14934.9 14952.9 15001.4 11929.3 11972.6 12055.8 12268.8 12499.3 3447.8 3853.5 3947.4 3951.5 4097.0 105243.0 106523.0 107570.0 108059.5 108158.4 11224.4 11301.8 11569.0 11585.9 11652.8 23196.8 23219.9 23229.2 23232.3 23284.2 6822.0 7156.5 7956.2 8288.2 8356.5 3638.4 4640.6 4661.1 4666.2 4798.3 3398.3 3464.7 3496.3 3511.3 3515.9 620.7 624.8 636.7 642.6 647.7 613.9 615.9 654.4 667.9 685.6 8617.4 8925.2 9524.2 9658.1 10029.2 669.0 676.7 685.7 687.6 695.9 4448.7 4756.6 4762.7 4763.7 4810.3 94663.6 135957.3 143298.0 164751.0 168983.4 133565.0 138717.0 139688.0 142373.0 147518.0 99117.0 100352.0 117360.3 117737.8 119703.0 9088.8 9105.5 9126.8 9206.4 9408.7 63383.0 64092.0 64867.9 67050.7 67575.7 68571.7 84828.0 100218.0 100462.0 102637.0 11894.4 12156.1 12172.8 12184.0 12853.6 13140.8 13241.8 13299.8 13322.8 13546.8 11585.1 12174.0 12445.9 12457.0 12841.7 11394.2 11895.8 12075.2 12570.7 12732.7 12054.3 12125.5 12264.2 12650.4 12667.9 9605.7 10439.0 10473.7 10511.8 10535.2 8485.3 9319.7 9463.9 11037.8 11640.8 10:47:47 INFO - PROCESS | 1930 | -------- Summary: end -------- 10:47:47 INFO - PROCESS | 1930 | 10:47:47 INFO - TEST-INFO | 1930: exit 0 10:47:47 INFO - TEST-OK | dromaeo_css | took 1117778ms 10:47:47 INFO - TEST-START | kraken 10:47:47 INFO - operating with platform_type : mac_ 10:47:47 INFO - Initialising browser for kraken test... 10:47:47 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmpeR77Uq/profile http://localhost:52570/getInfo.html) 10:47:50 INFO - PROCESS | 1934 | __metrics Screen width/height:1600/1200 10:47:50 INFO - PROCESS | 1934 | colorDepth:24 10:47:50 INFO - PROCESS | 1934 | Browser inner width/height: 1024/689 10:47:50 INFO - PROCESS | 1934 | __metrics 10:47:50 INFO - PROCESS | 1934 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:47:50 INFO - PROCESS | 1934 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:47:51 INFO - TEST-INFO | 1934: exit 0 10:47:51 INFO - Browser initialized. 10:47:51 INFO - Running cycle 1/1 for kraken test... 10:47:51 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmpeR77Uq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:48:02 INFO - PROCESS | 1935 | RSS: Main: 228794368 10:48:02 INFO - PROCESS | 1935 | 10:48:05 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52570/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52570/tests/kraken/driver.html?testName=audio-beat-detection) 10:48:06 INFO - PROCESS | 1935 | RSS: Main: 285581312 10:48:06 INFO - PROCESS | 1935 | 10:48:09 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52570/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52570/tests/kraken/driver.html?testName=audio-dft) 10:48:09 INFO - PROCESS | 1935 | RSS: Main: 295366656 10:48:09 INFO - PROCESS | 1935 | 10:48:13 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52570/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52570/tests/kraken/driver.html?testName=audio-fft) 10:48:14 INFO - PROCESS | 1935 | RSS: Main: 386805760 10:48:14 INFO - PROCESS | 1935 | 10:48:16 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52570/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52570/tests/kraken/driver.html?testName=audio-oscillator) 10:48:16 INFO - PROCESS | 1935 | RSS: Main: 290742272 10:48:16 INFO - PROCESS | 1935 | 10:48:18 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52570/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52570/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:48:19 INFO - PROCESS | 1935 | RSS: Main: 295145472 10:48:19 INFO - PROCESS | 1935 | 10:48:22 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52570/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52570/tests/kraken/driver.html?testName=imaging-darkroom) 10:48:22 INFO - PROCESS | 1935 | RSS: Main: 332615680 10:48:22 INFO - PROCESS | 1935 | 10:48:25 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52570/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52570/tests/kraken/driver.html?testName=imaging-desaturate) 10:48:26 INFO - PROCESS | 1935 | RSS: Main: 333766656 10:48:26 INFO - PROCESS | 1935 | 10:48:29 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52570/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52570/tests/kraken/driver.html?testName=json-parse-financial) 10:48:29 INFO - PROCESS | 1935 | RSS: Main: 335118336 10:48:29 INFO - PROCESS | 1935 | 10:48:31 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52570/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52570/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:48:31 INFO - PROCESS | 1935 | RSS: Main: 300974080 10:48:31 INFO - PROCESS | 1935 | 10:48:33 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52570/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52570/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:48:34 INFO - PROCESS | 1935 | RSS: Main: 307888128 10:48:34 INFO - PROCESS | 1935 | 10:48:36 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52570/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52570/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:48:37 INFO - PROCESS | 1935 | RSS: Main: 307294208 10:48:37 INFO - PROCESS | 1935 | 10:48:40 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52570/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52570/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:48:41 INFO - PROCESS | 1935 | RSS: Main: 314109952 10:48:41 INFO - PROCESS | 1935 | 10:48:43 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52570/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52570/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:48:44 INFO - PROCESS | 1935 | RSS: Main: 316596224 10:48:44 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52570/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52570/tests/kraken/driver.html?testName=ai-astar) 10:48:46 INFO - PROCESS | 1935 | RSS: Main: 360144896 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | __start_tp_report 10:48:46 INFO - PROCESS | 1935 | _x_x_mozilla_page_load 10:48:46 INFO - PROCESS | 1935 | _x_x_mozilla_page_load_details 10:48:46 INFO - PROCESS | 1935 | |i|pagename|runs| 10:48:46 INFO - PROCESS | 1935 | |0;ai-astar;104;167;161;114;108;113;113;114;112;114 10:48:46 INFO - PROCESS | 1935 | |1;audio-beat-detection;144;108;106;166;108;107;133;107;143;108 10:48:46 INFO - PROCESS | 1935 | |2;audio-dft;184;185;185;184;184;183;185;181;184;184 10:48:46 INFO - PROCESS | 1935 | |3;audio-fft;81;58;61;89;58;58;69;59;83;57 10:48:46 INFO - PROCESS | 1935 | |4;audio-oscillator;85;70;101;71;70;69;97;71;68;106 10:48:46 INFO - PROCESS | 1935 | |5;imaging-gaussian-blur;88;88;87;86;89;84;89;84;87;85 10:48:46 INFO - PROCESS | 1935 | |6;imaging-darkroom;94;94;101;143;94;94;93;94;94;93 10:48:46 INFO - PROCESS | 1935 | |7;imaging-desaturate;85;83;85;83;87;83;84;84;85;81 10:48:46 INFO - PROCESS | 1935 | |8;json-parse-financial;56;55;56;56;56;55;56;53;55;53 10:48:46 INFO - PROCESS | 1935 | |9;json-stringify-tinderbox;65;73;78;75;73;68;68;69;69;71 10:48:46 INFO - PROCESS | 1935 | |10;stanford-crypto-aes;89;86;85;88;83;85;88;85;88;74 10:48:46 INFO - PROCESS | 1935 | |11;stanford-crypto-ccm;142;149;152;136;136;138;136;147;224;140 10:48:46 INFO - PROCESS | 1935 | |12;stanford-crypto-pbkdf2;168;169;169;169;168;169;170;166;166;166 10:48:46 INFO - PROCESS | 1935 | |13;stanford-crypto-sha256-iterative;67;67;68;67;67;67;68;67;67;67 10:48:46 INFO - PROCESS | 1935 | __end_tp_report 10:48:46 INFO - PROCESS | 1935 | __start_cc_report 10:48:46 INFO - PROCESS | 1935 | _x_x_mozilla_cycle_collect,2131 10:48:46 INFO - PROCESS | 1935 | __end_cc_report 10:48:46 INFO - PROCESS | 1935 | __startTimestamp1471283326459__endTimestamp 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | ------- Summary: start ------- 10:48:46 INFO - PROCESS | 1935 | Number of tests: 14 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | [#0] ai-astar Cycles:10 Average:122.00 Median:114.00 stddev:22.41 (19.7%) stddev-sans-first:22.80 10:48:46 INFO - PROCESS | 1935 | Values: 104.0 167.0 161.0 114.0 108.0 113.0 113.0 114.0 112.0 114.0 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | [#1] audio-beat-detection Cycles:10 Average:123.00 Median:120.50 stddev:21.77 (18.1%) stddev-sans-first:21.73 10:48:46 INFO - PROCESS | 1935 | Values: 144.0 108.0 106.0 166.0 108.0 107.0 133.0 107.0 143.0 108.0 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.20 (0.7%) stddev-sans-first:1.27 10:48:46 INFO - PROCESS | 1935 | Values: 184.0 185.0 185.0 184.0 184.0 183.0 185.0 181.0 184.0 184.0 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | [#3] audio-fft Cycles:10 Average:67.30 Median:65.00 stddev:12.39 (19.1%) stddev-sans-first:12.11 10:48:46 INFO - PROCESS | 1935 | Values: 81.0 58.0 61.0 89.0 58.0 58.0 69.0 59.0 83.0 57.0 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | [#4] audio-oscillator Cycles:10 Average:80.80 Median:70.50 stddev:15.10 (21.4%) stddev-sans-first:15.94 10:48:46 INFO - PROCESS | 1935 | Values: 85.0 70.0 101.0 71.0 70.0 69.0 97.0 71.0 68.0 106.0 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.50 stddev:1.89 (2.2%) stddev-sans-first:1.94 10:48:46 INFO - PROCESS | 1935 | Values: 88.0 88.0 87.0 86.0 89.0 84.0 89.0 84.0 87.0 85.0 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | [#6] imaging-darkroom Cycles:10 Average:99.40 Median:94.00 stddev:15.49 (16.5%) stddev-sans-first:16.31 10:48:46 INFO - PROCESS | 1935 | Values: 94.0 94.0 101.0 143.0 94.0 94.0 93.0 94.0 94.0 93.0 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.50 stddev:1.63 (1.9%) stddev-sans-first:1.69 10:48:46 INFO - PROCESS | 1935 | Values: 85.0 83.0 85.0 83.0 87.0 83.0 84.0 84.0 85.0 81.0 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:56.00 stddev:1.20 (2.1%) stddev-sans-first:1.22 10:48:46 INFO - PROCESS | 1935 | Values: 56.0 55.0 56.0 56.0 56.0 55.0 56.0 53.0 55.0 53.0 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:72.00 stddev:3.87 (5.4%) stddev-sans-first:3.47 10:48:46 INFO - PROCESS | 1935 | Values: 65.0 73.0 78.0 75.0 73.0 68.0 68.0 69.0 69.0 71.0 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:87.00 stddev:4.33 (5.0%) stddev-sans-first:4.36 10:48:46 INFO - PROCESS | 1935 | Values: 89.0 86.0 85.0 88.0 83.0 85.0 88.0 85.0 88.0 74.0 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:144.50 stddev:26.64 (18.4%) stddev-sans-first:28.10 10:48:46 INFO - PROCESS | 1935 | Values: 142.0 149.0 152.0 136.0 136.0 138.0 136.0 147.0 224.0 140.0 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:169.00 stddev:1.49 (0.9%) stddev-sans-first:1.58 10:48:46 INFO - PROCESS | 1935 | Values: 168.0 169.0 169.0 169.0 168.0 169.0 170.0 166.0 166.0 166.0 10:48:46 INFO - PROCESS | 1935 | 10:48:46 INFO - PROCESS | 1935 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 10:48:46 INFO - PROCESS | 1935 | Values: 67.0 67.0 68.0 67.0 67.0 67.0 68.0 67.0 67.0 67.0 10:48:46 INFO - PROCESS | 1935 | -------- Summary: end -------- 10:48:46 INFO - PROCESS | 1935 | 10:48:47 INFO - TEST-INFO | 1935: exit 0 10:48:47 INFO - TEST-OK | kraken | took 59410ms 10:48:47 INFO - SUITE-END | took 1177s 10:48:47 INFO - Completed test suite (00:19:37) 10:48:47 INFO - javascript benchmark 10:48:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2861.871215795289, 3324.019414272789, 3350.994701589523, 3350.9999999999986, 3357.815320157393, 4872.780724867381, 5346.930613877225, 5510.8071217507395, 5615.859603509914, 5628.958642651102, 4819.638527110464, 5703.486686198241, 5712.771489140439, 5765.423457654243, 5827.708614569276, 4845.685030473026, 4937.654364194508, 5193.350831146106, 5202.531772140506, 5216.00895829793, 5242.397124330712, 5299.5230429261355, 5304.761285742132, 5315.920261196085, 5337.06601344765, 5150.227465880111, 5189.818356357547, 5195.844124676266, 5203.609729270301, 5260.789568417259, 5215.400228973679, 5218.843434696965, 5223.947760522384, 5227.581793456515, 5231.947680523184, 5114.43741188469, 5164.793408263665, 5178.818741344035, 5179.766910489037, 5186.766595503212, 5599.524040456566, 5687.061634830248, 5728.627639203439, 5753.1370294456165, 5795.652260864362, 5183.352080989876, 6103.779244151143, 6210.534209934228, 6238.469730072904, 6251.093591429263, 6155.87688246237, 6200.751969921198, 6235.407636274546, 6243.375662433766, 6253.87492250157, 5368.570514358842, 5402.51377376038, 5414.945850541523, 5459.808906688327, 5519.917201241985, 6264.9373506265265, 6266.060090986389, 6279.309275979638, 6282.0, 6283.3088360280335, 6080.014298906775, 6098.420650038239, 6120.938790612036, 6121.847543219036, 6128.514979093687, 6124.816255512342, 6164.4143806338325, 6225.62646241227, 6229.626222426669, 6238.220222472191, 6220.688965551704, 6231.9688401557705, 6233.501319894398, 6236.438720515084, 6242.781502647385, 6242.937570624235, 6244.594101383395, 6251.718672659741, 6253.249610046823, 6263.060540918807, 4843.782029808667, 4938.802447902115, 4944.703317800871, 4969.453360130383, 4998.6750861193905, 4396.912061758747, 4438.090191510797, 4474.6420286377015, 4489.820407183742, 4579.358889755437, 5654.575906807006, 5670.716464176775, 5671.744771485293, 5674.631148975386, 5718.085106383042, 6217.906731399033, 6250.124982502453, 6260.655663938436, 6276.968615156987, 6282.4974002079725, 4753.809847606057, 5470.863228419322, 5615.859603509945, 5675.063614503643, 5730.19777231188, 5412.350517937873, 5414.269073675082, 5417.3770016447825, 5424.755885985141, 5426.240326354314, 6249.562530622904, 6328.493720502349, 6332.0, 6339.302676705559, 6343.9682801585695, 6140.657627863787, 6141.07883817431, 6175.44421002112, 6207.037909124093, 6282.560220784501, 6268.404501572343, 6275.184226050585, 6282.937170628235, 6289.716962736779, 6296.212973378328, 6113.938860611515, 6121.204243448323, 6135.294441139326, 6141.815745527641, 6172.907406388818, 4768.403949506312, 6120.571559990758, 6148.678034222664, 6184.165137706352, 6292.27638821523, 6064.272287325549, 6083.0, 6150.907736384047, 6169.167162433014, 6172.22847144107, 6117.571769976059, 6136.079588061826, 6150.846228844314, 6160.10678451631, 6164.907526387197, 5762.222100016612, 5775.8556036099435, 5823.7379317931645, 5847.327557330961, 5862.794802181903, 5952.613080149777, 5961.314448838439, 5970.283565972111, 5971.761129554943, 5992.760289588542, 5762.4525670061275, 5800.01399762033, 5818.156367326798, 5819.534437244925, 5821.679807610622], "value": 5639.6340393368855, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.7437732379165, 496.2556165751373, 501.05300981145643, 523.4922125538229, 526.2159382520045, 30472.54291185631, 39732.00669983252, 41022.794886025564, 41090.38364424536, 43393.91515212121, 42515.57484425163, 42815.57184428155, 42878.78560607193, 42883.14233715332, 42958.35562466558, 9710.320277580573, 9880.802383952352, 9895.109440150385, 9899.50502474877, 9918.05778451046, 9589.280803939697, 9710.271729620272, 9714.757131071745, 9716.61133554657, 9751.951240243789, 8478.109798471165, 9662.71011869645, 9717.46553939533, 9738.756531086745, 9739.561719722595, 9606.087421694929, 9655.758606034835, 9669.613215471372, 9675.806483870283, 9689.079537443931, 9652.27607929408, 9732.464714440703, 9733.124018838338, 9746.756331091708, 9762.121409073146, 39723.40414893779, 40105.79947100275, 40142.799286003676, 40212.0, 40226.0, 3498.3353162898966, 3820.9426858596867, 3910.061585219527, 4104.261232978063, 4117.3206420940505, 30961.535576966362, 31256.531152032738, 31694.524582131286, 32117.678823211936, 32369.6763032369, 3206.887758928426, 3226.8547915343865, 3257.7393808495262, 3356.6978971892645, 3402.319536092791, 484176.0, 490110.0, 491413.0, 491922.0, 492622.5368873133, 56141.7192914041, 56519.71740141355, 56550.151747723816, 56670.0, 56685.71657141688, 34361.14097147541, 34870.12824679403, 34943.65056349404, 35811.4628280576, 60393.698031509564, 12564.246145231315, 16536.503904882873, 16792.748108778378, 16811.41160059392, 16818.663626727397, 12737.044721645914, 12940.547080852126, 12943.352832358345, 12945.89959853423, 12965.416556254992, 13707.383167757474, 15787.28957196929, 15813.525594232191, 15830.68338633244, 16018.475935294216, 7783.48222096697, 7927.643256053491, 7943.682252709942, 7966.2033796621035, 7982.241687039722, 840.920112589303, 929.8609203725382, 961.4759955824093, 987.1461186073994, 988.6935050134481, 28569.857150714113, 29800.403991920473, 30045.399092018037, 30479.69520304813, 30481.933132340262, 1119.8992090711874, 1135.6820090374708, 1148.8276758486295, 1172.1267655596635, 1209.2200530657676, 14635.780463293058, 14654.340554675066, 15494.922525387301, 15511.922440387725, 15757.684846303238, 722860.0, 729137.354313225, 729773.0, 730355.0, 730748.0, 57295.713521432124, 57673.4232657668, 57820.421795781505, 57910.0, 58211.70894145502, 1272.7454509098218, 1312.8424589049184, 1313.9408726607517, 1315.3423288355823, 1414.4342263094845, 24811.890989266114, 29356.412871742872, 30514.237144071576, 31820.5226921601, 32266.35467290594, 52877.0, 54270.0, 54333.82664693368, 54345.0, 54353.91292174055, 615419.0, 621150.0, 621490.528589218, 621904.0, 622963.8851805712, 23053.65419518742, 23702.52594948057, 26168.73831261663, 26529.469410612066, 26537.203883883514, 44330.0, 46772.29841552312, 47097.0, 48914.51085489242, 49514.76213094701, 38720.80639596784, 38776.6122338773, 39193.2161356777, 39732.60935867231, 41418.58581414268, 38453.61546384612, 38705.806470967465, 39349.0, 40141.0, 40796.79601601973, 30894.691053090082, 32325.0, 38115.42826857544, 38722.6447074351, 38938.80530597442, 31733.5239971396, 33864.984050478524, 34885.12787180341, 34924.825375872955, 35265.823670882506, 22428.738539677408, 25237.864296106352, 25532.170174473977, 25591.616125757755, 25946.481070378864], "value": 19841.026239558094, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.3292877674864, 360.8628721085989, 371.3742344150107, 380.15605356109427, 397.884613462096, 15779.684406311866, 16416.753748693765, 21140.57718845626, 21141.894290528566, 21311.68032479512, 19281.4215573533, 21555.0, 21681.56636867262, 21725.565488690216, 21849.235276765277, 5846.473817356437, 5847.1521629363715, 5916.526677865761, 6023.096535519685, 6128.9080663789855, 5232.424433312332, 5870.26621672291, 5914.9112763308585, 5959.88080238395, 5974.253218347707, 4176.874693759192, 4277.9263168860025, 6398.0, 6657.367550082734, 6770.492213084014, 5792.102224155221, 6090.0, 6741.089952856375, 6745.730170793162, 6748.7637932672615, 5980.13288073227, 6004.279486461609, 6007.879842403171, 6013.128096425994, 6015.819525414245, 14281.428742850274, 26310.6053409199, 26461.603075953877, 26671.666416679087, 26673.0, 2169.5443956769095, 2233.129079658934, 2286.542691461714, 2416.6375043743406, 2953.51267040938, 9701.951490242574, 10117.291789574732, 10153.441560714158, 10618.840717389245, 10703.357798532114, 1462.8317743459424, 1886.4906475251605, 1896.2794138227384, 1908.1222637586727, 1956.6086782643386, 76743.0, 110438.0, 112924.43537782259, 116874.41562792216, 121075.0, 11201.551937922555, 11377.66757814646, 11534.423278836024, 11557.76884462306, 11740.647780566596, 14661.120332780067, 14678.339474723663, 14682.0, 14682.632934176518, 14693.191874446795, 6932.722691092401, 8025.518468891885, 8635.913640863511, 8661.653533858702, 8730.69442569507, 8553.572321383906, 8562.400631955828, 8568.400211985227, 8726.69456569017, 8771.824563508822, 4541.545845415498, 4541.659375546781, 4555.316702494586, 4575.757878639442, 4706.623470122382, 1605.4359322304465, 1662.393226472329, 1831.4871835885974, 1897.7722673279295, 1937.302571074412, 764.9655765490567, 836.5733475927199, 906.9863952040724, 949.2311227905419, 1051.7896420715888, 20295.4926126848, 21344.826034567937, 21386.0, 22503.54992900133, 22618.886905565367, 886.8004698942815, 891.3315013739696, 934.8083642853198, 943.0663642993525, 962.9951850240844, 10126.493675316353, 11653.292129791056, 11672.416379181008, 11831.645050648494, 12050.096242781827, 589796.0, 605986.0, 606261.0, 614269.0, 626941.0, 92157.53921230351, 101658.49170754247, 117277.0, 145496.27251863672, 151891.0, 915.2906497464518, 923.0584803500392, 959.1319855530755, 960.6925783749273, 962.3888830592484, 19023.33418330352, 20151.899240503702, 20546.383608491768, 20587.79412205859, 20637.793622063586, 56929.86140277254, 58069.0, 59900.0, 60647.696761517676, 60731.0, 80046.5997670008, 80625.5499560022, 82183.58908205421, 82285.0, 85444.57277713572, 7087.751928682472, 8055.516668999879, 8184.2224988625985, 8280.83438331242, 8476.957615211884, 11917.940410297893, 12363.505459781867, 12472.314022728837, 12757.362131893553, 13342.466301348226, 11590.014848737952, 11688.006519445602, 11688.649340519458, 11888.9894358977, 11919.284842909106, 11035.503402346752, 11455.942720286344, 11532.25040372373, 11602.18784685064, 11900.28598284071, 11273.830892536782, 11629.59296424621, 11911.225770324902, 11925.940370298093, 12030.939845300718, 7870.803229919298, 8060.355171586141, 8076.878846817185, 8110.513369197631, 8364.0, 11274.436278185894, 11510.0, 11752.529898803952, 11814.586489472827, 11923.940380298043], "value": 9652.942483832538, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.2024874950655, 1522.6650136969863, 1650.4141029934374, 1799.2892807341093, 1856.3595559531966, 8318.292945099667, 8869.42348747331, 8876.822463550734, 8878.86681699775, 8895.955520222406, 7088.326608972152, 7826.686932522693, 8050.95974520128, 8557.529335886524, 8579.356548258875, 2357.823163262758, 2391.485830546432, 2391.6053851114634, 2393.5212957408503, 2393.724721657006, 2327.55776402483, 2366.5976783946685, 2375.429896824758, 2375.643653451979, 2386.427257458206, 2365.396823809926, 2365.4914193448403, 2375.798057165143, 2393.7366889642212, 2400.8919598618104, 2329.906803727849, 2340.8946597403074, 2353.97646023541, 2358.5282943411385, 2373.0, 2253.5154941687615, 2254.2786308381324, 2255.8082562982167, 2259.5819773341987, 2261.0, 8726.912730872673, 8731.0, 8829.293656507463, 8839.02770695223, 8861.291096712312, 5193.376794784613, 5269.841904742864, 5366.838994830161, 5379.892402151974, 5388.326459192601, 3406.8466918988706, 3440.6559344065395, 3558.323918455511, 3650.5071815305123, 3651.032476393758, 1057.550541020071, 1099.5766629847567, 1111.4609414433994, 1120.5293776613842, 1124.9043831274269, 11482.253653512495, 11542.192046556744, 11587.942060289728, 11639.94180029103, 11708.0, 4439.467263928349, 4656.3713898623255, 4720.693154944986, 4827.396575428072, 4856.053069651384, 9815.0, 9817.656382026595, 9880.45657488845, 9897.208223342259, 9920.355176913621, 5292.470752924677, 5308.0710875596615, 5311.601629877775, 5318.175682769177, 5332.33345831771, 4385.429894113745, 4548.317752337176, 4770.379850619467, 4862.124817532807, 4886.682365646222, 3867.0, 3937.586553411908, 4022.9195416091516, 4046.6964977626794, 4069.064115253449, 1293.489071816627, 1295.9805602915965, 1301.3883474766844, 1309.4173092973726, 1317.8682131786745, 762.4929421934462, 860.350435421253, 893.6827426263712, 928.1553786054793, 951.1822618437592, 4489.6408287336935, 5293.629445938747, 5736.827895163068, 5947.286325640951, 6160.537959653044, 885.6147575804417, 939.3987847777428, 953.5613617735919, 961.2166084640995, 980.7302991677345, 3569.1434055826935, 3913.62820532049, 3926.6662333701956, 4023.5372932112596, 4026.315526360526, 10501.369917804956, 10774.138068954464, 10810.891891081146, 10817.405042722727, 10830.566777328977, 10700.678979630624, 10709.892901071045, 10715.624953126604, 10721.624743133953, 10723.89276107229, 8047.718329858427, 8192.508449493049, 8193.426460147852, 8198.713045043514, 8214.301784348278, 5445.910817836528, 5571.9442805571425, 5671.574631902707, 5873.295204575478, 5883.676397798162, 8904.86642700373, 9801.31390802637, 9957.0540798624, 10084.344517606332, 10111.54498047575, 9778.55996480146, 10226.977302269714, 10264.230182736175, 10332.793344132924, 10338.622116913002, 6760.492963027694, 7019.4735394844565, 7021.613811240431, 7176.425885929218, 7682.769516914502, 6786.76246331376, 7839.294463498312, 8007.399445041528, 8023.799405014921, 8385.916140838513, 6991.510594258353, 7426.925730742623, 7490.475666703279, 7513.924860751323, 7579.279968402993, 7320.816979575553, 7329.984254329888, 7374.078240219973, 7385.852282954418, 7474.850502990019, 6854.520183587101, 6990.755323563651, 7041.0, 7080.681369338495, 7085.0, 4919.286703428054, 4969.254611808257, 4996.15065438869, 5026.120428924851, 5120.359955005624, 4878.341423907727, 4887.486813884491, 4972.875678107932, 4988.55103040728, 5169.922451163311], "value": 4559.742594271837, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [267.77105574733605, 286.7562571813958, 307.49263714870455, 312.788867514428, 314.065654677335, 4576.313552967056, 4686.531346865312, 5068.670536415139, 5298.91372268685, 5315.309009828727, 4730.97634511827, 5109.313926404287, 5163.179247356957, 5180.8704782380455, 5224.764885580148, 3242.481203007519, 3752.418375151856, 4033.737807042533, 4078.6547735052313, 4094.406311084891, 3891.280113179071, 4026.4161696553983, 4070.084231048005, 4075.5924407559155, 4101.8974525636795, 3624.275144971003, 3750.699944004487, 3999.160176362953, 4011.8165141283243, 4045.896134010313, 3282.8030318180986, 3989.3417586098403, 4005.978475488761, 4016.6786657067364, 4036.4147198656324, 3466.8559375406057, 3470.6529347065343, 3502.8423720932365, 3512.5071844466256, 3512.947305790415, 4271.722338048017, 4730.763461826895, 4743.335932969387, 4799.208130658437, 4824.927626085611, 3808.9619103808886, 3840.5391353037535, 4473.46318441789, 4496.55034496551, 4500.964778101023, 4933.7912211508055, 5430.185472179205, 5434.891302173974, 5480.314960629921, 5515.558755299607, 3064.555639432292, 3208.5187221916667, 3441.4149594568985, 3656.945145822815, 3695.7043436525014, 4820.276958456259, 4910.912862033621, 5254.7635356409055, 5806.854828629233, 5820.214271073393, 3841.769493830379, 3850.8074596270076, 3852.325842977468, 3854.807259637007, 3862.845486180521, 3911.2177564487215, 3917.2948869203246, 3924.47019652349, 3931.1744533647684, 3949.763014219156, 4157.3140431829015, 4282.743035417885, 4476.239039363316, 4565.178267911741, 4583.679142460062, 4881.341018962466, 5083.339165908408, 5312.787488500494, 5337.466253374631, 5592.384837667853, 1251.380566619511, 1286.7748144074749, 1295.8704129586965, 1300.4603089717662, 1301.297299458282, 749.5802350683525, 844.3582877013391, 850.4047166983137, 866.6966561703354, 873.214107303432, 4997.875902741321, 5177.430482646906, 5191.755153029357, 5211.973940130275, 5222.1383471727495, 5453.0, 5682.77268909239, 5693.202951586781, 5707.800226992035, 5714.342850572238, 4881.116996051423, 4994.9750251248515, 5097.949020509822, 5145.9416233506145, 5147.6654017488745, 2943.8822447101875, 2976.345204055104, 2987.9551806722916, 2991.7008299170343, 3013.789034767545, 5120.948790512121, 5911.438413350725, 5920.5559583031445, 5932.8813423732145, 5942.405759424109, 5528.55771538276, 5805.748780682831, 5842.824715258549, 5850.9414905851245, 5864.70676466175, 5872.765089396376, 5894.233749612523, 5904.6714489239575, 5936.139259807217, 5939.613925094855, 5363.356397232357, 5597.916031259616, 5709.115087161496, 5723.567483819277, 5764.567657425626, 5164.896702065863, 5643.125315576092, 5649.491545760901, 5652.745626446737, 5724.427557244242, 5149.948500515096, 5862.530997520103, 5907.312172961207, 5918.556108291981, 5962.55280853929, 3195.936081278408, 3260.6902344277255, 3287.8356082196274, 3532.3994920863, 3821.980890095532, 4673.299005149255, 4894.437139728977, 5416.102385223886, 5499.587530935116, 5570.4151064139305, 4554.608830364085, 5317.813876514303, 5519.917201241904, 5542.418046105262, 5591.748371323219, 5275.287836142225, 5502.532284755841, 5507.311586051744, 5517.59301378146, 5571.247881535941, 4070.5115386153852, 4947.282644016525, 5195.636305458582, 5371.570274377962, 5454.454554544673, 5051.105835311758, 5498.422665620213, 5504.4495550444635, 5512.7519261634125, 5524.779008839601, 4723.50403207672, 4937.753112344297, 5382.327209098863, 5482.725863706719, 5488.917666235089], "value": 4008.4553139774453, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.9018682477865, 732.8497657980112, 759.2938567132564, 780.6955287437901, 837.2339309531778, 15259.618509537268, 16101.114438705867, 16123.0, 16141.354345826183, 16148.83851161488, 14547.709045819078, 14667.486637967673, 14686.706265874676, 14694.485693000746, 14718.779218311734, 669.4242951062083, 685.4687617096765, 691.3466773898667, 694.1565997313263, 824.0811495182858, 694.7221111555368, 826.6445428465757, 852.7953291210127, 853.3599800149888, 857.5369300577712, 746.4476287547203, 902.6840605787987, 906.8095699903029, 907.0340087806497, 907.3557773980482, 898.1961144775422, 901.3420203251598, 901.5086777706166, 901.544719916438, 901.7655409593552, 527.1776029394119, 537.9731013449351, 667.2293501006345, 670.4837275297994, 670.8557660103102, 12414.131010829245, 12518.937405313007, 12530.0, 13475.0, 13975.09161904473, 14302.713945721027, 14824.551320175759, 14934.850651493454, 14952.850471495363, 15001.399944002227, 11929.343886086263, 11972.58095966637, 12055.758884822255, 12268.81596776049, 12499.312537810416, 3447.7588068295395, 3853.460515527828, 3947.4078888167005, 3951.486306780129, 4096.959030409687, 105243.0, 106523.0, 107570.0, 108059.45970270177, 108158.37762433491, 11224.438778061227, 11301.773964520664, 11569.0, 11585.942070289762, 11652.766944661229, 23196.7680323198, 23219.883900580728, 23229.186978455673, 23232.3030309091, 23284.185053523044, 6821.965890170517, 7156.534825236343, 7956.204379562113, 8288.171182881664, 8356.498610083292, 3638.3632864248652, 4640.629899313612, 4661.137689527448, 4666.230072038144, 4798.328234047236, 3398.2693720850134, 3464.6881780639865, 3496.30073985204, 3511.3328467591077, 3515.8593656253465, 620.7082671144555, 624.7688355308474, 636.7325723196269, 642.5919541091347, 647.7214797636967, 613.8680183760424, 615.8922188616974, 654.404491912367, 667.9131712877295, 685.5920727167328, 8617.396782225183, 8925.241354484811, 9524.238060955107, 9658.082482164324, 10029.19099249371, 668.9866202675919, 676.6921050921819, 685.7359916432109, 687.621808005595, 695.9373656370951, 4448.733076015449, 4756.5956893664425, 4762.690425122356, 4763.666543342002, 4810.326554282403, 94663.58004629936, 135957.32021340026, 143298.0, 164751.0, 168983.41182469917, 133565.0, 138717.0475395322, 139688.0, 142373.0, 147518.0, 99117.0, 100352.0, 117360.32995875516, 117737.82262177269, 119703.0, 9088.772780680272, 9105.499197543933, 9126.771830704285, 9206.355555111346, 9408.717738467856, 63383.04925426214, 64092.0, 64867.918491410295, 67050.65898682097, 67575.66212168908, 68571.65714171597, 84828.0, 100218.0, 100462.0, 102637.0, 11894.405279735805, 12156.149069565046, 12172.817407739069, 12184.015703311217, 12853.614391568268, 13140.802887956497, 13241.801372979606, 13299.80050299227, 13322.800157997444, 13546.848517876091, 11585.07319414428, 12174.0, 12445.875541244472, 12457.0, 12841.678958026125, 11394.202405831513, 11895.750946150529, 12075.215111017756, 12570.742925707356, 12732.745345093232, 12054.337011464453, 12125.514979400725, 12264.202826816228, 12650.367481626066, 12667.873321267038, 9605.711828645151, 10439.0, 10473.738156546147, 10511.842322365324, 10535.209859260432, 8485.321174305916, 9319.673811416567, 9463.905360946303, 11037.833739074922, 11640.767184656428], "value": 7675.506181804229, "unit": "score"}], "name": "dromaeo_css", "value": 7301.706822403219, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 167.0, 161.0, 114.0, 108.0, 113.0, 113.0, 114.0, 112.0, 114.0], "value": 122.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 108.0, 106.0, 166.0, 108.0, 107.0, 133.0, 107.0, 143.0, 108.0], "value": 123.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 185.0, 184.0, 184.0, 183.0, 185.0, 181.0, 184.0, 184.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 61.0, 89.0, 58.0, 58.0, 69.0, 59.0, 83.0, 57.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 101.0, 71.0, 70.0, 69.0, 97.0, 71.0, 68.0, 106.0], "value": 80.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 87.0, 86.0, 89.0, 84.0, 89.0, 84.0, 87.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 101.0, 143.0, 94.0, 94.0, 93.0, 94.0, 94.0, 93.0], "value": 99.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 85.0, 83.0, 87.0, 83.0, 84.0, 84.0, 85.0, 81.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 56.0, 56.0, 55.0, 56.0, 53.0, 55.0, 53.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 78.0, 75.0, 73.0, 68.0, 68.0, 69.0, 69.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 85.0, 88.0, 83.0, 85.0, 88.0, 85.0, 88.0, 74.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 149.0, 152.0, 136.0, 136.0, 138.0, 136.0, 147.0, 224.0, 140.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 169.0, 169.0, 168.0, 169.0, 170.0, 166.0, 166.0, 166.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 67.0, 67.0, 68.0, 67.0, 67.0, 67.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1443.4, "alertThreshold": 2.0}]} 10:48:47 INFO - Return code: 0 10:48:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:48:47 INFO - # TBPL SUCCESS # 10:48:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:48:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:48:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:48:47 INFO - 2016-08-15 10:48:47.765 screenresolution[1939:19628] starting screenresolution argv=screenresolution get 10:48:47 INFO - 2016-08-15 10:48:47.767 screenresolution[1939:19628] Display 0: 1600x1200x32@60 10:48:47 INFO - 2016-08-15 10:48:47.773 screenresolution[1940:19631] starting screenresolution argv=screenresolution list 10:48:47 INFO - Available Modes on Display 0 10:48:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:48:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:48:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:48:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:48:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:48:48 INFO - Intel Iris: 10:48:48 INFO - Chipset Model: Intel Iris 10:48:48 INFO - Type: GPU 10:48:48 INFO - Bus: Built-In 10:48:48 INFO - VRAM (Dynamic, Max): 1536 MB 10:48:48 INFO - Vendor: Intel (0x8086) 10:48:48 INFO - Device ID: 0x0a2e 10:48:48 INFO - Revision ID: 0x0009 10:48:48 INFO - Displays: 10:48:48 INFO - Display: 10:48:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:48:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:48:48 INFO - Display Serial Number: 1600x1200 60 10:48:48 INFO - Main Display: Yes 10:48:48 INFO - Mirror: Off 10:48:48 INFO - Online: Yes 10:48:48 INFO - Rotation: Supported 10:48:48 INFO - Return code: 0 10:48:48 INFO - Running post-action listener: _resource_record_post_action 10:48:48 INFO - [mozharness: 2016-08-15 17:48:48.007068Z] Finished run-tests step (success) 10:48:48 INFO - Running post-run listener: _resource_record_post_run 10:48:48 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 2457600; Write bytes: 701421056; Read time: 16; Write time: 2119 10:48:48 INFO - TinderboxPrint: CPU usage
53.4% 10:48:48 INFO - TinderboxPrint: I/O read bytes / time
2,457,600 / 16 10:48:48 INFO - TinderboxPrint: I/O write bytes / time
701,421,056 / 2,119 10:48:48 INFO - TinderboxPrint: CPU idle
2,259.8 (46.6%) 10:48:48 INFO - TinderboxPrint: CPU system
968.5 (20.0%) 10:48:48 INFO - TinderboxPrint: CPU user
1,621.4 (33.4%) 10:48:48 INFO - TinderboxPrint: Swap in / out
406,970,368 / 0 10:48:48 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 385546752; Write bytes: 379168256; Read time: 29232; Write time: 796 10:48:48 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 2457600; Write bytes: 294707200; Read time: 16; Write time: 1263 10:48:48 INFO - Running post-run listener: _upload_blobber_files 10:48:48 INFO - Blob upload gear active. 10:48:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:48:48 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 10:48:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:48:49 INFO - (blobuploader) - INFO - Open directory for files ... 10:48:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:48:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:48:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:48:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:48:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:48:50 INFO - (blobuploader) - INFO - Done attempting. 10:48:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:48:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:48:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:48:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:48:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:48:51 INFO - (blobuploader) - INFO - Done attempting. 10:48:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:48:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:48:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:48:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:48:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:48:53 INFO - (blobuploader) - INFO - Done attempting. 10:48:53 INFO - (blobuploader) - INFO - Iteration through files over. 10:48:53 INFO - Return code: 0 10:48:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:48:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:48:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b3c2e6ae3bc91aa5a5ebf9efe9075b96ea4ee55f7c58ca702c83575b54c2e63005e2cb4323f660bfe88b5636ddd4aab8cb7691bc39945e578bb7ce096bf356bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9098f61cfa8083301e3ae51e8953872f4f13f645e76fee59732484e299a2dc7d3433b5202963b3af64c922711a607b6b652627244b1bb87348c787be69f42811", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/484e219a0558e53ed29ec7f36a3c6a3b24687bad4fb25013977391322904966452761af4d90716fd519fbbd9ccde906fa85e1f9aa864c95991da1560f890e378"} 10:48:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:48:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:48:53 INFO - Contents: 10:48:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b3c2e6ae3bc91aa5a5ebf9efe9075b96ea4ee55f7c58ca702c83575b54c2e63005e2cb4323f660bfe88b5636ddd4aab8cb7691bc39945e578bb7ce096bf356bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9098f61cfa8083301e3ae51e8953872f4f13f645e76fee59732484e299a2dc7d3433b5202963b3af64c922711a607b6b652627244b1bb87348c787be69f42811", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/484e219a0558e53ed29ec7f36a3c6a3b24687bad4fb25013977391322904966452761af4d90716fd519fbbd9ccde906fa85e1f9aa864c95991da1560f890e378"} 10:48:53 INFO - Running post-run listener: copy_logs_to_upload_dir 10:48:53 INFO - Copying logs to upload dir... 10:48:53 INFO - mkdir: /builds/slave/test/build/upload/logs 10:48:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.109183 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-15 10:48:53.280681) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 10:48:53.284230) ========= 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.oKNCgV4vFf/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.t7NdtqAs7A/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b3c2e6ae3bc91aa5a5ebf9efe9075b96ea4ee55f7c58ca702c83575b54c2e63005e2cb4323f660bfe88b5636ddd4aab8cb7691bc39945e578bb7ce096bf356bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9098f61cfa8083301e3ae51e8953872f4f13f645e76fee59732484e299a2dc7d3433b5202963b3af64c922711a607b6b652627244b1bb87348c787be69f42811", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/484e219a0558e53ed29ec7f36a3c6a3b24687bad4fb25013977391322904966452761af4d90716fd519fbbd9ccde906fa85e1f9aa864c95991da1560f890e378"} build_url:https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011287 build_url: 'https://queue.taskcluster.net/v1/task/Ygn9rWyXS_O1IjMnGzKw6A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b3c2e6ae3bc91aa5a5ebf9efe9075b96ea4ee55f7c58ca702c83575b54c2e63005e2cb4323f660bfe88b5636ddd4aab8cb7691bc39945e578bb7ce096bf356bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9098f61cfa8083301e3ae51e8953872f4f13f645e76fee59732484e299a2dc7d3433b5202963b3af64c922711a607b6b652627244b1bb87348c787be69f42811", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/484e219a0558e53ed29ec7f36a3c6a3b24687bad4fb25013977391322904966452761af4d90716fd519fbbd9ccde906fa85e1f9aa864c95991da1560f890e378"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 10:48:53.318390) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 10:48:53.318743) ========= 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.oKNCgV4vFf/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.t7NdtqAs7A/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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.005088 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 10:48:53.367696) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 10:48:53.368013) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 10:48:53.368366) ========= ========= Total master_lag: 0.23 =========