builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0344 starttime: 1471910490.85 results: success (0) buildid: 20160822145214 builduid: bfa0b3d7dfdb4689b676509060d68745 revision: 3e537d8eb88c440fd0b7aa88deec56aa688715b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 17:01:30.849701) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 17:01:30.850143) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 17:01:30.850430) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 17:01:30.873792) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 17:01:30.874150) ========= 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.euDadNXgzw/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.I5auhp3cFA/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-22 17:01:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.47M=0.002s 2016-08-22 17:01:30 (7.47 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091172 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 17:01:30.987712) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 17:01:30.988029) ========= 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.euDadNXgzw/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.I5auhp3cFA/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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.020796 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 17:01:31.037810) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-22 17:01:31.038319) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3e537d8eb88c440fd0b7aa88deec56aa688715b0 --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 3e537d8eb88c440fd0b7aa88deec56aa688715b0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.euDadNXgzw/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.I5auhp3cFA/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-22 17:01:31,115 truncating revision to first 12 chars 2016-08-22 17:01:31,115 Setting DEBUG logging. 2016-08-22 17:01:31,115 attempt 1/10 2016-08-22 17:01:31,115 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3e537d8eb88c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 17:01:31,877 unpacking tar archive at: fx-team-3e537d8eb88c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.017668 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-22 17:01:32.081388) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 17:01:32.081722) ========= script_repo_revision: 3e537d8eb88c440fd0b7aa88deec56aa688715b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 17:01:32.082096) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 17:01:32.082378) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 17:01:32.100655) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-22 17:01:32.100926) ========= /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.euDadNXgzw/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.I5auhp3cFA/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 17:01:32 INFO - MultiFileLogger online at 20160822 17:01:32 in /builds/slave/test 17:01:32 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 17:01:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:01:32 INFO - {'append_to_log': False, 17:01:32 INFO - 'base_work_dir': '/builds/slave/test', 17:01:32 INFO - 'blob_upload_branch': 'Fx-Team', 17:01:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:01:32 INFO - 'branch': 'Fx-Team', 17:01:32 INFO - 'buildbot_json_path': 'buildprops.json', 17:01:32 INFO - 'config_files': ('talos/mac_config.py',), 17:01:32 INFO - 'default_actions': ('clobber', 17:01:32 INFO - 'read-buildbot-config', 17:01:32 INFO - 'download-and-extract', 17:01:32 INFO - 'populate-webroot', 17:01:32 INFO - 'create-virtualenv', 17:01:32 INFO - 'install', 17:01:32 INFO - 'run-tests'), 17:01:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:01:32 INFO - 'download_minidump_stackwalk': True, 17:01:32 INFO - 'download_symbols': 'ondemand', 17:01:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:01:32 INFO - 'tooltool.py': '/tools/tooltool.py', 17:01:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:01:32 INFO - '/tools/misc-python/virtualenv.py')}, 17:01:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:01:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:01:32 INFO - 'installer_path': 'installer.exe', 17:01:32 INFO - 'log_level': 'info', 17:01:32 INFO - 'log_name': 'talos', 17:01:32 INFO - 'log_to_console': True, 17:01:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:01:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:01:32 INFO - 'opt_config_files': (), 17:01:32 INFO - 'pip_index': False, 17:01:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:01:32 INFO - 'cmd': ('bash', 17:01:32 INFO - '-c', 17:01:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:01:32 INFO - 'enabled': True, 17:01:32 INFO - 'halt_on_failure': False, 17:01:32 INFO - 'name': 'check_screen_resolution'},), 17:01:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:01:32 INFO - 'cmd': ('bash', 17:01:32 INFO - '-c', 17:01:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:01:32 INFO - 'enabled': True, 17:01:32 INFO - 'halt_on_failure': False, 17:01:32 INFO - 'name': 'check_screen_resolution'},), 17:01:32 INFO - 'run_cmd_checks_enabled': True, 17:01:32 INFO - 'sps_profile': False, 17:01:32 INFO - 'sps_profile_interval': 0, 17:01:32 INFO - 'suite': 'dromaeojs', 17:01:32 INFO - 'system_bits': '32', 17:01:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:01:32 INFO - 'title': 't-yosemite-r7-0344', 17:01:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:01:32 INFO - 'use_talos_json': True, 17:01:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:01:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:01:32 INFO - 'work_dir': 'build'} 17:01:32 INFO - [mozharness: 2016-08-23 00:01:32.287607Z] Running clobber step. 17:01:32 INFO - Running pre-action listener: _resource_record_pre_action 17:01:32 INFO - Running main action method: clobber 17:01:32 INFO - rmtree: /builds/slave/test/build 17:01:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:01:33 INFO - Running post-action listener: _resource_record_post_action 17:01:33 INFO - [mozharness: 2016-08-23 00:01:33.864540Z] Finished clobber step (success) 17:01:33 INFO - [mozharness: 2016-08-23 00:01:33.864706Z] Running read-buildbot-config step. 17:01:33 INFO - Running pre-action listener: _resource_record_pre_action 17:01:33 INFO - Running main action method: read_buildbot_config 17:01:33 INFO - Using buildbot properties: 17:01:33 INFO - { 17:01:33 INFO - "project": "", 17:01:33 INFO - "product": "firefox", 17:01:33 INFO - "script_repo_revision": "production", 17:01:33 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 17:01:33 INFO - "repository": "", 17:01:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 17:01:33 INFO - "buildid": "20160822145214", 17:01:33 INFO - "pgo_build": "False", 17:01:33 INFO - "basedir": "/builds/slave/test", 17:01:33 INFO - "buildnumber": 926, 17:01:33 INFO - "slavename": "t-yosemite-r7-0344", 17:01:33 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 17:01:33 INFO - "platform": "yosemite_r7", 17:01:33 INFO - "scheduler": "tests-fx-team-macosx64-talos", 17:01:33 INFO - "branch": "fx-team", 17:01:33 INFO - "repo_path": "integration/fx-team", 17:01:33 INFO - "revision": "3e537d8eb88c440fd0b7aa88deec56aa688715b0", 17:01:33 INFO - "stage_platform": "macosx64", 17:01:33 INFO - "builduid": "bfa0b3d7dfdb4689b676509060d68745", 17:01:33 INFO - "slavebuilddir": "test" 17:01:33 INFO - } 17:01:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 17:01:33 INFO - Running post-action listener: _resource_record_post_action 17:01:33 INFO - [mozharness: 2016-08-23 00:01:33.867959Z] Finished read-buildbot-config step (success) 17:01:33 INFO - [mozharness: 2016-08-23 00:01:33.868092Z] Running download-and-extract step. 17:01:33 INFO - Running pre-action listener: _resource_record_pre_action 17:01:33 INFO - Running main action method: download_and_extract 17:01:33 INFO - mkdir: /builds/slave/test/build/tests 17:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:01:33 INFO - trying https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 17:01:33 INFO - Downloading https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/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 17:01:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/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 17:01:35 INFO - Downloaded 1199 bytes. 17:01:35 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 17:01:35 INFO - Using the following test package requirements: 17:01:35 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 17:01:35 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:35 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 17:01:35 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:35 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 17:01:35 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:35 INFO - u'jsshell-mac.zip'], 17:01:35 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:35 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 17:01:35 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 17:01:35 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:35 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 17:01:35 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:35 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 17:01:35 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:35 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 17:01:35 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:01:35 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 17:01:35 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:01:35 INFO - trying https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:01:35 INFO - Downloading https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/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 17:01:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/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 17:01:36 INFO - Downloaded 16352497 bytes. 17:01:36 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 17:01:42 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 17:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:01:42 INFO - trying https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:01:42 INFO - Downloading https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/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 17:01:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/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 17:01:43 INFO - Downloaded 16352497 bytes. 17:01:43 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 17:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:01:50 INFO - trying https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 17:01:50 INFO - Downloading https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/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 17:01:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/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 17:01:51 INFO - Downloaded 14413981 bytes. 17:01:51 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 17:01:51 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')]} 17:01:51 INFO - trying https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:01:51 INFO - Downloading https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 17:01:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/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 17:01:54 INFO - Downloaded 111477696 bytes. 17:01:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:01:54 INFO - mkdir: /builds/slave/test/properties 17:01:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:01:54 INFO - Writing to file /builds/slave/test/properties/build_url 17:01:54 INFO - Contents: 17:01:54 INFO - build_url:https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:01:55 INFO - Running post-action listener: _resource_record_post_action 17:01:55 INFO - Running post-action listener: set_extra_try_arguments 17:01:55 INFO - [mozharness: 2016-08-23 00:01:55.185463Z] Finished download-and-extract step (success) 17:01:55 INFO - [mozharness: 2016-08-23 00:01:55.185608Z] Running populate-webroot step. 17:01:55 INFO - Running pre-action listener: _resource_record_pre_action 17:01:55 INFO - Running main action method: populate_webroot 17:01:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:01:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:01:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:01:55 INFO - '--tppagecycles', 17:01:55 INFO - '7'], 17:01:55 INFO - 'tests': ['tsvgm']}}, 17:01:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:01:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:01:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:01:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:01:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:01:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:01:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:01:55 INFO - 'talos_options': ['--disable-e10s'], 17:01:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:01:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:01:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:01:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:01:55 INFO - 'talos_options': ['--disable-e10s'], 17:01:55 INFO - 'tests': ['damp', 'tps']}, 17:01:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:01:55 INFO - 'tests': ['damp', 'tps']}, 17:01:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:01:55 INFO - 'tests': ['dromaeo_dom']}, 17:01:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:01:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:01:55 INFO - 'tests': ['basic_compositor_video']}, 17:01:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:01:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:01:55 INFO - 'tests': ['a11yr', 17:01:55 INFO - 'ts_paint', 17:01:55 INFO - 'tpaint', 17:01:55 INFO - 'sessionrestore', 17:01:55 INFO - 'sessionrestore_no_auto_restore', 17:01:55 INFO - 'tabpaint']}, 17:01:55 INFO - 'other-e10s': {'tests': ['a11yr', 17:01:55 INFO - 'ts_paint', 17:01:55 INFO - 'tpaint', 17:01:55 INFO - 'sessionrestore', 17:01:55 INFO - 'sessionrestore_no_auto_restore', 17:01:55 INFO - 'tabpaint']}, 17:01:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:01:55 INFO - 'ts_paint', 17:01:55 INFO - 'tpaint', 17:01:55 INFO - 'sessionrestore', 17:01:55 INFO - 'sessionrestore_no_auto_restore', 17:01:55 INFO - 'tabpaint']}, 17:01:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:01:55 INFO - 'ts_paint', 17:01:55 INFO - 'tpaint', 17:01:55 INFO - 'sessionrestore', 17:01:55 INFO - 'sessionrestore_no_auto_restore', 17:01:55 INFO - 'tabpaint']}, 17:01:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:01:55 INFO - 'tests': ['a11yr', 17:01:55 INFO - 'ts_paint', 17:01:55 INFO - 'tpaint', 17:01:55 INFO - 'sessionrestore', 17:01:55 INFO - 'sessionrestore_no_auto_restore', 17:01:55 INFO - 'tabpaint']}, 17:01:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:01:55 INFO - 'tests': ['a11yr', 17:01:55 INFO - 'ts_paint', 17:01:55 INFO - 'tpaint', 17:01:55 INFO - 'sessionrestore', 17:01:55 INFO - 'sessionrestore_no_auto_restore', 17:01:55 INFO - 'tabpaint']}, 17:01:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:01:55 INFO - 'tests': ['tsvgx', 17:01:55 INFO - 'tsvgr_opacity', 17:01:55 INFO - 'tart', 17:01:55 INFO - 'tscrollx', 17:01:55 INFO - 'cart']}, 17:01:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:01:55 INFO - 'tsvgr_opacity', 17:01:55 INFO - 'tart', 17:01:55 INFO - 'tscrollx', 17:01:55 INFO - 'cart']}, 17:01:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:01:55 INFO - 'talos_options': ['--disable-e10s'], 17:01:55 INFO - 'tests': ['tp5o']}, 17:01:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:01:55 INFO - 'tests': ['tp5o']}, 17:01:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:01:55 INFO - 'talos_options': ['--disable-e10s', 17:01:55 INFO - '--xperf_path', 17:01:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:01:55 INFO - 'tests': ['tp5n']}, 17:01:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:01:55 INFO - 'talos_options': ['--xperf_path', 17:01:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:01:55 INFO - 'tests': ['tp5n']}}, 17:01:55 INFO - 'talos.zip': {'path': '', 17:01:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:01:55 INFO - Running post-action listener: _resource_record_post_action 17:01:55 INFO - [mozharness: 2016-08-23 00:01:55.204528Z] Finished populate-webroot step (success) 17:01:55 INFO - [mozharness: 2016-08-23 00:01:55.204663Z] Running create-virtualenv step. 17:01:55 INFO - Running pre-action listener: _resource_record_pre_action 17:01:55 INFO - Running main action method: create_virtualenv 17:01:55 INFO - Creating virtualenv /builds/slave/test/build/venv 17:01:55 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 17:01:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:01:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:01:55 INFO - Using real prefix '/tools/python27' 17:01:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:01:56 INFO - Installing distribute.............................................................................................................................................................................................done. 17:01:59 INFO - Installing pip.................done. 17:01:59 INFO - Return code: 0 17:01:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:59 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 0x10c09ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30bdae6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c002d30>, '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 0x10bd67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bffab20>, '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.euDadNXgzw/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.I5auhp3cFA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 17:01:59 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 17:01:59 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 17:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.euDadNXgzw/Render', 17:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:01:59 INFO - 'HOME': '/Users/cltbld', 17:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:01:59 INFO - 'LOGNAME': 'cltbld', 17:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:01:59 INFO - 'MOZ_NO_REMOTE': '1', 17:01:59 INFO - 'NO_EM_RESTART': '1', 17:01:59 INFO - 'PAGER': '/bin/cat', 17:01:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:01:59 INFO - 'PWD': '/builds/slave/test', 17:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:01:59 INFO - 'SHELL': '/bin/bash', 17:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I5auhp3cFA/Listeners', 17:01:59 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 17:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:01:59 INFO - 'USER': 'cltbld', 17:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:01:59 INFO - 'XPC_FLAGS': '0x0', 17:01:59 INFO - 'XPC_SERVICE_NAME': '0', 17:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:01:59 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 17:01:59 INFO - Cleaning up... 17:01:59 INFO - Return code: 0 17:01:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:59 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 0x10c09ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30bdae6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c002d30>, '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 0x10bd67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bffab20>, '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.euDadNXgzw/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.I5auhp3cFA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 17:01:59 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 17:01:59 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 17:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.euDadNXgzw/Render', 17:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:01:59 INFO - 'HOME': '/Users/cltbld', 17:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:01:59 INFO - 'LOGNAME': 'cltbld', 17:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:01:59 INFO - 'MOZ_NO_REMOTE': '1', 17:01:59 INFO - 'NO_EM_RESTART': '1', 17:01:59 INFO - 'PAGER': '/bin/cat', 17:01:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:01:59 INFO - 'PWD': '/builds/slave/test', 17:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:01:59 INFO - 'SHELL': '/bin/bash', 17:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I5auhp3cFA/Listeners', 17:01:59 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 17:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:01:59 INFO - 'USER': 'cltbld', 17:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:01:59 INFO - 'XPC_FLAGS': '0x0', 17:01:59 INFO - 'XPC_SERVICE_NAME': '0', 17:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:01:59 INFO - Downloading/unpacking psutil>=3.1.1 17:01:59 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 17:01:59 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 17:01:59 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 17:01:59 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 17:01:59 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 17:01:59 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 17:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:02:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:02:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:02:02 INFO - Installing collected packages: psutil 17:02:02 INFO - Running setup.py install for psutil 17:02:02 INFO - building 'psutil._psutil_osx' extension 17:02:02 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 17:02:03 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 17:02:03 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 17:02:03 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 17:02:03 INFO - building 'psutil._psutil_posix' extension 17:02:03 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 17:02:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:02:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:02:03 INFO - ^ 17:02:03 INFO - 1 warning generated. 17:02:03 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 17:02:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:02:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:02:03 INFO - Successfully installed psutil 17:02:03 INFO - Cleaning up... 17:02:03 INFO - Return code: 0 17:02:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:02: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')]} 17:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02: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')]} 17:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c09ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30bdae6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c002d30>, '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 0x10bd67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bffab20>, '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.euDadNXgzw/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.I5auhp3cFA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 17:02: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:02: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 mozsystemmonitor==0.3 17:02:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.euDadNXgzw/Render', 17:02:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:03 INFO - 'HOME': '/Users/cltbld', 17:02:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:03 INFO - 'LOGNAME': 'cltbld', 17:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:03 INFO - 'MOZ_NO_REMOTE': '1', 17:02:03 INFO - 'NO_EM_RESTART': '1', 17:02:03 INFO - 'PAGER': '/bin/cat', 17:02:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:03 INFO - 'PWD': '/builds/slave/test', 17:02:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:03 INFO - 'SHELL': '/bin/bash', 17:02:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I5auhp3cFA/Listeners', 17:02:03 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 17:02:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:03 INFO - 'USER': 'cltbld', 17:02:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:03 INFO - 'XPC_FLAGS': '0x0', 17:02:03 INFO - 'XPC_SERVICE_NAME': '0', 17:02:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:02: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 17:02: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 17:02: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 17:02: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 17:02: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 17:02: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 17:02:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:02:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:02:06 INFO - Installing collected packages: mozsystemmonitor 17:02:06 INFO - Running setup.py install for mozsystemmonitor 17:02:06 INFO - Successfully installed mozsystemmonitor 17:02:06 INFO - Cleaning up... 17:02:06 INFO - Return code: 0 17:02:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:02:06 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')]} 17:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:06 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')]} 17:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:06 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 0x10c09ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30bdae6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c002d30>, '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 0x10bd67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bffab20>, '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.euDadNXgzw/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.I5auhp3cFA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 17:02:06 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 17:02:06 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 17:02:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.euDadNXgzw/Render', 17:02:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:06 INFO - 'HOME': '/Users/cltbld', 17:02:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:06 INFO - 'LOGNAME': 'cltbld', 17:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:06 INFO - 'MOZ_NO_REMOTE': '1', 17:02:06 INFO - 'NO_EM_RESTART': '1', 17:02:06 INFO - 'PAGER': '/bin/cat', 17:02:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:06 INFO - 'PWD': '/builds/slave/test', 17:02:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:06 INFO - 'SHELL': '/bin/bash', 17:02:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I5auhp3cFA/Listeners', 17:02:06 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 17:02:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:06 INFO - 'USER': 'cltbld', 17:02:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:06 INFO - 'XPC_FLAGS': '0x0', 17:02:06 INFO - 'XPC_SERVICE_NAME': '0', 17:02:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:07 INFO - Downloading/unpacking jsonschema==2.5.1 17:02: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 17:02: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 17:02: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 17:02: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 17:02: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 17:02: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 17:02:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:02:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:02:09 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 17:02:09 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 17:02:09 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 17:02:09 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 17:02:09 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 17:02:09 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 17:02:09 INFO - Downloading functools32-3.2.3-2.tar.gz 17:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:02:09 INFO - warning: no files found matching '*.txt' 17:02:09 INFO - no previously-included directories found matching 'build' 17:02:09 INFO - no previously-included directories found matching 'dist' 17:02:09 INFO - no previously-included directories found matching '.git*' 17:02:09 INFO - Installing collected packages: jsonschema, functools32 17:02:10 INFO - Running setup.py install for functools32 17:02:10 INFO - warning: no files found matching '*.txt' 17:02:10 INFO - no previously-included directories found matching 'build' 17:02:10 INFO - no previously-included directories found matching 'dist' 17:02:10 INFO - no previously-included directories found matching '.git*' 17:02:10 INFO - Successfully installed jsonschema functools32 17:02:10 INFO - Cleaning up... 17:02:10 INFO - Return code: 0 17:02:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:02:10 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')]} 17:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:10 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')]} 17:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:10 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 0x10c09ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30bdae6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c002d30>, '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 0x10bd67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bffab20>, '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.euDadNXgzw/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.I5auhp3cFA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 17:02:10 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 17:02:10 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 17:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.euDadNXgzw/Render', 17:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:10 INFO - 'HOME': '/Users/cltbld', 17:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:10 INFO - 'LOGNAME': 'cltbld', 17:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:10 INFO - 'MOZ_NO_REMOTE': '1', 17:02:10 INFO - 'NO_EM_RESTART': '1', 17:02:10 INFO - 'PAGER': '/bin/cat', 17:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:10 INFO - 'PWD': '/builds/slave/test', 17:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:10 INFO - 'SHELL': '/bin/bash', 17:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I5auhp3cFA/Listeners', 17:02:10 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 17:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:10 INFO - 'USER': 'cltbld', 17:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:10 INFO - 'XPC_FLAGS': '0x0', 17:02:10 INFO - 'XPC_SERVICE_NAME': '0', 17:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:02:10 INFO - Cleaning up... 17:02:10 INFO - Return code: 0 17:02:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:02:10 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')]} 17:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:10 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')]} 17:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:10 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 0x10c09ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30bdae6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c002d30>, '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 0x10bd67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bffab20>, '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.euDadNXgzw/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.I5auhp3cFA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 17:02:10 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 17:02:10 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 17:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.euDadNXgzw/Render', 17:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:10 INFO - 'HOME': '/Users/cltbld', 17:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:10 INFO - 'LOGNAME': 'cltbld', 17:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:10 INFO - 'MOZ_NO_REMOTE': '1', 17:02:10 INFO - 'NO_EM_RESTART': '1', 17:02:10 INFO - 'PAGER': '/bin/cat', 17:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:10 INFO - 'PWD': '/builds/slave/test', 17:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:10 INFO - 'SHELL': '/bin/bash', 17:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I5auhp3cFA/Listeners', 17:02:10 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 17:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:10 INFO - 'USER': 'cltbld', 17:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:10 INFO - 'XPC_FLAGS': '0x0', 17:02:10 INFO - 'XPC_SERVICE_NAME': '0', 17:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:10 INFO - Downloading/unpacking blobuploader==1.2.4 17:02: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 17:02: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 17:02: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 17:02: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 17:02: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 17:02: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 17:02:13 INFO - Downloading blobuploader-1.2.4.tar.gz 17:02:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:02:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:02:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:14 INFO - Downloading docopt-0.6.1.tar.gz 17:02:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:02:14 INFO - Installing collected packages: blobuploader, requests, docopt 17:02:14 INFO - Running setup.py install for blobuploader 17:02:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:02:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:02:14 INFO - Running setup.py install for requests 17:02:15 INFO - Running setup.py install for docopt 17:02:15 INFO - Successfully installed blobuploader requests docopt 17:02:15 INFO - Cleaning up... 17:02:15 INFO - Return code: 0 17:02:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:15 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 0x10c09ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30bdae6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c002d30>, '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 0x10bd67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bffab20>, '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.euDadNXgzw/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.I5auhp3cFA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 17:02:15 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 17:02:15 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 17:02:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.euDadNXgzw/Render', 17:02:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:15 INFO - 'HOME': '/Users/cltbld', 17:02:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:15 INFO - 'LOGNAME': 'cltbld', 17:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:15 INFO - 'MOZ_NO_REMOTE': '1', 17:02:15 INFO - 'NO_EM_RESTART': '1', 17:02:15 INFO - 'PAGER': '/bin/cat', 17:02:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:15 INFO - 'PWD': '/builds/slave/test', 17:02:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:15 INFO - 'SHELL': '/bin/bash', 17:02:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I5auhp3cFA/Listeners', 17:02:15 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 17:02:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:15 INFO - 'USER': 'cltbld', 17:02:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:15 INFO - 'XPC_FLAGS': '0x0', 17:02:15 INFO - 'XPC_SERVICE_NAME': '0', 17:02:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:02:15 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-deY_uE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:02:15 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-74xBE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:02:15 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-9r7t8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:02:15 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-BRQJE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:02:16 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-kUbbKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:02:16 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-XxSC8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:02:16 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-miFf0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:02:16 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-rBaswj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:02:16 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-YN6OAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:02:16 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-_9Rh6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:02:16 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-jeQPkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:02:16 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-pVId0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:02:16 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-fla3Mw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:02:16 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-__gryH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:17 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-ny8FNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:02:17 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-cKc6RH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:02:17 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-S9N3u2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:02:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:02:17 INFO - Running setup.py install for manifestparser 17:02:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:02:17 INFO - Running setup.py install for mozcrash 17:02:17 INFO - Running setup.py install for mozdebug 17:02:17 INFO - Running setup.py install for mozdevice 17:02:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:02:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:02:18 INFO - Running setup.py install for mozfile 17:02:18 INFO - Running setup.py install for mozhttpd 17:02:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:02:18 INFO - Running setup.py install for mozinfo 17:02:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:02:18 INFO - Running setup.py install for mozInstall 17:02:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:02:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:02:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:02:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:02:18 INFO - Running setup.py install for mozleak 17:02:18 INFO - Running setup.py install for mozlog 17:02:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:02:18 INFO - Running setup.py install for moznetwork 17:02:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:02:19 INFO - Running setup.py install for mozprocess 17:02:19 INFO - Running setup.py install for mozprofile 17:02:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:02:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:02:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:02:19 INFO - Running setup.py install for mozrunner 17:02:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:02:19 INFO - Running setup.py install for mozscreenshot 17:02:19 INFO - Running setup.py install for moztest 17:02:19 INFO - Running setup.py install for mozversion 17:02:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:02:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:02:19 INFO - Cleaning up... 17:02:19 INFO - Return code: 0 17:02:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:20 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')]} 17:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:20 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')]} 17:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:20 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 0x10c09ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30bdae6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c002d30>, '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 0x10bd67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bffab20>, '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.euDadNXgzw/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.I5auhp3cFA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 17:02:20 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 17:02:20 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 17:02:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.euDadNXgzw/Render', 17:02:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:20 INFO - 'HOME': '/Users/cltbld', 17:02:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:20 INFO - 'LOGNAME': 'cltbld', 17:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:20 INFO - 'MOZ_NO_REMOTE': '1', 17:02:20 INFO - 'NO_EM_RESTART': '1', 17:02:20 INFO - 'PAGER': '/bin/cat', 17:02:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:20 INFO - 'PWD': '/builds/slave/test', 17:02:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:20 INFO - 'SHELL': '/bin/bash', 17:02:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I5auhp3cFA/Listeners', 17:02:20 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 17:02:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:20 INFO - 'USER': 'cltbld', 17:02:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:20 INFO - 'XPC_FLAGS': '0x0', 17:02:20 INFO - 'XPC_SERVICE_NAME': '0', 17:02:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:02:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-cMdnel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:02:20 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)) 17:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:02:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-JoZqKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:02:20 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)) 17:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:02:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-rLzFHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:02:20 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)) 17:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:02:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-eATk9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:02:20 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)) 17:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:02:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-fYQR8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:02:20 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)) 17:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:02:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-oBMk3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:02:20 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)) 17:02:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:02:20 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-B27lIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:02:21 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)) 17:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:02:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-tDRusn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:02:21 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)) 17:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:02:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-0obxTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:02:21 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)) 17:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:02:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-6xE05L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:02:21 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)) 17:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:02:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-hd4zQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:02:21 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)) 17:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:02:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-zeBTl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:02:21 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)) 17:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:02:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-CgXbrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:02:21 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)) 17:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:02:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-NNf2Pa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:02:21 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)) 17:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-klxLha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:21 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)) 17:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:02:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-iqa1Tm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:02:21 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)) 17:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:02:21 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-0_GHvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:02:22 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)) 17:02:22 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)) 17:02:22 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)) 17:02:22 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)) 17:02:22 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)) 17:02:22 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)) 17:02:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:24 INFO - Downloading blessings-1.6.tar.gz 17:02:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:02:24 INFO - Installing collected packages: blessings 17:02:24 INFO - Running setup.py install for blessings 17:02:24 INFO - Successfully installed blessings 17:02:24 INFO - Cleaning up... 17:02:24 INFO - Return code: 0 17:02:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:02:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:02:25 INFO - Reading from file tmpfile_stdout 17:02:25 INFO - Current package versions: 17:02:25 INFO - blessings == 1.6 17:02:25 INFO - blobuploader == 1.2.4 17:02:25 INFO - docopt == 0.6.1 17:02:25 INFO - functools32 == 3.2.3-2 17:02:25 INFO - jsonschema == 2.5.1 17:02:25 INFO - manifestparser == 1.1 17:02:25 INFO - mozInstall == 1.12 17:02:25 INFO - mozcrash == 0.17 17:02:25 INFO - mozdebug == 0.1 17:02:25 INFO - mozdevice == 0.48 17:02:25 INFO - mozfile == 1.2 17:02:25 INFO - mozhttpd == 0.7 17:02:25 INFO - mozinfo == 0.9 17:02:25 INFO - mozleak == 0.1 17:02:25 INFO - mozlog == 3.3 17:02:25 INFO - moznetwork == 0.27 17:02:25 INFO - mozprocess == 0.23 17:02:25 INFO - mozprofile == 0.28 17:02:25 INFO - mozrunner == 6.12 17:02:25 INFO - mozscreenshot == 0.1 17:02:25 INFO - mozsystemmonitor == 0.3 17:02:25 INFO - moztest == 0.7 17:02:25 INFO - mozversion == 1.4 17:02:25 INFO - psutil == 3.1.1 17:02:25 INFO - requests == 1.2.3 17:02:25 INFO - wsgiref == 0.1.2 17:02:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:25 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')]} 17:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:25 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')]} 17:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:25 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 0x10c09ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30bdae6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c002d30>, '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 0x10bd67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bffab20>, '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.euDadNXgzw/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.I5auhp3cFA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 17:02:25 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 17:02:25 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 17:02:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.euDadNXgzw/Render', 17:02:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:25 INFO - 'HOME': '/Users/cltbld', 17:02:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:25 INFO - 'LOGNAME': 'cltbld', 17:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:25 INFO - 'MOZ_NO_REMOTE': '1', 17:02:25 INFO - 'NO_EM_RESTART': '1', 17:02:25 INFO - 'PAGER': '/bin/cat', 17:02:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:25 INFO - 'PWD': '/builds/slave/test', 17:02:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:25 INFO - 'SHELL': '/bin/bash', 17:02:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I5auhp3cFA/Listeners', 17:02:25 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 17:02:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:25 INFO - 'USER': 'cltbld', 17:02:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:25 INFO - 'XPC_FLAGS': '0x0', 17:02:25 INFO - 'XPC_SERVICE_NAME': '0', 17:02:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:25 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)) 17:02:25 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)) 17:02:25 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)) 17:02:25 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)) 17:02:25 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)) 17:02:25 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)) 17:02:25 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)) 17:02:25 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)) 17:02:25 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)) 17:02:25 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)) 17:02:25 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)) 17:02:25 INFO - Cleaning up... 17:02:25 INFO - Return code: 0 17:02:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:02:25 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')]} 17:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:25 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')]} 17:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:25 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 0x10c09ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c133618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe30bdae6f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c002d30>, '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 0x10bd67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bffab20>, '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.euDadNXgzw/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.I5auhp3cFA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 17:02:25 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 17:02:25 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 17:02:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.euDadNXgzw/Render', 17:02:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:25 INFO - 'HOME': '/Users/cltbld', 17:02:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:25 INFO - 'LOGNAME': 'cltbld', 17:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:25 INFO - 'MOZ_NO_REMOTE': '1', 17:02:25 INFO - 'NO_EM_RESTART': '1', 17:02:25 INFO - 'PAGER': '/bin/cat', 17:02:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:25 INFO - 'PWD': '/builds/slave/test', 17:02:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:25 INFO - 'SHELL': '/bin/bash', 17:02:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I5auhp3cFA/Listeners', 17:02:25 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 17:02:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:25 INFO - 'USER': 'cltbld', 17:02:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:25 INFO - 'XPC_FLAGS': '0x0', 17:02:25 INFO - 'XPC_SERVICE_NAME': '0', 17:02:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:02:25 INFO - Cleaning up... 17:02:25 INFO - Return code: 0 17:02:25 INFO - Running post-action listener: _resource_record_post_action 17:02:25 INFO - Running post-action listener: _start_resource_monitoring 17:02:25 INFO - Starting resource monitoring. 17:02:25 INFO - [mozharness: 2016-08-23 00:02:25.703960Z] Finished create-virtualenv step (success) 17:02:25 INFO - [mozharness: 2016-08-23 00:02:25.704963Z] Running install step. 17:02:25 INFO - Running pre-action listener: _resource_record_pre_action 17:02:25 INFO - Running main action method: install 17:02:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:02:25 INFO - Reading from file tmpfile_stdout 17:02:25 INFO - Detecting whether we're running mozinstall >=1.0... 17:02:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:02:26 INFO - Reading from file tmpfile_stdout 17:02:26 INFO - Output received: 17:02:26 INFO - Usage: mozinstall [options] installer 17:02:26 INFO - Options: 17:02:26 INFO - -h, --help show this help message and exit 17:02:26 INFO - -d DEST, --destination=DEST 17:02:26 INFO - Directory to install application into. [default: 17:02:26 INFO - "/builds/slave/test"] 17:02:26 INFO - --app=APP Application being installed. [default: firefox] 17:02:26 INFO - mkdir: /builds/slave/test/build/application 17:02:26 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'] 17:02:26 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 17:03:00 INFO - Reading from file tmpfile_stdout 17:03:00 INFO - Output received: 17:03:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:03:00 INFO - Running post-action listener: _resource_record_post_action 17:03:00 INFO - [mozharness: 2016-08-23 00:03:00.176971Z] Finished install step (success) 17:03:00 INFO - [mozharness: 2016-08-23 00:03:00.177130Z] Running run-tests step. 17:03:00 INFO - Running pre-action listener: _resource_record_pre_action 17:03:00 INFO - Running main action method: run_tests 17:03:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:03:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:03:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:03:00 INFO - 2016-08-22 17:03:00.191 screenresolution[2262:288523] starting screenresolution argv=screenresolution get 17:03:00 INFO - 2016-08-22 17:03:00.194 screenresolution[2262:288523] Display 0: 1600x1200x32@60 17:03:00 INFO - 2016-08-22 17:03:00.200 screenresolution[2263:288525] starting screenresolution argv=screenresolution list 17:03:00 INFO - Available Modes on Display 0 17:03:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:03:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:03:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:03:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:03:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:03:00 INFO - Intel Iris: 17:03:00 INFO - Chipset Model: Intel Iris 17:03:00 INFO - Type: GPU 17:03:00 INFO - Bus: Built-In 17:03:00 INFO - VRAM (Dynamic, Max): 1536 MB 17:03:00 INFO - Vendor: Intel (0x8086) 17:03:00 INFO - Device ID: 0x0a2e 17:03:00 INFO - Revision ID: 0x0009 17:03:00 INFO - Displays: 17:03:00 INFO - Display: 17:03:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:03:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:03:00 INFO - Display Serial Number: 1600x1200 60 17:03:00 INFO - Main Display: Yes 17:03:00 INFO - Mirror: Off 17:03:00 INFO - Online: Yes 17:03:00 INFO - Rotation: Supported 17:03:00 INFO - Return code: 0 17:03:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:03:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:03:00 INFO - Python 2.7.3 17:03:00 INFO - Return code: 0 17:03:00 INFO - grabbing minidump binary from tooltool 17:03: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')]} 17:03:00 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 0x10c002d30>, '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 0x10bd67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bffab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:03:00 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 17:03:00 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 17:03:00 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 17:03:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:03:00 INFO - Return code: 0 17:03:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:03:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:03:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:03:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:03:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:03:00 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/KDiGew3LSvGHvOYUE5acVA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0344', '--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 17:03:00 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/KDiGew3LSvGHvOYUE5acVA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0344 --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 17:03:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.euDadNXgzw/Render', 17:03:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:00 INFO - 'HOME': '/Users/cltbld', 17:03:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:00 INFO - 'LOGNAME': 'cltbld', 17:03:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:03:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:00 INFO - 'MOZ_NO_REMOTE': '1', 17:03:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:03:00 INFO - 'NO_EM_RESTART': '1', 17:03:00 INFO - 'PAGER': '/bin/cat', 17:03:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:00 INFO - 'PWD': '/builds/slave/test', 17:03:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:03:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:00 INFO - 'SHELL': '/bin/bash', 17:03:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I5auhp3cFA/Listeners', 17:03:00 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 17:03:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:00 INFO - 'USER': 'cltbld', 17:03:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:00 INFO - 'XPC_FLAGS': '0x0', 17:03:00 INFO - 'XPC_SERVICE_NAME': '0', 17:03:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:00 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/KDiGew3LSvGHvOYUE5acVA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0344', '--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 17:03:00 INFO - mozversion application_buildid: 20160822145214 17:03:00 INFO - mozversion application_changeset: 3e537d8eb88c440fd0b7aa88deec56aa688715b0 17:03:00 INFO - mozversion application_display_name: Nightly 17:03:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:03:00 INFO - mozversion application_name: Firefox 17:03:00 INFO - mozversion application_remotingname: firefox 17:03:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:03:00 INFO - mozversion application_vendor: Mozilla 17:03:00 INFO - mozversion application_version: 51.0a1 17:03:00 INFO - mozversion platform_buildid: 20160822145214 17:03:00 INFO - mozversion platform_changeset: 3e537d8eb88c440fd0b7aa88deec56aa688715b0 17:03:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:03:00 INFO - mozversion platform_version: 51.0a1 17:03:00 INFO - using testdate: 1471910580 17:03:00 INFO - actual date: 1471910580 17:03:00 INFO - starting webserver on 'localhost:53240' 17:03:00 INFO - SUITE-START | Running 2 tests 17:03:00 INFO - TEST-START | dromaeo_css 17:03:00 INFO - operating with platform_type : mac_ 17:03:00 INFO - Initialising browser for dromaeo_css test... 17:03:00 INFO - TEST-INFO | started process 2273 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpO4ASVN/profile http://localhost:53240/getInfo.html) 17:03:03 INFO - PROCESS | 2273 | __metrics Screen width/height:1600/1200 17:03:03 INFO - PROCESS | 2273 | colorDepth:24 17:03:03 INFO - PROCESS | 2273 | Browser inner width/height: 1024/689 17:03:03 INFO - PROCESS | 2273 | __metrics 17:03:03 INFO - PROCESS | 2273 | 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" 17:03:03 INFO - PROCESS | 2273 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:03:03 INFO - TEST-INFO | 2273: exit 0 17:03:03 INFO - Browser initialized. 17:03:03 INFO - Running cycle 1/1 for dromaeo_css test... 17:03:03 INFO - TEST-INFO | started process 2275 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpO4ASVN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:03:15 INFO - PROCESS | 2275 | RSS: Main: 228790272 17:03:15 INFO - PROCESS | 2275 | 17:04:06 INFO - PROCESS | 2275 | 1471910646908 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] 17:06:19 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53240/tests/dromaeo/cssquery-ext.html) 17:06:20 INFO - PROCESS | 2275 | RSS: Main: 370597888 17:06:20 INFO - PROCESS | 2275 | 17:09:23 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53240/tests/dromaeo/cssquery-ext.html (next: http://localhost:53240/tests/dromaeo/cssquery-jquery.html) 17:09:24 INFO - PROCESS | 2275 | RSS: Main: 372289536 17:09:24 INFO - PROCESS | 2275 | 17:12:26 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53240/tests/dromaeo/cssquery-mootools.html) 17:12:27 INFO - PROCESS | 2275 | RSS: Main: 343109632 17:12:27 INFO - PROCESS | 2275 | 17:15:29 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53240/tests/dromaeo/cssquery-prototype.html) 17:15:30 INFO - PROCESS | 2275 | RSS: Main: 351772672 17:15:30 INFO - PROCESS | 2275 | 17:18:33 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53240/tests/dromaeo/cssquery-yui.html) 17:18:34 INFO - PROCESS | 2275 | RSS: Main: 336293888 17:18:34 INFO - PROCESS | 2275 | 17:21:38 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:53240/tests/dromaeo/cssquery-yui.html (next: http://localhost:53240/tests/dromaeo/cssquery-dojo.html) 17:21:38 INFO - PROCESS | 2275 | RSS: Main: 396541952 17:21:38 INFO - PROCESS | 2275 | 17:21:38 INFO - PROCESS | 2275 | __start_tp_report 17:21:38 INFO - PROCESS | 2275 | _x_x_mozilla_page_load 17:21:38 INFO - PROCESS | 2275 | _x_x_mozilla_page_load_details 17:21:38 INFO - PROCESS | 2275 | |i|pagename|runs| 17:21:38 INFO - PROCESS | 2275 | |0;dojo.html;3146.606674165729;3315.5358249845012;3318.7510936679755;3325.3016866458033;3357.983210083951;5500.092484740013;5565.916511252324;5610.691411972345;5671.064274394725;5681.147827825828;5510.669359838403;5581.107022876341;5591.4688104629995;5599.300087489064;5624.91562626561;5061.721605311706;5078.289039534459;5167.560757335612;5176.948230517684;5181.3264275644115;5125.231215317714;5138.897222055557;5221.530062294392;5261.921071183935;5288.444713305105;5258.290130832327;5273.868153296179;5289.920651190216;5294.629375943686;5297.417284098746;5081.085404627165;5083.237514372837;5270.104082305978;5272;5288.629795914288;5042.949570504285;5094.184930411154;5099.59203263738;5144.794208231666;5163.509466600667;4644.698094623838;5582.944170558283;5589.329280486327;5668.57485688575;5673.319201695782;6079.787207447718;6241.500679945593;6246.812595622138;6256.937430625681;6262.499000079983;6159.692015399212;6170.197874276315;6243.62538247702;6254.718537665816;6278.780242691484;4831.951680483186;5821.039528477839;5827;5827.475527202572;5827.854303642443;6158.815235542851;6184.752609895554;6254.468370188585;6256.5307601930035;6261.499080073583;5070.847874563769;6086.847828804315;6164.4143806338325;6167.660778657217;6174.66039367839;5150.639455238097;6227.813165605039;6244.469220116341;6259.186305780219;6260.7495700171485;5962.463378295887;6072.51419886408;6119.96940015306;6169.475594574422;6171.290301615371;6119.4798442132915;6161.137440758297;6170.352113028157;6222;6223.875522489616;4925.458199598042;4969.006198760262;4971.825986090542;4975.104481193348;4990.50094990498;4434.290513517822;4441.311596702516;4465.732056076646;4472.239719247736;4473.776311184492;5541.196526503634;5599.496045355938;5612.242347283146;5652.236948012048;5694.316681998187;6257.843553911098;6273.309935907047;6280.560360774702;6283.7800676977;6321.273053598803;5684.8294551163535;5713.028785106542;5729.8567535811935;5736.569757268222;5739.770409183586;5277.8416647500635;5331.200319952039;5369.4630536946;5388.38033626138;5439.619226654097;6257.09272155544;6288;6290.339514351111;6291.150694656163;6329.810105696836;5179.482051794941;6266.279377871603;6267.529935254783;6288.245410550763;6305;6180.505559555315;6181.505479561534;6219.284782250099;6241.032639940817;6313.2424109107205;6163.414475624809;6186.1648677427975;6190.473809726223;6198.163247961467;6200.503959683305;6218.440340369389;6244.500439964882;6253.812385628438;6275.592086514362;6281.340459251713;5868.295804503487;5938.406159384027;5965.254343207099;6010.909836352545;6071.119687645179;5875.529957603296;6085.087236914498;6132;6135.53983451234;6155.35368786271;5891.23413956183;5912.054071348563;5915.763369465173;5924.022536281637;5953.613015154001;5823.18475413451;5920.79277225295;5956.136360227656;6001.459868611846;6093.116498107661;5932.93207222704;5942.435468630473;5950.702464876652;5954.136650185786;5958.285005799332 17:21:38 INFO - PROCESS | 2275 | |1;ext.html;428.89277680579835;488.63596620517717;493.38573476022356;513.43265691411;522.6393788286082;30068.398632027373;31822.045338639848;33322.83338583304;41240.99999999996;42238.57761422385;41598.58401415985;43931.34102988458;43954.34068488967;43959.56040439595;44089.7795511022;9814.411135331886;9981.251406144569;9982.450965196911;9990.800183996316;9994;8557.400981931267;9897.90102098977;10015.048570385801;10020.494876280929;10033.59865605375;9981.451020193887;10040.548175332091;10050.597976080984;10051.195904327673;10068.29521933465;9932.900670993306;10014.649487267983;10030.59877604895;10035.448050357227;10037.397756134655;9736.269779766546;9777.653363192854;9787.951060244653;9788.755281117958;9806.362586431858;40964.79517602393;41309.1738165238;41310.58689413127;41315.793421033006;41381.37927931084;3561.626029266916;3596.8381422836037;3607.4769158471877;3684.207895302496;4100.753954762724;23034.424139396648;32435.837820810983;32546.996311870596;32745.508817367758;33279;3181.8906106839736;3221.6617255188335;3314.486254630536;3322.750793690483;3375.561177046968;486003;491134;492756;493564;495678.9999999964;60610.39389606136;60958.78082438326;61056.69471652613;61085.38914610886;61343.6932815342;64197;64544.354556453836;64596.677016614616;64631.67684161549;64707.70584588282;16393.180340982904;16667.83332166663;16670.91664541694;16679.91660041692;16734.916325418297;11004.39475828821;12876.484940602271;12914.67713307156;12918.90189333917;12924.612261632166;17043;17739.911300443415;17793.7330940036;17799.288028478717;17841.553961151076;5662.801801936995;6646.966765166143;6650.534462587572;6679.096457509587;6761.695723692445;837.2966707965338;957.6025949230991;1006.33079002464;1013.2856336282903;1074.5433190893839;28437.289067773472;28667.996620118196;30581.541276880864;30797.384052318826;31145.844270778955;818.4679958027211;896.3098414221143;1010.6210171185805;1042.8592140060994;1142.737170450801;15239.847601524065;15755;16141.192940352936;16151.465610766958;16373.67252654964;678190;686469;687781;688033;689641;58350;58653.12020319699;58676.533086673175;58711.11933321004;58845.70577147087;1254.1973137192022;1367.6649220940933;1384.2179168769612;1425.3585886351184;1427.892908031881;25754.484910302064;29558;31183.84408077945;31199;32074;42384;51969.74015130052;52121.696957576365;52129.95740085253;52228.73885630548;549934;551167;551937;552510.2238753653;552750;26627.733722662524;27409.588856166774;27414.862925685244;28086.2978425541;28151.71848281491;48095.519044810506;48355.5164448351;48528.5147148524;49085.75457122691;49694.751526243585;33275.16812079717;39477.802610987914;39692.603073968894;40313.59686403099;40997.79501102595;38167.61832381641;39623.60376396315;39679.40480892731;40345;40773.592264078165;36079;37615.62384376121;37710.62289377071;38217;39271.82184534468;30346.84826575853;31712.841435792674;32307;35214.82392588123;35472.645273546936;23743.406414839767;24224.273271801874;25163.370915726522;25743.84152713095;26444.603330950435 17:21:38 INFO - PROCESS | 2275 | |2;jquery.html;347.50133558343765;353.72586245659613;360.96943227086666;381.6260065136166;395.4325542846016;15674.921625391871;17133.22900469479;20568.382948511568;21345.466363340885;21390.57218855622;20120.39638810838;20810.751354918677;20886.37340879772;21460.8926955365;21622.459438514044;5251.369835619712;5378.0857254266775;5412.431694672062;5560.388357280696;5566.165075238727;3856.3636999895125;5392.865178370533;5481.369642491125;5483.8080667176655;5485.862853428656;5789.305283366002;6064.9090263646085;6109.083637454373;6126.509879209675;6139.570230083896;5827.155062515912;5964.731587078549;6066.6663333516635;6089.573729838913;6120.081987701858;5842.824715258506;5844.415558444121;5846.181534585162;5848.210491583667;5850.0932355484965;13539.39072741729;15716.607084822857;19898.403047908585;26068.86965565179;26497.205083847515;2186.693862859201;2195.7913998170307;2220.555888822242;2262.1064679451524;2515.3963048868322;9796.755081123029;9923.503824808802;10004.649837255662;10418.739531511697;10452.268341216119;1845.474039898636;1858.0895361273015;1883.896385698786;1902.5338791995912;1947.552063025512;118273;124309.37845310716;124690;127933;128945.35527322303;11019.779604407868;11417.885821141683;11482.138839587064;11539.249948753304;11683.474243659055;14573.198474084027;14576.854231457763;14621.488247911271;14623.415063397557;14623.415063397557;8341.624626891804;8611.311095112502;8628.180322869317;8765.035846056928;8787.736367908972;8604.48373097616;8656.523891186045;8665.696700615448;8827.779305517413;8850.822983540293;4452.285869940097;4525.864224073283;4552.135094332067;4598.655100867449;4633.837815676503;1845.972310415318;1877.6807942649782;1895.6398284325935;1898.9715154272697;1916.5975145219381;701.7087908517908;750.909890813095;898.9460632362079;899.3929097858919;974.9275796623799;20919.84940828269;21122.471938201666;21227.46931326698;21384.572308554052;21558;900.8243392538392;902.8194361127801;924.2144177449114;963.9325247232626;1003.6286573967533;10625.25623206368;11046.33721976684;11086.33481991067;11110.833337499944;11176.05003574689;536329;552260;559240;560553;566778;131583.68416315713;133411;134494.99999999805;138755.67465952077;141235.52586844823;931.5621657820814;932.0958670090001;949.3591825517748;951.167728237792;953.6280850468323;18032.729509057113;18879.716804247673;18911.716324255423;18948.336808211647;18967.241310347432;55050;55613.44386556245;55699;55969.72015140061;56157;79615;79896.60051699704;80384;80790;80792;5018.971110922325;5133.998870220271;6866.34769696878;7437.995870557405;8114.756557303291;9216.631334746804;11906;12398;12706.618801435972;12824.871751282368;9931.900680993387;11020.008199262105;11124.499397527292;11176.832347514957;11206.495707693337;10397.84403233937;10424.635137770141;10706.732331691459;10726.785464290515;10757.623483178051;10616.097631701083;10864.402457864893;10876.945615271872;10886.945565272123;11053.392063436588;7724.961375193088;7872.763817085496;7899.802504937423;7948.16544262844;7965.8406831864195;9005.504697241715;10475.166845801801;10683.94658026705;10858.891411085788;10881.292715973437 17:21:38 INFO - PROCESS | 2275 | |3;mootools.html;1521.6043828604559;1541.4296710217218;1709.4786090242478;1853.05494197959;1859.89770562619;8475.491470511774;8987.59555819988;8993.865092023625;8998.460092394462;9000.684976025841;7848.843023139548;8107.148749381318;8478.66085356585;8717.694880679177;8797.340199485032;2380.143148466551;2406.506666133447;2407.963880541793;2417.2869003643877;2417.697787776528;2373.727021392545;2393.970592645162;2422.188566830114;2429.2712186344024;2430.5017471418405;2402.3513651314133;2416.347586151747;2422.624493203556;2426.2721183644926;2428.0530593068715;2340.216027630746;2361.551305252005;2366.8343215974887;2374.78626923576;2374.8456350337347;2381.821363397735;2399.652050452692;2400.975990240093;2401.0275838285525;2401.5797235483897;8816.471011739317;8868.98006729228;8908.51003194824;8941.64233430662;9006.324525660602;5257.263983042377;5424.403315635277;5429.755660995264;5439.945600543983;5460.808871689512;3578.4632305154173;3667.064898450878;3747.2505498900055;3751.0622344413896;3763.34141525232;1092.8633920759905;1110.6779034080107;1111.3498603317069;1127.1827924754568;1134.3420815926825;9927.453990030546;11706.473208705629;11797.351145686984;11800.64598062051;11808.350540720172;4332.176886391576;4448.377227188196;4630.652701047367;4634.351190833286;4635.466921304093;9206.447613143098;9230.53847307632;9238.95380523093;9241.168294853362;9251.167394934488;5253.711045892513;5264;5269.71016594091;5275.366955965309;5278.5513231375;4002.399640054015;4423.203823311835;4601.44782626087;4869.9269510957365;4925.655204135676;3737.271232109767;3780.9432858507143;3844.519435070616;3883.3010058189234;3891.2412079644196;1012.4937531234383;1148.6152139033518;1158.8493495845487;1160.193665402554;1176.4294317256124;683.2552517755571;723.5658604837056;770.2567022823002;913.8126684029891;937.4361122330421;5798.507126894261;5913;5990.101484777231;6071.939280607226;6216.875662486725;436.23658597454454;455.4147919922883;456.40895040922084;459.87353477793874;461.04793601213385;3965.6827453803626;4032.858849940238;4107.732997355162;4111.403846442249;4140.23405669952;9902.30683852138;10834.620788272372;10964.342139471497;10965.013148816486;10973.725656858483;10668.83996740034;10757.193210509244;10922.781544369069;10934.507947142398;10985.450727463594;7651.311381975649;8346.791330216793;8349.415540912078;8365.665373384996;8370.958145209357;5880.206172166702;5954.910676339945;5969.791057312973;6033.336333003278;6058.7273572688455;9684.612615495302;10009.39943603357;10080.949595251977;10124.240681948737;10175.643852465128;10307.484625768531;10311.742206444898;10334.793304134026;10355.223358248311;10367.066963973279;6538.1827271591055;6734.057231987427;6746.392824645805;7071.540349877241;7159.785206443815;7876.488028278143;8182.4681395709085;8301.709440169565;8514.276286515715;8584.399092063495;7596.202398748052;7598.710437827236;7654.425918056279;7687.385009199363;7714.035745531809;7292.34368906801;7361.521501102411;7453.888191677021;7457.403407727477;7572.7728168155045;6994.510384273052;7151.034610327747;7152.105986751656;7158.06945097134;7158.928410715826;4795.490822027163;5065.468125846881;5068.619853511075;5098.617603679665;5117;5036.6726162799305;5058.671186372874;5217.869553261199;5233.215017747369;5336.653117547502 17:21:38 INFO - PROCESS | 2275 | |4;prototype.html;276.55612741549817;292.1119795820705;305.2384301168584;308.45711547676086;312.6014331727048;5246.790128394864;5272.156454967206;5273.709945952971;5288.920666190005;5300.814471493489;4970.950290497094;5036.496350364971;5142.2745944649305;5226.529612334889;5271.551918086967;3666.486691863128;3758.6617204451595;4012.7993600320015;4073.5926407359175;4091.2022155679656;4044.211378781139;4054.6350828425434;4077.062275676596;4093.631573158415;4099.7745124018165;3206.8396580171016;3260.396826587077;3957.4855268815013;4014.558398576154;4027.637512623863;3148.763842711806;3990.541087774914;4027.254957832781;4032.576579459144;4034.8615233926066;2832.7875409344174;3327.4361050306316;3423.828808559587;3434.643315890234;3450.6894379505966;4358.694891357606;5120.334356533627;5129.871753206163;5136.7671758778;5150.892558100027;3838.6353296436796;4184.958150418518;4575.382323386333;4616.861494155147;4644.7213167210075;4931.80272789088;5386.245925570423;5457;5724.9427505724825;5725.055365864628;3212.4538828398995;3388.9830550847337;3827.8086095695103;3856.614338566149;3858.8070596470343;5118.411382690963;5512.724363781795;5622.12073669167;5854.912176317359;5867.442592953663;3813.188735350742;3837.827297771607;3846.9230615388096;3855.1904100139286;3876.4379165020914;3908.6580973268015;3916.1789767758128;3916.5300163980505;3916.941245881314;3935.842566297316;4375.934360984587;4479.350494178326;4583.679142459996;4587.587117159472;4599.264117741145;4643.419572553431;5404.91892621611;5589.385167631557;5688.744006519716;5692.487676109087;1146.7214056327302;1298.9090763646454;1319.6700824793802;1328.2069206571246;1332.540273605624;621.7481919822479;877.9070057778107;884.6638277454655;894.5706061090583;899.8020435504178;4194.517630472474;5213.895722085537;5216.817411390583;5218.347706536683;5245.554127899095;5690.971545142331;5719.342275638355;5726.77092916287;5755.913661295084;5790.36306006339;3889.630485103911;4159.854405095807;5113.002964421977;5187.273781670569;5245.1607742761735;2828.892475106018;2979.2402937251295;2987.7460415864894;2989.7757668174972;3002.84390509651;5095.745212739348;5680.829575112753;5876.41235876409;5894.233749612523;5919.638483148814;5742.655440673573;5781.392953739881;5792.623479473905;5803.506701930299;5822.883542329129;5855.970720146372;5863.5309175265875;5870.911936320958;5878.882422351615;5925.703714814242;4639.652026097989;5322.308099946982;5664.603477756517;5668.574856885668;5688.260526131577;4406.691531592758;4894.437139728977;5102.4897510248675;5583.55331573465;5611.354694210218;5444.183372494158;5839;5841.649501029951;5873.471387575139;5896.410358964069;3722.4974628424816;3775.980485268907;3778.525066081721;3795.1650636859845;3795.5635101963626;4663;5245.557471695161;5546.112621980464;5570.777168913198;5671.602987790815;5031.547160755403;5059.772310246121;5377.521181675071;5485.725713714378;5496.80761173357;4573.8274319697875;4830.033993201304;4958.2810492477665;5355.465392840706;5400.945990540202;4459.598636122764;4867.7322747249245;5135.4336927237555;5349.9465005351;5385.246065550904;5126.53861152498;5438.619296649196;5460;5465.371482279589;5485;5455.608819750938;5458.808941687022;5489.176623506506;5509.5399719073675;5568.721563921868 17:21:38 INFO - PROCESS | 2275 | |5;yui.html;649.5128653509868;786.2962648429656;795.5584650518963;798.5568009754587;816.9264766171043;15113.471028514005;15770.132642704646;16381.09903955282;16386.42647507336;16712;14814.185219812933;15211.543653690407;15227.543173704778;15235.31441085151;15250.084994900313;687.6905392573337;833.1834801894132;851.7104184577242;855.119227195986;856.4946681457939;700.1458220970408;700.3906601256914;701.7438634898276;702.9789106326792;708.6527601475266;866.2247288676631;909.0500427053745;921.6037104045258;922.3635691372965;926.4487629860222;893.7765558610347;894.7852515396258;895.3285036222833;896.1665650944586;896.188949001155;535.1998761851019;652.5530011941787;666.7266420767511;669.4276393683427;669.9665016749143;15002.69994600102;15116.622084447978;15137.091774493565;15148.772768408482;15167.317470713733;13816.792748108786;14053.859461405358;14103.365348559339;14141.575752727435;14207.502737404142;11137.777244455065;12797.488100476063;13040.869591304061;13147.14543554666;13228.669283267898;3931.3513270310364;4481.775911204427;4571.674214477797;4593.8621841344475;4705.95294047062;120393.39803300927;121245;121253.39373303077;121293;121594.39202803929;11023.889761102286;11282.830757538644;11286.040686541735;11303.378314192634;11534.769304613861;21855.890720546613;21932.671009934864;21933.341999740034;21967.340979770634;21973.67039494409;7989.2809647132035;7991.006744941294;7998.160193179749;8023.4784738991775;8182.263596276363;4343.305071188594;4428.933565996513;4516.774161291923;4641.512641172628;4691.906161876744;2712.864356782153;3542.5217595624777;3544.149404142987;3548.1484443733834;3548.2548664780684;643.6170478565247;647.346180357842;654.5876098058193;673.050998092687;674.1640365946191;495.88594623236855;566.8754459684407;641.3874749614107;650;656.2715385921623;8752.51861147643;9002.05478424769;9016.63933442668;9139.923487086005;9150.81698366029;581.2937281203358;612.1521692455981;628.78330430616;640.0495264532166;641.1568787045114;4114.506259248909;4430.988176763828;4571.428571428572;4592.678512504092;5203;117555.8244417562;141565;141798.2910085443;144628;146848;64288.678556606916;82054.17945820466;112887.87112128938;120160;121580.3708462268;116387;120816;121025;121049;126484.99999999817;8748;9583.137517623447;10147.84778228342;10236.897631023594;10249.077583017564;36829;62292.68853655703;63823.36176638174;64690.67654661697;66853;100776.49611751894;104339;105068.47465762928;105603;107526.92473075169;13405.32973351309;13470.989675774552;13581.253031083384;13604.523841665494;13690.65773355674;15195.164265965477;15209.391624335345;15499.574078041056;15655;16008.199590020686;14224.359903804147;14540.345684444013;14548.563543093724;14975.475858344906;15018.174000430081;13961.790573141208;14490.203038832544;14688.41246350134;14925.701485970003;15008.47470338533;13862.4455021798;14416.351264192926;14436.278186090443;14737.11577305365;14765.557033289018;11216.383098929637;11457.369844658624;11546;11737.530498779954;11837.289762614271;11544;11613.187076904536;11735.119866010255;11890.569770968024;11992.580259690869 17:21:38 INFO - PROCESS | 2275 | __end_tp_report 17:21:38 INFO - PROCESS | 2275 | __start_cc_report 17:21:38 INFO - PROCESS | 2275 | _x_x_mozilla_cycle_collect,1531 17:21:38 INFO - PROCESS | 2275 | __end_cc_report 17:21:38 INFO - PROCESS | 2275 | __startTimestamp1471911698306__endTimestamp 17:21:38 INFO - PROCESS | 2275 | 17:21:38 INFO - PROCESS | 2275 | ------- Summary: start ------- 17:21:38 INFO - PROCESS | 2275 | Number of tests: 6 17:21:38 INFO - PROCESS | 2275 | 17:21:38 INFO - PROCESS | 2275 | [#0] dojo.html Cycles:165 Average:5687.19 Median:5912.05 stddev:648.66 (11.0%) stddev-sans-first:619.28 17:21:38 INFO - PROCESS | 2275 | Values: 3146.6 3315.5 3318.8 3325.3 3358.0 5500.1 5565.9 5610.7 5671.1 5681.1 5510.7 5581.1 5591.5 5599.3 5624.9 5061.7 5078.3 5167.6 5176.9 5181.3 5125.2 5138.9 5221.5 5261.9 5288.4 5258.3 5273.9 5289.9 5294.6 5297.4 5081.1 5083.2 5270.1 5272.0 5288.6 5042.9 5094.2 5099.6 5144.8 5163.5 4644.7 5582.9 5589.3 5668.6 5673.3 6079.8 6241.5 6246.8 6256.9 6262.5 6159.7 6170.2 6243.6 6254.7 6278.8 4832.0 5821.0 5827.0 5827.5 5827.9 6158.8 6184.8 6254.5 6256.5 6261.5 5070.8 6086.8 6164.4 6167.7 6174.7 5150.6 6227.8 6244.5 6259.2 6260.7 5962.5 6072.5 6120.0 6169.5 6171.3 6119.5 6161.1 6170.4 6222.0 6223.9 4925.5 4969.0 4971.8 4975.1 4990.5 4434.3 4441.3 4465.7 4472.2 4473.8 5541.2 5599.5 5612.2 5652.2 5694.3 6257.8 6273.3 6280.6 6283.8 6321.3 5684.8 5713.0 5729.9 5736.6 5739.8 5277.8 5331.2 5369.5 5388.4 5439.6 6257.1 6288.0 6290.3 6291.2 6329.8 5179.5 6266.3 6267.5 6288.2 6305.0 6180.5 6181.5 6219.3 6241.0 6313.2 6163.4 6186.2 6190.5 6198.2 6200.5 6218.4 6244.5 6253.8 6275.6 6281.3 5868.3 5938.4 5965.3 6010.9 6071.1 5875.5 6085.1 6132.0 6135.5 6155.4 5891.2 5912.1 5915.8 5924.0 5953.6 5823.2 5920.8 5956.1 6001.5 6093.1 5932.9 5942.4 5950.7 5954.1 5958.3 17:21:38 INFO - PROCESS | 2275 | 17:21:38 INFO - PROCESS | 2275 | [#1] ext.html Cycles:180 Average:70977.40 Median:39374.81 stddev:155708.22 (395.5%) stddev-sans-first:156054.93 17:21:38 INFO - PROCESS | 2275 | Values: 428.9 488.6 493.4 513.4 522.6 30068.4 31822.0 33322.8 41241.0 42238.6 41598.6 43931.3 43954.3 43959.6 44089.8 9814.4 9981.3 9982.5 9990.8 9994.0 8557.4 9897.9 10015.0 10020.5 10033.6 9981.5 10040.5 10050.6 10051.2 10068.3 9932.9 10014.6 10030.6 10035.4 10037.4 9736.3 9777.7 9788.0 9788.8 9806.4 40964.8 41309.2 41310.6 41315.8 41381.4 3561.6 3596.8 3607.5 3684.2 4100.8 23034.4 32435.8 32547.0 32745.5 33279.0 3181.9 3221.7 3314.5 3322.8 3375.6 486003.0 491134.0 492756.0 493564.0 495679.0 60610.4 60958.8 61056.7 61085.4 61343.7 64197.0 64544.4 64596.7 64631.7 64707.7 16393.2 16667.8 16670.9 16679.9 16734.9 11004.4 12876.5 12914.7 12918.9 12924.6 17043.0 17739.9 17793.7 17799.3 17841.6 5662.8 6647.0 6650.5 6679.1 6761.7 837.3 957.6 1006.3 1013.3 1074.5 28437.3 28668.0 30581.5 30797.4 31145.8 818.5 896.3 1010.6 1042.9 1142.7 15239.8 15755.0 16141.2 16151.5 16373.7 678190.0 686469.0 687781.0 688033.0 689641.0 58350.0 58653.1 58676.5 58711.1 58845.7 1254.2 1367.7 1384.2 1425.4 1427.9 25754.5 29558.0 31183.8 31199.0 32074.0 42384.0 51969.7 52121.7 52130.0 52228.7 549934.0 551167.0 551937.0 552510.2 552750.0 26627.7 27409.6 27414.9 28086.3 28151.7 48095.5 48355.5 48528.5 49085.8 49694.8 33275.2 39477.8 39692.6 40313.6 40997.8 38167.6 39623.6 39679.4 40345.0 40773.6 36079.0 37615.6 37710.6 38217.0 39271.8 30346.8 31712.8 32307.0 35214.8 35472.6 23743.4 24224.3 25163.4 25743.8 26444.6 17:21:38 INFO - PROCESS | 2275 | 17:21:38 INFO - PROCESS | 2275 | [#2] jquery.html Cycles:180 Average:34108.83 Median:13422.35 stddev:93593.00 (697.3%) stddev-sans-first:93821.22 17:21:38 INFO - PROCESS | 2275 | Values: 347.5 353.7 361.0 381.6 395.4 15674.9 17133.2 20568.4 21345.5 21390.6 20120.4 20810.8 20886.4 21460.9 21622.5 5251.4 5378.1 5412.4 5560.4 5566.2 3856.4 5392.9 5481.4 5483.8 5485.9 5789.3 6064.9 6109.1 6126.5 6139.6 5827.2 5964.7 6066.7 6089.6 6120.1 5842.8 5844.4 5846.2 5848.2 5850.1 13539.4 15716.6 19898.4 26068.9 26497.2 2186.7 2195.8 2220.6 2262.1 2515.4 9796.8 9923.5 10004.6 10418.7 10452.3 1845.5 1858.1 1883.9 1902.5 1947.6 118273.0 124309.4 124690.0 127933.0 128945.4 11019.8 11417.9 11482.1 11539.2 11683.5 14573.2 14576.9 14621.5 14623.4 14623.4 8341.6 8611.3 8628.2 8765.0 8787.7 8604.5 8656.5 8665.7 8827.8 8850.8 4452.3 4525.9 4552.1 4598.7 4633.8 1846.0 1877.7 1895.6 1899.0 1916.6 701.7 750.9 898.9 899.4 974.9 20919.8 21122.5 21227.5 21384.6 21558.0 900.8 902.8 924.2 963.9 1003.6 10625.3 11046.3 11086.3 11110.8 11176.1 536329.0 552260.0 559240.0 560553.0 566778.0 131583.7 133411.0 134495.0 138755.7 141235.5 931.6 932.1 949.4 951.2 953.6 18032.7 18879.7 18911.7 18948.3 18967.2 55050.0 55613.4 55699.0 55969.7 56157.0 79615.0 79896.6 80384.0 80790.0 80792.0 5019.0 5134.0 6866.3 7438.0 8114.8 9216.6 11906.0 12398.0 12706.6 12824.9 9931.9 11020.0 11124.5 11176.8 11206.5 10397.8 10424.6 10706.7 10726.8 10757.6 10616.1 10864.4 10876.9 10886.9 11053.4 7725.0 7872.8 7899.8 7948.2 7965.8 9005.5 10475.2 10683.9 10858.9 10881.3 17:21:38 INFO - PROCESS | 2275 | 17:21:38 INFO - PROCESS | 2275 | [#3] mootools.html Cycles:180 Average:5696.57 Median:4832.71 stddev:3202.40 (66.3%) stddev-sans-first:3196.02 17:21:38 INFO - PROCESS | 2275 | Values: 1521.6 1541.4 1709.5 1853.1 1859.9 8475.5 8987.6 8993.9 8998.5 9000.7 7848.8 8107.1 8478.7 8717.7 8797.3 2380.1 2406.5 2408.0 2417.3 2417.7 2373.7 2394.0 2422.2 2429.3 2430.5 2402.4 2416.3 2422.6 2426.3 2428.1 2340.2 2361.6 2366.8 2374.8 2374.8 2381.8 2399.7 2401.0 2401.0 2401.6 8816.5 8869.0 8908.5 8941.6 9006.3 5257.3 5424.4 5429.8 5439.9 5460.8 3578.5 3667.1 3747.3 3751.1 3763.3 1092.9 1110.7 1111.3 1127.2 1134.3 9927.5 11706.5 11797.4 11800.6 11808.4 4332.2 4448.4 4630.7 4634.4 4635.5 9206.4 9230.5 9239.0 9241.2 9251.2 5253.7 5264.0 5269.7 5275.4 5278.6 4002.4 4423.2 4601.4 4869.9 4925.7 3737.3 3780.9 3844.5 3883.3 3891.2 1012.5 1148.6 1158.8 1160.2 1176.4 683.3 723.6 770.3 913.8 937.4 5798.5 5913.0 5990.1 6071.9 6216.9 436.2 455.4 456.4 459.9 461.0 3965.7 4032.9 4107.7 4111.4 4140.2 9902.3 10834.6 10964.3 10965.0 10973.7 10668.8 10757.2 10922.8 10934.5 10985.5 7651.3 8346.8 8349.4 8365.7 8371.0 5880.2 5954.9 5969.8 6033.3 6058.7 9684.6 10009.4 10080.9 10124.2 10175.6 10307.5 10311.7 10334.8 10355.2 10367.1 6538.2 6734.1 6746.4 7071.5 7159.8 7876.5 8182.5 8301.7 8514.3 8584.4 7596.2 7598.7 7654.4 7687.4 7714.0 7292.3 7361.5 7453.9 7457.4 7572.8 6994.5 7151.0 7152.1 7158.1 7158.9 4795.5 5065.5 5068.6 5098.6 5117.0 5036.7 5058.7 5217.9 5233.2 5336.7 17:21:38 INFO - PROCESS | 2275 | 17:21:38 INFO - PROCESS | 2275 | [#4] prototype.html Cycles:180 Average:4467.06 Median:5107.75 stddev:1385.63 (27.1%) stddev-sans-first:1353.35 17:21:38 INFO - PROCESS | 2275 | Values: 276.6 292.1 305.2 308.5 312.6 5246.8 5272.2 5273.7 5288.9 5300.8 4971.0 5036.5 5142.3 5226.5 5271.6 3666.5 3758.7 4012.8 4073.6 4091.2 4044.2 4054.6 4077.1 4093.6 4099.8 3206.8 3260.4 3957.5 4014.6 4027.6 3148.8 3990.5 4027.3 4032.6 4034.9 2832.8 3327.4 3423.8 3434.6 3450.7 4358.7 5120.3 5129.9 5136.8 5150.9 3838.6 4185.0 4575.4 4616.9 4644.7 4931.8 5386.2 5457.0 5724.9 5725.1 3212.5 3389.0 3827.8 3856.6 3858.8 5118.4 5512.7 5622.1 5854.9 5867.4 3813.2 3837.8 3846.9 3855.2 3876.4 3908.7 3916.2 3916.5 3916.9 3935.8 4375.9 4479.4 4583.7 4587.6 4599.3 4643.4 5404.9 5589.4 5688.7 5692.5 1146.7 1298.9 1319.7 1328.2 1332.5 621.7 877.9 884.7 894.6 899.8 4194.5 5213.9 5216.8 5218.3 5245.6 5691.0 5719.3 5726.8 5755.9 5790.4 3889.6 4159.9 5113.0 5187.3 5245.2 2828.9 2979.2 2987.7 2989.8 3002.8 5095.7 5680.8 5876.4 5894.2 5919.6 5742.7 5781.4 5792.6 5803.5 5822.9 5856.0 5863.5 5870.9 5878.9 5925.7 4639.7 5322.3 5664.6 5668.6 5688.3 4406.7 4894.4 5102.5 5583.6 5611.4 5444.2 5839.0 5841.6 5873.5 5896.4 3722.5 3776.0 3778.5 3795.2 3795.6 4663.0 5245.6 5546.1 5570.8 5671.6 5031.5 5059.8 5377.5 5485.7 5496.8 4573.8 4830.0 4958.3 5355.5 5400.9 4459.6 4867.7 5135.4 5349.9 5385.2 5126.5 5438.6 5460.0 5465.4 5485.0 5455.6 5458.8 5489.2 5509.5 5568.7 17:21:38 INFO - PROCESS | 2275 | 17:21:38 INFO - PROCESS | 2275 | [#5] yui.html Cycles:180 Average:24925.76 Median:19283.95 stddev:39105.25 (202.8%) stddev-sans-first:39172.47 17:21:38 INFO - PROCESS | 2275 | Values: 649.5 786.3 795.6 798.6 816.9 15113.5 15770.1 16381.1 16386.4 16712.0 14814.2 15211.5 15227.5 15235.3 15250.1 687.7 833.2 851.7 855.1 856.5 700.1 700.4 701.7 703.0 708.7 866.2 909.1 921.6 922.4 926.4 893.8 894.8 895.3 896.2 896.2 535.2 652.6 666.7 669.4 670.0 15002.7 15116.6 15137.1 15148.8 15167.3 13816.8 14053.9 14103.4 14141.6 14207.5 11137.8 12797.5 13040.9 13147.1 13228.7 3931.4 4481.8 4571.7 4593.9 4706.0 120393.4 121245.0 121253.4 121293.0 121594.4 11023.9 11282.8 11286.0 11303.4 11534.8 21855.9 21932.7 21933.3 21967.3 21973.7 7989.3 7991.0 7998.2 8023.5 8182.3 4343.3 4428.9 4516.8 4641.5 4691.9 2712.9 3542.5 3544.1 3548.1 3548.3 643.6 647.3 654.6 673.1 674.2 495.9 566.9 641.4 650.0 656.3 8752.5 9002.1 9016.6 9139.9 9150.8 581.3 612.2 628.8 640.0 641.2 4114.5 4431.0 4571.4 4592.7 5203.0 117555.8 141565.0 141798.3 144628.0 146848.0 64288.7 82054.2 112887.9 120160.0 121580.4 116387.0 120816.0 121025.0 121049.0 126485.0 8748.0 9583.1 10147.8 10236.9 10249.1 36829.0 62292.7 63823.4 64690.7 66853.0 100776.5 104339.0 105068.5 105603.0 107526.9 13405.3 13471.0 13581.3 13604.5 13690.7 15195.2 15209.4 15499.6 15655.0 16008.2 14224.4 14540.3 14548.6 14975.5 15018.2 13961.8 14490.2 14688.4 14925.7 15008.5 13862.4 14416.4 14436.3 14737.1 14765.6 11216.4 11457.4 11546.0 11737.5 11837.3 11544.0 11613.2 11735.1 11890.6 11992.6 17:21:38 INFO - PROCESS | 2275 | -------- Summary: end -------- 17:21:38 INFO - PROCESS | 2275 | 17:21:38 INFO - TEST-INFO | 2275: exit 0 17:21:38 INFO - TEST-OK | dromaeo_css | took 1118158ms 17:21:38 INFO - TEST-START | kraken 17:21:38 INFO - operating with platform_type : mac_ 17:21:38 INFO - Initialising browser for kraken test... 17:21:38 INFO - TEST-INFO | started process 2279 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpuPpB0X/profile http://localhost:53240/getInfo.html) 17:21:41 INFO - PROCESS | 2279 | __metrics Screen width/height:1600/1200 17:21:41 INFO - PROCESS | 2279 | colorDepth:24 17:21:41 INFO - PROCESS | 2279 | Browser inner width/height: 1024/689 17:21:41 INFO - PROCESS | 2279 | __metrics 17:21:41 INFO - PROCESS | 2279 | 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" 17:21:41 INFO - PROCESS | 2279 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:21:42 INFO - TEST-INFO | 2279: exit 0 17:21:42 INFO - Browser initialized. 17:21:42 INFO - Running cycle 1/1 for kraken test... 17:21:42 INFO - TEST-INFO | started process 2280 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpuPpB0X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:21:54 INFO - PROCESS | 2280 | RSS: Main: 228790272 17:21:54 INFO - PROCESS | 2280 | 17:21:56 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:53240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53240/tests/kraken/driver.html?testName=audio-beat-detection) 17:21:57 INFO - PROCESS | 2280 | RSS: Main: 294334464 17:21:57 INFO - PROCESS | 2280 | 17:22:00 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:53240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53240/tests/kraken/driver.html?testName=audio-dft) 17:22:00 INFO - PROCESS | 2280 | RSS: Main: 294658048 17:22:00 INFO - PROCESS | 2280 | 17:22:04 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:53240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53240/tests/kraken/driver.html?testName=audio-fft) 17:22:05 INFO - PROCESS | 2280 | RSS: Main: 381054976 17:22:05 INFO - PROCESS | 2280 | 17:22:07 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:53240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53240/tests/kraken/driver.html?testName=audio-oscillator) 17:22:07 INFO - PROCESS | 2280 | RSS: Main: 282120192 17:22:07 INFO - PROCESS | 2280 | 17:22:09 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:53240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:22:10 INFO - PROCESS | 2280 | RSS: Main: 286228480 17:22:10 INFO - PROCESS | 2280 | 17:22:13 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:53240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53240/tests/kraken/driver.html?testName=imaging-darkroom) 17:22:13 INFO - PROCESS | 2280 | RSS: Main: 326348800 17:22:13 INFO - PROCESS | 2280 | 17:22:16 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:53240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53240/tests/kraken/driver.html?testName=imaging-desaturate) 17:22:17 INFO - PROCESS | 2280 | RSS: Main: 326103040 17:22:17 INFO - PROCESS | 2280 | 17:22:20 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:53240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53240/tests/kraken/driver.html?testName=json-parse-financial) 17:22:20 INFO - PROCESS | 2280 | RSS: Main: 324227072 17:22:20 INFO - PROCESS | 2280 | 17:22:22 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:53240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:22:22 INFO - PROCESS | 2280 | RSS: Main: 301191168 17:22:22 INFO - PROCESS | 2280 | 17:22:24 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:53240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53240/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:22:25 INFO - PROCESS | 2280 | RSS: Main: 304648192 17:22:25 INFO - PROCESS | 2280 | 17:22:27 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:53240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:22:28 INFO - PROCESS | 2280 | RSS: Main: 310579200 17:22:28 INFO - PROCESS | 2280 | 17:22:31 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:53240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:22:31 INFO - PROCESS | 2280 | RSS: Main: 315359232 17:22:31 INFO - PROCESS | 2280 | 17:22:34 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:53240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:22:35 INFO - PROCESS | 2280 | RSS: Main: 317693952 17:22:35 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:53240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53240/tests/kraken/driver.html?testName=ai-astar) 17:22:37 INFO - PROCESS | 2280 | RSS: Main: 361136128 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | __start_tp_report 17:22:37 INFO - PROCESS | 2280 | _x_x_mozilla_page_load 17:22:37 INFO - PROCESS | 2280 | _x_x_mozilla_page_load_details 17:22:37 INFO - PROCESS | 2280 | |i|pagename|runs| 17:22:37 INFO - PROCESS | 2280 | |0;ai-astar;134;168;154;114;113;112;117;112;114;113 17:22:37 INFO - PROCESS | 2280 | |1;audio-beat-detection;152;109;136;164;109;132;131;108;159;107 17:22:37 INFO - PROCESS | 2280 | |2;audio-dft;186;185;184;181;181;183;184;188;182;179 17:22:37 INFO - PROCESS | 2280 | |3;audio-fft;77;77;104;96;56;60;61;90;88;56 17:22:37 INFO - PROCESS | 2280 | |4;audio-oscillator;85;90;131;70;71;77;116;70;70;114 17:22:37 INFO - PROCESS | 2280 | |5;imaging-gaussian-blur;87;85;89;84;88;84;88;85;88;84 17:22:37 INFO - PROCESS | 2280 | |6;imaging-darkroom;93;93;93;94;94;93;94;94;93;94 17:22:37 INFO - PROCESS | 2280 | |7;imaging-desaturate;85;82;87;86;84;83;84;85;86;83 17:22:37 INFO - PROCESS | 2280 | |8;json-parse-financial;55;54;55;57;53;56;55;54;55;54 17:22:37 INFO - PROCESS | 2280 | |9;json-stringify-tinderbox;68;65;72;74;78;74;72;72;69;70 17:22:37 INFO - PROCESS | 2280 | |10;stanford-crypto-aes;85;85;87;87;85;87;85;89;85;89 17:22:37 INFO - PROCESS | 2280 | |11;stanford-crypto-ccm;138;137;136;137;136;127;154;141;244;149 17:22:37 INFO - PROCESS | 2280 | |12;stanford-crypto-pbkdf2;169;169;169;168;170;239;172;165;166;170 17:22:37 INFO - PROCESS | 2280 | |13;stanford-crypto-sha256-iterative;68;67;68;69;68;67;66;68;67;65 17:22:37 INFO - PROCESS | 2280 | __end_tp_report 17:22:37 INFO - PROCESS | 2280 | __start_cc_report 17:22:37 INFO - PROCESS | 2280 | _x_x_mozilla_cycle_collect,2123 17:22:37 INFO - PROCESS | 2280 | __end_cc_report 17:22:37 INFO - PROCESS | 2280 | __startTimestamp1471911757600__endTimestamp 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | ------- Summary: start ------- 17:22:37 INFO - PROCESS | 2280 | Number of tests: 14 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | [#0] ai-astar Cycles:10 Average:125.10 Median:115.50 stddev:20.28 (17.6%) stddev-sans-first:21.26 17:22:37 INFO - PROCESS | 2280 | Values: 134.0 168.0 154.0 114.0 113.0 112.0 117.0 112.0 114.0 113.0 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:134.00 stddev:22.14 (16.5%) stddev-sans-first:22.10 17:22:37 INFO - PROCESS | 2280 | Values: 152.0 109.0 136.0 164.0 109.0 132.0 131.0 108.0 159.0 107.0 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:2.67 (1.5%) stddev-sans-first:2.65 17:22:37 INFO - PROCESS | 2280 | Values: 186.0 185.0 184.0 181.0 181.0 183.0 184.0 188.0 182.0 179.0 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | [#3] audio-fft Cycles:10 Average:76.50 Median:77.00 stddev:17.65 (22.9%) stddev-sans-first:18.72 17:22:37 INFO - PROCESS | 2280 | Values: 77.0 77.0 104.0 96.0 56.0 60.0 61.0 90.0 88.0 56.0 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | [#4] audio-oscillator Cycles:10 Average:89.40 Median:70.50 stddev:22.81 (32.4%) stddev-sans-first:24.14 17:22:37 INFO - PROCESS | 2280 | Values: 85.0 90.0 131.0 70.0 71.0 77.0 116.0 70.0 70.0 114.0 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.50 stddev:1.99 (2.3%) stddev-sans-first:2.09 17:22:37 INFO - PROCESS | 2280 | Values: 87.0 85.0 89.0 84.0 88.0 84.0 88.0 85.0 88.0 84.0 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 17:22:37 INFO - PROCESS | 2280 | Values: 93.0 93.0 93.0 94.0 94.0 93.0 94.0 94.0 93.0 94.0 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:1.58 (1.9%) stddev-sans-first:1.67 17:22:37 INFO - PROCESS | 2280 | Values: 85.0 82.0 87.0 86.0 84.0 83.0 84.0 85.0 86.0 83.0 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:1.14 (2.1%) stddev-sans-first:1.20 17:22:37 INFO - PROCESS | 2280 | Values: 55.0 54.0 55.0 57.0 53.0 56.0 55.0 54.0 55.0 54.0 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:72.00 stddev:3.63 (5.0%) stddev-sans-first:3.63 17:22:37 INFO - PROCESS | 2280 | Values: 68.0 65.0 72.0 74.0 78.0 74.0 72.0 72.0 69.0 70.0 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:87.00 stddev:1.65 (1.9%) stddev-sans-first:1.67 17:22:37 INFO - PROCESS | 2280 | Values: 85.0 85.0 87.0 87.0 85.0 87.0 85.0 89.0 85.0 89.0 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:139.50 stddev:33.89 (24.3%) stddev-sans-first:35.67 17:22:37 INFO - PROCESS | 2280 | Values: 138.0 137.0 136.0 137.0 136.0 127.0 154.0 141.0 244.0 149.0 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.70 Median:169.50 stddev:22.33 (13.2%) stddev-sans-first:23.55 17:22:37 INFO - PROCESS | 2280 | Values: 169.0 169.0 169.0 168.0 170.0 239.0 172.0 165.0 166.0 170.0 17:22:37 INFO - PROCESS | 2280 | 17:22:37 INFO - PROCESS | 2280 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:1.16 (1.7%) stddev-sans-first:1.20 17:22:37 INFO - PROCESS | 2280 | Values: 68.0 67.0 68.0 69.0 68.0 67.0 66.0 68.0 67.0 65.0 17:22:37 INFO - PROCESS | 2280 | -------- Summary: end -------- 17:22:37 INFO - PROCESS | 2280 | 17:22:38 INFO - TEST-INFO | 2280: exit 0 17:22:38 INFO - TEST-OK | kraken | took 59561ms 17:22:38 INFO - SUITE-END | took 1177s 17:22:38 INFO - Completed test suite (00:19:37) 17:22:38 INFO - javascript benchmark 17:22:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3146.606674165729, 3315.5358249845012, 3318.7510936679755, 3325.3016866458033, 3357.983210083951, 5500.092484740013, 5565.916511252324, 5610.691411972345, 5671.064274394725, 5681.147827825828, 5510.669359838403, 5581.107022876341, 5591.4688104629995, 5599.300087489064, 5624.91562626561, 5061.721605311706, 5078.289039534459, 5167.560757335612, 5176.948230517684, 5181.3264275644115, 5125.231215317714, 5138.897222055557, 5221.530062294392, 5261.921071183935, 5288.444713305105, 5258.290130832327, 5273.868153296179, 5289.920651190216, 5294.629375943686, 5297.417284098746, 5081.085404627165, 5083.237514372837, 5270.104082305978, 5272.0, 5288.629795914288, 5042.949570504285, 5094.184930411154, 5099.59203263738, 5144.794208231666, 5163.509466600667, 4644.698094623838, 5582.944170558283, 5589.329280486327, 5668.57485688575, 5673.319201695782, 6079.787207447718, 6241.500679945593, 6246.812595622138, 6256.937430625681, 6262.499000079983, 6159.692015399212, 6170.197874276315, 6243.62538247702, 6254.718537665816, 6278.780242691484, 4831.951680483186, 5821.039528477839, 5827.0, 5827.475527202572, 5827.854303642443, 6158.815235542851, 6184.752609895554, 6254.468370188585, 6256.5307601930035, 6261.499080073583, 5070.847874563769, 6086.847828804315, 6164.4143806338325, 6167.660778657217, 6174.66039367839, 5150.639455238097, 6227.813165605039, 6244.469220116341, 6259.186305780219, 6260.7495700171485, 5962.463378295887, 6072.51419886408, 6119.96940015306, 6169.475594574422, 6171.290301615371, 6119.4798442132915, 6161.137440758297, 6170.352113028157, 6222.0, 6223.875522489616, 4925.458199598042, 4969.006198760262, 4971.825986090542, 4975.104481193348, 4990.50094990498, 4434.290513517822, 4441.311596702516, 4465.732056076646, 4472.239719247736, 4473.776311184492, 5541.196526503634, 5599.496045355938, 5612.242347283146, 5652.236948012048, 5694.316681998187, 6257.843553911098, 6273.309935907047, 6280.560360774702, 6283.7800676977, 6321.273053598803, 5684.8294551163535, 5713.028785106542, 5729.8567535811935, 5736.569757268222, 5739.770409183586, 5277.8416647500635, 5331.200319952039, 5369.4630536946, 5388.38033626138, 5439.619226654097, 6257.09272155544, 6288.0, 6290.339514351111, 6291.150694656163, 6329.810105696836, 5179.482051794941, 6266.279377871603, 6267.529935254783, 6288.245410550763, 6305.0, 6180.505559555315, 6181.505479561534, 6219.284782250099, 6241.032639940817, 6313.2424109107205, 6163.414475624809, 6186.1648677427975, 6190.473809726223, 6198.163247961467, 6200.503959683305, 6218.440340369389, 6244.500439964882, 6253.812385628438, 6275.592086514362, 6281.340459251713, 5868.295804503487, 5938.406159384027, 5965.254343207099, 6010.909836352545, 6071.119687645179, 5875.529957603296, 6085.087236914498, 6132.0, 6135.53983451234, 6155.35368786271, 5891.23413956183, 5912.054071348563, 5915.763369465173, 5924.022536281637, 5953.613015154001, 5823.18475413451, 5920.79277225295, 5956.136360227656, 6001.459868611846, 6093.116498107661, 5932.93207222704, 5942.435468630473, 5950.702464876652, 5954.136650185786, 5958.285005799332], "value": 5646.268248533735, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.89277680579835, 488.63596620517717, 493.38573476022356, 513.43265691411, 522.6393788286082, 30068.398632027373, 31822.045338639848, 33322.83338583304, 41240.99999999996, 42238.57761422385, 41598.58401415985, 43931.34102988458, 43954.34068488967, 43959.56040439595, 44089.7795511022, 9814.411135331886, 9981.251406144569, 9982.450965196911, 9990.800183996316, 9994.0, 8557.400981931267, 9897.90102098977, 10015.048570385801, 10020.494876280929, 10033.59865605375, 9981.451020193887, 10040.548175332091, 10050.597976080984, 10051.195904327673, 10068.29521933465, 9932.900670993306, 10014.649487267983, 10030.59877604895, 10035.448050357227, 10037.397756134655, 9736.269779766546, 9777.653363192854, 9787.951060244653, 9788.755281117958, 9806.362586431858, 40964.79517602393, 41309.1738165238, 41310.58689413127, 41315.793421033006, 41381.37927931084, 3561.626029266916, 3596.8381422836037, 3607.4769158471877, 3684.207895302496, 4100.753954762724, 23034.424139396648, 32435.837820810983, 32546.996311870596, 32745.508817367758, 33279.0, 3181.8906106839736, 3221.6617255188335, 3314.486254630536, 3322.750793690483, 3375.561177046968, 486003.0, 491134.0, 492756.0, 493564.0, 495678.9999999964, 60610.39389606136, 60958.78082438326, 61056.69471652613, 61085.38914610886, 61343.6932815342, 64197.0, 64544.354556453836, 64596.677016614616, 64631.67684161549, 64707.70584588282, 16393.180340982904, 16667.83332166663, 16670.91664541694, 16679.91660041692, 16734.916325418297, 11004.39475828821, 12876.484940602271, 12914.67713307156, 12918.90189333917, 12924.612261632166, 17043.0, 17739.911300443415, 17793.7330940036, 17799.288028478717, 17841.553961151076, 5662.801801936995, 6646.966765166143, 6650.534462587572, 6679.096457509587, 6761.695723692445, 837.2966707965338, 957.6025949230991, 1006.33079002464, 1013.2856336282903, 1074.5433190893839, 28437.289067773472, 28667.996620118196, 30581.541276880864, 30797.384052318826, 31145.844270778955, 818.4679958027211, 896.3098414221143, 1010.6210171185805, 1042.8592140060994, 1142.737170450801, 15239.847601524065, 15755.0, 16141.192940352936, 16151.465610766958, 16373.67252654964, 678190.0, 686469.0, 687781.0, 688033.0, 689641.0, 58350.0, 58653.12020319699, 58676.533086673175, 58711.11933321004, 58845.70577147087, 1254.1973137192022, 1367.6649220940933, 1384.2179168769612, 1425.3585886351184, 1427.892908031881, 25754.484910302064, 29558.0, 31183.84408077945, 31199.0, 32074.0, 42384.0, 51969.74015130052, 52121.696957576365, 52129.95740085253, 52228.73885630548, 549934.0, 551167.0, 551937.0, 552510.2238753653, 552750.0, 26627.733722662524, 27409.588856166774, 27414.862925685244, 28086.2978425541, 28151.71848281491, 48095.519044810506, 48355.5164448351, 48528.5147148524, 49085.75457122691, 49694.751526243585, 33275.16812079717, 39477.802610987914, 39692.603073968894, 40313.59686403099, 40997.79501102595, 38167.61832381641, 39623.60376396315, 39679.40480892731, 40345.0, 40773.592264078165, 36079.0, 37615.62384376121, 37710.62289377071, 38217.0, 39271.82184534468, 30346.84826575853, 31712.841435792674, 32307.0, 35214.82392588123, 35472.645273546936, 23743.406414839767, 24224.273271801874, 25163.370915726522, 25743.84152713095, 26444.603330950435], "value": 20011.45344044309, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.50133558343765, 353.72586245659613, 360.96943227086666, 381.6260065136166, 395.4325542846016, 15674.921625391871, 17133.22900469479, 20568.382948511568, 21345.466363340885, 21390.57218855622, 20120.39638810838, 20810.751354918677, 20886.37340879772, 21460.8926955365, 21622.459438514044, 5251.369835619712, 5378.0857254266775, 5412.431694672062, 5560.388357280696, 5566.165075238727, 3856.3636999895125, 5392.865178370533, 5481.369642491125, 5483.8080667176655, 5485.862853428656, 5789.305283366002, 6064.9090263646085, 6109.083637454373, 6126.509879209675, 6139.570230083896, 5827.155062515912, 5964.731587078549, 6066.6663333516635, 6089.573729838913, 6120.081987701858, 5842.824715258506, 5844.415558444121, 5846.181534585162, 5848.210491583667, 5850.0932355484965, 13539.39072741729, 15716.607084822857, 19898.403047908585, 26068.86965565179, 26497.205083847515, 2186.693862859201, 2195.7913998170307, 2220.555888822242, 2262.1064679451524, 2515.3963048868322, 9796.755081123029, 9923.503824808802, 10004.649837255662, 10418.739531511697, 10452.268341216119, 1845.474039898636, 1858.0895361273015, 1883.896385698786, 1902.5338791995912, 1947.552063025512, 118273.0, 124309.37845310716, 124690.0, 127933.0, 128945.35527322303, 11019.779604407868, 11417.885821141683, 11482.138839587064, 11539.249948753304, 11683.474243659055, 14573.198474084027, 14576.854231457763, 14621.488247911271, 14623.415063397557, 14623.415063397557, 8341.624626891804, 8611.311095112502, 8628.180322869317, 8765.035846056928, 8787.736367908972, 8604.48373097616, 8656.523891186045, 8665.696700615448, 8827.779305517413, 8850.822983540293, 4452.285869940097, 4525.864224073283, 4552.135094332067, 4598.655100867449, 4633.837815676503, 1845.972310415318, 1877.6807942649782, 1895.6398284325935, 1898.9715154272697, 1916.5975145219381, 701.7087908517908, 750.909890813095, 898.9460632362079, 899.3929097858919, 974.9275796623799, 20919.84940828269, 21122.471938201666, 21227.46931326698, 21384.572308554052, 21558.0, 900.8243392538392, 902.8194361127801, 924.2144177449114, 963.9325247232626, 1003.6286573967533, 10625.25623206368, 11046.33721976684, 11086.33481991067, 11110.833337499944, 11176.05003574689, 536329.0, 552260.0, 559240.0, 560553.0, 566778.0, 131583.68416315713, 133411.0, 134494.99999999805, 138755.67465952077, 141235.52586844823, 931.5621657820814, 932.0958670090001, 949.3591825517748, 951.167728237792, 953.6280850468323, 18032.729509057113, 18879.716804247673, 18911.716324255423, 18948.336808211647, 18967.241310347432, 55050.0, 55613.44386556245, 55699.0, 55969.72015140061, 56157.0, 79615.0, 79896.60051699704, 80384.0, 80790.0, 80792.0, 5018.971110922325, 5133.998870220271, 6866.34769696878, 7437.995870557405, 8114.756557303291, 9216.631334746804, 11906.0, 12398.0, 12706.618801435972, 12824.871751282368, 9931.900680993387, 11020.008199262105, 11124.499397527292, 11176.832347514957, 11206.495707693337, 10397.84403233937, 10424.635137770141, 10706.732331691459, 10726.785464290515, 10757.623483178051, 10616.097631701083, 10864.402457864893, 10876.945615271872, 10886.945565272123, 11053.392063436588, 7724.961375193088, 7872.763817085496, 7899.802504937423, 7948.16544262844, 7965.8406831864195, 9005.504697241715, 10475.166845801801, 10683.94658026705, 10858.891411085788, 10881.292715973437], "value": 9359.583053080418, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1521.6043828604559, 1541.4296710217218, 1709.4786090242478, 1853.05494197959, 1859.89770562619, 8475.491470511774, 8987.59555819988, 8993.865092023625, 8998.460092394462, 9000.684976025841, 7848.843023139548, 8107.148749381318, 8478.66085356585, 8717.694880679177, 8797.340199485032, 2380.143148466551, 2406.506666133447, 2407.963880541793, 2417.2869003643877, 2417.697787776528, 2373.727021392545, 2393.970592645162, 2422.188566830114, 2429.2712186344024, 2430.5017471418405, 2402.3513651314133, 2416.347586151747, 2422.624493203556, 2426.2721183644926, 2428.0530593068715, 2340.216027630746, 2361.551305252005, 2366.8343215974887, 2374.78626923576, 2374.8456350337347, 2381.821363397735, 2399.652050452692, 2400.975990240093, 2401.0275838285525, 2401.5797235483897, 8816.471011739317, 8868.98006729228, 8908.51003194824, 8941.64233430662, 9006.324525660602, 5257.263983042377, 5424.403315635277, 5429.755660995264, 5439.945600543983, 5460.808871689512, 3578.4632305154173, 3667.064898450878, 3747.2505498900055, 3751.0622344413896, 3763.34141525232, 1092.8633920759905, 1110.6779034080107, 1111.3498603317069, 1127.1827924754568, 1134.3420815926825, 9927.453990030546, 11706.473208705629, 11797.351145686984, 11800.64598062051, 11808.350540720172, 4332.176886391576, 4448.377227188196, 4630.652701047367, 4634.351190833286, 4635.466921304093, 9206.447613143098, 9230.53847307632, 9238.95380523093, 9241.168294853362, 9251.167394934488, 5253.711045892513, 5264.0, 5269.71016594091, 5275.366955965309, 5278.5513231375, 4002.399640054015, 4423.203823311835, 4601.44782626087, 4869.9269510957365, 4925.655204135676, 3737.271232109767, 3780.9432858507143, 3844.519435070616, 3883.3010058189234, 3891.2412079644196, 1012.4937531234383, 1148.6152139033518, 1158.8493495845487, 1160.193665402554, 1176.4294317256124, 683.2552517755571, 723.5658604837056, 770.2567022823002, 913.8126684029891, 937.4361122330421, 5798.507126894261, 5913.0, 5990.101484777231, 6071.939280607226, 6216.875662486725, 436.23658597454454, 455.4147919922883, 456.40895040922084, 459.87353477793874, 461.04793601213385, 3965.6827453803626, 4032.858849940238, 4107.732997355162, 4111.403846442249, 4140.23405669952, 9902.30683852138, 10834.620788272372, 10964.342139471497, 10965.013148816486, 10973.725656858483, 10668.83996740034, 10757.193210509244, 10922.781544369069, 10934.507947142398, 10985.450727463594, 7651.311381975649, 8346.791330216793, 8349.415540912078, 8365.665373384996, 8370.958145209357, 5880.206172166702, 5954.910676339945, 5969.791057312973, 6033.336333003278, 6058.7273572688455, 9684.612615495302, 10009.39943603357, 10080.949595251977, 10124.240681948737, 10175.643852465128, 10307.484625768531, 10311.742206444898, 10334.793304134026, 10355.223358248311, 10367.066963973279, 6538.1827271591055, 6734.057231987427, 6746.392824645805, 7071.540349877241, 7159.785206443815, 7876.488028278143, 8182.4681395709085, 8301.709440169565, 8514.276286515715, 8584.399092063495, 7596.202398748052, 7598.710437827236, 7654.425918056279, 7687.385009199363, 7714.035745531809, 7292.34368906801, 7361.521501102411, 7453.888191677021, 7457.403407727477, 7572.7728168155045, 6994.510384273052, 7151.034610327747, 7152.105986751656, 7158.06945097134, 7158.928410715826, 4795.490822027163, 5065.468125846881, 5068.619853511075, 5098.617603679665, 5117.0, 5036.6726162799305, 5058.671186372874, 5217.869553261199, 5233.215017747369, 5336.653117547502], "value": 4495.376415044611, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.55612741549817, 292.1119795820705, 305.2384301168584, 308.45711547676086, 312.6014331727048, 5246.790128394864, 5272.156454967206, 5273.709945952971, 5288.920666190005, 5300.814471493489, 4970.950290497094, 5036.496350364971, 5142.2745944649305, 5226.529612334889, 5271.551918086967, 3666.486691863128, 3758.6617204451595, 4012.7993600320015, 4073.5926407359175, 4091.2022155679656, 4044.211378781139, 4054.6350828425434, 4077.062275676596, 4093.631573158415, 4099.7745124018165, 3206.8396580171016, 3260.396826587077, 3957.4855268815013, 4014.558398576154, 4027.637512623863, 3148.763842711806, 3990.541087774914, 4027.254957832781, 4032.576579459144, 4034.8615233926066, 2832.7875409344174, 3327.4361050306316, 3423.828808559587, 3434.643315890234, 3450.6894379505966, 4358.694891357606, 5120.334356533627, 5129.871753206163, 5136.7671758778, 5150.892558100027, 3838.6353296436796, 4184.958150418518, 4575.382323386333, 4616.861494155147, 4644.7213167210075, 4931.80272789088, 5386.245925570423, 5457.0, 5724.9427505724825, 5725.055365864628, 3212.4538828398995, 3388.9830550847337, 3827.8086095695103, 3856.614338566149, 3858.8070596470343, 5118.411382690963, 5512.724363781795, 5622.12073669167, 5854.912176317359, 5867.442592953663, 3813.188735350742, 3837.827297771607, 3846.9230615388096, 3855.1904100139286, 3876.4379165020914, 3908.6580973268015, 3916.1789767758128, 3916.5300163980505, 3916.941245881314, 3935.842566297316, 4375.934360984587, 4479.350494178326, 4583.679142459996, 4587.587117159472, 4599.264117741145, 4643.419572553431, 5404.91892621611, 5589.385167631557, 5688.744006519716, 5692.487676109087, 1146.7214056327302, 1298.9090763646454, 1319.6700824793802, 1328.2069206571246, 1332.540273605624, 621.7481919822479, 877.9070057778107, 884.6638277454655, 894.5706061090583, 899.8020435504178, 4194.517630472474, 5213.895722085537, 5216.817411390583, 5218.347706536683, 5245.554127899095, 5690.971545142331, 5719.342275638355, 5726.77092916287, 5755.913661295084, 5790.36306006339, 3889.630485103911, 4159.854405095807, 5113.002964421977, 5187.273781670569, 5245.1607742761735, 2828.892475106018, 2979.2402937251295, 2987.7460415864894, 2989.7757668174972, 3002.84390509651, 5095.745212739348, 5680.829575112753, 5876.41235876409, 5894.233749612523, 5919.638483148814, 5742.655440673573, 5781.392953739881, 5792.623479473905, 5803.506701930299, 5822.883542329129, 5855.970720146372, 5863.5309175265875, 5870.911936320958, 5878.882422351615, 5925.703714814242, 4639.652026097989, 5322.308099946982, 5664.603477756517, 5668.574856885668, 5688.260526131577, 4406.691531592758, 4894.437139728977, 5102.4897510248675, 5583.55331573465, 5611.354694210218, 5444.183372494158, 5839.0, 5841.649501029951, 5873.471387575139, 5896.410358964069, 3722.4974628424816, 3775.980485268907, 3778.525066081721, 3795.1650636859845, 3795.5635101963626, 4663.0, 5245.557471695161, 5546.112621980464, 5570.777168913198, 5671.602987790815, 5031.547160755403, 5059.772310246121, 5377.521181675071, 5485.725713714378, 5496.80761173357, 4573.8274319697875, 4830.033993201304, 4958.2810492477665, 5355.465392840706, 5400.945990540202, 4459.598636122764, 4867.7322747249245, 5135.4336927237555, 5349.9465005351, 5385.246065550904, 5126.53861152498, 5438.619296649196, 5460.0, 5465.371482279589, 5485.0, 5455.608819750938, 5458.808941687022, 5489.176623506506, 5509.5399719073675, 5568.721563921868], "value": 4021.9761409470493, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.5128653509868, 786.2962648429656, 795.5584650518963, 798.5568009754587, 816.9264766171043, 15113.471028514005, 15770.132642704646, 16381.09903955282, 16386.42647507336, 16712.0, 14814.185219812933, 15211.543653690407, 15227.543173704778, 15235.31441085151, 15250.084994900313, 687.6905392573337, 833.1834801894132, 851.7104184577242, 855.119227195986, 856.4946681457939, 700.1458220970408, 700.3906601256914, 701.7438634898276, 702.9789106326792, 708.6527601475266, 866.2247288676631, 909.0500427053745, 921.6037104045258, 922.3635691372965, 926.4487629860222, 893.7765558610347, 894.7852515396258, 895.3285036222833, 896.1665650944586, 896.188949001155, 535.1998761851019, 652.5530011941787, 666.7266420767511, 669.4276393683427, 669.9665016749143, 15002.69994600102, 15116.622084447978, 15137.091774493565, 15148.772768408482, 15167.317470713733, 13816.792748108786, 14053.859461405358, 14103.365348559339, 14141.575752727435, 14207.502737404142, 11137.777244455065, 12797.488100476063, 13040.869591304061, 13147.14543554666, 13228.669283267898, 3931.3513270310364, 4481.775911204427, 4571.674214477797, 4593.8621841344475, 4705.95294047062, 120393.39803300927, 121245.0, 121253.39373303077, 121293.0, 121594.39202803929, 11023.889761102286, 11282.830757538644, 11286.040686541735, 11303.378314192634, 11534.769304613861, 21855.890720546613, 21932.671009934864, 21933.341999740034, 21967.340979770634, 21973.67039494409, 7989.2809647132035, 7991.006744941294, 7998.160193179749, 8023.4784738991775, 8182.263596276363, 4343.305071188594, 4428.933565996513, 4516.774161291923, 4641.512641172628, 4691.906161876744, 2712.864356782153, 3542.5217595624777, 3544.149404142987, 3548.1484443733834, 3548.2548664780684, 643.6170478565247, 647.346180357842, 654.5876098058193, 673.050998092687, 674.1640365946191, 495.88594623236855, 566.8754459684407, 641.3874749614107, 650.0, 656.2715385921623, 8752.51861147643, 9002.05478424769, 9016.63933442668, 9139.923487086005, 9150.81698366029, 581.2937281203358, 612.1521692455981, 628.78330430616, 640.0495264532166, 641.1568787045114, 4114.506259248909, 4430.988176763828, 4571.428571428572, 4592.678512504092, 5203.0, 117555.8244417562, 141565.0, 141798.2910085443, 144628.0, 146848.0, 64288.678556606916, 82054.17945820466, 112887.87112128938, 120160.0, 121580.3708462268, 116387.0, 120816.0, 121025.0, 121049.0, 126484.99999999817, 8748.0, 9583.137517623447, 10147.84778228342, 10236.897631023594, 10249.077583017564, 36829.0, 62292.68853655703, 63823.36176638174, 64690.67654661697, 66853.0, 100776.49611751894, 104339.0, 105068.47465762928, 105603.0, 107526.92473075169, 13405.32973351309, 13470.989675774552, 13581.253031083384, 13604.523841665494, 13690.65773355674, 15195.164265965477, 15209.391624335345, 15499.574078041056, 15655.0, 16008.199590020686, 14224.359903804147, 14540.345684444013, 14548.563543093724, 14975.475858344906, 15018.174000430081, 13961.790573141208, 14490.203038832544, 14688.41246350134, 14925.701485970003, 15008.47470338533, 13862.4455021798, 14416.351264192926, 14436.278186090443, 14737.11577305365, 14765.557033289018, 11216.383098929637, 11457.369844658624, 11546.0, 11737.530498779954, 11837.289762614271, 11544.0, 11613.187076904536, 11735.119866010255, 11890.569770968024, 11992.580259690869], "value": 7921.774643553973, "unit": "score"}], "name": "dromaeo_css", "value": 7301.218865129272, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 168.0, 154.0, 114.0, 113.0, 112.0, 117.0, 112.0, 114.0, 113.0], "value": 125.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 109.0, 136.0, 164.0, 109.0, 132.0, 131.0, 108.0, 159.0, 107.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 184.0, 181.0, 181.0, 183.0, 184.0, 188.0, 182.0, 179.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 104.0, 96.0, 56.0, 60.0, 61.0, 90.0, 88.0, 56.0], "value": 76.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 90.0, 131.0, 70.0, 71.0, 77.0, 116.0, 70.0, 70.0, 114.0], "value": 89.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 89.0, 84.0, 88.0, 84.0, 88.0, 85.0, 88.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 87.0, 86.0, 84.0, 83.0, 84.0, 85.0, 86.0, 83.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 57.0, 53.0, 56.0, 55.0, 54.0, 55.0, 54.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 72.0, 74.0, 78.0, 74.0, 72.0, 72.0, 69.0, 70.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 87.0, 85.0, 87.0, 85.0, 89.0, 85.0, 89.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 136.0, 137.0, 136.0, 127.0, 154.0, 141.0, 244.0, 149.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 169.0, 168.0, 170.0, 239.0, 172.0, 165.0, 166.0, 170.0], "value": 175.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 69.0, 68.0, 67.0, 66.0, 68.0, 67.0, 65.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1474.7, "alertThreshold": 2.0}]} 17:22:38 INFO - Return code: 0 17:22:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:22:38 INFO - # TBPL SUCCESS # 17:22:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:22:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:22:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:22:38 INFO - 2016-08-22 17:22:38.910 screenresolution[2284:290763] starting screenresolution argv=screenresolution get 17:22:38 INFO - 2016-08-22 17:22:38.913 screenresolution[2284:290763] Display 0: 1600x1200x32@60 17:22:38 INFO - 2016-08-22 17:22:38.918 screenresolution[2285:290766] starting screenresolution argv=screenresolution list 17:22:38 INFO - Available Modes on Display 0 17:22:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:22:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:22:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:22:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:22:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:22:39 INFO - Intel Iris: 17:22:39 INFO - Chipset Model: Intel Iris 17:22:39 INFO - Type: GPU 17:22:39 INFO - Bus: Built-In 17:22:39 INFO - VRAM (Dynamic, Max): 1536 MB 17:22:39 INFO - Vendor: Intel (0x8086) 17:22:39 INFO - Device ID: 0x0a2e 17:22:39 INFO - Revision ID: 0x0009 17:22:39 INFO - Displays: 17:22:39 INFO - Display: 17:22:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:22:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:22:39 INFO - Display Serial Number: 1600x1200 60 17:22:39 INFO - Main Display: Yes 17:22:39 INFO - Mirror: Off 17:22:39 INFO - Online: Yes 17:22:39 INFO - Rotation: Supported 17:22:39 INFO - Return code: 0 17:22:39 INFO - Running post-action listener: _resource_record_post_action 17:22:39 INFO - [mozharness: 2016-08-23 00:22:39.153367Z] Finished run-tests step (success) 17:22:39 INFO - Running post-run listener: _resource_record_post_run 17:22:39 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1064960; Write bytes: 705268224; Read time: 11; Write time: 2052 17:22:39 INFO - TinderboxPrint: CPU usage
53.5% 17:22:39 INFO - TinderboxPrint: I/O read bytes / time
1,064,960 / 11 17:22:39 INFO - TinderboxPrint: I/O write bytes / time
705,268,224 / 2,052 17:22:39 INFO - TinderboxPrint: CPU idle
2,254.4 (46.5%) 17:22:39 INFO - TinderboxPrint: CPU system
971.9 (20.0%) 17:22:39 INFO - TinderboxPrint: CPU user
1,623.3 (33.5%) 17:22:39 INFO - TinderboxPrint: Swap in / out
407,408,640 / 0 17:22:39 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 397472256; Write bytes: 393174528; Read time: 29561; Write time: 868 17:22:39 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 1056768; Write bytes: 294104064; Read time: 10; Write time: 1132 17:22:39 INFO - Running post-run listener: _upload_blobber_files 17:22:39 INFO - Blob upload gear active. 17:22:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:22:39 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 17:22:39 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'] 17:22:39 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 17:22:40 INFO - (blobuploader) - INFO - Open directory for files ... 17:22:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:22:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:22:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:22:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:22:40 INFO - (blobuploader) - INFO - Done attempting. 17:22:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:22:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:22:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:22:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:22:41 INFO - (blobuploader) - INFO - Done attempting. 17:22:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:22:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:22:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:22:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:22:44 INFO - (blobuploader) - INFO - Done attempting. 17:22:44 INFO - (blobuploader) - INFO - Iteration through files over. 17:22:44 INFO - Return code: 0 17:22:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:22:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:22:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2cda9d3f1e4b2bc337353ccfa790c8c3edc9c89d70234dcc9429cf7fe42b62b8d546e1dbcb9697b27b1ca9d28f6731837dd29d418a28cc215ac2fef98851bf28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e8e7fdb03be1281a7f10951ce1a745c242b3ae3c6046523ed43c19ea7f1575beb18f5c18ef9c9c7664080887243518c8354c1fd9d044aa0e01715ee49796988", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dc2699c45bc5450e3dc2220df69aa800109e98861c22dc032624a123822187176868570fae5c49cf728c0e12674f9428d8eaa75a04c3ee5274722c4a998156a1"} 17:22:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:22:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:22:44 INFO - Contents: 17:22:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2cda9d3f1e4b2bc337353ccfa790c8c3edc9c89d70234dcc9429cf7fe42b62b8d546e1dbcb9697b27b1ca9d28f6731837dd29d418a28cc215ac2fef98851bf28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e8e7fdb03be1281a7f10951ce1a745c242b3ae3c6046523ed43c19ea7f1575beb18f5c18ef9c9c7664080887243518c8354c1fd9d044aa0e01715ee49796988", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dc2699c45bc5450e3dc2220df69aa800109e98861c22dc032624a123822187176868570fae5c49cf728c0e12674f9428d8eaa75a04c3ee5274722c4a998156a1"} 17:22:44 INFO - Running post-run listener: copy_logs_to_upload_dir 17:22:44 INFO - Copying logs to upload dir... 17:22:44 INFO - mkdir: /builds/slave/test/build/upload/logs 17:22:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.089794 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-22 17:22:44.296375) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 17:22:44.299996) ========= 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.euDadNXgzw/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.I5auhp3cFA/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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/2cda9d3f1e4b2bc337353ccfa790c8c3edc9c89d70234dcc9429cf7fe42b62b8d546e1dbcb9697b27b1ca9d28f6731837dd29d418a28cc215ac2fef98851bf28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e8e7fdb03be1281a7f10951ce1a745c242b3ae3c6046523ed43c19ea7f1575beb18f5c18ef9c9c7664080887243518c8354c1fd9d044aa0e01715ee49796988", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dc2699c45bc5450e3dc2220df69aa800109e98861c22dc032624a123822187176868570fae5c49cf728c0e12674f9428d8eaa75a04c3ee5274722c4a998156a1"} build_url:https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011641 build_url: 'https://queue.taskcluster.net/v1/task/KDiGew3LSvGHvOYUE5acVA/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/2cda9d3f1e4b2bc337353ccfa790c8c3edc9c89d70234dcc9429cf7fe42b62b8d546e1dbcb9697b27b1ca9d28f6731837dd29d418a28cc215ac2fef98851bf28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e8e7fdb03be1281a7f10951ce1a745c242b3ae3c6046523ed43c19ea7f1575beb18f5c18ef9c9c7664080887243518c8354c1fd9d044aa0e01715ee49796988", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dc2699c45bc5450e3dc2220df69aa800109e98861c22dc032624a123822187176868570fae5c49cf728c0e12674f9428d8eaa75a04c3ee5274722c4a998156a1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 17:22:44.334345) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-08-22 17:22:44.334697) ========= 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.euDadNXgzw/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.I5auhp3cFA/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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.005444 ========= master_lag: 3.58 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-08-22 17:22:47.923484) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 17:22:47.923850) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 17:22:47.928719) ========= ========= Total master_lag: 3.79 =========