builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0095 starttime: 1471547606.7 results: success (0) buildid: 20160818095355 builduid: 02b524d601314e17a0e9e131b280106d revision: dce857dd57e2d91ae4c6014cf21e93ef95e4dd36 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 12:13:26.697969) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 12:13:26.698421) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 12:13:26.698709) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 12:13:26.784658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 12:13:26.784975) ========= 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.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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-18 12:13:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.73M=0.001s 2016-08-18 12:13:26 (9.73 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.090771 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 12:13:26.898792) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 12:13:26.899097) ========= 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.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.036568 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 12:13:26.956399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 12:13:26.956748) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev dce857dd57e2d91ae4c6014cf21e93ef95e4dd36 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev dce857dd57e2d91ae4c6014cf21e93ef95e4dd36 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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-18 12:13:27,034 truncating revision to first 12 chars 2016-08-18 12:13:27,034 Setting DEBUG logging. 2016-08-18 12:13:27,034 attempt 1/10 2016-08-18 12:13:27,034 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/dce857dd57e2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 12:13:28,013 unpacking tar archive at: fx-team-dce857dd57e2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.249995 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 12:13:28.229457) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 12:13:28.230202) ========= script_repo_revision: dce857dd57e2d91ae4c6014cf21e93ef95e4dd36 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 12:13:28.230829) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 12:13:28.231090) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 12:13:28.249302) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-08-18 12:13:28.249599) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:13:28 INFO - MultiFileLogger online at 20160818 12:13:28 in /builds/slave/test 12:13:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:13:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:13:28 INFO - {'append_to_log': False, 12:13:28 INFO - 'base_work_dir': '/builds/slave/test', 12:13:28 INFO - 'blob_upload_branch': 'Fx-Team', 12:13:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:13:28 INFO - 'branch': 'Fx-Team', 12:13:28 INFO - 'buildbot_json_path': 'buildprops.json', 12:13:28 INFO - 'config_files': ('talos/mac_config.py',), 12:13:28 INFO - 'default_actions': ('clobber', 12:13:28 INFO - 'read-buildbot-config', 12:13:28 INFO - 'download-and-extract', 12:13:28 INFO - 'populate-webroot', 12:13:28 INFO - 'create-virtualenv', 12:13:28 INFO - 'install', 12:13:28 INFO - 'run-tests'), 12:13:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:13:28 INFO - 'download_minidump_stackwalk': True, 12:13:28 INFO - 'download_symbols': 'ondemand', 12:13:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:13:28 INFO - 'tooltool.py': '/tools/tooltool.py', 12:13:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:13:28 INFO - '/tools/misc-python/virtualenv.py')}, 12:13:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:13:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:13:28 INFO - 'installer_path': 'installer.exe', 12:13:28 INFO - 'log_level': 'info', 12:13:28 INFO - 'log_name': 'talos', 12:13:28 INFO - 'log_to_console': True, 12:13:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:13:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:13:28 INFO - 'opt_config_files': (), 12:13:28 INFO - 'pip_index': False, 12:13:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:13:28 INFO - 'cmd': ('bash', 12:13:28 INFO - '-c', 12:13:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:13:28 INFO - 'enabled': True, 12:13:28 INFO - 'halt_on_failure': False, 12:13:28 INFO - 'name': 'check_screen_resolution'},), 12:13:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:13:28 INFO - 'cmd': ('bash', 12:13:28 INFO - '-c', 12:13:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:13:28 INFO - 'enabled': True, 12:13:28 INFO - 'halt_on_failure': False, 12:13:28 INFO - 'name': 'check_screen_resolution'},), 12:13:28 INFO - 'run_cmd_checks_enabled': True, 12:13:28 INFO - 'sps_profile': False, 12:13:28 INFO - 'sps_profile_interval': 0, 12:13:28 INFO - 'suite': 'dromaeojs', 12:13:28 INFO - 'system_bits': '32', 12:13:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:13:28 INFO - 'title': 't-yosemite-r7-0095', 12:13:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:13:28 INFO - 'use_talos_json': True, 12:13:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:13:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:13:28 INFO - 'work_dir': 'build'} 12:13:28 INFO - [mozharness: 2016-08-18 19:13:28.447881Z] Running clobber step. 12:13:28 INFO - Running pre-action listener: _resource_record_pre_action 12:13:28 INFO - Running main action method: clobber 12:13:28 INFO - rmtree: /builds/slave/test/build 12:13:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:13:30 INFO - Running post-action listener: _resource_record_post_action 12:13:30 INFO - [mozharness: 2016-08-18 19:13:30.595691Z] Finished clobber step (success) 12:13:30 INFO - [mozharness: 2016-08-18 19:13:30.595774Z] Running read-buildbot-config step. 12:13:30 INFO - Running pre-action listener: _resource_record_pre_action 12:13:30 INFO - Running main action method: read_buildbot_config 12:13:30 INFO - Using buildbot properties: 12:13:30 INFO - { 12:13:30 INFO - "project": "", 12:13:30 INFO - "product": "firefox", 12:13:30 INFO - "script_repo_revision": "production", 12:13:30 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:13:30 INFO - "repository": "", 12:13:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:13:30 INFO - "buildid": "20160818095355", 12:13:30 INFO - "pgo_build": "False", 12:13:30 INFO - "basedir": "/builds/slave/test", 12:13:30 INFO - "buildnumber": 817, 12:13:30 INFO - "slavename": "t-yosemite-r7-0095", 12:13:30 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:13:30 INFO - "platform": "yosemite_r7", 12:13:30 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:13:30 INFO - "branch": "fx-team", 12:13:30 INFO - "repo_path": "integration/fx-team", 12:13:30 INFO - "revision": "dce857dd57e2d91ae4c6014cf21e93ef95e4dd36", 12:13:30 INFO - "stage_platform": "macosx64", 12:13:30 INFO - "builduid": "02b524d601314e17a0e9e131b280106d", 12:13:30 INFO - "slavebuilddir": "test" 12:13:30 INFO - } 12:13:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 12:13:30 INFO - Running post-action listener: _resource_record_post_action 12:13:30 INFO - [mozharness: 2016-08-18 19:13:30.599050Z] Finished read-buildbot-config step (success) 12:13:30 INFO - [mozharness: 2016-08-18 19:13:30.599169Z] Running download-and-extract step. 12:13:30 INFO - Running pre-action listener: _resource_record_pre_action 12:13:30 INFO - Running main action method: download_and_extract 12:13:30 INFO - mkdir: /builds/slave/test/build/tests 12:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:30 INFO - trying https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 12:13:30 INFO - Downloading https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/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 12:13:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/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 12:13:31 INFO - Downloaded 1199 bytes. 12:13:31 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 12:13:31 INFO - Using the following test package requirements: 12:13:31 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:13:31 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:13:31 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 12:13:31 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:13:31 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 12:13:31 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:13:31 INFO - u'jsshell-mac.zip'], 12:13:31 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:13:31 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 12:13:31 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:13:31 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:13:31 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 12:13:31 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:13:31 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 12:13:31 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:13:31 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 12:13:31 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:13:31 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 12:13:31 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:31 INFO - trying https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:13:31 INFO - Downloading https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/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 12:13:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/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 12:13:32 INFO - Downloaded 16339554 bytes. 12:13:32 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 12:13:39 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 12:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:39 INFO - trying https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:13:39 INFO - Downloading https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/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 12:13:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/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 12:13:40 INFO - Downloaded 16339554 bytes. 12:13:40 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 12:13: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')]} 12:13:46 INFO - trying https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 12:13:46 INFO - Downloading https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/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 12:13:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/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 12:13:47 INFO - Downloaded 14413981 bytes. 12:13:47 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 12:13:48 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')]} 12:13:48 INFO - trying https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:13:48 INFO - Downloading https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 12:13:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/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 12:13:51 INFO - Downloaded 111294791 bytes. 12:13:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:13:51 INFO - mkdir: /builds/slave/test/properties 12:13:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:13:51 INFO - Writing to file /builds/slave/test/properties/build_url 12:13:51 INFO - Contents: 12:13:51 INFO - build_url:https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:13:51 INFO - Running post-action listener: _resource_record_post_action 12:13:51 INFO - Running post-action listener: set_extra_try_arguments 12:13:51 INFO - [mozharness: 2016-08-18 19:13:51.927629Z] Finished download-and-extract step (success) 12:13:51 INFO - [mozharness: 2016-08-18 19:13:51.927757Z] Running populate-webroot step. 12:13:51 INFO - Running pre-action listener: _resource_record_pre_action 12:13:51 INFO - Running main action method: populate_webroot 12:13:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:13:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:13:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:13:51 INFO - '--tppagecycles', 12:13:51 INFO - '7'], 12:13:51 INFO - 'tests': ['tsvgm']}}, 12:13:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:13:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:13:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:13:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:13:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:13:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:13:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:51 INFO - 'talos_options': ['--disable-e10s'], 12:13:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:13:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:13:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:51 INFO - 'talos_options': ['--disable-e10s'], 12:13:51 INFO - 'tests': ['damp', 'tps']}, 12:13:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:51 INFO - 'tests': ['damp', 'tps']}, 12:13:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:13:51 INFO - 'tests': ['dromaeo_dom']}, 12:13:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:13:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:13:51 INFO - 'tests': ['basic_compositor_video']}, 12:13:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:13:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:13:51 INFO - 'tests': ['a11yr', 12:13:51 INFO - 'ts_paint', 12:13:51 INFO - 'tpaint', 12:13:51 INFO - 'sessionrestore', 12:13:51 INFO - 'sessionrestore_no_auto_restore', 12:13:51 INFO - 'tabpaint']}, 12:13:51 INFO - 'other-e10s': {'tests': ['a11yr', 12:13:51 INFO - 'ts_paint', 12:13:51 INFO - 'tpaint', 12:13:51 INFO - 'sessionrestore', 12:13:51 INFO - 'sessionrestore_no_auto_restore', 12:13:51 INFO - 'tabpaint']}, 12:13:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:13:51 INFO - 'ts_paint', 12:13:51 INFO - 'tpaint', 12:13:51 INFO - 'sessionrestore', 12:13:51 INFO - 'sessionrestore_no_auto_restore', 12:13:51 INFO - 'tabpaint']}, 12:13:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:13:51 INFO - 'ts_paint', 12:13:51 INFO - 'tpaint', 12:13:51 INFO - 'sessionrestore', 12:13:51 INFO - 'sessionrestore_no_auto_restore', 12:13:51 INFO - 'tabpaint']}, 12:13:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:13:51 INFO - 'tests': ['a11yr', 12:13:51 INFO - 'ts_paint', 12:13:51 INFO - 'tpaint', 12:13:51 INFO - 'sessionrestore', 12:13:51 INFO - 'sessionrestore_no_auto_restore', 12:13:51 INFO - 'tabpaint']}, 12:13:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:13:51 INFO - 'tests': ['a11yr', 12:13:51 INFO - 'ts_paint', 12:13:51 INFO - 'tpaint', 12:13:51 INFO - 'sessionrestore', 12:13:51 INFO - 'sessionrestore_no_auto_restore', 12:13:51 INFO - 'tabpaint']}, 12:13:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:13:51 INFO - 'tests': ['tsvgx', 12:13:51 INFO - 'tsvgr_opacity', 12:13:51 INFO - 'tart', 12:13:51 INFO - 'tscrollx', 12:13:51 INFO - 'cart']}, 12:13:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:13:51 INFO - 'tsvgr_opacity', 12:13:51 INFO - 'tart', 12:13:51 INFO - 'tscrollx', 12:13:51 INFO - 'cart']}, 12:13:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:51 INFO - 'talos_options': ['--disable-e10s'], 12:13:51 INFO - 'tests': ['tp5o']}, 12:13:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:51 INFO - 'tests': ['tp5o']}, 12:13:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:51 INFO - 'talos_options': ['--disable-e10s', 12:13:51 INFO - '--xperf_path', 12:13:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:13:51 INFO - 'tests': ['tp5n']}, 12:13:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:51 INFO - 'talos_options': ['--xperf_path', 12:13:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:13:51 INFO - 'tests': ['tp5n']}}, 12:13:51 INFO - 'talos.zip': {'path': '', 12:13:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:13:51 INFO - Running post-action listener: _resource_record_post_action 12:13:51 INFO - [mozharness: 2016-08-18 19:13:51.946176Z] Finished populate-webroot step (success) 12:13:51 INFO - [mozharness: 2016-08-18 19:13:51.946295Z] Running create-virtualenv step. 12:13:51 INFO - Running pre-action listener: _resource_record_pre_action 12:13:51 INFO - Running main action method: create_virtualenv 12:13:51 INFO - Creating virtualenv /builds/slave/test/build/venv 12:13:51 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 12:13:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:13:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:13:52 INFO - Using real prefix '/tools/python27' 12:13:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:13:52 INFO - Installing distribute.............................................................................................................................................................................................done. 12:13:55 INFO - Installing pip.................done. 12:13:55 INFO - Return code: 0 12:13:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:13:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:55 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 0x10feeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85114d0110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe4ed30>, '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 0x10fbb3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe46b20>, '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.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:13:55 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 12:13:55 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 12:13:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9KG6mg2Vi/Render', 12:13:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:55 INFO - 'HOME': '/Users/cltbld', 12:13:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:55 INFO - 'LOGNAME': 'cltbld', 12:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:55 INFO - 'MOZ_NO_REMOTE': '1', 12:13:55 INFO - 'NO_EM_RESTART': '1', 12:13:55 INFO - 'PAGER': '/bin/cat', 12:13:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:55 INFO - 'PWD': '/builds/slave/test', 12:13:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:55 INFO - 'SHELL': '/bin/bash', 12:13:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iUxJhZjc2i/Listeners', 12:13:55 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:13:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:55 INFO - 'USER': 'cltbld', 12:13:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:55 INFO - 'XPC_FLAGS': '0x0', 12:13:55 INFO - 'XPC_SERVICE_NAME': '0', 12:13:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:55 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 12:13:55 INFO - Cleaning up... 12:13:55 INFO - Return code: 0 12:13:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:13:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:55 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 0x10feeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85114d0110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe4ed30>, '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 0x10fbb3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe46b20>, '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.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:13:55 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 12:13:55 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 12:13:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9KG6mg2Vi/Render', 12:13:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:55 INFO - 'HOME': '/Users/cltbld', 12:13:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:55 INFO - 'LOGNAME': 'cltbld', 12:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:55 INFO - 'MOZ_NO_REMOTE': '1', 12:13:55 INFO - 'NO_EM_RESTART': '1', 12:13:55 INFO - 'PAGER': '/bin/cat', 12:13:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:55 INFO - 'PWD': '/builds/slave/test', 12:13:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:55 INFO - 'SHELL': '/bin/bash', 12:13:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iUxJhZjc2i/Listeners', 12:13:55 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:13:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:55 INFO - 'USER': 'cltbld', 12:13:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:55 INFO - 'XPC_FLAGS': '0x0', 12:13:55 INFO - 'XPC_SERVICE_NAME': '0', 12:13:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:56 INFO - Downloading/unpacking psutil>=3.1.1 12:13: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 12:13: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 12:13: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 12:13: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 12:13: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 12:13: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 12:13:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:13:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:13:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:13:59 INFO - Installing collected packages: psutil 12:13:59 INFO - Running setup.py install for psutil 12:13:59 INFO - building 'psutil._psutil_osx' extension 12:13:59 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 12:14:00 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 12:14:00 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 12:14:00 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 12:14:00 INFO - building 'psutil._psutil_posix' extension 12:14:00 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 12:14:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:14:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:14:00 INFO - ^ 12:14:00 INFO - 1 warning generated. 12:14:00 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 12:14:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:14:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:14:00 INFO - Successfully installed psutil 12:14:00 INFO - Cleaning up... 12:14:00 INFO - Return code: 0 12:14:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:00 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 0x10feeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85114d0110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe4ed30>, '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 0x10fbb3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe46b20>, '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.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:14:00 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 12:14:00 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 12:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9KG6mg2Vi/Render', 12:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:00 INFO - 'HOME': '/Users/cltbld', 12:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:00 INFO - 'LOGNAME': 'cltbld', 12:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:00 INFO - 'MOZ_NO_REMOTE': '1', 12:14:00 INFO - 'NO_EM_RESTART': '1', 12:14:00 INFO - 'PAGER': '/bin/cat', 12:14:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:00 INFO - 'PWD': '/builds/slave/test', 12:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:00 INFO - 'SHELL': '/bin/bash', 12:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iUxJhZjc2i/Listeners', 12:14:00 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:00 INFO - 'USER': 'cltbld', 12:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:00 INFO - 'XPC_FLAGS': '0x0', 12:14:00 INFO - 'XPC_SERVICE_NAME': '0', 12:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:14:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:14:03 INFO - Installing collected packages: mozsystemmonitor 12:14:03 INFO - Running setup.py install for mozsystemmonitor 12:14:03 INFO - Successfully installed mozsystemmonitor 12:14:03 INFO - Cleaning up... 12:14:03 INFO - Return code: 0 12:14:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10feeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85114d0110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe4ed30>, '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 0x10fbb3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe46b20>, '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.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:14:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:14:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:14:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9KG6mg2Vi/Render', 12:14:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:03 INFO - 'HOME': '/Users/cltbld', 12:14:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:03 INFO - 'LOGNAME': 'cltbld', 12:14:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:03 INFO - 'MOZ_NO_REMOTE': '1', 12:14:03 INFO - 'NO_EM_RESTART': '1', 12:14:03 INFO - 'PAGER': '/bin/cat', 12:14:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:03 INFO - 'PWD': '/builds/slave/test', 12:14:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:03 INFO - 'SHELL': '/bin/bash', 12:14:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iUxJhZjc2i/Listeners', 12:14:03 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:14:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:03 INFO - 'USER': 'cltbld', 12:14:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:03 INFO - 'XPC_FLAGS': '0x0', 12:14:03 INFO - 'XPC_SERVICE_NAME': '0', 12:14:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:04 INFO - Downloading/unpacking jsonschema==2.5.1 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:14:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:14:06 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 12:14:06 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 12:14:06 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 12:14:06 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 12:14:06 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 12:14:06 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 12:14:06 INFO - Downloading functools32-3.2.3-2.tar.gz 12:14:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:14:07 INFO - warning: no files found matching '*.txt' 12:14:07 INFO - no previously-included directories found matching 'build' 12:14:07 INFO - no previously-included directories found matching 'dist' 12:14:07 INFO - no previously-included directories found matching '.git*' 12:14:07 INFO - Installing collected packages: jsonschema, functools32 12:14:07 INFO - Running setup.py install for functools32 12:14:07 INFO - warning: no files found matching '*.txt' 12:14:07 INFO - no previously-included directories found matching 'build' 12:14:07 INFO - no previously-included directories found matching 'dist' 12:14:07 INFO - no previously-included directories found matching '.git*' 12:14:07 INFO - Successfully installed jsonschema functools32 12:14:07 INFO - Cleaning up... 12:14:07 INFO - Return code: 0 12:14:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:07 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 0x10feeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85114d0110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe4ed30>, '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 0x10fbb3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe46b20>, '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.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:14:07 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 12:14:07 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 12:14:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9KG6mg2Vi/Render', 12:14:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:07 INFO - 'HOME': '/Users/cltbld', 12:14:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:07 INFO - 'LOGNAME': 'cltbld', 12:14:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:07 INFO - 'MOZ_NO_REMOTE': '1', 12:14:07 INFO - 'NO_EM_RESTART': '1', 12:14:07 INFO - 'PAGER': '/bin/cat', 12:14:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:07 INFO - 'PWD': '/builds/slave/test', 12:14:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:07 INFO - 'SHELL': '/bin/bash', 12:14:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iUxJhZjc2i/Listeners', 12:14:07 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:14:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:07 INFO - 'USER': 'cltbld', 12:14:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:07 INFO - 'XPC_FLAGS': '0x0', 12:14:07 INFO - 'XPC_SERVICE_NAME': '0', 12:14:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:14:07 INFO - Cleaning up... 12:14:07 INFO - Return code: 0 12:14:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:07 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 0x10feeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85114d0110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe4ed30>, '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 0x10fbb3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe46b20>, '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.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:14:07 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 12:14:07 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 12:14:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9KG6mg2Vi/Render', 12:14:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:07 INFO - 'HOME': '/Users/cltbld', 12:14:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:07 INFO - 'LOGNAME': 'cltbld', 12:14:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:07 INFO - 'MOZ_NO_REMOTE': '1', 12:14:07 INFO - 'NO_EM_RESTART': '1', 12:14:07 INFO - 'PAGER': '/bin/cat', 12:14:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:07 INFO - 'PWD': '/builds/slave/test', 12:14:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:07 INFO - 'SHELL': '/bin/bash', 12:14:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iUxJhZjc2i/Listeners', 12:14:07 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:14:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:07 INFO - 'USER': 'cltbld', 12:14:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:07 INFO - 'XPC_FLAGS': '0x0', 12:14:07 INFO - 'XPC_SERVICE_NAME': '0', 12:14:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:07 INFO - Downloading/unpacking blobuploader==1.2.4 12:14:07 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 12:14:07 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 12:14:07 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 12:14:07 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 12:14:07 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 12:14:07 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 12:14:10 INFO - Downloading blobuploader-1.2.4.tar.gz 12:14:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:14:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:14:10 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 12:14:10 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 12:14:10 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 12:14:10 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 12:14:10 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 12:14:10 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 12:14:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:14:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:14:11 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 12:14:11 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 12:14:11 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 12:14:11 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 12:14:11 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 12:14:11 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 12:14:11 INFO - Downloading docopt-0.6.1.tar.gz 12:14:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:14:11 INFO - Installing collected packages: blobuploader, requests, docopt 12:14:11 INFO - Running setup.py install for blobuploader 12:14:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:14:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:14:11 INFO - Running setup.py install for requests 12:14:12 INFO - Running setup.py install for docopt 12:14:12 INFO - Successfully installed blobuploader requests docopt 12:14:12 INFO - Cleaning up... 12:14:12 INFO - Return code: 0 12:14:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:12 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 0x10feeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85114d0110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe4ed30>, '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 0x10fbb3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe46b20>, '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.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:14:12 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 12:14:12 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 12:14:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9KG6mg2Vi/Render', 12:14:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:12 INFO - 'HOME': '/Users/cltbld', 12:14:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:12 INFO - 'LOGNAME': 'cltbld', 12:14:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:12 INFO - 'MOZ_NO_REMOTE': '1', 12:14:12 INFO - 'NO_EM_RESTART': '1', 12:14:12 INFO - 'PAGER': '/bin/cat', 12:14:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:12 INFO - 'PWD': '/builds/slave/test', 12:14:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:12 INFO - 'SHELL': '/bin/bash', 12:14:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iUxJhZjc2i/Listeners', 12:14:12 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:14:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:12 INFO - 'USER': 'cltbld', 12:14:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:12 INFO - 'XPC_FLAGS': '0x0', 12:14:12 INFO - 'XPC_SERVICE_NAME': '0', 12:14:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:14:12 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-NP0Gkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:14:12 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-uvJJbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:14:12 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-5kZDet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:14:13 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-vFUVZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:14:13 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-aiLcP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:14:13 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-5VW0UG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:14:13 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-EkFAht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:14:13 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-_4_hgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:14:13 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-JyJlu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:14:13 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-F31jSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:14:13 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-vPUpT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:14:13 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-1pkSui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:14:13 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-x7wydO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:14:14 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Nt77o4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:14 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-IY6j4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:14:14 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-ee10zC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:14:14 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Ekwl6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:14:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:14:14 INFO - Running setup.py install for manifestparser 12:14:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:14:14 INFO - Running setup.py install for mozcrash 12:14:14 INFO - Running setup.py install for mozdebug 12:14:14 INFO - Running setup.py install for mozdevice 12:14:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:14:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:14:15 INFO - Running setup.py install for mozfile 12:14:15 INFO - Running setup.py install for mozhttpd 12:14:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:14:15 INFO - Running setup.py install for mozinfo 12:14:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:14:15 INFO - Running setup.py install for mozInstall 12:14:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:14:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:14:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:14:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:14:15 INFO - Running setup.py install for mozleak 12:14:15 INFO - Running setup.py install for mozlog 12:14:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:14:15 INFO - Running setup.py install for moznetwork 12:14:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Running setup.py install for mozprocess 12:14:16 INFO - Running setup.py install for mozprofile 12:14:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Running setup.py install for mozrunner 12:14:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:14:16 INFO - Running setup.py install for mozscreenshot 12:14:16 INFO - Running setup.py install for moztest 12:14:16 INFO - Running setup.py install for mozversion 12:14:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:14:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:14:17 INFO - Cleaning up... 12:14:17 INFO - Return code: 0 12:14:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:17 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 0x10feeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85114d0110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe4ed30>, '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 0x10fbb3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe46b20>, '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.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:14:17 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 12:14:17 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 12:14:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9KG6mg2Vi/Render', 12:14:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:17 INFO - 'HOME': '/Users/cltbld', 12:14:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:17 INFO - 'LOGNAME': 'cltbld', 12:14:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:17 INFO - 'MOZ_NO_REMOTE': '1', 12:14:17 INFO - 'NO_EM_RESTART': '1', 12:14:17 INFO - 'PAGER': '/bin/cat', 12:14:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:17 INFO - 'PWD': '/builds/slave/test', 12:14:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:17 INFO - 'SHELL': '/bin/bash', 12:14:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iUxJhZjc2i/Listeners', 12:14:17 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:14:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:17 INFO - 'USER': 'cltbld', 12:14:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:17 INFO - 'XPC_FLAGS': '0x0', 12:14:17 INFO - 'XPC_SERVICE_NAME': '0', 12:14:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:14:17 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-yQrzGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:14:17 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)) 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:14:17 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Um3U91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:14:17 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)) 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:14:17 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-md0omS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:14:17 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)) 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:14:17 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-WZ1Um9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:14:17 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)) 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:14:17 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-gqL_A8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:14:17 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)) 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:14:17 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-VWLTYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:14:17 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)) 12:14:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:14:17 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Keed4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:14:18 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-4UxDIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:14:18 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-rx3Yo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:14:18 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-HQvHtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:14:18 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-UJeaIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:14:18 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-HJQMOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:14:18 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-8aFJrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:14:18 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-DqsoAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:18 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-HWH4FS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:18 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)) 12:14:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:14:18 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-UHtfo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:14:19 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)) 12:14:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:14:19 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-bQ4iRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:14:19 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)) 12:14:19 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)) 12:14:19 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)) 12:14:19 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)) 12:14:19 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)) 12:14:19 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)) 12:14:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:21 INFO - Downloading blessings-1.6.tar.gz 12:14:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:14:21 INFO - Installing collected packages: blessings 12:14:21 INFO - Running setup.py install for blessings 12:14:21 INFO - Successfully installed blessings 12:14:21 INFO - Cleaning up... 12:14:21 INFO - Return code: 0 12:14:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:14:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:14:22 INFO - Reading from file tmpfile_stdout 12:14:22 INFO - Current package versions: 12:14:22 INFO - blessings == 1.6 12:14:22 INFO - blobuploader == 1.2.4 12:14:22 INFO - docopt == 0.6.1 12:14:22 INFO - functools32 == 3.2.3-2 12:14:22 INFO - jsonschema == 2.5.1 12:14:22 INFO - manifestparser == 1.1 12:14:22 INFO - mozInstall == 1.12 12:14:22 INFO - mozcrash == 0.17 12:14:22 INFO - mozdebug == 0.1 12:14:22 INFO - mozdevice == 0.48 12:14:22 INFO - mozfile == 1.2 12:14:22 INFO - mozhttpd == 0.7 12:14:22 INFO - mozinfo == 0.9 12:14:22 INFO - mozleak == 0.1 12:14:22 INFO - mozlog == 3.3 12:14:22 INFO - moznetwork == 0.27 12:14:22 INFO - mozprocess == 0.23 12:14:22 INFO - mozprofile == 0.28 12:14:22 INFO - mozrunner == 6.12 12:14:22 INFO - mozscreenshot == 0.1 12:14:22 INFO - mozsystemmonitor == 0.3 12:14:22 INFO - moztest == 0.7 12:14:22 INFO - mozversion == 1.4 12:14:22 INFO - psutil == 3.1.1 12:14:22 INFO - requests == 1.2.3 12:14:22 INFO - wsgiref == 0.1.2 12:14:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:22 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 0x10feeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85114d0110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe4ed30>, '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 0x10fbb3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe46b20>, '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.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:14:22 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 12:14:22 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 12:14:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9KG6mg2Vi/Render', 12:14:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:22 INFO - 'HOME': '/Users/cltbld', 12:14:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:22 INFO - 'LOGNAME': 'cltbld', 12:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:22 INFO - 'MOZ_NO_REMOTE': '1', 12:14:22 INFO - 'NO_EM_RESTART': '1', 12:14:22 INFO - 'PAGER': '/bin/cat', 12:14:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:22 INFO - 'PWD': '/builds/slave/test', 12:14:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:22 INFO - 'SHELL': '/bin/bash', 12:14:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iUxJhZjc2i/Listeners', 12:14:22 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:14:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:22 INFO - 'USER': 'cltbld', 12:14:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:22 INFO - 'XPC_FLAGS': '0x0', 12:14:22 INFO - 'XPC_SERVICE_NAME': '0', 12:14:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:22 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)) 12:14:22 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)) 12:14:22 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)) 12:14:22 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)) 12:14:22 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)) 12:14:22 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)) 12:14:22 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)) 12:14:22 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)) 12:14:22 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)) 12:14:22 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)) 12:14:22 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)) 12:14:22 INFO - Cleaning up... 12:14:22 INFO - Return code: 0 12:14:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10feeaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85114d0110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe4ed30>, '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 0x10fbb3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe46b20>, '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.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 12:14:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:14:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:14:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9KG6mg2Vi/Render', 12:14:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:22 INFO - 'HOME': '/Users/cltbld', 12:14:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:22 INFO - 'LOGNAME': 'cltbld', 12:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:22 INFO - 'MOZ_NO_REMOTE': '1', 12:14:22 INFO - 'NO_EM_RESTART': '1', 12:14:22 INFO - 'PAGER': '/bin/cat', 12:14:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:22 INFO - 'PWD': '/builds/slave/test', 12:14:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:22 INFO - 'SHELL': '/bin/bash', 12:14:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iUxJhZjc2i/Listeners', 12:14:22 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:14:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:22 INFO - 'USER': 'cltbld', 12:14:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:22 INFO - 'XPC_FLAGS': '0x0', 12:14:22 INFO - 'XPC_SERVICE_NAME': '0', 12:14:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:14:22 INFO - Cleaning up... 12:14:23 INFO - Return code: 0 12:14:23 INFO - Running post-action listener: _resource_record_post_action 12:14:23 INFO - Running post-action listener: _start_resource_monitoring 12:14:23 INFO - Starting resource monitoring. 12:14:23 INFO - [mozharness: 2016-08-18 19:14:23.040119Z] Finished create-virtualenv step (success) 12:14:23 INFO - [mozharness: 2016-08-18 19:14:23.041046Z] Running install step. 12:14:23 INFO - Running pre-action listener: _resource_record_pre_action 12:14:23 INFO - Running main action method: install 12:14:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:14:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:14:23 INFO - Reading from file tmpfile_stdout 12:14:23 INFO - Detecting whether we're running mozinstall >=1.0... 12:14:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:14:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:14:23 INFO - Reading from file tmpfile_stdout 12:14:23 INFO - Output received: 12:14:23 INFO - Usage: mozinstall [options] installer 12:14:23 INFO - Options: 12:14:23 INFO - -h, --help show this help message and exit 12:14:23 INFO - -d DEST, --destination=DEST 12:14:23 INFO - Directory to install application into. [default: 12:14:23 INFO - "/builds/slave/test"] 12:14:23 INFO - --app=APP Application being installed. [default: firefox] 12:14:23 INFO - mkdir: /builds/slave/test/build/application 12:14:23 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'] 12:14:23 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 12:14:57 INFO - Reading from file tmpfile_stdout 12:14:57 INFO - Output received: 12:14:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:14:57 INFO - Running post-action listener: _resource_record_post_action 12:14:57 INFO - [mozharness: 2016-08-18 19:14:57.221055Z] Finished install step (success) 12:14:57 INFO - [mozharness: 2016-08-18 19:14:57.221234Z] Running run-tests step. 12:14:57 INFO - Running pre-action listener: _resource_record_pre_action 12:14:57 INFO - Running main action method: run_tests 12:14:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:14:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:14:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:14:57 INFO - 2016-08-18 12:14:57.235 screenresolution[1637:7443] starting screenresolution argv=screenresolution get 12:14:57 INFO - 2016-08-18 12:14:57.238 screenresolution[1637:7443] Display 0: 1600x1200x32@60 12:14:57 INFO - 2016-08-18 12:14:57.244 screenresolution[1638:7446] starting screenresolution argv=screenresolution list 12:14:57 INFO - Available Modes on Display 0 12:14:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:14:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:14:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:14:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:14:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:14:57 INFO - Intel Iris: 12:14:57 INFO - Chipset Model: Intel Iris 12:14:57 INFO - Type: GPU 12:14:57 INFO - Bus: Built-In 12:14:57 INFO - VRAM (Dynamic, Max): 1536 MB 12:14:57 INFO - Vendor: Intel (0x8086) 12:14:57 INFO - Device ID: 0x0a2e 12:14:57 INFO - Revision ID: 0x0009 12:14:57 INFO - Displays: 12:14:57 INFO - Display: 12:14:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:14:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:14:57 INFO - Display Serial Number: 1600x1200 60 12:14:57 INFO - Main Display: Yes 12:14:57 INFO - Mirror: Off 12:14:57 INFO - Online: Yes 12:14:57 INFO - Rotation: Supported 12:14:57 INFO - Return code: 0 12:14:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:14:57 INFO - Python 2.7.3 12:14:57 INFO - Return code: 0 12:14:57 INFO - grabbing minidump binary from tooltool 12:14: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')]} 12:14:57 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 0x10fe4ed30>, '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 0x10fbb3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe46b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:14:57 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 12:14:57 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 12:14:57 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 12:14:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:14:57 INFO - Return code: 0 12:14:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:14:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:14:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:14:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:14:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:14:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--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 12:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0095 --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 12:14:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q9KG6mg2Vi/Render', 12:14:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:57 INFO - 'HOME': '/Users/cltbld', 12:14:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:57 INFO - 'LOGNAME': 'cltbld', 12:14:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:14:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:14:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:57 INFO - 'MOZ_NO_REMOTE': '1', 12:14:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:14:57 INFO - 'NO_EM_RESTART': '1', 12:14:57 INFO - 'PAGER': '/bin/cat', 12:14:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:57 INFO - 'PWD': '/builds/slave/test', 12:14:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:14:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:57 INFO - 'SHELL': '/bin/bash', 12:14:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iUxJhZjc2i/Listeners', 12:14:57 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:14:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:57 INFO - 'USER': 'cltbld', 12:14:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:57 INFO - 'XPC_FLAGS': '0x0', 12:14:57 INFO - 'XPC_SERVICE_NAME': '0', 12:14:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--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 12:14:57 INFO - mozversion application_buildid: 20160818095355 12:14:57 INFO - mozversion application_changeset: dce857dd57e2d91ae4c6014cf21e93ef95e4dd36 12:14:57 INFO - mozversion application_display_name: Nightly 12:14:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:14:57 INFO - mozversion application_name: Firefox 12:14:57 INFO - mozversion application_remotingname: firefox 12:14:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:14:57 INFO - mozversion application_vendor: Mozilla 12:14:57 INFO - mozversion application_version: 51.0a1 12:14:57 INFO - mozversion platform_buildid: 20160818095355 12:14:57 INFO - mozversion platform_changeset: dce857dd57e2d91ae4c6014cf21e93ef95e4dd36 12:14:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:14:57 INFO - mozversion platform_version: 51.0a1 12:14:57 INFO - using testdate: 1471547697 12:14:57 INFO - actual date: 1471547697 12:14:57 INFO - starting webserver on 'localhost:49267' 12:14:57 INFO - SUITE-START | Running 2 tests 12:14:57 INFO - TEST-START | dromaeo_css 12:14:57 INFO - operating with platform_type : mac_ 12:14:57 INFO - Initialising browser for dromaeo_css test... 12:14:57 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmp5NKDe6/profile http://localhost:49267/getInfo.html) 12:15:00 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 12:15:00 INFO - PROCESS | 1648 | colorDepth:24 12:15:00 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 12:15:00 INFO - PROCESS | 1648 | __metrics 12:15:00 INFO - PROCESS | 1648 | 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" 12:15:00 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:15:01 INFO - TEST-INFO | 1648: exit 0 12:15:01 INFO - Browser initialized. 12:15:01 INFO - Running cycle 1/1 for dromaeo_css test... 12:15:01 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmp5NKDe6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:15:13 INFO - PROCESS | 1650 | RSS: Main: 228143104 12:15:13 INFO - PROCESS | 1650 | 12:16:04 INFO - PROCESS | 1650 | 1471547764854 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] 12:18:16 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 12:18:17 INFO - PROCESS | 1650 | RSS: Main: 359559168 12:18:17 INFO - PROCESS | 1650 | 12:21:20 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 12:21:21 INFO - PROCESS | 1650 | RSS: Main: 374218752 12:21:21 INFO - PROCESS | 1650 | 12:24:24 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 12:24:24 INFO - PROCESS | 1650 | RSS: Main: 357789696 12:24:24 INFO - PROCESS | 1650 | 12:27:26 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 12:27:27 INFO - PROCESS | 1650 | RSS: Main: 336445440 12:27:27 INFO - PROCESS | 1650 | 12:30:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 12:30:31 INFO - PROCESS | 1650 | RSS: Main: 330313728 12:30:31 INFO - PROCESS | 1650 | 12:33:35 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 12:33:35 INFO - PROCESS | 1650 | RSS: Main: 375140352 12:33:35 INFO - PROCESS | 1650 | 12:33:35 INFO - PROCESS | 1650 | __start_tp_report 12:33:35 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 12:33:35 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 12:33:35 INFO - PROCESS | 1650 | |i|pagename|runs| 12:33:35 INFO - PROCESS | 1650 | |0;dojo.html;3334.883279085232;3352.983235083823;3361.1429085583172;3363.781354211976;3395.2190996070904;5571.8607034824045;5655.095184770437;5676.489115979561;5771.24973753412;5829.504492118175;4678.602318802898;5592.832215033545;5673.914891276624;5711.314642242926;5753.223314852504;5144.614153938451;5190.299309593194;5219.791208351661;5223.738813059351;5277.287566178575;5304.283921670583;5310.575153987691;5334.146536554152;5335.813246536372;5376.865578360553;5130.230465430215;5156.535911767959;5260.316158899319;5274.287971123926;5291.994521041009;5192.3249977503065;5192.844214673566;5203.167493201107;5211.635185537014;5213.218017297398;5102.974485127587;5162.457941916082;5163.870903227412;5173.767180476888;5182.481751824787;4482.8879278017985;5356.973215133899;5670.234518340013;5687.090065589486;5733;6128.500823723256;6183.81448556544;6189.350118237565;6207.937920620781;6210.31686514483;5182.300389447414;6075.276047036286;6144.201253837017;6230.190075290228;6235.9376406235815;5862.14998825168;5880.088586269092;5883.882322353572;5901.439363260483;5906.966280900825;6265.342139075331;6278.811635650938;6282.560220784592;6285.245770507477;6296.275928268216;6023.909641355383;6080.2399700037495;6117.938820611826;6130.367562715148;6169.629822210682;6167.753289868445;6194.907076393858;6201.410865967726;6217.191765070512;6227.906581401283;6192.535559833031;6203.2245969253845;6205.441510264008;6226.719797609118;6228.750849966042;6085.056816193497;6113.02191649334;6122.764110201795;6142.477889379453;6232.906506402408;4962.702237865739;5021.748912554358;5035.345405097314;5035.521625445577;5070.138076527;3794.430835374716;4471.8434854779925;4475.328700694922;4503.279475283939;4508.413906192174;5599.160125981054;5640.379558248589;5648.548116150698;5769.8557536061935;5793.362730099686;6121.602095863755;6185.567010309235;6193.938060619336;6233.189685340877;6253.437190652772;5790.623609465456;5802.100674395476;5817.040188368872;5833.854153646193;5844.649321040752;5446;5469.917951230735;5470.17947307907;5477.233187353773;5482.2873026507095;6120.6299886969155;6197.1324014638885;6235.937640623536;6333.619982800955;6339.492840572743;6217.720202590804;6249.031400132987;6250.687465626609;6266.091416744638;6286;6251.53113516479;6301.086342480223;6307.05394190875;6308.936910631019;6330.335314791881;6041.395860414099;6103.328633850362;6133;6167.229096362955;6172.259328880384;6065.636061836304;6213.347598502092;6214.658193799384;6252.218472690914;6264.718087686157;5080.974595127149;6118.571699980958;6121.47967422774;6126.1423400724825;6130.846728831815;5913.911291330547;5922.5261979040715;6028.246469191351;6112.724927378367;6141.723622436912;5879.176915231786;5976.491998180203;6032.3967603239325;6035.064564992434;6038.3357830637715;6113.29697084841;6136.417040381157;6141.877162456815;6146.6004709693725;6159.106929495288;5804.854878628068;5830.067189249699;5856.590038697421;5866.413358664099;6005.339412664691 12:33:35 INFO - PROCESS | 1650 | |1;ext.html;521.1063026908851;529.8993191293654;530.9256704061432;550.6063164837141;556.6214973817803;31793.205169870766;42221.78889105554;43604;43690.56309436905;43764.343534847;44506.777466112624;44978.550214497845;44992.550074499326;44995;45019.54980450203;9699.70900872975;9863.506824658774;9879.407235565854;9884;9895.851562226573;8201.876971845428;9680.80638387232;9808;9812.116909478145;9816.852747208797;9773.951130244375;9833.901660983405;9834.557444914995;9860.70417887461;9861.901380986206;9730.464824434654;9816.95091524545;9823.263255255848;9830.164436022947;9832.557534910946;9672.371295865747;9777.022297770238;9781;9781.217502599775;9790.90209097907;41857.58142418567;42223.7888810554;42289.788551057354;42314.942126446775;42460.363094553606;3431.4166591679473;3468.341015207128;3468.3930312195516;3486.1981744198724;3525.9294814103832;32004.19989500281;32110.839445802856;32178;32532.02403927862;33053.504197437054;3262.396456655503;3316.8673253069846;3319.2365755876285;3363.798172109681;3423.17843717506;484888;485749;487590;488469;489325.5533722344;55241.723791380784;58355.12467312994;62324.37675623276;62485.68757156276;62544.68727656332;35133.47299790505;35986.820065899505;66049;66194.00708989272;66327.36726327425;15500.30248638814;15966.920165399099;16015.759763603555;16040.679186416206;16059.678806423806;12032.518699251932;12224.694382640328;12240.510379584895;12247.571335003411;12277.69305767363;19498.22866022729;19700.01499924998;19700.90149549243;19718.112684929212;19763.814171149777;6987.650617469106;7569.470137090461;7729.613519324012;7840;7938.126806051329;909.9226565741853;954.9140577348072;1014.5434554450527;1063.6436793674206;1083.5015892689294;30482.847585762374;30521.93173238926;31046.679271450183;31200.843995779876;31214.53178202329;1021.3259248895694;1105.5688281570194;1112.4548971004149;1120.462178154474;1137.6643890052455;15323.157226352434;15874.888757786845;15994.76007859883;16222.586186234294;16272.83727162737;647366;712384;713590;713919;717338;43130.35304470435;43488.347674784905;49674.019558826585;61908;62197.689011554656;1225.3689349984766;1364.1337750528287;1387.7987691784635;1401.2153194211273;1410.8659677330638;30844.38311233718;32069.518957215194;32104.678953211103;32903.67096329102;33577.83211083929;52183.478165217864;58601.4139858613;60331.69834150801;60551.394486054574;64101.67949160224;617263;622483;624585;625860;629468;35916.4612530807;36853.44719829258;43769;44837.32744008777;45057.324140137265;46546.528686412275;48835;49579.51261462162;49652.75173624109;49889;40350.39474407827;40539.1892162161;40911.38632920568;41230.495407889466;41982.790086049376;40120.99395030178;40226;40400.595994039686;41905.742827715214;42170.25275955832;39311;39756.40365394464;40230.798846005586;40362.394564080976;40423.59576404198;32486.83756581202;34158;34993.65006349904;35360.46959295561;36205.637943620226;22572.774272257724;24982.875770590003;25428.745712542637;25793.484130317665;26025.958961642078 12:33:35 INFO - PROCESS | 1650 | |2;jquery.html;356.24653857092255;377.80731826768357;381.3745457449782;388.1150975775233;409.9467069280993;15510.612234694137;15580.766288505682;20828.68756968643;21142.682859757115;21238.46903827403;20667.379978600668;20985.950702464856;21048.789512104875;21321.893390533027;21627.45931351713;5813.970930145365;5921.644701317913;6014.789482368118;6096.4513193812545;6109.3890610939;4162.916741665165;5930.161649888527;6048.062550304688;6059.18201042858;6410.23077230733;6658.533902626794;6667.199936007686;6697.933020669804;6706.295838936914;6729.528932974694;4672;6029.668368239745;6086.513078953673;6099.664518451484;6673.733050677967;5639.492445679867;5825.359210486843;5872.5889187756875;5883.294004719418;5889.676067816268;13094.214347139106;13511;13543.729125417436;13562.321883905865;13564.796528052088;2364.4916342986166;2393.664886915833;2398.484325869929;2410.1564452441507;2422.987885060581;15719.52841414748;16890.91554542232;17258;17354.39259625907;17774.200160992787;1868.5328667833041;2063.9793602063937;2120.692499587551;2142.8500004999655;2182.3998400439846;111622.44188779085;112201.87798121996;138886.5278368049;150935.73596396172;151336;17619;20484.283050093174;21734.89132554327;21859.781402186094;21901.890490547445;35705.64294357023;35730;35804.28391432157;35858;35993.64006359955;8877.822443551093;10360.844587331196;10589.735256618646;10761.246712730033;10832.566697332019;7319.194888562254;8737.475751454933;8744.606492707844;8779.204210190584;8936.017038125801;8117.675292988332;8151.592420378957;9808.558614862348;9884.270252705748;9905.20758339331;3714.201446688921;3820.751651142667;3825.69394448449;3827.7129215308964;3844.8462061517225;851.9574021298911;874.8862647855865;890.8307421589877;903.0788595632549;973.9366941148803;21233.68149477759;21959.451013724465;21993.450163745714;22035.449113772283;22081.668774968388;916.637928018429;934.5140526926097;936.8547875079373;967.0667805567521;981.5190556627201;7758.456908016497;8895.599698013604;11369.658910232707;11453.484593193327;11517.078633709283;606196;608100;610655;619233;619384;132466;135076;135096;137414.62585374017;138764.30617846845;841.2428814067389;877.863931090682;929.5770424456856;975.3708857786688;985.881694196701;19371.806281937;19819.009049547753;19973.20107195754;20035.39893803188;20069.29757458482;54402;55359.723201383735;55424;55741.72129139328;56766.716166420556;79560.60219698865;81381;81731.59134204291;82057.9485512867;83811;6227.501799856092;7389.928460373323;7555.811104722426;8040.919590804017;8212.46618969765;11633.302001879914;12559.686007849878;12603.555617736321;12848;12904.225746455242;10584.417857017937;10729.568834532904;10775.676729698122;10962.835557466486;11012.559497620005;10551.78896422052;10620.628278010232;10738;10935.726606834893;11161.497732602214;10862.185336099983;10926.289791163548;11034.393108379116;11171.888281117084;11543;7124;7286.198518162892;7306;7464.813379665335;8454.66181352739;9418.905810941802;9528.761780955254;10538.420386878795;10966.396848173108;10983.395913224529 12:33:35 INFO - PROCESS | 1650 | |3;mootools.html;1516.8710659593935;1559.9454019109332;1706.8293170682925;1807.3854889337622;1843.7142242952339;8441.788955276113;8838.425502342343;8841.734747957564;8846.42498237614;8862.77843053924;7784.727534536292;8028.237317454832;8384.496930184194;8596.3122950164;8638.265747411477;2343.66016927545;2369.964450533235;2370.182287110944;2377.8705115070375;2383.1420688552107;2339.847909885852;2367.6448532720055;2389.1279682915697;2390.294863015406;2394.2099107294553;2087.499000239947;2158.2661894955713;2158.985276919845;2164.5238047629496;2167.1873047607146;2306.6194077977116;2371.584972629783;2372.561076200891;2374.430136767172;2388.904443822245;2359.127122964497;2373.4066483379156;2375.346779635614;2382.7617238276034;2388.7491813359693;8773.517456539888;8804.119588041209;8853.380263381567;8856.247218986395;8862.86705699415;5119.078565858144;5187.5071868172845;5256.684598924076;5261.552768014723;5265.7893684252585;3182.5385319128595;3569.625189355132;3682.9263414731554;3720.665140137374;3754.3242216401036;1064.1805809526697;1105.950232239551;1124.5895248234417;1131.4964840645916;1138.3568283919558;11520.078393728481;11521.827172592419;11555.595554155565;11567.768644627144;11622.360770157638;4563.657725670588;4623.653226008063;4715.929261061086;4728.148933191989;4760.404949381327;9851;9881.555330010024;9925.305228633926;9937.403755774676;9953;5305.681659100466;5324.92012619811;5328.946710532922;5329.094054010828;5331.44019877915;4773.64197685175;4780.593649539828;4885.975570122127;4904.043711476228;4909.26361045846;3546.858125674944;3868.207017561393;3868.916703323074;3923.293807114747;4059.2490389278078;1107.3466654673782;1274.1144904291614;1277.7636137314626;1279.7248591552861;1293.1465232946211;788.4756636836554;789.9802504937422;890.6214858685033;932.1750251027754;935.2891802230218;5686.687232202185;5832.096025116114;5942.1383899335215;5999.940000599938;6172.8765424691255;439.45946485822617;446.62706639956;457.01969275903;462.1150496798593;462.5582568646934;3852.9211820690252;4001.1797581495725;4042.5755295694116;4139.875803725833;4140.565240649748;10138.949305253574;10663.306885052447;10671;10679.252452328263;10681.465926703633;10205.183585313158;10570.577176912837;10595.364278143335;10641.787164256672;10664.040236378763;8015.438919275594;8209.83580328402;8250.298724608476;8252.257296843312;8262.710805121791;5924.8815023700145;6036.215292012011;6066.3023752269055;6128.816135515942;6164.445199931848;9739.707808765748;9784.266180036384;9856.260780441353;9869.309148359545;10006.299559030798;9753.366031207948;9932.205423565953;10109.241806864662;10261.794764104527;10303.330283531546;7394.038774959221;7488.850222995618;7544.509606875536;7567.962160189164;7661.425393095428;8172.978377702787;8246.216609422096;8246.670133194604;8251;8267.462614930011;7267.273272672691;7440.92559074424;7500.812479687833;7577.583232922022;7590.354819840375;7164.498485105993;7263.537044039001;7351.632418378953;7357.411407087313;7469.066366704162;6733.326667333227;6760.256371799;6801.2858649841055;6805.319468053155;6809.897851532132;5024.221245706921;5041.42023667283;5108.3103780989095;5124.359455068116;5159.845204643867;4778.966567167105;4998.6500944933505;5143.382794064587;5232.215167724719;5248.58011359098 12:33:35 INFO - PROCESS | 1650 | |4;prototype.html;303.2812235003043;309.71351499862624;312.954621579871;317.971382575568;322.39711739047954;4527.547245275471;4717.4574923883765;5413.45865413459;5429.321334833146;5472.589555783317;5352.357717073947;5355.9196612050755;5371.57027437805;5431.674099554029;5436.401995780471;3226.0644413120285;3408.096854333591;3447.8105053756412;3868.9613103868883;4031.979840100795;4081.510218773751;4101.805534039289;4107;4129.298019336705;4140.482439695038;4051.635352818246;4052.3111071117983;4059.4316795648633;4088.8364465421346;4089.9795501022454;3131.467650499409;3601.6218297078954;3768.623137686237;4051.412545180947;4065.715399922007;3008.4735171345146;3466.7053300469493;3520.2959408118477;3520.9471857921894;3556.964430355715;4523.801192683942;5050.671706339076;5160.303359046518;5199.38642643363;5246.449122842085;3921.960480627508;4434.122465288189;4502.649205238416;4560.270356742905;4582.198115329804;5573.972130139324;5591.832245032656;5609.411011843779;5610.747516361774;5647.887042259132;3893.474380958591;3921.4706014687936;3935.9569714025806;3937.940930886039;3941.8423263069444;5093.694378337312;5683.261176047087;5763.6253643513455;5909.674967876765;5949;3241.5137729340786;3297.241634424096;3397.708870629145;3948.58539853317;3978.781167035783;3690.797006164633;3991.640752332304;3996.2606917720314;4009.249039887684;4024.8188831502653;4193.266178418765;4554.931676024862;4649.883752906137;4685.718856868531;4705.694129881546;4693.601043911238;5337.557275557244;5561.916571251434;5609.80365687199;5623.606347555714;1342.3556692787322;1370.8834749046243;1370.9657258568416;1372.6842826150041;1372.9931350343184;795.0538858758175;817.7056259746487;823.950562966224;840.3193413335218;861.224897592172;4699.953000469952;5039.748012599355;5179.378474583074;5274.97138058075;5387.353517577916;5868.530517558585;5891.558133139946;5929.792457264061;5940.316863560746;5958.493528050164;4431.446069241345;4954.380702412199;5361.35663720356;5380.009396523234;5382.49290198745;2545.707243667002;2766.7233276672173;3038.756899448083;3047.7409420199533;3055.2514633914834;5246.711430871263;6010.205254556652;6028.517718582503;6068.908966365508;6072.574919755575;5344.7552028148275;5543.750531226105;5950.375210602911;5992.460678538952;6049.78825741097;5107.770150343244;6105.084237364342;6111.96944015277;6121.877562448726;6133.975787989863;5199.29809475716;5589.860253493695;5844.620099693507;5895.764169433174;5960.3443621202505;5790.768369265183;5793.217915581343;5841.357450680507;5843.503302219189;5857.912131318119;5193.052605273023;5619.207035251271;5794.5364370851075;6051.878962420815;6176.1970943778815;3354.5135955286837;3634.800085995296;3671.541057367829;3694.7413681042067;4032.5564187938717;4760.714357138583;5161.922571161511;5399.894041323965;5776.682282474504;5846.006178949504;5201.791928322825;5307.601929855199;5750.338711048283;5755.71221438918;5801.738921748616;5233.7383130842545;5509.3664228614225;5548.944510554843;5683.232763576864;5694;5525.502704756591;5536.75084621201;5557.72211389437;5566.248556444828;5578.470045345685;5016.598672106295;5030.773615187399;5218.182181621882;5463.535599474089;5531.778728850962;4645.605123564535;5350.491703288181;5540.836424350931;5613.46672066153;5616.69108199053 12:33:35 INFO - PROCESS | 1650 | |5;yui.html;706.9081019467468;736.7679181057966;768.9115751688557;838.3712215838124;848.478185915662;15626.453074142408;16687.916560417212;16700.415485458012;16709.415170469005;16739.163041847904;15087.170205638686;15106.471273505429;15142.545723628282;15170.54488365348;15233.77149342758;673.2224280955504;690.4165979747106;691.5055735149367;693.9548929319578;846.4075147396832;681.6319187638694;687.3710554842313;850.268768858781;881.2949640287776;884.5046773806657;913.1051569461931;916.5463095767604;919.9310051746112;929.2287401456808;932.2402242172642;921.4977837078741;921.7373048681161;922.1792604581927;923.6951805904035;925.8055808280269;518.4763388977123;684.5379368925956;685.6640246279337;686.3445409633788;686.4268335939496;12355;12464.626061218178;12488.438020289108;12492.125551211418;14328.856711432856;14119.294035298297;14968.251587420586;15010.849891501055;15013.549593512213;15016.699666006727;11880.702982425422;11930.105242106876;11967.880321196764;12108.091893107963;12218.633440996784;3842.865499707497;3861.9227615447817;3885.708571857094;3920.0983773731914;4025.5169379674335;114414;115558.42220788842;116517;120283;122585;11234.269772464764;11261.66215013551;11290.435478226056;11407.600733974437;11644.184907056424;23092.769072309064;23154.884225579102;23197.652035219486;23205.839708014533;23226.883865580563;8065.282839486653;8081.191880811872;8118.35053195743;8142.918570814216;8182;4500.369948207253;4514.571115743999;4606.262997920317;4736.029114031615;4771.141194585008;3562.2163124112653;3698.260347930425;3721.1441368484857;3722;3745.007572993159;651.5374084400129;669.2571245917028;670.9261981182066;692.4149094015548;699.3705664901629;567.6934455394123;571.894199573072;675.4431036461004;686.3376841348023;710.4600503617288;9514.33399662017;9610.087041731025;9665.90334096664;9705.417674939526;9759.756006099762;590.2150140313373;616.7964571691331;648.1541588227409;649.4641920415676;684.1004079635263;4458.977705111519;4509.233430316854;4527.547245275446;4547.954520454753;4619.3071039343695;166626.1692989976;167372;171825.14087429733;172706.44476623184;174066;144995;149140.2542987278;150178;150350;152328.47671523143;94457;111853.44073279582;119458;126744.09883851565;127425;8501.894753682229;8569.271611913056;8723.171298726611;8730.257928075931;8836.690715824916;53583.19625205547;65461.34538654552;66711.33288667051;69905.30094698988;71139.5772084545;82564;95743;98157;100096.99903001168;104472.95527044633;12074.45664945097;12537.059720520814;12754.298513581842;13290.800637990245;13350.732985340434;14625.92687036558;15017.774733379227;15124.773128402863;15306.311215995529;15706.21468926572;11169.560142065788;14058.437662493794;14081.436742530184;14528.636784080483;14570.198639074953;13804.17174969505;13879.653008674864;14103;14210.573682789533;14294.785578216128;11799.469023894117;13290.401931912893;13642.249676267504;13816.240106794223;14198.929005354907;11250.493727782434;11454.599089031844;11614.132006421638;11663.708407289547;11915;11272.887271127513;11405.543778248777;11451.427428628702;11585.7103572408;11619.535218591162 12:33:35 INFO - PROCESS | 1650 | __end_tp_report 12:33:35 INFO - PROCESS | 1650 | __start_cc_report 12:33:35 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1293 12:33:35 INFO - PROCESS | 1650 | __end_cc_report 12:33:35 INFO - PROCESS | 1650 | __startTimestamp1471548815394__endTimestamp 12:33:35 INFO - PROCESS | 1650 | 12:33:35 INFO - PROCESS | 1650 | ------- Summary: start ------- 12:33:35 INFO - PROCESS | 1650 | Number of tests: 6 12:33:35 INFO - PROCESS | 1650 | 12:33:35 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5716.91 Median:5913.91 stddev:644.17 (10.9%) stddev-sans-first:618.45 12:33:35 INFO - PROCESS | 1650 | Values: 3334.9 3353.0 3361.1 3363.8 3395.2 5571.9 5655.1 5676.5 5771.2 5829.5 4678.6 5592.8 5673.9 5711.3 5753.2 5144.6 5190.3 5219.8 5223.7 5277.3 5304.3 5310.6 5334.1 5335.8 5376.9 5130.2 5156.5 5260.3 5274.3 5292.0 5192.3 5192.8 5203.2 5211.6 5213.2 5103.0 5162.5 5163.9 5173.8 5182.5 4482.9 5357.0 5670.2 5687.1 5733.0 6128.5 6183.8 6189.4 6207.9 6210.3 5182.3 6075.3 6144.2 6230.2 6235.9 5862.1 5880.1 5883.9 5901.4 5907.0 6265.3 6278.8 6282.6 6285.2 6296.3 6023.9 6080.2 6117.9 6130.4 6169.6 6167.8 6194.9 6201.4 6217.2 6227.9 6192.5 6203.2 6205.4 6226.7 6228.8 6085.1 6113.0 6122.8 6142.5 6232.9 4962.7 5021.7 5035.3 5035.5 5070.1 3794.4 4471.8 4475.3 4503.3 4508.4 5599.2 5640.4 5648.5 5769.9 5793.4 6121.6 6185.6 6193.9 6233.2 6253.4 5790.6 5802.1 5817.0 5833.9 5844.6 5446.0 5469.9 5470.2 5477.2 5482.3 6120.6 6197.1 6235.9 6333.6 6339.5 6217.7 6249.0 6250.7 6266.1 6286.0 6251.5 6301.1 6307.1 6308.9 6330.3 6041.4 6103.3 6133.0 6167.2 6172.3 6065.6 6213.3 6214.7 6252.2 6264.7 5081.0 6118.6 6121.5 6126.1 6130.8 5913.9 5922.5 6028.2 6112.7 6141.7 5879.2 5976.5 6032.4 6035.1 6038.3 6113.3 6136.4 6141.9 6146.6 6159.1 5804.9 5830.1 5856.6 5866.4 6005.3 12:33:35 INFO - PROCESS | 1650 | 12:33:35 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:73993.69 Median:42256.79 stddev:163388.02 (386.7%) stddev-sans-first:163753.24 12:33:35 INFO - PROCESS | 1650 | Values: 521.1 529.9 530.9 550.6 556.6 31793.2 42221.8 43604.0 43690.6 43764.3 44506.8 44978.6 44992.6 44995.0 45019.5 9699.7 9863.5 9879.4 9884.0 9895.9 8201.9 9680.8 9808.0 9812.1 9816.9 9774.0 9833.9 9834.6 9860.7 9861.9 9730.5 9817.0 9823.3 9830.2 9832.6 9672.4 9777.0 9781.0 9781.2 9790.9 41857.6 42223.8 42289.8 42314.9 42460.4 3431.4 3468.3 3468.4 3486.2 3525.9 32004.2 32110.8 32178.0 32532.0 33053.5 3262.4 3316.9 3319.2 3363.8 3423.2 484888.0 485749.0 487590.0 488469.0 489325.6 55241.7 58355.1 62324.4 62485.7 62544.7 35133.5 35986.8 66049.0 66194.0 66327.4 15500.3 15966.9 16015.8 16040.7 16059.7 12032.5 12224.7 12240.5 12247.6 12277.7 19498.2 19700.0 19700.9 19718.1 19763.8 6987.7 7569.5 7729.6 7840.0 7938.1 909.9 954.9 1014.5 1063.6 1083.5 30482.8 30521.9 31046.7 31200.8 31214.5 1021.3 1105.6 1112.5 1120.5 1137.7 15323.2 15874.9 15994.8 16222.6 16272.8 647366.0 712384.0 713590.0 713919.0 717338.0 43130.4 43488.3 49674.0 61908.0 62197.7 1225.4 1364.1 1387.8 1401.2 1410.9 30844.4 32069.5 32104.7 32903.7 33577.8 52183.5 58601.4 60331.7 60551.4 64101.7 617263.0 622483.0 624585.0 625860.0 629468.0 35916.5 36853.4 43769.0 44837.3 45057.3 46546.5 48835.0 49579.5 49652.8 49889.0 40350.4 40539.2 40911.4 41230.5 41982.8 40121.0 40226.0 40400.6 41905.7 42170.3 39311.0 39756.4 40230.8 40362.4 40423.6 32486.8 34158.0 34993.7 35360.5 36205.6 22572.8 24982.9 25428.7 25793.5 26026.0 12:33:35 INFO - PROCESS | 1650 | 12:33:35 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:37157.81 Median:19853.92 stddev:102636.97 (517.0%) stddev-sans-first:102887.70 12:33:35 INFO - PROCESS | 1650 | Values: 356.2 377.8 381.4 388.1 409.9 15510.6 15580.8 20828.7 21142.7 21238.5 20667.4 20986.0 21048.8 21321.9 21627.5 5814.0 5921.6 6014.8 6096.5 6109.4 4162.9 5930.2 6048.1 6059.2 6410.2 6658.5 6667.2 6697.9 6706.3 6729.5 4672.0 6029.7 6086.5 6099.7 6673.7 5639.5 5825.4 5872.6 5883.3 5889.7 13094.2 13511.0 13543.7 13562.3 13564.8 2364.5 2393.7 2398.5 2410.2 2423.0 15719.5 16890.9 17258.0 17354.4 17774.2 1868.5 2064.0 2120.7 2142.9 2182.4 111622.4 112201.9 138886.5 150935.7 151336.0 17619.0 20484.3 21734.9 21859.8 21901.9 35705.6 35730.0 35804.3 35858.0 35993.6 8877.8 10360.8 10589.7 10761.2 10832.6 7319.2 8737.5 8744.6 8779.2 8936.0 8117.7 8151.6 9808.6 9884.3 9905.2 3714.2 3820.8 3825.7 3827.7 3844.8 852.0 874.9 890.8 903.1 973.9 21233.7 21959.5 21993.5 22035.4 22081.7 916.6 934.5 936.9 967.1 981.5 7758.5 8895.6 11369.7 11453.5 11517.1 606196.0 608100.0 610655.0 619233.0 619384.0 132466.0 135076.0 135096.0 137414.6 138764.3 841.2 877.9 929.6 975.4 985.9 19371.8 19819.0 19973.2 20035.4 20069.3 54402.0 55359.7 55424.0 55741.7 56766.7 79560.6 81381.0 81731.6 82057.9 83811.0 6227.5 7389.9 7555.8 8040.9 8212.5 11633.3 12559.7 12603.6 12848.0 12904.2 10584.4 10729.6 10775.7 10962.8 11012.6 10551.8 10620.6 10738.0 10935.7 11161.5 10862.2 10926.3 11034.4 11171.9 11543.0 7124.0 7286.2 7306.0 7464.8 8454.7 9418.9 9528.8 10538.4 10966.4 10983.4 12:33:35 INFO - PROCESS | 1650 | 12:33:35 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5683.98 Median:5011.44 stddev:3185.86 (63.6%) stddev-sans-first:3179.41 12:33:35 INFO - PROCESS | 1650 | Values: 1516.9 1559.9 1706.8 1807.4 1843.7 8441.8 8838.4 8841.7 8846.4 8862.8 7784.7 8028.2 8384.5 8596.3 8638.3 2343.7 2370.0 2370.2 2377.9 2383.1 2339.8 2367.6 2389.1 2390.3 2394.2 2087.5 2158.3 2159.0 2164.5 2167.2 2306.6 2371.6 2372.6 2374.4 2388.9 2359.1 2373.4 2375.3 2382.8 2388.7 8773.5 8804.1 8853.4 8856.2 8862.9 5119.1 5187.5 5256.7 5261.6 5265.8 3182.5 3569.6 3682.9 3720.7 3754.3 1064.2 1106.0 1124.6 1131.5 1138.4 11520.1 11521.8 11555.6 11567.8 11622.4 4563.7 4623.7 4715.9 4728.1 4760.4 9851.0 9881.6 9925.3 9937.4 9953.0 5305.7 5324.9 5328.9 5329.1 5331.4 4773.6 4780.6 4886.0 4904.0 4909.3 3546.9 3868.2 3868.9 3923.3 4059.2 1107.3 1274.1 1277.8 1279.7 1293.1 788.5 790.0 890.6 932.2 935.3 5686.7 5832.1 5942.1 5999.9 6172.9 439.5 446.6 457.0 462.1 462.6 3852.9 4001.2 4042.6 4139.9 4140.6 10138.9 10663.3 10671.0 10679.3 10681.5 10205.2 10570.6 10595.4 10641.8 10664.0 8015.4 8209.8 8250.3 8252.3 8262.7 5924.9 6036.2 6066.3 6128.8 6164.4 9739.7 9784.3 9856.3 9869.3 10006.3 9753.4 9932.2 10109.2 10261.8 10303.3 7394.0 7488.9 7544.5 7568.0 7661.4 8173.0 8246.2 8246.7 8251.0 8267.5 7267.3 7440.9 7500.8 7577.6 7590.4 7164.5 7263.5 7351.6 7357.4 7469.1 6733.3 6760.3 6801.3 6805.3 6809.9 5024.2 5041.4 5108.3 5124.4 5159.8 4779.0 4998.7 5143.4 5232.2 5248.6 12:33:35 INFO - PROCESS | 1650 | 12:33:35 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4561.92 Median:5200.59 stddev:1436.87 (27.6%) stddev-sans-first:1404.90 12:33:35 INFO - PROCESS | 1650 | Values: 303.3 309.7 313.0 318.0 322.4 4527.5 4717.5 5413.5 5429.3 5472.6 5352.4 5355.9 5371.6 5431.7 5436.4 3226.1 3408.1 3447.8 3869.0 4032.0 4081.5 4101.8 4107.0 4129.3 4140.5 4051.6 4052.3 4059.4 4088.8 4090.0 3131.5 3601.6 3768.6 4051.4 4065.7 3008.5 3466.7 3520.3 3520.9 3557.0 4523.8 5050.7 5160.3 5199.4 5246.4 3922.0 4434.1 4502.6 4560.3 4582.2 5574.0 5591.8 5609.4 5610.7 5647.9 3893.5 3921.5 3936.0 3937.9 3941.8 5093.7 5683.3 5763.6 5909.7 5949.0 3241.5 3297.2 3397.7 3948.6 3978.8 3690.8 3991.6 3996.3 4009.2 4024.8 4193.3 4554.9 4649.9 4685.7 4705.7 4693.6 5337.6 5561.9 5609.8 5623.6 1342.4 1370.9 1371.0 1372.7 1373.0 795.1 817.7 824.0 840.3 861.2 4700.0 5039.7 5179.4 5275.0 5387.4 5868.5 5891.6 5929.8 5940.3 5958.5 4431.4 4954.4 5361.4 5380.0 5382.5 2545.7 2766.7 3038.8 3047.7 3055.3 5246.7 6010.2 6028.5 6068.9 6072.6 5344.8 5543.8 5950.4 5992.5 6049.8 5107.8 6105.1 6112.0 6121.9 6134.0 5199.3 5589.9 5844.6 5895.8 5960.3 5790.8 5793.2 5841.4 5843.5 5857.9 5193.1 5619.2 5794.5 6051.9 6176.2 3354.5 3634.8 3671.5 3694.7 4032.6 4760.7 5161.9 5399.9 5776.7 5846.0 5201.8 5307.6 5750.3 5755.7 5801.7 5233.7 5509.4 5548.9 5683.2 5694.0 5525.5 5536.8 5557.7 5566.2 5578.5 5016.6 5030.8 5218.2 5463.5 5531.8 4645.6 5350.5 5540.8 5613.5 5616.7 12:33:35 INFO - PROCESS | 1650 | 12:33:35 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:26756.69 Median:13394.55 stddev:44217.74 (330.1%) stddev-sans-first:44298.53 12:33:35 INFO - PROCESS | 1650 | Values: 706.9 736.8 768.9 838.4 848.5 15626.5 16687.9 16700.4 16709.4 16739.2 15087.2 15106.5 15142.5 15170.5 15233.8 673.2 690.4 691.5 694.0 846.4 681.6 687.4 850.3 881.3 884.5 913.1 916.5 919.9 929.2 932.2 921.5 921.7 922.2 923.7 925.8 518.5 684.5 685.7 686.3 686.4 12355.0 12464.6 12488.4 12492.1 14328.9 14119.3 14968.3 15010.8 15013.5 15016.7 11880.7 11930.1 11967.9 12108.1 12218.6 3842.9 3861.9 3885.7 3920.1 4025.5 114414.0 115558.4 116517.0 120283.0 122585.0 11234.3 11261.7 11290.4 11407.6 11644.2 23092.8 23154.9 23197.7 23205.8 23226.9 8065.3 8081.2 8118.4 8142.9 8182.0 4500.4 4514.6 4606.3 4736.0 4771.1 3562.2 3698.3 3721.1 3722.0 3745.0 651.5 669.3 670.9 692.4 699.4 567.7 571.9 675.4 686.3 710.5 9514.3 9610.1 9665.9 9705.4 9759.8 590.2 616.8 648.2 649.5 684.1 4459.0 4509.2 4527.5 4548.0 4619.3 166626.2 167372.0 171825.1 172706.4 174066.0 144995.0 149140.3 150178.0 150350.0 152328.5 94457.0 111853.4 119458.0 126744.1 127425.0 8501.9 8569.3 8723.2 8730.3 8836.7 53583.2 65461.3 66711.3 69905.3 71139.6 82564.0 95743.0 98157.0 100097.0 104473.0 12074.5 12537.1 12754.3 13290.8 13350.7 14625.9 15017.8 15124.8 15306.3 15706.2 11169.6 14058.4 14081.4 14528.6 14570.2 13804.2 13879.7 14103.0 14210.6 14294.8 11799.5 13290.4 13642.2 13816.2 14198.9 11250.5 11454.6 11614.1 11663.7 11915.0 11272.9 11405.5 11451.4 11585.7 11619.5 12:33:35 INFO - PROCESS | 1650 | -------- Summary: end -------- 12:33:35 INFO - PROCESS | 1650 | 12:33:36 INFO - TEST-INFO | 1650: exit 0 12:33:36 INFO - TEST-OK | dromaeo_css | took 1118228ms 12:33:36 INFO - TEST-START | kraken 12:33:36 INFO - operating with platform_type : mac_ 12:33:36 INFO - Initialising browser for kraken test... 12:33:36 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpYmDPkO/profile http://localhost:49267/getInfo.html) 12:33:38 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 12:33:38 INFO - PROCESS | 1660 | colorDepth:24 12:33:38 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 12:33:38 INFO - PROCESS | 1660 | __metrics 12:33:38 INFO - PROCESS | 1660 | 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" 12:33:38 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:33:39 INFO - TEST-INFO | 1660: exit 0 12:33:39 INFO - Browser initialized. 12:33:39 INFO - Running cycle 1/1 for kraken test... 12:33:39 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpYmDPkO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:33:51 INFO - PROCESS | 1661 | RSS: Main: 228102144 12:33:51 INFO - PROCESS | 1661 | 12:33:53 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 12:33:54 INFO - PROCESS | 1661 | RSS: Main: 286482432 12:33:54 INFO - PROCESS | 1661 | 12:33:57 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 12:33:58 INFO - PROCESS | 1661 | RSS: Main: 295563264 12:33:58 INFO - PROCESS | 1661 | 12:34:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 12:34:02 INFO - PROCESS | 1661 | RSS: Main: 386326528 12:34:02 INFO - PROCESS | 1661 | 12:34:04 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 12:34:04 INFO - PROCESS | 1661 | RSS: Main: 289505280 12:34:04 INFO - PROCESS | 1661 | 12:34:06 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:34:07 INFO - PROCESS | 1661 | RSS: Main: 293822464 12:34:07 INFO - PROCESS | 1661 | 12:34:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 12:34:10 INFO - PROCESS | 1661 | RSS: Main: 332271616 12:34:10 INFO - PROCESS | 1661 | 12:34:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 12:34:14 INFO - PROCESS | 1661 | RSS: Main: 331923456 12:34:14 INFO - PROCESS | 1661 | 12:34:17 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 12:34:17 INFO - PROCESS | 1661 | RSS: Main: 329486336 12:34:17 INFO - PROCESS | 1661 | 12:34:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:34:19 INFO - PROCESS | 1661 | RSS: Main: 300371968 12:34:19 INFO - PROCESS | 1661 | 12:34:21 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:34:22 INFO - PROCESS | 1661 | RSS: Main: 308768768 12:34:22 INFO - PROCESS | 1661 | 12:34:24 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:34:25 INFO - PROCESS | 1661 | RSS: Main: 307937280 12:34:25 INFO - PROCESS | 1661 | 12:34:28 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:34:28 INFO - PROCESS | 1661 | RSS: Main: 315228160 12:34:28 INFO - PROCESS | 1661 | 12:34:31 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:34:32 INFO - PROCESS | 1661 | RSS: Main: 316280832 12:34:32 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 12:34:34 INFO - PROCESS | 1661 | RSS: Main: 359673856 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | __start_tp_report 12:34:34 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 12:34:34 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 12:34:34 INFO - PROCESS | 1661 | |i|pagename|runs| 12:34:34 INFO - PROCESS | 1661 | |0;ai-astar;103;112;112;111;117;163;160;113;112;112 12:34:34 INFO - PROCESS | 1661 | |1;audio-beat-detection;166;140;139;164;108;132;109;109;150;92 12:34:34 INFO - PROCESS | 1661 | |2;audio-dft;181;184;183;182;184;182;182;183;183;179 12:34:34 INFO - PROCESS | 1661 | |3;audio-fft;80;57;66;99;58;58;66;64;83;56 12:34:34 INFO - PROCESS | 1661 | |4;audio-oscillator;83;69;102;74;75;68;96;71;67;101 12:34:34 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;86;83;85;83;86;83;87;82;87;84 12:34:34 INFO - PROCESS | 1661 | |6;imaging-darkroom;93;92;93;93;92;92;93;93;91;92 12:34:34 INFO - PROCESS | 1661 | |7;imaging-desaturate;84;84;84;84;85;84;83;83;88;90 12:34:34 INFO - PROCESS | 1661 | |8;json-parse-financial;55;51;53;53;52;55;54;54;54;54 12:34:34 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;63;72;67;74;70;67;67;68;70;70 12:34:34 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;87;86;85;87;82;84;87;90;89;88 12:34:34 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;140;140;139;137;137;137;139;135;157;136 12:34:34 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;158;169;194;231;217;167;167;169;166;169 12:34:34 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;66;67;68;67;77;86;84;84;81;66 12:34:34 INFO - PROCESS | 1661 | __end_tp_report 12:34:34 INFO - PROCESS | 1661 | __start_cc_report 12:34:34 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2098 12:34:34 INFO - PROCESS | 1661 | __end_cc_report 12:34:34 INFO - PROCESS | 1661 | __startTimestamp1471548874351__endTimestamp 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | ------- Summary: start ------- 12:34:34 INFO - PROCESS | 1661 | Number of tests: 14 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:121.50 Median:112.50 stddev:21.37 (19.0%) stddev-sans-first:21.59 12:34:34 INFO - PROCESS | 1661 | Values: 103.0 112.0 112.0 111.0 117.0 163.0 160.0 113.0 112.0 112.0 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:145.00 stddev:25.47 (17.6%) stddev-sans-first:23.64 12:34:34 INFO - PROCESS | 1661 | Values: 166.0 140.0 139.0 164.0 108.0 132.0 109.0 109.0 150.0 92.0 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:182.30 Median:183.00 stddev:1.49 (0.8%) stddev-sans-first:1.51 12:34:34 INFO - PROCESS | 1661 | Values: 181.0 184.0 183.0 182.0 184.0 182.0 182.0 183.0 183.0 179.0 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:68.70 Median:66.00 stddev:14.20 (21.5%) stddev-sans-first:14.46 12:34:34 INFO - PROCESS | 1661 | Values: 80.0 57.0 66.0 99.0 58.0 58.0 66.0 64.0 83.0 56.0 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:80.60 Median:72.50 stddev:13.99 (19.3%) stddev-sans-first:14.82 12:34:34 INFO - PROCESS | 1661 | Values: 83.0 69.0 102.0 74.0 75.0 68.0 96.0 71.0 67.0 101.0 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:84.60 Median:85.50 stddev:1.84 (2.1%) stddev-sans-first:1.88 12:34:34 INFO - PROCESS | 1661 | Values: 86.0 83.0 85.0 83.0 86.0 83.0 87.0 82.0 87.0 84.0 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:92.40 Median:93.00 stddev:0.70 (0.8%) stddev-sans-first:0.71 12:34:34 INFO - PROCESS | 1661 | Values: 93.0 92.0 93.0 93.0 92.0 92.0 93.0 93.0 91.0 92.0 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:84.00 stddev:2.28 (2.7%) stddev-sans-first:2.40 12:34:34 INFO - PROCESS | 1661 | Values: 84.0 84.0 84.0 84.0 85.0 84.0 83.0 83.0 88.0 90.0 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:1.27 (2.4%) stddev-sans-first:1.22 12:34:34 INFO - PROCESS | 1661 | Values: 55.0 51.0 53.0 53.0 52.0 55.0 54.0 54.0 54.0 54.0 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:70.00 stddev:3.08 (4.4%) stddev-sans-first:2.46 12:34:34 INFO - PROCESS | 1661 | Values: 63.0 72.0 67.0 74.0 70.0 67.0 67.0 68.0 70.0 70.0 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:87.00 stddev:2.37 (2.7%) stddev-sans-first:2.51 12:34:34 INFO - PROCESS | 1661 | Values: 87.0 86.0 85.0 87.0 82.0 84.0 87.0 90.0 89.0 88.0 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:139.70 Median:139.00 stddev:6.31 (4.5%) stddev-sans-first:6.69 12:34:34 INFO - PROCESS | 1661 | Values: 140.0 140.0 139.0 137.0 137.0 137.0 139.0 135.0 157.0 136.0 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.70 Median:169.00 stddev:24.82 (14.7%) stddev-sans-first:24.92 12:34:34 INFO - PROCESS | 1661 | Values: 158.0 169.0 194.0 231.0 217.0 167.0 167.0 169.0 166.0 169.0 12:34:34 INFO - PROCESS | 1661 | 12:34:34 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.60 Median:79.00 stddev:8.57 (10.8%) stddev-sans-first:8.50 12:34:34 INFO - PROCESS | 1661 | Values: 66.0 67.0 68.0 67.0 77.0 86.0 84.0 84.0 81.0 66.0 12:34:34 INFO - PROCESS | 1661 | -------- Summary: end -------- 12:34:34 INFO - PROCESS | 1661 | 12:34:35 INFO - TEST-INFO | 1661: exit 0 12:34:35 INFO - TEST-OK | kraken | took 59158ms 12:34:35 INFO - SUITE-END | took 1177s 12:34:35 INFO - Completed test suite (00:19:37) 12:34:35 INFO - javascript benchmark 12:34:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.883279085232, 3352.983235083823, 3361.1429085583172, 3363.781354211976, 3395.2190996070904, 5571.8607034824045, 5655.095184770437, 5676.489115979561, 5771.24973753412, 5829.504492118175, 4678.602318802898, 5592.832215033545, 5673.914891276624, 5711.314642242926, 5753.223314852504, 5144.614153938451, 5190.299309593194, 5219.791208351661, 5223.738813059351, 5277.287566178575, 5304.283921670583, 5310.575153987691, 5334.146536554152, 5335.813246536372, 5376.865578360553, 5130.230465430215, 5156.535911767959, 5260.316158899319, 5274.287971123926, 5291.994521041009, 5192.3249977503065, 5192.844214673566, 5203.167493201107, 5211.635185537014, 5213.218017297398, 5102.974485127587, 5162.457941916082, 5163.870903227412, 5173.767180476888, 5182.481751824787, 4482.8879278017985, 5356.973215133899, 5670.234518340013, 5687.090065589486, 5733.0, 6128.500823723256, 6183.81448556544, 6189.350118237565, 6207.937920620781, 6210.31686514483, 5182.300389447414, 6075.276047036286, 6144.201253837017, 6230.190075290228, 6235.9376406235815, 5862.14998825168, 5880.088586269092, 5883.882322353572, 5901.439363260483, 5906.966280900825, 6265.342139075331, 6278.811635650938, 6282.560220784592, 6285.245770507477, 6296.275928268216, 6023.909641355383, 6080.2399700037495, 6117.938820611826, 6130.367562715148, 6169.629822210682, 6167.753289868445, 6194.907076393858, 6201.410865967726, 6217.191765070512, 6227.906581401283, 6192.535559833031, 6203.2245969253845, 6205.441510264008, 6226.719797609118, 6228.750849966042, 6085.056816193497, 6113.02191649334, 6122.764110201795, 6142.477889379453, 6232.906506402408, 4962.702237865739, 5021.748912554358, 5035.345405097314, 5035.521625445577, 5070.138076527, 3794.430835374716, 4471.8434854779925, 4475.328700694922, 4503.279475283939, 4508.413906192174, 5599.160125981054, 5640.379558248589, 5648.548116150698, 5769.8557536061935, 5793.362730099686, 6121.602095863755, 6185.567010309235, 6193.938060619336, 6233.189685340877, 6253.437190652772, 5790.623609465456, 5802.100674395476, 5817.040188368872, 5833.854153646193, 5844.649321040752, 5446.0, 5469.917951230735, 5470.17947307907, 5477.233187353773, 5482.2873026507095, 6120.6299886969155, 6197.1324014638885, 6235.937640623536, 6333.619982800955, 6339.492840572743, 6217.720202590804, 6249.031400132987, 6250.687465626609, 6266.091416744638, 6286.0, 6251.53113516479, 6301.086342480223, 6307.05394190875, 6308.936910631019, 6330.335314791881, 6041.395860414099, 6103.328633850362, 6133.0, 6167.229096362955, 6172.259328880384, 6065.636061836304, 6213.347598502092, 6214.658193799384, 6252.218472690914, 6264.718087686157, 5080.974595127149, 6118.571699980958, 6121.47967422774, 6126.1423400724825, 6130.846728831815, 5913.911291330547, 5922.5261979040715, 6028.246469191351, 6112.724927378367, 6141.723622436912, 5879.176915231786, 5976.491998180203, 6032.3967603239325, 6035.064564992434, 6038.3357830637715, 6113.29697084841, 6136.417040381157, 6141.877162456815, 6146.6004709693725, 6159.106929495288, 5804.854878628068, 5830.067189249699, 5856.590038697421, 5866.413358664099, 6005.339412664691], "value": 5676.527890690013, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [521.1063026908851, 529.8993191293654, 530.9256704061432, 550.6063164837141, 556.6214973817803, 31793.205169870766, 42221.78889105554, 43604.0, 43690.56309436905, 43764.343534847, 44506.777466112624, 44978.550214497845, 44992.550074499326, 44995.0, 45019.54980450203, 9699.70900872975, 9863.506824658774, 9879.407235565854, 9884.0, 9895.851562226573, 8201.876971845428, 9680.80638387232, 9808.0, 9812.116909478145, 9816.852747208797, 9773.951130244375, 9833.901660983405, 9834.557444914995, 9860.70417887461, 9861.901380986206, 9730.464824434654, 9816.95091524545, 9823.263255255848, 9830.164436022947, 9832.557534910946, 9672.371295865747, 9777.022297770238, 9781.0, 9781.217502599775, 9790.90209097907, 41857.58142418567, 42223.7888810554, 42289.788551057354, 42314.942126446775, 42460.363094553606, 3431.4166591679473, 3468.341015207128, 3468.3930312195516, 3486.1981744198724, 3525.9294814103832, 32004.19989500281, 32110.839445802856, 32178.0, 32532.02403927862, 33053.504197437054, 3262.396456655503, 3316.8673253069846, 3319.2365755876285, 3363.798172109681, 3423.17843717506, 484888.0, 485749.0, 487590.0, 488469.0, 489325.5533722344, 55241.723791380784, 58355.12467312994, 62324.37675623276, 62485.68757156276, 62544.68727656332, 35133.47299790505, 35986.820065899505, 66049.0, 66194.00708989272, 66327.36726327425, 15500.30248638814, 15966.920165399099, 16015.759763603555, 16040.679186416206, 16059.678806423806, 12032.518699251932, 12224.694382640328, 12240.510379584895, 12247.571335003411, 12277.69305767363, 19498.22866022729, 19700.01499924998, 19700.90149549243, 19718.112684929212, 19763.814171149777, 6987.650617469106, 7569.470137090461, 7729.613519324012, 7840.0, 7938.126806051329, 909.9226565741853, 954.9140577348072, 1014.5434554450527, 1063.6436793674206, 1083.5015892689294, 30482.847585762374, 30521.93173238926, 31046.679271450183, 31200.843995779876, 31214.53178202329, 1021.3259248895694, 1105.5688281570194, 1112.4548971004149, 1120.462178154474, 1137.6643890052455, 15323.157226352434, 15874.888757786845, 15994.76007859883, 16222.586186234294, 16272.83727162737, 647366.0, 712384.0, 713590.0, 713919.0, 717338.0, 43130.35304470435, 43488.347674784905, 49674.019558826585, 61908.0, 62197.689011554656, 1225.3689349984766, 1364.1337750528287, 1387.7987691784635, 1401.2153194211273, 1410.8659677330638, 30844.38311233718, 32069.518957215194, 32104.678953211103, 32903.67096329102, 33577.83211083929, 52183.478165217864, 58601.4139858613, 60331.69834150801, 60551.394486054574, 64101.67949160224, 617263.0, 622483.0, 624585.0, 625860.0, 629468.0, 35916.4612530807, 36853.44719829258, 43769.0, 44837.32744008777, 45057.324140137265, 46546.528686412275, 48835.0, 49579.51261462162, 49652.75173624109, 49889.0, 40350.39474407827, 40539.1892162161, 40911.38632920568, 41230.495407889466, 41982.790086049376, 40120.99395030178, 40226.0, 40400.595994039686, 41905.742827715214, 42170.25275955832, 39311.0, 39756.40365394464, 40230.798846005586, 40362.394564080976, 40423.59576404198, 32486.83756581202, 34158.0, 34993.65006349904, 35360.46959295561, 36205.637943620226, 22572.774272257724, 24982.875770590003, 25428.745712542637, 25793.484130317665, 26025.958961642078], "value": 20707.807003726095, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.24653857092255, 377.80731826768357, 381.3745457449782, 388.1150975775233, 409.9467069280993, 15510.612234694137, 15580.766288505682, 20828.68756968643, 21142.682859757115, 21238.46903827403, 20667.379978600668, 20985.950702464856, 21048.789512104875, 21321.893390533027, 21627.45931351713, 5813.970930145365, 5921.644701317913, 6014.789482368118, 6096.4513193812545, 6109.3890610939, 4162.916741665165, 5930.161649888527, 6048.062550304688, 6059.18201042858, 6410.23077230733, 6658.533902626794, 6667.199936007686, 6697.933020669804, 6706.295838936914, 6729.528932974694, 4672.0, 6029.668368239745, 6086.513078953673, 6099.664518451484, 6673.733050677967, 5639.492445679867, 5825.359210486843, 5872.5889187756875, 5883.294004719418, 5889.676067816268, 13094.214347139106, 13511.0, 13543.729125417436, 13562.321883905865, 13564.796528052088, 2364.4916342986166, 2393.664886915833, 2398.484325869929, 2410.1564452441507, 2422.987885060581, 15719.52841414748, 16890.91554542232, 17258.0, 17354.39259625907, 17774.200160992787, 1868.5328667833041, 2063.9793602063937, 2120.692499587551, 2142.8500004999655, 2182.3998400439846, 111622.44188779085, 112201.87798121996, 138886.5278368049, 150935.73596396172, 151336.0, 17619.0, 20484.283050093174, 21734.89132554327, 21859.781402186094, 21901.890490547445, 35705.64294357023, 35730.0, 35804.28391432157, 35858.0, 35993.64006359955, 8877.822443551093, 10360.844587331196, 10589.735256618646, 10761.246712730033, 10832.566697332019, 7319.194888562254, 8737.475751454933, 8744.606492707844, 8779.204210190584, 8936.017038125801, 8117.675292988332, 8151.592420378957, 9808.558614862348, 9884.270252705748, 9905.20758339331, 3714.201446688921, 3820.751651142667, 3825.69394448449, 3827.7129215308964, 3844.8462061517225, 851.9574021298911, 874.8862647855865, 890.8307421589877, 903.0788595632549, 973.9366941148803, 21233.68149477759, 21959.451013724465, 21993.450163745714, 22035.449113772283, 22081.668774968388, 916.637928018429, 934.5140526926097, 936.8547875079373, 967.0667805567521, 981.5190556627201, 7758.456908016497, 8895.599698013604, 11369.658910232707, 11453.484593193327, 11517.078633709283, 606196.0, 608100.0, 610655.0, 619233.0, 619384.0, 132466.0, 135076.0, 135096.0, 137414.62585374017, 138764.30617846845, 841.2428814067389, 877.863931090682, 929.5770424456856, 975.3708857786688, 985.881694196701, 19371.806281937, 19819.009049547753, 19973.20107195754, 20035.39893803188, 20069.29757458482, 54402.0, 55359.723201383735, 55424.0, 55741.72129139328, 56766.716166420556, 79560.60219698865, 81381.0, 81731.59134204291, 82057.9485512867, 83811.0, 6227.501799856092, 7389.928460373323, 7555.811104722426, 8040.919590804017, 8212.46618969765, 11633.302001879914, 12559.686007849878, 12603.555617736321, 12848.0, 12904.225746455242, 10584.417857017937, 10729.568834532904, 10775.676729698122, 10962.835557466486, 11012.559497620005, 10551.78896422052, 10620.628278010232, 10738.0, 10935.726606834893, 11161.497732602214, 10862.185336099983, 10926.289791163548, 11034.393108379116, 11171.888281117084, 11543.0, 7124.0, 7286.198518162892, 7306.0, 7464.813379665335, 8454.66181352739, 9418.905810941802, 9528.761780955254, 10538.420386878795, 10966.396848173108, 10983.395913224529], "value": 10464.745249422664, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1516.8710659593935, 1559.9454019109332, 1706.8293170682925, 1807.3854889337622, 1843.7142242952339, 8441.788955276113, 8838.425502342343, 8841.734747957564, 8846.42498237614, 8862.77843053924, 7784.727534536292, 8028.237317454832, 8384.496930184194, 8596.3122950164, 8638.265747411477, 2343.66016927545, 2369.964450533235, 2370.182287110944, 2377.8705115070375, 2383.1420688552107, 2339.847909885852, 2367.6448532720055, 2389.1279682915697, 2390.294863015406, 2394.2099107294553, 2087.499000239947, 2158.2661894955713, 2158.985276919845, 2164.5238047629496, 2167.1873047607146, 2306.6194077977116, 2371.584972629783, 2372.561076200891, 2374.430136767172, 2388.904443822245, 2359.127122964497, 2373.4066483379156, 2375.346779635614, 2382.7617238276034, 2388.7491813359693, 8773.517456539888, 8804.119588041209, 8853.380263381567, 8856.247218986395, 8862.86705699415, 5119.078565858144, 5187.5071868172845, 5256.684598924076, 5261.552768014723, 5265.7893684252585, 3182.5385319128595, 3569.625189355132, 3682.9263414731554, 3720.665140137374, 3754.3242216401036, 1064.1805809526697, 1105.950232239551, 1124.5895248234417, 1131.4964840645916, 1138.3568283919558, 11520.078393728481, 11521.827172592419, 11555.595554155565, 11567.768644627144, 11622.360770157638, 4563.657725670588, 4623.653226008063, 4715.929261061086, 4728.148933191989, 4760.404949381327, 9851.0, 9881.555330010024, 9925.305228633926, 9937.403755774676, 9953.0, 5305.681659100466, 5324.92012619811, 5328.946710532922, 5329.094054010828, 5331.44019877915, 4773.64197685175, 4780.593649539828, 4885.975570122127, 4904.043711476228, 4909.26361045846, 3546.858125674944, 3868.207017561393, 3868.916703323074, 3923.293807114747, 4059.2490389278078, 1107.3466654673782, 1274.1144904291614, 1277.7636137314626, 1279.7248591552861, 1293.1465232946211, 788.4756636836554, 789.9802504937422, 890.6214858685033, 932.1750251027754, 935.2891802230218, 5686.687232202185, 5832.096025116114, 5942.1383899335215, 5999.940000599938, 6172.8765424691255, 439.45946485822617, 446.62706639956, 457.01969275903, 462.1150496798593, 462.5582568646934, 3852.9211820690252, 4001.1797581495725, 4042.5755295694116, 4139.875803725833, 4140.565240649748, 10138.949305253574, 10663.306885052447, 10671.0, 10679.252452328263, 10681.465926703633, 10205.183585313158, 10570.577176912837, 10595.364278143335, 10641.787164256672, 10664.040236378763, 8015.438919275594, 8209.83580328402, 8250.298724608476, 8252.257296843312, 8262.710805121791, 5924.8815023700145, 6036.215292012011, 6066.3023752269055, 6128.816135515942, 6164.445199931848, 9739.707808765748, 9784.266180036384, 9856.260780441353, 9869.309148359545, 10006.299559030798, 9753.366031207948, 9932.205423565953, 10109.241806864662, 10261.794764104527, 10303.330283531546, 7394.038774959221, 7488.850222995618, 7544.509606875536, 7567.962160189164, 7661.425393095428, 8172.978377702787, 8246.216609422096, 8246.670133194604, 8251.0, 8267.462614930011, 7267.273272672691, 7440.92559074424, 7500.812479687833, 7577.583232922022, 7590.354819840375, 7164.498485105993, 7263.537044039001, 7351.632418378953, 7357.411407087313, 7469.066366704162, 6733.326667333227, 6760.256371799, 6801.2858649841055, 6805.319468053155, 6809.897851532132, 5024.221245706921, 5041.42023667283, 5108.3103780989095, 5124.359455068116, 5159.845204643867, 4778.966567167105, 4998.6500944933505, 5143.382794064587, 5232.215167724719, 5248.58011359098], "value": 4494.436799905165, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.2812235003043, 309.71351499862624, 312.954621579871, 317.971382575568, 322.39711739047954, 4527.547245275471, 4717.4574923883765, 5413.45865413459, 5429.321334833146, 5472.589555783317, 5352.357717073947, 5355.9196612050755, 5371.57027437805, 5431.674099554029, 5436.401995780471, 3226.0644413120285, 3408.096854333591, 3447.8105053756412, 3868.9613103868883, 4031.979840100795, 4081.510218773751, 4101.805534039289, 4107.0, 4129.298019336705, 4140.482439695038, 4051.635352818246, 4052.3111071117983, 4059.4316795648633, 4088.8364465421346, 4089.9795501022454, 3131.467650499409, 3601.6218297078954, 3768.623137686237, 4051.412545180947, 4065.715399922007, 3008.4735171345146, 3466.7053300469493, 3520.2959408118477, 3520.9471857921894, 3556.964430355715, 4523.801192683942, 5050.671706339076, 5160.303359046518, 5199.38642643363, 5246.449122842085, 3921.960480627508, 4434.122465288189, 4502.649205238416, 4560.270356742905, 4582.198115329804, 5573.972130139324, 5591.832245032656, 5609.411011843779, 5610.747516361774, 5647.887042259132, 3893.474380958591, 3921.4706014687936, 3935.9569714025806, 3937.940930886039, 3941.8423263069444, 5093.694378337312, 5683.261176047087, 5763.6253643513455, 5909.674967876765, 5949.0, 3241.5137729340786, 3297.241634424096, 3397.708870629145, 3948.58539853317, 3978.781167035783, 3690.797006164633, 3991.640752332304, 3996.2606917720314, 4009.249039887684, 4024.8188831502653, 4193.266178418765, 4554.931676024862, 4649.883752906137, 4685.718856868531, 4705.694129881546, 4693.601043911238, 5337.557275557244, 5561.916571251434, 5609.80365687199, 5623.606347555714, 1342.3556692787322, 1370.8834749046243, 1370.9657258568416, 1372.6842826150041, 1372.9931350343184, 795.0538858758175, 817.7056259746487, 823.950562966224, 840.3193413335218, 861.224897592172, 4699.953000469952, 5039.748012599355, 5179.378474583074, 5274.97138058075, 5387.353517577916, 5868.530517558585, 5891.558133139946, 5929.792457264061, 5940.316863560746, 5958.493528050164, 4431.446069241345, 4954.380702412199, 5361.35663720356, 5380.009396523234, 5382.49290198745, 2545.707243667002, 2766.7233276672173, 3038.756899448083, 3047.7409420199533, 3055.2514633914834, 5246.711430871263, 6010.205254556652, 6028.517718582503, 6068.908966365508, 6072.574919755575, 5344.7552028148275, 5543.750531226105, 5950.375210602911, 5992.460678538952, 6049.78825741097, 5107.770150343244, 6105.084237364342, 6111.96944015277, 6121.877562448726, 6133.975787989863, 5199.29809475716, 5589.860253493695, 5844.620099693507, 5895.764169433174, 5960.3443621202505, 5790.768369265183, 5793.217915581343, 5841.357450680507, 5843.503302219189, 5857.912131318119, 5193.052605273023, 5619.207035251271, 5794.5364370851075, 6051.878962420815, 6176.1970943778815, 3354.5135955286837, 3634.800085995296, 3671.541057367829, 3694.7413681042067, 4032.5564187938717, 4760.714357138583, 5161.922571161511, 5399.894041323965, 5776.682282474504, 5846.006178949504, 5201.791928322825, 5307.601929855199, 5750.338711048283, 5755.71221438918, 5801.738921748616, 5233.7383130842545, 5509.3664228614225, 5548.944510554843, 5683.232763576864, 5694.0, 5525.502704756591, 5536.75084621201, 5557.72211389437, 5566.248556444828, 5578.470045345685, 5016.598672106295, 5030.773615187399, 5218.182181621882, 5463.535599474089, 5531.778728850962, 4645.605123564535, 5350.491703288181, 5540.836424350931, 5613.46672066153, 5616.69108199053], "value": 4102.172625775401, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.9081019467468, 736.7679181057966, 768.9115751688557, 838.3712215838124, 848.478185915662, 15626.453074142408, 16687.916560417212, 16700.415485458012, 16709.415170469005, 16739.163041847904, 15087.170205638686, 15106.471273505429, 15142.545723628282, 15170.54488365348, 15233.77149342758, 673.2224280955504, 690.4165979747106, 691.5055735149367, 693.9548929319578, 846.4075147396832, 681.6319187638694, 687.3710554842313, 850.268768858781, 881.2949640287776, 884.5046773806657, 913.1051569461931, 916.5463095767604, 919.9310051746112, 929.2287401456808, 932.2402242172642, 921.4977837078741, 921.7373048681161, 922.1792604581927, 923.6951805904035, 925.8055808280269, 518.4763388977123, 684.5379368925956, 685.6640246279337, 686.3445409633788, 686.4268335939496, 12355.0, 12464.626061218178, 12488.438020289108, 12492.125551211418, 14328.856711432856, 14119.294035298297, 14968.251587420586, 15010.849891501055, 15013.549593512213, 15016.699666006727, 11880.702982425422, 11930.105242106876, 11967.880321196764, 12108.091893107963, 12218.633440996784, 3842.865499707497, 3861.9227615447817, 3885.708571857094, 3920.0983773731914, 4025.5169379674335, 114414.0, 115558.42220788842, 116517.0, 120283.0, 122585.0, 11234.269772464764, 11261.66215013551, 11290.435478226056, 11407.600733974437, 11644.184907056424, 23092.769072309064, 23154.884225579102, 23197.652035219486, 23205.839708014533, 23226.883865580563, 8065.282839486653, 8081.191880811872, 8118.35053195743, 8142.918570814216, 8182.0, 4500.369948207253, 4514.571115743999, 4606.262997920317, 4736.029114031615, 4771.141194585008, 3562.2163124112653, 3698.260347930425, 3721.1441368484857, 3722.0, 3745.007572993159, 651.5374084400129, 669.2571245917028, 670.9261981182066, 692.4149094015548, 699.3705664901629, 567.6934455394123, 571.894199573072, 675.4431036461004, 686.3376841348023, 710.4600503617288, 9514.33399662017, 9610.087041731025, 9665.90334096664, 9705.417674939526, 9759.756006099762, 590.2150140313373, 616.7964571691331, 648.1541588227409, 649.4641920415676, 684.1004079635263, 4458.977705111519, 4509.233430316854, 4527.547245275446, 4547.954520454753, 4619.3071039343695, 166626.1692989976, 167372.0, 171825.14087429733, 172706.44476623184, 174066.0, 144995.0, 149140.2542987278, 150178.0, 150350.0, 152328.47671523143, 94457.0, 111853.44073279582, 119458.0, 126744.09883851565, 127425.0, 8501.894753682229, 8569.271611913056, 8723.171298726611, 8730.257928075931, 8836.690715824916, 53583.19625205547, 65461.34538654552, 66711.33288667051, 69905.30094698988, 71139.5772084545, 82564.0, 95743.0, 98157.0, 100096.99903001168, 104472.95527044633, 12074.45664945097, 12537.059720520814, 12754.298513581842, 13290.800637990245, 13350.732985340434, 14625.92687036558, 15017.774733379227, 15124.773128402863, 15306.311215995529, 15706.21468926572, 11169.560142065788, 14058.437662493794, 14081.436742530184, 14528.636784080483, 14570.198639074953, 13804.17174969505, 13879.653008674864, 14103.0, 14210.573682789533, 14294.785578216128, 11799.469023894117, 13290.401931912893, 13642.249676267504, 13816.240106794223, 14198.929005354907, 11250.493727782434, 11454.599089031844, 11614.132006421638, 11663.708407289547, 11915.0, 11272.887271127513, 11405.543778248777, 11451.427428628702, 11585.7103572408, 11619.535218591162], "value": 7990.617604044716, "unit": "score"}], "name": "dromaeo_css", "value": 7522.763738757752, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 112.0, 111.0, 117.0, 163.0, 160.0, 113.0, 112.0, 112.0], "value": 121.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 140.0, 139.0, 164.0, 108.0, 132.0, 109.0, 109.0, 150.0, 92.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 183.0, 182.0, 184.0, 182.0, 182.0, 183.0, 183.0, 179.0], "value": 182.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 66.0, 99.0, 58.0, 58.0, 66.0, 64.0, 83.0, 56.0], "value": 68.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 69.0, 102.0, 74.0, 75.0, 68.0, 96.0, 71.0, 67.0, 101.0], "value": 80.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 85.0, 83.0, 86.0, 83.0, 87.0, 82.0, 87.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 93.0, 92.0, 92.0, 93.0, 93.0, 91.0, 92.0], "value": 92.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 85.0, 84.0, 83.0, 83.0, 88.0, 90.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 51.0, 53.0, 53.0, 52.0, 55.0, 54.0, 54.0, 54.0, 54.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 72.0, 67.0, 74.0, 70.0, 67.0, 67.0, 68.0, 70.0, 70.0], "value": 68.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 85.0, 87.0, 82.0, 84.0, 87.0, 90.0, 89.0, 88.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 139.0, 137.0, 137.0, 137.0, 139.0, 135.0, 157.0, 136.0], "value": 139.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 169.0, 194.0, 231.0, 217.0, 167.0, 167.0, 169.0, 166.0, 169.0], "value": 180.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 67.0, 77.0, 86.0, 84.0, 84.0, 81.0, 66.0], "value": 74.6, "unit": "score"}], "name": "kraken", "value": 1449.6999999999998, "alertThreshold": 2.0}]} 12:34:35 INFO - Return code: 0 12:34:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:34:35 INFO - # TBPL SUCCESS # 12:34:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:34:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:34:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:34:35 INFO - 2016-08-18 12:34:35.696 screenresolution[1665:9737] starting screenresolution argv=screenresolution get 12:34:35 INFO - 2016-08-18 12:34:35.698 screenresolution[1665:9737] Display 0: 1600x1200x32@60 12:34:35 INFO - 2016-08-18 12:34:35.704 screenresolution[1666:9741] starting screenresolution argv=screenresolution list 12:34:35 INFO - Available Modes on Display 0 12:34:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:34:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:34:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:34:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:34:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:34:35 INFO - Intel Iris: 12:34:35 INFO - Chipset Model: Intel Iris 12:34:35 INFO - Type: GPU 12:34:35 INFO - Bus: Built-In 12:34:35 INFO - VRAM (Dynamic, Max): 1536 MB 12:34:35 INFO - Vendor: Intel (0x8086) 12:34:35 INFO - Device ID: 0x0a2e 12:34:35 INFO - Revision ID: 0x0009 12:34:35 INFO - Displays: 12:34:35 INFO - Display: 12:34:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:34:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:34:35 INFO - Display Serial Number: 1600x1200 60 12:34:35 INFO - Main Display: Yes 12:34:35 INFO - Mirror: Off 12:34:35 INFO - Online: Yes 12:34:35 INFO - Rotation: Supported 12:34:35 INFO - Return code: 0 12:34:35 INFO - Running post-action listener: _resource_record_post_action 12:34:35 INFO - [mozharness: 2016-08-18 19:34:35.941799Z] Finished run-tests step (success) 12:34:35 INFO - Running post-run listener: _resource_record_post_run 12:34:36 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38355968; Write bytes: 704038912; Read time: 387; Write time: 2211 12:34:36 INFO - TinderboxPrint: CPU usage
53.6% 12:34:36 INFO - TinderboxPrint: I/O read bytes / time
38,355,968 / 387 12:34:36 INFO - TinderboxPrint: I/O write bytes / time
704,038,912 / 2,211 12:34:36 INFO - TinderboxPrint: CPU idle
2,252.1 (46.4%) 12:34:36 INFO - TinderboxPrint: CPU system
969.0 (20.0%) 12:34:36 INFO - TinderboxPrint: CPU user
1,628.6 (33.6%) 12:34:36 INFO - TinderboxPrint: Swap in / out
443,469,824 / 0 12:34:36 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 405094912; Write bytes: 399263744; Read time: 29816; Write time: 683 12:34:36 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34481152; Write bytes: 294813696; Read time: 356; Write time: 1494 12:34:36 INFO - Running post-run listener: _upload_blobber_files 12:34:36 INFO - Blob upload gear active. 12:34:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:34:36 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 12:34:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:34:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:34:36 INFO - (blobuploader) - INFO - Open directory for files ... 12:34:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:34:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:34:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:37 INFO - (blobuploader) - INFO - Done attempting. 12:34:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:34:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:34:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:38 INFO - (blobuploader) - INFO - Done attempting. 12:34:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:34:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:34:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:46 INFO - (blobuploader) - INFO - Done attempting. 12:34:46 INFO - (blobuploader) - INFO - Iteration through files over. 12:34:46 INFO - Return code: 0 12:34:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:34:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:34:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3a52fd44c98da1e59237020b4ce503eb52c812e451dce5ff6090c9360aee7aca8c6aff1afb99ccadd1c1d97d21dd67cf0b10616fad047181b73ab96aa42528c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e6d8f1f436f0eadc23e94d6c053c5c0ac2aa020e708e350e03d3f7d351de3db71f2426ff9dd45912f976855921ad8846186b31e4a74d7fbc98da948479d090f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e71e89777f67d7102b9da5fd52b7975252b6e131b7e4e4386a0202ffa94c8b3d06ba4ba7a7740cd0a49defe70c8a3162e9b38c9a48b8deb4dfcd4a3d02d9a849"} 12:34:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:34:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:34:46 INFO - Contents: 12:34:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3a52fd44c98da1e59237020b4ce503eb52c812e451dce5ff6090c9360aee7aca8c6aff1afb99ccadd1c1d97d21dd67cf0b10616fad047181b73ab96aa42528c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e6d8f1f436f0eadc23e94d6c053c5c0ac2aa020e708e350e03d3f7d351de3db71f2426ff9dd45912f976855921ad8846186b31e4a74d7fbc98da948479d090f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e71e89777f67d7102b9da5fd52b7975252b6e131b7e4e4386a0202ffa94c8b3d06ba4ba7a7740cd0a49defe70c8a3162e9b38c9a48b8deb4dfcd4a3d02d9a849"} 12:34:46 INFO - Running post-run listener: copy_logs_to_upload_dir 12:34:46 INFO - Copying logs to upload dir... 12:34:46 INFO - mkdir: /builds/slave/test/build/upload/logs 12:34:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.897795 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-08-18 12:34:46.184358) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 12:34:46.187822) ========= 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.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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/Fx-Team/sha512/c3a52fd44c98da1e59237020b4ce503eb52c812e451dce5ff6090c9360aee7aca8c6aff1afb99ccadd1c1d97d21dd67cf0b10616fad047181b73ab96aa42528c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e6d8f1f436f0eadc23e94d6c053c5c0ac2aa020e708e350e03d3f7d351de3db71f2426ff9dd45912f976855921ad8846186b31e4a74d7fbc98da948479d090f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e71e89777f67d7102b9da5fd52b7975252b6e131b7e4e4386a0202ffa94c8b3d06ba4ba7a7740cd0a49defe70c8a3162e9b38c9a48b8deb4dfcd4a3d02d9a849"} build_url:https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011024 build_url: 'https://queue.taskcluster.net/v1/task/TqPm8hUsQ12KgHDZoxPzwg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3a52fd44c98da1e59237020b4ce503eb52c812e451dce5ff6090c9360aee7aca8c6aff1afb99ccadd1c1d97d21dd67cf0b10616fad047181b73ab96aa42528c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e6d8f1f436f0eadc23e94d6c053c5c0ac2aa020e708e350e03d3f7d351de3db71f2426ff9dd45912f976855921ad8846186b31e4a74d7fbc98da948479d090f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e71e89777f67d7102b9da5fd52b7975252b6e131b7e4e4386a0202ffa94c8b3d06ba4ba7a7740cd0a49defe70c8a3162e9b38c9a48b8deb4dfcd4a3d02d9a849"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 12:34:46.221863) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 12:34:46.222167) ========= 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.q9KG6mg2Vi/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.iUxJhZjc2i/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.005159 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 12:34:46.270209) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 12:34:46.270514) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 12:34:46.270802) ========= ========= Total master_lag: 0.17 =========