builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0313 starttime: 1472782704.36 results: success (0) buildid: 20160901170142 builduid: 02cab5aab2a24838b41e2ab04ee0f466 revision: 4bee815089891447e819466885b771bf1db1f59a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 19:18:24.364882) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 19:18:24.365309) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 19:18:24.365592) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 19:18:24.393737) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:18:24.394049) ========= 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.jGDMzBWDEH/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.UIGYNjsmHu/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-01 19:18:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.75M=0.001s 2016-09-01 19:18:24 (7.75 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119414 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:18:24.535786) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:18:24.536096) ========= 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.jGDMzBWDEH/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.UIGYNjsmHu/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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.019810 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:18:24.586729) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-09-01 19:18:24.587087) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4bee815089891447e819466885b771bf1db1f59a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 4bee815089891447e819466885b771bf1db1f59a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jGDMzBWDEH/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.UIGYNjsmHu/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-01 19:18:24,793 truncating revision to first 12 chars 2016-09-01 19:18:24,793 Setting DEBUG logging. 2016-09-01 19:18:24,793 attempt 1/10 2016-09-01 19:18:24,794 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4bee81508989?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 19:18:26,489 unpacking tar archive at: mozilla-central-4bee81508989/testing/mozharness/ program finished with exit code 0 elapsedTime=1.975995 ========= master_lag: 2.77 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-09-01 19:18:29.332753) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 19:18:29.333073) ========= script_repo_revision: 4bee815089891447e819466885b771bf1db1f59a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 19:18:29.333478) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 19:18:29.333740) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 19:18:29.369186) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-01 19:18:29.370137) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jGDMzBWDEH/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.UIGYNjsmHu/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 19:18:29 INFO - MultiFileLogger online at 20160901 19:18:29 in /builds/slave/test 19:18:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:18:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:18:29 INFO - {'append_to_log': False, 19:18:29 INFO - 'base_work_dir': '/builds/slave/test', 19:18:29 INFO - 'blob_upload_branch': 'Firefox', 19:18:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:18:29 INFO - 'branch': 'Firefox', 19:18:29 INFO - 'buildbot_json_path': 'buildprops.json', 19:18:29 INFO - 'config_files': ('talos/mac_config.py',), 19:18:29 INFO - 'default_actions': ('clobber', 19:18:29 INFO - 'read-buildbot-config', 19:18:29 INFO - 'download-and-extract', 19:18:29 INFO - 'populate-webroot', 19:18:29 INFO - 'create-virtualenv', 19:18:29 INFO - 'install', 19:18:29 INFO - 'run-tests'), 19:18:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:18:29 INFO - 'download_minidump_stackwalk': True, 19:18:29 INFO - 'download_symbols': 'ondemand', 19:18:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:18:29 INFO - 'tooltool.py': '/tools/tooltool.py', 19:18:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:18:29 INFO - '/tools/misc-python/virtualenv.py')}, 19:18:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:18:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:18:29 INFO - 'installer_path': 'installer.exe', 19:18:29 INFO - 'log_level': 'info', 19:18:29 INFO - 'log_name': 'talos', 19:18:29 INFO - 'log_to_console': True, 19:18:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:18:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:18:29 INFO - 'opt_config_files': (), 19:18:29 INFO - 'pip_index': False, 19:18:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:18:29 INFO - 'cmd': ('bash', 19:18:29 INFO - '-c', 19:18:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:18:29 INFO - 'enabled': True, 19:18:29 INFO - 'halt_on_failure': False, 19:18:29 INFO - 'name': 'check_screen_resolution'},), 19:18:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:18:29 INFO - 'cmd': ('bash', 19:18:29 INFO - '-c', 19:18:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:18:29 INFO - 'enabled': True, 19:18:29 INFO - 'halt_on_failure': False, 19:18:29 INFO - 'name': 'check_screen_resolution'},), 19:18:29 INFO - 'run_cmd_checks_enabled': True, 19:18:29 INFO - 'sps_profile': False, 19:18:29 INFO - 'sps_profile_interval': 0, 19:18:29 INFO - 'suite': 'dromaeojs', 19:18:29 INFO - 'system_bits': '32', 19:18:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:18:29 INFO - 'title': 't-yosemite-r7-0313', 19:18:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:18:29 INFO - 'use_talos_json': True, 19:18:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:18:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:18:29 INFO - 'work_dir': 'build'} 19:18:29 INFO - [mozharness: 2016-09-02 02:18:29.685794Z] Running clobber step. 19:18:29 INFO - Running pre-action listener: _resource_record_pre_action 19:18:29 INFO - Running main action method: clobber 19:18:29 INFO - rmtree: /builds/slave/test/build 19:18:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:18:31 INFO - Running post-action listener: _resource_record_post_action 19:18:31 INFO - [mozharness: 2016-09-02 02:18:31.068684Z] Finished clobber step (success) 19:18:31 INFO - [mozharness: 2016-09-02 02:18:31.068846Z] Running read-buildbot-config step. 19:18:31 INFO - Running pre-action listener: _resource_record_pre_action 19:18:31 INFO - Running main action method: read_buildbot_config 19:18:31 INFO - Using buildbot properties: 19:18:31 INFO - { 19:18:31 INFO - "project": "", 19:18:31 INFO - "product": "firefox", 19:18:31 INFO - "script_repo_revision": "production", 19:18:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:18:31 INFO - "repository": "", 19:18:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:18:31 INFO - "buildid": "20160901170142", 19:18:31 INFO - "pgo_build": "False", 19:18:31 INFO - "basedir": "/builds/slave/test", 19:18:31 INFO - "buildnumber": 37, 19:18:31 INFO - "slavename": "t-yosemite-r7-0313", 19:18:31 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 19:18:31 INFO - "platform": "yosemite_r7", 19:18:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:18:31 INFO - "branch": "mozilla-central", 19:18:31 INFO - "repo_path": "mozilla-central", 19:18:31 INFO - "revision": "4bee815089891447e819466885b771bf1db1f59a", 19:18:31 INFO - "stage_platform": "macosx64", 19:18:31 INFO - "builduid": "02cab5aab2a24838b41e2ab04ee0f466", 19:18:31 INFO - "slavebuilddir": "test" 19:18:31 INFO - } 19:18:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:18:31 INFO - Running post-action listener: _resource_record_post_action 19:18:31 INFO - [mozharness: 2016-09-02 02:18:31.072281Z] Finished read-buildbot-config step (success) 19:18:31 INFO - [mozharness: 2016-09-02 02:18:31.072410Z] Running download-and-extract step. 19:18:31 INFO - Running pre-action listener: _resource_record_pre_action 19:18:31 INFO - Running main action method: download_and_extract 19:18:31 INFO - mkdir: /builds/slave/test/build/tests 19:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:31 INFO - trying https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:18:31 INFO - Downloading https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:18:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 19:18:32 INFO - Downloaded 1199 bytes. 19:18:32 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:18:32 INFO - Using the following test package requirements: 19:18:32 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:18:32 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:32 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:18:32 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:32 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:18:32 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:32 INFO - u'jsshell-mac.zip'], 19:18:32 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:32 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:18:32 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:18:32 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:32 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:18:32 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:32 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:18:32 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:32 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:18:32 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:18:32 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:18:32 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:18:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:32 INFO - trying https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:18:32 INFO - Downloading https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:18:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:18:33 INFO - Downloaded 16399282 bytes. 19:18:33 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:18:40 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:40 INFO - trying https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:18:40 INFO - Downloading https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:18:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:18:41 INFO - Downloaded 16399282 bytes. 19:18:41 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:47 INFO - trying https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:18:47 INFO - Downloading https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:18:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:18:48 INFO - Downloaded 14413998 bytes. 19:18:48 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 19:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:49 INFO - trying https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:18:49 INFO - Downloading https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:18:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 19:18:51 INFO - Downloaded 111580786 bytes. 19:18:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:18:51 INFO - mkdir: /builds/slave/test/properties 19:18:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:18:51 INFO - Writing to file /builds/slave/test/properties/build_url 19:18:51 INFO - Contents: 19:18:51 INFO - build_url:https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:18:52 INFO - Running post-action listener: _resource_record_post_action 19:18:52 INFO - Running post-action listener: set_extra_try_arguments 19:18:52 INFO - [mozharness: 2016-09-02 02:18:52.893436Z] Finished download-and-extract step (success) 19:18:52 INFO - [mozharness: 2016-09-02 02:18:52.893560Z] Running populate-webroot step. 19:18:52 INFO - Running pre-action listener: _resource_record_pre_action 19:18:52 INFO - Running main action method: populate_webroot 19:18:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:18:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:18:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:18:52 INFO - '--tppagecycles', 19:18:52 INFO - '7'], 19:18:52 INFO - 'tests': ['tsvgm']}}, 19:18:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:18:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:18:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:18:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:18:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:18:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:18:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:52 INFO - 'talos_options': ['--disable-e10s'], 19:18:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:18:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:18:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:52 INFO - 'talos_options': ['--disable-e10s'], 19:18:52 INFO - 'tests': ['damp', 'tps']}, 19:18:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:52 INFO - 'tests': ['damp', 'tps']}, 19:18:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:18:52 INFO - 'tests': ['dromaeo_dom']}, 19:18:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:18:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:18:52 INFO - 'tests': ['basic_compositor_video']}, 19:18:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:18:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:18:52 INFO - 'tests': ['a11yr', 19:18:52 INFO - 'ts_paint', 19:18:52 INFO - 'tpaint', 19:18:52 INFO - 'sessionrestore', 19:18:52 INFO - 'sessionrestore_no_auto_restore', 19:18:52 INFO - 'tabpaint']}, 19:18:52 INFO - 'other-e10s': {'tests': ['a11yr', 19:18:52 INFO - 'ts_paint', 19:18:52 INFO - 'tpaint', 19:18:52 INFO - 'sessionrestore', 19:18:52 INFO - 'sessionrestore_no_auto_restore', 19:18:52 INFO - 'tabpaint']}, 19:18:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:18:52 INFO - 'ts_paint', 19:18:52 INFO - 'tpaint', 19:18:52 INFO - 'sessionrestore', 19:18:52 INFO - 'sessionrestore_no_auto_restore', 19:18:52 INFO - 'tabpaint']}, 19:18:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:18:52 INFO - 'ts_paint', 19:18:52 INFO - 'tpaint', 19:18:52 INFO - 'sessionrestore', 19:18:52 INFO - 'sessionrestore_no_auto_restore', 19:18:52 INFO - 'tabpaint']}, 19:18:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:18:52 INFO - 'tests': ['a11yr', 19:18:52 INFO - 'ts_paint', 19:18:52 INFO - 'tpaint', 19:18:52 INFO - 'sessionrestore', 19:18:52 INFO - 'sessionrestore_no_auto_restore', 19:18:52 INFO - 'tabpaint']}, 19:18:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:18:52 INFO - 'tests': ['a11yr', 19:18:52 INFO - 'ts_paint', 19:18:52 INFO - 'tpaint', 19:18:52 INFO - 'sessionrestore', 19:18:52 INFO - 'sessionrestore_no_auto_restore', 19:18:52 INFO - 'tabpaint']}, 19:18:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:18:52 INFO - 'tests': ['tsvgx', 19:18:52 INFO - 'tsvgr_opacity', 19:18:52 INFO - 'tart', 19:18:52 INFO - 'tscrollx', 19:18:52 INFO - 'cart']}, 19:18:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:18:52 INFO - 'tsvgr_opacity', 19:18:52 INFO - 'tart', 19:18:52 INFO - 'tscrollx', 19:18:52 INFO - 'cart']}, 19:18:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:52 INFO - 'talos_options': ['--disable-e10s'], 19:18:52 INFO - 'tests': ['tp5o']}, 19:18:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:52 INFO - 'tests': ['tp5o']}, 19:18:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:52 INFO - 'talos_options': ['--disable-e10s', 19:18:52 INFO - '--xperf_path', 19:18:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:18:52 INFO - 'tests': ['tp5n']}, 19:18:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:18:52 INFO - 'talos_options': ['--xperf_path', 19:18:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:18:52 INFO - 'tests': ['tp5n']}}, 19:18:52 INFO - 'talos.zip': {'path': '', 19:18:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:18:52 INFO - Running post-action listener: _resource_record_post_action 19:18:52 INFO - [mozharness: 2016-09-02 02:18:52.913413Z] Finished populate-webroot step (success) 19:18:52 INFO - [mozharness: 2016-09-02 02:18:52.913527Z] Running create-virtualenv step. 19:18:52 INFO - Running pre-action listener: _resource_record_pre_action 19:18:52 INFO - Running main action method: create_virtualenv 19:18:52 INFO - Creating virtualenv /builds/slave/test/build/venv 19:18:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:18:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:18:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:18:53 INFO - Using real prefix '/tools/python27' 19:18:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:18:53 INFO - Installing distribute.............................................................................................................................................................................................done. 19:18:56 INFO - Installing pip.................done. 19:18:56 INFO - Return code: 0 19:18:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:56 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 0x106d88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e1d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5904cf930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cecd30>, '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 0x106a51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce4b20>, '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.jGDMzBWDEH/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.UIGYNjsmHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGDMzBWDEH/Render', 19:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:18:56 INFO - 'HOME': '/Users/cltbld', 19:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:18:56 INFO - 'LOGNAME': 'cltbld', 19:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:56 INFO - 'MOZ_NO_REMOTE': '1', 19:18:56 INFO - 'NO_EM_RESTART': '1', 19:18:56 INFO - 'PAGER': '/bin/cat', 19:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:56 INFO - 'PWD': '/builds/slave/test', 19:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:18:56 INFO - 'SHELL': '/bin/bash', 19:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UIGYNjsmHu/Listeners', 19:18:56 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 19:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:18:56 INFO - 'USER': 'cltbld', 19:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:56 INFO - 'XPC_FLAGS': '0x0', 19:18:56 INFO - 'XPC_SERVICE_NAME': '0', 19:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:18:56 INFO - Cleaning up... 19:18:56 INFO - Return code: 0 19:18:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:18:56 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 0x106d88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e1d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5904cf930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cecd30>, '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 0x106a51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce4b20>, '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.jGDMzBWDEH/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.UIGYNjsmHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGDMzBWDEH/Render', 19:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:18:56 INFO - 'HOME': '/Users/cltbld', 19:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:18:56 INFO - 'LOGNAME': 'cltbld', 19:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:18:56 INFO - 'MOZ_NO_REMOTE': '1', 19:18:56 INFO - 'NO_EM_RESTART': '1', 19:18:56 INFO - 'PAGER': '/bin/cat', 19:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:18:56 INFO - 'PWD': '/builds/slave/test', 19:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:18:56 INFO - 'SHELL': '/bin/bash', 19:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UIGYNjsmHu/Listeners', 19:18:56 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 19:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:18:56 INFO - 'USER': 'cltbld', 19:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:18:56 INFO - 'XPC_FLAGS': '0x0', 19:18:56 INFO - 'XPC_SERVICE_NAME': '0', 19:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:18:57 INFO - Downloading/unpacking psutil>=3.1.1 19:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:18:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:18:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:19:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:19:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:19:01 INFO - Installing collected packages: psutil 19:19:01 INFO - Running setup.py install for psutil 19:19:01 INFO - building 'psutil._psutil_osx' extension 19:19:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:19:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:19:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:19:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:19:02 INFO - building 'psutil._psutil_posix' extension 19:19:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 19:19:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:19:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:19:02 INFO - ^ 19:19:02 INFO - 1 warning generated. 19:19:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 19:19:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:19:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:19:02 INFO - Successfully installed psutil 19:19:02 INFO - Cleaning up... 19:19:02 INFO - Return code: 0 19:19:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:02 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 0x106d88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e1d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5904cf930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cecd30>, '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 0x106a51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce4b20>, '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.jGDMzBWDEH/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.UIGYNjsmHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:19:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:19:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGDMzBWDEH/Render', 19:19:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:02 INFO - 'HOME': '/Users/cltbld', 19:19:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:02 INFO - 'LOGNAME': 'cltbld', 19:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:02 INFO - 'MOZ_NO_REMOTE': '1', 19:19:02 INFO - 'NO_EM_RESTART': '1', 19:19:02 INFO - 'PAGER': '/bin/cat', 19:19:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:02 INFO - 'PWD': '/builds/slave/test', 19:19:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:02 INFO - 'SHELL': '/bin/bash', 19:19:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UIGYNjsmHu/Listeners', 19:19:02 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 19:19:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:02 INFO - 'USER': 'cltbld', 19:19:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:02 INFO - 'XPC_FLAGS': '0x0', 19:19:02 INFO - 'XPC_SERVICE_NAME': '0', 19:19:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:19:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:19:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:19:05 INFO - Installing collected packages: mozsystemmonitor 19:19:05 INFO - Running setup.py install for mozsystemmonitor 19:19:05 INFO - Successfully installed mozsystemmonitor 19:19:05 INFO - Cleaning up... 19:19:05 INFO - Return code: 0 19:19:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:05 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 0x106d88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e1d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5904cf930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cecd30>, '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 0x106a51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce4b20>, '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.jGDMzBWDEH/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.UIGYNjsmHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:19:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:19:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGDMzBWDEH/Render', 19:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:05 INFO - 'HOME': '/Users/cltbld', 19:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:05 INFO - 'LOGNAME': 'cltbld', 19:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:05 INFO - 'MOZ_NO_REMOTE': '1', 19:19:05 INFO - 'NO_EM_RESTART': '1', 19:19:05 INFO - 'PAGER': '/bin/cat', 19:19:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:05 INFO - 'PWD': '/builds/slave/test', 19:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:05 INFO - 'SHELL': '/bin/bash', 19:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UIGYNjsmHu/Listeners', 19:19:05 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 19:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:05 INFO - 'USER': 'cltbld', 19:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:05 INFO - 'XPC_FLAGS': '0x0', 19:19:05 INFO - 'XPC_SERVICE_NAME': '0', 19:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:05 INFO - Downloading/unpacking jsonschema==2.5.1 19:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:19:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:08 INFO - Downloading functools32-3.2.3-2.tar.gz 19:19:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:19:08 INFO - warning: no files found matching '*.txt' 19:19:08 INFO - no previously-included directories found matching 'build' 19:19:08 INFO - no previously-included directories found matching 'dist' 19:19:08 INFO - no previously-included directories found matching '.git*' 19:19:08 INFO - Installing collected packages: jsonschema, functools32 19:19:08 INFO - Running setup.py install for functools32 19:19:08 INFO - warning: no files found matching '*.txt' 19:19:08 INFO - no previously-included directories found matching 'build' 19:19:08 INFO - no previously-included directories found matching 'dist' 19:19:08 INFO - no previously-included directories found matching '.git*' 19:19:08 INFO - Successfully installed jsonschema functools32 19:19:08 INFO - Cleaning up... 19:19:08 INFO - Return code: 0 19:19:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106d88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e1d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5904cf930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cecd30>, '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 0x106a51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce4b20>, '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.jGDMzBWDEH/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.UIGYNjsmHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGDMzBWDEH/Render', 19:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:08 INFO - 'HOME': '/Users/cltbld', 19:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:08 INFO - 'LOGNAME': 'cltbld', 19:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:08 INFO - 'MOZ_NO_REMOTE': '1', 19:19:08 INFO - 'NO_EM_RESTART': '1', 19:19:08 INFO - 'PAGER': '/bin/cat', 19:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:08 INFO - 'PWD': '/builds/slave/test', 19:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:08 INFO - 'SHELL': '/bin/bash', 19:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UIGYNjsmHu/Listeners', 19:19:08 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 19:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:08 INFO - 'USER': 'cltbld', 19:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:08 INFO - 'XPC_FLAGS': '0x0', 19:19:08 INFO - 'XPC_SERVICE_NAME': '0', 19:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:19:09 INFO - Cleaning up... 19:19:09 INFO - Return code: 0 19:19:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:09 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 0x106d88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e1d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5904cf930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cecd30>, '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 0x106a51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce4b20>, '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.jGDMzBWDEH/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.UIGYNjsmHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:19:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:19:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGDMzBWDEH/Render', 19:19:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:09 INFO - 'HOME': '/Users/cltbld', 19:19:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:09 INFO - 'LOGNAME': 'cltbld', 19:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:09 INFO - 'MOZ_NO_REMOTE': '1', 19:19:09 INFO - 'NO_EM_RESTART': '1', 19:19:09 INFO - 'PAGER': '/bin/cat', 19:19:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:09 INFO - 'PWD': '/builds/slave/test', 19:19:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:09 INFO - 'SHELL': '/bin/bash', 19:19:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UIGYNjsmHu/Listeners', 19:19:09 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 19:19:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:09 INFO - 'USER': 'cltbld', 19:19:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:09 INFO - 'XPC_FLAGS': '0x0', 19:19:09 INFO - 'XPC_SERVICE_NAME': '0', 19:19:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:09 INFO - Downloading/unpacking blobuploader==1.2.4 19:19: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 19:19: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 19:19: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 19:19: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 19:19: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 19:19: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 19:19:11 INFO - Downloading blobuploader-1.2.4.tar.gz 19:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:19:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:19:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:13 INFO - Downloading docopt-0.6.1.tar.gz 19:19:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:19:13 INFO - Installing collected packages: blobuploader, requests, docopt 19:19:13 INFO - Running setup.py install for blobuploader 19:19:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:19:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:19:13 INFO - Running setup.py install for requests 19:19:13 INFO - Running setup.py install for docopt 19:19:13 INFO - Successfully installed blobuploader requests docopt 19:19:13 INFO - Cleaning up... 19:19:14 INFO - Return code: 0 19:19:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:14 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 0x106d88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e1d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5904cf930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cecd30>, '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 0x106a51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce4b20>, '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.jGDMzBWDEH/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.UIGYNjsmHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:19:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:19:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:19:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGDMzBWDEH/Render', 19:19:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:14 INFO - 'HOME': '/Users/cltbld', 19:19:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:14 INFO - 'LOGNAME': 'cltbld', 19:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:14 INFO - 'MOZ_NO_REMOTE': '1', 19:19:14 INFO - 'NO_EM_RESTART': '1', 19:19:14 INFO - 'PAGER': '/bin/cat', 19:19:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:14 INFO - 'PWD': '/builds/slave/test', 19:19:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:14 INFO - 'SHELL': '/bin/bash', 19:19:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UIGYNjsmHu/Listeners', 19:19:14 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 19:19:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:14 INFO - 'USER': 'cltbld', 19:19:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:14 INFO - 'XPC_FLAGS': '0x0', 19:19:14 INFO - 'XPC_SERVICE_NAME': '0', 19:19:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:19:14 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-u2h3uA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:19:14 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-IPHCwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:19:14 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-AGjAQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:19:14 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-LwYiN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:19:14 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-VavSVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:19:14 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-RRBD7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:19:14 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-x933ON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:19:15 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-owKFd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:19:15 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-dSNggy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:19:15 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-HAJOCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:19:15 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-NjE1EW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:19:15 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-lUis7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:19:15 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-ZpwuWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:19:15 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-lBq7g1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:15 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-w1FO6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:19:15 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-KMQ9nf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:19:15 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-gcsR5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:19:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:19:16 INFO - Running setup.py install for manifestparser 19:19:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:19:16 INFO - Running setup.py install for mozcrash 19:19:16 INFO - Running setup.py install for mozdebug 19:19:16 INFO - Running setup.py install for mozdevice 19:19:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:19:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:19:16 INFO - Running setup.py install for mozfile 19:19:16 INFO - Running setup.py install for mozhttpd 19:19:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:19:16 INFO - Running setup.py install for mozinfo 19:19:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:19:17 INFO - Running setup.py install for mozInstall 19:19:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:19:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:19:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:19:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:19:17 INFO - Running setup.py install for mozleak 19:19:17 INFO - Running setup.py install for mozlog 19:19:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:19:17 INFO - Running setup.py install for moznetwork 19:19:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:19:17 INFO - Running setup.py install for mozprocess 19:19:17 INFO - Running setup.py install for mozprofile 19:19:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:19:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:19:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:19:18 INFO - Running setup.py install for mozrunner 19:19:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:19:18 INFO - Running setup.py install for mozscreenshot 19:19:18 INFO - Running setup.py install for moztest 19:19:18 INFO - Running setup.py install for mozversion 19:19:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:19:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:19:18 INFO - Cleaning up... 19:19:18 INFO - Return code: 0 19:19:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:18 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 0x106d88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e1d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5904cf930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cecd30>, '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 0x106a51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce4b20>, '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.jGDMzBWDEH/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.UIGYNjsmHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:19:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:19:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGDMzBWDEH/Render', 19:19:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:18 INFO - 'HOME': '/Users/cltbld', 19:19:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:18 INFO - 'LOGNAME': 'cltbld', 19:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:18 INFO - 'MOZ_NO_REMOTE': '1', 19:19:18 INFO - 'NO_EM_RESTART': '1', 19:19:18 INFO - 'PAGER': '/bin/cat', 19:19:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:18 INFO - 'PWD': '/builds/slave/test', 19:19:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:18 INFO - 'SHELL': '/bin/bash', 19:19:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UIGYNjsmHu/Listeners', 19:19:18 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 19:19:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:18 INFO - 'USER': 'cltbld', 19:19:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:18 INFO - 'XPC_FLAGS': '0x0', 19:19:18 INFO - 'XPC_SERVICE_NAME': '0', 19:19:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:19:19 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-cr4av4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:19:19 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-UNYcTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:19:19 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-ctlfEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:19:19 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-RY6zVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:19:19 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-FLfDNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:19:19 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-X2ix4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:19:19 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-QpgkVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:19:20 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-bmSb8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:19:20 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-nxZa04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:19:20 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-QI2N8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:19:20 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-BLG2LG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:19:20 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-FwHC35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:19:20 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-1N_JkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:19:20 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-qQ32eC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:20 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-MBcjMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:19:20 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-QnepaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:19:20 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-vSWHXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:19:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:23 INFO - Downloading blessings-1.6.tar.gz 19:19:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:19:23 INFO - Installing collected packages: blessings 19:19:23 INFO - Running setup.py install for blessings 19:19:24 INFO - Successfully installed blessings 19:19:24 INFO - Cleaning up... 19:19:24 INFO - Return code: 0 19:19:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:19:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:19:24 INFO - Reading from file tmpfile_stdout 19:19:24 INFO - Current package versions: 19:19:24 INFO - blessings == 1.6 19:19:24 INFO - blobuploader == 1.2.4 19:19:24 INFO - docopt == 0.6.1 19:19:24 INFO - functools32 == 3.2.3-2 19:19:24 INFO - jsonschema == 2.5.1 19:19:24 INFO - manifestparser == 1.1 19:19:24 INFO - mozInstall == 1.12 19:19:24 INFO - mozcrash == 0.17 19:19:24 INFO - mozdebug == 0.1 19:19:24 INFO - mozdevice == 0.48 19:19:24 INFO - mozfile == 1.2 19:19:24 INFO - mozhttpd == 0.7 19:19:24 INFO - mozinfo == 0.9 19:19:24 INFO - mozleak == 0.1 19:19:24 INFO - mozlog == 3.3 19:19:24 INFO - moznetwork == 0.27 19:19:24 INFO - mozprocess == 0.23 19:19:24 INFO - mozprofile == 0.28 19:19:24 INFO - mozrunner == 6.12 19:19:24 INFO - mozscreenshot == 0.1 19:19:24 INFO - mozsystemmonitor == 0.3 19:19:24 INFO - moztest == 0.7 19:19:24 INFO - mozversion == 1.4 19:19:24 INFO - psutil == 3.1.1 19:19:24 INFO - requests == 1.2.3 19:19:24 INFO - wsgiref == 0.1.2 19:19:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:24 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 0x106d88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e1d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5904cf930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cecd30>, '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 0x106a51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce4b20>, '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.jGDMzBWDEH/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.UIGYNjsmHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:19:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:19:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGDMzBWDEH/Render', 19:19:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:24 INFO - 'HOME': '/Users/cltbld', 19:19:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:24 INFO - 'LOGNAME': 'cltbld', 19:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:24 INFO - 'MOZ_NO_REMOTE': '1', 19:19:24 INFO - 'NO_EM_RESTART': '1', 19:19:24 INFO - 'PAGER': '/bin/cat', 19:19:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:24 INFO - 'PWD': '/builds/slave/test', 19:19:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:24 INFO - 'SHELL': '/bin/bash', 19:19:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UIGYNjsmHu/Listeners', 19:19:24 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 19:19:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:24 INFO - 'USER': 'cltbld', 19:19:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:24 INFO - 'XPC_FLAGS': '0x0', 19:19:24 INFO - 'XPC_SERVICE_NAME': '0', 19:19:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:19:24 INFO - Cleaning up... 19:19:24 INFO - Return code: 0 19:19:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:24 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 0x106d88a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e1d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5904cf930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cecd30>, '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 0x106a51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce4b20>, '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.jGDMzBWDEH/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.UIGYNjsmHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:19:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:19:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGDMzBWDEH/Render', 19:19:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:19:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:19:24 INFO - 'HOME': '/Users/cltbld', 19:19:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:19:24 INFO - 'LOGNAME': 'cltbld', 19:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:24 INFO - 'MOZ_NO_REMOTE': '1', 19:19:24 INFO - 'NO_EM_RESTART': '1', 19:19:24 INFO - 'PAGER': '/bin/cat', 19:19:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:24 INFO - 'PWD': '/builds/slave/test', 19:19:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:19:24 INFO - 'SHELL': '/bin/bash', 19:19:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UIGYNjsmHu/Listeners', 19:19:24 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 19:19:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:19:24 INFO - 'USER': 'cltbld', 19:19:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:19:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:24 INFO - 'XPC_FLAGS': '0x0', 19:19:24 INFO - 'XPC_SERVICE_NAME': '0', 19:19:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:19:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:19:25 INFO - Cleaning up... 19:19:25 INFO - Return code: 0 19:19:25 INFO - Running post-action listener: _resource_record_post_action 19:19:25 INFO - Running post-action listener: _start_resource_monitoring 19:19:25 INFO - Starting resource monitoring. 19:19:25 INFO - [mozharness: 2016-09-02 02:19:25.074536Z] Finished create-virtualenv step (success) 19:19:25 INFO - [mozharness: 2016-09-02 02:19:25.075478Z] Running install step. 19:19:25 INFO - Running pre-action listener: _resource_record_pre_action 19:19:25 INFO - Running main action method: install 19:19:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:19:25 INFO - Reading from file tmpfile_stdout 19:19:25 INFO - Detecting whether we're running mozinstall >=1.0... 19:19:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:19:25 INFO - Reading from file tmpfile_stdout 19:19:25 INFO - Output received: 19:19:25 INFO - Usage: mozinstall [options] installer 19:19:25 INFO - Options: 19:19:25 INFO - -h, --help show this help message and exit 19:19:25 INFO - -d DEST, --destination=DEST 19:19:25 INFO - Directory to install application into. [default: 19:19:25 INFO - "/builds/slave/test"] 19:19:25 INFO - --app=APP Application being installed. [default: firefox] 19:19:25 INFO - mkdir: /builds/slave/test/build/application 19:19:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:19:59 INFO - Reading from file tmpfile_stdout 19:19:59 INFO - Output received: 19:19:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:19:59 INFO - Running post-action listener: _resource_record_post_action 19:19:59 INFO - [mozharness: 2016-09-02 02:19:59.825659Z] Finished install step (success) 19:19:59 INFO - [mozharness: 2016-09-02 02:19:59.825787Z] Running run-tests step. 19:19:59 INFO - Running pre-action listener: _resource_record_pre_action 19:19:59 INFO - Running main action method: run_tests 19:19:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:19:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:19:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:19:59 INFO - 2016-09-01 19:19:59.840 screenresolution[1947:11599] starting screenresolution argv=screenresolution get 19:19:59 INFO - 2016-09-01 19:19:59.842 screenresolution[1947:11599] Display 0: 1600x1200x32@60 19:19:59 INFO - 2016-09-01 19:19:59.848 screenresolution[1948:11601] starting screenresolution argv=screenresolution list 19:19:59 INFO - Available Modes on Display 0 19:19:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:19:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:19:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:19:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:20:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:20:00 INFO - Intel Iris: 19:20:00 INFO - Chipset Model: Intel Iris 19:20:00 INFO - Type: GPU 19:20:00 INFO - Bus: Built-In 19:20:00 INFO - VRAM (Dynamic, Max): 1536 MB 19:20:00 INFO - Vendor: Intel (0x8086) 19:20:00 INFO - Device ID: 0x0a2e 19:20:00 INFO - Revision ID: 0x0009 19:20:00 INFO - Displays: 19:20:00 INFO - Display: 19:20:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:20:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:20:00 INFO - Display Serial Number: 1600x1200 60 19:20:00 INFO - Main Display: Yes 19:20:00 INFO - Mirror: Off 19:20:00 INFO - Online: Yes 19:20:00 INFO - Rotation: Supported 19:20:00 INFO - Return code: 0 19:20:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:20:00 INFO - Python 2.7.3 19:20:00 INFO - Return code: 0 19:20:00 INFO - grabbing minidump binary from tooltool 19:20: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')]} 19:20: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 0x106cecd30>, '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 0x106a51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ce4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:20: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 19:20: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 19:20: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 19:20:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:20:00 INFO - Return code: 0 19:20:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:20:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:20:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:20:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:20:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:20:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0313', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0313 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:20:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGDMzBWDEH/Render', 19:20:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:20:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:20:00 INFO - 'HOME': '/Users/cltbld', 19:20:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:20:00 INFO - 'LOGNAME': 'cltbld', 19:20:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:20:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:20:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:20:00 INFO - 'MOZ_NO_REMOTE': '1', 19:20:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:20:00 INFO - 'NO_EM_RESTART': '1', 19:20:00 INFO - 'PAGER': '/bin/cat', 19:20:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:20:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:20:00 INFO - 'PWD': '/builds/slave/test', 19:20:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:20:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:20:00 INFO - 'SHELL': '/bin/bash', 19:20:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UIGYNjsmHu/Listeners', 19:20:00 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 19:20:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:20:00 INFO - 'USER': 'cltbld', 19:20:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:20:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:20:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:20:00 INFO - 'XPC_FLAGS': '0x0', 19:20:00 INFO - 'XPC_SERVICE_NAME': '0', 19:20:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:20:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0313', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:20:00 INFO - mozversion application_buildid: 20160901170142 19:20:00 INFO - mozversion application_changeset: 4bee815089891447e819466885b771bf1db1f59a 19:20:00 INFO - mozversion application_display_name: Nightly 19:20:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:20:00 INFO - mozversion application_name: Firefox 19:20:00 INFO - mozversion application_remotingname: firefox 19:20:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:20:00 INFO - mozversion application_vendor: Mozilla 19:20:00 INFO - mozversion application_version: 51.0a1 19:20:00 INFO - mozversion platform_buildid: 20160901170142 19:20:00 INFO - mozversion platform_changeset: 4bee815089891447e819466885b771bf1db1f59a 19:20:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:20:00 INFO - mozversion platform_version: 51.0a1 19:20:00 INFO - using testdate: 1472782800 19:20:00 INFO - actual date: 1472782800 19:20:00 INFO - starting webserver on 'localhost:50341' 19:20:00 INFO - SUITE-START | Running 2 tests 19:20:00 INFO - TEST-START | dromaeo_css 19:20:00 INFO - operating with platform_type : mac_ 19:20:00 INFO - Initialising browser for dromaeo_css test... 19:20:00 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmppSGm6d/profile http://localhost:50341/getInfo.html) 19:20:02 INFO - PROCESS | 1958 | __metrics Screen width/height:1600/1200 19:20:02 INFO - PROCESS | 1958 | colorDepth:24 19:20:02 INFO - PROCESS | 1958 | Browser inner width/height: 1024/689 19:20:02 INFO - PROCESS | 1958 | __metrics 19:20:02 INFO - PROCESS | 1958 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:20:02 INFO - PROCESS | 1958 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:20:03 INFO - TEST-INFO | 1958: exit 0 19:20:03 INFO - Browser initialized. 19:20:03 INFO - Running cycle 1/1 for dromaeo_css test... 19:20:03 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmppSGm6d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:20:15 INFO - PROCESS | 1960 | RSS: Main: 233955328 19:20:15 INFO - PROCESS | 1960 | 19:21:06 INFO - PROCESS | 1960 | 1472782866267 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:23:18 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:50341/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50341/tests/dromaeo/cssquery-ext.html) 19:23:20 INFO - PROCESS | 1960 | RSS: Main: 385658880 19:23:20 INFO - PROCESS | 1960 | 19:26:23 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:50341/tests/dromaeo/cssquery-ext.html (next: http://localhost:50341/tests/dromaeo/cssquery-jquery.html) 19:26:24 INFO - PROCESS | 1960 | RSS: Main: 376647680 19:26:24 INFO - PROCESS | 1960 | 19:29:26 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:50341/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50341/tests/dromaeo/cssquery-mootools.html) 19:29:27 INFO - PROCESS | 1960 | RSS: Main: 351830016 19:29:27 INFO - PROCESS | 1960 | 19:32:29 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:50341/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50341/tests/dromaeo/cssquery-prototype.html) 19:32:30 INFO - PROCESS | 1960 | RSS: Main: 343740416 19:32:30 INFO - PROCESS | 1960 | 19:35:33 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:50341/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50341/tests/dromaeo/cssquery-yui.html) 19:35:34 INFO - PROCESS | 1960 | RSS: Main: 327897088 19:35:34 INFO - PROCESS | 1960 | 19:38:37 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:50341/tests/dromaeo/cssquery-yui.html (next: http://localhost:50341/tests/dromaeo/cssquery-dojo.html) 19:38:37 INFO - PROCESS | 1960 | RSS: Main: 445038592 19:38:37 INFO - PROCESS | 1960 | 19:38:37 INFO - PROCESS | 1960 | __start_tp_report 19:38:37 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 19:38:37 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 19:38:37 INFO - PROCESS | 1960 | |i|pagename|runs| 19:38:37 INFO - PROCESS | 1960 | |0;dojo.html;3304.8678052877876;3320.833958302085;3338.966610333896;3380.729541636669;3395.4737015762566;4672.299155126729;5565.638233514819;5591.30108736408;5627.9718601406985;5653.265075540179;5642.4357564243655;5662.320521537421;5740.196372507852;5776.768929242836;5780.5664575156925;4761.90476190476;5073.213651883964;5100.183970564711;5183.663061900965;5209.583233341343;5301.310829592167;5304.681719096847;5313.73431328434;5337.279467271907;5338.412774594792;5122.154844450662;5183.740812959337;5235.162374020177;5250.921236181461;5293.602979776532;4291.570842915684;5202.479752024805;5214.843554693327;5218.60860435465;5219.164933610604;4517.20948833956;5139.331886854722;5156.896862062776;5202.661826981234;5267.920981185285;5574.9721251393485;5667.348254950651;5685.573581981326;5690.174924635946;5705.343885453143;6255.40573645503;6264.9686751566405;6273.12176295319;6276;6279.937200628026;5202.713850738246;6088.391160883921;6218.347073557302;6354.332795056545;6358.141650877119;5733.455321744427;5771;5773.88452230953;5815.680137592473;5839.065749480148;6230.657313847782;6305.432511074026;6347.55567110307;6358.982562789932;6366.6180029198395;6052.969735151296;6107.15847211733;6113.724882380304;6169.784057557964;6172.22847144107;6301.52738544611;6304.558680892385;6306.936930630727;6321.557490975588;6347.809565713036;5988.514357053683;6240.344763799826;6253.78111766086;6253.906191407133;6263.217097862767;5156.432792392834;6108.918862504554;6137.683438940261;6225.8132256032395;6271.9024170770845;4857.684250523705;4860.562549370574;4866.075445665312;4869.586085182799;4905.9264111038365;4387.078713470143;4410.448693913261;4432.667549933768;4434.733915965053;4503.189425903303;5622.57830662702;5641.717914104278;5712.714364281769;5712.714364281853;5771.624844385102;6296.008913294305;6336.049592561153;6346.397092276226;6351.872962540723;6371.52213583983;4790.233562630032;5690.061139911868;5701.743421546041;5714.885702285931;5767.798127065533;5223.399309079428;5383.327084114486;5389.865253368619;5429.131338985822;5472.91790623141;6227.19046523949;6347.682615869096;6359.300476947439;6363.395477429637;6366.172397588284;6340.841478963062;6354.3963323484195;6365.267994180728;6372.267189273108;6377.553571249968;6287.905681414874;6291.402316779899;6306.054091886071;6319.58922670025;6361;6207.627542347293;6228;6229.3770622937345;6230.06549017651;6253.936830738694;5609;6298.244210694563;6313.084602732486;6320.715567799368;6323.1463752394675;4322.740635561876;6022.45797878193;6079.756809727562;6080.878382432238;6133.977404287646;5064.5948324134715;6042.244719410074;6090.908636370546;6102.1762062122825;6127.6936153193055;5926.199963005111;5941.197938278277;5960.165576819336;5962.940370596239;5963.612365196248;5904.0553511437965;5965.105234214729;6026.0659597762415;6083.117947897618;6096.359882212304;5928.703564821655;5930.673812940329;5945.464908158287;5963.403659634002;5990.820275391745 19:38:37 INFO - PROCESS | 1960 | |1;ext.html;464.7676161919038;493.7136460852705;510.6604108268002;513.4813838023596;535.117056856187;30123.849380753094;39016.829495115155;40359.59640403599;40546.7972660137;42701.7864910675;41979.58020419795;42295.57704422955;42490.78754606222;42500.787496062556;42659.573404265946;9582.329236953416;9772.21822254218;9775.951120244388;9783.706488805346;9784.412935223874;8764.824703505927;8995.5052472114;8997.370184087084;9007.774805629846;9769.755756106084;9758;9831.852522212173;11550.595729149481;11954.581589644364;12000.939995300012;11932;12044.939775301154;12054.15620906537;12058.939705301505;12069.0344772418;11787;11848.466818993165;11858.11064170182;11865.82201266973;11872.347020913847;39568.80215598903;39693;39821;39829.60170398288;39900;2667.894194979004;3254.7070763631386;3527.2592755521373;3968.6626636735914;4107.486564179478;30019.849900750356;30532.389352213053;31027.689723102707;32046.198845028826;32101;3161;3324.1855745342305;3405.0465869556565;3422.5892892852767;3424.126847653857;449058;455127;461652;462446;463143;64811;65736;65852.670736646;65964.34035659677;66152.00771988423;68576.97134542986;68985;69006.0993900606;69008.6549567249;69041;16481.752773708406;16694.4156954506;16702.248398821837;16730.49808505747;16732.498025059267;12662.430190641444;12785.360731963365;12786.29675367845;12792.104552681223;12792.488300467878;16146.354464781352;17189.398371056952;17543.71931648903;19618;20002.799972000386;7853;7902.626214174416;8449.394615023126;8505.149485051568;8517.318614510825;856.8971723393232;862.1550880137501;920.9861852072224;965.8454647256406;982.2338575910801;28274.575881361798;29489.255372313844;29638.657728848717;29646.703532964824;29864.253393664898;1039.6880935719255;1074.8280275155937;1076.381080878498;1136.2898188632105;1165.7668466306773;14593.78109328361;15084.321205545777;15237.6190595236;15238.46665366729;15550.455734049254;600684;602906;603919;604833;605039;47703.28445073257;53499.19751203735;54818.725906370215;54830.177547336825;58143.12785308224;1365.8565850585744;1374.4502199120432;1407.06430223902;1408.619672688348;1411.7435482420858;29833.701662983094;30160.5475917857;30352.39295214039;30441.695583043886;30819.537706933967;47702.52297477119;48103.75948120237;48286.275705863736;48294.75852620855;48298;532870;533583;533935;535235;537084;38051.61948380592;38195;38516.61483385242;38552.80723596364;41111;44921.977983713754;45316.77341613271;45414;45961;46442.76778616085;37509.81245093757;37875.431868522544;38154.61845381622;39140.412893806046;39373.80313098416;37121.628783711814;37766.24467510689;37819.78100523213;38679;39160.80419597884;37204.813975929945;37391.81304093571;37999.430008550444;38078;38178.61821381862;31528;32510.18724531811;33610.159746006546;33713.157171069935;33827;24707.382315442257;24847.503049939263;25044.874775626005;25587.616185756855;25673.10144144946 19:38:37 INFO - PROCESS | 1960 | |2;jquery.html;295.4032853635657;358.08509258843645;359.7949168973685;372.7148731220616;393.4000649010259;14825.555233343004;17781.844180128297;20046.699299510517;20143.59712805743;20178.49553761157;15695.293711782968;18731.719024214646;19476.220951161973;19695.704564431544;20482.18071277147;5786.103154011135;6114.144019837227;6119.204503414572;6125.785597504088;6136.877262454748;5213.244079608455;6020.6688632125215;6113.23584551931;6115.724792384353;6125.111858780474;5755.136729490568;6034.275886893579;6074.088886666991;6082.422169893853;6097.420745029237;5906.497947674453;5994.190784244137;5995.970020149915;6008.218931538872;6013.75944962201;4976.477469865685;5089.821856235051;5194.844154675366;5195.68825870449;5203.635745497817;20944;24983;25304;25597.872010640014;26197;2040.653088974863;2429;2865.5701644753244;3106.968930310713;3208.0375887233968;9804.264680149017;10326.225533084973;10362;14510.97843981202;15091.622709432351;1632.8243664561496;1802.9188686509008;1844.6034102667857;1912.4645099372199;1962.2445358536922;92376;108047.99999999921;110845;110948.445257774;111470.88529114686;10981.945090274497;10986.560537578407;11099.5560177592;11112.277701949348;11270.323780573028;13955.232462214675;13991.720165596631;14006.649833754238;14009.929950350182;14020.439182432588;6491.318411566717;7654.73208437702;7779.416543759241;7839.451238413257;7851.254130857559;7350.375218106521;7355.374793142536;7389.076365454318;7487.775366739006;7490.812729681802;4150.667946564267;4452.065066336041;4470.329450582439;4524.457065152137;4528.479224889114;1850.3246315095162;1900.1829213438355;1903.6002439487586;1904.23830467814;1911.3788018893915;834.929031032369;876.237673224299;889.7597648634962;896.3841532797031;954.737447202025;16156.596085097966;19886.502837428892;20477.38567842967;20694.586108277752;20794;904.2720609908955;929.5909799688116;934.7756538430727;1019.7807471393686;1033.256055639938;10417.374957502574;10485.161187105014;10844.349339039683;10854.29447085937;10882.238243322892;564248;567000;572165;577190;580583;133500.33249833688;138648;139501.60498394887;140049;142590.5740942598;948.8861336639648;958.3435346787472;1003.1473247739364;1004.8593196952432;1022.4223313827812;19028.809711903257;19267.51831204175;19295.614087718448;19401.902990484956;19437.902810485855;52086.73956630193;52218.738906305225;52851.73574132105;52876.47123528716;53106;46667.766661167836;75492.62253688696;75715.62142189255;77196.61401692955;78390.6080469594;7754.612269386395;7966.880496792669;8017.594721583385;8130.065042519949;8135.599938561936;7071.186813516306;9680;10999.505022273857;12129.362536057519;12631.747365052464;10653.201009924442;10796.514156863117;10961.83557246626;11077.667669969915;11195.552177912792;8473.66105355779;10691.786164276826;10832.079273261861;10962.725931851448;10982.780344393228;10670.83993740079;11156.553737850396;11265.324080555192;11363.886361136283;11463.369514676773;7739.380849531912;7996.640151193326;8046.1149273578685;8101.068377136705;8224.62989165501;7478.551286922802;7907.604619768874;7946.483478573874;8326;8354.373421993254 19:38:37 INFO - PROCESS | 1960 | |3;mootools.html;1324.0268402724002;1486.7175236705025;1519.210010794387;1676.857467115295;1913.712943058542;7793.961030194855;8649.048604653482;8884.289256859436;8903.955480222598;9038.72883813485;8764.956175219131;8834.95582522088;8844.11558844117;8848.77878053049;8869.733907982754;1943.8542109341786;2373.6320870265095;2388.8566685998894;2396.113438027923;2407.57867373209;1960.255103060835;2078.6466300728835;2392.126873691099;2396.8921398537022;2397.1969390254285;2337.041812856729;2378.8810559472045;2381.0594815048044;2393.0547433763654;2401.075585899424;1939.6702560564668;2317.6407656813135;2326.26722582386;2326.336993956714;2329.94175145621;2325.732540757818;2342.3324352559607;2345.1674655497222;2345.6598793175067;2350.8001819845335;8829.0729473405;8861.335399845037;8862.911370886337;8924.776880577972;8963.507007114606;5331.253624492574;5442.564594832404;5454.836354909359;5471.699056551888;5473.233747275384;3714.739968202227;3735.9813200933822;3784.9810750946344;3812.80935953204;3819.2743378758223;931.8835145606799;957.4398976598698;1766.2846547148426;1834.7339635752742;1885.7737071551364;11400.486978086006;11715.824262636068;11727.472263748066;11818.704532386673;11868.643940681794;4560.33875088117;4647.0938167057775;4708.270218116198;4712.59942904857;4731.33761273422;9250.907490925005;9259.444433334022;9261.814763704688;9266.953665231631;9272.443653380684;5073.619478539124;5191.169412893919;5322.840314790563;5323.387810401853;5325.334333208349;4429.047754732684;4561.384213131251;4580.7938642761155;4963.57809586182;4965.1807451770055;3506.1409954561295;3610.837512311952;3720.813959302024;3879.282332768447;3881.7088718346236;1010.3079390617451;1123.7808627317595;1157.3345326437332;1164.9068074554184;1172.7595842852195;845.9111793261619;866.7919699272129;891.148952750122;905.7961958559272;932.6269492203173;5598;5749;5850.678212698343;5975.19334889793;6087.634741915411;948.7059011706393;951.4957072751453;960.655082883968;968.3850754770772;1008.7276435362411;3659.7621154624862;3825.2349530093534;3892.241013002494;3927.7250592458827;4034.152827906114;10329.9483502582;10591.84112238317;10868;11046.944765276283;11062.336259824437;9537.904620953841;10862.511186996604;11023.228374013903;11027.724306892393;11081.556737730562;7451.254874512505;7705.807354816175;7784.532928024337;7802.882956755653;7809.960950195326;5427.375851776938;5443.591730620298;5493.780248790164;5498.835034948958;5653.387364301891;8999.460032398078;9204.815903681754;9376.29677774156;9383.296252781205;9524.190443812353;9321.860172097287;9441.008694087297;9553.665621703207;9556.808863822824;9566.091221333962;7034.788956331318;7156.212816590275;7160.176579693402;7163.31948464895;7169.964150179216;8180.222878826502;8197.303229225583;8293.046299675374;8312.168783121639;8328.458650187718;7209.783706488814;7358;7457.440691948234;7549.584772837547;7699.769006929801;7275.963620181865;7324.96337518309;7336.706531738884;7338.853222935618;7365.631718414165;6951.270116637883;6959.443244540324;6980.441564674916;7045.26024767392;7062.929370706433;4345.369921361448;4432.268675668479;4919.532644398776;4942.505749425029;5031.270465782514;4924.778384972613;4949.727764972961;5074.492550744896;5077.720725360141;5152.76812543444 19:38:37 INFO - PROCESS | 1960 | |4;prototype.html;292.7584742587366;295.4770056999111;304.45502550434725;304.80340180583516;306.57386233135946;4441;4610.0034977264795;5051.686561494011;5248.501392367719;5350.565492700822;4418.602325790678;5072.695638261697;5146.073706732786;5228.372595288571;5242.318498595187;3286.720628746559;3517.5954765201946;3655.9580519551932;3886.6113388661047;4002.359622460407;3126.812391256532;3195.3130077033434;3398.762086653935;3997.2005598880196;4024.678025757947;3650.689691376236;3998.6401223889843;4019.879403617896;4025.335819589764;4050.5949405059555;3987.760734355948;3995.380715989026;4009.6190861868076;4021.819018144176;4040.9191816163807;3183.681631836821;3475.079104037432;3477.8608855645743;3486.3724529584665;3502.317048175607;4449.443819522559;4669.7898594562985;4816.95183048172;4904.5477261369315;5060.367454068241;4410.625096866738;4507.098580283956;4516.683832131752;4516.864494065183;4554.749488778116;5369.114096174127;5417.052015897202;5419.536725084245;5426.186072089179;5435.320584926884;3505.1938054247667;3680.1535646801117;3699.2601479704167;3714.7771133732062;3718.2749363874054;4896.534829191186;5201.869953251161;5644.8306550803545;5702.942970570241;5818.185454036438;3331.3670402623425;3624.9818750906074;3738.6448287412654;3755.9295600753653;3766.472693822867;3218.5815843940604;3281.409346317638;3652.9634703653155;3780.8996622769614;3818.694504439638;3596.3886139356373;4061.329880569732;4417.536158703354;4427.446569178853;4430.556944305544;5400.081986062379;5443.646162999393;5446.863828404322;5450.427705090988;5485.424030476823;1168.696139003865;1236.8003037054043;1273.6690158784093;1295.3976400973404;1299.2918859221747;753.5177486408703;849.2356878809121;877.8946526416743;879.6877108626474;890.7906641939139;4742.668013239109;5158.845234642967;5185.377754669388;5193.714345710947;5210.192420174879;5394.815099884553;5529.142982837667;5629.451900727251;5667.489925906688;5700.489370316869;4227.387028880795;4793;4985.9501404985485;5010.448850626428;5058.974705126451;2250.975806008262;2251.2570851618925;2288.5353373840753;2410.8915098820594;2499.712533058685;4108.322126849096;4814.470408255089;4841.8547443576745;4861.368022157168;4872.707637541759;3963.7027222958395;4228.716493093756;4409.823607055746;4792.466410748527;4830.975845120752;4118.979405102956;4762.4754086900475;4833.782479788418;4869.831240502254;4888.486708895586;4100.815463304218;4215.852445164404;4475.328700694922;4496.145732310982;4514.661400394918;3859.324618191749;3940.3695408734466;3985;4596.139243035866;4692.343071969859;3815.6756675682877;4322.1139666368945;4452.487963884065;4475.70907890986;4659.5573420525;2694.0166839103194;2846.1746093632614;3158.7730649891314;3210.502372132323;3244.7890887092262;3893.8053097344455;4136.44158038673;4230.15396920611;4605.766203391419;4701;4444.622207112432;4446.844360447369;4564.292534657134;4627.907441851212;4629.722216667011;3970.662493688069;4222.184460681936;4424.977875110604;4425;4431.224535706303;3815.523059617548;4431.8670439886855;4567.926537263674;5000.874978125576;5220.399654039834;4999.900002000012;5282.577393808563;5474.343078830566;5538.639988400902;5568.916466252928;5381.67709937405;5386.326709161355;5395.541378982831;5397.379301380392;5404.297441332602 19:38:37 INFO - PROCESS | 1960 | |5;yui.html;734.8383355661756;775.1592198845192;798.4730078148423;799.8520273749357;807.850547648685;15096.47162349318;15653.452129175452;15661.765073523891;15687.843121568781;15698.996755827262;14168;14177;14189.929050354734;14203.786943195835;14533.709325813477;669.4878418010226;686.3651122711472;826.5701835045772;830.4187069051673;850.4047166983106;824.8762685597148;837.6774941647479;838.8783626374172;840.932725381968;841.9938173882214;901.0313912544009;902.3908861518481;902.842002649537;903.905089965554;906.0305473143738;706.8126946359218;877.1360210192955;895.6059333893098;898.9685361012366;910.0308171797014;676.3608390071385;678.348785166241;678.4707927816311;678.7997540725498;679.1205389021229;14525.927370363186;14578.343974521173;14580.635484112876;14585.343659535347;14590.562283131523;13470.663233419145;13626;13700.177989320573;13832.510148173325;14142.434302627882;12506.624801255977;12617.242965422103;12668.936655316664;12969.54606588765;13179.209247445087;4487.910241795146;4520.3219517072375;4524.411826462571;4699.859004229845;4714.905701885978;107177;108378.91621083768;119803.80196198013;120004;122440;10885.455727213608;10889.292196007234;10911.83632245517;10955.287906286067;11330.660080197607;21389.893050534647;21578.028988695547;21588.568228635653;21680;21764.129434822746;7853.41099417546;7871;7892.644830982619;7939.801504962422;8106.63520141595;4317.093410383792;4321.978390108029;4418.337249412614;4458.286674132123;4554.772261386917;3013.4877070898;3383.4924761286006;3504.807235602015;3505.386557352453;3514.2268700885766;625.4277336237292;626.9905951410733;632.9778457753956;635.0664523150961;635.8664680417164;510.08185266520115;557.6849080269716;578.2136294639287;593.7506247376112;605.4631095709053;8785.662940287064;8822.955885220534;8938;8985.415947963362;9137.08629137081;541.9783208671608;583.7023118209744;627.1439485102893;629.8393909553034;645.4674893213117;4013.257547353749;4079.530853951774;4340.175366680321;4414.735115892992;4442.4669039715445;123365.38317308357;144430.2778486122;155272;155398;158357;91829.54085229665;112583;136394.3180284092;136622.26755464834;137518;117518;119951;120852.39573802074;121145;124368;8379.203975622306;8487.36344774132;8535.743927682179;8604.698835540976;8685;62446;63379;63446.68276658587;64558.607381040165;66768.33231667815;89357;97161.51419242859;97806.5329020051;99066;99882.5005874966;11183.608573699881;11365.48855301497;11418.31490110596;11485.885141148481;11696.35670038156;11670.241434306743;11795.705107372385;11886.940565297464;12088.939555302168;12186.695332616755;10896.455177241265;11686.298822070703;13560.660983475493;13660.453581857011;13801.240931748449;12905.03212259103;13197.502661794824;13523.256220907944;13531.729365412833;13571.457141714222;11737.647870563897;13078.411471483616;13143.60569182926;13278.336083195994;13870.791938121138;9031.593578288861;10950.890491094988;11315.773684526099;11560.94219528897;11665.766684666089;10948.507317170906;11374.501892930983;11439.084873209957;11451.770964580828;11600.129990250596 19:38:37 INFO - PROCESS | 1960 | __end_tp_report 19:38:37 INFO - PROCESS | 1960 | __start_cc_report 19:38:37 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,1539 19:38:37 INFO - PROCESS | 1960 | __end_cc_report 19:38:37 INFO - PROCESS | 1960 | __startTimestamp1472783917779__endTimestamp 19:38:37 INFO - PROCESS | 1960 | 19:38:37 INFO - PROCESS | 1960 | ------- Summary: start ------- 19:38:37 INFO - PROCESS | 1960 | Number of tests: 6 19:38:37 INFO - PROCESS | 1960 | 19:38:37 INFO - PROCESS | 1960 | [#0] dojo.html Cycles:165 Average:5699.35 Median:5928.70 stddev:686.53 (11.6%) stddev-sans-first:662.44 19:38:37 INFO - PROCESS | 1960 | Values: 3304.9 3320.8 3339.0 3380.7 3395.5 4672.3 5565.6 5591.3 5628.0 5653.3 5642.4 5662.3 5740.2 5776.8 5780.6 4761.9 5073.2 5100.2 5183.7 5209.6 5301.3 5304.7 5313.7 5337.3 5338.4 5122.2 5183.7 5235.2 5250.9 5293.6 4291.6 5202.5 5214.8 5218.6 5219.2 4517.2 5139.3 5156.9 5202.7 5267.9 5575.0 5667.3 5685.6 5690.2 5705.3 6255.4 6265.0 6273.1 6276.0 6279.9 5202.7 6088.4 6218.3 6354.3 6358.1 5733.5 5771.0 5773.9 5815.7 5839.1 6230.7 6305.4 6347.6 6359.0 6366.6 6053.0 6107.2 6113.7 6169.8 6172.2 6301.5 6304.6 6306.9 6321.6 6347.8 5988.5 6240.3 6253.8 6253.9 6263.2 5156.4 6108.9 6137.7 6225.8 6271.9 4857.7 4860.6 4866.1 4869.6 4905.9 4387.1 4410.4 4432.7 4434.7 4503.2 5622.6 5641.7 5712.7 5712.7 5771.6 6296.0 6336.0 6346.4 6351.9 6371.5 4790.2 5690.1 5701.7 5714.9 5767.8 5223.4 5383.3 5389.9 5429.1 5472.9 6227.2 6347.7 6359.3 6363.4 6366.2 6340.8 6354.4 6365.3 6372.3 6377.6 6287.9 6291.4 6306.1 6319.6 6361.0 6207.6 6228.0 6229.4 6230.1 6253.9 5609.0 6298.2 6313.1 6320.7 6323.1 4322.7 6022.5 6079.8 6080.9 6134.0 5064.6 6042.2 6090.9 6102.2 6127.7 5926.2 5941.2 5960.2 5962.9 5963.6 5904.1 5965.1 6026.1 6083.1 6096.4 5928.7 5930.7 5945.5 5963.4 5990.8 19:38:37 INFO - PROCESS | 1960 | 19:38:37 INFO - PROCESS | 1960 | [#1] ext.html Cycles:180 Average:67689.96 Median:38534.71 stddev:142632.15 (370.1%) stddev-sans-first:142942.96 19:38:37 INFO - PROCESS | 1960 | Values: 464.8 493.7 510.7 513.5 535.1 30123.8 39016.8 40359.6 40546.8 42701.8 41979.6 42295.6 42490.8 42500.8 42659.6 9582.3 9772.2 9776.0 9783.7 9784.4 8764.8 8995.5 8997.4 9007.8 9769.8 9758.0 9831.9 11550.6 11954.6 12000.9 11932.0 12044.9 12054.2 12058.9 12069.0 11787.0 11848.5 11858.1 11865.8 11872.3 39568.8 39693.0 39821.0 39829.6 39900.0 2667.9 3254.7 3527.3 3968.7 4107.5 30019.8 30532.4 31027.7 32046.2 32101.0 3161.0 3324.2 3405.0 3422.6 3424.1 449058.0 455127.0 461652.0 462446.0 463143.0 64811.0 65736.0 65852.7 65964.3 66152.0 68577.0 68985.0 69006.1 69008.7 69041.0 16481.8 16694.4 16702.2 16730.5 16732.5 12662.4 12785.4 12786.3 12792.1 12792.5 16146.4 17189.4 17543.7 19618.0 20002.8 7853.0 7902.6 8449.4 8505.1 8517.3 856.9 862.2 921.0 965.8 982.2 28274.6 29489.3 29638.7 29646.7 29864.3 1039.7 1074.8 1076.4 1136.3 1165.8 14593.8 15084.3 15237.6 15238.5 15550.5 600684.0 602906.0 603919.0 604833.0 605039.0 47703.3 53499.2 54818.7 54830.2 58143.1 1365.9 1374.5 1407.1 1408.6 1411.7 29833.7 30160.5 30352.4 30441.7 30819.5 47702.5 48103.8 48286.3 48294.8 48298.0 532870.0 533583.0 533935.0 535235.0 537084.0 38051.6 38195.0 38516.6 38552.8 41111.0 44922.0 45316.8 45414.0 45961.0 46442.8 37509.8 37875.4 38154.6 39140.4 39373.8 37121.6 37766.2 37819.8 38679.0 39160.8 37204.8 37391.8 37999.4 38078.0 38178.6 31528.0 32510.2 33610.2 33713.2 33827.0 24707.4 24847.5 25044.9 25587.6 25673.1 19:38:37 INFO - PROCESS | 1960 | 19:38:37 INFO - PROCESS | 1960 | [#2] jquery.html Cycles:180 Average:33750.35 Median:1783.06 stddev:95831.00 (5374.5%) stddev-sans-first:96066.91 19:38:37 INFO - PROCESS | 1960 | Values: 295.4 358.1 359.8 372.7 393.4 14825.6 17781.8 20046.7 20143.6 20178.5 15695.3 18731.7 19476.2 19695.7 20482.2 5786.1 6114.1 6119.2 6125.8 6136.9 5213.2 6020.7 6113.2 6115.7 6125.1 5755.1 6034.3 6074.1 6082.4 6097.4 5906.5 5994.2 5996.0 6008.2 6013.8 4976.5 5089.8 5194.8 5195.7 5203.6 20944.0 24983.0 25304.0 25597.9 26197.0 2040.7 2429.0 2865.6 3107.0 3208.0 9804.3 10326.2 10362.0 14511.0 15091.6 1632.8 1802.9 1844.6 1912.5 1962.2 92376.0 108048.0 110845.0 110948.4 111470.9 10981.9 10986.6 11099.6 11112.3 11270.3 13955.2 13991.7 14006.6 14009.9 14020.4 6491.3 7654.7 7779.4 7839.5 7851.3 7350.4 7355.4 7389.1 7487.8 7490.8 4150.7 4452.1 4470.3 4524.5 4528.5 1850.3 1900.2 1903.6 1904.2 1911.4 834.9 876.2 889.8 896.4 954.7 16156.6 19886.5 20477.4 20694.6 20794.0 904.3 929.6 934.8 1019.8 1033.3 10417.4 10485.2 10844.3 10854.3 10882.2 564248.0 567000.0 572165.0 577190.0 580583.0 133500.3 138648.0 139501.6 140049.0 142590.6 948.9 958.3 1003.1 1004.9 1022.4 19028.8 19267.5 19295.6 19401.9 19437.9 52086.7 52218.7 52851.7 52876.5 53106.0 46667.8 75492.6 75715.6 77196.6 78390.6 7754.6 7966.9 8017.6 8130.1 8135.6 7071.2 9680.0 10999.5 12129.4 12631.7 10653.2 10796.5 10961.8 11077.7 11195.6 8473.7 10691.8 10832.1 10962.7 10982.8 10670.8 11156.6 11265.3 11363.9 11463.4 7739.4 7996.6 8046.1 8101.1 8224.6 7478.6 7907.6 7946.5 8326.0 8354.4 19:38:37 INFO - PROCESS | 1960 | 19:38:37 INFO - PROCESS | 1960 | [#3] mootools.html Cycles:180 Average:5627.93 Median:5323.11 stddev:3129.31 (58.8%) stddev-sans-first:3121.37 19:38:37 INFO - PROCESS | 1960 | Values: 1324.0 1486.7 1519.2 1676.9 1913.7 7794.0 8649.0 8884.3 8904.0 9038.7 8765.0 8835.0 8844.1 8848.8 8869.7 1943.9 2373.6 2388.9 2396.1 2407.6 1960.3 2078.6 2392.1 2396.9 2397.2 2337.0 2378.9 2381.1 2393.1 2401.1 1939.7 2317.6 2326.3 2326.3 2329.9 2325.7 2342.3 2345.2 2345.7 2350.8 8829.1 8861.3 8862.9 8924.8 8963.5 5331.3 5442.6 5454.8 5471.7 5473.2 3714.7 3736.0 3785.0 3812.8 3819.3 931.9 957.4 1766.3 1834.7 1885.8 11400.5 11715.8 11727.5 11818.7 11868.6 4560.3 4647.1 4708.3 4712.6 4731.3 9250.9 9259.4 9261.8 9267.0 9272.4 5073.6 5191.2 5322.8 5323.4 5325.3 4429.0 4561.4 4580.8 4963.6 4965.2 3506.1 3610.8 3720.8 3879.3 3881.7 1010.3 1123.8 1157.3 1164.9 1172.8 845.9 866.8 891.1 905.8 932.6 5598.0 5749.0 5850.7 5975.2 6087.6 948.7 951.5 960.7 968.4 1008.7 3659.8 3825.2 3892.2 3927.7 4034.2 10329.9 10591.8 10868.0 11046.9 11062.3 9537.9 10862.5 11023.2 11027.7 11081.6 7451.3 7705.8 7784.5 7802.9 7810.0 5427.4 5443.6 5493.8 5498.8 5653.4 8999.5 9204.8 9376.3 9383.3 9524.2 9321.9 9441.0 9553.7 9556.8 9566.1 7034.8 7156.2 7160.2 7163.3 7170.0 8180.2 8197.3 8293.0 8312.2 8328.5 7209.8 7358.0 7457.4 7549.6 7699.8 7276.0 7325.0 7336.7 7338.9 7365.6 6951.3 6959.4 6980.4 7045.3 7062.9 4345.4 4432.3 4919.5 4942.5 5031.3 4924.8 4949.7 5074.5 5077.7 5152.8 19:38:37 INFO - PROCESS | 1960 | 19:38:37 INFO - PROCESS | 1960 | [#4] prototype.html Cycles:180 Average:4104.48 Median:4442.81 stddev:1262.37 (28.4%) stddev-sans-first:1233.07 19:38:37 INFO - PROCESS | 1960 | Values: 292.8 295.5 304.5 304.8 306.6 4441.0 4610.0 5051.7 5248.5 5350.6 4418.6 5072.7 5146.1 5228.4 5242.3 3286.7 3517.6 3656.0 3886.6 4002.4 3126.8 3195.3 3398.8 3997.2 4024.7 3650.7 3998.6 4019.9 4025.3 4050.6 3987.8 3995.4 4009.6 4021.8 4040.9 3183.7 3475.1 3477.9 3486.4 3502.3 4449.4 4669.8 4817.0 4904.5 5060.4 4410.6 4507.1 4516.7 4516.9 4554.7 5369.1 5417.1 5419.5 5426.2 5435.3 3505.2 3680.2 3699.3 3714.8 3718.3 4896.5 5201.9 5644.8 5702.9 5818.2 3331.4 3625.0 3738.6 3755.9 3766.5 3218.6 3281.4 3653.0 3780.9 3818.7 3596.4 4061.3 4417.5 4427.4 4430.6 5400.1 5443.6 5446.9 5450.4 5485.4 1168.7 1236.8 1273.7 1295.4 1299.3 753.5 849.2 877.9 879.7 890.8 4742.7 5158.8 5185.4 5193.7 5210.2 5394.8 5529.1 5629.5 5667.5 5700.5 4227.4 4793.0 4986.0 5010.4 5059.0 2251.0 2251.3 2288.5 2410.9 2499.7 4108.3 4814.5 4841.9 4861.4 4872.7 3963.7 4228.7 4409.8 4792.5 4831.0 4119.0 4762.5 4833.8 4869.8 4888.5 4100.8 4215.9 4475.3 4496.1 4514.7 3859.3 3940.4 3985.0 4596.1 4692.3 3815.7 4322.1 4452.5 4475.7 4659.6 2694.0 2846.2 3158.8 3210.5 3244.8 3893.8 4136.4 4230.2 4605.8 4701.0 4444.6 4446.8 4564.3 4627.9 4629.7 3970.7 4222.2 4425.0 4425.0 4431.2 3815.5 4431.9 4567.9 5000.9 5220.4 4999.9 5282.6 5474.3 5538.6 5568.9 5381.7 5386.3 5395.5 5397.4 5404.3 19:38:37 INFO - PROCESS | 1960 | 19:38:37 INFO - PROCESS | 1960 | [#5] yui.html Cycles:180 Average:25121.68 Median:3505.10 stddev:40595.53 (1158.2%) stddev-sans-first:40668.11 19:38:37 INFO - PROCESS | 1960 | Values: 734.8 775.2 798.5 799.9 807.9 15096.5 15653.5 15661.8 15687.8 15699.0 14168.0 14177.0 14189.9 14203.8 14533.7 669.5 686.4 826.6 830.4 850.4 824.9 837.7 838.9 840.9 842.0 901.0 902.4 902.8 903.9 906.0 706.8 877.1 895.6 899.0 910.0 676.4 678.3 678.5 678.8 679.1 14525.9 14578.3 14580.6 14585.3 14590.6 13470.7 13626.0 13700.2 13832.5 14142.4 12506.6 12617.2 12668.9 12969.5 13179.2 4487.9 4520.3 4524.4 4699.9 4714.9 107177.0 108378.9 119803.8 120004.0 122440.0 10885.5 10889.3 10911.8 10955.3 11330.7 21389.9 21578.0 21588.6 21680.0 21764.1 7853.4 7871.0 7892.6 7939.8 8106.6 4317.1 4322.0 4418.3 4458.3 4554.8 3013.5 3383.5 3504.8 3505.4 3514.2 625.4 627.0 633.0 635.1 635.9 510.1 557.7 578.2 593.8 605.5 8785.7 8823.0 8938.0 8985.4 9137.1 542.0 583.7 627.1 629.8 645.5 4013.3 4079.5 4340.2 4414.7 4442.5 123365.4 144430.3 155272.0 155398.0 158357.0 91829.5 112583.0 136394.3 136622.3 137518.0 117518.0 119951.0 120852.4 121145.0 124368.0 8379.2 8487.4 8535.7 8604.7 8685.0 62446.0 63379.0 63446.7 64558.6 66768.3 89357.0 97161.5 97806.5 99066.0 99882.5 11183.6 11365.5 11418.3 11485.9 11696.4 11670.2 11795.7 11886.9 12088.9 12186.7 10896.5 11686.3 13560.7 13660.5 13801.2 12905.0 13197.5 13523.3 13531.7 13571.5 11737.6 13078.4 13143.6 13278.3 13870.8 9031.6 10950.9 11315.8 11560.9 11665.8 10948.5 11374.5 11439.1 11451.8 11600.1 19:38:37 INFO - PROCESS | 1960 | -------- Summary: end -------- 19:38:37 INFO - PROCESS | 1960 | 19:38:38 INFO - TEST-INFO | 1960: exit 0 19:38:38 INFO - TEST-OK | dromaeo_css | took 1117970ms 19:38:38 INFO - TEST-START | kraken 19:38:38 INFO - operating with platform_type : mac_ 19:38:38 INFO - Initialising browser for kraken test... 19:38:38 INFO - TEST-INFO | started process 1965 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmpuWkuML/profile http://localhost:50341/getInfo.html) 19:38:40 INFO - PROCESS | 1965 | __metrics Screen width/height:1600/1200 19:38:40 INFO - PROCESS | 1965 | colorDepth:24 19:38:40 INFO - PROCESS | 1965 | Browser inner width/height: 1024/689 19:38:40 INFO - PROCESS | 1965 | __metrics 19:38:40 INFO - PROCESS | 1965 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:38:40 INFO - PROCESS | 1965 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:38:41 INFO - TEST-INFO | 1965: exit 0 19:38:41 INFO - Browser initialized. 19:38:41 INFO - Running cycle 1/1 for kraken test... 19:38:41 INFO - TEST-INFO | started process 1966 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmpuWkuML/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:38:53 INFO - PROCESS | 1966 | RSS: Main: 233971712 19:38:53 INFO - PROCESS | 1966 | 19:38:56 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:50341/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50341/tests/kraken/driver.html?testName=audio-beat-detection) 19:38:56 INFO - PROCESS | 1966 | RSS: Main: 293007360 19:38:56 INFO - PROCESS | 1966 | 19:38:59 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:50341/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50341/tests/kraken/driver.html?testName=audio-dft) 19:39:00 INFO - PROCESS | 1966 | RSS: Main: 288759808 19:39:00 INFO - PROCESS | 1966 | 19:39:03 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:50341/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50341/tests/kraken/driver.html?testName=audio-fft) 19:39:04 INFO - PROCESS | 1966 | RSS: Main: 375918592 19:39:04 INFO - PROCESS | 1966 | 19:39:06 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:50341/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50341/tests/kraken/driver.html?testName=audio-oscillator) 19:39:07 INFO - PROCESS | 1966 | RSS: Main: 280338432 19:39:07 INFO - PROCESS | 1966 | 19:39:09 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:50341/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50341/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:39:09 INFO - PROCESS | 1966 | RSS: Main: 283553792 19:39:09 INFO - PROCESS | 1966 | 19:39:12 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:50341/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50341/tests/kraken/driver.html?testName=imaging-darkroom) 19:39:13 INFO - PROCESS | 1966 | RSS: Main: 324071424 19:39:13 INFO - PROCESS | 1966 | 19:39:16 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:50341/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50341/tests/kraken/driver.html?testName=imaging-desaturate) 19:39:16 INFO - PROCESS | 1966 | RSS: Main: 324288512 19:39:16 INFO - PROCESS | 1966 | 19:39:19 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:50341/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50341/tests/kraken/driver.html?testName=json-parse-financial) 19:39:20 INFO - PROCESS | 1966 | RSS: Main: 322330624 19:39:20 INFO - PROCESS | 1966 | 19:39:22 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:50341/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50341/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:39:22 INFO - PROCESS | 1966 | RSS: Main: 298844160 19:39:22 INFO - PROCESS | 1966 | 19:39:24 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:50341/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50341/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:39:25 INFO - PROCESS | 1966 | RSS: Main: 303464448 19:39:25 INFO - PROCESS | 1966 | 19:39:27 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:50341/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50341/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:39:28 INFO - PROCESS | 1966 | RSS: Main: 304939008 19:39:28 INFO - PROCESS | 1966 | 19:39:31 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:50341/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50341/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:39:31 INFO - PROCESS | 1966 | RSS: Main: 321388544 19:39:31 INFO - PROCESS | 1966 | 19:39:34 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:50341/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50341/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:39:35 INFO - PROCESS | 1966 | RSS: Main: 317247488 19:39:35 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:50341/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50341/tests/kraken/driver.html?testName=ai-astar) 19:39:37 INFO - PROCESS | 1966 | RSS: Main: 360718336 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | __start_tp_report 19:39:37 INFO - PROCESS | 1966 | _x_x_mozilla_page_load 19:39:37 INFO - PROCESS | 1966 | _x_x_mozilla_page_load_details 19:39:37 INFO - PROCESS | 1966 | |i|pagename|runs| 19:39:37 INFO - PROCESS | 1966 | |0;ai-astar;113;114;117;114;113;113;114;114;112;122 19:39:37 INFO - PROCESS | 1966 | |1;audio-beat-detection;149;114;129;161;108;130;129;107;162;111 19:39:37 INFO - PROCESS | 1966 | |2;audio-dft;183;185;182;185;183;182;186;184;181;183 19:39:37 INFO - PROCESS | 1966 | |3;audio-fft;72;76;76;155;61;62;76;91;164;57 19:39:37 INFO - PROCESS | 1966 | |4;audio-oscillator;82;84;134;87;101;104;133;69;69;116 19:39:37 INFO - PROCESS | 1966 | |5;imaging-gaussian-blur;90;87;90;90;90;86;90;88;88;89 19:39:37 INFO - PROCESS | 1966 | |6;imaging-darkroom;94;95;95;95;117;150;94;101;149;134 19:39:37 INFO - PROCESS | 1966 | |7;imaging-desaturate;84;83;83;83;86;85;84;86;84;84 19:39:37 INFO - PROCESS | 1966 | |8;json-parse-financial;54;51;53;51;82;80;81;80;58;52 19:39:37 INFO - PROCESS | 1966 | |9;json-stringify-tinderbox;67;70;66;81;76;77;73;95;103;102 19:39:37 INFO - PROCESS | 1966 | |10;stanford-crypto-aes;89;86;86;84;93;103;86;88;90;90 19:39:37 INFO - PROCESS | 1966 | |11;stanford-crypto-ccm;139;138;144;153;150;145;147;130;174;153 19:39:37 INFO - PROCESS | 1966 | |12;stanford-crypto-pbkdf2;169;167;171;231;204;170;168;169;174;170 19:39:37 INFO - PROCESS | 1966 | |13;stanford-crypto-sha256-iterative;63;84;66;67;67;68;66;66;66;66 19:39:37 INFO - PROCESS | 1966 | __end_tp_report 19:39:37 INFO - PROCESS | 1966 | __start_cc_report 19:39:37 INFO - PROCESS | 1966 | _x_x_mozilla_cycle_collect,2051 19:39:37 INFO - PROCESS | 1966 | __end_cc_report 19:39:37 INFO - PROCESS | 1966 | __startTimestamp1472783977550__endTimestamp 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | ------- Summary: start ------- 19:39:37 INFO - PROCESS | 1966 | Number of tests: 14 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | [#0] ai-astar Cycles:10 Average:114.60 Median:114.00 stddev:2.91 (2.6%) stddev-sans-first:3.03 19:39:37 INFO - PROCESS | 1966 | Values: 113.0 114.0 117.0 114.0 113.0 113.0 114.0 114.0 112.0 122.0 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:129.50 stddev:21.02 (16.2%) stddev-sans-first:21.15 19:39:37 INFO - PROCESS | 1966 | Values: 149.0 114.0 129.0 161.0 108.0 130.0 129.0 107.0 162.0 111.0 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | [#2] audio-dft Cycles:10 Average:183.40 Median:183.50 stddev:1.58 (0.9%) stddev-sans-first:1.67 19:39:37 INFO - PROCESS | 1966 | Values: 183.0 185.0 182.0 185.0 183.0 182.0 186.0 184.0 181.0 183.0 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | [#3] audio-fft Cycles:10 Average:89.00 Median:74.00 stddev:38.47 (52.0%) stddev-sans-first:40.31 19:39:37 INFO - PROCESS | 1966 | Values: 72.0 76.0 76.0 155.0 61.0 62.0 76.0 91.0 164.0 57.0 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:69.00 stddev:23.91 (34.7%) stddev-sans-first:24.66 19:39:37 INFO - PROCESS | 1966 | Values: 82.0 84.0 134.0 87.0 101.0 104.0 133.0 69.0 69.0 116.0 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | [#5] imaging-gaussian-blur Cycles:10 Average:88.80 Median:90.00 stddev:1.48 (1.6%) stddev-sans-first:1.50 19:39:37 INFO - PROCESS | 1966 | Values: 90.0 87.0 90.0 90.0 90.0 86.0 90.0 88.0 88.0 89.0 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | [#6] imaging-darkroom Cycles:10 Average:112.40 Median:94.00 stddev:23.47 (25.0%) stddev-sans-first:23.93 19:39:37 INFO - PROCESS | 1966 | Values: 94.0 95.0 95.0 95.0 117.0 150.0 94.0 101.0 149.0 134.0 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.00 stddev:1.14 (1.4%) stddev-sans-first:1.20 19:39:37 INFO - PROCESS | 1966 | Values: 84.0 83.0 83.0 83.0 86.0 85.0 84.0 86.0 84.0 84.0 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:69.00 stddev:14.39 (20.9%) stddev-sans-first:14.78 19:39:37 INFO - PROCESS | 1966 | Values: 54.0 51.0 53.0 51.0 82.0 80.0 81.0 80.0 58.0 52.0 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | [#9] json-stringify-tinderbox Cycles:10 Average:81.00 Median:74.50 stddev:14.02 (18.8%) stddev-sans-first:13.92 19:39:37 INFO - PROCESS | 1966 | Values: 67.0 70.0 66.0 81.0 76.0 77.0 73.0 95.0 103.0 102.0 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:88.50 stddev:5.42 (6.1%) stddev-sans-first:5.75 19:39:37 INFO - PROCESS | 1966 | Values: 89.0 86.0 86.0 84.0 93.0 103.0 86.0 88.0 90.0 90.0 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:148.50 stddev:11.81 (8.0%) stddev-sans-first:12.14 19:39:37 INFO - PROCESS | 1966 | Values: 139.0 138.0 144.0 153.0 150.0 145.0 147.0 130.0 174.0 153.0 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.30 Median:170.50 stddev:21.20 (12.4%) stddev-sans-first:22.15 19:39:37 INFO - PROCESS | 1966 | Values: 169.0 167.0 171.0 231.0 204.0 170.0 168.0 169.0 174.0 170.0 19:39:37 INFO - PROCESS | 1966 | 19:39:37 INFO - PROCESS | 1966 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:66.50 stddev:5.80 (8.7%) stddev-sans-first:5.88 19:39:37 INFO - PROCESS | 1966 | Values: 63.0 84.0 66.0 67.0 67.0 68.0 66.0 66.0 66.0 66.0 19:39:37 INFO - PROCESS | 1966 | -------- Summary: end -------- 19:39:37 INFO - PROCESS | 1966 | 19:39:38 INFO - TEST-INFO | 1966: exit 0 19:39:38 INFO - TEST-OK | kraken | took 59990ms 19:39:38 INFO - SUITE-END | took 1177s 19:39:38 INFO - Completed test suite (00:19:38) 19:39:38 INFO - javascript benchmark 19:39:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3304.8678052877876, 3320.833958302085, 3338.966610333896, 3380.729541636669, 3395.4737015762566, 4672.299155126729, 5565.638233514819, 5591.30108736408, 5627.9718601406985, 5653.265075540179, 5642.4357564243655, 5662.320521537421, 5740.196372507852, 5776.768929242836, 5780.5664575156925, 4761.90476190476, 5073.213651883964, 5100.183970564711, 5183.663061900965, 5209.583233341343, 5301.310829592167, 5304.681719096847, 5313.73431328434, 5337.279467271907, 5338.412774594792, 5122.154844450662, 5183.740812959337, 5235.162374020177, 5250.921236181461, 5293.602979776532, 4291.570842915684, 5202.479752024805, 5214.843554693327, 5218.60860435465, 5219.164933610604, 4517.20948833956, 5139.331886854722, 5156.896862062776, 5202.661826981234, 5267.920981185285, 5574.9721251393485, 5667.348254950651, 5685.573581981326, 5690.174924635946, 5705.343885453143, 6255.40573645503, 6264.9686751566405, 6273.12176295319, 6276.0, 6279.937200628026, 5202.713850738246, 6088.391160883921, 6218.347073557302, 6354.332795056545, 6358.141650877119, 5733.455321744427, 5771.0, 5773.88452230953, 5815.680137592473, 5839.065749480148, 6230.657313847782, 6305.432511074026, 6347.55567110307, 6358.982562789932, 6366.6180029198395, 6052.969735151296, 6107.15847211733, 6113.724882380304, 6169.784057557964, 6172.22847144107, 6301.52738544611, 6304.558680892385, 6306.936930630727, 6321.557490975588, 6347.809565713036, 5988.514357053683, 6240.344763799826, 6253.78111766086, 6253.906191407133, 6263.217097862767, 5156.432792392834, 6108.918862504554, 6137.683438940261, 6225.8132256032395, 6271.9024170770845, 4857.684250523705, 4860.562549370574, 4866.075445665312, 4869.586085182799, 4905.9264111038365, 4387.078713470143, 4410.448693913261, 4432.667549933768, 4434.733915965053, 4503.189425903303, 5622.57830662702, 5641.717914104278, 5712.714364281769, 5712.714364281853, 5771.624844385102, 6296.008913294305, 6336.049592561153, 6346.397092276226, 6351.872962540723, 6371.52213583983, 4790.233562630032, 5690.061139911868, 5701.743421546041, 5714.885702285931, 5767.798127065533, 5223.399309079428, 5383.327084114486, 5389.865253368619, 5429.131338985822, 5472.91790623141, 6227.19046523949, 6347.682615869096, 6359.300476947439, 6363.395477429637, 6366.172397588284, 6340.841478963062, 6354.3963323484195, 6365.267994180728, 6372.267189273108, 6377.553571249968, 6287.905681414874, 6291.402316779899, 6306.054091886071, 6319.58922670025, 6361.0, 6207.627542347293, 6228.0, 6229.3770622937345, 6230.06549017651, 6253.936830738694, 5609.0, 6298.244210694563, 6313.084602732486, 6320.715567799368, 6323.1463752394675, 4322.740635561876, 6022.45797878193, 6079.756809727562, 6080.878382432238, 6133.977404287646, 5064.5948324134715, 6042.244719410074, 6090.908636370546, 6102.1762062122825, 6127.6936153193055, 5926.199963005111, 5941.197938278277, 5960.165576819336, 5962.940370596239, 5963.612365196248, 5904.0553511437965, 5965.105234214729, 6026.0659597762415, 6083.117947897618, 6096.359882212304, 5928.703564821655, 5930.673812940329, 5945.464908158287, 5963.403659634002, 5990.820275391745], "value": 5656.2931188166085, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.7676161919038, 493.7136460852705, 510.6604108268002, 513.4813838023596, 535.117056856187, 30123.849380753094, 39016.829495115155, 40359.59640403599, 40546.7972660137, 42701.7864910675, 41979.58020419795, 42295.57704422955, 42490.78754606222, 42500.787496062556, 42659.573404265946, 9582.329236953416, 9772.21822254218, 9775.951120244388, 9783.706488805346, 9784.412935223874, 8764.824703505927, 8995.5052472114, 8997.370184087084, 9007.774805629846, 9769.755756106084, 9758.0, 9831.852522212173, 11550.595729149481, 11954.581589644364, 12000.939995300012, 11932.0, 12044.939775301154, 12054.15620906537, 12058.939705301505, 12069.0344772418, 11787.0, 11848.466818993165, 11858.11064170182, 11865.82201266973, 11872.347020913847, 39568.80215598903, 39693.0, 39821.0, 39829.60170398288, 39900.0, 2667.894194979004, 3254.7070763631386, 3527.2592755521373, 3968.6626636735914, 4107.486564179478, 30019.849900750356, 30532.389352213053, 31027.689723102707, 32046.198845028826, 32101.0, 3161.0, 3324.1855745342305, 3405.0465869556565, 3422.5892892852767, 3424.126847653857, 449058.0, 455127.0, 461652.0, 462446.0, 463143.0, 64811.0, 65736.0, 65852.670736646, 65964.34035659677, 66152.00771988423, 68576.97134542986, 68985.0, 69006.0993900606, 69008.6549567249, 69041.0, 16481.752773708406, 16694.4156954506, 16702.248398821837, 16730.49808505747, 16732.498025059267, 12662.430190641444, 12785.360731963365, 12786.29675367845, 12792.104552681223, 12792.488300467878, 16146.354464781352, 17189.398371056952, 17543.71931648903, 19618.0, 20002.799972000386, 7853.0, 7902.626214174416, 8449.394615023126, 8505.149485051568, 8517.318614510825, 856.8971723393232, 862.1550880137501, 920.9861852072224, 965.8454647256406, 982.2338575910801, 28274.575881361798, 29489.255372313844, 29638.657728848717, 29646.703532964824, 29864.253393664898, 1039.6880935719255, 1074.8280275155937, 1076.381080878498, 1136.2898188632105, 1165.7668466306773, 14593.78109328361, 15084.321205545777, 15237.6190595236, 15238.46665366729, 15550.455734049254, 600684.0, 602906.0, 603919.0, 604833.0, 605039.0, 47703.28445073257, 53499.19751203735, 54818.725906370215, 54830.177547336825, 58143.12785308224, 1365.8565850585744, 1374.4502199120432, 1407.06430223902, 1408.619672688348, 1411.7435482420858, 29833.701662983094, 30160.5475917857, 30352.39295214039, 30441.695583043886, 30819.537706933967, 47702.52297477119, 48103.75948120237, 48286.275705863736, 48294.75852620855, 48298.0, 532870.0, 533583.0, 533935.0, 535235.0, 537084.0, 38051.61948380592, 38195.0, 38516.61483385242, 38552.80723596364, 41111.0, 44921.977983713754, 45316.77341613271, 45414.0, 45961.0, 46442.76778616085, 37509.81245093757, 37875.431868522544, 38154.61845381622, 39140.412893806046, 39373.80313098416, 37121.628783711814, 37766.24467510689, 37819.78100523213, 38679.0, 39160.80419597884, 37204.813975929945, 37391.81304093571, 37999.430008550444, 38078.0, 38178.61821381862, 31528.0, 32510.18724531811, 33610.159746006546, 33713.157171069935, 33827.0, 24707.382315442257, 24847.503049939263, 25044.874775626005, 25587.616185756855, 25673.10144144946], "value": 20408.817377409567, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.4032853635657, 358.08509258843645, 359.7949168973685, 372.7148731220616, 393.4000649010259, 14825.555233343004, 17781.844180128297, 20046.699299510517, 20143.59712805743, 20178.49553761157, 15695.293711782968, 18731.719024214646, 19476.220951161973, 19695.704564431544, 20482.18071277147, 5786.103154011135, 6114.144019837227, 6119.204503414572, 6125.785597504088, 6136.877262454748, 5213.244079608455, 6020.6688632125215, 6113.23584551931, 6115.724792384353, 6125.111858780474, 5755.136729490568, 6034.275886893579, 6074.088886666991, 6082.422169893853, 6097.420745029237, 5906.497947674453, 5994.190784244137, 5995.970020149915, 6008.218931538872, 6013.75944962201, 4976.477469865685, 5089.821856235051, 5194.844154675366, 5195.68825870449, 5203.635745497817, 20944.0, 24983.0, 25304.0, 25597.872010640014, 26197.0, 2040.653088974863, 2429.0, 2865.5701644753244, 3106.968930310713, 3208.0375887233968, 9804.264680149017, 10326.225533084973, 10362.0, 14510.97843981202, 15091.622709432351, 1632.8243664561496, 1802.9188686509008, 1844.6034102667857, 1912.4645099372199, 1962.2445358536922, 92376.0, 108047.99999999921, 110845.0, 110948.445257774, 111470.88529114686, 10981.945090274497, 10986.560537578407, 11099.5560177592, 11112.277701949348, 11270.323780573028, 13955.232462214675, 13991.720165596631, 14006.649833754238, 14009.929950350182, 14020.439182432588, 6491.318411566717, 7654.73208437702, 7779.416543759241, 7839.451238413257, 7851.254130857559, 7350.375218106521, 7355.374793142536, 7389.076365454318, 7487.775366739006, 7490.812729681802, 4150.667946564267, 4452.065066336041, 4470.329450582439, 4524.457065152137, 4528.479224889114, 1850.3246315095162, 1900.1829213438355, 1903.6002439487586, 1904.23830467814, 1911.3788018893915, 834.929031032369, 876.237673224299, 889.7597648634962, 896.3841532797031, 954.737447202025, 16156.596085097966, 19886.502837428892, 20477.38567842967, 20694.586108277752, 20794.0, 904.2720609908955, 929.5909799688116, 934.7756538430727, 1019.7807471393686, 1033.256055639938, 10417.374957502574, 10485.161187105014, 10844.349339039683, 10854.29447085937, 10882.238243322892, 564248.0, 567000.0, 572165.0, 577190.0, 580583.0, 133500.33249833688, 138648.0, 139501.60498394887, 140049.0, 142590.5740942598, 948.8861336639648, 958.3435346787472, 1003.1473247739364, 1004.8593196952432, 1022.4223313827812, 19028.809711903257, 19267.51831204175, 19295.614087718448, 19401.902990484956, 19437.902810485855, 52086.73956630193, 52218.738906305225, 52851.73574132105, 52876.47123528716, 53106.0, 46667.766661167836, 75492.62253688696, 75715.62142189255, 77196.61401692955, 78390.6080469594, 7754.612269386395, 7966.880496792669, 8017.594721583385, 8130.065042519949, 8135.599938561936, 7071.186813516306, 9680.0, 10999.505022273857, 12129.362536057519, 12631.747365052464, 10653.201009924442, 10796.514156863117, 10961.83557246626, 11077.667669969915, 11195.552177912792, 8473.66105355779, 10691.786164276826, 10832.079273261861, 10962.725931851448, 10982.780344393228, 10670.83993740079, 11156.553737850396, 11265.324080555192, 11363.886361136283, 11463.369514676773, 7739.380849531912, 7996.640151193326, 8046.1149273578685, 8101.068377136705, 8224.62989165501, 7478.551286922802, 7907.604619768874, 7946.483478573874, 8326.0, 8354.373421993254], "value": 9272.495154767666, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1324.0268402724002, 1486.7175236705025, 1519.210010794387, 1676.857467115295, 1913.712943058542, 7793.961030194855, 8649.048604653482, 8884.289256859436, 8903.955480222598, 9038.72883813485, 8764.956175219131, 8834.95582522088, 8844.11558844117, 8848.77878053049, 8869.733907982754, 1943.8542109341786, 2373.6320870265095, 2388.8566685998894, 2396.113438027923, 2407.57867373209, 1960.255103060835, 2078.6466300728835, 2392.126873691099, 2396.8921398537022, 2397.1969390254285, 2337.041812856729, 2378.8810559472045, 2381.0594815048044, 2393.0547433763654, 2401.075585899424, 1939.6702560564668, 2317.6407656813135, 2326.26722582386, 2326.336993956714, 2329.94175145621, 2325.732540757818, 2342.3324352559607, 2345.1674655497222, 2345.6598793175067, 2350.8001819845335, 8829.0729473405, 8861.335399845037, 8862.911370886337, 8924.776880577972, 8963.507007114606, 5331.253624492574, 5442.564594832404, 5454.836354909359, 5471.699056551888, 5473.233747275384, 3714.739968202227, 3735.9813200933822, 3784.9810750946344, 3812.80935953204, 3819.2743378758223, 931.8835145606799, 957.4398976598698, 1766.2846547148426, 1834.7339635752742, 1885.7737071551364, 11400.486978086006, 11715.824262636068, 11727.472263748066, 11818.704532386673, 11868.643940681794, 4560.33875088117, 4647.0938167057775, 4708.270218116198, 4712.59942904857, 4731.33761273422, 9250.907490925005, 9259.444433334022, 9261.814763704688, 9266.953665231631, 9272.443653380684, 5073.619478539124, 5191.169412893919, 5322.840314790563, 5323.387810401853, 5325.334333208349, 4429.047754732684, 4561.384213131251, 4580.7938642761155, 4963.57809586182, 4965.1807451770055, 3506.1409954561295, 3610.837512311952, 3720.813959302024, 3879.282332768447, 3881.7088718346236, 1010.3079390617451, 1123.7808627317595, 1157.3345326437332, 1164.9068074554184, 1172.7595842852195, 845.9111793261619, 866.7919699272129, 891.148952750122, 905.7961958559272, 932.6269492203173, 5598.0, 5749.0, 5850.678212698343, 5975.19334889793, 6087.634741915411, 948.7059011706393, 951.4957072751453, 960.655082883968, 968.3850754770772, 1008.7276435362411, 3659.7621154624862, 3825.2349530093534, 3892.241013002494, 3927.7250592458827, 4034.152827906114, 10329.9483502582, 10591.84112238317, 10868.0, 11046.944765276283, 11062.336259824437, 9537.904620953841, 10862.511186996604, 11023.228374013903, 11027.724306892393, 11081.556737730562, 7451.254874512505, 7705.807354816175, 7784.532928024337, 7802.882956755653, 7809.960950195326, 5427.375851776938, 5443.591730620298, 5493.780248790164, 5498.835034948958, 5653.387364301891, 8999.460032398078, 9204.815903681754, 9376.29677774156, 9383.296252781205, 9524.190443812353, 9321.860172097287, 9441.008694087297, 9553.665621703207, 9556.808863822824, 9566.091221333962, 7034.788956331318, 7156.212816590275, 7160.176579693402, 7163.31948464895, 7169.964150179216, 8180.222878826502, 8197.303229225583, 8293.046299675374, 8312.168783121639, 8328.458650187718, 7209.783706488814, 7358.0, 7457.440691948234, 7549.584772837547, 7699.769006929801, 7275.963620181865, 7324.96337518309, 7336.706531738884, 7338.853222935618, 7365.631718414165, 6951.270116637883, 6959.443244540324, 6980.441564674916, 7045.26024767392, 7062.929370706433, 4345.369921361448, 4432.268675668479, 4919.532644398776, 4942.505749425029, 5031.270465782514, 4924.778384972613, 4949.727764972961, 5074.492550744896, 5077.720725360141, 5152.76812543444], "value": 4552.960294937294, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.7584742587366, 295.4770056999111, 304.45502550434725, 304.80340180583516, 306.57386233135946, 4441.0, 4610.0034977264795, 5051.686561494011, 5248.501392367719, 5350.565492700822, 4418.602325790678, 5072.695638261697, 5146.073706732786, 5228.372595288571, 5242.318498595187, 3286.720628746559, 3517.5954765201946, 3655.9580519551932, 3886.6113388661047, 4002.359622460407, 3126.812391256532, 3195.3130077033434, 3398.762086653935, 3997.2005598880196, 4024.678025757947, 3650.689691376236, 3998.6401223889843, 4019.879403617896, 4025.335819589764, 4050.5949405059555, 3987.760734355948, 3995.380715989026, 4009.6190861868076, 4021.819018144176, 4040.9191816163807, 3183.681631836821, 3475.079104037432, 3477.8608855645743, 3486.3724529584665, 3502.317048175607, 4449.443819522559, 4669.7898594562985, 4816.95183048172, 4904.5477261369315, 5060.367454068241, 4410.625096866738, 4507.098580283956, 4516.683832131752, 4516.864494065183, 4554.749488778116, 5369.114096174127, 5417.052015897202, 5419.536725084245, 5426.186072089179, 5435.320584926884, 3505.1938054247667, 3680.1535646801117, 3699.2601479704167, 3714.7771133732062, 3718.2749363874054, 4896.534829191186, 5201.869953251161, 5644.8306550803545, 5702.942970570241, 5818.185454036438, 3331.3670402623425, 3624.9818750906074, 3738.6448287412654, 3755.9295600753653, 3766.472693822867, 3218.5815843940604, 3281.409346317638, 3652.9634703653155, 3780.8996622769614, 3818.694504439638, 3596.3886139356373, 4061.329880569732, 4417.536158703354, 4427.446569178853, 4430.556944305544, 5400.081986062379, 5443.646162999393, 5446.863828404322, 5450.427705090988, 5485.424030476823, 1168.696139003865, 1236.8003037054043, 1273.6690158784093, 1295.3976400973404, 1299.2918859221747, 753.5177486408703, 849.2356878809121, 877.8946526416743, 879.6877108626474, 890.7906641939139, 4742.668013239109, 5158.845234642967, 5185.377754669388, 5193.714345710947, 5210.192420174879, 5394.815099884553, 5529.142982837667, 5629.451900727251, 5667.489925906688, 5700.489370316869, 4227.387028880795, 4793.0, 4985.9501404985485, 5010.448850626428, 5058.974705126451, 2250.975806008262, 2251.2570851618925, 2288.5353373840753, 2410.8915098820594, 2499.712533058685, 4108.322126849096, 4814.470408255089, 4841.8547443576745, 4861.368022157168, 4872.707637541759, 3963.7027222958395, 4228.716493093756, 4409.823607055746, 4792.466410748527, 4830.975845120752, 4118.979405102956, 4762.4754086900475, 4833.782479788418, 4869.831240502254, 4888.486708895586, 4100.815463304218, 4215.852445164404, 4475.328700694922, 4496.145732310982, 4514.661400394918, 3859.324618191749, 3940.3695408734466, 3985.0, 4596.139243035866, 4692.343071969859, 3815.6756675682877, 4322.1139666368945, 4452.487963884065, 4475.70907890986, 4659.5573420525, 2694.0166839103194, 2846.1746093632614, 3158.7730649891314, 3210.502372132323, 3244.7890887092262, 3893.8053097344455, 4136.44158038673, 4230.15396920611, 4605.766203391419, 4701.0, 4444.622207112432, 4446.844360447369, 4564.292534657134, 4627.907441851212, 4629.722216667011, 3970.662493688069, 4222.184460681936, 4424.977875110604, 4425.0, 4431.224535706303, 3815.523059617548, 4431.8670439886855, 4567.926537263674, 5000.874978125576, 5220.399654039834, 4999.900002000012, 5282.577393808563, 5474.343078830566, 5538.639988400902, 5568.916466252928, 5381.67709937405, 5386.326709161355, 5395.541378982831, 5397.379301380392, 5404.297441332602], "value": 3715.4261912516536, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.8383355661756, 775.1592198845192, 798.4730078148423, 799.8520273749357, 807.850547648685, 15096.47162349318, 15653.452129175452, 15661.765073523891, 15687.843121568781, 15698.996755827262, 14168.0, 14177.0, 14189.929050354734, 14203.786943195835, 14533.709325813477, 669.4878418010226, 686.3651122711472, 826.5701835045772, 830.4187069051673, 850.4047166983106, 824.8762685597148, 837.6774941647479, 838.8783626374172, 840.932725381968, 841.9938173882214, 901.0313912544009, 902.3908861518481, 902.842002649537, 903.905089965554, 906.0305473143738, 706.8126946359218, 877.1360210192955, 895.6059333893098, 898.9685361012366, 910.0308171797014, 676.3608390071385, 678.348785166241, 678.4707927816311, 678.7997540725498, 679.1205389021229, 14525.927370363186, 14578.343974521173, 14580.635484112876, 14585.343659535347, 14590.562283131523, 13470.663233419145, 13626.0, 13700.177989320573, 13832.510148173325, 14142.434302627882, 12506.624801255977, 12617.242965422103, 12668.936655316664, 12969.54606588765, 13179.209247445087, 4487.910241795146, 4520.3219517072375, 4524.411826462571, 4699.859004229845, 4714.905701885978, 107177.0, 108378.91621083768, 119803.80196198013, 120004.0, 122440.0, 10885.455727213608, 10889.292196007234, 10911.83632245517, 10955.287906286067, 11330.660080197607, 21389.893050534647, 21578.028988695547, 21588.568228635653, 21680.0, 21764.129434822746, 7853.41099417546, 7871.0, 7892.644830982619, 7939.801504962422, 8106.63520141595, 4317.093410383792, 4321.978390108029, 4418.337249412614, 4458.286674132123, 4554.772261386917, 3013.4877070898, 3383.4924761286006, 3504.807235602015, 3505.386557352453, 3514.2268700885766, 625.4277336237292, 626.9905951410733, 632.9778457753956, 635.0664523150961, 635.8664680417164, 510.08185266520115, 557.6849080269716, 578.2136294639287, 593.7506247376112, 605.4631095709053, 8785.662940287064, 8822.955885220534, 8938.0, 8985.415947963362, 9137.08629137081, 541.9783208671608, 583.7023118209744, 627.1439485102893, 629.8393909553034, 645.4674893213117, 4013.257547353749, 4079.530853951774, 4340.175366680321, 4414.735115892992, 4442.4669039715445, 123365.38317308357, 144430.2778486122, 155272.0, 155398.0, 158357.0, 91829.54085229665, 112583.0, 136394.3180284092, 136622.26755464834, 137518.0, 117518.0, 119951.0, 120852.39573802074, 121145.0, 124368.0, 8379.203975622306, 8487.36344774132, 8535.743927682179, 8604.698835540976, 8685.0, 62446.0, 63379.0, 63446.68276658587, 64558.607381040165, 66768.33231667815, 89357.0, 97161.51419242859, 97806.5329020051, 99066.0, 99882.5005874966, 11183.608573699881, 11365.48855301497, 11418.31490110596, 11485.885141148481, 11696.35670038156, 11670.241434306743, 11795.705107372385, 11886.940565297464, 12088.939555302168, 12186.695332616755, 10896.455177241265, 11686.298822070703, 13560.660983475493, 13660.453581857011, 13801.240931748449, 12905.03212259103, 13197.502661794824, 13523.256220907944, 13531.729365412833, 13571.457141714222, 11737.647870563897, 13078.411471483616, 13143.60569182926, 13278.336083195994, 13870.791938121138, 9031.593578288861, 10950.890491094988, 11315.773684526099, 11560.94219528897, 11665.766684666089, 10948.507317170906, 11374.501892930983, 11439.084873209957, 11451.770964580828, 11600.129990250596], "value": 7699.158834877833, "unit": "score"}], "name": "dromaeo_css", "value": 7200.964021920667, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 117.0, 114.0, 113.0, 113.0, 114.0, 114.0, 112.0, 122.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 114.0, 129.0, 161.0, 108.0, 130.0, 129.0, 107.0, 162.0, 111.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 182.0, 185.0, 183.0, 182.0, 186.0, 184.0, 181.0, 183.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 76.0, 76.0, 155.0, 61.0, 62.0, 76.0, 91.0, 164.0, 57.0], "value": 89.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 134.0, 87.0, 101.0, 104.0, 133.0, 69.0, 69.0, 116.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 90.0, 90.0, 86.0, 90.0, 88.0, 88.0, 89.0], "value": 88.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 117.0, 150.0, 94.0, 101.0, 149.0, 134.0], "value": 112.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 83.0, 86.0, 85.0, 84.0, 86.0, 84.0, 84.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 53.0, 51.0, 82.0, 80.0, 81.0, 80.0, 58.0, 52.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 66.0, 81.0, 76.0, 77.0, 73.0, 95.0, 103.0, 102.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 86.0, 84.0, 93.0, 103.0, 86.0, 88.0, 90.0, 90.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 144.0, 153.0, 150.0, 145.0, 147.0, 130.0, 174.0, 153.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 171.0, 231.0, 204.0, 170.0, 168.0, 169.0, 174.0, 170.0], "value": 179.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 84.0, 66.0, 67.0, 67.0, 68.0, 66.0, 66.0, 66.0, 66.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1529.5, "alertThreshold": 2.0}]} 19:39:38 INFO - Return code: 0 19:39:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:39:38 INFO - # TBPL SUCCESS # 19:39:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:39:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:39:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:39:38 INFO - 2016-09-01 19:39:38.879 screenresolution[1970:13628] starting screenresolution argv=screenresolution get 19:39:38 INFO - 2016-09-01 19:39:38.881 screenresolution[1970:13628] Display 0: 1600x1200x32@60 19:39:38 INFO - 2016-09-01 19:39:38.887 screenresolution[1971:13631] starting screenresolution argv=screenresolution list 19:39:38 INFO - Available Modes on Display 0 19:39:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:39:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:39:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:39:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:39:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:39:39 INFO - Intel Iris: 19:39:39 INFO - Chipset Model: Intel Iris 19:39:39 INFO - Type: GPU 19:39:39 INFO - Bus: Built-In 19:39:39 INFO - VRAM (Dynamic, Max): 1536 MB 19:39:39 INFO - Vendor: Intel (0x8086) 19:39:39 INFO - Device ID: 0x0a2e 19:39:39 INFO - Revision ID: 0x0009 19:39:39 INFO - Displays: 19:39:39 INFO - Display: 19:39:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:39:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:39:39 INFO - Display Serial Number: 1600x1200 60 19:39:39 INFO - Main Display: Yes 19:39:39 INFO - Mirror: Off 19:39:39 INFO - Online: Yes 19:39:39 INFO - Rotation: Supported 19:39:39 INFO - Return code: 0 19:39:39 INFO - Running post-action listener: _resource_record_post_action 19:39:39 INFO - [mozharness: 2016-09-02 02:39:39.122968Z] Finished run-tests step (success) 19:39:39 INFO - Running post-run listener: _resource_record_post_run 19:39:39 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 1204224; Write bytes: 704025088; Read time: 13; Write time: 1973 19:39:39 INFO - TinderboxPrint: CPU usage
53.6% 19:39:39 INFO - TinderboxPrint: I/O read bytes / time
1,204,224 / 13 19:39:39 INFO - TinderboxPrint: I/O write bytes / time
704,025,088 / 1,973 19:39:39 INFO - TinderboxPrint: CPU idle
2,249.8 (46.4%) 19:39:39 INFO - TinderboxPrint: CPU system
972.8 (20.0%) 19:39:39 INFO - TinderboxPrint: CPU user
1,631.1 (33.6%) 19:39:39 INFO - TinderboxPrint: Swap in / out
407,572,480 / 0 19:39:39 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392260096; Write bytes: 387486208; Read time: 29271; Write time: 815 19:39:39 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1204224; Write bytes: 294502400; Read time: 13; Write time: 1105 19:39:39 INFO - Running post-run listener: _upload_blobber_files 19:39:39 INFO - Blob upload gear active. 19:39:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:39: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 19:39: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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:39: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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:39:40 INFO - (blobuploader) - INFO - Open directory for files ... 19:39:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:39:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:39:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:40 INFO - (blobuploader) - INFO - Done attempting. 19:39:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:39:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:39:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:41 INFO - (blobuploader) - INFO - Done attempting. 19:39:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:39:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:39:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:44 INFO - (blobuploader) - INFO - Done attempting. 19:39:44 INFO - (blobuploader) - INFO - Iteration through files over. 19:39:44 INFO - Return code: 0 19:39:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:39:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:39:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a7f04451b09be22be9722a7c9fe5735006bdaddf29de2537a3048164c1dc14a1c4227c0791588319217f08a4b30e68467d476929263a470191fba934eac7fd41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c7d2de3fbf22b014018adf640904f3114f2d53e1e368365083f30a0399da267ab2f5a479a4d92a49dce74c9739b619a89480f639ebcfb1dd0a7cada18d4ccdc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2b5d843d3cce924b5b0380b066dba53a313cdf302172d86a1d8c31b6fbe5fe5527e9a1aaf7ce498d31d9957601158e78c13fe78af32f3e681a74fad89065500"} 19:39:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:39:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:39:44 INFO - Contents: 19:39:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a7f04451b09be22be9722a7c9fe5735006bdaddf29de2537a3048164c1dc14a1c4227c0791588319217f08a4b30e68467d476929263a470191fba934eac7fd41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c7d2de3fbf22b014018adf640904f3114f2d53e1e368365083f30a0399da267ab2f5a479a4d92a49dce74c9739b619a89480f639ebcfb1dd0a7cada18d4ccdc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2b5d843d3cce924b5b0380b066dba53a313cdf302172d86a1d8c31b6fbe5fe5527e9a1aaf7ce498d31d9957601158e78c13fe78af32f3e681a74fad89065500"} 19:39:44 INFO - Running post-run listener: copy_logs_to_upload_dir 19:39:44 INFO - Copying logs to upload dir... 19:39:44 INFO - mkdir: /builds/slave/test/build/upload/logs 19:39:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.611642 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-01 19:39:44.058025) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 19:39:44.061640) ========= 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.jGDMzBWDEH/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.UIGYNjsmHu/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a7f04451b09be22be9722a7c9fe5735006bdaddf29de2537a3048164c1dc14a1c4227c0791588319217f08a4b30e68467d476929263a470191fba934eac7fd41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c7d2de3fbf22b014018adf640904f3114f2d53e1e368365083f30a0399da267ab2f5a479a4d92a49dce74c9739b619a89480f639ebcfb1dd0a7cada18d4ccdc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2b5d843d3cce924b5b0380b066dba53a313cdf302172d86a1d8c31b6fbe5fe5527e9a1aaf7ce498d31d9957601158e78c13fe78af32f3e681a74fad89065500"} build_url:https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011329 build_url: 'https://queue.taskcluster.net/v1/task/EiiSCxjeSgmVNcX4ODwKCQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a7f04451b09be22be9722a7c9fe5735006bdaddf29de2537a3048164c1dc14a1c4227c0791588319217f08a4b30e68467d476929263a470191fba934eac7fd41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c7d2de3fbf22b014018adf640904f3114f2d53e1e368365083f30a0399da267ab2f5a479a4d92a49dce74c9739b619a89480f639ebcfb1dd0a7cada18d4ccdc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2b5d843d3cce924b5b0380b066dba53a313cdf302172d86a1d8c31b6fbe5fe5527e9a1aaf7ce498d31d9957601158e78c13fe78af32f3e681a74fad89065500"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 19:39:44.099066) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:39:44.099796) ========= 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.jGDMzBWDEH/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.UIGYNjsmHu/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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.005120 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:39:44.152438) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 19:39:44.153320) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 19:39:44.154027) ========= ========= Total master_lag: 2.97 =========