builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0105 starttime: 1472731780.62 results: success (0) buildid: 20160901025721 builduid: c6f312169de444e5a868d92531a05951 revision: 0770506cb101cb2a70d0e4d267361767a9bfc9b6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 05:09:40.619364) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 05:09:40.619824) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 05:09:40.620138) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 05:09:40.665150) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 05:09:40.665553) ========= 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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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-09-01 05:09:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 5.91M=0.002s 2016-09-01 05:09:40 (5.91 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.302362 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 05:09:41.000507) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 05:09:41.000811) ========= 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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.020490 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 05:09:41.049620) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 05:09:41.049943) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 0770506cb101cb2a70d0e4d267361767a9bfc9b6 --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 0770506cb101cb2a70d0e4d267361767a9bfc9b6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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-09-01 05:09:41,127 truncating revision to first 12 chars 2016-09-01 05:09:41,127 Setting DEBUG logging. 2016-09-01 05:09:41,127 attempt 1/10 2016-09-01 05:09:41,127 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/0770506cb101?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 05:09:42,249 unpacking tar archive at: autoland-0770506cb101/testing/mozharness/ program finished with exit code 0 elapsedTime=1.384738 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 05:09:42.457047) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 05:09:42.457509) ========= script_repo_revision: 0770506cb101cb2a70d0e4d267361767a9bfc9b6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 05:09:42.458023) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 05:09:42.458392) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 05:09:42.477398) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-09-01 05:09:42.477816) ========= /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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 05:09:42 INFO - MultiFileLogger online at 20160901 05:09:42 in /builds/slave/test 05:09:42 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 05:09:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:09:42 INFO - {'append_to_log': False, 05:09:42 INFO - 'base_work_dir': '/builds/slave/test', 05:09:42 INFO - 'blob_upload_branch': 'Autoland', 05:09:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:09:42 INFO - 'branch': 'Autoland', 05:09:42 INFO - 'buildbot_json_path': 'buildprops.json', 05:09:42 INFO - 'config_files': ('talos/mac_config.py',), 05:09:42 INFO - 'default_actions': ('clobber', 05:09:42 INFO - 'read-buildbot-config', 05:09:42 INFO - 'download-and-extract', 05:09:42 INFO - 'populate-webroot', 05:09:42 INFO - 'create-virtualenv', 05:09:42 INFO - 'install', 05:09:42 INFO - 'run-tests'), 05:09:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:09:42 INFO - 'download_minidump_stackwalk': True, 05:09:42 INFO - 'download_symbols': 'ondemand', 05:09:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:09:42 INFO - 'tooltool.py': '/tools/tooltool.py', 05:09:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:09:42 INFO - '/tools/misc-python/virtualenv.py')}, 05:09:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:09:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:09:42 INFO - 'installer_path': 'installer.exe', 05:09:42 INFO - 'log_level': 'info', 05:09:42 INFO - 'log_name': 'talos', 05:09:42 INFO - 'log_to_console': True, 05:09:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:09:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:09:42 INFO - 'opt_config_files': (), 05:09:42 INFO - 'pip_index': False, 05:09:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:09:42 INFO - 'cmd': ('bash', 05:09:42 INFO - '-c', 05:09:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:09:42 INFO - 'enabled': True, 05:09:42 INFO - 'halt_on_failure': False, 05:09:42 INFO - 'name': 'check_screen_resolution'},), 05:09:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:09:42 INFO - 'cmd': ('bash', 05:09:42 INFO - '-c', 05:09:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:09:42 INFO - 'enabled': True, 05:09:42 INFO - 'halt_on_failure': False, 05:09:42 INFO - 'name': 'check_screen_resolution'},), 05:09:42 INFO - 'run_cmd_checks_enabled': True, 05:09:42 INFO - 'sps_profile': False, 05:09:42 INFO - 'sps_profile_interval': 0, 05:09:42 INFO - 'suite': 'dromaeojs', 05:09:42 INFO - 'system_bits': '32', 05:09:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:09:42 INFO - 'title': 't-yosemite-r7-0105', 05:09:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:09:42 INFO - 'use_talos_json': True, 05:09:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:09:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:09:42 INFO - 'work_dir': 'build'} 05:09:42 INFO - [mozharness: 2016-09-01 12:09:42.673437Z] Running clobber step. 05:09:42 INFO - Running pre-action listener: _resource_record_pre_action 05:09:42 INFO - Running main action method: clobber 05:09:42 INFO - rmtree: /builds/slave/test/build 05:09:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:09:44 INFO - Running post-action listener: _resource_record_post_action 05:09:44 INFO - [mozharness: 2016-09-01 12:09:44.103298Z] Finished clobber step (success) 05:09:44 INFO - [mozharness: 2016-09-01 12:09:44.103432Z] Running read-buildbot-config step. 05:09:44 INFO - Running pre-action listener: _resource_record_pre_action 05:09:44 INFO - Running main action method: read_buildbot_config 05:09:44 INFO - Using buildbot properties: 05:09:44 INFO - { 05:09:44 INFO - "project": "", 05:09:44 INFO - "product": "firefox", 05:09:44 INFO - "script_repo_revision": "production", 05:09:44 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 05:09:44 INFO - "repository": "", 05:09:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 05:09:44 INFO - "buildid": "20160901025721", 05:09:44 INFO - "pgo_build": "False", 05:09:44 INFO - "basedir": "/builds/slave/test", 05:09:44 INFO - "buildnumber": 209, 05:09:44 INFO - "slavename": "t-yosemite-r7-0105", 05:09:44 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 05:09:44 INFO - "platform": "yosemite_r7", 05:09:44 INFO - "scheduler": "tests-autoland-macosx64-talos", 05:09:44 INFO - "branch": "autoland", 05:09:44 INFO - "repo_path": "integration/autoland", 05:09:44 INFO - "revision": "0770506cb101cb2a70d0e4d267361767a9bfc9b6", 05:09:44 INFO - "stage_platform": "macosx64", 05:09:44 INFO - "builduid": "c6f312169de444e5a868d92531a05951", 05:09:44 INFO - "slavebuilddir": "test" 05:09:44 INFO - } 05:09:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:09:44 INFO - Running post-action listener: _resource_record_post_action 05:09:44 INFO - [mozharness: 2016-09-01 12:09:44.106746Z] Finished read-buildbot-config step (success) 05:09:44 INFO - [mozharness: 2016-09-01 12:09:44.106861Z] Running download-and-extract step. 05:09:44 INFO - Running pre-action listener: _resource_record_pre_action 05:09:44 INFO - Running main action method: download_and_extract 05:09:44 INFO - mkdir: /builds/slave/test/build/tests 05:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:44 INFO - trying https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:09:44 INFO - Downloading https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/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 05:09:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/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 05:09:45 INFO - Downloaded 1199 bytes. 05:09:45 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:09:45 INFO - Using the following test package requirements: 05:09:45 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:09:45 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:09:45 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:09:45 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:09:45 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:09:45 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:09:45 INFO - u'jsshell-mac.zip'], 05:09:45 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:09:45 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:09:45 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:09:45 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:09:45 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:09:45 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:09:45 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:09:45 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:09:45 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:09:45 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:09:45 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:09:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:45 INFO - trying https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:09:45 INFO - Downloading https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/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 05:09:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/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 05:09:47 INFO - Downloaded 16398122 bytes. 05:09:47 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:09:54 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 05:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:54 INFO - trying https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:09:54 INFO - Downloading https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/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 05:09:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/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 05:09:56 INFO - Downloaded 16398122 bytes. 05:09:56 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:10:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:02 INFO - trying https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:10:02 INFO - Downloading https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/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 05:10:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/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 05:10:04 INFO - Downloaded 14413998 bytes. 05:10:04 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 05:10:04 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')]} 05:10:04 INFO - trying https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:10:04 INFO - Downloading https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:10:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/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 05:10:09 INFO - Downloaded 111596445 bytes. 05:10:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:10:09 INFO - mkdir: /builds/slave/test/properties 05:10:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:10:09 INFO - Writing to file /builds/slave/test/properties/build_url 05:10:09 INFO - Contents: 05:10:09 INFO - build_url:https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:10:10 INFO - Running post-action listener: _resource_record_post_action 05:10:10 INFO - Running post-action listener: set_extra_try_arguments 05:10:10 INFO - [mozharness: 2016-09-01 12:10:10.498328Z] Finished download-and-extract step (success) 05:10:10 INFO - [mozharness: 2016-09-01 12:10:10.498447Z] Running populate-webroot step. 05:10:10 INFO - Running pre-action listener: _resource_record_pre_action 05:10:10 INFO - Running main action method: populate_webroot 05:10:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:10:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:10:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:10:10 INFO - '--tppagecycles', 05:10:10 INFO - '7'], 05:10:10 INFO - 'tests': ['tsvgm']}}, 05:10:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:10:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:10:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:10:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:10:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:10:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:10:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:10 INFO - 'talos_options': ['--disable-e10s'], 05:10:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:10:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:10:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:10 INFO - 'talos_options': ['--disable-e10s'], 05:10:10 INFO - 'tests': ['damp', 'tps']}, 05:10:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:10 INFO - 'tests': ['damp', 'tps']}, 05:10:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:10:10 INFO - 'tests': ['dromaeo_dom']}, 05:10:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:10:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:10:10 INFO - 'tests': ['basic_compositor_video']}, 05:10:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:10:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:10:10 INFO - 'tests': ['a11yr', 05:10:10 INFO - 'ts_paint', 05:10:10 INFO - 'tpaint', 05:10:10 INFO - 'sessionrestore', 05:10:10 INFO - 'sessionrestore_no_auto_restore', 05:10:10 INFO - 'tabpaint']}, 05:10:10 INFO - 'other-e10s': {'tests': ['a11yr', 05:10:10 INFO - 'ts_paint', 05:10:10 INFO - 'tpaint', 05:10:10 INFO - 'sessionrestore', 05:10:10 INFO - 'sessionrestore_no_auto_restore', 05:10:10 INFO - 'tabpaint']}, 05:10:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:10:10 INFO - 'ts_paint', 05:10:10 INFO - 'tpaint', 05:10:10 INFO - 'sessionrestore', 05:10:10 INFO - 'sessionrestore_no_auto_restore', 05:10:10 INFO - 'tabpaint']}, 05:10:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:10:10 INFO - 'ts_paint', 05:10:10 INFO - 'tpaint', 05:10:10 INFO - 'sessionrestore', 05:10:10 INFO - 'sessionrestore_no_auto_restore', 05:10:10 INFO - 'tabpaint']}, 05:10:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:10:10 INFO - 'tests': ['a11yr', 05:10:10 INFO - 'ts_paint', 05:10:10 INFO - 'tpaint', 05:10:10 INFO - 'sessionrestore', 05:10:10 INFO - 'sessionrestore_no_auto_restore', 05:10:10 INFO - 'tabpaint']}, 05:10:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:10:10 INFO - 'tests': ['a11yr', 05:10:10 INFO - 'ts_paint', 05:10:10 INFO - 'tpaint', 05:10:10 INFO - 'sessionrestore', 05:10:10 INFO - 'sessionrestore_no_auto_restore', 05:10:10 INFO - 'tabpaint']}, 05:10:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:10:10 INFO - 'tests': ['tsvgx', 05:10:10 INFO - 'tsvgr_opacity', 05:10:10 INFO - 'tart', 05:10:10 INFO - 'tscrollx', 05:10:10 INFO - 'cart']}, 05:10:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:10:10 INFO - 'tsvgr_opacity', 05:10:10 INFO - 'tart', 05:10:10 INFO - 'tscrollx', 05:10:10 INFO - 'cart']}, 05:10:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:10 INFO - 'talos_options': ['--disable-e10s'], 05:10:10 INFO - 'tests': ['tp5o']}, 05:10:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:10 INFO - 'tests': ['tp5o']}, 05:10:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:10 INFO - 'talos_options': ['--disable-e10s', 05:10:10 INFO - '--xperf_path', 05:10:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:10:10 INFO - 'tests': ['tp5n']}, 05:10:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:10 INFO - 'talos_options': ['--xperf_path', 05:10:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:10:10 INFO - 'tests': ['tp5n']}}, 05:10:10 INFO - 'talos.zip': {'path': '', 05:10:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:10:10 INFO - Running post-action listener: _resource_record_post_action 05:10:10 INFO - [mozharness: 2016-09-01 12:10:10.517212Z] Finished populate-webroot step (success) 05:10:10 INFO - [mozharness: 2016-09-01 12:10:10.517345Z] Running create-virtualenv step. 05:10:10 INFO - Running pre-action listener: _resource_record_pre_action 05:10:10 INFO - Running main action method: create_virtualenv 05:10:10 INFO - Creating virtualenv /builds/slave/test/build/venv 05:10:10 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 05:10:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:10:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:10:10 INFO - Using real prefix '/tools/python27' 05:10:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:10:11 INFO - Installing distribute.............................................................................................................................................................................................done. 05:10:14 INFO - Installing pip.................done. 05:10:14 INFO - Return code: 0 05:10:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:10:14 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')]} 05:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:14 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')]} 05:10:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:14 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 0x10c8e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb953d94e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c849d30>, '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 0x10c5aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 05:10:14 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 05:10:14 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 05:10:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q7dA9ofQuL/Render', 05:10:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:14 INFO - 'HOME': '/Users/cltbld', 05:10:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:14 INFO - 'LOGNAME': 'cltbld', 05:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:14 INFO - 'MOZ_NO_REMOTE': '1', 05:10:14 INFO - 'NO_EM_RESTART': '1', 05:10:14 INFO - 'PAGER': '/bin/cat', 05:10:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:14 INFO - 'PWD': '/builds/slave/test', 05:10:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:14 INFO - 'SHELL': '/bin/bash', 05:10:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zj5CKev0zV/Listeners', 05:10:14 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 05:10:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:14 INFO - 'USER': 'cltbld', 05:10:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:14 INFO - 'XPC_FLAGS': '0x0', 05:10:14 INFO - 'XPC_SERVICE_NAME': '0', 05:10:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:14 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 05:10:14 INFO - Cleaning up... 05:10:14 INFO - Return code: 0 05:10:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:10:14 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')]} 05:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:14 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')]} 05:10:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:14 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 0x10c8e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb953d94e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c849d30>, '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 0x10c5aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 05:10:14 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 05:10:14 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 05:10:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q7dA9ofQuL/Render', 05:10:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:14 INFO - 'HOME': '/Users/cltbld', 05:10:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:14 INFO - 'LOGNAME': 'cltbld', 05:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:14 INFO - 'MOZ_NO_REMOTE': '1', 05:10:14 INFO - 'NO_EM_RESTART': '1', 05:10:14 INFO - 'PAGER': '/bin/cat', 05:10:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:14 INFO - 'PWD': '/builds/slave/test', 05:10:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:14 INFO - 'SHELL': '/bin/bash', 05:10:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zj5CKev0zV/Listeners', 05:10:14 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 05:10:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:14 INFO - 'USER': 'cltbld', 05:10:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:14 INFO - 'XPC_FLAGS': '0x0', 05:10:14 INFO - 'XPC_SERVICE_NAME': '0', 05:10:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:14 INFO - Downloading/unpacking psutil>=3.1.1 05:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:10:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:10:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:10:18 INFO - Installing collected packages: psutil 05:10:18 INFO - Running setup.py install for psutil 05:10:18 INFO - building 'psutil._psutil_osx' extension 05:10:18 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 05:10:19 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 05:10:19 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 05:10:19 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 05:10:19 INFO - building 'psutil._psutil_posix' extension 05:10:19 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 05:10:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:10:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:10:19 INFO - ^ 05:10:19 INFO - 1 warning generated. 05:10:19 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 05:10:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:10:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:10:19 INFO - Successfully installed psutil 05:10:19 INFO - Cleaning up... 05:10:19 INFO - Return code: 0 05:10:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:10: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')]} 05:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10: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')]} 05:10:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c8e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb953d94e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c849d30>, '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 0x10c5aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 05:10: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:10: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 mozsystemmonitor==0.3 05:10:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q7dA9ofQuL/Render', 05:10:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:19 INFO - 'HOME': '/Users/cltbld', 05:10:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:19 INFO - 'LOGNAME': 'cltbld', 05:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:19 INFO - 'MOZ_NO_REMOTE': '1', 05:10:19 INFO - 'NO_EM_RESTART': '1', 05:10:19 INFO - 'PAGER': '/bin/cat', 05:10:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:19 INFO - 'PWD': '/builds/slave/test', 05:10:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:19 INFO - 'SHELL': '/bin/bash', 05:10:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zj5CKev0zV/Listeners', 05:10:19 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 05:10:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:19 INFO - 'USER': 'cltbld', 05:10:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:19 INFO - 'XPC_FLAGS': '0x0', 05:10:19 INFO - 'XPC_SERVICE_NAME': '0', 05:10:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:10: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 05:10: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 05:10: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 05:10: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 05:10: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 05:10: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 05:10:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:10:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:10:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:10:22 INFO - Installing collected packages: mozsystemmonitor 05:10:22 INFO - Running setup.py install for mozsystemmonitor 05:10:22 INFO - Successfully installed mozsystemmonitor 05:10:22 INFO - Cleaning up... 05:10:22 INFO - Return code: 0 05:10:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:22 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 0x10c8e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb953d94e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c849d30>, '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 0x10c5aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 05:10:22 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 05:10:22 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 05:10:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q7dA9ofQuL/Render', 05:10:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:22 INFO - 'HOME': '/Users/cltbld', 05:10:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:22 INFO - 'LOGNAME': 'cltbld', 05:10:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:22 INFO - 'MOZ_NO_REMOTE': '1', 05:10:22 INFO - 'NO_EM_RESTART': '1', 05:10:22 INFO - 'PAGER': '/bin/cat', 05:10:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:22 INFO - 'PWD': '/builds/slave/test', 05:10:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:22 INFO - 'SHELL': '/bin/bash', 05:10:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zj5CKev0zV/Listeners', 05:10:22 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 05:10:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:22 INFO - 'USER': 'cltbld', 05:10:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:22 INFO - 'XPC_FLAGS': '0x0', 05:10:22 INFO - 'XPC_SERVICE_NAME': '0', 05:10:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:22 INFO - Downloading/unpacking jsonschema==2.5.1 05:10: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 05:10: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 05:10: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 05:10: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 05:10: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 05:10: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 05:10:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:10:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:25 INFO - Downloading functools32-3.2.3-2.tar.gz 05:10:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:10:25 INFO - warning: no files found matching '*.txt' 05:10:25 INFO - no previously-included directories found matching 'build' 05:10:25 INFO - no previously-included directories found matching 'dist' 05:10:25 INFO - no previously-included directories found matching '.git*' 05:10:25 INFO - Installing collected packages: jsonschema, functools32 05:10:25 INFO - Running setup.py install for functools32 05:10:26 INFO - warning: no files found matching '*.txt' 05:10:26 INFO - no previously-included directories found matching 'build' 05:10:26 INFO - no previously-included directories found matching 'dist' 05:10:26 INFO - no previously-included directories found matching '.git*' 05:10:26 INFO - Successfully installed jsonschema functools32 05:10:26 INFO - Cleaning up... 05:10:26 INFO - Return code: 0 05:10:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c8e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb953d94e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c849d30>, '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 0x10c5aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 05:10:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:10:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q7dA9ofQuL/Render', 05:10:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:26 INFO - 'HOME': '/Users/cltbld', 05:10:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:26 INFO - 'LOGNAME': 'cltbld', 05:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:26 INFO - 'MOZ_NO_REMOTE': '1', 05:10:26 INFO - 'NO_EM_RESTART': '1', 05:10:26 INFO - 'PAGER': '/bin/cat', 05:10:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:26 INFO - 'PWD': '/builds/slave/test', 05:10:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:26 INFO - 'SHELL': '/bin/bash', 05:10:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zj5CKev0zV/Listeners', 05:10:26 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 05:10:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:26 INFO - 'USER': 'cltbld', 05:10:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:26 INFO - 'XPC_FLAGS': '0x0', 05:10:26 INFO - 'XPC_SERVICE_NAME': '0', 05:10:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:10:26 INFO - Cleaning up... 05:10:26 INFO - Return code: 0 05:10:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c8e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb953d94e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c849d30>, '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 0x10c5aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 05:10:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:10:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q7dA9ofQuL/Render', 05:10:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:26 INFO - 'HOME': '/Users/cltbld', 05:10:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:26 INFO - 'LOGNAME': 'cltbld', 05:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:26 INFO - 'MOZ_NO_REMOTE': '1', 05:10:26 INFO - 'NO_EM_RESTART': '1', 05:10:26 INFO - 'PAGER': '/bin/cat', 05:10:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:26 INFO - 'PWD': '/builds/slave/test', 05:10:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:26 INFO - 'SHELL': '/bin/bash', 05:10:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zj5CKev0zV/Listeners', 05:10:26 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 05:10:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:26 INFO - 'USER': 'cltbld', 05:10:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:26 INFO - 'XPC_FLAGS': '0x0', 05:10:26 INFO - 'XPC_SERVICE_NAME': '0', 05:10:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:26 INFO - Downloading/unpacking blobuploader==1.2.4 05:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:29 INFO - Downloading blobuploader-1.2.4.tar.gz 05:10:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:10:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:10:29 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 05:10:29 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 05:10:29 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 05:10:29 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 05:10:29 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 05:10:29 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 05:10:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:10:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:10:29 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 05:10:29 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 05:10:29 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 05:10:29 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 05:10:29 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 05:10:29 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 05:10:30 INFO - Downloading docopt-0.6.1.tar.gz 05:10:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:10:30 INFO - Installing collected packages: blobuploader, requests, docopt 05:10:30 INFO - Running setup.py install for blobuploader 05:10:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:10:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:10:30 INFO - Running setup.py install for requests 05:10:31 INFO - Running setup.py install for docopt 05:10:31 INFO - Successfully installed blobuploader requests docopt 05:10:31 INFO - Cleaning up... 05:10:31 INFO - Return code: 0 05:10:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:31 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 0x10c8e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb953d94e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c849d30>, '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 0x10c5aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 05:10:31 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 05:10:31 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 05:10:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q7dA9ofQuL/Render', 05:10:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:31 INFO - 'HOME': '/Users/cltbld', 05:10:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:31 INFO - 'LOGNAME': 'cltbld', 05:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:31 INFO - 'MOZ_NO_REMOTE': '1', 05:10:31 INFO - 'NO_EM_RESTART': '1', 05:10:31 INFO - 'PAGER': '/bin/cat', 05:10:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:31 INFO - 'PWD': '/builds/slave/test', 05:10:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:31 INFO - 'SHELL': '/bin/bash', 05:10:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zj5CKev0zV/Listeners', 05:10:31 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 05:10:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:31 INFO - 'USER': 'cltbld', 05:10:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:31 INFO - 'XPC_FLAGS': '0x0', 05:10:31 INFO - 'XPC_SERVICE_NAME': '0', 05:10:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:10:31 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-aQodLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:10:31 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-_XUxuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:10:31 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-XZUFWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:10:31 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-1vfnKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:10:31 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-pXuMrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:10:32 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-IQqhK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:10:32 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-X1RBDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:10:32 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-TuFEDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:10:32 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-hEV2VK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:10:32 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-bGrGzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:10:32 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-AHt7Hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:10:32 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-F3C2cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:10:32 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-EVp6ba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:10:32 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-66LEJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:32 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-M3wKXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:10:33 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-9CpCLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:10:33 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-p7llHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:10:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:10:33 INFO - Running setup.py install for manifestparser 05:10:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:10:33 INFO - Running setup.py install for mozcrash 05:10:33 INFO - Running setup.py install for mozdebug 05:10:33 INFO - Running setup.py install for mozdevice 05:10:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:10:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:10:33 INFO - Running setup.py install for mozfile 05:10:34 INFO - Running setup.py install for mozhttpd 05:10:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:10:34 INFO - Running setup.py install for mozinfo 05:10:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:10:34 INFO - Running setup.py install for mozInstall 05:10:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:10:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:10:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:10:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:10:34 INFO - Running setup.py install for mozleak 05:10:34 INFO - Running setup.py install for mozlog 05:10:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:10:34 INFO - Running setup.py install for moznetwork 05:10:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:10:34 INFO - Running setup.py install for mozprocess 05:10:35 INFO - Running setup.py install for mozprofile 05:10:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:10:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:10:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:10:35 INFO - Running setup.py install for mozrunner 05:10:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:10:35 INFO - Running setup.py install for mozscreenshot 05:10:35 INFO - Running setup.py install for moztest 05:10:35 INFO - Running setup.py install for mozversion 05:10:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:10:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:10:35 INFO - Cleaning up... 05:10:35 INFO - Return code: 0 05:10:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:35 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')]} 05:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:35 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')]} 05:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:35 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 0x10c8e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb953d94e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c849d30>, '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 0x10c5aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 05:10:35 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 05:10:35 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 05:10:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q7dA9ofQuL/Render', 05:10:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:35 INFO - 'HOME': '/Users/cltbld', 05:10:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:35 INFO - 'LOGNAME': 'cltbld', 05:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:35 INFO - 'MOZ_NO_REMOTE': '1', 05:10:35 INFO - 'NO_EM_RESTART': '1', 05:10:35 INFO - 'PAGER': '/bin/cat', 05:10:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:35 INFO - 'PWD': '/builds/slave/test', 05:10:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:35 INFO - 'SHELL': '/bin/bash', 05:10:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zj5CKev0zV/Listeners', 05:10:35 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 05:10:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:35 INFO - 'USER': 'cltbld', 05:10:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:35 INFO - 'XPC_FLAGS': '0x0', 05:10:35 INFO - 'XPC_SERVICE_NAME': '0', 05:10:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:10:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-aLiYEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:10:36 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)) 05:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:10:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-84Bwf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:10:36 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)) 05:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:10:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-4yYdP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:10:36 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)) 05:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:10:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-gferUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:10:36 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)) 05:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:10:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-SH8zdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:10:36 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)) 05:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:10:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-tT29N0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:10:36 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)) 05:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:10:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-hONKqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:10:36 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)) 05:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:10:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ctSJTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:10:36 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)) 05:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:10:36 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-0iZAcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:10:37 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)) 05:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:10:37 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-bBx1Aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:10:37 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)) 05:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:10:37 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-9wB4DA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:10:37 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)) 05:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:10:37 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-yxwvBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:10:37 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)) 05:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:10:37 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-bl4T7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:10:37 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)) 05:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:10:37 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-l_Ftyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:10:37 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)) 05:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:37 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ADvHvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:37 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)) 05:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:10:37 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-hbXsLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:10:37 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)) 05:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:10:37 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-g3ERR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:10:37 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)) 05:10:37 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)) 05:10:37 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)) 05:10:37 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)) 05:10:37 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)) 05:10:37 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)) 05:10:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:40 INFO - Downloading blessings-1.6.tar.gz 05:10:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:10:40 INFO - Installing collected packages: blessings 05:10:40 INFO - Running setup.py install for blessings 05:10:40 INFO - Successfully installed blessings 05:10:40 INFO - Cleaning up... 05:10:40 INFO - Return code: 0 05:10:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:10:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:10:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:10:41 INFO - Reading from file tmpfile_stdout 05:10:41 INFO - Current package versions: 05:10:41 INFO - blessings == 1.6 05:10:41 INFO - blobuploader == 1.2.4 05:10:41 INFO - docopt == 0.6.1 05:10:41 INFO - functools32 == 3.2.3-2 05:10:41 INFO - jsonschema == 2.5.1 05:10:41 INFO - manifestparser == 1.1 05:10:41 INFO - mozInstall == 1.12 05:10:41 INFO - mozcrash == 0.17 05:10:41 INFO - mozdebug == 0.1 05:10:41 INFO - mozdevice == 0.48 05:10:41 INFO - mozfile == 1.2 05:10:41 INFO - mozhttpd == 0.7 05:10:41 INFO - mozinfo == 0.9 05:10:41 INFO - mozleak == 0.1 05:10:41 INFO - mozlog == 3.3 05:10:41 INFO - moznetwork == 0.27 05:10:41 INFO - mozprocess == 0.23 05:10:41 INFO - mozprofile == 0.28 05:10:41 INFO - mozrunner == 6.12 05:10:41 INFO - mozscreenshot == 0.1 05:10:41 INFO - mozsystemmonitor == 0.3 05:10:41 INFO - moztest == 0.7 05:10:41 INFO - mozversion == 1.4 05:10:41 INFO - psutil == 3.1.1 05:10:41 INFO - requests == 1.2.3 05:10:41 INFO - wsgiref == 0.1.2 05:10:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10: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')]} 05:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10: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')]} 05:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:41 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 0x10c8e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb953d94e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c849d30>, '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 0x10c5aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 05:10:41 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 05:10:41 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 05:10:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q7dA9ofQuL/Render', 05:10:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:41 INFO - 'HOME': '/Users/cltbld', 05:10:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:41 INFO - 'LOGNAME': 'cltbld', 05:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:41 INFO - 'MOZ_NO_REMOTE': '1', 05:10:41 INFO - 'NO_EM_RESTART': '1', 05:10:41 INFO - 'PAGER': '/bin/cat', 05:10:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:41 INFO - 'PWD': '/builds/slave/test', 05:10:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:41 INFO - 'SHELL': '/bin/bash', 05:10:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zj5CKev0zV/Listeners', 05:10:41 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 05:10:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:41 INFO - 'USER': 'cltbld', 05:10:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:41 INFO - 'XPC_FLAGS': '0x0', 05:10:41 INFO - 'XPC_SERVICE_NAME': '0', 05:10:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:41 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)) 05:10:41 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)) 05:10:41 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)) 05:10:41 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)) 05:10:41 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)) 05:10:41 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)) 05:10:41 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)) 05:10:41 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)) 05:10:41 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)) 05:10:41 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)) 05:10:41 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)) 05:10:41 INFO - Cleaning up... 05:10:41 INFO - Return code: 0 05:10:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:10: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')]} 05:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10: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')]} 05:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c8e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c97a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb953d94e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c849d30>, '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 0x10c5aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, '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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 05:10:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:10:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q7dA9ofQuL/Render', 05:10:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:41 INFO - 'HOME': '/Users/cltbld', 05:10:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:41 INFO - 'LOGNAME': 'cltbld', 05:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:41 INFO - 'MOZ_NO_REMOTE': '1', 05:10:41 INFO - 'NO_EM_RESTART': '1', 05:10:41 INFO - 'PAGER': '/bin/cat', 05:10:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:41 INFO - 'PWD': '/builds/slave/test', 05:10:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:41 INFO - 'SHELL': '/bin/bash', 05:10:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zj5CKev0zV/Listeners', 05:10:41 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 05:10:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:41 INFO - 'USER': 'cltbld', 05:10:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:41 INFO - 'XPC_FLAGS': '0x0', 05:10:41 INFO - 'XPC_SERVICE_NAME': '0', 05:10:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:10:41 INFO - Cleaning up... 05:10:41 INFO - Return code: 0 05:10:41 INFO - Running post-action listener: _resource_record_post_action 05:10:41 INFO - Running post-action listener: _start_resource_monitoring 05:10:41 INFO - Starting resource monitoring. 05:10:41 INFO - [mozharness: 2016-09-01 12:10:41.631168Z] Finished create-virtualenv step (success) 05:10:41 INFO - [mozharness: 2016-09-01 12:10:41.632240Z] Running install step. 05:10:41 INFO - Running pre-action listener: _resource_record_pre_action 05:10:41 INFO - Running main action method: install 05:10:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:10:41 INFO - Reading from file tmpfile_stdout 05:10:41 INFO - Detecting whether we're running mozinstall >=1.0... 05:10:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:10:41 INFO - Reading from file tmpfile_stdout 05:10:41 INFO - Output received: 05:10:41 INFO - Usage: mozinstall [options] installer 05:10:41 INFO - Options: 05:10:41 INFO - -h, --help show this help message and exit 05:10:41 INFO - -d DEST, --destination=DEST 05:10:41 INFO - Directory to install application into. [default: 05:10:41 INFO - "/builds/slave/test"] 05:10:41 INFO - --app=APP Application being installed. [default: firefox] 05:10:41 INFO - mkdir: /builds/slave/test/build/application 05:10:41 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'] 05:10:41 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 05:11:15 INFO - Reading from file tmpfile_stdout 05:11:15 INFO - Output received: 05:11:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:11:15 INFO - Running post-action listener: _resource_record_post_action 05:11:15 INFO - [mozharness: 2016-09-01 12:11:15.917443Z] Finished install step (success) 05:11:15 INFO - [mozharness: 2016-09-01 12:11:15.917572Z] Running run-tests step. 05:11:15 INFO - Running pre-action listener: _resource_record_pre_action 05:11:15 INFO - Running main action method: run_tests 05:11:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:11:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:11:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:11:15 INFO - 2016-09-01 05:11:15.932 screenresolution[1935:17586] starting screenresolution argv=screenresolution get 05:11:15 INFO - 2016-09-01 05:11:15.935 screenresolution[1935:17586] Display 0: 1600x1200x32@60 05:11:15 INFO - 2016-09-01 05:11:15.940 screenresolution[1936:17588] starting screenresolution argv=screenresolution list 05:11:15 INFO - Available Modes on Display 0 05:11:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:11:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:11:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:11:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:11:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:11:16 INFO - Intel Iris: 05:11:16 INFO - Chipset Model: Intel Iris 05:11:16 INFO - Type: GPU 05:11:16 INFO - Bus: Built-In 05:11:16 INFO - VRAM (Dynamic, Max): 1536 MB 05:11:16 INFO - Vendor: Intel (0x8086) 05:11:16 INFO - Device ID: 0x0a2e 05:11:16 INFO - Revision ID: 0x0009 05:11:16 INFO - Displays: 05:11:16 INFO - Display: 05:11:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:11:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:11:16 INFO - Display Serial Number: 1600x1200 60 05:11:16 INFO - Main Display: Yes 05:11:16 INFO - Mirror: Off 05:11:16 INFO - Online: Yes 05:11:16 INFO - Rotation: Supported 05:11:16 INFO - Return code: 0 05:11:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:11:16 INFO - Python 2.7.3 05:11:16 INFO - Return code: 0 05:11:16 INFO - grabbing minidump binary from tooltool 05:11:16 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')]} 05:11:16 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 0x10c849d30>, '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 0x10c5aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c841b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:11:16 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 05:11:16 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 05:11:16 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 05:11:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:11:16 INFO - Return code: 0 05:11:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:11:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:11:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:11:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:11:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:11:16 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/SowZmXPhT2KmT0YFlswiUQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 05:11:16 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/SowZmXPhT2KmT0YFlswiUQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0105 --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 05:11:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q7dA9ofQuL/Render', 05:11:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:16 INFO - 'HOME': '/Users/cltbld', 05:11:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:16 INFO - 'LOGNAME': 'cltbld', 05:11:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:11:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:16 INFO - 'MOZ_NO_REMOTE': '1', 05:11:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:11:16 INFO - 'NO_EM_RESTART': '1', 05:11:16 INFO - 'PAGER': '/bin/cat', 05:11:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:16 INFO - 'PWD': '/builds/slave/test', 05:11:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:11:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:16 INFO - 'SHELL': '/bin/bash', 05:11:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Zj5CKev0zV/Listeners', 05:11:16 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 05:11:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:16 INFO - 'USER': 'cltbld', 05:11:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:16 INFO - 'XPC_FLAGS': '0x0', 05:11:16 INFO - 'XPC_SERVICE_NAME': '0', 05:11:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:16 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/SowZmXPhT2KmT0YFlswiUQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 05:11:16 INFO - mozversion application_buildid: 20160901025721 05:11:16 INFO - mozversion application_changeset: 0770506cb101cb2a70d0e4d267361767a9bfc9b6 05:11:16 INFO - mozversion application_display_name: Nightly 05:11:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:11:16 INFO - mozversion application_name: Firefox 05:11:16 INFO - mozversion application_remotingname: firefox 05:11:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 05:11:16 INFO - mozversion application_vendor: Mozilla 05:11:16 INFO - mozversion application_version: 51.0a1 05:11:16 INFO - mozversion platform_buildid: 20160901025721 05:11:16 INFO - mozversion platform_changeset: 0770506cb101cb2a70d0e4d267361767a9bfc9b6 05:11:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 05:11:16 INFO - mozversion platform_version: 51.0a1 05:11:16 INFO - using testdate: 1472731876 05:11:16 INFO - actual date: 1472731876 05:11:16 INFO - starting webserver on 'localhost:53506' 05:11:16 INFO - SUITE-START | Running 2 tests 05:11:16 INFO - TEST-START | dromaeo_css 05:11:16 INFO - operating with platform_type : mac_ 05:11:16 INFO - Initialising browser for dromaeo_css test... 05:11:16 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmp0f2QZy/profile http://localhost:53506/getInfo.html) 05:11:18 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 05:11:18 INFO - PROCESS | 1946 | colorDepth:24 05:11:18 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 05:11:18 INFO - PROCESS | 1946 | __metrics 05:11:19 INFO - PROCESS | 1946 | 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" 05:11:19 INFO - PROCESS | 1946 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:11:19 INFO - TEST-INFO | 1946: exit 0 05:11:19 INFO - Browser initialized. 05:11:19 INFO - Running cycle 1/1 for dromaeo_css test... 05:11:19 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmp0f2QZy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:11:31 INFO - PROCESS | 1948 | RSS: Main: 233734144 05:11:31 INFO - PROCESS | 1948 | 05:12:23 INFO - PROCESS | 1948 | 1472731943179 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] 05:14:35 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:53506/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53506/tests/dromaeo/cssquery-ext.html) 05:14:36 INFO - PROCESS | 1948 | RSS: Main: 388218880 05:14:36 INFO - PROCESS | 1948 | 05:17:39 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:53506/tests/dromaeo/cssquery-ext.html (next: http://localhost:53506/tests/dromaeo/cssquery-jquery.html) 05:17:40 INFO - PROCESS | 1948 | RSS: Main: 371732480 05:17:40 INFO - PROCESS | 1948 | 05:20:42 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:53506/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53506/tests/dromaeo/cssquery-mootools.html) 05:20:43 INFO - PROCESS | 1948 | RSS: Main: 330018816 05:20:43 INFO - PROCESS | 1948 | 05:23:45 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:53506/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53506/tests/dromaeo/cssquery-prototype.html) 05:23:46 INFO - PROCESS | 1948 | RSS: Main: 333692928 05:23:46 INFO - PROCESS | 1948 | 05:26:49 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:53506/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53506/tests/dromaeo/cssquery-yui.html) 05:26:50 INFO - PROCESS | 1948 | RSS: Main: 325906432 05:26:50 INFO - PROCESS | 1948 | 05:29:53 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:53506/tests/dromaeo/cssquery-yui.html (next: http://localhost:53506/tests/dromaeo/cssquery-dojo.html) 05:29:53 INFO - PROCESS | 1948 | RSS: Main: 509304832 05:29:53 INFO - PROCESS | 1948 | 05:29:53 INFO - PROCESS | 1948 | __start_tp_report 05:29:53 INFO - PROCESS | 1948 | _x_x_mozilla_page_load 05:29:53 INFO - PROCESS | 1948 | _x_x_mozilla_page_load_details 05:29:53 INFO - PROCESS | 1948 | |i|pagename|runs| 05:29:53 INFO - PROCESS | 1948 | |0;dojo.html;2750.326170088328;3270.999999999999;3281.081297236773;3315;3348.0625424881027;5500.642458240211;5560.666360018402;5584.6090773645865;5619.2975878015195;5692.37383887772;5606.299212598426;5619.072852979253;5644.294463192101;5652.982463156641;5671.007573674611;4352.238358287287;5111.872203194913;5135.717535535544;5184.455632158626;5315.707636080014;5199.402068762084;5200.245964335169;5218.504242096995;5259.684418934876;5323.281357016792;5107.157319042372;5253.816116435925;5255.237990491377;5262.631615786897;5270.710110943896;5301.1783173608155;5309.628326017161;5333.919991200135;5341;5342.839714808562;5039.647224694273;5042.924356134661;5053.242013697938;5112.488751124858;5127.897442051138;5515.972420137914;5523.199136125239;5537.031019571599;5554.777808887639;5678.034734095214;6129.632222066691;6151.600145990541;6167.136600875881;6173.166622505994;6191.783287584958;6060.3939606039485;6131.233595800525;6172.84567885802;6234.532410069263;6247.500199983991;5564.638298510584;5651.9152212716845;5689.175069614883;5700.059490184073;5774.249347584787;6152.261728592508;6210.7515699371515;6240.500759939285;6246.125542424064;6253.593516421418;5909.704514774244;5958.553108516879;6043.244594425697;6062.727177277033;6067.848303792441;6068.72995541793;6103.450689437972;6131.040261761288;6198.0702894566175;6209.944309467402;6129.877402452015;6146.508279337642;6154.815355539341;6164.8767024659255;6164.907526387018;6019.608725432833;6103.1455596216565;6176.536759742948;6177.444030037318;6179.62922224668;4844.854654360375;4854.417469903634;4863.659543831898;4877.756112194376;4893;4375.234333991539;4396.956030439655;4409.889752756207;4419.867403977886;4429.601335879786;5579.609427340129;5593.776248950077;5626.634268772516;5648.604597678123;5733.111367738007;6131.294901086432;6196.938030619636;6215.098810672517;6222.813315600449;6233.96883015582;5390.730463476811;5431.293931788842;5694.974904517227;5703.201551782754;5718.228039214653;5298.602604804578;5341.118715411992;5346.759395827197;5409.377921538995;5414.8917021659345;6101.095933926299;6213.1922850029205;6221.720022598994;6268.905966410416;6284.905726414016;6231.813045608639;6248.9062664059165;6252.656103914328;6268.1851359323;6278.623282603058;5556.972215138899;6136.3046386708;6181.041938499539;6250.156228909039;6256.374362563707;6045.21412216409;6115.483736156635;6136.610985192662;6137.703607474697;6141.977005061692;6149.446549810538;6165.982612869006;6203.627782333075;6210.378962103753;6234.189555357774;5817.621854579439;5959.195508606283;6001.7599296027665;6021.357764945359;6040.305364882919;5969.134475501115;5997.880042399215;6059.909101363572;6087.726052327567;6094.75620975156;5855.59010869235;5877.089051197071;5880.588358814842;5883.794067207627;5954.404559544011;5881.5588830838715;5886.175935369138;6051.7579296827635;6076.088586711851;6083.939160608337;5784.190213370216;5845.795397161079;5911.733971971185;5939.732712027882;5953.732082056231 05:29:53 INFO - PROCESS | 1948 | |1;ext.html;470.17249640632497;488.75562218890553;508.8448023352877;519.3326575350675;550.8402563256655;30964.535531967038;31006.68993310069;31251.531227031584;40130;41179.58820411795;42114;42230.7888460558;42250;42349.78825105878;42454;9632.133108020294;9785.510724463784;9798.461084640341;9804.46075465849;9816;8575.571221438935;9592.232621390272;9703.611855525805;9718.416894986289;9723.951380243088;9639.277054220962;9698.175655069328;9705.563249653747;9707.563159657833;9719.75700607487;9654.517274136266;9682.515874206261;9713.174380177694;9713.417194968324;9715;9650.855237171449;9704.51477426133;9737.853932190952;9744.561494732754;9747.707568772948;39450.8164755055;39721;39817.20365592672;39857;39880.40179397311;3211.903642890717;3321.850516726753;3346.0631023313513;3691.50164727761;4085.428040074392;30548;30627;30883.0735077948;31337.529937050964;31568.368632627218;3218.09893229896;3233.5149727541057;3235.5146727990755;3247.870085196589;3336.7831090979007;320677;321041;321632;324224;326477;56665.150022749694;57022;57077.71461142751;57205;57238.7138064307;34186.48720269198;34392.484112738326;34512.65487345094;59532;59762.70118649379;15547.30037148331;15922.28349724242;15939.760903586455;15942.123183224801;15960.281787319598;12303.69240768988;12316.891479766684;12445.502179912883;12445.937770311091;12461.875381246253;15838.9208053959;17927;18609.534761630795;18609.627807443776;18638.81361186371;6688.498362622823;7598.8100297493;7663.888736133229;7702.691892324357;7796;839.9538025408661;946.7396465971913;1003.9548220330103;1014.6550172941236;1092.464692300767;24538.386540336633;26034.218973430827;28000;30177.396452071276;30203.244918876764;833.0087196236581;838.9496630202208;1038.0190719769746;1041.250299784154;1051.8211903976342;14428.422863085569;15131.092134471966;15163.166025868682;15459.458918937784;15475.767863482057;630384;633985;635115;636114;642770;54741.726291368286;55288.34134975957;55522.44477555253;55547;55624.44375556274;1209.1354681402838;1310.5871650430274;1334.252818421687;1377.5040985245103;1383.1217177092417;29661.85169074141;30841.69158308478;31029.379412411174;31305.21736956503;31766.841165794023;47697.76151119361;48185.03629927452;48357.27464087971;48397.75801120972;48440;518047;518297;518470;520241;852949;39928.00179995407;40735;40844.38733419061;40902.18195636011;41164.70682345345;45717;45914.081718364774;46031;46077.76961115173;46477.767611161726;29424.852875736342;37413.625863741014;37654.43518347172;37662;38829;37212.627873720914;37652.623473766005;38639.80680096582;38793;38885.416718748675;36350;36847.815760922094;36932.44601330929;37199;37344.81327593345;28166.2958426041;31850.362992739552;32426.31286247742;32822.5076623846;33066.66933330636;24461;24525.38686532851;24864;25141.49717005613;25992.35019124537 05:29:53 INFO - PROCESS | 1948 | |2;jquery.html;352.8341679410676;361.6347489036074;371.25563245692393;372.253631468905;403.2720938705637;15173.848261517382;16515.917420412912;20091.29680461184;20601.896990515026;20752.273670421557;20304.883231422267;20586.897065514688;20910.790892091074;21180.576388472222;21224.57550848986;5283.313169287987;5914.438128377808;6058.454739073482;6184.721687524072;6188.195534580498;6064.332923378447;6112.541559383042;6148.477379422754;6150.446459818615;6172.135900973867;5938.10928360745;5986.640801551899;6022.457978781908;6075.17985072014;6119.470132466883;5993.520518358521;6007.879842403149;6054.303755068157;6064.272287325505;6068.362821903681;4988.476209997934;5235.790568377261;5236.005159019774;5238.869028274324;5242.6330156889035;26147.477050458885;26284.685765711638;26429;26471;26525;1673.7740405045286;2061.8247448966854;2156.590247852903;2334.6264597664463;2499.637552554869;9674.467904265262;9803.90196098037;9928.851067233998;10197.18422526196;10285.794284114276;1743.7994630617513;1837.2467288411822;1857.674906891302;1859.5165257033134;1878.2674756844842;93716;121228;123627;124243;133373.33313333566;8918.66220066995;11085.944570277097;11287.54849806015;11366.829497557543;11507.252028618113;14434.639134021734;14455.855441445661;14478.986470947144;14495.637609059648;14502.419903203754;8239.835203296021;8385.664573416994;8421.663133474593;8440.957795210985;8531.829363412697;8283.792905177419;8305.626246818907;8336.583170841433;8460.619272132768;8496;3703.981480092636;3731.8134093295225;4431.822727090945;4453.77731113443;4459.509453960061;1825.5527395787851;1871.691170956777;1895.6682580548625;1898.9905050474658;1935.4677463697597;666.8432918264205;694.2675477371422;711.7579227802253;749.9775006749807;945.385499425379;20257.290994815405;20907;20919.058642360826;21103.677428045292;21394.251201207884;876.9342299327449;882.5543100734088;891.5289771876455;903.5933829776627;925.4771054354268;10109.241806864515;10794.946025269981;10834.295770775032;10838.620648277272;10864.456777161111;597154;602134;610466;613396;617550.4734857961;112861.87138128678;120892;135129;137910.31044844713;146215.2689236547;963.7735132243918;989.881433979584;1026.6971243483042;1031.7008067660295;1034.0641719244097;18265.817341826412;18862;19094.427167185007;19281.51796205106;19887.801121988596;55037.72481137569;55175.724121379135;55187.44812551823;56060;56333;80686.98282542983;80998.59500702459;81028.5948570277;81503;84280;7596.506227095221;7695.576743279174;7859.724909628136;7950.24472675095;8043.87934180976;8306;9245.72262832116;11143.888561114285;12294.50821967147;12523.499060037497;10666.413347265665;10680.305780124267;10800.729981750519;10933.56265749361;10986.780264394827;10540.894591054299;10591.311564748266;10721.35671859691;10838.620648277272;10868.891311086787;10532.157427405637;10953.890461095605;11024.228304018641;11086.778264434828;11228.382438965935;6684.43182329488;7747.535147891144;7764.107127680163;7825.765227043198;7900.565468899265;10670.89329106699;10785;10834.620788272372;10984.286021408583;11024.944875275572 05:29:53 INFO - PROCESS | 1948 | |3;mootools.html;1473.0498828255775;1513.4475916290553;1697.5501492104586;1879.9812001879993;1908.9618207635847;8413;8771.649134034631;8778.91221087789;8820.470771753691;8827.117288271178;6635.60186388817;7787.4938129021575;8005.759827205178;8556.657733690645;8671.479711217316;2378.3935096550354;2405.326508577601;2407.6629271901948;2410.6625072489865;2415.4685969086772;2025.6657651487487;2286.7141607299086;2398.64020396941;2399.7360290368133;2400.8199385046105;2075.0973326602902;2151.8924053797327;2310.8151347892212;2391.2467572714627;2392.0790495659207;2374.726906405768;2380.92857214283;2401.171595799455;2408.1210358219123;2408.1812183857573;2383.6186210206283;2387.689600351943;2388.641703744452;2391.4141035446255;2393.114547617393;8745.606447709868;8828.646854125827;8829.470231786114;8830.116988301183;8852.424592401474;5146.665466744649;5318.095923692943;5319.601029922771;5323.494268044529;5323.946760532423;3004.1137864330076;3291.1772056985756;3334.6498617645284;3525.788452692821;3533.9293214135573;1025.5897640943608;1030.3148406309795;1044.7910417916446;1049.9842502362471;1052.3475445223933;11429.028452602382;11532;11560.6531804046;11581.247218930743;11681.240719353214;3817.9618203818163;4479.395281636937;4704.435467743892;4725.763711814464;4734.597559207437;9744.269179811543;9747.3664211826;9748.610055597697;9752.9024709752;9794.65718699842;5217.034848552954;5219.582433405319;5219.634625576173;5223.4776522347465;5226.484319547365;4607.424071991001;4764;4796.544328288807;4801.4958429365115;4804.615630749532;3213.3412650406613;3871.477350557695;3915.275674000319;3967.444557761916;3981.442598036277;1178.4814681540095;1262.7916393794922;1268.4735834628525;1269.8285731426324;1287.6973911130876;761.2463660975704;867.9045305016443;878.4158534574564;890.3322508118911;959.467495539982;5747.281589801275;5858.53131749459;5926.881462370815;6144.815655530341;6156.153154053766;891.2068259249273;931.2549960031947;934.238595544629;948.5399581203111;961.8749562556962;3414.7404282290804;3918.5689574146822;3925.8429662813737;4049.3521036633997;4064.8577299794365;10315.638952636771;10655;10670.51982660782;10679.466026698634;10696.94651526753;9981.900180998096;10446.007629275207;10446.373217606968;10480.004399582027;10608.681739547827;7736.187700291501;7921.960390198012;7924.881126783102;7932.04815422153;7943.285104340637;4911.435184953776;5831.883362332815;5846.444587764156;5995.820125396245;6176.382361763787;9798.265130115413;9816.411015339103;9817.901820981699;9836.458994755358;9845.950770246103;8685.652573897227;9727.221822254343;9783.75540611462;9977.201823854219;9987.850182247126;7319.963400182965;7529.849403012018;7532.7740167795055;7536.171021187555;7538.359239464707;8062.83874322522;8132.83734325322;8222.301104405953;8261.049979252462;8277.710280140407;5674.290713660793;6279.9590133205975;7350.228226036189;7367.963160184165;7520.924790752022;7000.50996430245;7173.964130179525;7233.4574906883245;7295.817604559928;7313.744018959524;6744.426723728342;6853.7943861684225;6859.965700171467;6867.141607299088;6871.03805467225;4341.283688191413;4788.138135135709;4963.379577552806;5083.339165908408;5135.897282054413;4926.950730492649;4977.900441991213;5024.924626130538;5243.449437809128;5300.992811365828 05:29:53 INFO - PROCESS | 1948 | |4;prototype.html;284.4027542161461;297.71270723751576;312.8639042016724;312.8811051800316;316.4683332002235;4864.659473836833;5243.423223445423;5261.368635763709;5283.920741188876;5366.570674346048;4047.6559492443102;4175.979120104403;4957.975210123945;4964.751762411874;5105.974470127653;3261.7442284720314;3320.5019247112946;3439.3981053315697;3979.8408063677416;4024.235395274903;3327.317899830529;3629.909252268688;3761.360568703327;4006.3189257826093;4023.3361495353233;3110.4851517784873;3229.54786329914;3561.3589553880292;3705.722070844684;3945.7632542047427;3922.4900762900925;3944.250592387466;3963.326234540135;3969.8809035728973;3998.0004998750314;3454.274602333513;3463.79217246966;3469.7397695172713;3472;3474.6351633078666;4720.57514823664;5156.845294641129;5158.400895722338;5158.613104017214;5176.689398636094;3937.4881265435597;4440.622547083502;4482.058767658796;4485.945802736354;4502.077074199814;4575.405197324327;5484.177373393983;5515.034868897927;5519.254900588409;5523.199136125279;3134.357456721367;3741.8316175772156;3754.624537546251;3760.2291530236507;3791.3554695701523;4949.455559888409;4963.5780958618925;5044.2937988681615;5757.827265182052;5792.65244085352;3620.5836328822525;3771.0195349208852;3792.1657235408165;3795.2788970095594;3812.6377994090517;3026.697330267;3883.0874744435037;3896.4350169225304;3910.1006768443417;4006.7595944242858;3556.8973618178447;4085.20338533983;4159.875203743893;4512.097580483916;4553.749543775124;4638.675292729544;5483.862903427446;5522.779088836482;5540.584456165804;5645.181448689917;1167.8656954450178;1218.256863313378;1293.249915049288;1300.232862611064;1326.8191309734343;789.1674283630709;819.4304958054095;830.8089139497829;847.7287268074281;871.6687658689657;4444.488883778342;4627.745473998963;5034.306645944403;5082.313887625197;5082.440931497532;4639.930401043987;4947.752612369367;5551.667599776025;5565.048815341153;5636.830895073154;4144.150449157915;4380.802863871133;5048.7728052237735;5077.949220507748;5173.456787037382;2535.3773584905675;2599.727028662001;2623.2130360891656;2648.894044238209;2806.579013148044;5876.588638795329;6002.849928751729;6007.939920600826;6031.728572214261;6046.818595442144;5288.206768984606;5379.515843574019;5555.527780138734;5859.589828712034;5881.3530511643685;4613.723176609415;5647.576431767634;5784.913226301524;5893.3222679391565;5922.378150294243;4875.24433712775;5300.469953004668;5309.707966061904;5667;5677.643777832319;5163.0706472833745;5449.809256675997;5593.33636468114;5654.293213348332;5675.347335056521;4901.5588597026435;5603.299587551556;5888.440598143169;5896.49879760208;5905.498032667099;3649.397849354933;3754.3429899766884;3762.4732537444233;3811;3821.9044523887123;4807.375041244616;5070.670406423571;5659.292588426447;5675.404082571271;5699.401562835843;4877.756112194305;5327.53492789486;5519.6265605789895;5582.972085139549;5606.719664016701;5348.786048558014;5536.861578460571;5553.4724201200825;5574.9163762542785;5587.469190426903;5418.45815418455;5467.699276539669;5468.425815289337;5470;5511.338639363302;5183.5593974512585;5281.947180528299;5487.286652735119;5525.419830917856;5553.4724201200825;4525.981654127795;5170.819021334235;5186.066508028591;5387;5400.05499037659 05:29:53 INFO - PROCESS | 1948 | |5;yui.html;691.5643144818764;707.5895980331409;722.8265216348071;750.4334227658118;773.744664260794;15044.699106017873;15652.608684782886;15660.373585056612;15735.21323933803;15765;14075.718485630281;14209.147451152938;14213.928930355334;14234.644133896658;14687.779683304732;673.6665350651434;685.7531288736076;821.511200835505;821.893153889995;845.5011543189516;825.6697321071559;839.8404303182406;840.4201101240155;840.5965136734371;842.204117109332;898.7393655839799;899.3210126354593;899.3614533681061;900.0999000999001;900.3382513852313;710.0378986473357;725.905632267807;725.9455540834406;889.7464222696533;894.1862904756705;547.0290234833163;656.671664167916;672.5393105722546;675.0818886314609;682.8258794007546;14442.422303107864;14490.20303883286;14491.202983835885;14500.92749536256;14501.927490362586;11687.033994376723;13382.531611393555;13625;13901.16593004423;14390.640233994234;10942.17933654971;12064.517419303218;12255.448504817305;12323.260604363677;12493.438320209974;4526.818927242906;4558.977205113986;4629.490756016836;4633.397658304432;4761.119192949281;110394;110957.44521277423;112533;113089;115374.99999999916;10573.841392379121;10878.184136189817;10878.945605271923;11019.559217631366;11127.944360278147;21230.36308910735;21256.149754009977;21257.468563286042;21286.574268514854;21286.574268514854;7394.86451190015;7679;7682.923170768333;7849;7872.72445464406;3654.3422184007136;4372.322290045048;4453.665975051819;4515.1195516873895;4588.3805686231935;2731.2762118038736;3192.7605429592872;3272.9509057364157;3278.639349671534;3282.491213861855;630.7413960276357;631.6620607974711;635.167930011677;647.7473785223766;650.6193876582253;547.4990383798779;588.4497994375273;602.7468463245448;607.7629724407485;611.367234911873;8370.707025254087;8825.293976481866;8937.865932011026;9030.142136497023;9054.864177037218;586.234963372794;597.1772499196661;597.2981746447906;625.8560531077786;627.704978660029;4067.328890732996;4223.577642235752;4239.51245606753;4308.267594508941;4377.233984052778;122386.5522689559;154106.22946885193;161915;162910.18544907437;165876;137366.31316843352;137760;139206;142976;145364.27317863342;79845.60077199577;90925;116100;117800;119422;8374.49753014821;8397.580120993804;8437.535935523605;8576.699815506427;8652.264557512683;61862;63858.36141638524;64197.35802641914;65585.67207163933;67233;63512;66025;73785;93205.00939136728;99271.50364248133;9852.044386683823;9978.650747223812;9983.550740216562;10064.54709538058;10276;9808.264380171373;9884.85172722424;10059.647912323033;10175.592976280866;10445.582176713151;10604.681859544226;10827.729306767395;10832.221978940675;10952.56189752401;10975.890241097486;11603.767924641292;11810.291382516732;11864.584739534075;12256.264624122581;12367.319797411228;11282.097432205568;11814;11887.405629718307;11963.64109076729;12074.094442916641;8820.073892241478;9257.074292570689;9263.583138758637;9277.536123193948;9536.952315238379;8672.653093876173;9946.005399459997;9967.302288839712;10003.599856005678;10019.248556358156 05:29:53 INFO - PROCESS | 1948 | __end_tp_report 05:29:53 INFO - PROCESS | 1948 | __start_cc_report 05:29:53 INFO - PROCESS | 1948 | _x_x_mozilla_cycle_collect,1393 05:29:53 INFO - PROCESS | 1948 | __end_cc_report 05:29:53 INFO - PROCESS | 1948 | __startTimestamp1472732993709__endTimestamp 05:29:53 INFO - PROCESS | 1948 | 05:29:53 INFO - PROCESS | 1948 | ------- Summary: start ------- 05:29:53 INFO - PROCESS | 1948 | Number of tests: 6 05:29:53 INFO - PROCESS | 1948 | 05:29:53 INFO - PROCESS | 1948 | [#0] dojo.html Cycles:165 Average:5673.93 Median:5881.56 stddev:645.59 (11.0%) stddev-sans-first:605.46 05:29:53 INFO - PROCESS | 1948 | Values: 2750.3 3271.0 3281.1 3315.0 3348.1 5500.6 5560.7 5584.6 5619.3 5692.4 5606.3 5619.1 5644.3 5653.0 5671.0 4352.2 5111.9 5135.7 5184.5 5315.7 5199.4 5200.2 5218.5 5259.7 5323.3 5107.2 5253.8 5255.2 5262.6 5270.7 5301.2 5309.6 5333.9 5341.0 5342.8 5039.6 5042.9 5053.2 5112.5 5127.9 5516.0 5523.2 5537.0 5554.8 5678.0 6129.6 6151.6 6167.1 6173.2 6191.8 6060.4 6131.2 6172.8 6234.5 6247.5 5564.6 5651.9 5689.2 5700.1 5774.2 6152.3 6210.8 6240.5 6246.1 6253.6 5909.7 5958.6 6043.2 6062.7 6067.8 6068.7 6103.5 6131.0 6198.1 6209.9 6129.9 6146.5 6154.8 6164.9 6164.9 6019.6 6103.1 6176.5 6177.4 6179.6 4844.9 4854.4 4863.7 4877.8 4893.0 4375.2 4397.0 4409.9 4419.9 4429.6 5579.6 5593.8 5626.6 5648.6 5733.1 6131.3 6196.9 6215.1 6222.8 6234.0 5390.7 5431.3 5695.0 5703.2 5718.2 5298.6 5341.1 5346.8 5409.4 5414.9 6101.1 6213.2 6221.7 6268.9 6284.9 6231.8 6248.9 6252.7 6268.2 6278.6 5557.0 6136.3 6181.0 6250.2 6256.4 6045.2 6115.5 6136.6 6137.7 6142.0 6149.4 6166.0 6203.6 6210.4 6234.2 5817.6 5959.2 6001.8 6021.4 6040.3 5969.1 5997.9 6059.9 6087.7 6094.8 5855.6 5877.1 5880.6 5883.8 5954.4 5881.6 5886.2 6051.8 6076.1 6083.9 5784.2 5845.8 5911.7 5939.7 5953.7 05:29:53 INFO - PROCESS | 1948 | 05:29:53 INFO - PROCESS | 1948 | [#1] ext.html Cycles:180 Average:64952.41 Median:39168.12 stddev:144389.53 (368.6%) stddev-sans-first:144713.42 05:29:53 INFO - PROCESS | 1948 | Values: 470.2 488.8 508.8 519.3 550.8 30964.5 31006.7 31251.5 40130.0 41179.6 42114.0 42230.8 42250.0 42349.8 42454.0 9632.1 9785.5 9798.5 9804.5 9816.0 8575.6 9592.2 9703.6 9718.4 9724.0 9639.3 9698.2 9705.6 9707.6 9719.8 9654.5 9682.5 9713.2 9713.4 9715.0 9650.9 9704.5 9737.9 9744.6 9747.7 39450.8 39721.0 39817.2 39857.0 39880.4 3211.9 3321.9 3346.1 3691.5 4085.4 30548.0 30627.0 30883.1 31337.5 31568.4 3218.1 3233.5 3235.5 3247.9 3336.8 320677.0 321041.0 321632.0 324224.0 326477.0 56665.2 57022.0 57077.7 57205.0 57238.7 34186.5 34392.5 34512.7 59532.0 59762.7 15547.3 15922.3 15939.8 15942.1 15960.3 12303.7 12316.9 12445.5 12445.9 12461.9 15838.9 17927.0 18609.5 18609.6 18638.8 6688.5 7598.8 7663.9 7702.7 7796.0 840.0 946.7 1004.0 1014.7 1092.5 24538.4 26034.2 28000.0 30177.4 30203.2 833.0 838.9 1038.0 1041.3 1051.8 14428.4 15131.1 15163.2 15459.5 15475.8 630384.0 633985.0 635115.0 636114.0 642770.0 54741.7 55288.3 55522.4 55547.0 55624.4 1209.1 1310.6 1334.3 1377.5 1383.1 29661.9 30841.7 31029.4 31305.2 31766.8 47697.8 48185.0 48357.3 48397.8 48440.0 518047.0 518297.0 518470.0 520241.0 852949.0 39928.0 40735.0 40844.4 40902.2 41164.7 45717.0 45914.1 46031.0 46077.8 46477.8 29424.9 37413.6 37654.4 37662.0 38829.0 37212.6 37652.6 38639.8 38793.0 38885.4 36350.0 36847.8 36932.4 37199.0 37344.8 28166.3 31850.4 32426.3 32822.5 33066.7 24461.0 24525.4 24864.0 25141.5 25992.4 05:29:53 INFO - PROCESS | 1948 | 05:29:53 INFO - PROCESS | 1948 | [#2] jquery.html Cycles:180 Average:35434.89 Median:13438.92 stddev:101652.23 (756.4%) stddev-sans-first:101903.26 05:29:53 INFO - PROCESS | 1948 | Values: 352.8 361.6 371.3 372.3 403.3 15173.8 16515.9 20091.3 20601.9 20752.3 20304.9 20586.9 20910.8 21180.6 21224.6 5283.3 5914.4 6058.5 6184.7 6188.2 6064.3 6112.5 6148.5 6150.4 6172.1 5938.1 5986.6 6022.5 6075.2 6119.5 5993.5 6007.9 6054.3 6064.3 6068.4 4988.5 5235.8 5236.0 5238.9 5242.6 26147.5 26284.7 26429.0 26471.0 26525.0 1673.8 2061.8 2156.6 2334.6 2499.6 9674.5 9803.9 9928.9 10197.2 10285.8 1743.8 1837.2 1857.7 1859.5 1878.3 93716.0 121228.0 123627.0 124243.0 133373.3 8918.7 11085.9 11287.5 11366.8 11507.3 14434.6 14455.9 14479.0 14495.6 14502.4 8239.8 8385.7 8421.7 8441.0 8531.8 8283.8 8305.6 8336.6 8460.6 8496.0 3704.0 3731.8 4431.8 4453.8 4459.5 1825.6 1871.7 1895.7 1899.0 1935.5 666.8 694.3 711.8 750.0 945.4 20257.3 20907.0 20919.1 21103.7 21394.3 876.9 882.6 891.5 903.6 925.5 10109.2 10794.9 10834.3 10838.6 10864.5 597154.0 602134.0 610466.0 613396.0 617550.5 112861.9 120892.0 135129.0 137910.3 146215.3 963.8 989.9 1026.7 1031.7 1034.1 18265.8 18862.0 19094.4 19281.5 19887.8 55037.7 55175.7 55187.4 56060.0 56333.0 80687.0 80998.6 81028.6 81503.0 84280.0 7596.5 7695.6 7859.7 7950.2 8043.9 8306.0 9245.7 11143.9 12294.5 12523.5 10666.4 10680.3 10800.7 10933.6 10986.8 10540.9 10591.3 10721.4 10838.6 10868.9 10532.2 10953.9 11024.2 11086.8 11228.4 6684.4 7747.5 7764.1 7825.8 7900.6 10670.9 10785.0 10834.6 10984.3 11024.9 05:29:53 INFO - PROCESS | 1948 | 05:29:53 INFO - PROCESS | 1948 | [#3] mootools.html Cycles:180 Average:5602.04 Median:5219.61 stddev:3127.26 (59.9%) stddev-sans-first:3120.64 05:29:53 INFO - PROCESS | 1948 | Values: 1473.0 1513.4 1697.6 1880.0 1909.0 8413.0 8771.6 8778.9 8820.5 8827.1 6635.6 7787.5 8005.8 8556.7 8671.5 2378.4 2405.3 2407.7 2410.7 2415.5 2025.7 2286.7 2398.6 2399.7 2400.8 2075.1 2151.9 2310.8 2391.2 2392.1 2374.7 2380.9 2401.2 2408.1 2408.2 2383.6 2387.7 2388.6 2391.4 2393.1 8745.6 8828.6 8829.5 8830.1 8852.4 5146.7 5318.1 5319.6 5323.5 5323.9 3004.1 3291.2 3334.6 3525.8 3533.9 1025.6 1030.3 1044.8 1050.0 1052.3 11429.0 11532.0 11560.7 11581.2 11681.2 3818.0 4479.4 4704.4 4725.8 4734.6 9744.3 9747.4 9748.6 9752.9 9794.7 5217.0 5219.6 5219.6 5223.5 5226.5 4607.4 4764.0 4796.5 4801.5 4804.6 3213.3 3871.5 3915.3 3967.4 3981.4 1178.5 1262.8 1268.5 1269.8 1287.7 761.2 867.9 878.4 890.3 959.5 5747.3 5858.5 5926.9 6144.8 6156.2 891.2 931.3 934.2 948.5 961.9 3414.7 3918.6 3925.8 4049.4 4064.9 10315.6 10655.0 10670.5 10679.5 10696.9 9981.9 10446.0 10446.4 10480.0 10608.7 7736.2 7922.0 7924.9 7932.0 7943.3 4911.4 5831.9 5846.4 5995.8 6176.4 9798.3 9816.4 9817.9 9836.5 9846.0 8685.7 9727.2 9783.8 9977.2 9987.9 7320.0 7529.8 7532.8 7536.2 7538.4 8062.8 8132.8 8222.3 8261.0 8277.7 5674.3 6280.0 7350.2 7368.0 7520.9 7000.5 7174.0 7233.5 7295.8 7313.7 6744.4 6853.8 6860.0 6867.1 6871.0 4341.3 4788.1 4963.4 5083.3 5135.9 4927.0 4977.9 5024.9 5243.4 5301.0 05:29:53 INFO - PROCESS | 1948 | 05:29:53 INFO - PROCESS | 1948 | [#4] prototype.html Cycles:180 Average:4404.06 Median:4964.16 stddev:1395.04 (28.1%) stddev-sans-first:1364.26 05:29:53 INFO - PROCESS | 1948 | Values: 284.4 297.7 312.9 312.9 316.5 4864.7 5243.4 5261.4 5283.9 5366.6 4047.7 4176.0 4958.0 4964.8 5106.0 3261.7 3320.5 3439.4 3979.8 4024.2 3327.3 3629.9 3761.4 4006.3 4023.3 3110.5 3229.5 3561.4 3705.7 3945.8 3922.5 3944.3 3963.3 3969.9 3998.0 3454.3 3463.8 3469.7 3472.0 3474.6 4720.6 5156.8 5158.4 5158.6 5176.7 3937.5 4440.6 4482.1 4485.9 4502.1 4575.4 5484.2 5515.0 5519.3 5523.2 3134.4 3741.8 3754.6 3760.2 3791.4 4949.5 4963.6 5044.3 5757.8 5792.7 3620.6 3771.0 3792.2 3795.3 3812.6 3026.7 3883.1 3896.4 3910.1 4006.8 3556.9 4085.2 4159.9 4512.1 4553.7 4638.7 5483.9 5522.8 5540.6 5645.2 1167.9 1218.3 1293.2 1300.2 1326.8 789.2 819.4 830.8 847.7 871.7 4444.5 4627.7 5034.3 5082.3 5082.4 4639.9 4947.8 5551.7 5565.0 5636.8 4144.2 4380.8 5048.8 5077.9 5173.5 2535.4 2599.7 2623.2 2648.9 2806.6 5876.6 6002.8 6007.9 6031.7 6046.8 5288.2 5379.5 5555.5 5859.6 5881.4 4613.7 5647.6 5784.9 5893.3 5922.4 4875.2 5300.5 5309.7 5667.0 5677.6 5163.1 5449.8 5593.3 5654.3 5675.3 4901.6 5603.3 5888.4 5896.5 5905.5 3649.4 3754.3 3762.5 3811.0 3821.9 4807.4 5070.7 5659.3 5675.4 5699.4 4877.8 5327.5 5519.6 5583.0 5606.7 5348.8 5536.9 5553.5 5574.9 5587.5 5418.5 5467.7 5468.4 5470.0 5511.3 5183.6 5281.9 5487.3 5525.4 5553.5 4526.0 5170.8 5186.1 5387.0 5400.1 05:29:53 INFO - PROCESS | 1948 | 05:29:53 INFO - PROCESS | 1948 | [#5] yui.html Cycles:180 Average:24400.95 Median:4573.68 stddev:40696.58 (889.8%) stddev-sans-first:40771.81 05:29:53 INFO - PROCESS | 1948 | Values: 691.6 707.6 722.8 750.4 773.7 15044.7 15652.6 15660.4 15735.2 15765.0 14075.7 14209.1 14213.9 14234.6 14687.8 673.7 685.8 821.5 821.9 845.5 825.7 839.8 840.4 840.6 842.2 898.7 899.3 899.4 900.1 900.3 710.0 725.9 725.9 889.7 894.2 547.0 656.7 672.5 675.1 682.8 14442.4 14490.2 14491.2 14500.9 14501.9 11687.0 13382.5 13625.0 13901.2 14390.6 10942.2 12064.5 12255.4 12323.3 12493.4 4526.8 4559.0 4629.5 4633.4 4761.1 110394.0 110957.4 112533.0 113089.0 115375.0 10573.8 10878.2 10878.9 11019.6 11127.9 21230.4 21256.1 21257.5 21286.6 21286.6 7394.9 7679.0 7682.9 7849.0 7872.7 3654.3 4372.3 4453.7 4515.1 4588.4 2731.3 3192.8 3273.0 3278.6 3282.5 630.7 631.7 635.2 647.7 650.6 547.5 588.4 602.7 607.8 611.4 8370.7 8825.3 8937.9 9030.1 9054.9 586.2 597.2 597.3 625.9 627.7 4067.3 4223.6 4239.5 4308.3 4377.2 122386.6 154106.2 161915.0 162910.2 165876.0 137366.3 137760.0 139206.0 142976.0 145364.3 79845.6 90925.0 116100.0 117800.0 119422.0 8374.5 8397.6 8437.5 8576.7 8652.3 61862.0 63858.4 64197.4 65585.7 67233.0 63512.0 66025.0 73785.0 93205.0 99271.5 9852.0 9978.7 9983.6 10064.5 10276.0 9808.3 9884.9 10059.6 10175.6 10445.6 10604.7 10827.7 10832.2 10952.6 10975.9 11603.8 11810.3 11864.6 12256.3 12367.3 11282.1 11814.0 11887.4 11963.6 12074.1 8820.1 9257.1 9263.6 9277.5 9537.0 8672.7 9946.0 9967.3 10003.6 10019.2 05:29:53 INFO - PROCESS | 1948 | -------- Summary: end -------- 05:29:53 INFO - PROCESS | 1948 | 05:29:54 INFO - TEST-INFO | 1948: exit 0 05:29:54 INFO - TEST-OK | dromaeo_css | took 1117981ms 05:29:54 INFO - TEST-START | kraken 05:29:54 INFO - operating with platform_type : mac_ 05:29:54 INFO - Initialising browser for kraken test... 05:29:54 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpiGYI_e/profile http://localhost:53506/getInfo.html) 05:29:56 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 05:29:56 INFO - PROCESS | 1952 | colorDepth:24 05:29:56 INFO - PROCESS | 1952 | Browser inner width/height: 1024/689 05:29:56 INFO - PROCESS | 1952 | __metrics 05:29:57 INFO - PROCESS | 1952 | 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" 05:29:57 INFO - PROCESS | 1952 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:29:57 INFO - TEST-INFO | 1952: exit 0 05:29:57 INFO - Browser initialized. 05:29:57 INFO - Running cycle 1/1 for kraken test... 05:29:57 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpiGYI_e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:30:09 INFO - PROCESS | 1953 | RSS: Main: 233648128 05:30:09 INFO - PROCESS | 1953 | 05:30:12 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:53506/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53506/tests/kraken/driver.html?testName=audio-beat-detection) 05:30:13 INFO - PROCESS | 1953 | RSS: Main: 293040128 05:30:13 INFO - PROCESS | 1953 | 05:30:15 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:53506/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53506/tests/kraken/driver.html?testName=audio-dft) 05:30:16 INFO - PROCESS | 1953 | RSS: Main: 288464896 05:30:16 INFO - PROCESS | 1953 | 05:30:20 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:53506/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53506/tests/kraken/driver.html?testName=audio-fft) 05:30:20 INFO - PROCESS | 1953 | RSS: Main: 379469824 05:30:20 INFO - PROCESS | 1953 | 05:30:22 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:53506/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53506/tests/kraken/driver.html?testName=audio-oscillator) 05:30:23 INFO - PROCESS | 1953 | RSS: Main: 281382912 05:30:23 INFO - PROCESS | 1953 | 05:30:25 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:53506/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53506/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:30:26 INFO - PROCESS | 1953 | RSS: Main: 285724672 05:30:26 INFO - PROCESS | 1953 | 05:30:28 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:53506/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53506/tests/kraken/driver.html?testName=imaging-darkroom) 05:30:29 INFO - PROCESS | 1953 | RSS: Main: 323907584 05:30:29 INFO - PROCESS | 1953 | 05:30:32 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:53506/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53506/tests/kraken/driver.html?testName=imaging-desaturate) 05:30:33 INFO - PROCESS | 1953 | RSS: Main: 324136960 05:30:33 INFO - PROCESS | 1953 | 05:30:35 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:53506/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53506/tests/kraken/driver.html?testName=json-parse-financial) 05:30:36 INFO - PROCESS | 1953 | RSS: Main: 324005888 05:30:36 INFO - PROCESS | 1953 | 05:30:38 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:53506/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53506/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:30:38 INFO - PROCESS | 1953 | RSS: Main: 298033152 05:30:38 INFO - PROCESS | 1953 | 05:30:40 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:53506/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53506/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:30:41 INFO - PROCESS | 1953 | RSS: Main: 302391296 05:30:41 INFO - PROCESS | 1953 | 05:30:43 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:53506/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53506/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:30:44 INFO - PROCESS | 1953 | RSS: Main: 300400640 05:30:44 INFO - PROCESS | 1953 | 05:30:47 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:53506/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53506/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:30:47 INFO - PROCESS | 1953 | RSS: Main: 318033920 05:30:47 INFO - PROCESS | 1953 | 05:30:50 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:53506/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53506/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:30:51 INFO - PROCESS | 1953 | RSS: Main: 314179584 05:30:51 INFO - PROCESS | 1953 | 05:30:52 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:53506/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53506/tests/kraken/driver.html?testName=ai-astar) 05:30:53 INFO - PROCESS | 1953 | RSS: Main: 357355520 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | __start_tp_report 05:30:53 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 05:30:53 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 05:30:53 INFO - PROCESS | 1953 | |i|pagename|runs| 05:30:53 INFO - PROCESS | 1953 | |0;ai-astar;112;113;116;111;114;112;113;113;112;123 05:30:53 INFO - PROCESS | 1953 | |1;audio-beat-detection;159;152;163;160;108;132;151;135;172;109 05:30:53 INFO - PROCESS | 1953 | |2;audio-dft;184;184;183;185;182;185;181;181;183;185 05:30:53 INFO - PROCESS | 1953 | |3;audio-fft;78;75;76;157;59;73;80;88;138;57 05:30:53 INFO - PROCESS | 1953 | |4;audio-oscillator;82;84;138;70;70;76;125;68;69;119 05:30:53 INFO - PROCESS | 1953 | |5;imaging-gaussian-blur;89;86;89;85;88;85;89;88;134;129 05:30:53 INFO - PROCESS | 1953 | |6;imaging-darkroom;93;93;94;94;93;93;94;95;93;94 05:30:53 INFO - PROCESS | 1953 | |7;imaging-desaturate;85;87;86;83;86;84;86;83;85;86 05:30:53 INFO - PROCESS | 1953 | |8;json-parse-financial;52;55;57;52;53;72;83;83;81;55 05:30:53 INFO - PROCESS | 1953 | |9;json-stringify-tinderbox;66;70;68;77;80;72;76;74;72;72 05:30:53 INFO - PROCESS | 1953 | |10;stanford-crypto-aes;88;85;86;85;88;83;84;85;85;84 05:30:53 INFO - PROCESS | 1953 | |11;stanford-crypto-ccm;142;150;141;151;156;153;133;139;169;151 05:30:53 INFO - PROCESS | 1953 | |12;stanford-crypto-pbkdf2;168;168;165;170;167;166;167;168;169;167 05:30:53 INFO - PROCESS | 1953 | |13;stanford-crypto-sha256-iterative;68;67;68;67;67;68;66;67;66;66 05:30:53 INFO - PROCESS | 1953 | __end_tp_report 05:30:53 INFO - PROCESS | 1953 | __start_cc_report 05:30:53 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,2031 05:30:53 INFO - PROCESS | 1953 | __end_cc_report 05:30:53 INFO - PROCESS | 1953 | __startTimestamp1472733053146__endTimestamp 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | ------- Summary: start ------- 05:30:53 INFO - PROCESS | 1953 | Number of tests: 14 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | [#0] ai-astar Cycles:10 Average:113.90 Median:113.00 stddev:3.48 (3.1%) stddev-sans-first:3.62 05:30:53 INFO - PROCESS | 1953 | Values: 112.0 113.0 116.0 111.0 114.0 112.0 113.0 113.0 112.0 123.0 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | [#1] audio-beat-detection Cycles:10 Average:144.10 Median:155.50 stddev:22.32 (14.4%) stddev-sans-first:23.02 05:30:53 INFO - PROCESS | 1953 | Values: 159.0 152.0 163.0 160.0 108.0 132.0 151.0 135.0 172.0 109.0 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:1.57 (0.9%) stddev-sans-first:1.64 05:30:53 INFO - PROCESS | 1953 | Values: 184.0 184.0 183.0 185.0 182.0 185.0 181.0 181.0 183.0 185.0 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | [#3] audio-fft Cycles:10 Average:88.10 Median:75.50 stddev:32.94 (43.6%) stddev-sans-first:34.73 05:30:53 INFO - PROCESS | 1953 | Values: 78.0 75.0 76.0 157.0 59.0 73.0 80.0 88.0 138.0 57.0 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | [#4] audio-oscillator Cycles:10 Average:90.10 Median:70.00 stddev:26.65 (38.1%) stddev-sans-first:28.10 05:30:53 INFO - PROCESS | 1953 | Values: 82.0 84.0 138.0 70.0 70.0 76.0 125.0 68.0 69.0 119.0 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | [#5] imaging-gaussian-blur Cycles:10 Average:96.20 Median:88.00 stddev:18.71 (21.3%) stddev-sans-first:19.66 05:30:53 INFO - PROCESS | 1953 | Values: 89.0 86.0 89.0 85.0 88.0 85.0 89.0 88.0 134.0 129.0 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 05:30:53 INFO - PROCESS | 1953 | Values: 93.0 93.0 94.0 94.0 93.0 93.0 94.0 95.0 93.0 94.0 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:86.00 stddev:1.37 (1.6%) stddev-sans-first:1.45 05:30:53 INFO - PROCESS | 1953 | Values: 85.0 87.0 86.0 83.0 86.0 84.0 86.0 83.0 85.0 86.0 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.50 stddev:13.72 (21.3%) stddev-sans-first:13.81 05:30:53 INFO - PROCESS | 1953 | Values: 52.0 55.0 57.0 52.0 53.0 72.0 83.0 83.0 81.0 55.0 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | [#9] json-stringify-tinderbox Cycles:10 Average:72.70 Median:73.00 stddev:4.22 (5.8%) stddev-sans-first:3.71 05:30:53 INFO - PROCESS | 1953 | Values: 66.0 70.0 68.0 77.0 80.0 72.0 76.0 74.0 72.0 72.0 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.00 stddev:1.64 (1.9%) stddev-sans-first:1.41 05:30:53 INFO - PROCESS | 1953 | Values: 88.0 85.0 86.0 85.0 88.0 83.0 84.0 85.0 85.0 84.0 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | [#11] stanford-crypto-ccm Cycles:10 Average:148.50 Median:151.00 stddev:10.22 (6.8%) stddev-sans-first:10.57 05:30:53 INFO - PROCESS | 1953 | Values: 142.0 150.0 141.0 151.0 156.0 153.0 133.0 139.0 169.0 151.0 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.50 Median:168.00 stddev:1.43 (0.9%) stddev-sans-first:1.51 05:30:53 INFO - PROCESS | 1953 | Values: 168.0 168.0 165.0 170.0 167.0 166.0 167.0 168.0 169.0 167.0 05:30:53 INFO - PROCESS | 1953 | 05:30:53 INFO - PROCESS | 1953 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 05:30:53 INFO - PROCESS | 1953 | Values: 68.0 67.0 68.0 67.0 67.0 68.0 66.0 67.0 66.0 66.0 05:30:53 INFO - PROCESS | 1953 | -------- Summary: end -------- 05:30:53 INFO - PROCESS | 1953 | 05:30:54 INFO - TEST-INFO | 1953: exit 0 05:30:54 INFO - TEST-OK | kraken | took 59577ms 05:30:54 INFO - SUITE-END | took 1177s 05:30:54 INFO - Completed test suite (00:19:37) 05:30:54 INFO - javascript benchmark 05:30:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2750.326170088328, 3270.999999999999, 3281.081297236773, 3315.0, 3348.0625424881027, 5500.642458240211, 5560.666360018402, 5584.6090773645865, 5619.2975878015195, 5692.37383887772, 5606.299212598426, 5619.072852979253, 5644.294463192101, 5652.982463156641, 5671.007573674611, 4352.238358287287, 5111.872203194913, 5135.717535535544, 5184.455632158626, 5315.707636080014, 5199.402068762084, 5200.245964335169, 5218.504242096995, 5259.684418934876, 5323.281357016792, 5107.157319042372, 5253.816116435925, 5255.237990491377, 5262.631615786897, 5270.710110943896, 5301.1783173608155, 5309.628326017161, 5333.919991200135, 5341.0, 5342.839714808562, 5039.647224694273, 5042.924356134661, 5053.242013697938, 5112.488751124858, 5127.897442051138, 5515.972420137914, 5523.199136125239, 5537.031019571599, 5554.777808887639, 5678.034734095214, 6129.632222066691, 6151.600145990541, 6167.136600875881, 6173.166622505994, 6191.783287584958, 6060.3939606039485, 6131.233595800525, 6172.84567885802, 6234.532410069263, 6247.500199983991, 5564.638298510584, 5651.9152212716845, 5689.175069614883, 5700.059490184073, 5774.249347584787, 6152.261728592508, 6210.7515699371515, 6240.500759939285, 6246.125542424064, 6253.593516421418, 5909.704514774244, 5958.553108516879, 6043.244594425697, 6062.727177277033, 6067.848303792441, 6068.72995541793, 6103.450689437972, 6131.040261761288, 6198.0702894566175, 6209.944309467402, 6129.877402452015, 6146.508279337642, 6154.815355539341, 6164.8767024659255, 6164.907526387018, 6019.608725432833, 6103.1455596216565, 6176.536759742948, 6177.444030037318, 6179.62922224668, 4844.854654360375, 4854.417469903634, 4863.659543831898, 4877.756112194376, 4893.0, 4375.234333991539, 4396.956030439655, 4409.889752756207, 4419.867403977886, 4429.601335879786, 5579.609427340129, 5593.776248950077, 5626.634268772516, 5648.604597678123, 5733.111367738007, 6131.294901086432, 6196.938030619636, 6215.098810672517, 6222.813315600449, 6233.96883015582, 5390.730463476811, 5431.293931788842, 5694.974904517227, 5703.201551782754, 5718.228039214653, 5298.602604804578, 5341.118715411992, 5346.759395827197, 5409.377921538995, 5414.8917021659345, 6101.095933926299, 6213.1922850029205, 6221.720022598994, 6268.905966410416, 6284.905726414016, 6231.813045608639, 6248.9062664059165, 6252.656103914328, 6268.1851359323, 6278.623282603058, 5556.972215138899, 6136.3046386708, 6181.041938499539, 6250.156228909039, 6256.374362563707, 6045.21412216409, 6115.483736156635, 6136.610985192662, 6137.703607474697, 6141.977005061692, 6149.446549810538, 6165.982612869006, 6203.627782333075, 6210.378962103753, 6234.189555357774, 5817.621854579439, 5959.195508606283, 6001.7599296027665, 6021.357764945359, 6040.305364882919, 5969.134475501115, 5997.880042399215, 6059.909101363572, 6087.726052327567, 6094.75620975156, 5855.59010869235, 5877.089051197071, 5880.588358814842, 5883.794067207627, 5954.404559544011, 5881.5588830838715, 5886.175935369138, 6051.7579296827635, 6076.088586711851, 6083.939160608337, 5784.190213370216, 5845.795397161079, 5911.733971971185, 5939.732712027882, 5953.732082056231], "value": 5630.538530833555, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.17249640632497, 488.75562218890553, 508.8448023352877, 519.3326575350675, 550.8402563256655, 30964.535531967038, 31006.68993310069, 31251.531227031584, 40130.0, 41179.58820411795, 42114.0, 42230.7888460558, 42250.0, 42349.78825105878, 42454.0, 9632.133108020294, 9785.510724463784, 9798.461084640341, 9804.46075465849, 9816.0, 8575.571221438935, 9592.232621390272, 9703.611855525805, 9718.416894986289, 9723.951380243088, 9639.277054220962, 9698.175655069328, 9705.563249653747, 9707.563159657833, 9719.75700607487, 9654.517274136266, 9682.515874206261, 9713.174380177694, 9713.417194968324, 9715.0, 9650.855237171449, 9704.51477426133, 9737.853932190952, 9744.561494732754, 9747.707568772948, 39450.8164755055, 39721.0, 39817.20365592672, 39857.0, 39880.40179397311, 3211.903642890717, 3321.850516726753, 3346.0631023313513, 3691.50164727761, 4085.428040074392, 30548.0, 30627.0, 30883.0735077948, 31337.529937050964, 31568.368632627218, 3218.09893229896, 3233.5149727541057, 3235.5146727990755, 3247.870085196589, 3336.7831090979007, 320677.0, 321041.0, 321632.0, 324224.0, 326477.0, 56665.150022749694, 57022.0, 57077.71461142751, 57205.0, 57238.7138064307, 34186.48720269198, 34392.484112738326, 34512.65487345094, 59532.0, 59762.70118649379, 15547.30037148331, 15922.28349724242, 15939.760903586455, 15942.123183224801, 15960.281787319598, 12303.69240768988, 12316.891479766684, 12445.502179912883, 12445.937770311091, 12461.875381246253, 15838.9208053959, 17927.0, 18609.534761630795, 18609.627807443776, 18638.81361186371, 6688.498362622823, 7598.8100297493, 7663.888736133229, 7702.691892324357, 7796.0, 839.9538025408661, 946.7396465971913, 1003.9548220330103, 1014.6550172941236, 1092.464692300767, 24538.386540336633, 26034.218973430827, 28000.0, 30177.396452071276, 30203.244918876764, 833.0087196236581, 838.9496630202208, 1038.0190719769746, 1041.250299784154, 1051.8211903976342, 14428.422863085569, 15131.092134471966, 15163.166025868682, 15459.458918937784, 15475.767863482057, 630384.0, 633985.0, 635115.0, 636114.0, 642770.0, 54741.726291368286, 55288.34134975957, 55522.44477555253, 55547.0, 55624.44375556274, 1209.1354681402838, 1310.5871650430274, 1334.252818421687, 1377.5040985245103, 1383.1217177092417, 29661.85169074141, 30841.69158308478, 31029.379412411174, 31305.21736956503, 31766.841165794023, 47697.76151119361, 48185.03629927452, 48357.27464087971, 48397.75801120972, 48440.0, 518047.0, 518297.0, 518470.0, 520241.0, 852949.0, 39928.00179995407, 40735.0, 40844.38733419061, 40902.18195636011, 41164.70682345345, 45717.0, 45914.081718364774, 46031.0, 46077.76961115173, 46477.767611161726, 29424.852875736342, 37413.625863741014, 37654.43518347172, 37662.0, 38829.0, 37212.627873720914, 37652.623473766005, 38639.80680096582, 38793.0, 38885.416718748675, 36350.0, 36847.815760922094, 36932.44601330929, 37199.0, 37344.81327593345, 28166.2958426041, 31850.362992739552, 32426.31286247742, 32822.5076623846, 33066.66933330636, 24461.0, 24525.38686532851, 24864.0, 25141.49717005613, 25992.35019124537], "value": 19417.67696318386, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.8341679410676, 361.6347489036074, 371.25563245692393, 372.253631468905, 403.2720938705637, 15173.848261517382, 16515.917420412912, 20091.29680461184, 20601.896990515026, 20752.273670421557, 20304.883231422267, 20586.897065514688, 20910.790892091074, 21180.576388472222, 21224.57550848986, 5283.313169287987, 5914.438128377808, 6058.454739073482, 6184.721687524072, 6188.195534580498, 6064.332923378447, 6112.541559383042, 6148.477379422754, 6150.446459818615, 6172.135900973867, 5938.10928360745, 5986.640801551899, 6022.457978781908, 6075.17985072014, 6119.470132466883, 5993.520518358521, 6007.879842403149, 6054.303755068157, 6064.272287325505, 6068.362821903681, 4988.476209997934, 5235.790568377261, 5236.005159019774, 5238.869028274324, 5242.6330156889035, 26147.477050458885, 26284.685765711638, 26429.0, 26471.0, 26525.0, 1673.7740405045286, 2061.8247448966854, 2156.590247852903, 2334.6264597664463, 2499.637552554869, 9674.467904265262, 9803.90196098037, 9928.851067233998, 10197.18422526196, 10285.794284114276, 1743.7994630617513, 1837.2467288411822, 1857.674906891302, 1859.5165257033134, 1878.2674756844842, 93716.0, 121228.0, 123627.0, 124243.0, 133373.33313333566, 8918.66220066995, 11085.944570277097, 11287.54849806015, 11366.829497557543, 11507.252028618113, 14434.639134021734, 14455.855441445661, 14478.986470947144, 14495.637609059648, 14502.419903203754, 8239.835203296021, 8385.664573416994, 8421.663133474593, 8440.957795210985, 8531.829363412697, 8283.792905177419, 8305.626246818907, 8336.583170841433, 8460.619272132768, 8496.0, 3703.981480092636, 3731.8134093295225, 4431.822727090945, 4453.77731113443, 4459.509453960061, 1825.5527395787851, 1871.691170956777, 1895.6682580548625, 1898.9905050474658, 1935.4677463697597, 666.8432918264205, 694.2675477371422, 711.7579227802253, 749.9775006749807, 945.385499425379, 20257.290994815405, 20907.0, 20919.058642360826, 21103.677428045292, 21394.251201207884, 876.9342299327449, 882.5543100734088, 891.5289771876455, 903.5933829776627, 925.4771054354268, 10109.241806864515, 10794.946025269981, 10834.295770775032, 10838.620648277272, 10864.456777161111, 597154.0, 602134.0, 610466.0, 613396.0, 617550.4734857961, 112861.87138128678, 120892.0, 135129.0, 137910.31044844713, 146215.2689236547, 963.7735132243918, 989.881433979584, 1026.6971243483042, 1031.7008067660295, 1034.0641719244097, 18265.817341826412, 18862.0, 19094.427167185007, 19281.51796205106, 19887.801121988596, 55037.72481137569, 55175.724121379135, 55187.44812551823, 56060.0, 56333.0, 80686.98282542983, 80998.59500702459, 81028.5948570277, 81503.0, 84280.0, 7596.506227095221, 7695.576743279174, 7859.724909628136, 7950.24472675095, 8043.87934180976, 8306.0, 9245.72262832116, 11143.888561114285, 12294.50821967147, 12523.499060037497, 10666.413347265665, 10680.305780124267, 10800.729981750519, 10933.56265749361, 10986.780264394827, 10540.894591054299, 10591.311564748266, 10721.35671859691, 10838.620648277272, 10868.891311086787, 10532.157427405637, 10953.890461095605, 11024.228304018641, 11086.778264434828, 11228.382438965935, 6684.43182329488, 7747.535147891144, 7764.107127680163, 7825.765227043198, 7900.565468899265, 10670.89329106699, 10785.0, 10834.620788272372, 10984.286021408583, 11024.944875275572], "value": 9374.99198144219, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1473.0498828255775, 1513.4475916290553, 1697.5501492104586, 1879.9812001879993, 1908.9618207635847, 8413.0, 8771.649134034631, 8778.91221087789, 8820.470771753691, 8827.117288271178, 6635.60186388817, 7787.4938129021575, 8005.759827205178, 8556.657733690645, 8671.479711217316, 2378.3935096550354, 2405.326508577601, 2407.6629271901948, 2410.6625072489865, 2415.4685969086772, 2025.6657651487487, 2286.7141607299086, 2398.64020396941, 2399.7360290368133, 2400.8199385046105, 2075.0973326602902, 2151.8924053797327, 2310.8151347892212, 2391.2467572714627, 2392.0790495659207, 2374.726906405768, 2380.92857214283, 2401.171595799455, 2408.1210358219123, 2408.1812183857573, 2383.6186210206283, 2387.689600351943, 2388.641703744452, 2391.4141035446255, 2393.114547617393, 8745.606447709868, 8828.646854125827, 8829.470231786114, 8830.116988301183, 8852.424592401474, 5146.665466744649, 5318.095923692943, 5319.601029922771, 5323.494268044529, 5323.946760532423, 3004.1137864330076, 3291.1772056985756, 3334.6498617645284, 3525.788452692821, 3533.9293214135573, 1025.5897640943608, 1030.3148406309795, 1044.7910417916446, 1049.9842502362471, 1052.3475445223933, 11429.028452602382, 11532.0, 11560.6531804046, 11581.247218930743, 11681.240719353214, 3817.9618203818163, 4479.395281636937, 4704.435467743892, 4725.763711814464, 4734.597559207437, 9744.269179811543, 9747.3664211826, 9748.610055597697, 9752.9024709752, 9794.65718699842, 5217.034848552954, 5219.582433405319, 5219.634625576173, 5223.4776522347465, 5226.484319547365, 4607.424071991001, 4764.0, 4796.544328288807, 4801.4958429365115, 4804.615630749532, 3213.3412650406613, 3871.477350557695, 3915.275674000319, 3967.444557761916, 3981.442598036277, 1178.4814681540095, 1262.7916393794922, 1268.4735834628525, 1269.8285731426324, 1287.6973911130876, 761.2463660975704, 867.9045305016443, 878.4158534574564, 890.3322508118911, 959.467495539982, 5747.281589801275, 5858.53131749459, 5926.881462370815, 6144.815655530341, 6156.153154053766, 891.2068259249273, 931.2549960031947, 934.238595544629, 948.5399581203111, 961.8749562556962, 3414.7404282290804, 3918.5689574146822, 3925.8429662813737, 4049.3521036633997, 4064.8577299794365, 10315.638952636771, 10655.0, 10670.51982660782, 10679.466026698634, 10696.94651526753, 9981.900180998096, 10446.007629275207, 10446.373217606968, 10480.004399582027, 10608.681739547827, 7736.187700291501, 7921.960390198012, 7924.881126783102, 7932.04815422153, 7943.285104340637, 4911.435184953776, 5831.883362332815, 5846.444587764156, 5995.820125396245, 6176.382361763787, 9798.265130115413, 9816.411015339103, 9817.901820981699, 9836.458994755358, 9845.950770246103, 8685.652573897227, 9727.221822254343, 9783.75540611462, 9977.201823854219, 9987.850182247126, 7319.963400182965, 7529.849403012018, 7532.7740167795055, 7536.171021187555, 7538.359239464707, 8062.83874322522, 8132.83734325322, 8222.301104405953, 8261.049979252462, 8277.710280140407, 5674.290713660793, 6279.9590133205975, 7350.228226036189, 7367.963160184165, 7520.924790752022, 7000.50996430245, 7173.964130179525, 7233.4574906883245, 7295.817604559928, 7313.744018959524, 6744.426723728342, 6853.7943861684225, 6859.965700171467, 6867.141607299088, 6871.03805467225, 4341.283688191413, 4788.138135135709, 4963.379577552806, 5083.339165908408, 5135.897282054413, 4926.950730492649, 4977.900441991213, 5024.924626130538, 5243.449437809128, 5300.992811365828], "value": 4515.69707811225, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.4027542161461, 297.71270723751576, 312.8639042016724, 312.8811051800316, 316.4683332002235, 4864.659473836833, 5243.423223445423, 5261.368635763709, 5283.920741188876, 5366.570674346048, 4047.6559492443102, 4175.979120104403, 4957.975210123945, 4964.751762411874, 5105.974470127653, 3261.7442284720314, 3320.5019247112946, 3439.3981053315697, 3979.8408063677416, 4024.235395274903, 3327.317899830529, 3629.909252268688, 3761.360568703327, 4006.3189257826093, 4023.3361495353233, 3110.4851517784873, 3229.54786329914, 3561.3589553880292, 3705.722070844684, 3945.7632542047427, 3922.4900762900925, 3944.250592387466, 3963.326234540135, 3969.8809035728973, 3998.0004998750314, 3454.274602333513, 3463.79217246966, 3469.7397695172713, 3472.0, 3474.6351633078666, 4720.57514823664, 5156.845294641129, 5158.400895722338, 5158.613104017214, 5176.689398636094, 3937.4881265435597, 4440.622547083502, 4482.058767658796, 4485.945802736354, 4502.077074199814, 4575.405197324327, 5484.177373393983, 5515.034868897927, 5519.254900588409, 5523.199136125279, 3134.357456721367, 3741.8316175772156, 3754.624537546251, 3760.2291530236507, 3791.3554695701523, 4949.455559888409, 4963.5780958618925, 5044.2937988681615, 5757.827265182052, 5792.65244085352, 3620.5836328822525, 3771.0195349208852, 3792.1657235408165, 3795.2788970095594, 3812.6377994090517, 3026.697330267, 3883.0874744435037, 3896.4350169225304, 3910.1006768443417, 4006.7595944242858, 3556.8973618178447, 4085.20338533983, 4159.875203743893, 4512.097580483916, 4553.749543775124, 4638.675292729544, 5483.862903427446, 5522.779088836482, 5540.584456165804, 5645.181448689917, 1167.8656954450178, 1218.256863313378, 1293.249915049288, 1300.232862611064, 1326.8191309734343, 789.1674283630709, 819.4304958054095, 830.8089139497829, 847.7287268074281, 871.6687658689657, 4444.488883778342, 4627.745473998963, 5034.306645944403, 5082.313887625197, 5082.440931497532, 4639.930401043987, 4947.752612369367, 5551.667599776025, 5565.048815341153, 5636.830895073154, 4144.150449157915, 4380.802863871133, 5048.7728052237735, 5077.949220507748, 5173.456787037382, 2535.3773584905675, 2599.727028662001, 2623.2130360891656, 2648.894044238209, 2806.579013148044, 5876.588638795329, 6002.849928751729, 6007.939920600826, 6031.728572214261, 6046.818595442144, 5288.206768984606, 5379.515843574019, 5555.527780138734, 5859.589828712034, 5881.3530511643685, 4613.723176609415, 5647.576431767634, 5784.913226301524, 5893.3222679391565, 5922.378150294243, 4875.24433712775, 5300.469953004668, 5309.707966061904, 5667.0, 5677.643777832319, 5163.0706472833745, 5449.809256675997, 5593.33636468114, 5654.293213348332, 5675.347335056521, 4901.5588597026435, 5603.299587551556, 5888.440598143169, 5896.49879760208, 5905.498032667099, 3649.397849354933, 3754.3429899766884, 3762.4732537444233, 3811.0, 3821.9044523887123, 4807.375041244616, 5070.670406423571, 5659.292588426447, 5675.404082571271, 5699.401562835843, 4877.756112194305, 5327.53492789486, 5519.6265605789895, 5582.972085139549, 5606.719664016701, 5348.786048558014, 5536.861578460571, 5553.4724201200825, 5574.9163762542785, 5587.469190426903, 5418.45815418455, 5467.699276539669, 5468.425815289337, 5470.0, 5511.338639363302, 5183.5593974512585, 5281.947180528299, 5487.286652735119, 5525.419830917856, 5553.4724201200825, 4525.981654127795, 5170.819021334235, 5186.066508028591, 5387.0, 5400.05499037659], "value": 3961.0244554499955, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.5643144818764, 707.5895980331409, 722.8265216348071, 750.4334227658118, 773.744664260794, 15044.699106017873, 15652.608684782886, 15660.373585056612, 15735.21323933803, 15765.0, 14075.718485630281, 14209.147451152938, 14213.928930355334, 14234.644133896658, 14687.779683304732, 673.6665350651434, 685.7531288736076, 821.511200835505, 821.893153889995, 845.5011543189516, 825.6697321071559, 839.8404303182406, 840.4201101240155, 840.5965136734371, 842.204117109332, 898.7393655839799, 899.3210126354593, 899.3614533681061, 900.0999000999001, 900.3382513852313, 710.0378986473357, 725.905632267807, 725.9455540834406, 889.7464222696533, 894.1862904756705, 547.0290234833163, 656.671664167916, 672.5393105722546, 675.0818886314609, 682.8258794007546, 14442.422303107864, 14490.20303883286, 14491.202983835885, 14500.92749536256, 14501.927490362586, 11687.033994376723, 13382.531611393555, 13625.0, 13901.16593004423, 14390.640233994234, 10942.17933654971, 12064.517419303218, 12255.448504817305, 12323.260604363677, 12493.438320209974, 4526.818927242906, 4558.977205113986, 4629.490756016836, 4633.397658304432, 4761.119192949281, 110394.0, 110957.44521277423, 112533.0, 113089.0, 115374.99999999916, 10573.841392379121, 10878.184136189817, 10878.945605271923, 11019.559217631366, 11127.944360278147, 21230.36308910735, 21256.149754009977, 21257.468563286042, 21286.574268514854, 21286.574268514854, 7394.86451190015, 7679.0, 7682.923170768333, 7849.0, 7872.72445464406, 3654.3422184007136, 4372.322290045048, 4453.665975051819, 4515.1195516873895, 4588.3805686231935, 2731.2762118038736, 3192.7605429592872, 3272.9509057364157, 3278.639349671534, 3282.491213861855, 630.7413960276357, 631.6620607974711, 635.167930011677, 647.7473785223766, 650.6193876582253, 547.4990383798779, 588.4497994375273, 602.7468463245448, 607.7629724407485, 611.367234911873, 8370.707025254087, 8825.293976481866, 8937.865932011026, 9030.142136497023, 9054.864177037218, 586.234963372794, 597.1772499196661, 597.2981746447906, 625.8560531077786, 627.704978660029, 4067.328890732996, 4223.577642235752, 4239.51245606753, 4308.267594508941, 4377.233984052778, 122386.5522689559, 154106.22946885193, 161915.0, 162910.18544907437, 165876.0, 137366.31316843352, 137760.0, 139206.0, 142976.0, 145364.27317863342, 79845.60077199577, 90925.0, 116100.0, 117800.0, 119422.0, 8374.49753014821, 8397.580120993804, 8437.535935523605, 8576.699815506427, 8652.264557512683, 61862.0, 63858.36141638524, 64197.35802641914, 65585.67207163933, 67233.0, 63512.0, 66025.0, 73785.0, 93205.00939136728, 99271.50364248133, 9852.044386683823, 9978.650747223812, 9983.550740216562, 10064.54709538058, 10276.0, 9808.264380171373, 9884.85172722424, 10059.647912323033, 10175.592976280866, 10445.582176713151, 10604.681859544226, 10827.729306767395, 10832.221978940675, 10952.56189752401, 10975.890241097486, 11603.767924641292, 11810.291382516732, 11864.584739534075, 12256.264624122581, 12367.319797411228, 11282.097432205568, 11814.0, 11887.405629718307, 11963.64109076729, 12074.094442916641, 8820.073892241478, 9257.074292570689, 9263.583138758637, 9277.536123193948, 9536.952315238379, 8672.653093876173, 9946.005399459997, 9967.302288839712, 10003.599856005678, 10019.248556358156], "value": 7367.627542047883, "unit": "score"}], "name": "dromaeo_css", "value": 7163.151714942361, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 116.0, 111.0, 114.0, 112.0, 113.0, 113.0, 112.0, 123.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 152.0, 163.0, 160.0, 108.0, 132.0, 151.0, 135.0, 172.0, 109.0], "value": 144.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 185.0, 182.0, 185.0, 181.0, 181.0, 183.0, 185.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 76.0, 157.0, 59.0, 73.0, 80.0, 88.0, 138.0, 57.0], "value": 88.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 138.0, 70.0, 70.0, 76.0, 125.0, 68.0, 69.0, 119.0], "value": 90.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 85.0, 88.0, 85.0, 89.0, 88.0, 134.0, 129.0], "value": 96.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 93.0, 93.0, 94.0, 95.0, 93.0, 94.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 86.0, 83.0, 86.0, 84.0, 86.0, 83.0, 85.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 57.0, 52.0, 53.0, 72.0, 83.0, 83.0, 81.0, 55.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 68.0, 77.0, 80.0, 72.0, 76.0, 74.0, 72.0, 72.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 86.0, 85.0, 88.0, 83.0, 84.0, 85.0, 85.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 150.0, 141.0, 151.0, 156.0, 153.0, 133.0, 139.0, 169.0, 151.0], "value": 148.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 165.0, 170.0, 167.0, 166.0, 167.0, 168.0, 169.0, 167.0], "value": 167.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 67.0, 67.0, 68.0, 66.0, 67.0, 66.0, 66.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1499.7, "alertThreshold": 2.0}]} 05:30:54 INFO - Return code: 0 05:30:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:30:54 INFO - # TBPL SUCCESS # 05:30:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:30:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:30:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:30:54 INFO - 2016-09-01 05:30:54.494 screenresolution[1958:19818] starting screenresolution argv=screenresolution get 05:30:54 INFO - 2016-09-01 05:30:54.497 screenresolution[1958:19818] Display 0: 1600x1200x32@60 05:30:54 INFO - 2016-09-01 05:30:54.503 screenresolution[1959:19821] starting screenresolution argv=screenresolution list 05:30:54 INFO - Available Modes on Display 0 05:30:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:30:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:30:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:30:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:30:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:30:54 INFO - Intel Iris: 05:30:54 INFO - Chipset Model: Intel Iris 05:30:54 INFO - Type: GPU 05:30:54 INFO - Bus: Built-In 05:30:54 INFO - VRAM (Dynamic, Max): 1536 MB 05:30:54 INFO - Vendor: Intel (0x8086) 05:30:54 INFO - Device ID: 0x0a2e 05:30:54 INFO - Revision ID: 0x0009 05:30:54 INFO - Displays: 05:30:54 INFO - Display: 05:30:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:30:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:30:54 INFO - Display Serial Number: 1600x1200 60 05:30:54 INFO - Main Display: Yes 05:30:54 INFO - Mirror: Off 05:30:54 INFO - Online: Yes 05:30:54 INFO - Rotation: Supported 05:30:54 INFO - Return code: 0 05:30:54 INFO - Running post-action listener: _resource_record_post_action 05:30:54 INFO - [mozharness: 2016-09-01 12:30:54.741728Z] Finished run-tests step (success) 05:30:54 INFO - Running post-run listener: _resource_record_post_run 05:30:54 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 786432; Write bytes: 706744320; Read time: 10; Write time: 2024 05:30:54 INFO - TinderboxPrint: CPU usage
53.4% 05:30:54 INFO - TinderboxPrint: I/O read bytes / time
786,432 / 10 05:30:54 INFO - TinderboxPrint: I/O write bytes / time
706,744,320 / 2,024 05:30:54 INFO - TinderboxPrint: CPU idle
2,261.2 (46.6%) 05:30:54 INFO - TinderboxPrint: CPU system
967.6 (20.0%) 05:30:54 INFO - TinderboxPrint: CPU user
1,620.9 (33.4%) 05:30:54 INFO - TinderboxPrint: Swap in / out
406,441,984 / 0 05:30:54 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402776576; Write bytes: 397063680; Read time: 29865; Write time: 788 05:30:54 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 688128; Write bytes: 295100928; Read time: 9; Write time: 1190 05:30:55 INFO - Running post-run listener: _upload_blobber_files 05:30:55 INFO - Blob upload gear active. 05:30:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:30:55 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 05:30:55 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'] 05:30:55 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 05:30:55 INFO - (blobuploader) - INFO - Open directory for files ... 05:30:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:30:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:30:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:30:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:30:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:30:58 INFO - (blobuploader) - INFO - Done attempting. 05:30:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:30:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:30:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:30:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:30:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:30:59 INFO - (blobuploader) - INFO - Done attempting. 05:30:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:30:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:30:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:31:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:01 INFO - (blobuploader) - INFO - Done attempting. 05:31:01 INFO - (blobuploader) - INFO - Iteration through files over. 05:31:01 INFO - Return code: 0 05:31:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:31:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:31:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1be0b0d76de9b81c6e7bd7c70c79de0636b9fe21341554025043eb560d27c073d7f1607dd5726d452486e660a052fdacf5185b999e059bef3af43f5c7ff40412", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/99ed0091e4e0ead2ca1cd40e001c9531c83407b77dd54741cccadf2c08270546b3eda07a21f6b2acbc44181a6e174e52e11965c9746741ce20bbf90783886874", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1ea825e3b218617e5831d4b119841fb8fb78d5b4a92e9cc7231c540b23df741b6cd986561512ba855379fe9e17cd21102b4e85e09fe94bf0b6fa71439e82ef41"} 05:31:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:31:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:31:01 INFO - Contents: 05:31:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1be0b0d76de9b81c6e7bd7c70c79de0636b9fe21341554025043eb560d27c073d7f1607dd5726d452486e660a052fdacf5185b999e059bef3af43f5c7ff40412", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/99ed0091e4e0ead2ca1cd40e001c9531c83407b77dd54741cccadf2c08270546b3eda07a21f6b2acbc44181a6e174e52e11965c9746741ce20bbf90783886874", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1ea825e3b218617e5831d4b119841fb8fb78d5b4a92e9cc7231c540b23df741b6cd986561512ba855379fe9e17cd21102b4e85e09fe94bf0b6fa71439e82ef41"} 05:31:01 INFO - Running post-run listener: copy_logs_to_upload_dir 05:31:01 INFO - Copying logs to upload dir... 05:31:01 INFO - mkdir: /builds/slave/test/build/upload/logs 05:31:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.222998 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-09-01 05:31:01.741502) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 05:31:01.745568) ========= 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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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/1be0b0d76de9b81c6e7bd7c70c79de0636b9fe21341554025043eb560d27c073d7f1607dd5726d452486e660a052fdacf5185b999e059bef3af43f5c7ff40412", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/99ed0091e4e0ead2ca1cd40e001c9531c83407b77dd54741cccadf2c08270546b3eda07a21f6b2acbc44181a6e174e52e11965c9746741ce20bbf90783886874", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1ea825e3b218617e5831d4b119841fb8fb78d5b4a92e9cc7231c540b23df741b6cd986561512ba855379fe9e17cd21102b4e85e09fe94bf0b6fa71439e82ef41"} build_url:https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011052 build_url: 'https://queue.taskcluster.net/v1/task/SowZmXPhT2KmT0YFlswiUQ/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/1be0b0d76de9b81c6e7bd7c70c79de0636b9fe21341554025043eb560d27c073d7f1607dd5726d452486e660a052fdacf5185b999e059bef3af43f5c7ff40412", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/99ed0091e4e0ead2ca1cd40e001c9531c83407b77dd54741cccadf2c08270546b3eda07a21f6b2acbc44181a6e174e52e11965c9746741ce20bbf90783886874", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1ea825e3b218617e5831d4b119841fb8fb78d5b4a92e9cc7231c540b23df741b6cd986561512ba855379fe9e17cd21102b4e85e09fe94bf0b6fa71439e82ef41"}' ========= master_lag: 0.21 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 05:31:01.970590) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 05:31:01.971012) ========= 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.Q7dA9ofQuL/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.Zj5CKev0zV/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.005061 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 05:31:02.021608) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 05:31:02.022168) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 05:31:02.022765) ========= ========= Total master_lag: 0.38 =========