builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0239 starttime: 1471881189.76 results: success (0) buildid: 20160822063914 builduid: ac95ec65533e440cb6a32a4a9fd69779 revision: 5f3d66a9ea5ddcf59e0b83a81579773aa7d68ae4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 08:53:09.759057) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 08:53:09.759633) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 08:53:09.759985) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 08:53:09.786237) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 08:53:09.786586) ========= 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.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-22 08:53:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 25.9M=0s 2016-08-22 08:53:09 (25.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082976 ========= master_lag: 0.32 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 08:53:10.188694) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 08:53:10.189068) ========= 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.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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.026655 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 08:53:10.238683) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 08:53:10.239071) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5f3d66a9ea5ddcf59e0b83a81579773aa7d68ae4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5f3d66a9ea5ddcf59e0b83a81579773aa7d68ae4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-22 08:53:10,323 truncating revision to first 12 chars 2016-08-22 08:53:10,323 Setting DEBUG logging. 2016-08-22 08:53:10,323 attempt 1/10 2016-08-22 08:53:10,323 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5f3d66a9ea5d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 08:53:10,651 unpacking tar archive at: mozilla-central-5f3d66a9ea5d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.585647 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 08:53:10.848386) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 08:53:10.848883) ========= script_repo_revision: 5f3d66a9ea5ddcf59e0b83a81579773aa7d68ae4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 08:53:10.849365) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 08:53:10.849682) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 08:53:10.868918) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-22 08:53:10.869295) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 08:53:11 INFO - MultiFileLogger online at 20160822 08:53:11 in /builds/slave/test 08:53:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:53:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:53:11 INFO - {'append_to_log': False, 08:53:11 INFO - 'base_work_dir': '/builds/slave/test', 08:53:11 INFO - 'blob_upload_branch': 'Firefox', 08:53:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:53:11 INFO - 'branch': 'Firefox', 08:53:11 INFO - 'buildbot_json_path': 'buildprops.json', 08:53:11 INFO - 'config_files': ('talos/mac_config.py',), 08:53:11 INFO - 'default_actions': ('clobber', 08:53:11 INFO - 'read-buildbot-config', 08:53:11 INFO - 'download-and-extract', 08:53:11 INFO - 'populate-webroot', 08:53:11 INFO - 'create-virtualenv', 08:53:11 INFO - 'install', 08:53:11 INFO - 'run-tests'), 08:53:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:53:11 INFO - 'download_minidump_stackwalk': True, 08:53:11 INFO - 'download_symbols': 'ondemand', 08:53:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:53:11 INFO - 'tooltool.py': '/tools/tooltool.py', 08:53:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:53:11 INFO - '/tools/misc-python/virtualenv.py')}, 08:53:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:53:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:53:11 INFO - 'installer_path': 'installer.exe', 08:53:11 INFO - 'log_level': 'info', 08:53:11 INFO - 'log_name': 'talos', 08:53:11 INFO - 'log_to_console': True, 08:53:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:53:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:53:11 INFO - 'opt_config_files': (), 08:53:11 INFO - 'pip_index': False, 08:53:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:53:11 INFO - 'cmd': ('bash', 08:53:11 INFO - '-c', 08:53:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:53:11 INFO - 'enabled': True, 08:53:11 INFO - 'halt_on_failure': False, 08:53:11 INFO - 'name': 'check_screen_resolution'},), 08:53:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:53:11 INFO - 'cmd': ('bash', 08:53:11 INFO - '-c', 08:53:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:53:11 INFO - 'enabled': True, 08:53:11 INFO - 'halt_on_failure': False, 08:53:11 INFO - 'name': 'check_screen_resolution'},), 08:53:11 INFO - 'run_cmd_checks_enabled': True, 08:53:11 INFO - 'sps_profile': False, 08:53:11 INFO - 'sps_profile_interval': 0, 08:53:11 INFO - 'suite': 'dromaeojs', 08:53:11 INFO - 'system_bits': '32', 08:53:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:53:11 INFO - 'title': 't-yosemite-r7-0239', 08:53:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:53:11 INFO - 'use_talos_json': True, 08:53:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:53:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:53:11 INFO - 'work_dir': 'build'} 08:53:11 INFO - [mozharness: 2016-08-22 15:53:11.063551Z] Running clobber step. 08:53:11 INFO - Running pre-action listener: _resource_record_pre_action 08:53:11 INFO - Running main action method: clobber 08:53:11 INFO - rmtree: /builds/slave/test/build 08:53:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:53:13 INFO - Running post-action listener: _resource_record_post_action 08:53:13 INFO - [mozharness: 2016-08-22 15:53:13.126583Z] Finished clobber step (success) 08:53:13 INFO - [mozharness: 2016-08-22 15:53:13.126826Z] Running read-buildbot-config step. 08:53:13 INFO - Running pre-action listener: _resource_record_pre_action 08:53:13 INFO - Running main action method: read_buildbot_config 08:53:13 INFO - Using buildbot properties: 08:53:13 INFO - { 08:53:13 INFO - "project": "", 08:53:13 INFO - "product": "firefox", 08:53:13 INFO - "script_repo_revision": "production", 08:53:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:53:13 INFO - "repository": "", 08:53:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:53:13 INFO - "buildid": "20160822063914", 08:53:13 INFO - "pgo_build": "False", 08:53:13 INFO - "basedir": "/builds/slave/test", 08:53:13 INFO - "buildnumber": 36, 08:53:13 INFO - "slavename": "t-yosemite-r7-0239", 08:53:13 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:53:13 INFO - "platform": "yosemite_r7", 08:53:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:53:13 INFO - "branch": "mozilla-central", 08:53:13 INFO - "repo_path": "mozilla-central", 08:53:13 INFO - "revision": "5f3d66a9ea5ddcf59e0b83a81579773aa7d68ae4", 08:53:13 INFO - "stage_platform": "macosx64", 08:53:13 INFO - "builduid": "ac95ec65533e440cb6a32a4a9fd69779", 08:53:13 INFO - "slavebuilddir": "test" 08:53:13 INFO - } 08:53:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 08:53:13 INFO - Running post-action listener: _resource_record_post_action 08:53:13 INFO - [mozharness: 2016-08-22 15:53:13.132450Z] Finished read-buildbot-config step (success) 08:53:13 INFO - [mozharness: 2016-08-22 15:53:13.132663Z] Running download-and-extract step. 08:53:13 INFO - Running pre-action listener: _resource_record_pre_action 08:53:13 INFO - Running main action method: download_and_extract 08:53:13 INFO - mkdir: /builds/slave/test/build/tests 08:53:13 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')]} 08:53:13 INFO - trying https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 08:53:13 INFO - Downloading https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/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 08:53:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/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 08:53:14 INFO - Downloaded 1199 bytes. 08:53:14 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:53:14 INFO - Using the following test package requirements: 08:53:14 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:53:14 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:53:14 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 08:53:14 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:53:14 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 08:53:14 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:53:14 INFO - u'jsshell-mac.zip'], 08:53:14 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:53:14 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 08:53:14 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:53:14 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:53:14 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 08:53:14 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:53:14 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 08:53:14 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:53:14 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 08:53:14 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:53:14 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 08:53:14 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:53: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')]} 08:53:14 INFO - trying https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:53:14 INFO - Downloading https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/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 08:53:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/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 08:53:15 INFO - Downloaded 16351880 bytes. 08:53:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:53:21 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 08:53:21 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')]} 08:53:21 INFO - trying https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:53:21 INFO - Downloading https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/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 08:53:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/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 08:53:22 INFO - Downloaded 16351880 bytes. 08:53:22 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:53:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:29 INFO - trying https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:53:29 INFO - Downloading https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/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 08:53:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/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 08:53:30 INFO - Downloaded 14413981 bytes. 08:53:30 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 08:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:30 INFO - trying https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:53:30 INFO - Downloading https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 08:53:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/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 08:53:33 INFO - Downloaded 111515635 bytes. 08:53:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:53:33 INFO - mkdir: /builds/slave/test/properties 08:53:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:53:33 INFO - Writing to file /builds/slave/test/properties/build_url 08:53:33 INFO - Contents: 08:53:33 INFO - build_url:https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:53:34 INFO - Running post-action listener: _resource_record_post_action 08:53:34 INFO - Running post-action listener: set_extra_try_arguments 08:53:34 INFO - [mozharness: 2016-08-22 15:53:34.248666Z] Finished download-and-extract step (success) 08:53:34 INFO - [mozharness: 2016-08-22 15:53:34.248800Z] Running populate-webroot step. 08:53:34 INFO - Running pre-action listener: _resource_record_pre_action 08:53:34 INFO - Running main action method: populate_webroot 08:53:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:53:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:53:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:53:34 INFO - '--tppagecycles', 08:53:34 INFO - '7'], 08:53:34 INFO - 'tests': ['tsvgm']}}, 08:53:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:53:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:53:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:53:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:53:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:53:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:53:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:34 INFO - 'talos_options': ['--disable-e10s'], 08:53:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:53:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:53:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:34 INFO - 'talos_options': ['--disable-e10s'], 08:53:34 INFO - 'tests': ['damp', 'tps']}, 08:53:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:34 INFO - 'tests': ['damp', 'tps']}, 08:53:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:53:34 INFO - 'tests': ['dromaeo_dom']}, 08:53:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:53:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:53:34 INFO - 'tests': ['basic_compositor_video']}, 08:53:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:53:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:53:34 INFO - 'tests': ['a11yr', 08:53:34 INFO - 'ts_paint', 08:53:34 INFO - 'tpaint', 08:53:34 INFO - 'sessionrestore', 08:53:34 INFO - 'sessionrestore_no_auto_restore', 08:53:34 INFO - 'tabpaint']}, 08:53:34 INFO - 'other-e10s': {'tests': ['a11yr', 08:53:34 INFO - 'ts_paint', 08:53:34 INFO - 'tpaint', 08:53:34 INFO - 'sessionrestore', 08:53:34 INFO - 'sessionrestore_no_auto_restore', 08:53:34 INFO - 'tabpaint']}, 08:53:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:53:34 INFO - 'ts_paint', 08:53:34 INFO - 'tpaint', 08:53:34 INFO - 'sessionrestore', 08:53:34 INFO - 'sessionrestore_no_auto_restore', 08:53:34 INFO - 'tabpaint']}, 08:53:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:53:34 INFO - 'ts_paint', 08:53:34 INFO - 'tpaint', 08:53:34 INFO - 'sessionrestore', 08:53:34 INFO - 'sessionrestore_no_auto_restore', 08:53:34 INFO - 'tabpaint']}, 08:53:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:53:34 INFO - 'tests': ['a11yr', 08:53:34 INFO - 'ts_paint', 08:53:34 INFO - 'tpaint', 08:53:34 INFO - 'sessionrestore', 08:53:34 INFO - 'sessionrestore_no_auto_restore', 08:53:34 INFO - 'tabpaint']}, 08:53:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:53:34 INFO - 'tests': ['a11yr', 08:53:34 INFO - 'ts_paint', 08:53:34 INFO - 'tpaint', 08:53:34 INFO - 'sessionrestore', 08:53:34 INFO - 'sessionrestore_no_auto_restore', 08:53:34 INFO - 'tabpaint']}, 08:53:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:53:34 INFO - 'tests': ['tsvgx', 08:53:34 INFO - 'tsvgr_opacity', 08:53:34 INFO - 'tart', 08:53:34 INFO - 'tscrollx', 08:53:34 INFO - 'cart']}, 08:53:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:53:34 INFO - 'tsvgr_opacity', 08:53:34 INFO - 'tart', 08:53:34 INFO - 'tscrollx', 08:53:34 INFO - 'cart']}, 08:53:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:34 INFO - 'talos_options': ['--disable-e10s'], 08:53:34 INFO - 'tests': ['tp5o']}, 08:53:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:34 INFO - 'tests': ['tp5o']}, 08:53:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:34 INFO - 'talos_options': ['--disable-e10s', 08:53:34 INFO - '--xperf_path', 08:53:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:53:34 INFO - 'tests': ['tp5n']}, 08:53:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:34 INFO - 'talos_options': ['--xperf_path', 08:53:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:53:34 INFO - 'tests': ['tp5n']}}, 08:53:34 INFO - 'talos.zip': {'path': '', 08:53:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:53:34 INFO - Running post-action listener: _resource_record_post_action 08:53:34 INFO - [mozharness: 2016-08-22 15:53:34.267812Z] Finished populate-webroot step (success) 08:53:34 INFO - [mozharness: 2016-08-22 15:53:34.267924Z] Running create-virtualenv step. 08:53:34 INFO - Running pre-action listener: _resource_record_pre_action 08:53:34 INFO - Running main action method: create_virtualenv 08:53:34 INFO - Creating virtualenv /builds/slave/test/build/venv 08:53:34 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 08:53:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:53:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:53:34 INFO - Using real prefix '/tools/python27' 08:53:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:53:35 INFO - Installing distribute.............................................................................................................................................................................................done. 08:53:37 INFO - Installing pip.................done. 08:53:38 INFO - Return code: 0 08:53:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:53:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:38 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 0x10b64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb468cc5d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b2d30>, '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 0x10b317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5aab20>, '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.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 08:53:38 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 08:53:38 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 08:53:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dbKDcSI5Sq/Render', 08:53:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:38 INFO - 'HOME': '/Users/cltbld', 08:53:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:38 INFO - 'LOGNAME': 'cltbld', 08:53:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:38 INFO - 'MOZ_NO_REMOTE': '1', 08:53:38 INFO - 'NO_EM_RESTART': '1', 08:53:38 INFO - 'PAGER': '/bin/cat', 08:53:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:38 INFO - 'PWD': '/builds/slave/test', 08:53:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:38 INFO - 'SHELL': '/bin/bash', 08:53:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sKWcJvmCoJ/Listeners', 08:53:38 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 08:53:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:38 INFO - 'USER': 'cltbld', 08:53:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:38 INFO - 'XPC_FLAGS': '0x0', 08:53:38 INFO - 'XPC_SERVICE_NAME': '0', 08:53:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:38 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 08:53:38 INFO - Cleaning up... 08:53:38 INFO - Return code: 0 08:53:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:53:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:38 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 0x10b64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb468cc5d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b2d30>, '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 0x10b317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5aab20>, '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.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 08:53:38 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 08:53:38 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 08:53:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dbKDcSI5Sq/Render', 08:53:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:38 INFO - 'HOME': '/Users/cltbld', 08:53:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:38 INFO - 'LOGNAME': 'cltbld', 08:53:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:38 INFO - 'MOZ_NO_REMOTE': '1', 08:53:38 INFO - 'NO_EM_RESTART': '1', 08:53:38 INFO - 'PAGER': '/bin/cat', 08:53:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:38 INFO - 'PWD': '/builds/slave/test', 08:53:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:38 INFO - 'SHELL': '/bin/bash', 08:53:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sKWcJvmCoJ/Listeners', 08:53:38 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 08:53:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:38 INFO - 'USER': 'cltbld', 08:53:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:38 INFO - 'XPC_FLAGS': '0x0', 08:53:38 INFO - 'XPC_SERVICE_NAME': '0', 08:53:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:38 INFO - Downloading/unpacking psutil>=3.1.1 08:53:38 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 08:53:38 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 08:53:38 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 08:53:38 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 08:53:38 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 08:53:38 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 08:53:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:53:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:41 INFO - Installing collected packages: psutil 08:53:41 INFO - Running setup.py install for psutil 08:53:41 INFO - building 'psutil._psutil_osx' extension 08:53:41 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 08:53:42 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 08:53:42 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 08:53:42 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 08:53:42 INFO - building 'psutil._psutil_posix' extension 08:53:42 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 08:53:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:53:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:53:42 INFO - ^ 08:53:42 INFO - 1 warning generated. 08:53:42 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 08:53:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:42 INFO - Successfully installed psutil 08:53:42 INFO - Cleaning up... 08:53:42 INFO - Return code: 0 08:53:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:53:42 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')]} 08:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:42 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')]} 08:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:42 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 0x10b64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb468cc5d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b2d30>, '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 0x10b317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5aab20>, '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.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 08:53:42 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 08:53:42 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 08:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dbKDcSI5Sq/Render', 08:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:42 INFO - 'HOME': '/Users/cltbld', 08:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:42 INFO - 'LOGNAME': 'cltbld', 08:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:42 INFO - 'MOZ_NO_REMOTE': '1', 08:53:42 INFO - 'NO_EM_RESTART': '1', 08:53:42 INFO - 'PAGER': '/bin/cat', 08:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:42 INFO - 'PWD': '/builds/slave/test', 08:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:42 INFO - 'SHELL': '/bin/bash', 08:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sKWcJvmCoJ/Listeners', 08:53:42 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 08:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:42 INFO - 'USER': 'cltbld', 08:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:42 INFO - 'XPC_FLAGS': '0x0', 08:53:42 INFO - 'XPC_SERVICE_NAME': '0', 08:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:53:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:53:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:53:45 INFO - Installing collected packages: mozsystemmonitor 08:53:45 INFO - Running setup.py install for mozsystemmonitor 08:53:45 INFO - Successfully installed mozsystemmonitor 08:53:45 INFO - Cleaning up... 08:53:45 INFO - Return code: 0 08:53:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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')]} 08:53:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb468cc5d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b2d30>, '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 0x10b317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5aab20>, '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.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 08:53:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:53:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:53:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dbKDcSI5Sq/Render', 08:53:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:45 INFO - 'HOME': '/Users/cltbld', 08:53:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:45 INFO - 'LOGNAME': 'cltbld', 08:53:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:45 INFO - 'MOZ_NO_REMOTE': '1', 08:53:45 INFO - 'NO_EM_RESTART': '1', 08:53:45 INFO - 'PAGER': '/bin/cat', 08:53:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:45 INFO - 'PWD': '/builds/slave/test', 08:53:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:45 INFO - 'SHELL': '/bin/bash', 08:53:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sKWcJvmCoJ/Listeners', 08:53:45 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 08:53:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:45 INFO - 'USER': 'cltbld', 08:53:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:45 INFO - 'XPC_FLAGS': '0x0', 08:53:45 INFO - 'XPC_SERVICE_NAME': '0', 08:53:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:46 INFO - Downloading/unpacking jsonschema==2.5.1 08:53:46 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 08:53:46 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 08:53:46 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 08:53:46 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 08:53:46 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 08:53:46 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 08:53:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:53:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:53:48 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 08:53:48 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 08:53:48 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 08:53:48 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 08:53:48 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 08:53:48 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 08:53:48 INFO - Downloading functools32-3.2.3-2.tar.gz 08:53:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:53:49 INFO - warning: no files found matching '*.txt' 08:53:49 INFO - no previously-included directories found matching 'build' 08:53:49 INFO - no previously-included directories found matching 'dist' 08:53:49 INFO - no previously-included directories found matching '.git*' 08:53:49 INFO - Installing collected packages: jsonschema, functools32 08:53:49 INFO - Running setup.py install for functools32 08:53:49 INFO - warning: no files found matching '*.txt' 08:53:49 INFO - no previously-included directories found matching 'build' 08:53:49 INFO - no previously-included directories found matching 'dist' 08:53:49 INFO - no previously-included directories found matching '.git*' 08:53:49 INFO - Successfully installed jsonschema functools32 08:53:49 INFO - Cleaning up... 08:53:49 INFO - Return code: 0 08:53:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:53:49 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')]} 08:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:49 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')]} 08:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:49 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 0x10b64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb468cc5d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b2d30>, '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 0x10b317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5aab20>, '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.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 08:53:49 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 08:53:49 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 08:53:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dbKDcSI5Sq/Render', 08:53:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:49 INFO - 'HOME': '/Users/cltbld', 08:53:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:49 INFO - 'LOGNAME': 'cltbld', 08:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:49 INFO - 'MOZ_NO_REMOTE': '1', 08:53:49 INFO - 'NO_EM_RESTART': '1', 08:53:49 INFO - 'PAGER': '/bin/cat', 08:53:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:49 INFO - 'PWD': '/builds/slave/test', 08:53:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:49 INFO - 'SHELL': '/bin/bash', 08:53:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sKWcJvmCoJ/Listeners', 08:53:49 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 08:53:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:49 INFO - 'USER': 'cltbld', 08:53:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:49 INFO - 'XPC_FLAGS': '0x0', 08:53:49 INFO - 'XPC_SERVICE_NAME': '0', 08:53:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:53:49 INFO - Cleaning up... 08:53:49 INFO - Return code: 0 08:53:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:53:49 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')]} 08:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:49 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')]} 08:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:49 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 0x10b64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb468cc5d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b2d30>, '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 0x10b317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5aab20>, '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.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 08:53:49 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 08:53:49 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 08:53:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dbKDcSI5Sq/Render', 08:53:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:49 INFO - 'HOME': '/Users/cltbld', 08:53:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:49 INFO - 'LOGNAME': 'cltbld', 08:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:49 INFO - 'MOZ_NO_REMOTE': '1', 08:53:49 INFO - 'NO_EM_RESTART': '1', 08:53:49 INFO - 'PAGER': '/bin/cat', 08:53:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:49 INFO - 'PWD': '/builds/slave/test', 08:53:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:49 INFO - 'SHELL': '/bin/bash', 08:53:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sKWcJvmCoJ/Listeners', 08:53:49 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 08:53:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:49 INFO - 'USER': 'cltbld', 08:53:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:49 INFO - 'XPC_FLAGS': '0x0', 08:53:49 INFO - 'XPC_SERVICE_NAME': '0', 08:53:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:49 INFO - Downloading/unpacking blobuploader==1.2.4 08:53:49 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 08:53:49 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 08:53:49 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 08:53:49 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 08:53:49 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 08:53:49 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 08:53:52 INFO - Downloading blobuploader-1.2.4.tar.gz 08:53:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:53:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:53:52 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 08:53:52 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 08:53:52 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 08:53:52 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 08:53:52 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 08:53:52 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 08:53:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:53:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:53:53 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 08:53:53 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 08:53:53 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 08:53:53 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 08:53:53 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 08:53:53 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 08:53:53 INFO - Downloading docopt-0.6.1.tar.gz 08:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:53:53 INFO - Installing collected packages: blobuploader, requests, docopt 08:53:53 INFO - Running setup.py install for blobuploader 08:53:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:53:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:53:53 INFO - Running setup.py install for requests 08:53:54 INFO - Running setup.py install for docopt 08:53:54 INFO - Successfully installed blobuploader requests docopt 08:53:54 INFO - Cleaning up... 08:53:54 INFO - Return code: 0 08:53:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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')]} 08:53:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:54 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 0x10b64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb468cc5d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b2d30>, '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 0x10b317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5aab20>, '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.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 08:53:54 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 08:53:54 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 08:53:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dbKDcSI5Sq/Render', 08:53:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:54 INFO - 'HOME': '/Users/cltbld', 08:53:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:54 INFO - 'LOGNAME': 'cltbld', 08:53:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:54 INFO - 'MOZ_NO_REMOTE': '1', 08:53:54 INFO - 'NO_EM_RESTART': '1', 08:53:54 INFO - 'PAGER': '/bin/cat', 08:53:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:54 INFO - 'PWD': '/builds/slave/test', 08:53:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:54 INFO - 'SHELL': '/bin/bash', 08:53:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sKWcJvmCoJ/Listeners', 08:53:54 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 08:53:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:54 INFO - 'USER': 'cltbld', 08:53:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:54 INFO - 'XPC_FLAGS': '0x0', 08:53:54 INFO - 'XPC_SERVICE_NAME': '0', 08:53:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:54 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-xBxo2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:54 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-6pEPDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:54 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-H6qTDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:55 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-p7Mswk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:55 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-eIxKPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:55 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-2oMs2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:55 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-LaVr0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:55 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-zgvwLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:55 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-GNQ_ZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:55 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-NlMGp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:55 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-JjRTLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:55 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-Edm8Zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:56 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-KkIBB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:56 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-YG0MYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:56 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-OhPqh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:56 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-BJpoSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:56 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-MkN2OD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:53:56 INFO - Running setup.py install for manifestparser 08:53:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:53:56 INFO - Running setup.py install for mozcrash 08:53:56 INFO - Running setup.py install for mozdebug 08:53:56 INFO - Running setup.py install for mozdevice 08:53:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:53:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:53:57 INFO - Running setup.py install for mozfile 08:53:57 INFO - Running setup.py install for mozhttpd 08:53:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:53:57 INFO - Running setup.py install for mozinfo 08:53:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:53:57 INFO - Running setup.py install for mozInstall 08:53:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:53:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:53:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:53:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:53:57 INFO - Running setup.py install for mozleak 08:53:57 INFO - Running setup.py install for mozlog 08:53:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Running setup.py install for moznetwork 08:53:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Running setup.py install for mozprocess 08:53:58 INFO - Running setup.py install for mozprofile 08:53:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Running setup.py install for mozrunner 08:53:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Running setup.py install for mozscreenshot 08:53:58 INFO - Running setup.py install for moztest 08:53:58 INFO - Running setup.py install for mozversion 08:53:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:53:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:53:59 INFO - Cleaning up... 08:53:59 INFO - Return code: 0 08:53:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:59 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')]} 08:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:59 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')]} 08:53:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:59 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 0x10b64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb468cc5d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b2d30>, '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 0x10b317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5aab20>, '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.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 08:53:59 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 08:53:59 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 08:53:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dbKDcSI5Sq/Render', 08:53:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:59 INFO - 'HOME': '/Users/cltbld', 08:53:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:59 INFO - 'LOGNAME': 'cltbld', 08:53:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:59 INFO - 'MOZ_NO_REMOTE': '1', 08:53:59 INFO - 'NO_EM_RESTART': '1', 08:53:59 INFO - 'PAGER': '/bin/cat', 08:53:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:59 INFO - 'PWD': '/builds/slave/test', 08:53:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:59 INFO - 'SHELL': '/bin/bash', 08:53:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sKWcJvmCoJ/Listeners', 08:53:59 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 08:53:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:59 INFO - 'USER': 'cltbld', 08:53:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:59 INFO - 'XPC_FLAGS': '0x0', 08:53:59 INFO - 'XPC_SERVICE_NAME': '0', 08:53:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:59 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-2EA4Sj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:59 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)) 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:59 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-tvAaCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:59 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)) 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:59 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-WV_oOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:59 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)) 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:59 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-zuvp5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:59 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)) 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:59 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-92illl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:59 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)) 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:59 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-B9d7Ij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:54:00 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-wVMcKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:54:00 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-3AJ03T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:54:00 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-v2g9zi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:54:00 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-t14oyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:54:00 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-UotamS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:54:00 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-wMaeaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:54:00 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-7s9EjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:54:00 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-wku8tt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:00 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-BzxcgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:54:00 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-w8dP0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:54:01 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)) 08:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:54:01 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-91TPYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:54:01 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 08:54:01 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 08:54:01 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 08:54:01 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 08:54:01 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 08:54:01 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 08:54:03 INFO - Downloading blessings-1.6.tar.gz 08:54:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:54:03 INFO - Installing collected packages: blessings 08:54:03 INFO - Running setup.py install for blessings 08:54:03 INFO - Successfully installed blessings 08:54:03 INFO - Cleaning up... 08:54:03 INFO - Return code: 0 08:54:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:54:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:04 INFO - Reading from file tmpfile_stdout 08:54:04 INFO - Current package versions: 08:54:04 INFO - blessings == 1.6 08:54:04 INFO - blobuploader == 1.2.4 08:54:04 INFO - docopt == 0.6.1 08:54:04 INFO - functools32 == 3.2.3-2 08:54:04 INFO - jsonschema == 2.5.1 08:54:04 INFO - manifestparser == 1.1 08:54:04 INFO - mozInstall == 1.12 08:54:04 INFO - mozcrash == 0.17 08:54:04 INFO - mozdebug == 0.1 08:54:04 INFO - mozdevice == 0.48 08:54:04 INFO - mozfile == 1.2 08:54:04 INFO - mozhttpd == 0.7 08:54:04 INFO - mozinfo == 0.9 08:54:04 INFO - mozleak == 0.1 08:54:04 INFO - mozlog == 3.3 08:54:04 INFO - moznetwork == 0.27 08:54:04 INFO - mozprocess == 0.23 08:54:04 INFO - mozprofile == 0.28 08:54:04 INFO - mozrunner == 6.12 08:54:04 INFO - mozscreenshot == 0.1 08:54:04 INFO - mozsystemmonitor == 0.3 08:54:04 INFO - moztest == 0.7 08:54:04 INFO - mozversion == 1.4 08:54:04 INFO - psutil == 3.1.1 08:54:04 INFO - requests == 1.2.3 08:54:04 INFO - wsgiref == 0.1.2 08:54:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54: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')]} 08:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54: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')]} 08:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:04 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 0x10b64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb468cc5d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b2d30>, '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 0x10b317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5aab20>, '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.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 08:54:04 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 08:54:04 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 08:54:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dbKDcSI5Sq/Render', 08:54:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:04 INFO - 'HOME': '/Users/cltbld', 08:54:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:04 INFO - 'LOGNAME': 'cltbld', 08:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:04 INFO - 'MOZ_NO_REMOTE': '1', 08:54:04 INFO - 'NO_EM_RESTART': '1', 08:54:04 INFO - 'PAGER': '/bin/cat', 08:54:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:04 INFO - 'PWD': '/builds/slave/test', 08:54:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:04 INFO - 'SHELL': '/bin/bash', 08:54:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sKWcJvmCoJ/Listeners', 08:54:04 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 08:54:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:04 INFO - 'USER': 'cltbld', 08:54:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:04 INFO - 'XPC_FLAGS': '0x0', 08:54:04 INFO - 'XPC_SERVICE_NAME': '0', 08:54:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:04 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)) 08:54:04 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)) 08:54:04 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)) 08:54:04 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)) 08:54:04 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)) 08:54:04 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)) 08:54:04 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)) 08:54:04 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)) 08:54:04 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)) 08:54:04 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)) 08:54:04 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)) 08:54:04 INFO - Cleaning up... 08:54:04 INFO - Return code: 0 08:54:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:54: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')]} 08:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54: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')]} 08:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:04 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 0x10b64ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b6e3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb468cc5d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5b2d30>, '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 0x10b317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5aab20>, '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.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 08:54:04 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 08:54:04 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 08:54:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dbKDcSI5Sq/Render', 08:54:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:04 INFO - 'HOME': '/Users/cltbld', 08:54:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:04 INFO - 'LOGNAME': 'cltbld', 08:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:04 INFO - 'MOZ_NO_REMOTE': '1', 08:54:04 INFO - 'NO_EM_RESTART': '1', 08:54:04 INFO - 'PAGER': '/bin/cat', 08:54:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:04 INFO - 'PWD': '/builds/slave/test', 08:54:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:04 INFO - 'SHELL': '/bin/bash', 08:54:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sKWcJvmCoJ/Listeners', 08:54:04 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 08:54:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:04 INFO - 'USER': 'cltbld', 08:54:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:04 INFO - 'XPC_FLAGS': '0x0', 08:54:04 INFO - 'XPC_SERVICE_NAME': '0', 08:54:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:54:04 INFO - Cleaning up... 08:54:04 INFO - Return code: 0 08:54:04 INFO - Running post-action listener: _resource_record_post_action 08:54:04 INFO - Running post-action listener: _start_resource_monitoring 08:54:04 INFO - Starting resource monitoring. 08:54:04 INFO - [mozharness: 2016-08-22 15:54:04.811353Z] Finished create-virtualenv step (success) 08:54:04 INFO - [mozharness: 2016-08-22 15:54:04.812382Z] Running install step. 08:54:04 INFO - Running pre-action listener: _resource_record_pre_action 08:54:04 INFO - Running main action method: install 08:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:05 INFO - Reading from file tmpfile_stdout 08:54:05 INFO - Detecting whether we're running mozinstall >=1.0... 08:54:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:54:05 INFO - Reading from file tmpfile_stdout 08:54:05 INFO - Output received: 08:54:05 INFO - Usage: mozinstall [options] installer 08:54:05 INFO - Options: 08:54:05 INFO - -h, --help show this help message and exit 08:54:05 INFO - -d DEST, --destination=DEST 08:54:05 INFO - Directory to install application into. [default: 08:54:05 INFO - "/builds/slave/test"] 08:54:05 INFO - --app=APP Application being installed. [default: firefox] 08:54:05 INFO - mkdir: /builds/slave/test/build/application 08:54:05 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'] 08:54:05 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 08:54:39 INFO - Reading from file tmpfile_stdout 08:54:39 INFO - Output received: 08:54:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:54:39 INFO - Running post-action listener: _resource_record_post_action 08:54:39 INFO - [mozharness: 2016-08-22 15:54:39.310275Z] Finished install step (success) 08:54:39 INFO - [mozharness: 2016-08-22 15:54:39.310390Z] Running run-tests step. 08:54:39 INFO - Running pre-action listener: _resource_record_pre_action 08:54:39 INFO - Running main action method: run_tests 08:54:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:54:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:54:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:54:39 INFO - 2016-08-22 08:54:39.322 screenresolution[1655:7661] starting screenresolution argv=screenresolution get 08:54:39 INFO - 2016-08-22 08:54:39.325 screenresolution[1655:7661] Display 0: 1600x1200x32@60 08:54:39 INFO - 2016-08-22 08:54:39.329 screenresolution[1656:7663] starting screenresolution argv=screenresolution list 08:54:39 INFO - Available Modes on Display 0 08:54:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:54:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:54:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:54:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:54:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:54:39 INFO - Intel Iris: 08:54:39 INFO - Chipset Model: Intel Iris 08:54:39 INFO - Type: GPU 08:54:39 INFO - Bus: Built-In 08:54:39 INFO - VRAM (Dynamic, Max): 1536 MB 08:54:39 INFO - Vendor: Intel (0x8086) 08:54:39 INFO - Device ID: 0x0a2e 08:54:39 INFO - Revision ID: 0x0009 08:54:39 INFO - Displays: 08:54:39 INFO - Display: 08:54:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:54:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:54:39 INFO - Display Serial Number: 1600x1200 60 08:54:39 INFO - Main Display: Yes 08:54:39 INFO - Mirror: Off 08:54:39 INFO - Online: Yes 08:54:39 INFO - Rotation: Supported 08:54:39 INFO - Return code: 0 08:54:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:54:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:54:39 INFO - Python 2.7.3 08:54:39 INFO - Return code: 0 08:54:39 INFO - grabbing minidump binary from tooltool 08:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:39 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 0x10b5b2d30>, '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 0x10b317db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5aab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:54:39 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 08:54:39 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 08:54:39 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 08:54:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:54:39 INFO - Return code: 0 08:54:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:54:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:54:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:54:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:54:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:54:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0239', '--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 08:54:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0239 --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 08:54:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dbKDcSI5Sq/Render', 08:54:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:39 INFO - 'HOME': '/Users/cltbld', 08:54:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:39 INFO - 'LOGNAME': 'cltbld', 08:54:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:54:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:54:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:39 INFO - 'MOZ_NO_REMOTE': '1', 08:54:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:54:39 INFO - 'NO_EM_RESTART': '1', 08:54:39 INFO - 'PAGER': '/bin/cat', 08:54:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:39 INFO - 'PWD': '/builds/slave/test', 08:54:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:54:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:39 INFO - 'SHELL': '/bin/bash', 08:54:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sKWcJvmCoJ/Listeners', 08:54:39 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 08:54:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:39 INFO - 'USER': 'cltbld', 08:54:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:39 INFO - 'XPC_FLAGS': '0x0', 08:54:39 INFO - 'XPC_SERVICE_NAME': '0', 08:54:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0239', '--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 08:54:39 INFO - mozversion application_buildid: 20160822063914 08:54:39 INFO - mozversion application_changeset: 5f3d66a9ea5ddcf59e0b83a81579773aa7d68ae4 08:54:39 INFO - mozversion application_display_name: Nightly 08:54:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:54:39 INFO - mozversion application_name: Firefox 08:54:39 INFO - mozversion application_remotingname: firefox 08:54:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:54:39 INFO - mozversion application_vendor: Mozilla 08:54:39 INFO - mozversion application_version: 51.0a1 08:54:39 INFO - mozversion platform_buildid: 20160822063914 08:54:39 INFO - mozversion platform_changeset: 5f3d66a9ea5ddcf59e0b83a81579773aa7d68ae4 08:54:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:54:39 INFO - mozversion platform_version: 51.0a1 08:54:39 INFO - using testdate: 1471881279 08:54:39 INFO - actual date: 1471881279 08:54:39 INFO - starting webserver on 'localhost:49268' 08:54:39 INFO - SUITE-START | Running 2 tests 08:54:39 INFO - TEST-START | dromaeo_css 08:54:39 INFO - operating with platform_type : mac_ 08:54:39 INFO - Initialising browser for dromaeo_css test... 08:54:39 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmp06Wnaa/profile http://localhost:49268/getInfo.html) 08:54:42 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 08:54:42 INFO - PROCESS | 1666 | colorDepth:24 08:54:42 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 08:54:42 INFO - PROCESS | 1666 | __metrics 08:54:42 INFO - PROCESS | 1666 | 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" 08:54:42 INFO - PROCESS | 1666 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:54:43 INFO - TEST-INFO | 1666: exit 0 08:54:43 INFO - Browser initialized. 08:54:43 INFO - Running cycle 1/1 for dromaeo_css test... 08:54:43 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmp06Wnaa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:54:55 INFO - PROCESS | 1669 | RSS: Main: 228524032 08:54:55 INFO - PROCESS | 1669 | 08:55:46 INFO - PROCESS | 1669 | 1471881346143 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] 08:57:58 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 08:57:59 INFO - PROCESS | 1669 | RSS: Main: 334921728 08:57:59 INFO - PROCESS | 1669 | 09:01:02 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 09:01:03 INFO - PROCESS | 1669 | RSS: Main: 370655232 09:01:03 INFO - PROCESS | 1669 | 09:04:05 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 09:04:06 INFO - PROCESS | 1669 | RSS: Main: 337268736 09:04:06 INFO - PROCESS | 1669 | 09:07:08 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 09:07:09 INFO - PROCESS | 1669 | RSS: Main: 334077952 09:07:09 INFO - PROCESS | 1669 | 09:10:12 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 09:10:13 INFO - PROCESS | 1669 | RSS: Main: 331063296 09:10:13 INFO - PROCESS | 1669 | 09:13:16 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 09:13:17 INFO - PROCESS | 1669 | RSS: Main: 535302144 09:13:17 INFO - PROCESS | 1669 | 09:13:17 INFO - PROCESS | 1669 | __start_tp_report 09:13:17 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 09:13:17 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 09:13:17 INFO - PROCESS | 1669 | |i|pagename|runs| 09:13:17 INFO - PROCESS | 1669 | |0;dojo.html;3333.30000699853;3377.3414184234075;3397.847096880643;3398.91502712432;3417.248205394814;5659.037963546197;5702.857428564283;5720.427957204278;5776.942230577698;5778.191053252543;5712.200291959119;5783.681897495636;5819.214406055181;5865.76536938522;5904.143899134623;5201.817936372227;5231.555317797992;5243.108671525831;5262.605304602151;5268.262443257947;5332.386775520807;5366.4096949335735;5373.435789242132;5377.489138531833;5382.650127741704;5357.357117145929;5360.0083984463;5380.166074258496;5397.46025397461;5406.459354064582;5094.1849304111165;5158.92261616076;5174.4825517448335;5230.137027390476;5269.894602107936;5176.352955880515;5192.844214673528;5216.478352164791;5222.686638801684;5230.764615592308;5796.391378905197;5826.038703613898;5845.532357411397;5848.093545500455;5855.677937713424;6105.145279660851;6201.472874805647;6254.87490250197;6270.278917924452;6286.937130628727;5773.480386765169;6296;6298.622082675054;6305.842353941142;6319.115323854664;5735.254416925773;5901.144334071536;5930.733117009745;5965.73154208053;5969.462748352669;6205.100260462298;6207.906881396782;6247;6258.561900666909;6345.555811093272;6190.81427557174;6200.627962322275;6237.625742455467;6257.405546473078;6288.245410550763;6306.369363063657;6329.651869147241;6335.208098987627;6347.016212487072;6353.459955903708;5102.9489705103215;5539.808941077607;5873.706314684248;6093.055576385667;6176.722047507873;6251.874962500725;6295.716692748837;6322.9051564226575;6326.177596912464;6343;5048.217526283432;5051.1413059779925;5058.519440653132;5062.215356619729;5080.720560369141;4396;4474.910501790011;4476.865694029184;4488.416505854283;4588.242939914877;5889.5582831286965;5890.440408161217;5891.7054147292465;5892.292924849045;5908.4091590841435;6210.347913469111;6243.96878015607;6252.781152659635;6274.121622972787;6274.623522588659;5623.297087864017;5749.942500574941;5786.024019343361;5790.449907258803;5795.536357091422;5496.285482887279;5501.669899806025;5504.366997795305;5517.806876759294;5529.419410961871;6358.650274234961;6413.839654008687;6427.903581446376;6434.292227854932;6445.4199122079235;6121.173641558511;6215.96892015537;6241.875162496815;6270.153529273673;6280.905786413116;6339.683015849281;6353.142325786144;6354.1104245406605;6355.459785918064;6370.203724534434;6211.440970312513;6248.656323902229;6282.183316169052;6304.369563043842;6318.842028949313;6154.630722156685;6209.2548894133;6239.78160764371;6275.403836635513;6285.308616052139;6198.194234749454;6229.190205273286;6241.375862413723;6257.624542527463;6265.091561723616;6012.6392416455155;6235.719392627252;6258.061290806233;6262.93530099875;6265.718042688181;5799.304083509837;6013.609115407485;6054.9091763624465;6089;6109.725062372293;6249.906251406142;6270.811875643738;6271.184745982993;6275.34108918557;6286;5226.790928362823;6098.298695650057;6099.969500152471;6122.7244773984385;6147.84630384244 09:13:17 INFO - PROCESS | 1669 | |1;ext.html;472.8132387706856;491.6189952786592;511.30462570903563;520.2274622186053;531.195239212593;30617.846910765442;40138.1972360553;41593;41606.37590436142;43946.68159955199;42671.78664106683;43004.784976075156;43014.35478467817;43030.35454468177;43113.568864311346;8795.648174073029;9785.309040013983;9972.850407243897;9972.950135249313;9978.600855965753;8742.825143497126;8940.776480587972;8952.462852228888;8959.552022398886;11886.167968242227;11821.527138914476;12143.514259429612;12188.634340969786;12204.572839950604;12254.326012069332;12101;12167.756644867142;12192.939035304767;12200.755984880298;12230.021598272117;10044;12090.697732556668;12095.455704493319;12095.93952030243;12106.334151621657;40303;40707.98230044243;40744.38883416751;40761;40794;3359.5800524934384;3407.1993081625797;3550.14796448853;3604.6936010439144;3637.418013117888;31109.533356999662;31485.84257078723;32387;32621.51067733986;32793.6720632793;2876.3384421583155;3209.849510311327;3212.710856022946;3264.445044342444;3278.1804548862783;510466;512040;513077;513605;522943;59336.40663593309;59764;59899.70050149808;59977;60022;34349.656503435144;35347.46978795269;35387.82306088453;36758.816205918796;36781.44827827585;16344.10107444102;16494.587635308973;16505.6698866022;16508.42220522276;16514.917425412797;12551.623451296477;12633.93683031579;12642.683932901751;12649.241045537297;12688.429020694091;16437.424690135787;16775.664486710197;18789.060546972596;19223.885332949907;19388.321408750628;7859.764207073797;8069.233422824822;8146.551939643258;8150.307223886036;8189.385796065319;870.9695160669346;955.4076472586902;984.8473486609587;985.7683444390562;1031.3811712972156;29183.41633167325;30320.84839575788;30956.69043309583;30985.0704478866;31684.366312673617;929.9349045566745;989.9307048506679;1029.5161274201114;1096.4408151842458;1125.3360517294836;15524.767128493082;15531.767023494656;15553;15585.766213506806;15902.920485397499;519379.2093118606;670463;690512;696515;696923;40677.796611016754;48705;57044.714776426685;57965.42034579685;58136;1232.8150777383105;1390.402127085363;1404.8876089912783;1440.5030264558711;1444.8555144485676;30182.245443864078;31108.688913110578;31335.68664313328;31423;31971.36057278888;51822.222666659276;52792.73603631957;52824.47175528196;52835.20747188872;52885.7355713219;623180;625718;627189;627287;627472;22665;26813.865930671003;26852.731472685024;27208.591871121553;27504.724952751018;39910;47737.52262477331;48038.5196148034;48893;49292;27728.722712772615;38013;38425.6157438422;39892;40296.58961936318;38554.6144538551;38704.03239919025;39056.609433905294;39942.60057399505;40347.1930561393;38440.84677459681;38800.41799372955;39100.6089939097;39479.60520394759;39704.80147599243;31990.52014219742;32640;34644;35466;35513.1121721959;25628.74371256338;25712.357191070372;25894.223173304832;26097.608535872358;26380.868095660167 09:13:17 INFO - PROCESS | 1669 | |2;jquery.html;345.1870844161997;365.64897698209717;373.65810283590514;377.74313466842545;398.5715355992309;12833.358332083393;15347.539573812775;16955.74566381502;20192.293269735543;21543.24598639048;20515.897420512913;21004.894975525138;21651.350459486202;21676.132954681794;21860.45348866279;5133.973205358924;5898.1447690085015;6174.043023331391;6175.969120154393;6192.721327540249;6612.537122401433;6793;6823.897641535381;6843.178818541782;6843.281455447181;6504.414602685757;6602.570832895871;6654.168228971379;6673.532852700313;6794.796156115324;6395.776147834804;6627.337266273383;6646.700898459581;6663.866722665543;6697.19633643961;5783.88432231353;5816.8836623267725;5826.563007774392;5833.854153646151;5842.211301474289;25103;25837.35406614831;25963.74036259632;26085.73914260852;26471;2255.537614788964;2475.6781618389673;2502.8373155745;2534.936626584332;2993.760499160062;10354.618437759282;10566.366018038942;14314.92842535791;15011.549653510412;16101.436449724204;1565.3425561264296;1817.6637322095455;1864.0679660169915;1902.391234804863;1938.4669215965582;91155;107069;107981.20193960918;110453.89546104516;135229.3238533811;11214;11467.54129834814;11632.463339297681;11776.587819426279;11974.640760777016;14704.044237124554;14722.263886805616;14733.92633036828;14747.705045899236;14762.926185369004;6559.573627714279;8460.238578527838;8465.66137354499;8571.100034496412;8585.742427727178;8338.916610833814;8362.996440427187;8365.958170209231;8501.829963400822;8541.48751074925;3973.7814420206587;4403.691741578123;4536.412255710475;4549.681522293474;4555.06621142665;1856.4616261284345;1921.8366438852854;1922.375228050889;1926.6820974539044;1949.2885096939515;793.5714714054346;861.1947828779986;885.1458342699326;998.8551660009404;1001.2540657210425;20108.39674809758;22213.55572888533;22271.888640556692;22353.329400118022;22410.77589224087;908.5638893331238;917.8531434970506;951.8810148731409;975.2831668723483;986.9587585097647;11194.608188713355;11363.545458181745;11473.451084103539;11657.475413606408;11731.296122232694;606441;607529.9623501854;611106;630782;631837;78066;78212.6089369561;93130.53434732919;107590;143738;971.723058928202;979.9265055120894;1007.2495990486988;1040.9791804163874;1045.4302405189037;19355.032248387244;20181.054540562847;20191;20248.96265560178;20308.59382812306;52246.21630675467;54337.45662543324;57107.71446142909;57535;58183.12725309039;75208;78493;83338.58330708307;86048;86657;7134.8929766052515;7768.665522951947;7945.8410831784195;8253.752387428387;8426.620802063799;12892.935535322638;13212.207267563592;13287.734245315232;13716.451341946211;13739.587812365646;11422.600208992646;11440.542378304775;11546.711332216762;11669;11823.054155667698;10945.793871311433;11133.610323638633;11405.030572401107;11685.707857303636;11813.586524471602;9900.702978910644;11257.662270131908;11450.599229026944;11553.884461155281;11952.446181996285;7023.47323950716;7358.190599034209;7405.851882962203;8135.430519863553;8412.369072319723;10976.835347469954;11694.590689325832;11702.063834893017;11908.880911191123;12004.579839705568 09:13:17 INFO - PROCESS | 1669 | |3;mootools.html;1381.5026590427444;1508.630385555539;1651.4797838680815;1785.0717626834055;1804.702224133018;8367.623456944437;8892.510911899852;8930.053082482782;8934.195922366995;8956.238719708832;7820.92179078209;8047.396445266614;8434.704785332515;8719.520426376546;8756.91243087569;2341.1688850458054;2370.3718514593647;2372.6796882420913;2373.4066483379156;2373.4541055557233;2346.378209774411;2370.727366352866;2399.9520009599796;2401.591729406005;2407.121400688745;1873.3068764557133;2052.692096185569;2081.2403472732494;2237.038073628339;2245.640697488402;2319.512902290521;2350.929472115831;2355.6348765941307;2356.8939397727145;2363.5509253241917;2341.8946147423235;2353.8587684738973;2363.196513185525;2365.6096744037213;2370.0756704885107;8802.779930501789;8871.423357481743;8922.241609463203;8953.149450802164;8957.283417326664;5251.579873610102;5435.0488664483555;5436.21174929633;5441.809536666237;5448.83653490396;3498.2828520153307;3579.4451859961746;3709.9072523186865;3714.3499887519574;3716.684081853046;954.7326748510359;1066.173715370594;1077.805994920914;1089.8256278995402;1123.1127409346607;11640.708982275512;11743.941280293544;11768.94115529434;11781.352025638587;11795.351255680935;4547.15877562652;4602.102589994919;4800.231962885921;4801.279808028754;4843.539863712942;9992.600295988079;10000.04999525044;10001;10001.49992500372;10026.19790416765;4752.073345697556;5222.3472065991755;5383.43473935239;5392.299001129828;5396.08266594672;4422.380866678667;4604.125216208977;4773.689710168828;4885.6335774817035;4942.555170034714;3891.455196272524;3897.1815918656835;4001.47980762499;4035.0114222015795;4054.432379466877;1262.532862840755;1288.1305119044607;1292.1084451728277;1296.125115547002;1307.267929959226;719.4819729794644;774.7714424244862;869.4479005831342;881.8941726992801;944.2729098594044;5698.943010569924;5828.067509198508;5945.702714864239;6020.277566692025;6174.876502470015;734.3794493652855;907.2469850024596;922.423485321674;946.8059047895165;987.456898705709;3400.25194457219;4006;4116.650084742771;4211.494620645542;4240.257954857888;10631.362118272928;10700.78598428027;10811.891881081088;10836.620718274822;10837.403942783223;10355.482225888676;10632.255742098134;10866.348019118877;10883.18376121794;10894.509747061402;8086.838263234702;8113.432059755761;8232.711855085163;8346.207110324509;8362.414630975773;5193.935243275195;5981.700914954148;6045.244344456943;6122.112293717475;6132;9723.56243969009;9779.951100244738;10141.29010969225;10175.592976280866;10292.588296468057;9955.800883982134;10269.97300269967;10320.741981450525;10375.636852710119;10376.584936602452;6401.679916004275;6701.597904125768;7458.179600244077;7575.696972121274;7613.010308659839;8501.659933602834;8549.974003119665;8555.657773689232;8556.700515481929;8566.314694824523;7256.7097316106765;7451.105867295959;7507.849843003219;7630.8092297693;7682.347000505019;7255.56466612005;7379;7388.630568471663;7458.328750412272;7600.80997975055;6877.1747390313485;7009.369156775914;7013.894791578028;7014.824629384306;7028.367446929801;4908.263291341418;4989.750512474289;5019.949800501949;5045.268436076675;5065.746712664426;4905.092557876804;4983.700977941335;5041.722705251246;5097.108006098992;5183.792648294026 09:13:17 INFO - PROCESS | 1669 | |4;prototype.html;295.2456473709672;303.2207227425517;309.9426606077875;313.27476890997355;316.5868541553272;4538.931916021254;5225.51072944211;5294.682319060859;5297.708626025572;5320.388155362135;4560.612347950429;4926.3842019747535;5055.674389780696;5253.921191182126;5267.39424966129;3831.3295173344623;4048.4939382577177;4074.8403346226546;4075.266452038632;4090.018395585068;4051.858184963527;4065.857694980676;4067.1662309226535;4069.3895915612748;4095.75425474471;3828.9042773930596;3991.501062367204;4031.5766844481345;4087.979560102195;4093.0995181060266;3463.688268055862;3700.962990370089;3753.6246375362516;4110.77390743509;4133.016152246938;3558.9644103558894;3564.269324788438;3589.2821435712963;3591.7306202034692;3594.622564630702;4884.3161957326;5092.949070509321;5131.050755610187;5131.461196574381;5132.640715149941;4093.6315731584004;4659.836905708284;4697.577218050357;4723.716577005391;4724.102420540121;5539.529140023103;5641.294838145232;5646.887062258732;5669.744861481202;5702.179539708405;3933.3509970854775;3959.762414255125;3991.840326386941;3992.8802135935966;3998;4299.527052024275;4790.233562629997;5037.942032173248;5203.791848326063;5817.941820581825;3186.235303527183;3232.886848960275;3649.2701459708164;3862.1268684687902;3870.59358767331;3371.7976921384798;3761.9247615047548;3919.902427320355;3928.7053470989217;3934.9803250984132;4340.240457919915;4416.757078360721;4556.521565235669;4560.384348113029;4575.611073058827;4710.293455981561;5280;5516.282883225155;5555.916596263771;5651.378348381675;1295.9740805184033;1359.3611002828654;1364.208758919824;1374.271636032904;1388.8055672205899;807.6890397197121;874.9019979925154;891.3715830339725;909.5634095634133;916.6012784438791;3743.139078012018;4383.802728877208;4739.559127049572;5147.0735267651435;5176.508231718056;4695.765211739399;5033.84898453047;5150.819721309736;5294.92057619136;5450.209719590716;4319.920019995001;4887.564238397464;4918.729469879119;5049.040682270357;5158.406783219978;1957.1779852461716;2152.7524334701397;2396.6938018779806;2473.3198370447913;2577.5179271918346;3972.324704800191;4147.4815648044;4680.906381872412;4807.519248075235;4836;4381.852892082857;4435.290353543418;4721.763911804396;4733.77512569092;4794.2808578713475;3952.505936757905;4209.7895105245225;4579.129965306581;4667.906641867212;4819.168760835074;3920.0983773732773;4154.688398370195;4254.595813397722;4623.12160689468;4661.766911654471;4000.7999600020466;4590.036092420629;4608.769561521844;4649.372334734903;4660.720356778605;4448.9110217796115;4475.820967161277;4753.405824271966;5765.673895004089;5964.850878728066;3570.1431656402797;3695.667389934919;3830.98873091618;3902.9609703902597;3990.86031988879;4803.423589169182;4811.65273723358;5830.416958304136;5845.3570107289015;5879.265091863517;4088.6081642239624;4409.757463339547;4531.750753708446;4731.739754313546;5504.394516603251;5004.899902002013;5346.518813306821;5592.356878958972;5611.410801865909;5642.576806739429;5504.22940788298;5525.778968841201;5537.529310008534;5546.805861794818;5563.443655634404;5423.83728488146;5498.1752737089755;5546.029444847055;5547.5007249347755;5628;4476.440444944382;4532.002959348938;4544.182606783683;5457.290552228185;5521.889562208653 09:13:17 INFO - PROCESS | 1669 | |5;yui.html;732.4506620034973;756.3722110648163;759.5063208914205;778.1518145221708;820.5568992743919;15193.772093418595;16373.345066197338;16400.75398869018;16460.423885164022;16476.42332518362;14701.55895323142;14924.179170145637;14932.925335373307;14948.551543453686;14948.850511494882;660.0824853453703;680.0377465885766;680.928502507237;849.5921957460407;871.376965469689;684.5584597934329;688.5455599304435;753.7964749517627;831.966721331146;867.6182479708939;908.2552307108141;913.2465715784471;913.8035322405682;920.2086205862953;923.0262073512441;914.2046419614911;915.5605309451468;917.5320586500866;920.2408013388914;921.861720741894;520.5783315514445;681.5910453727773;683.6171743823475;684.6816230452813;686.3411125319699;14118.71762564743;14600.70798584033;14638.341274642667;14641;14654;14267.500637477639;14854.554363369116;14869.107853528714;14958.326875290639;14992.100473971597;11864.982613214019;11885.702857428547;11944.880551194463;12018.579349722806;12023.15837891348;3591.1560783215923;3591.5690117186105;3946.072672921861;3964.7224694271413;4023.7786921719294;119306;120376.99999999913;123057.76942230553;124367;124367.3781631095;11224.719382015352;11335.603253886075;11421.657350279338;11506.942465287788;11734.413279335999;23903;23971.161009364587;24000.27999159993;24016.919238634302;24026.759732402454;8004.134757306675;8117.918820811717;8169.101398846122;8184.2224988625985;8249.546274954935;4396.934045989313;4574.08518296342;4596.195665758546;4672.556107169813;4699.530046995274;3562.1272788167066;3687.870924517629;3691.446283057563;3697.6672099511175;3700.5929347771726;636.9745210191633;643.8422586466346;652.8139480248201;666.037575703114;666.1306994372294;566.5411017076182;570.4951118260286;574.4227051812901;625.8748250349948;640.0814830717935;9085.545722713838;9479.90520094804;9509;9554.378965367228;9732.12410883024;556.9860753481196;561.147056474157;624.6907780648608;646.3342756960338;693.272064332449;3685.8894233173046;4429.689921705514;4637.814487420533;4674.252119660906;4759.054772520997;101072;101346;109227;136239;164912;142551.92347805505;147495.2625236867;147946;150092;152022;75855.62072189603;104546;107868.46065769621;109071.90928090776;109544;7624.618769061635;9708.4466485362;9754.658586949423;9915.454649994032;9950.701478955643;48482.03035939332;52777.736111319195;59702.402975971425;61990;62629.686851567276;66896.33103669096;67370;82032;83029.16970830456;87529.54080869725;13315.06794524374;13389.397477113358;13440.663983400493;13790.241536715577;13957.232352220319;11746.76506469883;14665.841584158228;15208.543743687707;15318.770218446509;15371.84628153749;13686.931565342109;13692.863071369557;14068.507602233873;14310.713785724436;14322.785158222427;11001.889981100407;13720.108192967426;13872.653183670489;13986.44054237819;14169.858301416854;13410.530631427853;13706.93146534261;13743.587692369245;14007.159570425807;14139.575812725634;11394.439010758579;11395.259308144945;11502.53989840397;11525.366104864313;11564.710882227675;8008;9908.702738917844;10115.291929565154;10599.576016959543;11316.60391886313 09:13:17 INFO - PROCESS | 1669 | __end_tp_report 09:13:17 INFO - PROCESS | 1669 | __start_cc_report 09:13:17 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1206 09:13:17 INFO - PROCESS | 1669 | __end_cc_report 09:13:17 INFO - PROCESS | 1669 | __startTimestamp1471882397127__endTimestamp 09:13:17 INFO - PROCESS | 1669 | 09:13:17 INFO - PROCESS | 1669 | ------- Summary: start ------- 09:13:17 INFO - PROCESS | 1669 | Number of tests: 6 09:13:17 INFO - PROCESS | 1669 | 09:13:17 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5802.30 Median:6013.61 stddev:639.86 (10.6%) stddev-sans-first:611.81 09:13:17 INFO - PROCESS | 1669 | Values: 3333.3 3377.3 3397.8 3398.9 3417.2 5659.0 5702.9 5720.4 5776.9 5778.2 5712.2 5783.7 5819.2 5865.8 5904.1 5201.8 5231.6 5243.1 5262.6 5268.3 5332.4 5366.4 5373.4 5377.5 5382.7 5357.4 5360.0 5380.2 5397.5 5406.5 5094.2 5158.9 5174.5 5230.1 5269.9 5176.4 5192.8 5216.5 5222.7 5230.8 5796.4 5826.0 5845.5 5848.1 5855.7 6105.1 6201.5 6254.9 6270.3 6286.9 5773.5 6296.0 6298.6 6305.8 6319.1 5735.3 5901.1 5930.7 5965.7 5969.5 6205.1 6207.9 6247.0 6258.6 6345.6 6190.8 6200.6 6237.6 6257.4 6288.2 6306.4 6329.7 6335.2 6347.0 6353.5 5102.9 5539.8 5873.7 6093.1 6176.7 6251.9 6295.7 6322.9 6326.2 6343.0 5048.2 5051.1 5058.5 5062.2 5080.7 4396.0 4474.9 4476.9 4488.4 4588.2 5889.6 5890.4 5891.7 5892.3 5908.4 6210.3 6244.0 6252.8 6274.1 6274.6 5623.3 5749.9 5786.0 5790.4 5795.5 5496.3 5501.7 5504.4 5517.8 5529.4 6358.7 6413.8 6427.9 6434.3 6445.4 6121.2 6216.0 6241.9 6270.2 6280.9 6339.7 6353.1 6354.1 6355.5 6370.2 6211.4 6248.7 6282.2 6304.4 6318.8 6154.6 6209.3 6239.8 6275.4 6285.3 6198.2 6229.2 6241.4 6257.6 6265.1 6012.6 6235.7 6258.1 6262.9 6265.7 5799.3 6013.6 6054.9 6089.0 6109.7 6249.9 6270.8 6271.2 6275.3 6286.0 5226.8 6098.3 6100.0 6122.7 6147.8 09:13:17 INFO - PROCESS | 1669 | 09:13:17 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:72169.31 Median:36770.13 stddev:161347.31 (438.8%) stddev-sans-first:161710.13 09:13:17 INFO - PROCESS | 1669 | Values: 472.8 491.6 511.3 520.2 531.2 30617.8 40138.2 41593.0 41606.4 43946.7 42671.8 43004.8 43014.4 43030.4 43113.6 8795.6 9785.3 9972.9 9973.0 9978.6 8742.8 8940.8 8952.5 8959.6 11886.2 11821.5 12143.5 12188.6 12204.6 12254.3 12101.0 12167.8 12192.9 12200.8 12230.0 10044.0 12090.7 12095.5 12095.9 12106.3 40303.0 40708.0 40744.4 40761.0 40794.0 3359.6 3407.2 3550.1 3604.7 3637.4 31109.5 31485.8 32387.0 32621.5 32793.7 2876.3 3209.8 3212.7 3264.4 3278.2 510466.0 512040.0 513077.0 513605.0 522943.0 59336.4 59764.0 59899.7 59977.0 60022.0 34349.7 35347.5 35387.8 36758.8 36781.4 16344.1 16494.6 16505.7 16508.4 16514.9 12551.6 12633.9 12642.7 12649.2 12688.4 16437.4 16775.7 18789.1 19223.9 19388.3 7859.8 8069.2 8146.6 8150.3 8189.4 871.0 955.4 984.8 985.8 1031.4 29183.4 30320.8 30956.7 30985.1 31684.4 929.9 989.9 1029.5 1096.4 1125.3 15524.8 15531.8 15553.0 15585.8 15902.9 519379.2 670463.0 690512.0 696515.0 696923.0 40677.8 48705.0 57044.7 57965.4 58136.0 1232.8 1390.4 1404.9 1440.5 1444.9 30182.2 31108.7 31335.7 31423.0 31971.4 51822.2 52792.7 52824.5 52835.2 52885.7 623180.0 625718.0 627189.0 627287.0 627472.0 22665.0 26813.9 26852.7 27208.6 27504.7 39910.0 47737.5 48038.5 48893.0 49292.0 27728.7 38013.0 38425.6 39892.0 40296.6 38554.6 38704.0 39056.6 39942.6 40347.2 38440.8 38800.4 39100.6 39479.6 39704.8 31990.5 32640.0 34644.0 35466.0 35513.1 25628.7 25712.4 25894.2 26097.6 26380.9 09:13:17 INFO - PROCESS | 1669 | 09:13:17 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:35016.15 Median:26024.74 stddev:102265.29 (393.0%) stddev-sans-first:102519.03 09:13:17 INFO - PROCESS | 1669 | Values: 345.2 365.6 373.7 377.7 398.6 12833.4 15347.5 16955.7 20192.3 21543.2 20515.9 21004.9 21651.4 21676.1 21860.5 5134.0 5898.1 6174.0 6176.0 6192.7 6612.5 6793.0 6823.9 6843.2 6843.3 6504.4 6602.6 6654.2 6673.5 6794.8 6395.8 6627.3 6646.7 6663.9 6697.2 5783.9 5816.9 5826.6 5833.9 5842.2 25103.0 25837.4 25963.7 26085.7 26471.0 2255.5 2475.7 2502.8 2534.9 2993.8 10354.6 10566.4 14314.9 15011.5 16101.4 1565.3 1817.7 1864.1 1902.4 1938.5 91155.0 107069.0 107981.2 110453.9 135229.3 11214.0 11467.5 11632.5 11776.6 11974.6 14704.0 14722.3 14733.9 14747.7 14762.9 6559.6 8460.2 8465.7 8571.1 8585.7 8338.9 8363.0 8366.0 8501.8 8541.5 3973.8 4403.7 4536.4 4549.7 4555.1 1856.5 1921.8 1922.4 1926.7 1949.3 793.6 861.2 885.1 998.9 1001.3 20108.4 22213.6 22271.9 22353.3 22410.8 908.6 917.9 951.9 975.3 987.0 11194.6 11363.5 11473.5 11657.5 11731.3 606441.0 607530.0 611106.0 630782.0 631837.0 78066.0 78212.6 93130.5 107590.0 143738.0 971.7 979.9 1007.2 1041.0 1045.4 19355.0 20181.1 20191.0 20249.0 20308.6 52246.2 54337.5 57107.7 57535.0 58183.1 75208.0 78493.0 83338.6 86048.0 86657.0 7134.9 7768.7 7945.8 8253.8 8426.6 12892.9 13212.2 13287.7 13716.5 13739.6 11422.6 11440.5 11546.7 11669.0 11823.1 10945.8 11133.6 11405.0 11685.7 11813.6 9900.7 11257.7 11450.6 11553.9 11952.4 7023.5 7358.2 7405.9 8135.4 8412.4 10976.8 11694.6 11702.1 11908.9 12004.6 09:13:17 INFO - PROCESS | 1669 | 09:13:17 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5729.78 Median:4906.68 stddev:3227.96 (65.8%) stddev-sans-first:3220.47 09:13:17 INFO - PROCESS | 1669 | Values: 1381.5 1508.6 1651.5 1785.1 1804.7 8367.6 8892.5 8930.1 8934.2 8956.2 7820.9 8047.4 8434.7 8719.5 8756.9 2341.2 2370.4 2372.7 2373.4 2373.5 2346.4 2370.7 2400.0 2401.6 2407.1 1873.3 2052.7 2081.2 2237.0 2245.6 2319.5 2350.9 2355.6 2356.9 2363.6 2341.9 2353.9 2363.2 2365.6 2370.1 8802.8 8871.4 8922.2 8953.1 8957.3 5251.6 5435.0 5436.2 5441.8 5448.8 3498.3 3579.4 3709.9 3714.3 3716.7 954.7 1066.2 1077.8 1089.8 1123.1 11640.7 11743.9 11768.9 11781.4 11795.4 4547.2 4602.1 4800.2 4801.3 4843.5 9992.6 10000.0 10001.0 10001.5 10026.2 4752.1 5222.3 5383.4 5392.3 5396.1 4422.4 4604.1 4773.7 4885.6 4942.6 3891.5 3897.2 4001.5 4035.0 4054.4 1262.5 1288.1 1292.1 1296.1 1307.3 719.5 774.8 869.4 881.9 944.3 5698.9 5828.1 5945.7 6020.3 6174.9 734.4 907.2 922.4 946.8 987.5 3400.3 4006.0 4116.7 4211.5 4240.3 10631.4 10700.8 10811.9 10836.6 10837.4 10355.5 10632.3 10866.3 10883.2 10894.5 8086.8 8113.4 8232.7 8346.2 8362.4 5193.9 5981.7 6045.2 6122.1 6132.0 9723.6 9780.0 10141.3 10175.6 10292.6 9955.8 10270.0 10320.7 10375.6 10376.6 6401.7 6701.6 7458.2 7575.7 7613.0 8501.7 8550.0 8555.7 8556.7 8566.3 7256.7 7451.1 7507.8 7630.8 7682.3 7255.6 7379.0 7388.6 7458.3 7600.8 6877.2 7009.4 7013.9 7014.8 7028.4 4908.3 4989.8 5019.9 5045.3 5065.7 4905.1 4983.7 5041.7 5097.1 5183.8 09:13:17 INFO - PROCESS | 1669 | 09:13:17 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4260.34 Median:4584.58 stddev:1279.73 (27.9%) stddev-sans-first:1248.23 09:13:17 INFO - PROCESS | 1669 | Values: 295.2 303.2 309.9 313.3 316.6 4538.9 5225.5 5294.7 5297.7 5320.4 4560.6 4926.4 5055.7 5253.9 5267.4 3831.3 4048.5 4074.8 4075.3 4090.0 4051.9 4065.9 4067.2 4069.4 4095.8 3828.9 3991.5 4031.6 4088.0 4093.1 3463.7 3701.0 3753.6 4110.8 4133.0 3559.0 3564.3 3589.3 3591.7 3594.6 4884.3 5092.9 5131.1 5131.5 5132.6 4093.6 4659.8 4697.6 4723.7 4724.1 5539.5 5641.3 5646.9 5669.7 5702.2 3933.4 3959.8 3991.8 3992.9 3998.0 4299.5 4790.2 5037.9 5203.8 5817.9 3186.2 3232.9 3649.3 3862.1 3870.6 3371.8 3761.9 3919.9 3928.7 3935.0 4340.2 4416.8 4556.5 4560.4 4575.6 4710.3 5280.0 5516.3 5555.9 5651.4 1296.0 1359.4 1364.2 1374.3 1388.8 807.7 874.9 891.4 909.6 916.6 3743.1 4383.8 4739.6 5147.1 5176.5 4695.8 5033.8 5150.8 5294.9 5450.2 4319.9 4887.6 4918.7 5049.0 5158.4 1957.2 2152.8 2396.7 2473.3 2577.5 3972.3 4147.5 4680.9 4807.5 4836.0 4381.9 4435.3 4721.8 4733.8 4794.3 3952.5 4209.8 4579.1 4667.9 4819.2 3920.1 4154.7 4254.6 4623.1 4661.8 4000.8 4590.0 4608.8 4649.4 4660.7 4448.9 4475.8 4753.4 5765.7 5964.9 3570.1 3695.7 3831.0 3903.0 3990.9 4803.4 4811.7 5830.4 5845.4 5879.3 4088.6 4409.8 4531.8 4731.7 5504.4 5004.9 5346.5 5592.4 5611.4 5642.6 5504.2 5525.8 5537.5 5546.8 5563.4 5423.8 5498.2 5546.0 5547.5 5628.0 4476.4 4532.0 4544.2 5457.3 5521.9 09:13:17 INFO - PROCESS | 1669 | 09:13:17 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:24370.56 Median:3686.88 stddev:38912.33 (1055.4%) stddev-sans-first:38981.01 09:13:17 INFO - PROCESS | 1669 | Values: 732.5 756.4 759.5 778.2 820.6 15193.8 16373.3 16400.8 16460.4 16476.4 14701.6 14924.2 14932.9 14948.6 14948.9 660.1 680.0 680.9 849.6 871.4 684.6 688.5 753.8 832.0 867.6 908.3 913.2 913.8 920.2 923.0 914.2 915.6 917.5 920.2 921.9 520.6 681.6 683.6 684.7 686.3 14118.7 14600.7 14638.3 14641.0 14654.0 14267.5 14854.6 14869.1 14958.3 14992.1 11865.0 11885.7 11944.9 12018.6 12023.2 3591.2 3591.6 3946.1 3964.7 4023.8 119306.0 120377.0 123057.8 124367.0 124367.4 11224.7 11335.6 11421.7 11506.9 11734.4 23903.0 23971.2 24000.3 24016.9 24026.8 8004.1 8117.9 8169.1 8184.2 8249.5 4396.9 4574.1 4596.2 4672.6 4699.5 3562.1 3687.9 3691.4 3697.7 3700.6 637.0 643.8 652.8 666.0 666.1 566.5 570.5 574.4 625.9 640.1 9085.5 9479.9 9509.0 9554.4 9732.1 557.0 561.1 624.7 646.3 693.3 3685.9 4429.7 4637.8 4674.3 4759.1 101072.0 101346.0 109227.0 136239.0 164912.0 142551.9 147495.3 147946.0 150092.0 152022.0 75855.6 104546.0 107868.5 109071.9 109544.0 7624.6 9708.4 9754.7 9915.5 9950.7 48482.0 52777.7 59702.4 61990.0 62629.7 66896.3 67370.0 82032.0 83029.2 87529.5 13315.1 13389.4 13440.7 13790.2 13957.2 11746.8 14665.8 15208.5 15318.8 15371.8 13686.9 13692.9 14068.5 14310.7 14322.8 11001.9 13720.1 13872.7 13986.4 14169.9 13410.5 13706.9 13743.6 14007.2 14139.6 11394.4 11395.3 11502.5 11525.4 11564.7 8008.0 9908.7 10115.3 10599.6 11316.6 09:13:17 INFO - PROCESS | 1669 | -------- Summary: end -------- 09:13:17 INFO - PROCESS | 1669 | 09:13:17 INFO - TEST-INFO | 1669: exit 0 09:13:17 INFO - TEST-OK | dromaeo_css | took 1117841ms 09:13:17 INFO - TEST-START | kraken 09:13:17 INFO - operating with platform_type : mac_ 09:13:17 INFO - Initialising browser for kraken test... 09:13:17 INFO - TEST-INFO | started process 1786 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpIpx4SB/profile http://localhost:49268/getInfo.html) 09:13:20 INFO - PROCESS | 1786 | __metrics Screen width/height:1600/1200 09:13:20 INFO - PROCESS | 1786 | colorDepth:24 09:13:20 INFO - PROCESS | 1786 | Browser inner width/height: 1024/689 09:13:20 INFO - PROCESS | 1786 | __metrics 09:13:20 INFO - PROCESS | 1786 | 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" 09:13:20 INFO - PROCESS | 1786 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:13:20 INFO - TEST-INFO | 1786: exit 0 09:13:20 INFO - Browser initialized. 09:13:20 INFO - Running cycle 1/1 for kraken test... 09:13:20 INFO - TEST-INFO | started process 1787 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpIpx4SB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:32 INFO - PROCESS | 1787 | RSS: Main: 228675584 09:13:32 INFO - PROCESS | 1787 | 09:13:35 INFO - PROCESS | 1787 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 09:13:36 INFO - PROCESS | 1787 | RSS: Main: 293568512 09:13:36 INFO - PROCESS | 1787 | 09:13:39 INFO - PROCESS | 1787 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 09:13:39 INFO - PROCESS | 1787 | RSS: Main: 293212160 09:13:39 INFO - PROCESS | 1787 | 09:13:43 INFO - PROCESS | 1787 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 09:13:44 INFO - PROCESS | 1787 | RSS: Main: 380358656 09:13:44 INFO - PROCESS | 1787 | 09:13:46 INFO - PROCESS | 1787 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 09:13:46 INFO - PROCESS | 1787 | RSS: Main: 280162304 09:13:46 INFO - PROCESS | 1787 | 09:13:48 INFO - PROCESS | 1787 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:13:49 INFO - PROCESS | 1787 | RSS: Main: 283934720 09:13:49 INFO - PROCESS | 1787 | 09:13:52 INFO - PROCESS | 1787 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 09:13:52 INFO - PROCESS | 1787 | RSS: Main: 323796992 09:13:52 INFO - PROCESS | 1787 | 09:13:55 INFO - PROCESS | 1787 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 09:13:56 INFO - PROCESS | 1787 | RSS: Main: 325124096 09:13:56 INFO - PROCESS | 1787 | 09:13:59 INFO - PROCESS | 1787 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 09:13:59 INFO - PROCESS | 1787 | RSS: Main: 324038656 09:13:59 INFO - PROCESS | 1787 | 09:14:01 INFO - PROCESS | 1787 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:14:02 INFO - PROCESS | 1787 | RSS: Main: 301441024 09:14:02 INFO - PROCESS | 1787 | 09:14:04 INFO - PROCESS | 1787 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:14:04 INFO - PROCESS | 1787 | RSS: Main: 301027328 09:14:04 INFO - PROCESS | 1787 | 09:14:07 INFO - PROCESS | 1787 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:14:07 INFO - PROCESS | 1787 | RSS: Main: 305254400 09:14:07 INFO - PROCESS | 1787 | 09:14:10 INFO - PROCESS | 1787 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:14:11 INFO - PROCESS | 1787 | RSS: Main: 313397248 09:14:11 INFO - PROCESS | 1787 | 09:14:14 INFO - PROCESS | 1787 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:14:14 INFO - PROCESS | 1787 | RSS: Main: 316080128 09:14:14 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 09:14:16 INFO - PROCESS | 1787 | RSS: Main: 359718912 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | __start_tp_report 09:14:16 INFO - PROCESS | 1787 | _x_x_mozilla_page_load 09:14:16 INFO - PROCESS | 1787 | _x_x_mozilla_page_load_details 09:14:16 INFO - PROCESS | 1787 | |i|pagename|runs| 09:14:16 INFO - PROCESS | 1787 | |0;ai-astar;98;113;106;113;113;114;114;114;114;111 09:14:16 INFO - PROCESS | 1787 | |1;audio-beat-detection;142;132;135;170;127;167;156;109;158;107 09:14:16 INFO - PROCESS | 1787 | |2;audio-dft;187;182;183;190;184;185;181;260;181;185 09:14:16 INFO - PROCESS | 1787 | |3;audio-fft;77;73;78;150;70;58;57;91;99;73 09:14:16 INFO - PROCESS | 1787 | |4;audio-oscillator;84;90;131;72;70;71;114;71;69;114 09:14:16 INFO - PROCESS | 1787 | |5;imaging-gaussian-blur;89;85;89;86;89;85;89;87;74;126 09:14:16 INFO - PROCESS | 1787 | |6;imaging-darkroom;130;94;94;95;94;94;93;95;94;94 09:14:16 INFO - PROCESS | 1787 | |7;imaging-desaturate;131;136;86;83;84;84;85;84;85;87 09:14:16 INFO - PROCESS | 1787 | |8;json-parse-financial;56;55;54;54;53;55;55;54;55;54 09:14:16 INFO - PROCESS | 1787 | |9;json-stringify-tinderbox;67;65;79;110;110;108;69;68;68;69 09:14:16 INFO - PROCESS | 1787 | |10;stanford-crypto-aes;89;85;89;89;89;88;91;109;110;92 09:14:16 INFO - PROCESS | 1787 | |11;stanford-crypto-ccm;143;150;156;140;137;139;138;138;221;149 09:14:16 INFO - PROCESS | 1787 | |12;stanford-crypto-pbkdf2;172;172;173;175;172;171;172;185;171;173 09:14:16 INFO - PROCESS | 1787 | |13;stanford-crypto-sha256-iterative;68;68;69;69;67;68;71;69;68;68 09:14:16 INFO - PROCESS | 1787 | __end_tp_report 09:14:16 INFO - PROCESS | 1787 | __start_cc_report 09:14:16 INFO - PROCESS | 1787 | _x_x_mozilla_cycle_collect,2093 09:14:16 INFO - PROCESS | 1787 | __end_cc_report 09:14:16 INFO - PROCESS | 1787 | __startTimestamp1471882456931__endTimestamp 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | ------- Summary: start ------- 09:14:16 INFO - PROCESS | 1787 | Number of tests: 14 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | [#0] ai-astar Cycles:10 Average:111.00 Median:114.00 stddev:5.19 (4.5%) stddev-sans-first:2.60 09:14:16 INFO - PROCESS | 1787 | Values: 98.0 113.0 106.0 113.0 113.0 114.0 114.0 114.0 114.0 111.0 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:149.00 stddev:22.41 (15.0%) stddev-sans-first:23.76 09:14:16 INFO - PROCESS | 1787 | Values: 142.0 132.0 135.0 170.0 127.0 167.0 156.0 109.0 158.0 107.0 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | [#2] audio-dft Cycles:10 Average:191.80 Median:185.00 stddev:24.12 (13.0%) stddev-sans-first:25.52 09:14:16 INFO - PROCESS | 1787 | Values: 187.0 182.0 183.0 190.0 184.0 185.0 181.0 260.0 181.0 185.0 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:26.95 (35.9%) stddev-sans-first:28.51 09:14:16 INFO - PROCESS | 1787 | Values: 77.0 73.0 78.0 150.0 70.0 58.0 57.0 91.0 99.0 73.0 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | [#4] audio-oscillator Cycles:10 Average:88.60 Median:71.00 stddev:22.94 (32.3%) stddev-sans-first:24.27 09:14:16 INFO - PROCESS | 1787 | Values: 84.0 90.0 131.0 72.0 70.0 71.0 114.0 71.0 69.0 114.0 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:88.00 stddev:13.46 (15.3%) stddev-sans-first:14.27 09:14:16 INFO - PROCESS | 1787 | Values: 89.0 85.0 89.0 86.0 89.0 85.0 89.0 87.0 74.0 126.0 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | [#6] imaging-darkroom Cycles:10 Average:97.70 Median:94.00 stddev:11.36 (12.1%) stddev-sans-first:0.60 09:14:16 INFO - PROCESS | 1787 | Values: 130.0 94.0 94.0 95.0 94.0 94.0 93.0 95.0 94.0 94.0 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:84.50 stddev:20.62 (24.4%) stddev-sans-first:17.13 09:14:16 INFO - PROCESS | 1787 | Values: 131.0 136.0 86.0 83.0 84.0 84.0 85.0 84.0 85.0 87.0 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:0.85 (1.5%) stddev-sans-first:0.71 09:14:16 INFO - PROCESS | 1787 | Values: 56.0 55.0 54.0 54.0 53.0 55.0 55.0 54.0 55.0 54.0 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | [#9] json-stringify-tinderbox Cycles:10 Average:81.30 Median:68.00 stddev:19.70 (29.0%) stddev-sans-first:20.20 09:14:16 INFO - PROCESS | 1787 | Values: 67.0 65.0 79.0 110.0 110.0 108.0 69.0 68.0 68.0 69.0 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | [#10] stanford-crypto-aes Cycles:10 Average:93.10 Median:89.00 stddev:8.84 (9.9%) stddev-sans-first:9.25 09:14:16 INFO - PROCESS | 1787 | Values: 89.0 85.0 89.0 89.0 89.0 88.0 91.0 109.0 110.0 92.0 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | [#11] stanford-crypto-ccm Cycles:10 Average:151.10 Median:146.00 stddev:25.37 (17.4%) stddev-sans-first:26.74 09:14:16 INFO - PROCESS | 1787 | Values: 143.0 150.0 156.0 140.0 137.0 139.0 138.0 138.0 221.0 149.0 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:172.50 stddev:4.17 (2.4%) stddev-sans-first:4.38 09:14:16 INFO - PROCESS | 1787 | Values: 172.0 172.0 173.0 175.0 172.0 171.0 172.0 185.0 171.0 173.0 09:14:16 INFO - PROCESS | 1787 | 09:14:16 INFO - PROCESS | 1787 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.50 stddev:1.08 (1.6%) stddev-sans-first:1.13 09:14:16 INFO - PROCESS | 1787 | Values: 68.0 68.0 69.0 69.0 67.0 68.0 71.0 69.0 68.0 68.0 09:14:16 INFO - PROCESS | 1787 | -------- Summary: end -------- 09:14:16 INFO - PROCESS | 1787 | 09:14:17 INFO - TEST-INFO | 1787: exit 0 09:14:17 INFO - TEST-OK | kraken | took 60033ms 09:14:17 INFO - SUITE-END | took 1177s 09:14:18 INFO - Completed test suite (00:19:38) 09:14:18 INFO - javascript benchmark 09:14:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3333.30000699853, 3377.3414184234075, 3397.847096880643, 3398.91502712432, 3417.248205394814, 5659.037963546197, 5702.857428564283, 5720.427957204278, 5776.942230577698, 5778.191053252543, 5712.200291959119, 5783.681897495636, 5819.214406055181, 5865.76536938522, 5904.143899134623, 5201.817936372227, 5231.555317797992, 5243.108671525831, 5262.605304602151, 5268.262443257947, 5332.386775520807, 5366.4096949335735, 5373.435789242132, 5377.489138531833, 5382.650127741704, 5357.357117145929, 5360.0083984463, 5380.166074258496, 5397.46025397461, 5406.459354064582, 5094.1849304111165, 5158.92261616076, 5174.4825517448335, 5230.137027390476, 5269.894602107936, 5176.352955880515, 5192.844214673528, 5216.478352164791, 5222.686638801684, 5230.764615592308, 5796.391378905197, 5826.038703613898, 5845.532357411397, 5848.093545500455, 5855.677937713424, 6105.145279660851, 6201.472874805647, 6254.87490250197, 6270.278917924452, 6286.937130628727, 5773.480386765169, 6296.0, 6298.622082675054, 6305.842353941142, 6319.115323854664, 5735.254416925773, 5901.144334071536, 5930.733117009745, 5965.73154208053, 5969.462748352669, 6205.100260462298, 6207.906881396782, 6247.0, 6258.561900666909, 6345.555811093272, 6190.81427557174, 6200.627962322275, 6237.625742455467, 6257.405546473078, 6288.245410550763, 6306.369363063657, 6329.651869147241, 6335.208098987627, 6347.016212487072, 6353.459955903708, 5102.9489705103215, 5539.808941077607, 5873.706314684248, 6093.055576385667, 6176.722047507873, 6251.874962500725, 6295.716692748837, 6322.9051564226575, 6326.177596912464, 6343.0, 5048.217526283432, 5051.1413059779925, 5058.519440653132, 5062.215356619729, 5080.720560369141, 4396.0, 4474.910501790011, 4476.865694029184, 4488.416505854283, 4588.242939914877, 5889.5582831286965, 5890.440408161217, 5891.7054147292465, 5892.292924849045, 5908.4091590841435, 6210.347913469111, 6243.96878015607, 6252.781152659635, 6274.121622972787, 6274.623522588659, 5623.297087864017, 5749.942500574941, 5786.024019343361, 5790.449907258803, 5795.536357091422, 5496.285482887279, 5501.669899806025, 5504.366997795305, 5517.806876759294, 5529.419410961871, 6358.650274234961, 6413.839654008687, 6427.903581446376, 6434.292227854932, 6445.4199122079235, 6121.173641558511, 6215.96892015537, 6241.875162496815, 6270.153529273673, 6280.905786413116, 6339.683015849281, 6353.142325786144, 6354.1104245406605, 6355.459785918064, 6370.203724534434, 6211.440970312513, 6248.656323902229, 6282.183316169052, 6304.369563043842, 6318.842028949313, 6154.630722156685, 6209.2548894133, 6239.78160764371, 6275.403836635513, 6285.308616052139, 6198.194234749454, 6229.190205273286, 6241.375862413723, 6257.624542527463, 6265.091561723616, 6012.6392416455155, 6235.719392627252, 6258.061290806233, 6262.93530099875, 6265.718042688181, 5799.304083509837, 6013.609115407485, 6054.9091763624465, 6089.0, 6109.725062372293, 6249.906251406142, 6270.811875643738, 6271.184745982993, 6275.34108918557, 6286.0, 5226.790928362823, 6098.298695650057, 6099.969500152471, 6122.7244773984385, 6147.84630384244], "value": 5761.4136833042285, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.8132387706856, 491.6189952786592, 511.30462570903563, 520.2274622186053, 531.195239212593, 30617.846910765442, 40138.1972360553, 41593.0, 41606.37590436142, 43946.68159955199, 42671.78664106683, 43004.784976075156, 43014.35478467817, 43030.35454468177, 43113.568864311346, 8795.648174073029, 9785.309040013983, 9972.850407243897, 9972.950135249313, 9978.600855965753, 8742.825143497126, 8940.776480587972, 8952.462852228888, 8959.552022398886, 11886.167968242227, 11821.527138914476, 12143.514259429612, 12188.634340969786, 12204.572839950604, 12254.326012069332, 12101.0, 12167.756644867142, 12192.939035304767, 12200.755984880298, 12230.021598272117, 10044.0, 12090.697732556668, 12095.455704493319, 12095.93952030243, 12106.334151621657, 40303.0, 40707.98230044243, 40744.38883416751, 40761.0, 40794.0, 3359.5800524934384, 3407.1993081625797, 3550.14796448853, 3604.6936010439144, 3637.418013117888, 31109.533356999662, 31485.84257078723, 32387.0, 32621.51067733986, 32793.6720632793, 2876.3384421583155, 3209.849510311327, 3212.710856022946, 3264.445044342444, 3278.1804548862783, 510466.0, 512040.0, 513077.0, 513605.0, 522943.0, 59336.40663593309, 59764.0, 59899.70050149808, 59977.0, 60022.0, 34349.656503435144, 35347.46978795269, 35387.82306088453, 36758.816205918796, 36781.44827827585, 16344.10107444102, 16494.587635308973, 16505.6698866022, 16508.42220522276, 16514.917425412797, 12551.623451296477, 12633.93683031579, 12642.683932901751, 12649.241045537297, 12688.429020694091, 16437.424690135787, 16775.664486710197, 18789.060546972596, 19223.885332949907, 19388.321408750628, 7859.764207073797, 8069.233422824822, 8146.551939643258, 8150.307223886036, 8189.385796065319, 870.9695160669346, 955.4076472586902, 984.8473486609587, 985.7683444390562, 1031.3811712972156, 29183.41633167325, 30320.84839575788, 30956.69043309583, 30985.0704478866, 31684.366312673617, 929.9349045566745, 989.9307048506679, 1029.5161274201114, 1096.4408151842458, 1125.3360517294836, 15524.767128493082, 15531.767023494656, 15553.0, 15585.766213506806, 15902.920485397499, 519379.2093118606, 670463.0, 690512.0, 696515.0, 696923.0, 40677.796611016754, 48705.0, 57044.714776426685, 57965.42034579685, 58136.0, 1232.8150777383105, 1390.402127085363, 1404.8876089912783, 1440.5030264558711, 1444.8555144485676, 30182.245443864078, 31108.688913110578, 31335.68664313328, 31423.0, 31971.36057278888, 51822.222666659276, 52792.73603631957, 52824.47175528196, 52835.20747188872, 52885.7355713219, 623180.0, 625718.0, 627189.0, 627287.0, 627472.0, 22665.0, 26813.865930671003, 26852.731472685024, 27208.591871121553, 27504.724952751018, 39910.0, 47737.52262477331, 48038.5196148034, 48893.0, 49292.0, 27728.722712772615, 38013.0, 38425.6157438422, 39892.0, 40296.58961936318, 38554.6144538551, 38704.03239919025, 39056.609433905294, 39942.60057399505, 40347.1930561393, 38440.84677459681, 38800.41799372955, 39100.6089939097, 39479.60520394759, 39704.80147599243, 31990.52014219742, 32640.0, 34644.0, 35466.0, 35513.1121721959, 25628.74371256338, 25712.357191070372, 25894.223173304832, 26097.608535872358, 26380.868095660167], "value": 20184.97764912883, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.1870844161997, 365.64897698209717, 373.65810283590514, 377.74313466842545, 398.5715355992309, 12833.358332083393, 15347.539573812775, 16955.74566381502, 20192.293269735543, 21543.24598639048, 20515.897420512913, 21004.894975525138, 21651.350459486202, 21676.132954681794, 21860.45348866279, 5133.973205358924, 5898.1447690085015, 6174.043023331391, 6175.969120154393, 6192.721327540249, 6612.537122401433, 6793.0, 6823.897641535381, 6843.178818541782, 6843.281455447181, 6504.414602685757, 6602.570832895871, 6654.168228971379, 6673.532852700313, 6794.796156115324, 6395.776147834804, 6627.337266273383, 6646.700898459581, 6663.866722665543, 6697.19633643961, 5783.88432231353, 5816.8836623267725, 5826.563007774392, 5833.854153646151, 5842.211301474289, 25103.0, 25837.35406614831, 25963.74036259632, 26085.73914260852, 26471.0, 2255.537614788964, 2475.6781618389673, 2502.8373155745, 2534.936626584332, 2993.760499160062, 10354.618437759282, 10566.366018038942, 14314.92842535791, 15011.549653510412, 16101.436449724204, 1565.3425561264296, 1817.6637322095455, 1864.0679660169915, 1902.391234804863, 1938.4669215965582, 91155.0, 107069.0, 107981.20193960918, 110453.89546104516, 135229.3238533811, 11214.0, 11467.54129834814, 11632.463339297681, 11776.587819426279, 11974.640760777016, 14704.044237124554, 14722.263886805616, 14733.92633036828, 14747.705045899236, 14762.926185369004, 6559.573627714279, 8460.238578527838, 8465.66137354499, 8571.100034496412, 8585.742427727178, 8338.916610833814, 8362.996440427187, 8365.958170209231, 8501.829963400822, 8541.48751074925, 3973.7814420206587, 4403.691741578123, 4536.412255710475, 4549.681522293474, 4555.06621142665, 1856.4616261284345, 1921.8366438852854, 1922.375228050889, 1926.6820974539044, 1949.2885096939515, 793.5714714054346, 861.1947828779986, 885.1458342699326, 998.8551660009404, 1001.2540657210425, 20108.39674809758, 22213.55572888533, 22271.888640556692, 22353.329400118022, 22410.77589224087, 908.5638893331238, 917.8531434970506, 951.8810148731409, 975.2831668723483, 986.9587585097647, 11194.608188713355, 11363.545458181745, 11473.451084103539, 11657.475413606408, 11731.296122232694, 606441.0, 607529.9623501854, 611106.0, 630782.0, 631837.0, 78066.0, 78212.6089369561, 93130.53434732919, 107590.0, 143738.0, 971.723058928202, 979.9265055120894, 1007.2495990486988, 1040.9791804163874, 1045.4302405189037, 19355.032248387244, 20181.054540562847, 20191.0, 20248.96265560178, 20308.59382812306, 52246.21630675467, 54337.45662543324, 57107.71446142909, 57535.0, 58183.12725309039, 75208.0, 78493.0, 83338.58330708307, 86048.0, 86657.0, 7134.8929766052515, 7768.665522951947, 7945.8410831784195, 8253.752387428387, 8426.620802063799, 12892.935535322638, 13212.207267563592, 13287.734245315232, 13716.451341946211, 13739.587812365646, 11422.600208992646, 11440.542378304775, 11546.711332216762, 11669.0, 11823.054155667698, 10945.793871311433, 11133.610323638633, 11405.030572401107, 11685.707857303636, 11813.586524471602, 9900.702978910644, 11257.662270131908, 11450.599229026944, 11553.884461155281, 11952.446181996285, 7023.47323950716, 7358.190599034209, 7405.851882962203, 8135.430519863553, 8412.369072319723, 10976.835347469954, 11694.590689325832, 11702.063834893017, 11908.880911191123, 12004.579839705568], "value": 9767.815977023576, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.5026590427444, 1508.630385555539, 1651.4797838680815, 1785.0717626834055, 1804.702224133018, 8367.623456944437, 8892.510911899852, 8930.053082482782, 8934.195922366995, 8956.238719708832, 7820.92179078209, 8047.396445266614, 8434.704785332515, 8719.520426376546, 8756.91243087569, 2341.1688850458054, 2370.3718514593647, 2372.6796882420913, 2373.4066483379156, 2373.4541055557233, 2346.378209774411, 2370.727366352866, 2399.9520009599796, 2401.591729406005, 2407.121400688745, 1873.3068764557133, 2052.692096185569, 2081.2403472732494, 2237.038073628339, 2245.640697488402, 2319.512902290521, 2350.929472115831, 2355.6348765941307, 2356.8939397727145, 2363.5509253241917, 2341.8946147423235, 2353.8587684738973, 2363.196513185525, 2365.6096744037213, 2370.0756704885107, 8802.779930501789, 8871.423357481743, 8922.241609463203, 8953.149450802164, 8957.283417326664, 5251.579873610102, 5435.0488664483555, 5436.21174929633, 5441.809536666237, 5448.83653490396, 3498.2828520153307, 3579.4451859961746, 3709.9072523186865, 3714.3499887519574, 3716.684081853046, 954.7326748510359, 1066.173715370594, 1077.805994920914, 1089.8256278995402, 1123.1127409346607, 11640.708982275512, 11743.941280293544, 11768.94115529434, 11781.352025638587, 11795.351255680935, 4547.15877562652, 4602.102589994919, 4800.231962885921, 4801.279808028754, 4843.539863712942, 9992.600295988079, 10000.04999525044, 10001.0, 10001.49992500372, 10026.19790416765, 4752.073345697556, 5222.3472065991755, 5383.43473935239, 5392.299001129828, 5396.08266594672, 4422.380866678667, 4604.125216208977, 4773.689710168828, 4885.6335774817035, 4942.555170034714, 3891.455196272524, 3897.1815918656835, 4001.47980762499, 4035.0114222015795, 4054.432379466877, 1262.532862840755, 1288.1305119044607, 1292.1084451728277, 1296.125115547002, 1307.267929959226, 719.4819729794644, 774.7714424244862, 869.4479005831342, 881.8941726992801, 944.2729098594044, 5698.943010569924, 5828.067509198508, 5945.702714864239, 6020.277566692025, 6174.876502470015, 734.3794493652855, 907.2469850024596, 922.423485321674, 946.8059047895165, 987.456898705709, 3400.25194457219, 4006.0, 4116.650084742771, 4211.494620645542, 4240.257954857888, 10631.362118272928, 10700.78598428027, 10811.891881081088, 10836.620718274822, 10837.403942783223, 10355.482225888676, 10632.255742098134, 10866.348019118877, 10883.18376121794, 10894.509747061402, 8086.838263234702, 8113.432059755761, 8232.711855085163, 8346.207110324509, 8362.414630975773, 5193.935243275195, 5981.700914954148, 6045.244344456943, 6122.112293717475, 6132.0, 9723.56243969009, 9779.951100244738, 10141.29010969225, 10175.592976280866, 10292.588296468057, 9955.800883982134, 10269.97300269967, 10320.741981450525, 10375.636852710119, 10376.584936602452, 6401.679916004275, 6701.597904125768, 7458.179600244077, 7575.696972121274, 7613.010308659839, 8501.659933602834, 8549.974003119665, 8555.657773689232, 8556.700515481929, 8566.314694824523, 7256.7097316106765, 7451.105867295959, 7507.849843003219, 7630.8092297693, 7682.347000505019, 7255.56466612005, 7379.0, 7388.630568471663, 7458.328750412272, 7600.80997975055, 6877.1747390313485, 7009.369156775914, 7013.894791578028, 7014.824629384306, 7028.367446929801, 4908.263291341418, 4989.750512474289, 5019.949800501949, 5045.268436076675, 5065.746712664426, 4905.092557876804, 4983.700977941335, 5041.722705251246, 5097.108006098992, 5183.792648294026], "value": 4583.723191872671, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.2456473709672, 303.2207227425517, 309.9426606077875, 313.27476890997355, 316.5868541553272, 4538.931916021254, 5225.51072944211, 5294.682319060859, 5297.708626025572, 5320.388155362135, 4560.612347950429, 4926.3842019747535, 5055.674389780696, 5253.921191182126, 5267.39424966129, 3831.3295173344623, 4048.4939382577177, 4074.8403346226546, 4075.266452038632, 4090.018395585068, 4051.858184963527, 4065.857694980676, 4067.1662309226535, 4069.3895915612748, 4095.75425474471, 3828.9042773930596, 3991.501062367204, 4031.5766844481345, 4087.979560102195, 4093.0995181060266, 3463.688268055862, 3700.962990370089, 3753.6246375362516, 4110.77390743509, 4133.016152246938, 3558.9644103558894, 3564.269324788438, 3589.2821435712963, 3591.7306202034692, 3594.622564630702, 4884.3161957326, 5092.949070509321, 5131.050755610187, 5131.461196574381, 5132.640715149941, 4093.6315731584004, 4659.836905708284, 4697.577218050357, 4723.716577005391, 4724.102420540121, 5539.529140023103, 5641.294838145232, 5646.887062258732, 5669.744861481202, 5702.179539708405, 3933.3509970854775, 3959.762414255125, 3991.840326386941, 3992.8802135935966, 3998.0, 4299.527052024275, 4790.233562629997, 5037.942032173248, 5203.791848326063, 5817.941820581825, 3186.235303527183, 3232.886848960275, 3649.2701459708164, 3862.1268684687902, 3870.59358767331, 3371.7976921384798, 3761.9247615047548, 3919.902427320355, 3928.7053470989217, 3934.9803250984132, 4340.240457919915, 4416.757078360721, 4556.521565235669, 4560.384348113029, 4575.611073058827, 4710.293455981561, 5280.0, 5516.282883225155, 5555.916596263771, 5651.378348381675, 1295.9740805184033, 1359.3611002828654, 1364.208758919824, 1374.271636032904, 1388.8055672205899, 807.6890397197121, 874.9019979925154, 891.3715830339725, 909.5634095634133, 916.6012784438791, 3743.139078012018, 4383.802728877208, 4739.559127049572, 5147.0735267651435, 5176.508231718056, 4695.765211739399, 5033.84898453047, 5150.819721309736, 5294.92057619136, 5450.209719590716, 4319.920019995001, 4887.564238397464, 4918.729469879119, 5049.040682270357, 5158.406783219978, 1957.1779852461716, 2152.7524334701397, 2396.6938018779806, 2473.3198370447913, 2577.5179271918346, 3972.324704800191, 4147.4815648044, 4680.906381872412, 4807.519248075235, 4836.0, 4381.852892082857, 4435.290353543418, 4721.763911804396, 4733.77512569092, 4794.2808578713475, 3952.505936757905, 4209.7895105245225, 4579.129965306581, 4667.906641867212, 4819.168760835074, 3920.0983773732773, 4154.688398370195, 4254.595813397722, 4623.12160689468, 4661.766911654471, 4000.7999600020466, 4590.036092420629, 4608.769561521844, 4649.372334734903, 4660.720356778605, 4448.9110217796115, 4475.820967161277, 4753.405824271966, 5765.673895004089, 5964.850878728066, 3570.1431656402797, 3695.667389934919, 3830.98873091618, 3902.9609703902597, 3990.86031988879, 4803.423589169182, 4811.65273723358, 5830.416958304136, 5845.3570107289015, 5879.265091863517, 4088.6081642239624, 4409.757463339547, 4531.750753708446, 4731.739754313546, 5504.394516603251, 5004.899902002013, 5346.518813306821, 5592.356878958972, 5611.410801865909, 5642.576806739429, 5504.22940788298, 5525.778968841201, 5537.529310008534, 5546.805861794818, 5563.443655634404, 5423.83728488146, 5498.1752737089755, 5546.029444847055, 5547.5007249347755, 5628.0, 4476.440444944382, 4532.002959348938, 4544.182606783683, 5457.290552228185, 5521.889562208653], "value": 3865.6982423050367, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.4506620034973, 756.3722110648163, 759.5063208914205, 778.1518145221708, 820.5568992743919, 15193.772093418595, 16373.345066197338, 16400.75398869018, 16460.423885164022, 16476.42332518362, 14701.55895323142, 14924.179170145637, 14932.925335373307, 14948.551543453686, 14948.850511494882, 660.0824853453703, 680.0377465885766, 680.928502507237, 849.5921957460407, 871.376965469689, 684.5584597934329, 688.5455599304435, 753.7964749517627, 831.966721331146, 867.6182479708939, 908.2552307108141, 913.2465715784471, 913.8035322405682, 920.2086205862953, 923.0262073512441, 914.2046419614911, 915.5605309451468, 917.5320586500866, 920.2408013388914, 921.861720741894, 520.5783315514445, 681.5910453727773, 683.6171743823475, 684.6816230452813, 686.3411125319699, 14118.71762564743, 14600.70798584033, 14638.341274642667, 14641.0, 14654.0, 14267.500637477639, 14854.554363369116, 14869.107853528714, 14958.326875290639, 14992.100473971597, 11864.982613214019, 11885.702857428547, 11944.880551194463, 12018.579349722806, 12023.15837891348, 3591.1560783215923, 3591.5690117186105, 3946.072672921861, 3964.7224694271413, 4023.7786921719294, 119306.0, 120376.99999999913, 123057.76942230553, 124367.0, 124367.3781631095, 11224.719382015352, 11335.603253886075, 11421.657350279338, 11506.942465287788, 11734.413279335999, 23903.0, 23971.161009364587, 24000.27999159993, 24016.919238634302, 24026.759732402454, 8004.134757306675, 8117.918820811717, 8169.101398846122, 8184.2224988625985, 8249.546274954935, 4396.934045989313, 4574.08518296342, 4596.195665758546, 4672.556107169813, 4699.530046995274, 3562.1272788167066, 3687.870924517629, 3691.446283057563, 3697.6672099511175, 3700.5929347771726, 636.9745210191633, 643.8422586466346, 652.8139480248201, 666.037575703114, 666.1306994372294, 566.5411017076182, 570.4951118260286, 574.4227051812901, 625.8748250349948, 640.0814830717935, 9085.545722713838, 9479.90520094804, 9509.0, 9554.378965367228, 9732.12410883024, 556.9860753481196, 561.147056474157, 624.6907780648608, 646.3342756960338, 693.272064332449, 3685.8894233173046, 4429.689921705514, 4637.814487420533, 4674.252119660906, 4759.054772520997, 101072.0, 101346.0, 109227.0, 136239.0, 164912.0, 142551.92347805505, 147495.2625236867, 147946.0, 150092.0, 152022.0, 75855.62072189603, 104546.0, 107868.46065769621, 109071.90928090776, 109544.0, 7624.618769061635, 9708.4466485362, 9754.658586949423, 9915.454649994032, 9950.701478955643, 48482.03035939332, 52777.736111319195, 59702.402975971425, 61990.0, 62629.686851567276, 66896.33103669096, 67370.0, 82032.0, 83029.16970830456, 87529.54080869725, 13315.06794524374, 13389.397477113358, 13440.663983400493, 13790.241536715577, 13957.232352220319, 11746.76506469883, 14665.841584158228, 15208.543743687707, 15318.770218446509, 15371.84628153749, 13686.931565342109, 13692.863071369557, 14068.507602233873, 14310.713785724436, 14322.785158222427, 11001.889981100407, 13720.108192967426, 13872.653183670489, 13986.44054237819, 14169.858301416854, 13410.530631427853, 13706.93146534261, 13743.587692369245, 14007.159570425807, 14139.575812725634, 11394.439010758579, 11395.259308144945, 11502.53989840397, 11525.366104864313, 11564.710882227675, 8008.0, 9908.702738917844, 10115.291929565154, 10599.576016959543, 11316.60391886313], "value": 7779.4687020711035, "unit": "score"}], "name": "dromaeo_css", "value": 7341.758055439654, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 113.0, 106.0, 113.0, 113.0, 114.0, 114.0, 114.0, 114.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 132.0, 135.0, 170.0, 127.0, 167.0, 156.0, 109.0, 158.0, 107.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 182.0, 183.0, 190.0, 184.0, 185.0, 181.0, 260.0, 181.0, 185.0], "value": 191.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 78.0, 150.0, 70.0, 58.0, 57.0, 91.0, 99.0, 73.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 90.0, 131.0, 72.0, 70.0, 71.0, 114.0, 71.0, 69.0, 114.0], "value": 88.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 86.0, 89.0, 85.0, 89.0, 87.0, 74.0, 126.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 94.0, 94.0, 95.0, 94.0, 94.0, 93.0, 95.0, 94.0, 94.0], "value": 97.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 136.0, 86.0, 83.0, 84.0, 84.0, 85.0, 84.0, 85.0, 87.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 54.0, 53.0, 55.0, 55.0, 54.0, 55.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 79.0, 110.0, 110.0, 108.0, 69.0, 68.0, 68.0, 69.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 89.0, 89.0, 88.0, 91.0, 109.0, 110.0, 92.0], "value": 93.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 150.0, 156.0, 140.0, 137.0, 139.0, 138.0, 138.0, 221.0, 149.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 173.0, 175.0, 172.0, 171.0, 172.0, 185.0, 171.0, 173.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 69.0, 67.0, 68.0, 71.0, 69.0, 68.0, 68.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1518.4999999999998, "alertThreshold": 2.0}]} 09:14:18 INFO - Return code: 0 09:14:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:14:18 INFO - # TBPL SUCCESS # 09:14:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:14:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:14:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:14:18 INFO - 2016-08-22 09:14:18.233 screenresolution[1797:10046] starting screenresolution argv=screenresolution get 09:14:18 INFO - 2016-08-22 09:14:18.236 screenresolution[1797:10046] Display 0: 1600x1200x32@60 09:14:18 INFO - 2016-08-22 09:14:18.242 screenresolution[1798:10049] starting screenresolution argv=screenresolution list 09:14:18 INFO - Available Modes on Display 0 09:14:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:14:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:14:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:14:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:14:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:14:18 INFO - Intel Iris: 09:14:18 INFO - Chipset Model: Intel Iris 09:14:18 INFO - Type: GPU 09:14:18 INFO - Bus: Built-In 09:14:18 INFO - VRAM (Dynamic, Max): 1536 MB 09:14:18 INFO - Vendor: Intel (0x8086) 09:14:18 INFO - Device ID: 0x0a2e 09:14:18 INFO - Revision ID: 0x0009 09:14:18 INFO - Displays: 09:14:18 INFO - Display: 09:14:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:14:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:14:18 INFO - Display Serial Number: 1600x1200 60 09:14:18 INFO - Main Display: Yes 09:14:18 INFO - Mirror: Off 09:14:18 INFO - Online: Yes 09:14:18 INFO - Rotation: Supported 09:14:18 INFO - Return code: 0 09:14:18 INFO - Running post-action listener: _resource_record_post_action 09:14:18 INFO - [mozharness: 2016-08-22 16:14:18.476028Z] Finished run-tests step (success) 09:14:18 INFO - Running post-run listener: _resource_record_post_run 09:14:18 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37585920; Write bytes: 709703680; Read time: 252; Write time: 2157 09:14:18 INFO - TinderboxPrint: CPU usage
53.5% 09:14:18 INFO - TinderboxPrint: I/O read bytes / time
37,585,920 / 252 09:14:18 INFO - TinderboxPrint: I/O write bytes / time
709,703,680 / 2,157 09:14:18 INFO - TinderboxPrint: CPU idle
2,257.5 (46.5%) 09:14:18 INFO - TinderboxPrint: CPU system
971.5 (20.0%) 09:14:18 INFO - TinderboxPrint: CPU user
1,624.5 (33.5%) 09:14:18 INFO - TinderboxPrint: Swap in / out
443,273,216 / 0 09:14:18 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402303488; Write bytes: 398005760; Read time: 29709; Write time: 959 09:14:18 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 29912064; Write bytes: 297853440; Read time: 179; Write time: 1173 09:14:18 INFO - Running post-run listener: _upload_blobber_files 09:14:18 INFO - Blob upload gear active. 09:14:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:14:18 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 09:14:18 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:14:18 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:14:19 INFO - (blobuploader) - INFO - Open directory for files ... 09:14:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:14:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:14:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:20 INFO - (blobuploader) - INFO - Done attempting. 09:14:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:14:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:14:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:21 INFO - (blobuploader) - INFO - Done attempting. 09:14:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:14:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:14:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:23 INFO - (blobuploader) - INFO - Done attempting. 09:14:23 INFO - (blobuploader) - INFO - Iteration through files over. 09:14:23 INFO - Return code: 0 09:14:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:14:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:14:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4e0118b5cace996a8aebf1acd9fd6213ce659cb1b0417084fbb6d86f7fc4536775eabdb103f0bbf845ad97c01112f1d4469d87f47688189b8974d93c664edcd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e250ff5cfdc51d427dc51eeffaf6b3a7820b83deabef4ee8732986a22de22666eaf99a6d2e531ae75a1f533056f8c0e1b5269c9f6ef90988deac2e6631c90793", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86ebdf7e26b9e09683ebcad4d2306c5cfebb69ce824012f81bbb7882cb73e6571c70c59d86a1b36ef55258604a221be78a06718193e7cd57e01543b1ed4d85c3"} 09:14:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:14:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:14:23 INFO - Contents: 09:14:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4e0118b5cace996a8aebf1acd9fd6213ce659cb1b0417084fbb6d86f7fc4536775eabdb103f0bbf845ad97c01112f1d4469d87f47688189b8974d93c664edcd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e250ff5cfdc51d427dc51eeffaf6b3a7820b83deabef4ee8732986a22de22666eaf99a6d2e531ae75a1f533056f8c0e1b5269c9f6ef90988deac2e6631c90793", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86ebdf7e26b9e09683ebcad4d2306c5cfebb69ce824012f81bbb7882cb73e6571c70c59d86a1b36ef55258604a221be78a06718193e7cd57e01543b1ed4d85c3"} 09:14:23 INFO - Running post-run listener: copy_logs_to_upload_dir 09:14:23 INFO - Copying logs to upload dir... 09:14:23 INFO - mkdir: /builds/slave/test/build/upload/logs 09:14:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.099794 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-22 09:14:24.019194) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 09:14:24.023325) ========= 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.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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/Firefox/sha512/c4e0118b5cace996a8aebf1acd9fd6213ce659cb1b0417084fbb6d86f7fc4536775eabdb103f0bbf845ad97c01112f1d4469d87f47688189b8974d93c664edcd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e250ff5cfdc51d427dc51eeffaf6b3a7820b83deabef4ee8732986a22de22666eaf99a6d2e531ae75a1f533056f8c0e1b5269c9f6ef90988deac2e6631c90793", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86ebdf7e26b9e09683ebcad4d2306c5cfebb69ce824012f81bbb7882cb73e6571c70c59d86a1b36ef55258604a221be78a06718193e7cd57e01543b1ed4d85c3"} build_url:https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011307 build_url: 'https://queue.taskcluster.net/v1/task/F86j7ptdQKKrb-Oj9HCH9Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4e0118b5cace996a8aebf1acd9fd6213ce659cb1b0417084fbb6d86f7fc4536775eabdb103f0bbf845ad97c01112f1d4469d87f47688189b8974d93c664edcd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e250ff5cfdc51d427dc51eeffaf6b3a7820b83deabef4ee8732986a22de22666eaf99a6d2e531ae75a1f533056f8c0e1b5269c9f6ef90988deac2e6631c90793", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86ebdf7e26b9e09683ebcad4d2306c5cfebb69ce824012f81bbb7882cb73e6571c70c59d86a1b36ef55258604a221be78a06718193e7cd57e01543b1ed4d85c3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 09:14:24.057833) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 09:14:24.058212) ========= 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.dbKDcSI5Sq/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.sKWcJvmCoJ/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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.005089 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 09:14:24.107334) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 09:14:24.107703) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 09:14:24.108227) ========= ========= Total master_lag: 0.48 =========