builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0373 starttime: 1471055711.62 results: success (0) buildid: 20160812162516 builduid: b2d258a2bbf44226897399275612483d revision: 8fd2f35a58fa11f9fb6e356ce37528c6e7e0f728 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 19:35:11.622007) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 19:35:11.622486) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 19:35:11.622786) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 19:35:11.647445) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:35:11.647973) ========= 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.XpFKZ6qqNm/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.sdPQruqFj2/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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-12 19:35:11-- 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% 15.3M=0.001s 2016-08-12 19:35:11 (15.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125436 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:35:11.796076) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:35:11.796412) ========= 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.XpFKZ6qqNm/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.sdPQruqFj2/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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.026491 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:35:11.844998) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 19:35:11.845440) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 8fd2f35a58fa11f9fb6e356ce37528c6e7e0f728 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 8fd2f35a58fa11f9fb6e356ce37528c6e7e0f728 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XpFKZ6qqNm/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.sdPQruqFj2/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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-12 19:35:11,927 truncating revision to first 12 chars 2016-08-12 19:35:11,927 Setting DEBUG logging. 2016-08-12 19:35:11,927 attempt 1/10 2016-08-12 19:35:11,927 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/8fd2f35a58fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-12 19:35:13,598 unpacking tar archive at: autoland-8fd2f35a58fa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.939731 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 19:35:13.807590) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 19:35:13.808034) ========= script_repo_revision: 8fd2f35a58fa11f9fb6e356ce37528c6e7e0f728 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 19:35:13.808440) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 19:35:13.808806) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 19:35:13.826883) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-12 19:35:13.827300) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XpFKZ6qqNm/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.sdPQruqFj2/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 19:35:14 INFO - MultiFileLogger online at 20160812 19:35:14 in /builds/slave/test 19:35:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 19:35:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:35:14 INFO - {'append_to_log': False, 19:35:14 INFO - 'base_work_dir': '/builds/slave/test', 19:35:14 INFO - 'blob_upload_branch': 'Autoland', 19:35:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:35:14 INFO - 'branch': 'Autoland', 19:35:14 INFO - 'buildbot_json_path': 'buildprops.json', 19:35:14 INFO - 'config_files': ('talos/mac_config.py',), 19:35:14 INFO - 'default_actions': ('clobber', 19:35:14 INFO - 'read-buildbot-config', 19:35:14 INFO - 'download-and-extract', 19:35:14 INFO - 'populate-webroot', 19:35:14 INFO - 'create-virtualenv', 19:35:14 INFO - 'install', 19:35:14 INFO - 'run-tests'), 19:35:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:35:14 INFO - 'download_minidump_stackwalk': True, 19:35:14 INFO - 'download_symbols': 'ondemand', 19:35:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:35:14 INFO - 'tooltool.py': '/tools/tooltool.py', 19:35:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:35:14 INFO - '/tools/misc-python/virtualenv.py')}, 19:35:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:35:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:35:14 INFO - 'installer_path': 'installer.exe', 19:35:14 INFO - 'log_level': 'info', 19:35:14 INFO - 'log_name': 'talos', 19:35:14 INFO - 'log_to_console': True, 19:35:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:35:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:35:14 INFO - 'opt_config_files': (), 19:35:14 INFO - 'pip_index': False, 19:35:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:35:14 INFO - 'cmd': ('bash', 19:35:14 INFO - '-c', 19:35:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:35:14 INFO - 'enabled': True, 19:35:14 INFO - 'halt_on_failure': False, 19:35:14 INFO - 'name': 'check_screen_resolution'},), 19:35:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:35:14 INFO - 'cmd': ('bash', 19:35:14 INFO - '-c', 19:35:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:35:14 INFO - 'enabled': True, 19:35:14 INFO - 'halt_on_failure': False, 19:35:14 INFO - 'name': 'check_screen_resolution'},), 19:35:14 INFO - 'run_cmd_checks_enabled': True, 19:35:14 INFO - 'sps_profile': False, 19:35:14 INFO - 'sps_profile_interval': 0, 19:35:14 INFO - 'suite': 'dromaeojs', 19:35:14 INFO - 'system_bits': '32', 19:35:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:35:14 INFO - 'title': 't-yosemite-r7-0373', 19:35:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:35:14 INFO - 'use_talos_json': True, 19:35:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:35:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:35:14 INFO - 'work_dir': 'build'} 19:35:14 INFO - [mozharness: 2016-08-13 02:35:14.020997Z] Running clobber step. 19:35:14 INFO - Running pre-action listener: _resource_record_pre_action 19:35:14 INFO - Running main action method: clobber 19:35:14 INFO - rmtree: /builds/slave/test/build 19:35:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:35:16 INFO - Running post-action listener: _resource_record_post_action 19:35:16 INFO - [mozharness: 2016-08-13 02:35:16.171487Z] Finished clobber step (success) 19:35:16 INFO - [mozharness: 2016-08-13 02:35:16.171678Z] Running read-buildbot-config step. 19:35:16 INFO - Running pre-action listener: _resource_record_pre_action 19:35:16 INFO - Running main action method: read_buildbot_config 19:35:16 INFO - Using buildbot properties: 19:35:16 INFO - { 19:35:16 INFO - "project": "", 19:35:16 INFO - "product": "firefox", 19:35:16 INFO - "script_repo_revision": "production", 19:35:16 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 19:35:16 INFO - "repository": "", 19:35:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 19:35:16 INFO - "buildid": "20160812162516", 19:35:16 INFO - "pgo_build": "False", 19:35:16 INFO - "basedir": "/builds/slave/test", 19:35:16 INFO - "buildnumber": 89, 19:35:16 INFO - "slavename": "t-yosemite-r7-0373", 19:35:16 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 19:35:16 INFO - "platform": "yosemite_r7", 19:35:16 INFO - "scheduler": "tests-autoland-macosx64-talos", 19:35:16 INFO - "branch": "autoland", 19:35:16 INFO - "repo_path": "integration/autoland", 19:35:16 INFO - "revision": "8fd2f35a58fa11f9fb6e356ce37528c6e7e0f728", 19:35:16 INFO - "stage_platform": "macosx64", 19:35:16 INFO - "builduid": "b2d258a2bbf44226897399275612483d", 19:35:16 INFO - "slavebuilddir": "test" 19:35:16 INFO - } 19:35:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:35:16 INFO - Running post-action listener: _resource_record_post_action 19:35:16 INFO - [mozharness: 2016-08-13 02:35:16.177047Z] Finished read-buildbot-config step (success) 19:35:16 INFO - [mozharness: 2016-08-13 02:35:16.177260Z] Running download-and-extract step. 19:35:16 INFO - Running pre-action listener: _resource_record_pre_action 19:35:16 INFO - Running main action method: download_and_extract 19:35:16 INFO - mkdir: /builds/slave/test/build/tests 19:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:16 INFO - trying https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:35:16 INFO - Downloading https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/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 19:35:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/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 19:35:17 INFO - Downloaded 1199 bytes. 19:35:17 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:35:17 INFO - Using the following test package requirements: 19:35:17 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:35:17 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:35:17 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:35:17 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:35:17 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:35:17 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:35:17 INFO - u'jsshell-mac.zip'], 19:35:17 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:35:17 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:35:17 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:35:17 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:35:17 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:35:17 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:35:17 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:35:17 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:35:17 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:35:17 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:35:17 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:35:17 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:35:17 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')]} 19:35:17 INFO - trying https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:35:17 INFO - Downloading https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/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 19:35:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/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 19:35:18 INFO - Downloaded 16329842 bytes. 19:35:18 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:35:24 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 19:35:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:24 INFO - trying https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:35:24 INFO - Downloading https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/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 19:35:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/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 19:35:25 INFO - Downloaded 16329842 bytes. 19:35:25 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:35:32 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')]} 19:35:32 INFO - trying https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:35:32 INFO - Downloading https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/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 19:35:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/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 19:35:33 INFO - Downloaded 14413719 bytes. 19:35:33 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 19:35:33 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')]} 19:35:33 INFO - trying https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:35:33 INFO - Downloading https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:35:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/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 19:35:36 INFO - Downloaded 110973070 bytes. 19:35:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:35:36 INFO - mkdir: /builds/slave/test/properties 19:35:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:35:36 INFO - Writing to file /builds/slave/test/properties/build_url 19:35:36 INFO - Contents: 19:35:36 INFO - build_url:https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:35:37 INFO - Running post-action listener: _resource_record_post_action 19:35:37 INFO - Running post-action listener: set_extra_try_arguments 19:35:37 INFO - [mozharness: 2016-08-13 02:35:37.600684Z] Finished download-and-extract step (success) 19:35:37 INFO - [mozharness: 2016-08-13 02:35:37.600829Z] Running populate-webroot step. 19:35:37 INFO - Running pre-action listener: _resource_record_pre_action 19:35:37 INFO - Running main action method: populate_webroot 19:35:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:35:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:35:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:35:37 INFO - '--tppagecycles', 19:35:37 INFO - '7'], 19:35:37 INFO - 'tests': ['tsvgm']}}, 19:35:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:35:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:35:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:35:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:35:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:35:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:35:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:37 INFO - 'talos_options': ['--disable-e10s'], 19:35:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:35:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:35:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:37 INFO - 'talos_options': ['--disable-e10s'], 19:35:37 INFO - 'tests': ['damp', 'tps']}, 19:35:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:37 INFO - 'tests': ['damp', 'tps']}, 19:35:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:35:37 INFO - 'tests': ['dromaeo_dom']}, 19:35:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:35:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:35:37 INFO - 'tests': ['basic_compositor_video']}, 19:35:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:35:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:35:37 INFO - 'tests': ['a11yr', 19:35:37 INFO - 'ts_paint', 19:35:37 INFO - 'tpaint', 19:35:37 INFO - 'sessionrestore', 19:35:37 INFO - 'sessionrestore_no_auto_restore', 19:35:37 INFO - 'tabpaint']}, 19:35:37 INFO - 'other-e10s': {'tests': ['a11yr', 19:35:37 INFO - 'ts_paint', 19:35:37 INFO - 'tpaint', 19:35:37 INFO - 'sessionrestore', 19:35:37 INFO - 'sessionrestore_no_auto_restore', 19:35:37 INFO - 'tabpaint']}, 19:35:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:35:37 INFO - 'ts_paint', 19:35:37 INFO - 'tpaint', 19:35:37 INFO - 'sessionrestore', 19:35:37 INFO - 'sessionrestore_no_auto_restore', 19:35:37 INFO - 'tabpaint']}, 19:35:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:35:37 INFO - 'ts_paint', 19:35:37 INFO - 'tpaint', 19:35:37 INFO - 'sessionrestore', 19:35:37 INFO - 'sessionrestore_no_auto_restore', 19:35:37 INFO - 'tabpaint']}, 19:35:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:35:37 INFO - 'tests': ['a11yr', 19:35:37 INFO - 'ts_paint', 19:35:37 INFO - 'tpaint', 19:35:37 INFO - 'sessionrestore', 19:35:37 INFO - 'sessionrestore_no_auto_restore', 19:35:37 INFO - 'tabpaint']}, 19:35:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:35:37 INFO - 'tests': ['a11yr', 19:35:37 INFO - 'ts_paint', 19:35:37 INFO - 'tpaint', 19:35:37 INFO - 'sessionrestore', 19:35:37 INFO - 'sessionrestore_no_auto_restore', 19:35:37 INFO - 'tabpaint']}, 19:35:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:35:37 INFO - 'tests': ['tsvgx', 19:35:37 INFO - 'tsvgr_opacity', 19:35:37 INFO - 'tart', 19:35:37 INFO - 'tscrollx', 19:35:37 INFO - 'cart']}, 19:35:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:35:37 INFO - 'tsvgr_opacity', 19:35:37 INFO - 'tart', 19:35:37 INFO - 'tscrollx', 19:35:37 INFO - 'cart']}, 19:35:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:37 INFO - 'talos_options': ['--disable-e10s'], 19:35:37 INFO - 'tests': ['tp5o']}, 19:35:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:37 INFO - 'tests': ['tp5o']}, 19:35:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:37 INFO - 'talos_options': ['--disable-e10s', 19:35:37 INFO - '--xperf_path', 19:35:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:35:37 INFO - 'tests': ['tp5n']}, 19:35:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:35:37 INFO - 'talos_options': ['--xperf_path', 19:35:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:35:37 INFO - 'tests': ['tp5n']}}, 19:35:37 INFO - 'talos.zip': {'path': '', 19:35:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:35:37 INFO - Running post-action listener: _resource_record_post_action 19:35:37 INFO - [mozharness: 2016-08-13 02:35:37.619972Z] Finished populate-webroot step (success) 19:35:37 INFO - [mozharness: 2016-08-13 02:35:37.620082Z] Running create-virtualenv step. 19:35:37 INFO - Running pre-action listener: _resource_record_pre_action 19:35:37 INFO - Running main action method: create_virtualenv 19:35:37 INFO - Creating virtualenv /builds/slave/test/build/venv 19:35:37 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 19:35:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:35:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:35:37 INFO - Using real prefix '/tools/python27' 19:35:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:35:38 INFO - Installing distribute.............................................................................................................................................................................................done. 19:35:41 INFO - Installing pip.................done. 19:35:41 INFO - Return code: 0 19:35:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:35:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10983e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff622cc8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970dd30>, '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 0x109472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109705b20>, '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.XpFKZ6qqNm/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.sdPQruqFj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 19:35:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:35:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:35:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpFKZ6qqNm/Render', 19:35:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:41 INFO - 'HOME': '/Users/cltbld', 19:35:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:41 INFO - 'LOGNAME': 'cltbld', 19:35:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:41 INFO - 'MOZ_NO_REMOTE': '1', 19:35:41 INFO - 'NO_EM_RESTART': '1', 19:35:41 INFO - 'PAGER': '/bin/cat', 19:35:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:41 INFO - 'PWD': '/builds/slave/test', 19:35:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:41 INFO - 'SHELL': '/bin/bash', 19:35:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sdPQruqFj2/Listeners', 19:35:41 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 19:35:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:41 INFO - 'USER': 'cltbld', 19:35:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:41 INFO - 'XPC_FLAGS': '0x0', 19:35:41 INFO - 'XPC_SERVICE_NAME': '0', 19:35:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:41 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 19:35:41 INFO - Cleaning up... 19:35:41 INFO - Return code: 0 19:35:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:35:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1097a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10983e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff622cc8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970dd30>, '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 0x109472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109705b20>, '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.XpFKZ6qqNm/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.sdPQruqFj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 19:35:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:35:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:35:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpFKZ6qqNm/Render', 19:35:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:41 INFO - 'HOME': '/Users/cltbld', 19:35:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:41 INFO - 'LOGNAME': 'cltbld', 19:35:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:41 INFO - 'MOZ_NO_REMOTE': '1', 19:35:41 INFO - 'NO_EM_RESTART': '1', 19:35:41 INFO - 'PAGER': '/bin/cat', 19:35:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:41 INFO - 'PWD': '/builds/slave/test', 19:35:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:41 INFO - 'SHELL': '/bin/bash', 19:35:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sdPQruqFj2/Listeners', 19:35:41 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 19:35:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:41 INFO - 'USER': 'cltbld', 19:35:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:41 INFO - 'XPC_FLAGS': '0x0', 19:35:41 INFO - 'XPC_SERVICE_NAME': '0', 19:35:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:41 INFO - Downloading/unpacking psutil>=3.1.1 19:35:41 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 19:35:41 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 19:35:41 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 19:35:41 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 19:35:41 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 19:35:41 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 19:35:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:35:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:35:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:35:45 INFO - Installing collected packages: psutil 19:35:45 INFO - Running setup.py install for psutil 19:35:45 INFO - building 'psutil._psutil_osx' extension 19:35:45 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 19:35:45 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 19:35:45 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 19:35:45 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 19:35:46 INFO - building 'psutil._psutil_posix' extension 19:35:46 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 19:35:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:35:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:35:46 INFO - ^ 19:35:46 INFO - 1 warning generated. 19:35:46 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 19:35:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:35:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:35:46 INFO - Successfully installed psutil 19:35:46 INFO - Cleaning up... 19:35:46 INFO - Return code: 0 19:35:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:35:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:46 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 0x1097a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10983e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff622cc8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970dd30>, '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 0x109472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109705b20>, '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.XpFKZ6qqNm/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.sdPQruqFj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 19:35:46 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 19:35:46 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 19:35:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpFKZ6qqNm/Render', 19:35:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:46 INFO - 'HOME': '/Users/cltbld', 19:35:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:46 INFO - 'LOGNAME': 'cltbld', 19:35:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:46 INFO - 'MOZ_NO_REMOTE': '1', 19:35:46 INFO - 'NO_EM_RESTART': '1', 19:35:46 INFO - 'PAGER': '/bin/cat', 19:35:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:46 INFO - 'PWD': '/builds/slave/test', 19:35:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:46 INFO - 'SHELL': '/bin/bash', 19:35:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sdPQruqFj2/Listeners', 19:35:46 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 19:35:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:46 INFO - 'USER': 'cltbld', 19:35:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:46 INFO - 'XPC_FLAGS': '0x0', 19:35:46 INFO - 'XPC_SERVICE_NAME': '0', 19:35:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:35: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 19:35: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 19:35: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 19:35: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 19:35: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 19:35: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 19:35:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:35:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:35:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:35:49 INFO - Installing collected packages: mozsystemmonitor 19:35:49 INFO - Running setup.py install for mozsystemmonitor 19:35:49 INFO - Successfully installed mozsystemmonitor 19:35:49 INFO - Cleaning up... 19:35:49 INFO - Return code: 0 19:35:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:35: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')]} 19:35:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35: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')]} 19:35:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35: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', '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 0x1097a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10983e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff622cc8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970dd30>, '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 0x109472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109705b20>, '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.XpFKZ6qqNm/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.sdPQruqFj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 19:35: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:35: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 jsonschema==2.5.1 19:35:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpFKZ6qqNm/Render', 19:35:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:49 INFO - 'HOME': '/Users/cltbld', 19:35:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:49 INFO - 'LOGNAME': 'cltbld', 19:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:49 INFO - 'MOZ_NO_REMOTE': '1', 19:35:49 INFO - 'NO_EM_RESTART': '1', 19:35:49 INFO - 'PAGER': '/bin/cat', 19:35:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:49 INFO - 'PWD': '/builds/slave/test', 19:35:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:49 INFO - 'SHELL': '/bin/bash', 19:35:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sdPQruqFj2/Listeners', 19:35:49 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 19:35:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:49 INFO - 'USER': 'cltbld', 19:35:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:49 INFO - 'XPC_FLAGS': '0x0', 19:35:49 INFO - 'XPC_SERVICE_NAME': '0', 19:35:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:49 INFO - Downloading/unpacking jsonschema==2.5.1 19:35: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 19:35: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 19:35: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 19:35: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 19:35: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 19:35: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 19:35:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:35:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:35: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 19:35: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 19:35: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 19:35: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 19:35: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 19:35: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 19:35:52 INFO - Downloading functools32-3.2.3-2.tar.gz 19:35:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:35:52 INFO - warning: no files found matching '*.txt' 19:35:52 INFO - no previously-included directories found matching 'build' 19:35:52 INFO - no previously-included directories found matching 'dist' 19:35:52 INFO - no previously-included directories found matching '.git*' 19:35:52 INFO - Installing collected packages: jsonschema, functools32 19:35:52 INFO - Running setup.py install for functools32 19:35:52 INFO - warning: no files found matching '*.txt' 19:35:52 INFO - no previously-included directories found matching 'build' 19:35:52 INFO - no previously-included directories found matching 'dist' 19:35:52 INFO - no previously-included directories found matching '.git*' 19:35:52 INFO - Successfully installed jsonschema functools32 19:35:52 INFO - Cleaning up... 19:35:52 INFO - Return code: 0 19:35:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:35:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:52 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 0x1097a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10983e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff622cc8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970dd30>, '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 0x109472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109705b20>, '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.XpFKZ6qqNm/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.sdPQruqFj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 19:35:52 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 19:35:52 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 19:35:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpFKZ6qqNm/Render', 19:35:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:52 INFO - 'HOME': '/Users/cltbld', 19:35:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:52 INFO - 'LOGNAME': 'cltbld', 19:35:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:52 INFO - 'MOZ_NO_REMOTE': '1', 19:35:52 INFO - 'NO_EM_RESTART': '1', 19:35:52 INFO - 'PAGER': '/bin/cat', 19:35:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:52 INFO - 'PWD': '/builds/slave/test', 19:35:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:52 INFO - 'SHELL': '/bin/bash', 19:35:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sdPQruqFj2/Listeners', 19:35:52 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 19:35:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:52 INFO - 'USER': 'cltbld', 19:35:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:52 INFO - 'XPC_FLAGS': '0x0', 19:35:52 INFO - 'XPC_SERVICE_NAME': '0', 19:35:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:35:53 INFO - Cleaning up... 19:35:53 INFO - Return code: 0 19:35:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:35:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:53 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 0x1097a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10983e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff622cc8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970dd30>, '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 0x109472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109705b20>, '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.XpFKZ6qqNm/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.sdPQruqFj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 19:35:53 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 19:35:53 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 19:35:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpFKZ6qqNm/Render', 19:35:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:53 INFO - 'HOME': '/Users/cltbld', 19:35:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:53 INFO - 'LOGNAME': 'cltbld', 19:35:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:53 INFO - 'MOZ_NO_REMOTE': '1', 19:35:53 INFO - 'NO_EM_RESTART': '1', 19:35:53 INFO - 'PAGER': '/bin/cat', 19:35:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:53 INFO - 'PWD': '/builds/slave/test', 19:35:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:53 INFO - 'SHELL': '/bin/bash', 19:35:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sdPQruqFj2/Listeners', 19:35:53 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 19:35:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:53 INFO - 'USER': 'cltbld', 19:35:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:53 INFO - 'XPC_FLAGS': '0x0', 19:35:53 INFO - 'XPC_SERVICE_NAME': '0', 19:35:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:53 INFO - Downloading/unpacking blobuploader==1.2.4 19:35: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 19:35: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 19:35: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 19:35: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 19:35: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 19:35: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 19:35:55 INFO - Downloading blobuploader-1.2.4.tar.gz 19:35:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:35:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:35:55 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 19:35:55 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 19:35:55 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 19:35:55 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 19:35:55 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 19:35:55 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 19:35:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:35:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:35:56 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 19:35:56 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 19:35:56 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 19:35:56 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 19:35:56 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 19:35:56 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 19:35:56 INFO - Downloading docopt-0.6.1.tar.gz 19:35:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:35:57 INFO - Installing collected packages: blobuploader, requests, docopt 19:35:57 INFO - Running setup.py install for blobuploader 19:35:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:35:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:35:57 INFO - Running setup.py install for requests 19:35:57 INFO - Running setup.py install for docopt 19:35:57 INFO - Successfully installed blobuploader requests docopt 19:35:57 INFO - Cleaning up... 19:35:57 INFO - Return code: 0 19:35:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:35:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:57 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 0x1097a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10983e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff622cc8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970dd30>, '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 0x109472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109705b20>, '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.XpFKZ6qqNm/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.sdPQruqFj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 19:35:57 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 19:35:57 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 19:35:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpFKZ6qqNm/Render', 19:35:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:57 INFO - 'HOME': '/Users/cltbld', 19:35:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:57 INFO - 'LOGNAME': 'cltbld', 19:35:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:57 INFO - 'MOZ_NO_REMOTE': '1', 19:35:57 INFO - 'NO_EM_RESTART': '1', 19:35:57 INFO - 'PAGER': '/bin/cat', 19:35:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:57 INFO - 'PWD': '/builds/slave/test', 19:35:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:57 INFO - 'SHELL': '/bin/bash', 19:35:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sdPQruqFj2/Listeners', 19:35:57 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 19:35:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:57 INFO - 'USER': 'cltbld', 19:35:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:57 INFO - 'XPC_FLAGS': '0x0', 19:35:57 INFO - 'XPC_SERVICE_NAME': '0', 19:35:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:35:58 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-ec5KNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:35:58 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-BE0yUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:35:58 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-73T2AR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:35:58 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-VaLKbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:35:58 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-J2HWZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:35:58 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-bbTXp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:35:58 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-N2yXAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:35:58 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-VdOMiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:35:59 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-nn91lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:35:59 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-jIJGNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:35:59 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-vZY25e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:35:59 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-ELXaij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:35:59 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-KsKl1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:35:59 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-kmockH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:59 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-Zr18rJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:35:59 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-Td8zho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:35:59 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-8U4HYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:35:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:35:59 INFO - Running setup.py install for manifestparser 19:36:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:36:00 INFO - Running setup.py install for mozcrash 19:36:00 INFO - Running setup.py install for mozdebug 19:36:00 INFO - Running setup.py install for mozdevice 19:36:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:36:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:36:00 INFO - Running setup.py install for mozfile 19:36:00 INFO - Running setup.py install for mozhttpd 19:36:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:36:00 INFO - Running setup.py install for mozinfo 19:36:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:36:00 INFO - Running setup.py install for mozInstall 19:36:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:36:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:36:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:36:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:36:01 INFO - Running setup.py install for mozleak 19:36:01 INFO - Running setup.py install for mozlog 19:36:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:36:01 INFO - Running setup.py install for moznetwork 19:36:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:36:01 INFO - Running setup.py install for mozprocess 19:36:01 INFO - Running setup.py install for mozprofile 19:36:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:36:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:36:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:36:01 INFO - Running setup.py install for mozrunner 19:36:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:36:02 INFO - Running setup.py install for mozscreenshot 19:36:02 INFO - Running setup.py install for moztest 19:36:02 INFO - Running setup.py install for mozversion 19:36:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:36:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:36:02 INFO - Cleaning up... 19:36:02 INFO - Return code: 0 19:36:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:02 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 0x1097a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10983e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff622cc8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970dd30>, '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 0x109472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109705b20>, '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.XpFKZ6qqNm/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.sdPQruqFj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 19:36:02 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 19:36:02 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 19:36:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpFKZ6qqNm/Render', 19:36:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:02 INFO - 'HOME': '/Users/cltbld', 19:36:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:02 INFO - 'LOGNAME': 'cltbld', 19:36:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:02 INFO - 'MOZ_NO_REMOTE': '1', 19:36:02 INFO - 'NO_EM_RESTART': '1', 19:36:02 INFO - 'PAGER': '/bin/cat', 19:36:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:02 INFO - 'PWD': '/builds/slave/test', 19:36:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:02 INFO - 'SHELL': '/bin/bash', 19:36:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sdPQruqFj2/Listeners', 19:36:02 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 19:36:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:02 INFO - 'USER': 'cltbld', 19:36:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:02 INFO - 'XPC_FLAGS': '0x0', 19:36:02 INFO - 'XPC_SERVICE_NAME': '0', 19:36:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:36:02 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-ETkP1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:36:02 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)) 19:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:36:02 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-wcJks7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:36:03 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)) 19:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:36:03 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-XF3KQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:36:03 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)) 19:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:36:03 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-6fItqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:36:03 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)) 19:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:36:03 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-durWe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:36:03 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)) 19:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:36:03 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-i0rFsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:36:03 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)) 19:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:36:03 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-rc3EKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:36:03 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)) 19:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:36:03 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-s4XPXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:36:03 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)) 19:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:36:03 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-hM9If2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:36:03 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)) 19:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:36:03 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-3r0DBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:36:03 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)) 19:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:36:03 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-URR6I0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:36:03 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)) 19:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:36:03 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-Gm0rJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:36:04 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)) 19:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:36:04 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-ik2Gzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:36:04 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)) 19:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:36:04 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-ZDgWPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:36:04 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)) 19:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:36:04 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-sk9BE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:36:04 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)) 19:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:36:04 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-aSQqpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:36:04 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)) 19:36:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:36:04 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-74rnZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:36:04 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)) 19:36:04 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)) 19:36:04 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)) 19:36:04 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)) 19:36:04 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)) 19:36:04 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)) 19:36:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:06 INFO - Downloading blessings-1.6.tar.gz 19:36:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:36:07 INFO - Installing collected packages: blessings 19:36:07 INFO - Running setup.py install for blessings 19:36:07 INFO - Successfully installed blessings 19:36:07 INFO - Cleaning up... 19:36:07 INFO - Return code: 0 19:36:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:36:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:36:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:36:07 INFO - Reading from file tmpfile_stdout 19:36:07 INFO - Current package versions: 19:36:07 INFO - blessings == 1.6 19:36:07 INFO - blobuploader == 1.2.4 19:36:07 INFO - docopt == 0.6.1 19:36:07 INFO - functools32 == 3.2.3-2 19:36:07 INFO - jsonschema == 2.5.1 19:36:07 INFO - manifestparser == 1.1 19:36:07 INFO - mozInstall == 1.12 19:36:07 INFO - mozcrash == 0.17 19:36:07 INFO - mozdebug == 0.1 19:36:07 INFO - mozdevice == 0.48 19:36:07 INFO - mozfile == 1.2 19:36:07 INFO - mozhttpd == 0.7 19:36:07 INFO - mozinfo == 0.9 19:36:07 INFO - mozleak == 0.1 19:36:07 INFO - mozlog == 3.3 19:36:07 INFO - moznetwork == 0.27 19:36:07 INFO - mozprocess == 0.23 19:36:07 INFO - mozprofile == 0.28 19:36:07 INFO - mozrunner == 6.12 19:36:07 INFO - mozscreenshot == 0.1 19:36:07 INFO - mozsystemmonitor == 0.3 19:36:07 INFO - moztest == 0.7 19:36:07 INFO - mozversion == 1.4 19:36:07 INFO - psutil == 3.1.1 19:36:07 INFO - requests == 1.2.3 19:36:07 INFO - wsgiref == 0.1.2 19:36:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:36:07 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')]} 19:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:07 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')]} 19:36:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:07 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 0x1097a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10983e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff622cc8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970dd30>, '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 0x109472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109705b20>, '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.XpFKZ6qqNm/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.sdPQruqFj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 19:36:07 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 19:36:07 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 19:36:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpFKZ6qqNm/Render', 19:36:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:07 INFO - 'HOME': '/Users/cltbld', 19:36:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:07 INFO - 'LOGNAME': 'cltbld', 19:36:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:07 INFO - 'MOZ_NO_REMOTE': '1', 19:36:07 INFO - 'NO_EM_RESTART': '1', 19:36:07 INFO - 'PAGER': '/bin/cat', 19:36:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:07 INFO - 'PWD': '/builds/slave/test', 19:36:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:07 INFO - 'SHELL': '/bin/bash', 19:36:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sdPQruqFj2/Listeners', 19:36:07 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 19:36:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:07 INFO - 'USER': 'cltbld', 19:36:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:07 INFO - 'XPC_FLAGS': '0x0', 19:36:07 INFO - 'XPC_SERVICE_NAME': '0', 19:36:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:07 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)) 19:36:07 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)) 19:36:07 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)) 19:36:07 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)) 19:36:07 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)) 19:36:07 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)) 19:36:07 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)) 19:36:07 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)) 19:36:07 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)) 19:36:07 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)) 19:36:07 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)) 19:36:07 INFO - Cleaning up... 19:36:07 INFO - Return code: 0 19:36:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:36:07 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')]} 19:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:36:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097a9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10983e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff622cc8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10970dd30>, '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 0x109472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109705b20>, '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.XpFKZ6qqNm/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.sdPQruqFj2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 19:36:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:36:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:36:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpFKZ6qqNm/Render', 19:36:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:08 INFO - 'HOME': '/Users/cltbld', 19:36:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:08 INFO - 'LOGNAME': 'cltbld', 19:36:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:08 INFO - 'MOZ_NO_REMOTE': '1', 19:36:08 INFO - 'NO_EM_RESTART': '1', 19:36:08 INFO - 'PAGER': '/bin/cat', 19:36:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:08 INFO - 'PWD': '/builds/slave/test', 19:36:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:08 INFO - 'SHELL': '/bin/bash', 19:36:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sdPQruqFj2/Listeners', 19:36:08 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 19:36:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:08 INFO - 'USER': 'cltbld', 19:36:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:08 INFO - 'XPC_FLAGS': '0x0', 19:36:08 INFO - 'XPC_SERVICE_NAME': '0', 19:36:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:36:08 INFO - Cleaning up... 19:36:08 INFO - Return code: 0 19:36:08 INFO - Running post-action listener: _resource_record_post_action 19:36:08 INFO - Running post-action listener: _start_resource_monitoring 19:36:08 INFO - Starting resource monitoring. 19:36:08 INFO - [mozharness: 2016-08-13 02:36:08.543307Z] Finished create-virtualenv step (success) 19:36:08 INFO - [mozharness: 2016-08-13 02:36:08.544260Z] Running install step. 19:36:08 INFO - Running pre-action listener: _resource_record_pre_action 19:36:08 INFO - Running main action method: install 19:36:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:36:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:36:08 INFO - Reading from file tmpfile_stdout 19:36:08 INFO - Detecting whether we're running mozinstall >=1.0... 19:36:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:36:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:36:08 INFO - Reading from file tmpfile_stdout 19:36:08 INFO - Output received: 19:36:08 INFO - Usage: mozinstall [options] installer 19:36:08 INFO - Options: 19:36:08 INFO - -h, --help show this help message and exit 19:36:08 INFO - -d DEST, --destination=DEST 19:36:08 INFO - Directory to install application into. [default: 19:36:08 INFO - "/builds/slave/test"] 19:36:08 INFO - --app=APP Application being installed. [default: firefox] 19:36:08 INFO - mkdir: /builds/slave/test/build/application 19:36:08 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'] 19:36:08 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 19:36:42 INFO - Reading from file tmpfile_stdout 19:36:42 INFO - Output received: 19:36:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:36:42 INFO - Running post-action listener: _resource_record_post_action 19:36:42 INFO - [mozharness: 2016-08-13 02:36:42.703356Z] Finished install step (success) 19:36:42 INFO - [mozharness: 2016-08-13 02:36:42.703533Z] Running run-tests step. 19:36:42 INFO - Running pre-action listener: _resource_record_pre_action 19:36:42 INFO - Running main action method: run_tests 19:36:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:36:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:36:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:36:42 INFO - 2016-08-12 19:36:42.717 screenresolution[1656:7573] starting screenresolution argv=screenresolution get 19:36:42 INFO - 2016-08-12 19:36:42.720 screenresolution[1656:7573] Display 0: 1600x1200x32@60 19:36:42 INFO - 2016-08-12 19:36:42.726 screenresolution[1657:7575] starting screenresolution argv=screenresolution list 19:36:42 INFO - Available Modes on Display 0 19:36:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:36:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:36:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:36:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:36:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:36:42 INFO - Intel Iris: 19:36:42 INFO - Chipset Model: Intel Iris 19:36:42 INFO - Type: GPU 19:36:42 INFO - Bus: Built-In 19:36:42 INFO - VRAM (Dynamic, Max): 1536 MB 19:36:42 INFO - Vendor: Intel (0x8086) 19:36:42 INFO - Device ID: 0x0a2e 19:36:42 INFO - Revision ID: 0x0009 19:36:42 INFO - Displays: 19:36:42 INFO - Display: 19:36:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:36:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:36:42 INFO - Display Serial Number: 1600x1200 60 19:36:42 INFO - Main Display: Yes 19:36:42 INFO - Mirror: Off 19:36:42 INFO - Online: Yes 19:36:42 INFO - Rotation: Supported 19:36:42 INFO - Return code: 0 19:36:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:36:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:36:42 INFO - Python 2.7.3 19:36:42 INFO - Return code: 0 19:36:42 INFO - grabbing minidump binary from tooltool 19:36: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')]} 19:36:42 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 0x10970dd30>, '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 0x109472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109705b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:36:42 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 19:36:42 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 19:36:42 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 19:36:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:36:43 INFO - Return code: 0 19:36:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:36:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:36:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:36:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:36:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:36:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0373', '--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 19:36:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0373 --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 19:36:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XpFKZ6qqNm/Render', 19:36:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:43 INFO - 'HOME': '/Users/cltbld', 19:36:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:43 INFO - 'LOGNAME': 'cltbld', 19:36:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:36:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:43 INFO - 'MOZ_NO_REMOTE': '1', 19:36:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:36:43 INFO - 'NO_EM_RESTART': '1', 19:36:43 INFO - 'PAGER': '/bin/cat', 19:36:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:43 INFO - 'PWD': '/builds/slave/test', 19:36:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:36:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:43 INFO - 'SHELL': '/bin/bash', 19:36:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sdPQruqFj2/Listeners', 19:36:43 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 19:36:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:43 INFO - 'USER': 'cltbld', 19:36:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:43 INFO - 'XPC_FLAGS': '0x0', 19:36:43 INFO - 'XPC_SERVICE_NAME': '0', 19:36:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0373', '--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 19:36:43 INFO - mozversion application_buildid: 20160812162516 19:36:43 INFO - mozversion application_changeset: 8fd2f35a58fa11f9fb6e356ce37528c6e7e0f728 19:36:43 INFO - mozversion application_display_name: Nightly 19:36:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:36:43 INFO - mozversion application_name: Firefox 19:36:43 INFO - mozversion application_remotingname: firefox 19:36:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 19:36:43 INFO - mozversion application_vendor: Mozilla 19:36:43 INFO - mozversion application_version: 51.0a1 19:36:43 INFO - mozversion platform_buildid: 20160812162516 19:36:43 INFO - mozversion platform_changeset: 8fd2f35a58fa11f9fb6e356ce37528c6e7e0f728 19:36:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 19:36:43 INFO - mozversion platform_version: 51.0a1 19:36:43 INFO - using testdate: 1471055803 19:36:43 INFO - actual date: 1471055803 19:36:43 INFO - starting webserver on 'localhost:49276' 19:36:43 INFO - SUITE-START | Running 2 tests 19:36:43 INFO - TEST-START | dromaeo_css 19:36:43 INFO - operating with platform_type : mac_ 19:36:43 INFO - Initialising browser for dromaeo_css test... 19:36:43 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpn6Dal9/profile http://localhost:49276/getInfo.html) 19:36:45 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 19:36:45 INFO - PROCESS | 1667 | colorDepth:24 19:36:45 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 19:36:45 INFO - PROCESS | 1667 | __metrics 19:36:45 INFO - PROCESS | 1667 | 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" 19:36:45 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:36:46 INFO - TEST-INFO | 1667: exit 0 19:36:46 INFO - Browser initialized. 19:36:46 INFO - Running cycle 1/1 for dromaeo_css test... 19:36:46 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpn6Dal9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:36:58 INFO - PROCESS | 1670 | RSS: Main: 243826688 19:36:58 INFO - PROCESS | 1670 | 19:37:49 INFO - PROCESS | 1670 | 1471055869706 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] 19:40:01 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 19:40:02 INFO - PROCESS | 1670 | RSS: Main: 377827328 19:40:02 INFO - PROCESS | 1670 | 19:43:05 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 19:43:06 INFO - PROCESS | 1670 | RSS: Main: 400883712 19:43:06 INFO - PROCESS | 1670 | 19:46:08 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 19:46:09 INFO - PROCESS | 1670 | RSS: Main: 350420992 19:46:09 INFO - PROCESS | 1670 | 19:49:11 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 19:49:12 INFO - PROCESS | 1670 | RSS: Main: 348827648 19:49:12 INFO - PROCESS | 1670 | 19:52:15 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 19:52:16 INFO - PROCESS | 1670 | RSS: Main: 338759680 19:52:16 INFO - PROCESS | 1670 | 19:55:19 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 19:55:19 INFO - PROCESS | 1670 | RSS: Main: 515493888 19:55:19 INFO - PROCESS | 1670 | 19:55:19 INFO - PROCESS | 1670 | __start_tp_report 19:55:19 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 19:55:19 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 19:55:19 INFO - PROCESS | 1670 | |i|pagename|runs| 19:55:19 INFO - PROCESS | 1670 | |0;dojo.html;2671.6927553331366;2945.617069780928;3199.408109499742;3390.0846771371716;3401.9319613607713;5573.136163894603;5639.633423827453;5690.7439165237665;5760.567957403196;5771.36514983352;5709.314882214129;5759.827205183851;5762.308522977238;5769.480746732804;5777.768889244425;5177.844664660057;5242.3184985951775;5244.265802787613;5274.182501712222;5315.963387139509;5282.656627319212;5323.653962492445;5323.866903327409;5326.387465441466;5327.440618735035;5196.818111366103;5209.32278803755;5210.1403268460845;5225.372955245357;5294.655847369909;5274.894502109955;5304.257403963448;5317.9202311965355;5324.7071411072375;5369.785208591652;5144.305518754958;5157.097507936133;5173.405058418255;5232.6598771080135;5242.60680448964;5628.915566266509;5635.295588051494;5671.858203544944;5688.516476099537;5719.428057194247;6201.596896201732;6206.472449841769;6235.376462353774;6239.220097487814;6244.188255526798;5643.6049476536655;5945.019071853139;6092.756289748405;6100.793122958854;6181.752729890799;5881.500072493886;5883.794067207627;5896.439838215406;5914.852128696731;5916.556258280646;6285.874282514415;6293.181886354836;6298;6300.369963003663;6310.211223597051;6062.727177277033;6094.664793436404;6117.265928088658;6180.6600636965395;6199.535034872402;6201.627902325875;6201.658908759971;6218.782342618078;6224.097505861625;6227.439530442191;6154;6202.844928876814;6214.347493513206;6225.346338634468;6229.190205273376;5512.6692398456225;5786.1610066540115;6121.331035925228;6196.10156527301;6224.37756224374;4892.437369702458;4925.852224433273;4945.703257804544;4950.702957822542;4997.600191984632;4444.577765112309;4445.088756804912;4445.755483448376;4447.066116115588;4482.977585112054;5625.54995600351;5664.575156863251;5669.319681638147;5749.8562535936935;5749.9712501437225;6198.938010619926;6304.243490781136;6314.557980941382;6333.081703153017;6338.7464501420345;5720.427957204246;5725.912076705413;5729.942700573024;5742.196092547047;5784.161296612052;5294.470552944752;5350.571954243651;5360.946390536123;5405.351357837085;5410.269613602113;6241.21984751906;6276.435120839055;6285.654289014148;6339.683015849097;6340.4293613575;6314.747410103544;6319.652419116993;6338.112664227104;6346.2067241594805;6353.3011368748475;6235.532335074797;6240.375962403723;6291.1192433058495;6307.810765677037;6316.7157477914525;6120.0205129226715;6124.289394098022;6178.691065446799;6281.434670879643;6291.905621415591;6319.936800631935;6323.336049714714;6324.083007963912;6330.113784070326;6341.334159913143;5871.970640146772;6173.444390004742;6174.104754810617;6184.5052395809125;6190.102435146789;5956.315023772322;6124.112003759341;6127.7393367763425;6149.169862068742;6156.815295541141;5103.3110530077965;5588.608797384144;5961.254843144607;6015.819525414245;6024.819255422344;6049.124964666354;6049.516038716804;6077.058056001327;6124.173236613;6129.969350153399;4832.8791780205775;5868.2664666916635;5911.881762364815;5950;5978.671173085522 19:55:19 INFO - PROCESS | 1670 | |1;ext.html;441.1728009981283;464.4960218163293;520.8333333333334;521.7260938007546;527.2855281094118;30781.538276925836;40866;42414.7879260604;42450.57549424509;44341.33487997683;44001.899952501204;44381.8904527387;44436.77781611095;44451.66645000655;44464.3330350045;9757.316987810857;9934.956829532903;9945;9948.60205591779;9954.800903981915;9701.563429645648;9783.119519243266;9789.118979291863;9798.706038818847;9828.017198280151;9726.221902247838;9788.902110978906;9831.803363932717;9855.408675479493;9862.852057219148;9719.076687714656;9797.216222702167;9797.804043919117;9807.754806129904;9808.852867206997;9766.804663906682;9846.458444785534;9853;9868.802623947553;9870.654527091518;41821;42045.36931946023;42067.7896610515;42151.578484215075;42170;3381.2899291148888;3429.5198672185916;3511.7541772075965;3922.352811786052;4325.048489332342;31654.050378488682;32364.93881205855;32904.861184022964;32915.50626740601;33005.834970824995;3177.491601343797;3307.2889328794427;3320.518524813913;3360.5799275090612;3362.4620060790157;379936;380694;380979;381198;386194.9999999972;60053.399466005656;60702;60728;60911;61039;64476.67761661162;64669.67665161738;64802.67598661977;64870.35129648737;64930.35069649338;16109.67780644404;16345.673086538203;16373.754393684105;16384.918075409547;16386.508404747878;12591.433385497674;12637.873621263854;12656.430460629294;12664.620061398173;12682.556110536087;17089.743653845202;17651.38220162288;17727.73408398875;17739.11304434799;17743.645127097385;6612.636305003175;6638.933610663831;6641.966790166115;6710.563813352116;6722;864.2826454043129;910.7814124610046;982.8034393121404;1037.3931250218707;1120.199057673751;30315.696843031288;30319.93880214182;31047.534286985712;31132.688673113433;31365.843170784;917.8393781088283;1044.8171569975375;1067.4395942130443;1091.6397588795678;1190.2025642819333;15619.297131629104;15830.12934288602;15874.047557146609;15897.68204635924;16304.858659893693;639864;641896;642822;644655;645599;61249.38750612437;61729.38270617326;61745;61773.76452470925;61872;1080.8756992945912;1354.8170996915487;1375.2023826180791;1382.1016339379485;1401.9719605607831;30986;31813.8409307952;31844.36311273808;32487;32770.033797972195;52441.475585245185;53119.73440132775;53141.7342913283;53218;53278.46721532735;499790;501731;502079.48960254964;502534;503325;34415.96752097441;41373.37939930963;42417.36373954331;42637.36043959281;42638;48612.75693621509;49456.75271623619;49745.75127124486;49757.50242497529;50354;39307.410388844764;39709.60290397059;40073;40245.59754402418;41482;39642.405363920145;39767.60232397639;40223.396649049704;41388.379174311805;41618.75143745692;39032.024199395244;40240;40398.39402408907;40432.59567404288;40481;30682.232944176576;34207.31585368229;35662.8216858914;35670.82164589161;35980.28039439249;25125.87437062803;25784.871075645253;25887;26153.953841846873;26546 19:55:19 INFO - PROCESS | 1670 | |2;jquery.html;307.22732328194587;353.32867551651844;373.9569949455812;375.5230856811849;386.90520822398497;15588.688226235468;17568.64862702747;20742.792572074275;20838.89580552095;20839.895800520993;19775.208991640317;20710.48223794406;20872.47818804527;20968.580628387423;21119.472013199676;5794.5943783935245;5833.562482813784;5869.911951320712;6034.6982650867285;6035.124906888498;5800.129980502916;5997.700114994254;6005.279366476007;6007.128966299884;6014.248218972629;4021.526761041953;4068.6338229559333;4289.1636130954475;4293.828246870137;6300.21247344082;6287.103748196297;6424.132742079806;6495.772647957322;6517.641529715864;6533.444657204143;5658.7170641467765;5659.773609055633;5667.744951477193;5671.262735844355;5675.290588676416;13311.800322995066;22024.669629955562;23335.41661458477;23566.350355475122;24671.38321541976;2327.569399661051;2334.031376978552;2391.0196819304;2460.26357134715;2716.8098233123687;9854.556544955494;10047.547860346229;10408.635697750542;10444.26890117692;10445;1613.50788009657;1846.870719049667;1894.3464504745841;1904.5429097016754;1912.139537208248;111412.4429377848;121101.36695899138;127037.45925081539;134500;137613.49546018147;9164.816703666023;9469.573869175903;10782.514786834612;11473.54105835774;11522.711932201595;14314.928425357806;14326.140431574138;14328.996970212194;14330.713385732435;14349.282535873164;8103.513789172668;8405.747827565183;8420.957895210608;8477.194666506672;8490.660373585111;8316.217214628741;8335.66657333712;8376.413651044368;8556.315494760403;8581.313494920392;4470.597646211857;4479.238529450001;4496.1906856765445;4520.434945631796;4554.0891821635805;1689.5353777711227;1847.1133855749315;1917.4343568647282;1919.894405807694;1921.1258877210835;873.921347078766;878.4070752242211;895.4537731983485;938.6151677812064;970.4225985538593;20598.498255296636;20828.375148745563;21007;21125;21299.78700213009;933.2114363362947;944.5371767833849;1009.0928145804436;1022.4223313827664;1041.3335465302212;10592.622959015278;10984.231103822656;11014.118870490502;11103.333799971866;11105.888941110647;556927;592008;596097;596879;597626;81989.59005205055;103355;119366;126857;135757;946.0492205333733;952.1525842000625;975.492743773234;999.6501224571342;1004.352192835617;17916.91041544784;18536.814631853507;18627.720584190978;18655.440336789918;18665.44003679892;55569.08275677775;56431.71784141053;57502;57544;57757.42242577521;85680.57159714411;87313.56343218243;87342;87614.24771504407;88680;7930.167332430012;7959.243871832166;8078.272955434039;8124;8133.105358410452;11272.9436352821;11552.595659152226;11990;12691.93654031724;13161.407736651681;10810.081143102925;11151.776964460827;11293.887061129284;11343.829842552203;11387.658370248906;10688.412137332522;10786;10919.181061420584;11127.998480136826;11296.43517824122;9488.051194880456;11010.944945275221;11254.94372528132;11537.250078744855;11553.595624153115;5391.676499409891;7572;7854.60726963661;8313.33493320545;8377.748667539983;9416.387934784218;10555.525001374803;10759.515821787882;10889.346639201673;11299.604513842305 19:55:19 INFO - PROCESS | 1670 | |3;mootools.html;1513.1980050573197;1547.806524184477;1699.4816580942816;1798.1189217283536;1844.5204246895812;7773.144954055049;8052.516848989065;8204.589770511473;8721.302295816335;8744.78138046549;8264.710735124272;8524.872126918086;8525.062243153248;8632.784180395493;8647.005594356651;2312.2947501012186;2351.1770880191966;2351.306364622432;2352.2707960532202;2358.3160883343808;2077.66757318829;2149.5163588192686;2309.90760369585;2335.1243283768586;2361.370654248563;2254.2448279826276;2326.4765427778784;2332.5334933013382;2334.673145759595;2335.8948847301826;1934.8548858835572;2035.669158813158;2223.499712564676;2336.9298921032396;2341.613633750429;1921.9807801922082;2310.595645762002;2313.803326717231;2318.258157389636;2321.6633588129794;8597.570121493964;8794.692185773467;8803.955980220122;8810;8821.691240806542;5117.1556693145585;5306.548943339821;5307.2569840222395;5308.893822123536;5312.256284120226;3273.3616944695796;3449.6377880322457;3499.3701133795907;3528.8412021459094;3529.2235708144167;1007.5214273220236;1013.3565186106802;1048.8846226915034;1067.9572817087308;1068.5672302717335;11429.99975004371;11506.482208300647;11510;11519.424028798527;11525.827112593317;4444.911101777946;4620.422447194101;4747.216709243005;4751.40607424072;4751.714897106184;8222.958885205535;9859.950700246596;9860.013998600227;9860.45767482781;9863.063009014133;5303.15149576066;5303.28405665238;5307.761150748225;5310.230016647564;5323.2015197719875;4702;4779.3070004849105;4800.423949126127;4858.78135483904;4864.221724524059;3870.670992965573;3874.4815869684367;3915.197384536163;3959.8416063357145;4064.227796718614;1170.4206417823232;1209.3590397089556;1261.9242845429303;1272.7136394311206;1290.9806352904714;781.5857595474407;863.9565056221426;887.9822403552022;902.5757893789909;916.6974898283551;4923.409190897115;5519.730461993764;5871.588988770831;5983.611065280743;6134.539909506716;920.3833431600843;934.9018353072966;945.8675785390051;951.9476428796482;975.059067999384;3925.666318362914;3933.9606603933594;4040.777757223381;4069.7761623110423;4096.610821971908;8145.918540814635;10642.467876606139;10642.73393165177;10645.627403041011;10646.14830813533;9406;10452.16382689383;10515.316504427188;10533.631322903662;10667.893321066846;8014.799630009296;8126.634301456449;8139.226773456512;8144.022717273847;8147.674093036212;4978.4025916890205;5739.454751798572;5771;5921.61509501881;5922.200502932049;9789.804203915739;9817.509124543602;9830.655927042519;9854.014598540089;9867.802643946938;9018.954905225432;9301.069893010645;9875.506224688881;9908.108270255712;10092.142167915516;7416.480846340704;7593.924060759322;7606.9619651901385;7608.2391760823475;7613.428992825449;8151.1441298662785;8252.00975882898;8358.038825534899;8369.372297077622;8372.539510326991;7319.34125928669;7433.591152486665;7437.405007599271;7438.516496427715;7550.8673698945595;7300.196978332486;7327.596982166032;7388.335049845539;7411.221821708643;7466.066741657293;6889.724411023503;6991.790246292416;7005.719771209095;7007.299270072952;7008;4526.479454862733;4859.1010663027455;4981.551660350586;4988.875278117931;5186.559142472932;4897.583705385082;4954.628402869727;5077.416097148727;5104.234364845154;5191.506806853343 19:55:19 INFO - PROCESS | 1670 | |4;prototype.html;301.1147227152174;301.9290466740316;313.28727145743426;321.033688597322;321.14254939312036;5293.788248470061;5305.628605997582;5360.758765855536;5395.433479484656;5417.756200970956;3880.583586990749;4939.753012349386;5027.673201241917;5155.252488389191;5304.49607287308;3375.864965401381;3705.7035437165096;3735.8318875650593;3914.921701565967;4096.640754581244;3420;3579.5167652366986;3795.9430608540893;4106.7946602669745;4158.189153115144;3114.5483904833786;3731.4589384539227;4065.6747460203114;4074.694397920168;4091.9386209206887;3402.234497238114;3760.943585846228;3803.9809800951093;3999.1801680655394;4078.4494093297617;3117.719405253538;3542.3092496963104;3547.2728090741593;3548.0131126569204;3563.875264365761;4517.932231016537;5044.47033061527;5131.050755610224;5150.819721309736;5152.304438900738;3856.7878766667823;3896.610338966109;4212.578742125794;4671.743054132022;4672.906541869178;5490.80782172622;5517.475839795213;5553.389127196006;5565.634605292735;5570.832875013756;3757.135858752475;3779.4141908004303;3920.5883382245024;3931.5085614298214;3936.665383442411;4540.8864778380475;4815.303430079097;5528.723563821833;5875.297363212927;5900.527957763368;3672.797996110186;3955.406688996616;3973.900652483711;3991.960080399217;4016.05221467588;4037.596240375939;4059.3910913362643;4068.06434520062;4069.328560787496;4078.5513593504693;3944;4312.504062032906;4471.35165401015;4660.720356778605;4721.244600863845;5510.476504732044;5521.812810245817;5570;5608.551315894719;5652.236948012048;1269.3145701321369;1308.7905935050346;1378.9241591712553;1381.6062422209623;1382.9308534573431;801.354909298009;841.5287439034161;872.9219414023676;897.2507955856933;913.7167478081683;4663.834041489627;4844.394450693663;4876.5854902333695;5081.4918508148885;5171.172612382001;4710.717356958594;5654.151877218368;5678.346990096192;5684.32312467823;5703.771849125988;4876.561109500162;5141.997310524412;5178.585713142939;5219.582433405319;5263.36839579253;3043.808457910301;3054.480738274499;3057.156844134976;3065.846707664608;3069.3247485553143;5688.715564221772;5756.366799652035;6064.332923378425;6065.3934606539;6070.817875463743;5202.791888324425;5802.564807639444;5878.412158784087;5901.878643057806;5957.612755170987;5300.734963251822;6016.759329626766;6026.337102918676;6027.21646185993;6034.487068599043;4203.537610862863;4434.667399944952;5702.8289151325525;5774.87389958954;5809.933046867125;5709.571782116275;5714.485696287188;5714.5999780015;5714.971305165111;5743.023685973478;5675;5814.069748840166;5830.62100963436;5984.551158663031;6021.488173505301;3568.232829941575;3861.4940173332984;3868.516435445569;3897.376419772823;3917.96265941703;4831.782569784438;5813.273340832396;5825.41745825431;5850.414958504116;5865.530757539472;4650.232711602548;5179.637425380338;5277;5626.212330273846;5723.885522289447;4551.5676010778925;5353;5581.916271255853;5629.943700563106;5776.595638305278;4941.555260026615;5108.974455127701;5189.9379366174135;5525.696086715269;5603.523700485504;4608.124456353282;5216.113260745607;5412.97153540826;5453.890922181614;5596.608237423341;4381.663592604286;4835.129676658235;5128.666636668605;5499.312585926759;5602.4957753802355 19:55:19 INFO - PROCESS | 1670 | |5;yui.html;562.403851916968;713.2297119111361;729.2889432803014;743.3941337809681;784.9873662974762;14707.485238016672;16002.519924402257;16064.196790160475;16087.356505739772;16169.434069807545;14446;14576.562703118896;14607.707845843077;14625.341859616316;14634.560963171121;664.5813137723228;685.5132855672479;685.7874059041688;827.0323721246126;852.9402941794077;682.1609420412896;685.1230425055936;685.7359916432159;818.7953011747063;843.4770442325736;878.6002368922125;884.1114679746878;885.831691978522;888.8711136885148;891.2958762577556;880.7093659092484;881.8192270584515;882.4220135811054;882.7395918204145;883.0463099852144;517.3068088761078;660.0495286787011;661.2428769059449;661.3948237362807;661.4543002023302;11788.528458861636;12080.97311728504;12085;12121.151519393647;13972.72054558903;13900.304984750723;14752.3361448734;14765.261736913111;14769.778453323208;14890.329935152944;10033.54849031788;11850.703732406673;11904.226225295328;11978.580749673807;12404.317762523058;3881.2237552489614;3918.804059796999;3925;4015.4579131817413;4054.3513037914086;113631.43184284108;113649.43175284153;113980.4300978498;114331.28503072461;114672;10384.117350025332;10733.141348692086;10843.457827108614;10932.890671093346;11176.27354221973;20861.895690521753;21523.78476215218;21922.013509392116;22349.888250558644;22377.328680139297;6813.761518346933;8099.190080991853;8122.228388303101;8207.712730054538;8274.089850116483;4118.19695159447;4138.275801734744;4225.704200705983;4230.61924426803;4240.618344349024;3563.590187128513;3726.4162730839826;3756.7933763642714;3758.736888417839;3759.7368184226843;615.5537235504223;626.6929204689669;632.1844820182006;681.5774219983642;719.6833393306928;592.8577141486098;617.5183356981562;631.3181763695198;655.1810237203496;664.5514277862424;8365.874511882326;8608.881251516617;8782.956085219534;9719.805603887882;9903.8898856975;645.6771614192903;645.7513857164931;650.5738741124571;666.6167702018234;685.4379408884666;4268.786560671954;4482.731036137842;4709.387779588632;4745.311929770164;4862.95137048625;90351.54824225968;97034;109754;137820.24359512754;162331;131711.34144329408;133499;134400;137472.9379059315;140276;108873;110844;111609.44195278971;112764;113627;9418.058194180527;9588.137067663665;9719.125278724949;9746.902530974883;9878.357906736039;56992.86014279774;59169.40830591639;60102.39897600968;60789;61146.694266528386;89355.55322223347;91766.54116729373;91964;92259;93700.06299936914;12854.421551030038;13186.538471153464;13195.934020330222;13232.801507977196;13623.931880340535;14776.556703298918;14968.700625987201;14979.550613481613;15072.698546029238;15134.167620780963;13630.659233519242;13643.658908527366;14047.157170569799;14165.858341416453;14197.219152946274;13953.162810231419;13954.023218374616;13959;14173.936954728642;14387.424503019762;12914.547990820653;12996.155249908727;13646.385912633757;14060.226687532284;14193.219372934587;9732.756681082747;10195.184385249015;10679.359238445408;11737.941310293736;12104.213226140273;9787.21702263803;10430.739231519272;10494.580216791242;10867.602559650652;11596.652100437 19:55:19 INFO - PROCESS | 1670 | __end_tp_report 19:55:19 INFO - PROCESS | 1670 | __start_cc_report 19:55:19 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1314 19:55:19 INFO - PROCESS | 1670 | __end_cc_report 19:55:19 INFO - PROCESS | 1670 | __startTimestamp1471056919951__endTimestamp 19:55:19 INFO - PROCESS | 1670 | 19:55:19 INFO - PROCESS | 1670 | ------- Summary: start ------- 19:55:19 INFO - PROCESS | 1670 | Number of tests: 6 19:55:19 INFO - PROCESS | 1670 | 19:55:19 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5731.86 Median:5914.85 stddev:665.44 (11.3%) stddev-sans-first:622.68 19:55:19 INFO - PROCESS | 1670 | Values: 2671.7 2945.6 3199.4 3390.1 3401.9 5573.1 5639.6 5690.7 5760.6 5771.4 5709.3 5759.8 5762.3 5769.5 5777.8 5177.8 5242.3 5244.3 5274.2 5316.0 5282.7 5323.7 5323.9 5326.4 5327.4 5196.8 5209.3 5210.1 5225.4 5294.7 5274.9 5304.3 5317.9 5324.7 5369.8 5144.3 5157.1 5173.4 5232.7 5242.6 5628.9 5635.3 5671.9 5688.5 5719.4 6201.6 6206.5 6235.4 6239.2 6244.2 5643.6 5945.0 6092.8 6100.8 6181.8 5881.5 5883.8 5896.4 5914.9 5916.6 6285.9 6293.2 6298.0 6300.4 6310.2 6062.7 6094.7 6117.3 6180.7 6199.5 6201.6 6201.7 6218.8 6224.1 6227.4 6154.0 6202.8 6214.3 6225.3 6229.2 5512.7 5786.2 6121.3 6196.1 6224.4 4892.4 4925.9 4945.7 4950.7 4997.6 4444.6 4445.1 4445.8 4447.1 4483.0 5625.5 5664.6 5669.3 5749.9 5750.0 6198.9 6304.2 6314.6 6333.1 6338.7 5720.4 5725.9 5729.9 5742.2 5784.2 5294.5 5350.6 5360.9 5405.4 5410.3 6241.2 6276.4 6285.7 6339.7 6340.4 6314.7 6319.7 6338.1 6346.2 6353.3 6235.5 6240.4 6291.1 6307.8 6316.7 6120.0 6124.3 6178.7 6281.4 6291.9 6319.9 6323.3 6324.1 6330.1 6341.3 5872.0 6173.4 6174.1 6184.5 6190.1 5956.3 6124.1 6127.7 6149.2 6156.8 5103.3 5588.6 5961.3 6015.8 6024.8 6049.1 6049.5 6077.1 6124.2 6130.0 4832.9 5868.3 5911.9 5950.0 5978.7 19:55:19 INFO - PROCESS | 1670 | 19:55:19 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:66370.29 Median:42056.58 stddev:138608.55 (329.6%) stddev-sans-first:138908.99 19:55:19 INFO - PROCESS | 1670 | Values: 441.2 464.5 520.8 521.7 527.3 30781.5 40866.0 42414.8 42450.6 44341.3 44001.9 44381.9 44436.8 44451.7 44464.3 9757.3 9935.0 9945.0 9948.6 9954.8 9701.6 9783.1 9789.1 9798.7 9828.0 9726.2 9788.9 9831.8 9855.4 9862.9 9719.1 9797.2 9797.8 9807.8 9808.9 9766.8 9846.5 9853.0 9868.8 9870.7 41821.0 42045.4 42067.8 42151.6 42170.0 3381.3 3429.5 3511.8 3922.4 4325.0 31654.1 32364.9 32904.9 32915.5 33005.8 3177.5 3307.3 3320.5 3360.6 3362.5 379936.0 380694.0 380979.0 381198.0 386195.0 60053.4 60702.0 60728.0 60911.0 61039.0 64476.7 64669.7 64802.7 64870.4 64930.4 16109.7 16345.7 16373.8 16384.9 16386.5 12591.4 12637.9 12656.4 12664.6 12682.6 17089.7 17651.4 17727.7 17739.1 17743.6 6612.6 6638.9 6642.0 6710.6 6722.0 864.3 910.8 982.8 1037.4 1120.2 30315.7 30319.9 31047.5 31132.7 31365.8 917.8 1044.8 1067.4 1091.6 1190.2 15619.3 15830.1 15874.0 15897.7 16304.9 639864.0 641896.0 642822.0 644655.0 645599.0 61249.4 61729.4 61745.0 61773.8 61872.0 1080.9 1354.8 1375.2 1382.1 1402.0 30986.0 31813.8 31844.4 32487.0 32770.0 52441.5 53119.7 53141.7 53218.0 53278.5 499790.0 501731.0 502079.5 502534.0 503325.0 34416.0 41373.4 42417.4 42637.4 42638.0 48612.8 49456.8 49745.8 49757.5 50354.0 39307.4 39709.6 40073.0 40245.6 41482.0 39642.4 39767.6 40223.4 41388.4 41618.8 39032.0 40240.0 40398.4 40432.6 40481.0 30682.2 34207.3 35662.8 35670.8 35980.3 25125.9 25784.9 25887.0 26154.0 26546.0 19:55:19 INFO - PROCESS | 1670 | 19:55:19 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:34703.41 Median:13565.82 stddev:98361.85 (725.1%) stddev-sans-first:98603.87 19:55:19 INFO - PROCESS | 1670 | Values: 307.2 353.3 374.0 375.5 386.9 15588.7 17568.6 20742.8 20838.9 20839.9 19775.2 20710.5 20872.5 20968.6 21119.5 5794.6 5833.6 5869.9 6034.7 6035.1 5800.1 5997.7 6005.3 6007.1 6014.2 4021.5 4068.6 4289.2 4293.8 6300.2 6287.1 6424.1 6495.8 6517.6 6533.4 5658.7 5659.8 5667.7 5671.3 5675.3 13311.8 22024.7 23335.4 23566.4 24671.4 2327.6 2334.0 2391.0 2460.3 2716.8 9854.6 10047.5 10408.6 10444.3 10445.0 1613.5 1846.9 1894.3 1904.5 1912.1 111412.4 121101.4 127037.5 134500.0 137613.5 9164.8 9469.6 10782.5 11473.5 11522.7 14314.9 14326.1 14329.0 14330.7 14349.3 8103.5 8405.7 8421.0 8477.2 8490.7 8316.2 8335.7 8376.4 8556.3 8581.3 4470.6 4479.2 4496.2 4520.4 4554.1 1689.5 1847.1 1917.4 1919.9 1921.1 873.9 878.4 895.5 938.6 970.4 20598.5 20828.4 21007.0 21125.0 21299.8 933.2 944.5 1009.1 1022.4 1041.3 10592.6 10984.2 11014.1 11103.3 11105.9 556927.0 592008.0 596097.0 596879.0 597626.0 81989.6 103355.0 119366.0 126857.0 135757.0 946.0 952.2 975.5 999.7 1004.4 17916.9 18536.8 18627.7 18655.4 18665.4 55569.1 56431.7 57502.0 57544.0 57757.4 85680.6 87313.6 87342.0 87614.2 88680.0 7930.2 7959.2 8078.3 8124.0 8133.1 11272.9 11552.6 11990.0 12691.9 13161.4 10810.1 11151.8 11293.9 11343.8 11387.7 10688.4 10786.0 10919.2 11128.0 11296.4 9488.1 11010.9 11254.9 11537.3 11553.6 5391.7 7572.0 7854.6 8313.3 8377.7 9416.4 10555.5 10759.5 10889.3 11299.6 19:55:19 INFO - PROCESS | 1670 | 19:55:19 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5616.13 Median:5189.03 stddev:3121.58 (60.2%) stddev-sans-first:3115.11 19:55:19 INFO - PROCESS | 1670 | Values: 1513.2 1547.8 1699.5 1798.1 1844.5 7773.1 8052.5 8204.6 8721.3 8744.8 8264.7 8524.9 8525.1 8632.8 8647.0 2312.3 2351.2 2351.3 2352.3 2358.3 2077.7 2149.5 2309.9 2335.1 2361.4 2254.2 2326.5 2332.5 2334.7 2335.9 1934.9 2035.7 2223.5 2336.9 2341.6 1922.0 2310.6 2313.8 2318.3 2321.7 8597.6 8794.7 8804.0 8810.0 8821.7 5117.2 5306.5 5307.3 5308.9 5312.3 3273.4 3449.6 3499.4 3528.8 3529.2 1007.5 1013.4 1048.9 1068.0 1068.6 11430.0 11506.5 11510.0 11519.4 11525.8 4444.9 4620.4 4747.2 4751.4 4751.7 8223.0 9860.0 9860.0 9860.5 9863.1 5303.2 5303.3 5307.8 5310.2 5323.2 4702.0 4779.3 4800.4 4858.8 4864.2 3870.7 3874.5 3915.2 3959.8 4064.2 1170.4 1209.4 1261.9 1272.7 1291.0 781.6 864.0 888.0 902.6 916.7 4923.4 5519.7 5871.6 5983.6 6134.5 920.4 934.9 945.9 951.9 975.1 3925.7 3934.0 4040.8 4069.8 4096.6 8145.9 10642.5 10642.7 10645.6 10646.1 9406.0 10452.2 10515.3 10533.6 10667.9 8014.8 8126.6 8139.2 8144.0 8147.7 4978.4 5739.5 5771.0 5921.6 5922.2 9789.8 9817.5 9830.7 9854.0 9867.8 9019.0 9301.1 9875.5 9908.1 10092.1 7416.5 7593.9 7607.0 7608.2 7613.4 8151.1 8252.0 8358.0 8369.4 8372.5 7319.3 7433.6 7437.4 7438.5 7550.9 7300.2 7327.6 7388.3 7411.2 7466.1 6889.7 6991.8 7005.7 7007.3 7008.0 4526.5 4859.1 4981.6 4988.9 5186.6 4897.6 4954.6 5077.4 5104.2 5191.5 19:55:19 INFO - PROCESS | 1670 | 19:55:19 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4501.91 Median:5062.98 stddev:1392.79 (27.5%) stddev-sans-first:1360.54 19:55:19 INFO - PROCESS | 1670 | Values: 301.1 301.9 313.3 321.0 321.1 5293.8 5305.6 5360.8 5395.4 5417.8 3880.6 4939.8 5027.7 5155.3 5304.5 3375.9 3705.7 3735.8 3914.9 4096.6 3420.0 3579.5 3795.9 4106.8 4158.2 3114.5 3731.5 4065.7 4074.7 4091.9 3402.2 3760.9 3804.0 3999.2 4078.4 3117.7 3542.3 3547.3 3548.0 3563.9 4517.9 5044.5 5131.1 5150.8 5152.3 3856.8 3896.6 4212.6 4671.7 4672.9 5490.8 5517.5 5553.4 5565.6 5570.8 3757.1 3779.4 3920.6 3931.5 3936.7 4540.9 4815.3 5528.7 5875.3 5900.5 3672.8 3955.4 3973.9 3992.0 4016.1 4037.6 4059.4 4068.1 4069.3 4078.6 3944.0 4312.5 4471.4 4660.7 4721.2 5510.5 5521.8 5570.0 5608.6 5652.2 1269.3 1308.8 1378.9 1381.6 1382.9 801.4 841.5 872.9 897.3 913.7 4663.8 4844.4 4876.6 5081.5 5171.2 4710.7 5654.2 5678.3 5684.3 5703.8 4876.6 5142.0 5178.6 5219.6 5263.4 3043.8 3054.5 3057.2 3065.8 3069.3 5688.7 5756.4 6064.3 6065.4 6070.8 5202.8 5802.6 5878.4 5901.9 5957.6 5300.7 6016.8 6026.3 6027.2 6034.5 4203.5 4434.7 5702.8 5774.9 5809.9 5709.6 5714.5 5714.6 5715.0 5743.0 5675.0 5814.1 5830.6 5984.6 6021.5 3568.2 3861.5 3868.5 3897.4 3918.0 4831.8 5813.3 5825.4 5850.4 5865.5 4650.2 5179.6 5277.0 5626.2 5723.9 4551.6 5353.0 5581.9 5629.9 5776.6 4941.6 5109.0 5189.9 5525.7 5603.5 4608.1 5216.1 5413.0 5453.9 5596.6 4381.7 4835.1 5128.7 5499.3 5602.5 19:55:19 INFO - PROCESS | 1670 | 19:55:19 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:24295.02 Median:3820.48 stddev:38342.55 (1003.6%) stddev-sans-first:38408.70 19:55:19 INFO - PROCESS | 1670 | Values: 562.4 713.2 729.3 743.4 785.0 14707.5 16002.5 16064.2 16087.4 16169.4 14446.0 14576.6 14607.7 14625.3 14634.6 664.6 685.5 685.8 827.0 852.9 682.2 685.1 685.7 818.8 843.5 878.6 884.1 885.8 888.9 891.3 880.7 881.8 882.4 882.7 883.0 517.3 660.0 661.2 661.4 661.5 11788.5 12081.0 12085.0 12121.2 13972.7 13900.3 14752.3 14765.3 14769.8 14890.3 10033.5 11850.7 11904.2 11978.6 12404.3 3881.2 3918.8 3925.0 4015.5 4054.4 113631.4 113649.4 113980.4 114331.3 114672.0 10384.1 10733.1 10843.5 10932.9 11176.3 20861.9 21523.8 21922.0 22349.9 22377.3 6813.8 8099.2 8122.2 8207.7 8274.1 4118.2 4138.3 4225.7 4230.6 4240.6 3563.6 3726.4 3756.8 3758.7 3759.7 615.6 626.7 632.2 681.6 719.7 592.9 617.5 631.3 655.2 664.6 8365.9 8608.9 8783.0 9719.8 9903.9 645.7 645.8 650.6 666.6 685.4 4268.8 4482.7 4709.4 4745.3 4863.0 90351.5 97034.0 109754.0 137820.2 162331.0 131711.3 133499.0 134400.0 137472.9 140276.0 108873.0 110844.0 111609.4 112764.0 113627.0 9418.1 9588.1 9719.1 9746.9 9878.4 56992.9 59169.4 60102.4 60789.0 61146.7 89355.6 91766.5 91964.0 92259.0 93700.1 12854.4 13186.5 13195.9 13232.8 13623.9 14776.6 14968.7 14979.6 15072.7 15134.2 13630.7 13643.7 14047.2 14165.9 14197.2 13953.2 13954.0 13959.0 14173.9 14387.4 12914.5 12996.2 13646.4 14060.2 14193.2 9732.8 10195.2 10679.4 11737.9 12104.2 9787.2 10430.7 10494.6 10867.6 11596.7 19:55:19 INFO - PROCESS | 1670 | -------- Summary: end -------- 19:55:19 INFO - PROCESS | 1670 | 19:55:20 INFO - TEST-INFO | 1670: exit 0 19:55:20 INFO - TEST-OK | dromaeo_css | took 1117326ms 19:55:20 INFO - TEST-START | kraken 19:55:20 INFO - operating with platform_type : mac_ 19:55:20 INFO - Initialising browser for kraken test... 19:55:20 INFO - TEST-INFO | started process 1790 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpE2YGdH/profile http://localhost:49276/getInfo.html) 19:55:22 INFO - PROCESS | 1790 | __metrics Screen width/height:1600/1200 19:55:22 INFO - PROCESS | 1790 | colorDepth:24 19:55:22 INFO - PROCESS | 1790 | Browser inner width/height: 1024/689 19:55:22 INFO - PROCESS | 1790 | __metrics 19:55:22 INFO - PROCESS | 1790 | 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" 19:55:22 INFO - PROCESS | 1790 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:55:23 INFO - TEST-INFO | 1790: exit 0 19:55:23 INFO - Browser initialized. 19:55:23 INFO - Running cycle 1/1 for kraken test... 19:55:23 INFO - TEST-INFO | started process 1791 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpE2YGdH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:55:35 INFO - PROCESS | 1791 | RSS: Main: 243458048 19:55:35 INFO - PROCESS | 1791 | 19:55:38 INFO - PROCESS | 1791 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 19:55:39 INFO - PROCESS | 1791 | RSS: Main: 293711872 19:55:39 INFO - PROCESS | 1791 | 19:55:41 INFO - PROCESS | 1791 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 19:55:42 INFO - PROCESS | 1791 | RSS: Main: 306081792 19:55:42 INFO - PROCESS | 1791 | 19:55:46 INFO - PROCESS | 1791 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 19:55:46 INFO - PROCESS | 1791 | RSS: Main: 392511488 19:55:46 INFO - PROCESS | 1791 | 19:55:48 INFO - PROCESS | 1791 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 19:55:49 INFO - PROCESS | 1791 | RSS: Main: 300023808 19:55:49 INFO - PROCESS | 1791 | 19:55:51 INFO - PROCESS | 1791 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:55:52 INFO - PROCESS | 1791 | RSS: Main: 304443392 19:55:52 INFO - PROCESS | 1791 | 19:55:54 INFO - PROCESS | 1791 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 19:55:55 INFO - PROCESS | 1791 | RSS: Main: 340320256 19:55:55 INFO - PROCESS | 1791 | 19:55:58 INFO - PROCESS | 1791 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 19:55:59 INFO - PROCESS | 1791 | RSS: Main: 341700608 19:55:59 INFO - PROCESS | 1791 | 19:56:01 INFO - PROCESS | 1791 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 19:56:02 INFO - PROCESS | 1791 | RSS: Main: 343552000 19:56:02 INFO - PROCESS | 1791 | 19:56:04 INFO - PROCESS | 1791 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:56:04 INFO - PROCESS | 1791 | RSS: Main: 314646528 19:56:04 INFO - PROCESS | 1791 | 19:56:06 INFO - PROCESS | 1791 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:56:07 INFO - PROCESS | 1791 | RSS: Main: 319913984 19:56:07 INFO - PROCESS | 1791 | 19:56:09 INFO - PROCESS | 1791 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:56:10 INFO - PROCESS | 1791 | RSS: Main: 326758400 19:56:10 INFO - PROCESS | 1791 | 19:56:13 INFO - PROCESS | 1791 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:56:13 INFO - PROCESS | 1791 | RSS: Main: 331214848 19:56:13 INFO - PROCESS | 1791 | 19:56:16 INFO - PROCESS | 1791 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:56:17 INFO - PROCESS | 1791 | RSS: Main: 332328960 19:56:17 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 19:56:19 INFO - PROCESS | 1791 | RSS: Main: 376012800 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | __start_tp_report 19:56:19 INFO - PROCESS | 1791 | _x_x_mozilla_page_load 19:56:19 INFO - PROCESS | 1791 | _x_x_mozilla_page_load_details 19:56:19 INFO - PROCESS | 1791 | |i|pagename|runs| 19:56:19 INFO - PROCESS | 1791 | |0;ai-astar;102;160;164;96;111;142;163;121;112;111 19:56:19 INFO - PROCESS | 1791 | |1;audio-beat-detection;144;106;109;166;107;107;129;110;173;138 19:56:19 INFO - PROCESS | 1791 | |2;audio-dft;192;259;185;183;183;179;181;181;258;190 19:56:19 INFO - PROCESS | 1791 | |3;audio-fft;79;58;63;85;60;57;63;67;85;55 19:56:19 INFO - PROCESS | 1791 | |4;audio-oscillator;84;68;101;72;75;67;97;70;67;127 19:56:19 INFO - PROCESS | 1791 | |5;imaging-gaussian-blur;85;84;86;83;87;86;134;83;86;83 19:56:19 INFO - PROCESS | 1791 | |6;imaging-darkroom;95;132;148;122;92;93;93;93;93;93 19:56:19 INFO - PROCESS | 1791 | |7;imaging-desaturate;85;84;90;122;84;84;85;83;84;86 19:56:19 INFO - PROCESS | 1791 | |8;json-parse-financial;54;52;53;52;51;52;54;53;53;53 19:56:19 INFO - PROCESS | 1791 | |9;json-stringify-tinderbox;68;68;78;108;112;105;72;74;74;72 19:56:19 INFO - PROCESS | 1791 | |10;stanford-crypto-aes;88;87;84;87;88;87;86;89;89;118 19:56:19 INFO - PROCESS | 1791 | |11;stanford-crypto-ccm;149;156;146;142;153;134;139;139;151;140 19:56:19 INFO - PROCESS | 1791 | |12;stanford-crypto-pbkdf2;183;183;182;181;181;182;184;182;182;189 19:56:19 INFO - PROCESS | 1791 | |13;stanford-crypto-sha256-iterative;70;69;64;70;68;68;67;69;68;67 19:56:19 INFO - PROCESS | 1791 | __end_tp_report 19:56:19 INFO - PROCESS | 1791 | __start_cc_report 19:56:19 INFO - PROCESS | 1791 | _x_x_mozilla_cycle_collect,2019 19:56:19 INFO - PROCESS | 1791 | __end_cc_report 19:56:19 INFO - PROCESS | 1791 | __startTimestamp1471056979478__endTimestamp 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | ------- Summary: start ------- 19:56:19 INFO - PROCESS | 1791 | Number of tests: 14 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | [#0] ai-astar Cycles:10 Average:128.20 Median:151.00 stddev:26.51 (17.6%) stddev-sans-first:26.36 19:56:19 INFO - PROCESS | 1791 | Values: 102.0 160.0 164.0 96.0 111.0 142.0 163.0 121.0 112.0 111.0 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | [#1] audio-beat-detection Cycles:10 Average:128.90 Median:133.50 stddev:25.54 (19.1%) stddev-sans-first:26.49 19:56:19 INFO - PROCESS | 1791 | Values: 144.0 106.0 109.0 166.0 107.0 107.0 129.0 110.0 173.0 138.0 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | [#2] audio-dft Cycles:10 Average:199.10 Median:187.50 stddev:31.56 (16.8%) stddev-sans-first:33.37 19:56:19 INFO - PROCESS | 1791 | Values: 192.0 259.0 185.0 183.0 183.0 179.0 181.0 181.0 258.0 190.0 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | [#3] audio-fft Cycles:10 Average:67.20 Median:65.00 stddev:11.54 (17.7%) stddev-sans-first:11.42 19:56:19 INFO - PROCESS | 1791 | Values: 79.0 58.0 63.0 85.0 60.0 57.0 63.0 67.0 85.0 55.0 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | [#4] audio-oscillator Cycles:10 Average:82.80 Median:71.00 stddev:19.85 (28.0%) stddev-sans-first:21.05 19:56:19 INFO - PROCESS | 1791 | Values: 84.0 68.0 101.0 72.0 75.0 67.0 97.0 70.0 67.0 127.0 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:85.50 stddev:15.64 (18.3%) stddev-sans-first:16.49 19:56:19 INFO - PROCESS | 1791 | Values: 85.0 84.0 86.0 83.0 87.0 86.0 134.0 83.0 86.0 83.0 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | [#6] imaging-darkroom Cycles:10 Average:105.40 Median:93.00 stddev:20.69 (22.3%) stddev-sans-first:21.61 19:56:19 INFO - PROCESS | 1791 | Values: 95.0 132.0 148.0 122.0 92.0 93.0 93.0 93.0 93.0 93.0 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | [#7] imaging-desaturate Cycles:10 Average:88.70 Median:84.50 stddev:11.86 (14.0%) stddev-sans-first:12.50 19:56:19 INFO - PROCESS | 1791 | Values: 85.0 84.0 90.0 122.0 84.0 84.0 85.0 83.0 84.0 86.0 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:0.95 (1.8%) stddev-sans-first:0.88 19:56:19 INFO - PROCESS | 1791 | Values: 54.0 52.0 53.0 52.0 51.0 52.0 54.0 53.0 53.0 53.0 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | [#9] json-stringify-tinderbox Cycles:10 Average:83.10 Median:72.00 stddev:17.73 (24.6%) stddev-sans-first:17.94 19:56:19 INFO - PROCESS | 1791 | Values: 68.0 68.0 78.0 108.0 112.0 105.0 72.0 74.0 74.0 72.0 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:87.50 stddev:9.84 (11.3%) stddev-sans-first:10.41 19:56:19 INFO - PROCESS | 1791 | Values: 88.0 87.0 84.0 87.0 88.0 87.0 86.0 89.0 89.0 118.0 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | [#11] stanford-crypto-ccm Cycles:10 Average:144.90 Median:147.50 stddev:7.19 (4.9%) stddev-sans-first:7.47 19:56:19 INFO - PROCESS | 1791 | Values: 149.0 156.0 146.0 142.0 153.0 134.0 139.0 139.0 151.0 140.0 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.90 Median:182.50 stddev:2.33 (1.3%) stddev-sans-first:2.47 19:56:19 INFO - PROCESS | 1791 | Values: 183.0 183.0 182.0 181.0 181.0 182.0 184.0 182.0 182.0 189.0 19:56:19 INFO - PROCESS | 1791 | 19:56:19 INFO - PROCESS | 1791 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.50 stddev:1.76 (2.6%) stddev-sans-first:1.72 19:56:19 INFO - PROCESS | 1791 | Values: 70.0 69.0 64.0 70.0 68.0 68.0 67.0 69.0 68.0 67.0 19:56:19 INFO - PROCESS | 1791 | -------- Summary: end -------- 19:56:19 INFO - PROCESS | 1791 | 19:56:20 INFO - TEST-INFO | 1791: exit 0 19:56:20 INFO - TEST-OK | kraken | took 59716ms 19:56:20 INFO - SUITE-END | took 1177s 19:56:20 INFO - Completed test suite (00:19:37) 19:56:20 INFO - javascript benchmark 19:56:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2671.6927553331366, 2945.617069780928, 3199.408109499742, 3390.0846771371716, 3401.9319613607713, 5573.136163894603, 5639.633423827453, 5690.7439165237665, 5760.567957403196, 5771.36514983352, 5709.314882214129, 5759.827205183851, 5762.308522977238, 5769.480746732804, 5777.768889244425, 5177.844664660057, 5242.3184985951775, 5244.265802787613, 5274.182501712222, 5315.963387139509, 5282.656627319212, 5323.653962492445, 5323.866903327409, 5326.387465441466, 5327.440618735035, 5196.818111366103, 5209.32278803755, 5210.1403268460845, 5225.372955245357, 5294.655847369909, 5274.894502109955, 5304.257403963448, 5317.9202311965355, 5324.7071411072375, 5369.785208591652, 5144.305518754958, 5157.097507936133, 5173.405058418255, 5232.6598771080135, 5242.60680448964, 5628.915566266509, 5635.295588051494, 5671.858203544944, 5688.516476099537, 5719.428057194247, 6201.596896201732, 6206.472449841769, 6235.376462353774, 6239.220097487814, 6244.188255526798, 5643.6049476536655, 5945.019071853139, 6092.756289748405, 6100.793122958854, 6181.752729890799, 5881.500072493886, 5883.794067207627, 5896.439838215406, 5914.852128696731, 5916.556258280646, 6285.874282514415, 6293.181886354836, 6298.0, 6300.369963003663, 6310.211223597051, 6062.727177277033, 6094.664793436404, 6117.265928088658, 6180.6600636965395, 6199.535034872402, 6201.627902325875, 6201.658908759971, 6218.782342618078, 6224.097505861625, 6227.439530442191, 6154.0, 6202.844928876814, 6214.347493513206, 6225.346338634468, 6229.190205273376, 5512.6692398456225, 5786.1610066540115, 6121.331035925228, 6196.10156527301, 6224.37756224374, 4892.437369702458, 4925.852224433273, 4945.703257804544, 4950.702957822542, 4997.600191984632, 4444.577765112309, 4445.088756804912, 4445.755483448376, 4447.066116115588, 4482.977585112054, 5625.54995600351, 5664.575156863251, 5669.319681638147, 5749.8562535936935, 5749.9712501437225, 6198.938010619926, 6304.243490781136, 6314.557980941382, 6333.081703153017, 6338.7464501420345, 5720.427957204246, 5725.912076705413, 5729.942700573024, 5742.196092547047, 5784.161296612052, 5294.470552944752, 5350.571954243651, 5360.946390536123, 5405.351357837085, 5410.269613602113, 6241.21984751906, 6276.435120839055, 6285.654289014148, 6339.683015849097, 6340.4293613575, 6314.747410103544, 6319.652419116993, 6338.112664227104, 6346.2067241594805, 6353.3011368748475, 6235.532335074797, 6240.375962403723, 6291.1192433058495, 6307.810765677037, 6316.7157477914525, 6120.0205129226715, 6124.289394098022, 6178.691065446799, 6281.434670879643, 6291.905621415591, 6319.936800631935, 6323.336049714714, 6324.083007963912, 6330.113784070326, 6341.334159913143, 5871.970640146772, 6173.444390004742, 6174.104754810617, 6184.5052395809125, 6190.102435146789, 5956.315023772322, 6124.112003759341, 6127.7393367763425, 6149.169862068742, 6156.815295541141, 5103.3110530077965, 5588.608797384144, 5961.254843144607, 6015.819525414245, 6024.819255422344, 6049.124964666354, 6049.516038716804, 6077.058056001327, 6124.173236613, 6129.969350153399, 4832.8791780205775, 5868.2664666916635, 5911.881762364815, 5950.0, 5978.671173085522], "value": 5686.1065219077245, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [441.1728009981283, 464.4960218163293, 520.8333333333334, 521.7260938007546, 527.2855281094118, 30781.538276925836, 40866.0, 42414.7879260604, 42450.57549424509, 44341.33487997683, 44001.899952501204, 44381.8904527387, 44436.77781611095, 44451.66645000655, 44464.3330350045, 9757.316987810857, 9934.956829532903, 9945.0, 9948.60205591779, 9954.800903981915, 9701.563429645648, 9783.119519243266, 9789.118979291863, 9798.706038818847, 9828.017198280151, 9726.221902247838, 9788.902110978906, 9831.803363932717, 9855.408675479493, 9862.852057219148, 9719.076687714656, 9797.216222702167, 9797.804043919117, 9807.754806129904, 9808.852867206997, 9766.804663906682, 9846.458444785534, 9853.0, 9868.802623947553, 9870.654527091518, 41821.0, 42045.36931946023, 42067.7896610515, 42151.578484215075, 42170.0, 3381.2899291148888, 3429.5198672185916, 3511.7541772075965, 3922.352811786052, 4325.048489332342, 31654.050378488682, 32364.93881205855, 32904.861184022964, 32915.50626740601, 33005.834970824995, 3177.491601343797, 3307.2889328794427, 3320.518524813913, 3360.5799275090612, 3362.4620060790157, 379936.0, 380694.0, 380979.0, 381198.0, 386194.9999999972, 60053.399466005656, 60702.0, 60728.0, 60911.0, 61039.0, 64476.67761661162, 64669.67665161738, 64802.67598661977, 64870.35129648737, 64930.35069649338, 16109.67780644404, 16345.673086538203, 16373.754393684105, 16384.918075409547, 16386.508404747878, 12591.433385497674, 12637.873621263854, 12656.430460629294, 12664.620061398173, 12682.556110536087, 17089.743653845202, 17651.38220162288, 17727.73408398875, 17739.11304434799, 17743.645127097385, 6612.636305003175, 6638.933610663831, 6641.966790166115, 6710.563813352116, 6722.0, 864.2826454043129, 910.7814124610046, 982.8034393121404, 1037.3931250218707, 1120.199057673751, 30315.696843031288, 30319.93880214182, 31047.534286985712, 31132.688673113433, 31365.843170784, 917.8393781088283, 1044.8171569975375, 1067.4395942130443, 1091.6397588795678, 1190.2025642819333, 15619.297131629104, 15830.12934288602, 15874.047557146609, 15897.68204635924, 16304.858659893693, 639864.0, 641896.0, 642822.0, 644655.0, 645599.0, 61249.38750612437, 61729.38270617326, 61745.0, 61773.76452470925, 61872.0, 1080.8756992945912, 1354.8170996915487, 1375.2023826180791, 1382.1016339379485, 1401.9719605607831, 30986.0, 31813.8409307952, 31844.36311273808, 32487.0, 32770.033797972195, 52441.475585245185, 53119.73440132775, 53141.7342913283, 53218.0, 53278.46721532735, 499790.0, 501731.0, 502079.48960254964, 502534.0, 503325.0, 34415.96752097441, 41373.37939930963, 42417.36373954331, 42637.36043959281, 42638.0, 48612.75693621509, 49456.75271623619, 49745.75127124486, 49757.50242497529, 50354.0, 39307.410388844764, 39709.60290397059, 40073.0, 40245.59754402418, 41482.0, 39642.405363920145, 39767.60232397639, 40223.396649049704, 41388.379174311805, 41618.75143745692, 39032.024199395244, 40240.0, 40398.39402408907, 40432.59567404288, 40481.0, 30682.232944176576, 34207.31585368229, 35662.8216858914, 35670.82164589161, 35980.28039439249, 25125.87437062803, 25784.871075645253, 25887.0, 26153.953841846873, 26546.0], "value": 20369.536651698716, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.22732328194587, 353.32867551651844, 373.9569949455812, 375.5230856811849, 386.90520822398497, 15588.688226235468, 17568.64862702747, 20742.792572074275, 20838.89580552095, 20839.895800520993, 19775.208991640317, 20710.48223794406, 20872.47818804527, 20968.580628387423, 21119.472013199676, 5794.5943783935245, 5833.562482813784, 5869.911951320712, 6034.6982650867285, 6035.124906888498, 5800.129980502916, 5997.700114994254, 6005.279366476007, 6007.128966299884, 6014.248218972629, 4021.526761041953, 4068.6338229559333, 4289.1636130954475, 4293.828246870137, 6300.21247344082, 6287.103748196297, 6424.132742079806, 6495.772647957322, 6517.641529715864, 6533.444657204143, 5658.7170641467765, 5659.773609055633, 5667.744951477193, 5671.262735844355, 5675.290588676416, 13311.800322995066, 22024.669629955562, 23335.41661458477, 23566.350355475122, 24671.38321541976, 2327.569399661051, 2334.031376978552, 2391.0196819304, 2460.26357134715, 2716.8098233123687, 9854.556544955494, 10047.547860346229, 10408.635697750542, 10444.26890117692, 10445.0, 1613.50788009657, 1846.870719049667, 1894.3464504745841, 1904.5429097016754, 1912.139537208248, 111412.4429377848, 121101.36695899138, 127037.45925081539, 134500.0, 137613.49546018147, 9164.816703666023, 9469.573869175903, 10782.514786834612, 11473.54105835774, 11522.711932201595, 14314.928425357806, 14326.140431574138, 14328.996970212194, 14330.713385732435, 14349.282535873164, 8103.513789172668, 8405.747827565183, 8420.957895210608, 8477.194666506672, 8490.660373585111, 8316.217214628741, 8335.66657333712, 8376.413651044368, 8556.315494760403, 8581.313494920392, 4470.597646211857, 4479.238529450001, 4496.1906856765445, 4520.434945631796, 4554.0891821635805, 1689.5353777711227, 1847.1133855749315, 1917.4343568647282, 1919.894405807694, 1921.1258877210835, 873.921347078766, 878.4070752242211, 895.4537731983485, 938.6151677812064, 970.4225985538593, 20598.498255296636, 20828.375148745563, 21007.0, 21125.0, 21299.78700213009, 933.2114363362947, 944.5371767833849, 1009.0928145804436, 1022.4223313827664, 1041.3335465302212, 10592.622959015278, 10984.231103822656, 11014.118870490502, 11103.333799971866, 11105.888941110647, 556927.0, 592008.0, 596097.0, 596879.0, 597626.0, 81989.59005205055, 103355.0, 119366.0, 126857.0, 135757.0, 946.0492205333733, 952.1525842000625, 975.492743773234, 999.6501224571342, 1004.352192835617, 17916.91041544784, 18536.814631853507, 18627.720584190978, 18655.440336789918, 18665.44003679892, 55569.08275677775, 56431.71784141053, 57502.0, 57544.0, 57757.42242577521, 85680.57159714411, 87313.56343218243, 87342.0, 87614.24771504407, 88680.0, 7930.167332430012, 7959.243871832166, 8078.272955434039, 8124.0, 8133.105358410452, 11272.9436352821, 11552.595659152226, 11990.0, 12691.93654031724, 13161.407736651681, 10810.081143102925, 11151.776964460827, 11293.887061129284, 11343.829842552203, 11387.658370248906, 10688.412137332522, 10786.0, 10919.181061420584, 11127.998480136826, 11296.43517824122, 9488.051194880456, 11010.944945275221, 11254.94372528132, 11537.250078744855, 11553.595624153115, 5391.676499409891, 7572.0, 7854.60726963661, 8313.33493320545, 8377.748667539983, 9416.387934784218, 10555.525001374803, 10759.515821787882, 10889.346639201673, 11299.604513842305], "value": 9430.338506490765, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1513.1980050573197, 1547.806524184477, 1699.4816580942816, 1798.1189217283536, 1844.5204246895812, 7773.144954055049, 8052.516848989065, 8204.589770511473, 8721.302295816335, 8744.78138046549, 8264.710735124272, 8524.872126918086, 8525.062243153248, 8632.784180395493, 8647.005594356651, 2312.2947501012186, 2351.1770880191966, 2351.306364622432, 2352.2707960532202, 2358.3160883343808, 2077.66757318829, 2149.5163588192686, 2309.90760369585, 2335.1243283768586, 2361.370654248563, 2254.2448279826276, 2326.4765427778784, 2332.5334933013382, 2334.673145759595, 2335.8948847301826, 1934.8548858835572, 2035.669158813158, 2223.499712564676, 2336.9298921032396, 2341.613633750429, 1921.9807801922082, 2310.595645762002, 2313.803326717231, 2318.258157389636, 2321.6633588129794, 8597.570121493964, 8794.692185773467, 8803.955980220122, 8810.0, 8821.691240806542, 5117.1556693145585, 5306.548943339821, 5307.2569840222395, 5308.893822123536, 5312.256284120226, 3273.3616944695796, 3449.6377880322457, 3499.3701133795907, 3528.8412021459094, 3529.2235708144167, 1007.5214273220236, 1013.3565186106802, 1048.8846226915034, 1067.9572817087308, 1068.5672302717335, 11429.99975004371, 11506.482208300647, 11510.0, 11519.424028798527, 11525.827112593317, 4444.911101777946, 4620.422447194101, 4747.216709243005, 4751.40607424072, 4751.714897106184, 8222.958885205535, 9859.950700246596, 9860.013998600227, 9860.45767482781, 9863.063009014133, 5303.15149576066, 5303.28405665238, 5307.761150748225, 5310.230016647564, 5323.2015197719875, 4702.0, 4779.3070004849105, 4800.423949126127, 4858.78135483904, 4864.221724524059, 3870.670992965573, 3874.4815869684367, 3915.197384536163, 3959.8416063357145, 4064.227796718614, 1170.4206417823232, 1209.3590397089556, 1261.9242845429303, 1272.7136394311206, 1290.9806352904714, 781.5857595474407, 863.9565056221426, 887.9822403552022, 902.5757893789909, 916.6974898283551, 4923.409190897115, 5519.730461993764, 5871.588988770831, 5983.611065280743, 6134.539909506716, 920.3833431600843, 934.9018353072966, 945.8675785390051, 951.9476428796482, 975.059067999384, 3925.666318362914, 3933.9606603933594, 4040.777757223381, 4069.7761623110423, 4096.610821971908, 8145.918540814635, 10642.467876606139, 10642.73393165177, 10645.627403041011, 10646.14830813533, 9406.0, 10452.16382689383, 10515.316504427188, 10533.631322903662, 10667.893321066846, 8014.799630009296, 8126.634301456449, 8139.226773456512, 8144.022717273847, 8147.674093036212, 4978.4025916890205, 5739.454751798572, 5771.0, 5921.61509501881, 5922.200502932049, 9789.804203915739, 9817.509124543602, 9830.655927042519, 9854.014598540089, 9867.802643946938, 9018.954905225432, 9301.069893010645, 9875.506224688881, 9908.108270255712, 10092.142167915516, 7416.480846340704, 7593.924060759322, 7606.9619651901385, 7608.2391760823475, 7613.428992825449, 8151.1441298662785, 8252.00975882898, 8358.038825534899, 8369.372297077622, 8372.539510326991, 7319.34125928669, 7433.591152486665, 7437.405007599271, 7438.516496427715, 7550.8673698945595, 7300.196978332486, 7327.596982166032, 7388.335049845539, 7411.221821708643, 7466.066741657293, 6889.724411023503, 6991.790246292416, 7005.719771209095, 7007.299270072952, 7008.0, 4526.479454862733, 4859.1010663027455, 4981.551660350586, 4988.875278117931, 5186.559142472932, 4897.583705385082, 4954.628402869727, 5077.416097148727, 5104.234364845154, 5191.506806853343], "value": 4519.257067479174, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.1147227152174, 301.9290466740316, 313.28727145743426, 321.033688597322, 321.14254939312036, 5293.788248470061, 5305.628605997582, 5360.758765855536, 5395.433479484656, 5417.756200970956, 3880.583586990749, 4939.753012349386, 5027.673201241917, 5155.252488389191, 5304.49607287308, 3375.864965401381, 3705.7035437165096, 3735.8318875650593, 3914.921701565967, 4096.640754581244, 3420.0, 3579.5167652366986, 3795.9430608540893, 4106.7946602669745, 4158.189153115144, 3114.5483904833786, 3731.4589384539227, 4065.6747460203114, 4074.694397920168, 4091.9386209206887, 3402.234497238114, 3760.943585846228, 3803.9809800951093, 3999.1801680655394, 4078.4494093297617, 3117.719405253538, 3542.3092496963104, 3547.2728090741593, 3548.0131126569204, 3563.875264365761, 4517.932231016537, 5044.47033061527, 5131.050755610224, 5150.819721309736, 5152.304438900738, 3856.7878766667823, 3896.610338966109, 4212.578742125794, 4671.743054132022, 4672.906541869178, 5490.80782172622, 5517.475839795213, 5553.389127196006, 5565.634605292735, 5570.832875013756, 3757.135858752475, 3779.4141908004303, 3920.5883382245024, 3931.5085614298214, 3936.665383442411, 4540.8864778380475, 4815.303430079097, 5528.723563821833, 5875.297363212927, 5900.527957763368, 3672.797996110186, 3955.406688996616, 3973.900652483711, 3991.960080399217, 4016.05221467588, 4037.596240375939, 4059.3910913362643, 4068.06434520062, 4069.328560787496, 4078.5513593504693, 3944.0, 4312.504062032906, 4471.35165401015, 4660.720356778605, 4721.244600863845, 5510.476504732044, 5521.812810245817, 5570.0, 5608.551315894719, 5652.236948012048, 1269.3145701321369, 1308.7905935050346, 1378.9241591712553, 1381.6062422209623, 1382.9308534573431, 801.354909298009, 841.5287439034161, 872.9219414023676, 897.2507955856933, 913.7167478081683, 4663.834041489627, 4844.394450693663, 4876.5854902333695, 5081.4918508148885, 5171.172612382001, 4710.717356958594, 5654.151877218368, 5678.346990096192, 5684.32312467823, 5703.771849125988, 4876.561109500162, 5141.997310524412, 5178.585713142939, 5219.582433405319, 5263.36839579253, 3043.808457910301, 3054.480738274499, 3057.156844134976, 3065.846707664608, 3069.3247485553143, 5688.715564221772, 5756.366799652035, 6064.332923378425, 6065.3934606539, 6070.817875463743, 5202.791888324425, 5802.564807639444, 5878.412158784087, 5901.878643057806, 5957.612755170987, 5300.734963251822, 6016.759329626766, 6026.337102918676, 6027.21646185993, 6034.487068599043, 4203.537610862863, 4434.667399944952, 5702.8289151325525, 5774.87389958954, 5809.933046867125, 5709.571782116275, 5714.485696287188, 5714.5999780015, 5714.971305165111, 5743.023685973478, 5675.0, 5814.069748840166, 5830.62100963436, 5984.551158663031, 6021.488173505301, 3568.232829941575, 3861.4940173332984, 3868.516435445569, 3897.376419772823, 3917.96265941703, 4831.782569784438, 5813.273340832396, 5825.41745825431, 5850.414958504116, 5865.530757539472, 4650.232711602548, 5179.637425380338, 5277.0, 5626.212330273846, 5723.885522289447, 4551.5676010778925, 5353.0, 5581.916271255853, 5629.943700563106, 5776.595638305278, 4941.555260026615, 5108.974455127701, 5189.9379366174135, 5525.696086715269, 5603.523700485504, 4608.124456353282, 5216.113260745607, 5412.97153540826, 5453.890922181614, 5596.608237423341, 4381.663592604286, 4835.129676658235, 5128.666636668605, 5499.312585926759, 5602.4957753802355], "value": 4064.1852047219354, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.403851916968, 713.2297119111361, 729.2889432803014, 743.3941337809681, 784.9873662974762, 14707.485238016672, 16002.519924402257, 16064.196790160475, 16087.356505739772, 16169.434069807545, 14446.0, 14576.562703118896, 14607.707845843077, 14625.341859616316, 14634.560963171121, 664.5813137723228, 685.5132855672479, 685.7874059041688, 827.0323721246126, 852.9402941794077, 682.1609420412896, 685.1230425055936, 685.7359916432159, 818.7953011747063, 843.4770442325736, 878.6002368922125, 884.1114679746878, 885.831691978522, 888.8711136885148, 891.2958762577556, 880.7093659092484, 881.8192270584515, 882.4220135811054, 882.7395918204145, 883.0463099852144, 517.3068088761078, 660.0495286787011, 661.2428769059449, 661.3948237362807, 661.4543002023302, 11788.528458861636, 12080.97311728504, 12085.0, 12121.151519393647, 13972.72054558903, 13900.304984750723, 14752.3361448734, 14765.261736913111, 14769.778453323208, 14890.329935152944, 10033.54849031788, 11850.703732406673, 11904.226225295328, 11978.580749673807, 12404.317762523058, 3881.2237552489614, 3918.804059796999, 3925.0, 4015.4579131817413, 4054.3513037914086, 113631.43184284108, 113649.43175284153, 113980.4300978498, 114331.28503072461, 114672.0, 10384.117350025332, 10733.141348692086, 10843.457827108614, 10932.890671093346, 11176.27354221973, 20861.895690521753, 21523.78476215218, 21922.013509392116, 22349.888250558644, 22377.328680139297, 6813.761518346933, 8099.190080991853, 8122.228388303101, 8207.712730054538, 8274.089850116483, 4118.19695159447, 4138.275801734744, 4225.704200705983, 4230.61924426803, 4240.618344349024, 3563.590187128513, 3726.4162730839826, 3756.7933763642714, 3758.736888417839, 3759.7368184226843, 615.5537235504223, 626.6929204689669, 632.1844820182006, 681.5774219983642, 719.6833393306928, 592.8577141486098, 617.5183356981562, 631.3181763695198, 655.1810237203496, 664.5514277862424, 8365.874511882326, 8608.881251516617, 8782.956085219534, 9719.805603887882, 9903.8898856975, 645.6771614192903, 645.7513857164931, 650.5738741124571, 666.6167702018234, 685.4379408884666, 4268.786560671954, 4482.731036137842, 4709.387779588632, 4745.311929770164, 4862.95137048625, 90351.54824225968, 97034.0, 109754.0, 137820.24359512754, 162331.0, 131711.34144329408, 133499.0, 134400.0, 137472.9379059315, 140276.0, 108873.0, 110844.0, 111609.44195278971, 112764.0, 113627.0, 9418.058194180527, 9588.137067663665, 9719.125278724949, 9746.902530974883, 9878.357906736039, 56992.86014279774, 59169.40830591639, 60102.39897600968, 60789.0, 61146.694266528386, 89355.55322223347, 91766.54116729373, 91964.0, 92259.0, 93700.06299936914, 12854.421551030038, 13186.538471153464, 13195.934020330222, 13232.801507977196, 13623.931880340535, 14776.556703298918, 14968.700625987201, 14979.550613481613, 15072.698546029238, 15134.167620780963, 13630.659233519242, 13643.658908527366, 14047.157170569799, 14165.858341416453, 14197.219152946274, 13953.162810231419, 13954.023218374616, 13959.0, 14173.936954728642, 14387.424503019762, 12914.547990820653, 12996.155249908727, 13646.385912633757, 14060.226687532284, 14193.219372934587, 9732.756681082747, 10195.184385249015, 10679.359238445408, 11737.941310293736, 12104.213226140273, 9787.21702263803, 10430.739231519272, 10494.580216791242, 10867.602559650652, 11596.652100437], "value": 7702.026593895729, "unit": "score"}], "name": "dromaeo_css", "value": 7325.479502225307, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 160.0, 164.0, 96.0, 111.0, 142.0, 163.0, 121.0, 112.0, 111.0], "value": 128.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 106.0, 109.0, 166.0, 107.0, 107.0, 129.0, 110.0, 173.0, 138.0], "value": 128.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 259.0, 185.0, 183.0, 183.0, 179.0, 181.0, 181.0, 258.0, 190.0], "value": 199.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 63.0, 85.0, 60.0, 57.0, 63.0, 67.0, 85.0, 55.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 68.0, 101.0, 72.0, 75.0, 67.0, 97.0, 70.0, 67.0, 127.0], "value": 82.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 83.0, 87.0, 86.0, 134.0, 83.0, 86.0, 83.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 132.0, 148.0, 122.0, 92.0, 93.0, 93.0, 93.0, 93.0, 93.0], "value": 105.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 90.0, 122.0, 84.0, 84.0, 85.0, 83.0, 84.0, 86.0], "value": 88.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 53.0, 52.0, 51.0, 52.0, 54.0, 53.0, 53.0, 53.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 78.0, 108.0, 112.0, 105.0, 72.0, 74.0, 74.0, 72.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 84.0, 87.0, 88.0, 87.0, 86.0, 89.0, 89.0, 118.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 156.0, 146.0, 142.0, 153.0, 134.0, 139.0, 139.0, 151.0, 140.0], "value": 144.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 182.0, 181.0, 181.0, 182.0, 184.0, 182.0, 182.0, 189.0], "value": 182.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 64.0, 70.0, 68.0, 68.0, 67.0, 69.0, 68.0, 67.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1511.9000000000003, "alertThreshold": 2.0}]} 19:56:20 INFO - Return code: 0 19:56:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:56:20 INFO - # TBPL SUCCESS # 19:56:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:56:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:56:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:56:20 INFO - 2016-08-12 19:56:20.805 screenresolution[1801:10043] starting screenresolution argv=screenresolution get 19:56:20 INFO - 2016-08-12 19:56:20.808 screenresolution[1801:10043] Display 0: 1600x1200x32@60 19:56:20 INFO - 2016-08-12 19:56:20.814 screenresolution[1802:10046] starting screenresolution argv=screenresolution list 19:56:20 INFO - Available Modes on Display 0 19:56:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:56:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:56:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:56:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:56:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:56:21 INFO - Intel Iris: 19:56:21 INFO - Chipset Model: Intel Iris 19:56:21 INFO - Type: GPU 19:56:21 INFO - Bus: Built-In 19:56:21 INFO - VRAM (Dynamic, Max): 1536 MB 19:56:21 INFO - Vendor: Intel (0x8086) 19:56:21 INFO - Device ID: 0x0a2e 19:56:21 INFO - Revision ID: 0x0009 19:56:21 INFO - Displays: 19:56:21 INFO - Display: 19:56:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:56:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:56:21 INFO - Display Serial Number: 1600x1200 60 19:56:21 INFO - Main Display: Yes 19:56:21 INFO - Mirror: Off 19:56:21 INFO - Online: Yes 19:56:21 INFO - Rotation: Supported 19:56:21 INFO - Return code: 0 19:56:21 INFO - Running post-action listener: _resource_record_post_action 19:56:21 INFO - [mozharness: 2016-08-13 02:56:21.049227Z] Finished run-tests step (success) 19:56:21 INFO - Running post-run listener: _resource_record_post_run 19:56:21 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37213184; Write bytes: 706163200; Read time: 240; Write time: 2009 19:56:21 INFO - TinderboxPrint: CPU usage
53.5% 19:56:21 INFO - TinderboxPrint: I/O read bytes / time
37,213,184 / 240 19:56:21 INFO - TinderboxPrint: I/O write bytes / time
706,163,200 / 2,009 19:56:21 INFO - TinderboxPrint: CPU idle
2,254.0 (46.5%) 19:56:21 INFO - TinderboxPrint: CPU system
970.0 (20.0%) 19:56:21 INFO - TinderboxPrint: CPU user
1,625.6 (33.5%) 19:56:21 INFO - TinderboxPrint: Swap in / out
441,110,528 / 0 19:56:21 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 403913216; Write bytes: 397998080; Read time: 29800; Write time: 818 19:56:21 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 34489344; Write bytes: 298195456; Read time: 214; Write time: 1161 19:56:21 INFO - Running post-run listener: _upload_blobber_files 19:56:21 INFO - Blob upload gear active. 19:56:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:56:21 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 19:56:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:56:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:56:21 INFO - (blobuploader) - INFO - Open directory for files ... 19:56:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:56:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:56:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:56:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:56:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:56:22 INFO - (blobuploader) - INFO - Done attempting. 19:56:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:56:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:56:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:56:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:56:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:56:23 INFO - (blobuploader) - INFO - Done attempting. 19:56:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:56:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:56:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:56:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:56:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:56:25 INFO - (blobuploader) - INFO - Done attempting. 19:56:25 INFO - (blobuploader) - INFO - Iteration through files over. 19:56:25 INFO - Return code: 0 19:56:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:56:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:56:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3238ddc07b67e594b23543b41343462a14d707c2a1ed2eb51c5ab4738e690f42800860ad2714d29ebdcee72624d22440fa1fa55075168cb1819d33b8d6cd2bd5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ee58aaee957f6c132b348a6a1acc1894fa8b38af4cee23034018fbb97b4c132fce082d2301f4a38c04090728e6f0ca462ec292a6d0c37353a197ea0b551297b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9fe13b957e0ae80ee68cfd5384ef7f8d8efbe4bf847688e5ad928496e940bd1693cc3923d563d4a4e4cfd523f8be90a41fed1cb1e70af08003f47aa99cd4e8a6"} 19:56:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:56:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:56:25 INFO - Contents: 19:56:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3238ddc07b67e594b23543b41343462a14d707c2a1ed2eb51c5ab4738e690f42800860ad2714d29ebdcee72624d22440fa1fa55075168cb1819d33b8d6cd2bd5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ee58aaee957f6c132b348a6a1acc1894fa8b38af4cee23034018fbb97b4c132fce082d2301f4a38c04090728e6f0ca462ec292a6d0c37353a197ea0b551297b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9fe13b957e0ae80ee68cfd5384ef7f8d8efbe4bf847688e5ad928496e940bd1693cc3923d563d4a4e4cfd523f8be90a41fed1cb1e70af08003f47aa99cd4e8a6"} 19:56:25 INFO - Running post-run listener: copy_logs_to_upload_dir 19:56:25 INFO - Copying logs to upload dir... 19:56:25 INFO - mkdir: /builds/slave/test/build/upload/logs 19:56:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.160250 ========= master_lag: 2.42 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-12 19:56:28.405154) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-08-12 19:56:28.408381) ========= 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.XpFKZ6qqNm/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.sdPQruqFj2/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3238ddc07b67e594b23543b41343462a14d707c2a1ed2eb51c5ab4738e690f42800860ad2714d29ebdcee72624d22440fa1fa55075168cb1819d33b8d6cd2bd5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ee58aaee957f6c132b348a6a1acc1894fa8b38af4cee23034018fbb97b4c132fce082d2301f4a38c04090728e6f0ca462ec292a6d0c37353a197ea0b551297b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9fe13b957e0ae80ee68cfd5384ef7f8d8efbe4bf847688e5ad928496e940bd1693cc3923d563d4a4e4cfd523f8be90a41fed1cb1e70af08003f47aa99cd4e8a6"} build_url:https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011319 build_url: 'https://queue.taskcluster.net/v1/task/T-gKn8ViSxKLNDEABgz-nw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3238ddc07b67e594b23543b41343462a14d707c2a1ed2eb51c5ab4738e690f42800860ad2714d29ebdcee72624d22440fa1fa55075168cb1819d33b8d6cd2bd5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ee58aaee957f6c132b348a6a1acc1894fa8b38af4cee23034018fbb97b4c132fce082d2301f4a38c04090728e6f0ca462ec292a6d0c37353a197ea0b551297b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9fe13b957e0ae80ee68cfd5384ef7f8d8efbe4bf847688e5ad928496e940bd1693cc3923d563d4a4e4cfd523f8be90a41fed1cb1e70af08003f47aa99cd4e8a6"}' ========= master_lag: 3.65 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-08-12 19:56:32.073835) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:56:32.074208) ========= 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.XpFKZ6qqNm/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.sdPQruqFj2/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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.005275 ========= master_lag: 0.69 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:56:32.774232) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 19:56:32.774543) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 19:56:32.775816) ========= ========= Total master_lag: 6.83 =========