builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0094 starttime: 1471491979.82 results: success (0) buildid: 20160817173815 builduid: ff6880557c9d4be281ebdd3bf2e45b1e revision: 30da32629a3c6ef74b8c66f819c08b1106f6da91 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 20:46:19.824644) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 20:46:19.825127) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 20:46:19.825556) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 20:46:19.856268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 20:46:19.856678) ========= 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.YpwcQhCbbf/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.89etxRhU6T/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-17 20:46:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.26M=0.001s 2016-08-17 20:46:19 (9.26 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089547 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 20:46:20.046669) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 20:46:20.046995) ========= 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.YpwcQhCbbf/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.89etxRhU6T/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.027570 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 20:46:20.096198) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-17 20:46:20.096621) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 30da32629a3c6ef74b8c66f819c08b1106f6da91 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 30da32629a3c6ef74b8c66f819c08b1106f6da91 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YpwcQhCbbf/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.89etxRhU6T/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-17 20:46:20,172 truncating revision to first 12 chars 2016-08-17 20:46:20,172 Setting DEBUG logging. 2016-08-17 20:46:20,172 attempt 1/10 2016-08-17 20:46:20,172 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/30da32629a3c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-17 20:46:21,906 unpacking tar archive at: autoland-30da32629a3c/testing/mozharness/ program finished with exit code 0 elapsedTime=2.010726 ========= master_lag: 0.35 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-17 20:46:22.457177) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 20:46:22.457555) ========= script_repo_revision: 30da32629a3c6ef74b8c66f819c08b1106f6da91 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 20:46:22.457969) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 20:46:22.458237) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 20:46:22.477267) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-17 20:46:22.477680) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YpwcQhCbbf/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.89etxRhU6T/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:46:22 INFO - MultiFileLogger online at 20160817 20:46:22 in /builds/slave/test 20:46:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 20:46:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:46:22 INFO - {'append_to_log': False, 20:46:22 INFO - 'base_work_dir': '/builds/slave/test', 20:46:22 INFO - 'blob_upload_branch': 'Autoland', 20:46:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:46:22 INFO - 'branch': 'Autoland', 20:46:22 INFO - 'buildbot_json_path': 'buildprops.json', 20:46:22 INFO - 'config_files': ('talos/mac_config.py',), 20:46:22 INFO - 'default_actions': ('clobber', 20:46:22 INFO - 'read-buildbot-config', 20:46:22 INFO - 'download-and-extract', 20:46:22 INFO - 'populate-webroot', 20:46:22 INFO - 'create-virtualenv', 20:46:22 INFO - 'install', 20:46:22 INFO - 'run-tests'), 20:46:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:46:22 INFO - 'download_minidump_stackwalk': True, 20:46:22 INFO - 'download_symbols': 'ondemand', 20:46:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:46:22 INFO - 'tooltool.py': '/tools/tooltool.py', 20:46:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:46:22 INFO - '/tools/misc-python/virtualenv.py')}, 20:46:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:46:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:46:22 INFO - 'installer_path': 'installer.exe', 20:46:22 INFO - 'log_level': 'info', 20:46:22 INFO - 'log_name': 'talos', 20:46:22 INFO - 'log_to_console': True, 20:46:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:46:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:46:22 INFO - 'opt_config_files': (), 20:46:22 INFO - 'pip_index': False, 20:46:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:46:22 INFO - 'cmd': ('bash', 20:46:22 INFO - '-c', 20:46:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:46:22 INFO - 'enabled': True, 20:46:22 INFO - 'halt_on_failure': False, 20:46:22 INFO - 'name': 'check_screen_resolution'},), 20:46:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:46:22 INFO - 'cmd': ('bash', 20:46:22 INFO - '-c', 20:46:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:46:22 INFO - 'enabled': True, 20:46:22 INFO - 'halt_on_failure': False, 20:46:22 INFO - 'name': 'check_screen_resolution'},), 20:46:22 INFO - 'run_cmd_checks_enabled': True, 20:46:22 INFO - 'sps_profile': False, 20:46:22 INFO - 'sps_profile_interval': 0, 20:46:22 INFO - 'suite': 'dromaeojs', 20:46:22 INFO - 'system_bits': '32', 20:46:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:46:22 INFO - 'title': 't-yosemite-r7-0094', 20:46:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:46:22 INFO - 'use_talos_json': True, 20:46:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:46:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:46:22 INFO - 'work_dir': 'build'} 20:46:22 INFO - [mozharness: 2016-08-18 03:46:22.677354Z] Running clobber step. 20:46:22 INFO - Running pre-action listener: _resource_record_pre_action 20:46:22 INFO - Running main action method: clobber 20:46:22 INFO - rmtree: /builds/slave/test/build 20:46:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:46:24 INFO - Running post-action listener: _resource_record_post_action 20:46:24 INFO - [mozharness: 2016-08-18 03:46:24.692355Z] Finished clobber step (success) 20:46:24 INFO - [mozharness: 2016-08-18 03:46:24.692512Z] Running read-buildbot-config step. 20:46:24 INFO - Running pre-action listener: _resource_record_pre_action 20:46:24 INFO - Running main action method: read_buildbot_config 20:46:24 INFO - Using buildbot properties: 20:46:24 INFO - { 20:46:24 INFO - "project": "", 20:46:24 INFO - "product": "firefox", 20:46:24 INFO - "script_repo_revision": "production", 20:46:24 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 20:46:24 INFO - "repository": "", 20:46:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 20:46:24 INFO - "buildid": "20160817173815", 20:46:24 INFO - "pgo_build": "False", 20:46:24 INFO - "basedir": "/builds/slave/test", 20:46:24 INFO - "buildnumber": 167, 20:46:24 INFO - "slavename": "t-yosemite-r7-0094", 20:46:24 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 20:46:24 INFO - "platform": "yosemite_r7", 20:46:24 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 20:46:24 INFO - "branch": "autoland", 20:46:24 INFO - "repo_path": "integration/autoland", 20:46:24 INFO - "revision": "30da32629a3c6ef74b8c66f819c08b1106f6da91", 20:46:24 INFO - "stage_platform": "macosx64", 20:46:24 INFO - "builduid": "ff6880557c9d4be281ebdd3bf2e45b1e", 20:46:24 INFO - "slavebuilddir": "test" 20:46:24 INFO - } 20:46:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 20:46:24 INFO - Running post-action listener: _resource_record_post_action 20:46:24 INFO - [mozharness: 2016-08-18 03:46:24.695878Z] Finished read-buildbot-config step (success) 20:46:24 INFO - [mozharness: 2016-08-18 03:46:24.696017Z] Running download-and-extract step. 20:46:24 INFO - Running pre-action listener: _resource_record_pre_action 20:46:24 INFO - Running main action method: download_and_extract 20:46:24 INFO - mkdir: /builds/slave/test/build/tests 20:46: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')]} 20:46:24 INFO - trying https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 20:46:24 INFO - Downloading https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/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 20:46:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/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 20:46:26 INFO - Downloaded 1199 bytes. 20:46:26 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 20:46:26 INFO - Using the following test package requirements: 20:46:26 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 20:46:26 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:46:26 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 20:46:26 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:46:26 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 20:46:26 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:46:26 INFO - u'jsshell-mac.zip'], 20:46:26 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:46:26 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 20:46:26 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 20:46:26 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:46:26 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 20:46:26 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:46:26 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 20:46:26 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:46:26 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 20:46:26 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:46:26 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 20:46:26 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:46:26 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')]} 20:46:26 INFO - trying https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:46:26 INFO - Downloading https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/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 20:46:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/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 20:46:27 INFO - Downloaded 16339511 bytes. 20:46:27 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 20:46:33 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 20:46:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:33 INFO - trying https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:46:33 INFO - Downloading https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/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 20:46:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/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 20:46:34 INFO - Downloaded 16339511 bytes. 20:46:34 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 20:46:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:41 INFO - trying https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 20:46:41 INFO - Downloading https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/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 20:46:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/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 20:46:42 INFO - Downloaded 14413981 bytes. 20:46:42 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 20:46:43 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')]} 20:46:43 INFO - trying https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:46:43 INFO - Downloading https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 20:46:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/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 20:46:45 INFO - Downloaded 111264170 bytes. 20:46:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:46:45 INFO - mkdir: /builds/slave/test/properties 20:46:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:46:45 INFO - Writing to file /builds/slave/test/properties/build_url 20:46:45 INFO - Contents: 20:46:45 INFO - build_url:https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:46:46 INFO - Running post-action listener: _resource_record_post_action 20:46:46 INFO - Running post-action listener: set_extra_try_arguments 20:46:46 INFO - [mozharness: 2016-08-18 03:46:46.448691Z] Finished download-and-extract step (success) 20:46:46 INFO - [mozharness: 2016-08-18 03:46:46.448817Z] Running populate-webroot step. 20:46:46 INFO - Running pre-action listener: _resource_record_pre_action 20:46:46 INFO - Running main action method: populate_webroot 20:46:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:46:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:46:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:46:46 INFO - '--tppagecycles', 20:46:46 INFO - '7'], 20:46:46 INFO - 'tests': ['tsvgm']}}, 20:46:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:46:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:46:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:46:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:46:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:46:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:46:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:46 INFO - 'talos_options': ['--disable-e10s'], 20:46:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:46:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:46:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:46 INFO - 'talos_options': ['--disable-e10s'], 20:46:46 INFO - 'tests': ['damp', 'tps']}, 20:46:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:46 INFO - 'tests': ['damp', 'tps']}, 20:46:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:46:46 INFO - 'tests': ['dromaeo_dom']}, 20:46:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:46:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:46:46 INFO - 'tests': ['basic_compositor_video']}, 20:46:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:46:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:46:46 INFO - 'tests': ['a11yr', 20:46:46 INFO - 'ts_paint', 20:46:46 INFO - 'tpaint', 20:46:46 INFO - 'sessionrestore', 20:46:46 INFO - 'sessionrestore_no_auto_restore', 20:46:46 INFO - 'tabpaint']}, 20:46:46 INFO - 'other-e10s': {'tests': ['a11yr', 20:46:46 INFO - 'ts_paint', 20:46:46 INFO - 'tpaint', 20:46:46 INFO - 'sessionrestore', 20:46:46 INFO - 'sessionrestore_no_auto_restore', 20:46:46 INFO - 'tabpaint']}, 20:46:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:46:46 INFO - 'ts_paint', 20:46:46 INFO - 'tpaint', 20:46:46 INFO - 'sessionrestore', 20:46:46 INFO - 'sessionrestore_no_auto_restore', 20:46:46 INFO - 'tabpaint']}, 20:46:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:46:46 INFO - 'ts_paint', 20:46:46 INFO - 'tpaint', 20:46:46 INFO - 'sessionrestore', 20:46:46 INFO - 'sessionrestore_no_auto_restore', 20:46:46 INFO - 'tabpaint']}, 20:46:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:46:46 INFO - 'tests': ['a11yr', 20:46:46 INFO - 'ts_paint', 20:46:46 INFO - 'tpaint', 20:46:46 INFO - 'sessionrestore', 20:46:46 INFO - 'sessionrestore_no_auto_restore', 20:46:46 INFO - 'tabpaint']}, 20:46:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:46:46 INFO - 'tests': ['a11yr', 20:46:46 INFO - 'ts_paint', 20:46:46 INFO - 'tpaint', 20:46:46 INFO - 'sessionrestore', 20:46:46 INFO - 'sessionrestore_no_auto_restore', 20:46:46 INFO - 'tabpaint']}, 20:46:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:46:46 INFO - 'tests': ['tsvgx', 20:46:46 INFO - 'tsvgr_opacity', 20:46:46 INFO - 'tart', 20:46:46 INFO - 'tscrollx', 20:46:46 INFO - 'cart']}, 20:46:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:46:46 INFO - 'tsvgr_opacity', 20:46:46 INFO - 'tart', 20:46:46 INFO - 'tscrollx', 20:46:46 INFO - 'cart']}, 20:46:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:46 INFO - 'talos_options': ['--disable-e10s'], 20:46:46 INFO - 'tests': ['tp5o']}, 20:46:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:46 INFO - 'tests': ['tp5o']}, 20:46:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:46 INFO - 'talos_options': ['--disable-e10s', 20:46:46 INFO - '--xperf_path', 20:46:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:46:46 INFO - 'tests': ['tp5n']}, 20:46:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:46:46 INFO - 'talos_options': ['--xperf_path', 20:46:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:46:46 INFO - 'tests': ['tp5n']}}, 20:46:46 INFO - 'talos.zip': {'path': '', 20:46:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:46:46 INFO - Running post-action listener: _resource_record_post_action 20:46:46 INFO - [mozharness: 2016-08-18 03:46:46.468392Z] Finished populate-webroot step (success) 20:46:46 INFO - [mozharness: 2016-08-18 03:46:46.468505Z] Running create-virtualenv step. 20:46:46 INFO - Running pre-action listener: _resource_record_pre_action 20:46:46 INFO - Running main action method: create_virtualenv 20:46:46 INFO - Creating virtualenv /builds/slave/test/build/venv 20:46:46 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 20:46:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:46:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:46:46 INFO - Using real prefix '/tools/python27' 20:46:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:46:47 INFO - Installing distribute.............................................................................................................................................................................................done. 20:46:50 INFO - Installing pip.................done. 20:46:50 INFO - Return code: 0 20:46:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:50 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 0x1060b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106147618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96e04c0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106016d30>, '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 0x105d7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10600eb20>, '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.YpwcQhCbbf/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.89etxRhU6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:46:50 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 20:46:50 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 20:46:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpwcQhCbbf/Render', 20:46:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:50 INFO - 'HOME': '/Users/cltbld', 20:46:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:50 INFO - 'LOGNAME': 'cltbld', 20:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:50 INFO - 'MOZ_NO_REMOTE': '1', 20:46:50 INFO - 'NO_EM_RESTART': '1', 20:46:50 INFO - 'PAGER': '/bin/cat', 20:46:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:50 INFO - 'PWD': '/builds/slave/test', 20:46:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:50 INFO - 'SHELL': '/bin/bash', 20:46:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89etxRhU6T/Listeners', 20:46:50 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:46:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:50 INFO - 'USER': 'cltbld', 20:46:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:50 INFO - 'XPC_FLAGS': '0x0', 20:46:50 INFO - 'XPC_SERVICE_NAME': '0', 20:46:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:50 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 20:46:50 INFO - Cleaning up... 20:46:50 INFO - Return code: 0 20:46:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:50 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 0x1060b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106147618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96e04c0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106016d30>, '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 0x105d7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10600eb20>, '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.YpwcQhCbbf/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.89etxRhU6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:46:50 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 20:46:50 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 20:46:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpwcQhCbbf/Render', 20:46:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:50 INFO - 'HOME': '/Users/cltbld', 20:46:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:50 INFO - 'LOGNAME': 'cltbld', 20:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:50 INFO - 'MOZ_NO_REMOTE': '1', 20:46:50 INFO - 'NO_EM_RESTART': '1', 20:46:50 INFO - 'PAGER': '/bin/cat', 20:46:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:50 INFO - 'PWD': '/builds/slave/test', 20:46:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:50 INFO - 'SHELL': '/bin/bash', 20:46:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89etxRhU6T/Listeners', 20:46:50 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:46:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:50 INFO - 'USER': 'cltbld', 20:46:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:50 INFO - 'XPC_FLAGS': '0x0', 20:46:50 INFO - 'XPC_SERVICE_NAME': '0', 20:46:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:50 INFO - Downloading/unpacking psutil>=3.1.1 20:46:50 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 20:46:50 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 20:46:50 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 20:46:50 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 20:46:50 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 20:46:50 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 20:46:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:46:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:46:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:46:53 INFO - Installing collected packages: psutil 20:46:53 INFO - Running setup.py install for psutil 20:46:53 INFO - building 'psutil._psutil_osx' extension 20:46:53 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 20:46:54 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 20:46:54 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 20:46:54 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 20:46:54 INFO - building 'psutil._psutil_posix' extension 20:46:54 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 20:46:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:46:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:46:54 INFO - ^ 20:46:54 INFO - 1 warning generated. 20:46:54 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 20:46:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:46:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:46:54 INFO - Successfully installed psutil 20:46:54 INFO - Cleaning up... 20:46:54 INFO - Return code: 0 20:46:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:54 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 0x1060b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106147618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96e04c0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106016d30>, '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 0x105d7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10600eb20>, '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.YpwcQhCbbf/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.89etxRhU6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:46:54 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 20:46:54 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 20:46:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpwcQhCbbf/Render', 20:46:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:54 INFO - 'HOME': '/Users/cltbld', 20:46:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:54 INFO - 'LOGNAME': 'cltbld', 20:46:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:54 INFO - 'MOZ_NO_REMOTE': '1', 20:46:54 INFO - 'NO_EM_RESTART': '1', 20:46:54 INFO - 'PAGER': '/bin/cat', 20:46:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:54 INFO - 'PWD': '/builds/slave/test', 20:46:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:54 INFO - 'SHELL': '/bin/bash', 20:46:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89etxRhU6T/Listeners', 20:46:54 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:46:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:54 INFO - 'USER': 'cltbld', 20:46:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:54 INFO - 'XPC_FLAGS': '0x0', 20:46:54 INFO - 'XPC_SERVICE_NAME': '0', 20:46:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:46:54 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 20:46:54 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 20:46:54 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 20:46:54 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 20:46:54 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 20:46:54 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 20:46:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:46:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:46:57 INFO - Installing collected packages: mozsystemmonitor 20:46:57 INFO - Running setup.py install for mozsystemmonitor 20:46:57 INFO - Successfully installed mozsystemmonitor 20:46:57 INFO - Cleaning up... 20:46:57 INFO - Return code: 0 20:46:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:46:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:46:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:46:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:46:57 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 0x1060b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106147618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96e04c0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106016d30>, '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 0x105d7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10600eb20>, '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.YpwcQhCbbf/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.89etxRhU6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:46:57 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 20:46:57 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 20:46:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpwcQhCbbf/Render', 20:46:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:46:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:46:57 INFO - 'HOME': '/Users/cltbld', 20:46:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:46:57 INFO - 'LOGNAME': 'cltbld', 20:46:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:46:57 INFO - 'MOZ_NO_REMOTE': '1', 20:46:57 INFO - 'NO_EM_RESTART': '1', 20:46:57 INFO - 'PAGER': '/bin/cat', 20:46:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:46:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:46:57 INFO - 'PWD': '/builds/slave/test', 20:46:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:46:57 INFO - 'SHELL': '/bin/bash', 20:46:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89etxRhU6T/Listeners', 20:46:57 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:46:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:46:57 INFO - 'USER': 'cltbld', 20:46:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:46:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:46:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:46:57 INFO - 'XPC_FLAGS': '0x0', 20:46:57 INFO - 'XPC_SERVICE_NAME': '0', 20:46:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:46:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:46:57 INFO - Downloading/unpacking jsonschema==2.5.1 20:46: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 20:46: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 20:46: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 20:46: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 20:46: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 20:46: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 20:47:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:47:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:47:00 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 20:47:00 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 20:47:00 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 20:47:00 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 20:47:00 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 20:47:00 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 20:47:00 INFO - Downloading functools32-3.2.3-2.tar.gz 20:47:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:47:00 INFO - warning: no files found matching '*.txt' 20:47:00 INFO - no previously-included directories found matching 'build' 20:47:00 INFO - no previously-included directories found matching 'dist' 20:47:00 INFO - no previously-included directories found matching '.git*' 20:47:00 INFO - Installing collected packages: jsonschema, functools32 20:47:00 INFO - Running setup.py install for functools32 20:47:00 INFO - warning: no files found matching '*.txt' 20:47:00 INFO - no previously-included directories found matching 'build' 20:47:00 INFO - no previously-included directories found matching 'dist' 20:47:00 INFO - no previously-included directories found matching '.git*' 20:47:00 INFO - Successfully installed jsonschema functools32 20:47:00 INFO - Cleaning up... 20:47:01 INFO - Return code: 0 20:47:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:47:01 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')]} 20:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:01 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')]} 20:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:01 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 0x1060b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106147618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96e04c0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106016d30>, '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 0x105d7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10600eb20>, '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.YpwcQhCbbf/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.89etxRhU6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:47:01 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 20:47:01 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 20:47:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpwcQhCbbf/Render', 20:47:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:01 INFO - 'HOME': '/Users/cltbld', 20:47:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:01 INFO - 'LOGNAME': 'cltbld', 20:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:01 INFO - 'MOZ_NO_REMOTE': '1', 20:47:01 INFO - 'NO_EM_RESTART': '1', 20:47:01 INFO - 'PAGER': '/bin/cat', 20:47:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:01 INFO - 'PWD': '/builds/slave/test', 20:47:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:01 INFO - 'SHELL': '/bin/bash', 20:47:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89etxRhU6T/Listeners', 20:47:01 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:47:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:01 INFO - 'USER': 'cltbld', 20:47:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:01 INFO - 'XPC_FLAGS': '0x0', 20:47:01 INFO - 'XPC_SERVICE_NAME': '0', 20:47:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:47:01 INFO - Cleaning up... 20:47:01 INFO - Return code: 0 20:47:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:47:01 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')]} 20:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:01 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')]} 20:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:01 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 0x1060b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106147618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96e04c0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106016d30>, '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 0x105d7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10600eb20>, '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.YpwcQhCbbf/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.89etxRhU6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:47:01 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 20:47:01 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 20:47:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpwcQhCbbf/Render', 20:47:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:01 INFO - 'HOME': '/Users/cltbld', 20:47:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:01 INFO - 'LOGNAME': 'cltbld', 20:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:01 INFO - 'MOZ_NO_REMOTE': '1', 20:47:01 INFO - 'NO_EM_RESTART': '1', 20:47:01 INFO - 'PAGER': '/bin/cat', 20:47:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:01 INFO - 'PWD': '/builds/slave/test', 20:47:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:01 INFO - 'SHELL': '/bin/bash', 20:47:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89etxRhU6T/Listeners', 20:47:01 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:47:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:01 INFO - 'USER': 'cltbld', 20:47:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:01 INFO - 'XPC_FLAGS': '0x0', 20:47:01 INFO - 'XPC_SERVICE_NAME': '0', 20:47:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:01 INFO - Downloading/unpacking blobuploader==1.2.4 20:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:03 INFO - Downloading blobuploader-1.2.4.tar.gz 20:47:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:47:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:47:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:47:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:47:05 INFO - Downloading docopt-0.6.1.tar.gz 20:47:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:47:05 INFO - Installing collected packages: blobuploader, requests, docopt 20:47:05 INFO - Running setup.py install for blobuploader 20:47:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:47:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:47:05 INFO - Running setup.py install for requests 20:47:05 INFO - Running setup.py install for docopt 20:47:06 INFO - Successfully installed blobuploader requests docopt 20:47:06 INFO - Cleaning up... 20:47:06 INFO - Return code: 0 20:47:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:06 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 0x1060b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106147618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96e04c0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106016d30>, '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 0x105d7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10600eb20>, '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.YpwcQhCbbf/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.89etxRhU6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:47:06 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 20:47:06 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 20:47:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpwcQhCbbf/Render', 20:47:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:06 INFO - 'HOME': '/Users/cltbld', 20:47:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:06 INFO - 'LOGNAME': 'cltbld', 20:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:06 INFO - 'MOZ_NO_REMOTE': '1', 20:47:06 INFO - 'NO_EM_RESTART': '1', 20:47:06 INFO - 'PAGER': '/bin/cat', 20:47:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:06 INFO - 'PWD': '/builds/slave/test', 20:47:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:06 INFO - 'SHELL': '/bin/bash', 20:47:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89etxRhU6T/Listeners', 20:47:06 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:47:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:06 INFO - 'USER': 'cltbld', 20:47:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:06 INFO - 'XPC_FLAGS': '0x0', 20:47:06 INFO - 'XPC_SERVICE_NAME': '0', 20:47:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:47:06 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-bBK7yR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:47:06 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-HR8lPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:47:06 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Ie4B6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:47:06 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-wipTT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:47:06 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-PzHueY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:47:06 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-0RTyiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:47:07 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-I0f4Qg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:47:07 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-KuN1cc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:47:07 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-7KXFdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:47:07 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-XpYoap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:47:07 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-w4AhNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:47:07 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-fwMFb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:47:07 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-kY_Ngq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:47:07 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-4cJCJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:07 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-ikh1DI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:47:08 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-vS6atZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:47:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:47:08 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-GeKRnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:47:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:47:08 INFO - Running setup.py install for manifestparser 20:47:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:47:08 INFO - Running setup.py install for mozcrash 20:47:08 INFO - Running setup.py install for mozdebug 20:47:08 INFO - Running setup.py install for mozdevice 20:47:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:47:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:47:08 INFO - Running setup.py install for mozfile 20:47:08 INFO - Running setup.py install for mozhttpd 20:47:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:47:09 INFO - Running setup.py install for mozinfo 20:47:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:47:09 INFO - Running setup.py install for mozInstall 20:47:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:47:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:47:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:47:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:47:09 INFO - Running setup.py install for mozleak 20:47:09 INFO - Running setup.py install for mozlog 20:47:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:47:09 INFO - Running setup.py install for moznetwork 20:47:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:47:09 INFO - Running setup.py install for mozprocess 20:47:10 INFO - Running setup.py install for mozprofile 20:47:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:47:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:47:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:47:10 INFO - Running setup.py install for mozrunner 20:47:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:47:10 INFO - Running setup.py install for mozscreenshot 20:47:10 INFO - Running setup.py install for moztest 20:47:10 INFO - Running setup.py install for mozversion 20:47:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:47:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:47:10 INFO - Cleaning up... 20:47:10 INFO - Return code: 0 20:47:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:10 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 0x1060b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106147618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96e04c0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106016d30>, '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 0x105d7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10600eb20>, '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.YpwcQhCbbf/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.89etxRhU6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:47:10 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 20:47:10 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 20:47:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpwcQhCbbf/Render', 20:47:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:10 INFO - 'HOME': '/Users/cltbld', 20:47:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:10 INFO - 'LOGNAME': 'cltbld', 20:47:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:10 INFO - 'MOZ_NO_REMOTE': '1', 20:47:10 INFO - 'NO_EM_RESTART': '1', 20:47:10 INFO - 'PAGER': '/bin/cat', 20:47:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:10 INFO - 'PWD': '/builds/slave/test', 20:47:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:10 INFO - 'SHELL': '/bin/bash', 20:47:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89etxRhU6T/Listeners', 20:47:10 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:47:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:10 INFO - 'USER': 'cltbld', 20:47:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:10 INFO - 'XPC_FLAGS': '0x0', 20:47:10 INFO - 'XPC_SERVICE_NAME': '0', 20:47:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:47:11 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-fnSaAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:47:11 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)) 20:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:47:11 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-QnSXjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:47:11 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)) 20:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:47:11 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-ZAwGSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:47:11 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)) 20:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:47:11 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-_C_vcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:47:11 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)) 20:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:47:11 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-KdGOsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:47:11 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)) 20:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:47:11 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-STBC1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:47:11 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)) 20:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:47:11 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-RNwCHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:47:11 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)) 20:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:47:11 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-V_bPTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:47:11 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)) 20:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:47:11 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-0NRlOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:47:11 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)) 20:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:47:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-LEdSzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:47:12 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)) 20:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:47:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-qmtGYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:47:12 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)) 20:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:47:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-1a8kCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:47:12 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)) 20:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:47:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-MHk3gd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:47:12 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)) 20:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:47:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-a0D310-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:47:12 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)) 20:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Qu9FdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:47:12 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)) 20:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:47:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-YlSDg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:47:12 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)) 20:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:47:12 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-0Rrh8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:47:12 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)) 20:47:12 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)) 20:47:12 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)) 20:47:12 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)) 20:47:12 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)) 20:47:12 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)) 20:47:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:47: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 20:47: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 20:47: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 20:47: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 20:47: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 20:47: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 20:47:15 INFO - Downloading blessings-1.6.tar.gz 20:47:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:47:15 INFO - Installing collected packages: blessings 20:47:15 INFO - Running setup.py install for blessings 20:47:15 INFO - Successfully installed blessings 20:47:15 INFO - Cleaning up... 20:47:15 INFO - Return code: 0 20:47:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:47:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:47:15 INFO - Reading from file tmpfile_stdout 20:47:15 INFO - Current package versions: 20:47:15 INFO - blessings == 1.6 20:47:15 INFO - blobuploader == 1.2.4 20:47:15 INFO - docopt == 0.6.1 20:47:15 INFO - functools32 == 3.2.3-2 20:47:15 INFO - jsonschema == 2.5.1 20:47:15 INFO - manifestparser == 1.1 20:47:15 INFO - mozInstall == 1.12 20:47:15 INFO - mozcrash == 0.17 20:47:15 INFO - mozdebug == 0.1 20:47:15 INFO - mozdevice == 0.48 20:47:15 INFO - mozfile == 1.2 20:47:15 INFO - mozhttpd == 0.7 20:47:15 INFO - mozinfo == 0.9 20:47:15 INFO - mozleak == 0.1 20:47:15 INFO - mozlog == 3.3 20:47:15 INFO - moznetwork == 0.27 20:47:15 INFO - mozprocess == 0.23 20:47:15 INFO - mozprofile == 0.28 20:47:15 INFO - mozrunner == 6.12 20:47:15 INFO - mozscreenshot == 0.1 20:47:15 INFO - mozsystemmonitor == 0.3 20:47:15 INFO - moztest == 0.7 20:47:15 INFO - mozversion == 1.4 20:47:15 INFO - psutil == 3.1.1 20:47:15 INFO - requests == 1.2.3 20:47:15 INFO - wsgiref == 0.1.2 20:47:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:16 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 0x1060b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106147618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96e04c0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106016d30>, '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 0x105d7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10600eb20>, '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.YpwcQhCbbf/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.89etxRhU6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:47:16 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 20:47:16 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 20:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpwcQhCbbf/Render', 20:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:16 INFO - 'HOME': '/Users/cltbld', 20:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:16 INFO - 'LOGNAME': 'cltbld', 20:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:16 INFO - 'MOZ_NO_REMOTE': '1', 20:47:16 INFO - 'NO_EM_RESTART': '1', 20:47:16 INFO - 'PAGER': '/bin/cat', 20:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:16 INFO - 'PWD': '/builds/slave/test', 20:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:16 INFO - 'SHELL': '/bin/bash', 20:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89etxRhU6T/Listeners', 20:47:16 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:16 INFO - 'USER': 'cltbld', 20:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:16 INFO - 'XPC_FLAGS': '0x0', 20:47:16 INFO - 'XPC_SERVICE_NAME': '0', 20:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:16 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)) 20:47:16 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)) 20:47:16 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)) 20:47:16 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)) 20:47:16 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)) 20:47:16 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)) 20:47:16 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)) 20:47:16 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)) 20:47:16 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)) 20:47:16 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)) 20:47:16 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)) 20:47:16 INFO - Cleaning up... 20:47:16 INFO - Return code: 0 20:47:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:47:16 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 0x1060b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106147618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96e04c0a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106016d30>, '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 0x105d7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10600eb20>, '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.YpwcQhCbbf/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.89etxRhU6T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 20:47:16 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 20:47:16 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 20:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpwcQhCbbf/Render', 20:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:16 INFO - 'HOME': '/Users/cltbld', 20:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:16 INFO - 'LOGNAME': 'cltbld', 20:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:16 INFO - 'MOZ_NO_REMOTE': '1', 20:47:16 INFO - 'NO_EM_RESTART': '1', 20:47:16 INFO - 'PAGER': '/bin/cat', 20:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:16 INFO - 'PWD': '/builds/slave/test', 20:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:16 INFO - 'SHELL': '/bin/bash', 20:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89etxRhU6T/Listeners', 20:47:16 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:16 INFO - 'USER': 'cltbld', 20:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:16 INFO - 'XPC_FLAGS': '0x0', 20:47:16 INFO - 'XPC_SERVICE_NAME': '0', 20:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:47:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:47:16 INFO - Cleaning up... 20:47:16 INFO - Return code: 0 20:47:16 INFO - Running post-action listener: _resource_record_post_action 20:47:16 INFO - Running post-action listener: _start_resource_monitoring 20:47:16 INFO - Starting resource monitoring. 20:47:16 INFO - [mozharness: 2016-08-18 03:47:16.807639Z] Finished create-virtualenv step (success) 20:47:16 INFO - [mozharness: 2016-08-18 03:47:16.808670Z] Running install step. 20:47:16 INFO - Running pre-action listener: _resource_record_pre_action 20:47:16 INFO - Running main action method: install 20:47:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:47:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:47:17 INFO - Reading from file tmpfile_stdout 20:47:17 INFO - Detecting whether we're running mozinstall >=1.0... 20:47:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:47:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:47:17 INFO - Reading from file tmpfile_stdout 20:47:17 INFO - Output received: 20:47:17 INFO - Usage: mozinstall [options] installer 20:47:17 INFO - Options: 20:47:17 INFO - -h, --help show this help message and exit 20:47:17 INFO - -d DEST, --destination=DEST 20:47:17 INFO - Directory to install application into. [default: 20:47:17 INFO - "/builds/slave/test"] 20:47:17 INFO - --app=APP Application being installed. [default: firefox] 20:47:17 INFO - mkdir: /builds/slave/test/build/application 20:47:17 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'] 20:47:17 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 20:47:51 INFO - Reading from file tmpfile_stdout 20:47:51 INFO - Output received: 20:47:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:47:51 INFO - Running post-action listener: _resource_record_post_action 20:47:51 INFO - [mozharness: 2016-08-18 03:47:51.471390Z] Finished install step (success) 20:47:51 INFO - [mozharness: 2016-08-18 03:47:51.471524Z] Running run-tests step. 20:47:51 INFO - Running pre-action listener: _resource_record_pre_action 20:47:51 INFO - Running main action method: run_tests 20:47:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:47:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:47:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:47:51 INFO - 2016-08-17 20:47:51.485 screenresolution[1634:7457] starting screenresolution argv=screenresolution get 20:47:51 INFO - 2016-08-17 20:47:51.488 screenresolution[1634:7457] Display 0: 1600x1200x32@60 20:47:51 INFO - 2016-08-17 20:47:51.494 screenresolution[1635:7459] starting screenresolution argv=screenresolution list 20:47:51 INFO - Available Modes on Display 0 20:47:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:47:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:47:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:47:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:47:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:47:51 INFO - Intel Iris: 20:47:51 INFO - Chipset Model: Intel Iris 20:47:51 INFO - Type: GPU 20:47:51 INFO - Bus: Built-In 20:47:51 INFO - VRAM (Dynamic, Max): 1536 MB 20:47:51 INFO - Vendor: Intel (0x8086) 20:47:51 INFO - Device ID: 0x0a2e 20:47:51 INFO - Revision ID: 0x0009 20:47:51 INFO - Displays: 20:47:51 INFO - Display: 20:47:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:47:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:47:51 INFO - Display Serial Number: 1600x1200 60 20:47:51 INFO - Main Display: Yes 20:47:51 INFO - Mirror: Off 20:47:51 INFO - Online: Yes 20:47:51 INFO - Rotation: Supported 20:47:51 INFO - Return code: 0 20:47:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:47:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:47:51 INFO - Python 2.7.3 20:47:51 INFO - Return code: 0 20:47:51 INFO - grabbing minidump binary from tooltool 20:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:47:51 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 0x106016d30>, '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 0x105d7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10600eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:47:51 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 20:47:51 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 20:47:51 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 20:47:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:47:51 INFO - Return code: 0 20:47:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:47:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:47:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:47:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:47:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:47:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--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 20:47:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0094 --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 20:47:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YpwcQhCbbf/Render', 20:47:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:47:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:47:51 INFO - 'HOME': '/Users/cltbld', 20:47:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:47:51 INFO - 'LOGNAME': 'cltbld', 20:47:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:47:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:47:51 INFO - 'MOZ_NO_REMOTE': '1', 20:47:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:47:51 INFO - 'NO_EM_RESTART': '1', 20:47:51 INFO - 'PAGER': '/bin/cat', 20:47:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:47:51 INFO - 'PWD': '/builds/slave/test', 20:47:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:47:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:47:51 INFO - 'SHELL': '/bin/bash', 20:47:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89etxRhU6T/Listeners', 20:47:51 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 20:47:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:47:51 INFO - 'USER': 'cltbld', 20:47:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:47:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:47:51 INFO - 'XPC_FLAGS': '0x0', 20:47:51 INFO - 'XPC_SERVICE_NAME': '0', 20:47:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:47:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--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 20:47:52 INFO - mozversion application_buildid: 20160817173815 20:47:52 INFO - mozversion application_changeset: 30da32629a3c6ef74b8c66f819c08b1106f6da91 20:47:52 INFO - mozversion application_display_name: Nightly 20:47:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:47:52 INFO - mozversion application_name: Firefox 20:47:52 INFO - mozversion application_remotingname: firefox 20:47:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:47:52 INFO - mozversion application_vendor: Mozilla 20:47:52 INFO - mozversion application_version: 51.0a1 20:47:52 INFO - mozversion platform_buildid: 20160817173815 20:47:52 INFO - mozversion platform_changeset: 30da32629a3c6ef74b8c66f819c08b1106f6da91 20:47:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:47:52 INFO - mozversion platform_version: 51.0a1 20:47:52 INFO - using testdate: 1471492072 20:47:52 INFO - actual date: 1471492072 20:47:52 INFO - starting webserver on 'localhost:49263' 20:47:52 INFO - SUITE-START | Running 2 tests 20:47:52 INFO - TEST-START | dromaeo_css 20:47:52 INFO - operating with platform_type : mac_ 20:47:52 INFO - Initialising browser for dromaeo_css test... 20:47:52 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpDopLEX/profile http://localhost:49263/getInfo.html) 20:47:54 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 20:47:54 INFO - PROCESS | 1645 | colorDepth:24 20:47:54 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 20:47:54 INFO - PROCESS | 1645 | __metrics 20:47:54 INFO - PROCESS | 1645 | 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" 20:47:54 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:47:55 INFO - TEST-INFO | 1645: exit 0 20:47:55 INFO - Browser initialized. 20:47:55 INFO - Running cycle 1/1 for dromaeo_css test... 20:47:55 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpDopLEX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:48:07 INFO - PROCESS | 1647 | RSS: Main: 228122624 20:48:07 INFO - PROCESS | 1647 | 20:48:58 INFO - PROCESS | 1647 | 1471492138159 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] 20:51:10 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49263/tests/dromaeo/cssquery-ext.html) 20:51:12 INFO - PROCESS | 1647 | RSS: Main: 347283456 20:51:12 INFO - PROCESS | 1647 | 20:54:15 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-ext.html (next: http://localhost:49263/tests/dromaeo/cssquery-jquery.html) 20:54:15 INFO - PROCESS | 1647 | RSS: Main: 366735360 20:54:15 INFO - PROCESS | 1647 | 20:57:18 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49263/tests/dromaeo/cssquery-mootools.html) 20:57:18 INFO - PROCESS | 1647 | RSS: Main: 341274624 20:57:18 INFO - PROCESS | 1647 | 21:00:21 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49263/tests/dromaeo/cssquery-prototype.html) 21:00:22 INFO - PROCESS | 1647 | RSS: Main: 340107264 21:00:22 INFO - PROCESS | 1647 | 21:03:25 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49263/tests/dromaeo/cssquery-yui.html) 21:03:26 INFO - PROCESS | 1647 | RSS: Main: 328327168 21:03:26 INFO - PROCESS | 1647 | 21:06:29 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-yui.html (next: http://localhost:49263/tests/dromaeo/cssquery-dojo.html) 21:06:30 INFO - PROCESS | 1647 | RSS: Main: 375721984 21:06:30 INFO - PROCESS | 1647 | 21:06:30 INFO - PROCESS | 1647 | __start_tp_report 21:06:30 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 21:06:30 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 21:06:30 INFO - PROCESS | 1647 | |i|pagename|runs| 21:06:30 INFO - PROCESS | 1647 | |0;dojo.html;3373.578302712161;3401.234722187508;3406.148462884279;3411.2495251044775;3466.0641626760776;5654.208410822488;5721.170430287611;5794.362620111784;5815.505682017028;5837.7373018214175;5684.289463817023;5748.511376533;5754.769809207627;5781.653100813955;5835.124731290309;5192.688438693672;5238.947610523903;5272.182811664198;5277.973610131963;5362.2224777407255;4421.801018954138;5366.677999320033;5377.4084850666395;5379;5398.4871437213405;5168.974155129238;5222;5222.556082732935;5248.632595718283;5332.280142180794;4334.544872788375;5280.577553795725;5281.735913204325;5290.338707661543;5295.285136506599;4326.394304797331;4361;4747.905041899143;5228.633995620308;5282.99623071619;5692.971535142297;5762.193292938992;5764.991126552878;5820.592558520905;5834.474897259267;6217.533684973645;6291.087792270096;6295.2760432550385;6299.559030867841;6305.8108256752375;6161.7535298588;6184.5980011299125;6237.594556353823;6315.336889626569;6319.273283572402;5902.203202567642;5939.554533410012;5945.316288626776;5952.375000624958;5954.4045595440975;6326.177596912372;6370.331115232926;6392.936070639327;6409.647469389229;6413.935860641333;6062.030075187949;6130.9883869161085;6190;6203.8138855834395;6227.688615569204;6314.589551679126;6319;6323.841903952438;6332.271788744353;6336.556441049082;6280.466160376419;6282.4974002079725;6284.088807123033;6306.747730090745;6310.211223597051;5563.443655634404;5879.970600146972;6172.22847144107;6199.3490683478485;6252.062190671436;4959.256111583291;4972;4986.301917731521;5002.499750025041;5045.798168073309;4452.910941781146;4463.977680111579;4472.284434490466;4484.820607175677;4512.81948722054;5664;5710.000749868839;5730.570207234474;5777.826665200051;5783.363829978699;6197.96901015492;6282.465990390868;6286.622802631857;6299.874002519924;6302.212223472066;5729.598928074995;5776.653400795965;5794.536437085023;5802.448767367094;5809.099589563624;5439.972800135974;5510.8071217507195;5511.751971161308;5516.172574113915;5521.365043020024;6419;6433.4209921106385;6436.259830119502;6447.709853056624;6472.482201423781;6148.739508400857;6152.323244443018;6217.658028808459;6266.717997690206;6294.842628934313;6386.584871983307;6461.676916154268;6464;6468.3855033771715;6473.579217350857;6235.750569977332;6262.68686565679;6274.780382686584;6274.811755647338;6278.058291256348;6140.87237156979;6210.782622608187;6244.344343844013;6271.62370257786;6273.37266273369;6096.390360963868;6171.363677116722;6210.720517576629;6219.626822390671;6243.469305109116;6140.692965351804;6184.443400094013;6188.783392581238;6198.597091189239;6204.255489341127;5781.8554536136935;5915.142304365757;5974.342822289458;5976.8804623908145;6026.698665066817;6138.662373569497;6171.352008039091;6179.104029915727;6190.845228869314;6195.659238741912;5600.131979543177;5997.590566217023;6009.639421634716;6030.7889223876955;6052.03167493199 21:06:30 INFO - PROCESS | 1647 | |1;ext.html;463.7472577445292;514.354485121173;519.195247366582;530.7372850439033;547.3076558153933;29617.407651846977;29955.700442995592;31251.062468125936;31252.843735781316;39003.60996390035;33094.00717978458;41743.58256417442;41774.37338439926;41802;41843.16313673725;9569.856452153206;9747.707568772912;9753.707388778348;9767.951160244189;9770.51147442625;8628.223459888608;8826.779330516723;9601.231901447867;9725.756856078619;9729.951350243275;11663.183577149603;11949.641510754647;11967.102467314942;12154.0276777858;12170;11862.52549898003;11933.224340417844;11942.984846288076;11947.311854832637;11948.522059117626;11712.355820429873;11739.178257521977;11760.882391176065;11773.117016223818;11779.234349767237;38787.80606096979;38973.22053558913;38984.22031559381;39033.41449878254;39040;3385;3814.8855534334016;4074.4092106644666;4078.938815917764;4235.57644235577;30757.538636920464;30833.229169270722;30856;30896;31042.068737937672;3115.5949726535405;3273.214428537134;3341.732661387083;3342.7325813934826;3349.179451034512;490743;492648.0735192638;493299.9999999964;494032;500492;55987.88024239574;56029;56191.43808561944;56272.71863640656;56408;58715;58855.705721471975;58923;58923.41076589179;59083;15802.36790528389;15970.281337339848;15982.520524384287;15994.280257388446;16060.43788467398;12520.81218781719;12584.622461326175;12603.558875439316;12611.243325400505;12622.368881555885;13393.66515837112;13766.65583360404;14844.851551484562;15199.960007998445;15370.769438458432;5903.940960590425;7125.465590080765;7129.506541794587;7202.567845929261;7228.277172282835;885.9557022148866;907.2469850024464;937.231470194434;956.8134213828375;1064.1752641702587;27757;29057.56413653797;29224.853875730485;29930.25174370658;30084.84957575198;963.4700914497139;1079.7192729890128;1083.7561548651647;1089.1395797320188;1112.165875593305;14431.2062836545;15284.235788210544;15330.540083797503;15338.619524242871;15773.763393549107;669418;669481;670938;671362;671897;57071;57221.42778572161;57254;57362.42637573654;57409;1199.9040076793835;1241.509603706531;1241.8696036915994;1349.7165595224917;1375.2985977151725;23485.295441136794;30141.547876781427;30937.381252374376;31859.840700796347;32241.35517289688;51647;52158.21762673487;52231;52366.21450678319;52374.73812630912;590477.1904561799;595928;596239.0188049031;596619;597274;25839.483210336064;26183.73816261813;26449.60325595079;27142;38425.5417012459;32380;38353.80823095867;38904.416433754086;46834.29748553706;48222.51777482321;37455.625443745215;38225.04437389087;38474.96012918603;39061.804690976365;39400.40899386569;32420.83789581037;37938.62061379351;38693;38878.805605971786;39196.608033920435;36375;36805.44791828071;37317.44023839699;37477.6252237485;37563.87308380753;33493.49759753557;33589.66410335866;34288.142796430286;34431;34470.82764586161;24947.87526062358;25034;25110.74889251084;25703.357416064748;25746.485070298113 21:06:30 INFO - PROCESS | 1647 | |2;jquery.html;316.6611725453765;359.3262632563941;369.82987825600225;383.7467271600744;402.949631296088;20392.388228353153;21059.736415815063;21084.367468975935;21964.341069767932;22147;20932.848693321863;21065.47336316593;21152.894235528838;21457.67813482795;21548.56902861946;4767.380240568721;5201.505856943584;5908.615939963899;5986.918582896364;6232.439080482755;4175.895602609944;4438.423005009345;6133.11069894865;6182.319944806068;6191.071339299118;4443.5556444355625;6186.63084164464;6816.079829223041;6868.7252509899545;6899.862002759942;5505.953868764922;6646.867062658768;6698.4641228701585;6811.114555107853;6827.48793840464;5910.497607703351;5918.852028699274;5925.377835327315;5932.822015339547;5937.940620593782;26721.331966700793;26998.325041873915;27491;27822.3044423889;27844.303892402648;2051.0360130738527;2165.4261620670536;2225.799678028985;2550.2094350751327;2741.684706258786;12943.288119153443;17026.829731702772;17157;17297.394591189248;17565.648687026187;1685.9578510537212;2010.1758279105497;2075.989620051905;2110.482931681732;2163.610550100981;128259.96750406199;144422;147042;148606.25696871555;148905;21227;21629.56740865174;21735.456613584785;21798.782012179676;21913.452163696034;28685.85256589713;35133.82433087869;35184.29631407409;35265.11837204039;35307.4703879442;10642.574297028186;10846.94576527128;11057.834132488018;11074.335539867634;11253.831192532118;9062.378187124483;9066.22937050345;9101.499417532099;9277.814443711088;9279.164875161268;7964.442489025712;9527.714168574954;9795.412275263507;9948;9953.452560109263;3339.148517128117;3649.464090847946;3834.2906562286066;3841.654251117413;3842.2315536892734;370.2613286493463;380.3856771314307;388.646331838026;835.4987007795396;892.8839250897341;11578;11654;11824.645260642195;11964.64106076819;11984.460699268553;352.99294014119573;427.5382586806304;435.9149965756647;438;868.662260119409;10690.363088726766;10722.26432665107;10810.675679729464;11204.991550760471;11372.658820235407;634827;646573;647685;661007;668273;126577;134429;135340;137460.94121058483;140953;428.5692878656992;449.566168647257;451.67028069509445;454.2708952131847;454.58405558913887;11110;11189.272697274651;11246.381449020382;11421.172612381979;11522.251053681484;53715.73142134264;55485.7225713885;56201;57758;57793.711031444574;82103.17896820956;82826.58586707027;83411.16588834034;83557.74663379932;83887.99736040924;7759.495632783851;7816.257455541714;7970.163132871197;8028.4781489203015;8072.112067672669;9624.951875240858;12181.39093045362;12354.135210535536;12524.937375313066;12838.614841554769;10712.464376781285;10749.301295415773;10864.967828056322;11005.889941100806;11006;9523.809523809347;10432.269741118049;10676.733081673021;10912.399818010086;10967.287126336763;10851.457427128455;10988.670339889815;10992.670219893416;11124.721881953015;11178.329300242012;8115.310198633182;8178.591070446335;8319.584020798815;8351.665933362598;8372.581370931308;8327.541985190872;10508.737281568023;10887.564497420015;11311.377874216998;11321.433928303388 21:06:30 INFO - PROCESS | 1647 | |3;mootools.html;1287.922724636522;1303.876131767482;1345;1603.9037657740537;1870.551067743742;7491.925080749191;7810.297073263405;8539.018012928515;8774.561271936409;8811.911880881189;8661.480311181334;8752.78118047049;8764;8765.211130998208;8803.075677053914;2373.857568545884;2396.8921398537022;2397.5324811661735;2397.5684376812255;2400.0039983406864;2334.8015418689433;2378.7740164684415;2387.510560335127;2389.6296074108454;2391.246757271454;2156.4069880782754;2184.8252139828855;2351.8588884666888;2374.7387787343378;2377.9167729129485;2339.6841426407386;2348.906043758256;2360.4806942472715;2365.219477572397;2365.3022358404314;2312.7264112764;2337.357226762637;2337.812974961999;2341.4497593065616;2347.969006809115;8701.390902636818;8720.127987201293;8766.561671916379;8812.779680507974;8849.115088491164;5188.8183913563225;5395.811146609889;5402.864928376783;5404.891902161935;5410.513053825174;3657.5428071491065;3663.468797024443;3729.533808273966;3730.0301921500336;3731;1059.1685526861454;1062;1077.4289626497969;1090.7491277006334;1094.3324572011068;11640.650780476599;11690.123240756977;11806.940965295118;11819.64541063778;11835;3870.4968354113785;4351.673624478177;4574.199515084913;4622.815087396467;4638.002829391697;9081.273498120134;9087.363884528151;9093.636254549743;9099.81800363989;9103.499307538017;5199;5209.192575150858;5214.227162764678;5216.21756736485;5232.39827419844;4312.202242585132;4394.516603173648;4569.908601827945;4777.116233496814;4877.12211801879;3737.588865224823;3758.548974123068;3817.732758706864;3832.2527107213828;3855.267499175148;984.3011461862158;996.9501524923725;1172.202902026613;1183.5206741269799;1185.2532904270427;903.4443817052597;922.796984663373;931.7344556801279;937.3689779783128;976.8192884316425;5724.341700704473;5878.764849405976;5906.586538942232;5974.163617093611;6231.688415579203;435.1536261970437;439.3760859579405;440.79943625650253;444.4977175791335;454.2255454450154;3987.5414327352146;3988;4073.1446396256524;4102.097538541516;4138.110306284163;10218.233632477593;10289.3311934724;10602.151827853753;10772.138228941665;10923.672289831158;10613.097886679565;10885.727856803485;10889.401082940514;10893.618723344804;10933.070688991365;7902.3678105751405;8252.33981281496;8257.876131858027;8280.71017514384;8288.171182881664;5953.880922381441;5966.0454327307425;6085.817425477243;6133.631982080911;6159.322474527888;9261.212796912338;10089.445080520642;10092.192624589869;10132.898671013196;10140.442275674908;8362.832743345221;8838.337124715543;9483.383580067273;9489.57296921654;10422;6960.408365289007;7334.486585939147;7485.887711684438;7544.6227688616445;7552.018237629293;8125.918740812753;8137.186281371815;8144.348452123697;8232.259096681328;8232.876506852283;7393.963030184815;7417.777466676009;7464.104307483137;7526;7563.319301262913;7267.6366181691765;7273.9636301818155;7294.702810246207;7443.851122977619;7493.587852667937;6744.460443164633;6907.827304317432;6964.094667693167;6980.197277313174;6986.720531178696;4981.352424184833;5040.647154699136;5067.290579318972;5152.098382783073;5270.49930256625;4671.462781780138;4961.107000739901;5075.766662668414;5096.796128154832;5106.208537676666 21:06:30 INFO - PROCESS | 1647 | |4;prototype.html;272.7436209962635;293.4395304967513;299.93224122125235;310.11462275204303;311.38967623458024;4576.97711511442;4758.143534163849;5108;5184.844454666361;5212.348456442945;4824.445188803289;4853.660243782937;4916.80332786689;5023.146065168921;5150.793968241275;3951.802409879509;3964.9976761735284;3966.643002129808;3991.700622453313;4022.738521996061;3945.4081887716784;3971.960280397188;3993.0650971535897;3997.5402828674783;4019.1157945252;3585.4801053847177;3693.722970777189;3888.7555982085746;3943;3996.520417549898;3234.304624505719;3285.4578994466005;3831.5976822433663;3913.3542965410675;3954.9123990902444;3249.447593909041;3394.1684287349512;3426.5374174486374;3434.055634700452;3482.9651703483146;3894.6563435614667;4259.76571288579;4918.2376731606655;4940.308356830046;4958.87602809929;3317.1872891141584;3346.4311067118647;3935.330993731073;4113.280175969194;4119.198944717591;5441.918371224395;5458.699771512565;5461.2354270402175;5488.396276409591;5492.423295553949;3630.8729194478333;3658.2134841009047;3669.412893936984;3679.951213904051;3697.5932647408763;5344.385395679507;5542.445755424425;5577.776888924438;5601.535324457226;5676.659400435987;3005.8497075146156;3726.919193433928;3736.252749450121;3737.2338670572467;3802.885913422602;3358.7648864579733;3819.4461803038403;3822.9235415292096;3831.080540670275;3845.3462911304587;3423.9144021399666;4375.8030888609455;4450.5104438511735;4450.955490445054;4453.933191002073;4855.8057677693205;5262.60530460217;5445.782168713286;5464.863378415492;5510.889782204334;1140.3158105137018;1282.352412031918;1294.7539967406162;1297.9026573006875;1309.6641425745877;774.2567135549823;778.7118766056482;835.1690068381878;856.8928883889514;888.6445421831319;4579.061292435043;4907.037185125907;4984.252362145635;4993.850184494471;5020.017093248147;4569.223232050559;4591.632669386441;5079.187330027178;5531;5570.832875013756;4521.208788462006;4729.976350118227;4742.857714268577;4759.4764575896625;4779.354787103766;2585.2890455124616;2858.3140046388717;2878.4387044526534;2886.729838870932;2913.533834586456;5684.658920464785;5696.943030569724;5716.79991200306;5730.713464326767;5761.827145185651;4574.255446732001;5467.316585426822;5572.3870374258795;5670.489655930904;5773.268019594105;5042.773075211624;5248.845254044104;5766.500709815395;5771.567132465082;5785.421457854265;4901.313816065683;5233.345831771028;5297.125974214211;5504.91742623869;5640.85897852557;4185.748855068706;5032.949670503248;5111.539961403491;5258.342707161605;5484.862878428071;5197.740112994259;5237.324056302099;5489.92946375453;5769.53843692512;5776.104703770922;3621.5835178953703;3722.4416337549583;3743.1577894974503;3754.0614846288427;3786.8674596388996;5507.5593952484505;5511.614187006871;5545.08882836514;5566.97216513931;5591.329040515164;5355.973220133875;5357.901630165885;5361.919571206513;5431.456854314537;5472.534834538949;4635.860924172281;5169.147090729987;5413.648112872651;5443.8094666686475;5449.591280653887;5286.418493965738;5292.708901010481;5299.7615107321035;5317.282166907418;5335.67985920846;5154.278401023935;5219.2953951216095;5358.785648574013;5449.291592093003;5509.906235630435;5363.222332761806;5376.220448035091;5395.028894798974;5412.242286079873;5419.160030195326 21:06:30 INFO - PROCESS | 1647 | |5;yui.html;720.8522252938149;724.8006798130514;750.2047829300941;809.3606051219537;829.1625458287128;13445.865541344583;16680.66638667226;16689.49931502057;16690.749638755427;16703.248353824078;15011.249437528108;15230.39078436864;15256;15268.618284542892;15272.38910443581;667.2393471442559;686.2896901706735;826.3843436639712;834.1450013735923;854.7051267312798;830.9252167304942;839.8116664919132;841.3227351981652;841.4572600672562;845.4420082745389;905.7599736069947;905.868649045888;905.8867641544807;906.4380084347681;908.3278374003256;897.2149369301861;897.8698088777156;898.6764764684708;898.8786513820615;898.9999999999967;515.706047552895;655.0501772429948;669.1635455680399;671.788386658206;674.0765151742096;14877.553673389815;14893.77659335111;14910;14913.2543372832;14916.403343866232;12232.837880401348;13479.258640774753;14152.858471415257;14208;14379;12401.875981240162;12467.952235113333;12708.173968692005;13178.011649126258;13385.933070334684;4554.686958158702;4571.794269257858;4597.977010114962;4800.783964721561;4804.687695299829;108102;116646;116719.41640291829;118007;119179;11129.666110016713;11245.493952772145;11331.376774277494;11346.659600212008;11514.424278786195;22263.666045009337;22274;22326.44183895383;22335.888320558293;22362.776372236396;7869.527828330204;8139.918600814034;8160.102388737234;8223.301019413297;8313.625886835107;4359.891002724958;4493.887652808706;4542.432195975503;4583.129205450954;4688.038952014829;3381.3406385755034;3458.654134586521;3510.069831494656;3514.5606799150105;3524.2775231077567;647.2653538234044;654.1986067067899;654.5450911616476;662.1292999705469;670.4870773858003;569.4800163836529;589.4074773656664;601.9217501724747;602.2020822410324;633.9492840572743;7759.961200193963;8814.823703525894;9001.729948101567;9030;9190.678326258549;537.873599704077;561.093833458969;568.7469076261024;612.7977767336769;623.7816764132608;4055.878323650295;4269.295566231599;4391.890202744893;4400.625946794558;4405.647548196137;124930;130380;164272;168470;173257;93374.53312733393;114822;119679;121211;125151.37424313002;70094;70506;99245.50377248212;103089;106795;9264.305177111608;9523.047695230422;9634.855477167708;9952.55213515379;9964;45526.77236613796;48628.75685621549;63327.667233276305;64265.67867160821;66108;95071.52464237635;99365.50317248367;99385;99640;101078.49460752649;13177.27524986135;13234.338283086;13246.668833279246;13677.86322136766;13902.16587004783;14532.927335363256;14689.853101469276;14828.7775683367;14976.176310303037;15121.924390377977;9366.391184572982;12210.633680989584;13774;14021.439142434188;14585.124892506483;13814.37835297394;13918.304084795922;14015.50945716895;14217.857821421654;14622.341994610055;12259.080568957543;12362.258264504158;12813;13284.86715132875;13330.471717418957;10344.741381465523;11385.373804440835;11684.707188454107;11765.352905590275;12001.699957500783;9483.814523184601;11199.104071674083;11442.713932151762;11688;11697.649070527897 21:06:30 INFO - PROCESS | 1647 | __end_tp_report 21:06:30 INFO - PROCESS | 1647 | __start_cc_report 21:06:30 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1263 21:06:30 INFO - PROCESS | 1647 | __end_cc_report 21:06:30 INFO - PROCESS | 1647 | __startTimestamp1471493190172__endTimestamp 21:06:30 INFO - PROCESS | 1647 | 21:06:30 INFO - PROCESS | 1647 | ------- Summary: start ------- 21:06:30 INFO - PROCESS | 1647 | Number of tests: 6 21:06:30 INFO - PROCESS | 1647 | 21:06:30 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5779.32 Median:5997.59 stddev:673.44 (11.2%) stddev-sans-first:648.52 21:06:30 INFO - PROCESS | 1647 | Values: 3373.6 3401.2 3406.1 3411.2 3466.1 5654.2 5721.2 5794.4 5815.5 5837.7 5684.3 5748.5 5754.8 5781.7 5835.1 5192.7 5238.9 5272.2 5278.0 5362.2 4421.8 5366.7 5377.4 5379.0 5398.5 5169.0 5222.0 5222.6 5248.6 5332.3 4334.5 5280.6 5281.7 5290.3 5295.3 4326.4 4361.0 4747.9 5228.6 5283.0 5693.0 5762.2 5765.0 5820.6 5834.5 6217.5 6291.1 6295.3 6299.6 6305.8 6161.8 6184.6 6237.6 6315.3 6319.3 5902.2 5939.6 5945.3 5952.4 5954.4 6326.2 6370.3 6392.9 6409.6 6413.9 6062.0 6131.0 6190.0 6203.8 6227.7 6314.6 6319.0 6323.8 6332.3 6336.6 6280.5 6282.5 6284.1 6306.7 6310.2 5563.4 5880.0 6172.2 6199.3 6252.1 4959.3 4972.0 4986.3 5002.5 5045.8 4452.9 4464.0 4472.3 4484.8 4512.8 5664.0 5710.0 5730.6 5777.8 5783.4 6198.0 6282.5 6286.6 6299.9 6302.2 5729.6 5776.7 5794.5 5802.4 5809.1 5440.0 5510.8 5511.8 5516.2 5521.4 6419.0 6433.4 6436.3 6447.7 6472.5 6148.7 6152.3 6217.7 6266.7 6294.8 6386.6 6461.7 6464.0 6468.4 6473.6 6235.8 6262.7 6274.8 6274.8 6278.1 6140.9 6210.8 6244.3 6271.6 6273.4 6096.4 6171.4 6210.7 6219.6 6243.5 6140.7 6184.4 6188.8 6198.6 6204.3 5781.9 5915.1 5974.3 5976.9 6026.7 6138.7 6171.4 6179.1 6190.8 6195.7 5600.1 5997.6 6009.6 6030.8 6052.0 21:06:30 INFO - PROCESS | 1647 | 21:06:30 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:71077.81 Median:37466.63 stddev:158222.06 (422.3%) stddev-sans-first:158577.08 21:06:30 INFO - PROCESS | 1647 | Values: 463.7 514.4 519.2 530.7 547.3 29617.4 29955.7 31251.1 31252.8 39003.6 33094.0 41743.6 41774.4 41802.0 41843.2 9569.9 9747.7 9753.7 9768.0 9770.5 8628.2 8826.8 9601.2 9725.8 9730.0 11663.2 11949.6 11967.1 12154.0 12170.0 11862.5 11933.2 11943.0 11947.3 11948.5 11712.4 11739.2 11760.9 11773.1 11779.2 38787.8 38973.2 38984.2 39033.4 39040.0 3385.0 3814.9 4074.4 4078.9 4235.6 30757.5 30833.2 30856.0 30896.0 31042.1 3115.6 3273.2 3341.7 3342.7 3349.2 490743.0 492648.1 493300.0 494032.0 500492.0 55987.9 56029.0 56191.4 56272.7 56408.0 58715.0 58855.7 58923.0 58923.4 59083.0 15802.4 15970.3 15982.5 15994.3 16060.4 12520.8 12584.6 12603.6 12611.2 12622.4 13393.7 13766.7 14844.9 15200.0 15370.8 5903.9 7125.5 7129.5 7202.6 7228.3 886.0 907.2 937.2 956.8 1064.2 27757.0 29057.6 29224.9 29930.3 30084.8 963.5 1079.7 1083.8 1089.1 1112.2 14431.2 15284.2 15330.5 15338.6 15773.8 669418.0 669481.0 670938.0 671362.0 671897.0 57071.0 57221.4 57254.0 57362.4 57409.0 1199.9 1241.5 1241.9 1349.7 1375.3 23485.3 30141.5 30937.4 31859.8 32241.4 51647.0 52158.2 52231.0 52366.2 52374.7 590477.2 595928.0 596239.0 596619.0 597274.0 25839.5 26183.7 26449.6 27142.0 38425.5 32380.0 38353.8 38904.4 46834.3 48222.5 37455.6 38225.0 38475.0 39061.8 39400.4 32420.8 37938.6 38693.0 38878.8 39196.6 36375.0 36805.4 37317.4 37477.6 37563.9 33493.5 33589.7 34288.1 34431.0 34470.8 24947.9 25034.0 25110.7 25703.4 25746.5 21:06:30 INFO - PROCESS | 1647 | 21:06:30 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:38488.62 Median:35159.06 stddev:109034.33 (310.1%) stddev-sans-first:109302.53 21:06:30 INFO - PROCESS | 1647 | Values: 316.7 359.3 369.8 383.7 402.9 20392.4 21059.7 21084.4 21964.3 22147.0 20932.8 21065.5 21152.9 21457.7 21548.6 4767.4 5201.5 5908.6 5986.9 6232.4 4175.9 4438.4 6133.1 6182.3 6191.1 4443.6 6186.6 6816.1 6868.7 6899.9 5506.0 6646.9 6698.5 6811.1 6827.5 5910.5 5918.9 5925.4 5932.8 5937.9 26721.3 26998.3 27491.0 27822.3 27844.3 2051.0 2165.4 2225.8 2550.2 2741.7 12943.3 17026.8 17157.0 17297.4 17565.6 1686.0 2010.2 2076.0 2110.5 2163.6 128260.0 144422.0 147042.0 148606.3 148905.0 21227.0 21629.6 21735.5 21798.8 21913.5 28685.9 35133.8 35184.3 35265.1 35307.5 10642.6 10846.9 11057.8 11074.3 11253.8 9062.4 9066.2 9101.5 9277.8 9279.2 7964.4 9527.7 9795.4 9948.0 9953.5 3339.1 3649.5 3834.3 3841.7 3842.2 370.3 380.4 388.6 835.5 892.9 11578.0 11654.0 11824.6 11964.6 11984.5 353.0 427.5 435.9 438.0 868.7 10690.4 10722.3 10810.7 11205.0 11372.7 634827.0 646573.0 647685.0 661007.0 668273.0 126577.0 134429.0 135340.0 137460.9 140953.0 428.6 449.6 451.7 454.3 454.6 11110.0 11189.3 11246.4 11421.2 11522.3 53715.7 55485.7 56201.0 57758.0 57793.7 82103.2 82826.6 83411.2 83557.7 83888.0 7759.5 7816.3 7970.2 8028.5 8072.1 9625.0 12181.4 12354.1 12524.9 12838.6 10712.5 10749.3 10865.0 11005.9 11006.0 9523.8 10432.3 10676.7 10912.4 10967.3 10851.5 10988.7 10992.7 11124.7 11178.3 8115.3 8178.6 8319.6 8351.7 8372.6 8327.5 10508.7 10887.6 11311.4 11321.4 21:06:30 INFO - PROCESS | 1647 | 21:06:30 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5634.02 Median:5011.00 stddev:3170.68 (63.3%) stddev-sans-first:3162.75 21:06:30 INFO - PROCESS | 1647 | Values: 1287.9 1303.9 1345.0 1603.9 1870.6 7491.9 7810.3 8539.0 8774.6 8811.9 8661.5 8752.8 8764.0 8765.2 8803.1 2373.9 2396.9 2397.5 2397.6 2400.0 2334.8 2378.8 2387.5 2389.6 2391.2 2156.4 2184.8 2351.9 2374.7 2377.9 2339.7 2348.9 2360.5 2365.2 2365.3 2312.7 2337.4 2337.8 2341.4 2348.0 8701.4 8720.1 8766.6 8812.8 8849.1 5188.8 5395.8 5402.9 5404.9 5410.5 3657.5 3663.5 3729.5 3730.0 3731.0 1059.2 1062.0 1077.4 1090.7 1094.3 11640.7 11690.1 11806.9 11819.6 11835.0 3870.5 4351.7 4574.2 4622.8 4638.0 9081.3 9087.4 9093.6 9099.8 9103.5 5199.0 5209.2 5214.2 5216.2 5232.4 4312.2 4394.5 4569.9 4777.1 4877.1 3737.6 3758.5 3817.7 3832.3 3855.3 984.3 997.0 1172.2 1183.5 1185.3 903.4 922.8 931.7 937.4 976.8 5724.3 5878.8 5906.6 5974.2 6231.7 435.2 439.4 440.8 444.5 454.2 3987.5 3988.0 4073.1 4102.1 4138.1 10218.2 10289.3 10602.2 10772.1 10923.7 10613.1 10885.7 10889.4 10893.6 10933.1 7902.4 8252.3 8257.9 8280.7 8288.2 5953.9 5966.0 6085.8 6133.6 6159.3 9261.2 10089.4 10092.2 10132.9 10140.4 8362.8 8838.3 9483.4 9489.6 10422.0 6960.4 7334.5 7485.9 7544.6 7552.0 8125.9 8137.2 8144.3 8232.3 8232.9 7394.0 7417.8 7464.1 7526.0 7563.3 7267.6 7274.0 7294.7 7443.9 7493.6 6744.5 6907.8 6964.1 6980.2 6986.7 4981.4 5040.6 5067.3 5152.1 5270.5 4671.5 4961.1 5075.8 5096.8 5106.2 21:06:30 INFO - PROCESS | 1647 | 21:06:30 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4360.00 Median:4917.52 stddev:1350.78 (27.5%) stddev-sans-first:1319.28 21:06:30 INFO - PROCESS | 1647 | Values: 272.7 293.4 299.9 310.1 311.4 4577.0 4758.1 5108.0 5184.8 5212.3 4824.4 4853.7 4916.8 5023.1 5150.8 3951.8 3965.0 3966.6 3991.7 4022.7 3945.4 3972.0 3993.1 3997.5 4019.1 3585.5 3693.7 3888.8 3943.0 3996.5 3234.3 3285.5 3831.6 3913.4 3954.9 3249.4 3394.2 3426.5 3434.1 3483.0 3894.7 4259.8 4918.2 4940.3 4958.9 3317.2 3346.4 3935.3 4113.3 4119.2 5441.9 5458.7 5461.2 5488.4 5492.4 3630.9 3658.2 3669.4 3680.0 3697.6 5344.4 5542.4 5577.8 5601.5 5676.7 3005.8 3726.9 3736.3 3737.2 3802.9 3358.8 3819.4 3822.9 3831.1 3845.3 3423.9 4375.8 4450.5 4451.0 4453.9 4855.8 5262.6 5445.8 5464.9 5510.9 1140.3 1282.4 1294.8 1297.9 1309.7 774.3 778.7 835.2 856.9 888.6 4579.1 4907.0 4984.3 4993.9 5020.0 4569.2 4591.6 5079.2 5531.0 5570.8 4521.2 4730.0 4742.9 4759.5 4779.4 2585.3 2858.3 2878.4 2886.7 2913.5 5684.7 5696.9 5716.8 5730.7 5761.8 4574.3 5467.3 5572.4 5670.5 5773.3 5042.8 5248.8 5766.5 5771.6 5785.4 4901.3 5233.3 5297.1 5504.9 5640.9 4185.7 5032.9 5111.5 5258.3 5484.9 5197.7 5237.3 5489.9 5769.5 5776.1 3621.6 3722.4 3743.2 3754.1 3786.9 5507.6 5511.6 5545.1 5567.0 5591.3 5356.0 5357.9 5361.9 5431.5 5472.5 4635.9 5169.1 5413.6 5443.8 5449.6 5286.4 5292.7 5299.8 5317.3 5335.7 5154.3 5219.3 5358.8 5449.3 5509.9 5363.2 5376.2 5395.0 5412.2 5419.2 21:06:30 INFO - PROCESS | 1647 | 21:06:30 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:24341.90 Median:4162.59 stddev:38735.29 (930.6%) stddev-sans-first:38803.35 21:06:30 INFO - PROCESS | 1647 | Values: 720.9 724.8 750.2 809.4 829.2 13445.9 16680.7 16689.5 16690.7 16703.2 15011.2 15230.4 15256.0 15268.6 15272.4 667.2 686.3 826.4 834.1 854.7 830.9 839.8 841.3 841.5 845.4 905.8 905.9 905.9 906.4 908.3 897.2 897.9 898.7 898.9 899.0 515.7 655.1 669.2 671.8 674.1 14877.6 14893.8 14910.0 14913.3 14916.4 12232.8 13479.3 14152.9 14208.0 14379.0 12401.9 12468.0 12708.2 13178.0 13385.9 4554.7 4571.8 4598.0 4800.8 4804.7 108102.0 116646.0 116719.4 118007.0 119179.0 11129.7 11245.5 11331.4 11346.7 11514.4 22263.7 22274.0 22326.4 22335.9 22362.8 7869.5 8139.9 8160.1 8223.3 8313.6 4359.9 4493.9 4542.4 4583.1 4688.0 3381.3 3458.7 3510.1 3514.6 3524.3 647.3 654.2 654.5 662.1 670.5 569.5 589.4 601.9 602.2 633.9 7760.0 8814.8 9001.7 9030.0 9190.7 537.9 561.1 568.7 612.8 623.8 4055.9 4269.3 4391.9 4400.6 4405.6 124930.0 130380.0 164272.0 168470.0 173257.0 93374.5 114822.0 119679.0 121211.0 125151.4 70094.0 70506.0 99245.5 103089.0 106795.0 9264.3 9523.0 9634.9 9952.6 9964.0 45526.8 48628.8 63327.7 64265.7 66108.0 95071.5 99365.5 99385.0 99640.0 101078.5 13177.3 13234.3 13246.7 13677.9 13902.2 14532.9 14689.9 14828.8 14976.2 15121.9 9366.4 12210.6 13774.0 14021.4 14585.1 13814.4 13918.3 14015.5 14217.9 14622.3 12259.1 12362.3 12813.0 13284.9 13330.5 10344.7 11385.4 11684.7 11765.4 12001.7 9483.8 11199.1 11442.7 11688.0 11697.6 21:06:30 INFO - PROCESS | 1647 | -------- Summary: end -------- 21:06:30 INFO - PROCESS | 1647 | 21:06:30 INFO - TEST-INFO | 1647: exit 0 21:06:30 INFO - TEST-OK | dromaeo_css | took 1118681ms 21:06:30 INFO - TEST-START | kraken 21:06:30 INFO - operating with platform_type : mac_ 21:06:30 INFO - Initialising browser for kraken test... 21:06:30 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpiC4mLI/profile http://localhost:49263/getInfo.html) 21:06:33 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 21:06:33 INFO - PROCESS | 1654 | colorDepth:24 21:06:33 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 21:06:33 INFO - PROCESS | 1654 | __metrics 21:06:33 INFO - PROCESS | 1654 | 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" 21:06:33 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:06:34 INFO - TEST-INFO | 1654: exit 0 21:06:34 INFO - Browser initialized. 21:06:34 INFO - Running cycle 1/1 for kraken test... 21:06:34 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpiC4mLI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:06:46 INFO - PROCESS | 1655 | RSS: Main: 228179968 21:06:46 INFO - PROCESS | 1655 | 21:06:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection) 21:06:49 INFO - PROCESS | 1655 | RSS: Main: 285134848 21:06:49 INFO - PROCESS | 1655 | 21:06:52 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-dft) 21:06:52 INFO - PROCESS | 1655 | RSS: Main: 293789696 21:06:52 INFO - PROCESS | 1655 | 21:06:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-fft) 21:06:57 INFO - PROCESS | 1655 | RSS: Main: 388988928 21:06:57 INFO - PROCESS | 1655 | 21:06:58 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator) 21:06:59 INFO - PROCESS | 1655 | RSS: Main: 290328576 21:06:59 INFO - PROCESS | 1655 | 21:07:01 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:07:02 INFO - PROCESS | 1655 | RSS: Main: 294645760 21:07:02 INFO - PROCESS | 1655 | 21:07:05 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom) 21:07:05 INFO - PROCESS | 1655 | RSS: Main: 332689408 21:07:05 INFO - PROCESS | 1655 | 21:07:08 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate) 21:07:09 INFO - PROCESS | 1655 | RSS: Main: 331599872 21:07:09 INFO - PROCESS | 1655 | 21:07:12 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial) 21:07:12 INFO - PROCESS | 1655 | RSS: Main: 330428416 21:07:12 INFO - PROCESS | 1655 | 21:07:14 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:07:15 INFO - PROCESS | 1655 | RSS: Main: 300621824 21:07:15 INFO - PROCESS | 1655 | 21:07:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:07:17 INFO - PROCESS | 1655 | RSS: Main: 307249152 21:07:17 INFO - PROCESS | 1655 | 21:07:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:07:20 INFO - PROCESS | 1655 | RSS: Main: 309428224 21:07:20 INFO - PROCESS | 1655 | 21:07:23 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:07:24 INFO - PROCESS | 1655 | RSS: Main: 314646528 21:07:24 INFO - PROCESS | 1655 | 21:07:27 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:07:27 INFO - PROCESS | 1655 | RSS: Main: 317128704 21:07:27 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49263/tests/kraken/driver.html?testName=ai-astar) 21:07:29 INFO - PROCESS | 1655 | RSS: Main: 361009152 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | __start_tp_report 21:07:29 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 21:07:29 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 21:07:29 INFO - PROCESS | 1655 | |i|pagename|runs| 21:07:29 INFO - PROCESS | 1655 | |0;ai-astar;102;112;113;114;107;114;112;112;114;113 21:07:29 INFO - PROCESS | 1655 | |1;audio-beat-detection;142;113;109;164;109;107;128;111;132;132 21:07:29 INFO - PROCESS | 1655 | |2;audio-dft;183;186;184;184;185;180;183;183;182;184 21:07:29 INFO - PROCESS | 1655 | |3;audio-fft;78;58;61;88;59;58;66;68;84;59 21:07:29 INFO - PROCESS | 1655 | |4;audio-oscillator;86;92;93;71;70;68;96;71;68;107 21:07:29 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;88;85;88;86;88;88;88;86;88;83 21:07:29 INFO - PROCESS | 1655 | |6;imaging-darkroom;132;108;94;103;141;93;94;94;92;93 21:07:29 INFO - PROCESS | 1655 | |7;imaging-desaturate;83;86;86;85;85;83;102;142;136;87 21:07:29 INFO - PROCESS | 1655 | |8;json-parse-financial;56;53;54;53;52;56;52;53;54;53 21:07:29 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;62;71;77;72;70;68;69;67;69;69 21:07:29 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;89;86;88;85;87;89;89;89;87;83 21:07:29 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;135;138;137;132;138;148;150;153;217;139 21:07:29 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;169;171;175;234;173;169;169;168;231;216 21:07:29 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;68;68;68;66;67;66;67;68;68;67 21:07:29 INFO - PROCESS | 1655 | __end_tp_report 21:07:29 INFO - PROCESS | 1655 | __start_cc_report 21:07:29 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2134 21:07:29 INFO - PROCESS | 1655 | __end_cc_report 21:07:29 INFO - PROCESS | 1655 | __startTimestamp1471493249833__endTimestamp 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | ------- Summary: start ------- 21:07:29 INFO - PROCESS | 1655 | Number of tests: 14 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:111.30 Median:113.00 stddev:3.86 (3.4%) stddev-sans-first:2.18 21:07:29 INFO - PROCESS | 1655 | Values: 102.0 112.0 113.0 114.0 107.0 114.0 112.0 112.0 114.0 113.0 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:124.70 Median:130.00 stddev:18.54 (14.3%) stddev-sans-first:18.57 21:07:29 INFO - PROCESS | 1655 | Values: 142.0 113.0 109.0 164.0 109.0 107.0 128.0 111.0 132.0 132.0 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.65 (0.9%) stddev-sans-first:1.74 21:07:29 INFO - PROCESS | 1655 | Values: 183.0 186.0 184.0 184.0 185.0 180.0 183.0 183.0 182.0 184.0 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:67.90 Median:67.00 stddev:11.41 (17.0%) stddev-sans-first:11.50 21:07:29 INFO - PROCESS | 1655 | Values: 78.0 58.0 61.0 88.0 59.0 58.0 66.0 68.0 84.0 59.0 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:82.20 Median:78.50 stddev:14.28 (18.2%) stddev-sans-first:15.08 21:07:29 INFO - PROCESS | 1655 | Values: 86.0 92.0 93.0 71.0 70.0 68.0 96.0 71.0 68.0 107.0 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:88.00 stddev:1.75 (2.0%) stddev-sans-first:1.80 21:07:29 INFO - PROCESS | 1655 | Values: 88.0 85.0 88.0 86.0 88.0 88.0 88.0 86.0 88.0 83.0 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:104.40 Median:93.00 stddev:17.81 (19.1%) stddev-sans-first:15.84 21:07:29 INFO - PROCESS | 1655 | Values: 132.0 108.0 94.0 103.0 141.0 93.0 94.0 94.0 92.0 93.0 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:97.50 Median:85.00 stddev:22.58 (26.6%) stddev-sans-first:23.34 21:07:29 INFO - PROCESS | 1655 | Values: 83.0 86.0 86.0 85.0 85.0 83.0 102.0 142.0 136.0 87.0 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:53.50 stddev:1.43 (2.7%) stddev-sans-first:1.22 21:07:29 INFO - PROCESS | 1655 | Values: 56.0 53.0 54.0 53.0 52.0 56.0 52.0 53.0 54.0 53.0 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:69.50 stddev:3.81 (5.5%) stddev-sans-first:2.95 21:07:29 INFO - PROCESS | 1655 | Values: 62.0 71.0 77.0 72.0 70.0 68.0 69.0 67.0 69.0 69.0 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:88.50 stddev:2.04 (2.3%) stddev-sans-first:2.06 21:07:29 INFO - PROCESS | 1655 | Values: 89.0 86.0 88.0 85.0 87.0 89.0 89.0 89.0 87.0 83.0 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:143.50 stddev:24.97 (17.4%) stddev-sans-first:25.99 21:07:29 INFO - PROCESS | 1655 | Values: 135.0 138.0 137.0 132.0 138.0 148.0 150.0 153.0 217.0 139.0 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.50 Median:174.00 stddev:27.71 (15.9%) stddev-sans-first:28.57 21:07:29 INFO - PROCESS | 1655 | Values: 169.0 171.0 175.0 234.0 173.0 169.0 169.0 168.0 231.0 216.0 21:07:29 INFO - PROCESS | 1655 | 21:07:29 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:0.82 (1.2%) stddev-sans-first:0.83 21:07:29 INFO - PROCESS | 1655 | Values: 68.0 68.0 68.0 66.0 67.0 66.0 67.0 68.0 68.0 67.0 21:07:29 INFO - PROCESS | 1655 | -------- Summary: end -------- 21:07:29 INFO - PROCESS | 1655 | 21:07:30 INFO - TEST-INFO | 1655: exit 0 21:07:30 INFO - TEST-OK | kraken | took 59955ms 21:07:30 INFO - SUITE-END | took 1178s 21:07:30 INFO - Completed test suite (00:19:38) 21:07:30 INFO - javascript benchmark 21:07:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3373.578302712161, 3401.234722187508, 3406.148462884279, 3411.2495251044775, 3466.0641626760776, 5654.208410822488, 5721.170430287611, 5794.362620111784, 5815.505682017028, 5837.7373018214175, 5684.289463817023, 5748.511376533, 5754.769809207627, 5781.653100813955, 5835.124731290309, 5192.688438693672, 5238.947610523903, 5272.182811664198, 5277.973610131963, 5362.2224777407255, 4421.801018954138, 5366.677999320033, 5377.4084850666395, 5379.0, 5398.4871437213405, 5168.974155129238, 5222.0, 5222.556082732935, 5248.632595718283, 5332.280142180794, 4334.544872788375, 5280.577553795725, 5281.735913204325, 5290.338707661543, 5295.285136506599, 4326.394304797331, 4361.0, 4747.905041899143, 5228.633995620308, 5282.99623071619, 5692.971535142297, 5762.193292938992, 5764.991126552878, 5820.592558520905, 5834.474897259267, 6217.533684973645, 6291.087792270096, 6295.2760432550385, 6299.559030867841, 6305.8108256752375, 6161.7535298588, 6184.5980011299125, 6237.594556353823, 6315.336889626569, 6319.273283572402, 5902.203202567642, 5939.554533410012, 5945.316288626776, 5952.375000624958, 5954.4045595440975, 6326.177596912372, 6370.331115232926, 6392.936070639327, 6409.647469389229, 6413.935860641333, 6062.030075187949, 6130.9883869161085, 6190.0, 6203.8138855834395, 6227.688615569204, 6314.589551679126, 6319.0, 6323.841903952438, 6332.271788744353, 6336.556441049082, 6280.466160376419, 6282.4974002079725, 6284.088807123033, 6306.747730090745, 6310.211223597051, 5563.443655634404, 5879.970600146972, 6172.22847144107, 6199.3490683478485, 6252.062190671436, 4959.256111583291, 4972.0, 4986.301917731521, 5002.499750025041, 5045.798168073309, 4452.910941781146, 4463.977680111579, 4472.284434490466, 4484.820607175677, 4512.81948722054, 5664.0, 5710.000749868839, 5730.570207234474, 5777.826665200051, 5783.363829978699, 6197.96901015492, 6282.465990390868, 6286.622802631857, 6299.874002519924, 6302.212223472066, 5729.598928074995, 5776.653400795965, 5794.536437085023, 5802.448767367094, 5809.099589563624, 5439.972800135974, 5510.8071217507195, 5511.751971161308, 5516.172574113915, 5521.365043020024, 6419.0, 6433.4209921106385, 6436.259830119502, 6447.709853056624, 6472.482201423781, 6148.739508400857, 6152.323244443018, 6217.658028808459, 6266.717997690206, 6294.842628934313, 6386.584871983307, 6461.676916154268, 6464.0, 6468.3855033771715, 6473.579217350857, 6235.750569977332, 6262.68686565679, 6274.780382686584, 6274.811755647338, 6278.058291256348, 6140.87237156979, 6210.782622608187, 6244.344343844013, 6271.62370257786, 6273.37266273369, 6096.390360963868, 6171.363677116722, 6210.720517576629, 6219.626822390671, 6243.469305109116, 6140.692965351804, 6184.443400094013, 6188.783392581238, 6198.597091189239, 6204.255489341127, 5781.8554536136935, 5915.142304365757, 5974.342822289458, 5976.8804623908145, 6026.698665066817, 6138.662373569497, 6171.352008039091, 6179.104029915727, 6190.845228869314, 6195.659238741912, 5600.131979543177, 5997.590566217023, 6009.639421634716, 6030.7889223876955, 6052.03167493199], "value": 5735.221080768058, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.7472577445292, 514.354485121173, 519.195247366582, 530.7372850439033, 547.3076558153933, 29617.407651846977, 29955.700442995592, 31251.062468125936, 31252.843735781316, 39003.60996390035, 33094.00717978458, 41743.58256417442, 41774.37338439926, 41802.0, 41843.16313673725, 9569.856452153206, 9747.707568772912, 9753.707388778348, 9767.951160244189, 9770.51147442625, 8628.223459888608, 8826.779330516723, 9601.231901447867, 9725.756856078619, 9729.951350243275, 11663.183577149603, 11949.641510754647, 11967.102467314942, 12154.0276777858, 12170.0, 11862.52549898003, 11933.224340417844, 11942.984846288076, 11947.311854832637, 11948.522059117626, 11712.355820429873, 11739.178257521977, 11760.882391176065, 11773.117016223818, 11779.234349767237, 38787.80606096979, 38973.22053558913, 38984.22031559381, 39033.41449878254, 39040.0, 3385.0, 3814.8855534334016, 4074.4092106644666, 4078.938815917764, 4235.57644235577, 30757.538636920464, 30833.229169270722, 30856.0, 30896.0, 31042.068737937672, 3115.5949726535405, 3273.214428537134, 3341.732661387083, 3342.7325813934826, 3349.179451034512, 490743.0, 492648.0735192638, 493299.9999999964, 494032.0, 500492.0, 55987.88024239574, 56029.0, 56191.43808561944, 56272.71863640656, 56408.0, 58715.0, 58855.705721471975, 58923.0, 58923.41076589179, 59083.0, 15802.36790528389, 15970.281337339848, 15982.520524384287, 15994.280257388446, 16060.43788467398, 12520.81218781719, 12584.622461326175, 12603.558875439316, 12611.243325400505, 12622.368881555885, 13393.66515837112, 13766.65583360404, 14844.851551484562, 15199.960007998445, 15370.769438458432, 5903.940960590425, 7125.465590080765, 7129.506541794587, 7202.567845929261, 7228.277172282835, 885.9557022148866, 907.2469850024464, 937.231470194434, 956.8134213828375, 1064.1752641702587, 27757.0, 29057.56413653797, 29224.853875730485, 29930.25174370658, 30084.84957575198, 963.4700914497139, 1079.7192729890128, 1083.7561548651647, 1089.1395797320188, 1112.165875593305, 14431.2062836545, 15284.235788210544, 15330.540083797503, 15338.619524242871, 15773.763393549107, 669418.0, 669481.0, 670938.0, 671362.0, 671897.0, 57071.0, 57221.42778572161, 57254.0, 57362.42637573654, 57409.0, 1199.9040076793835, 1241.509603706531, 1241.8696036915994, 1349.7165595224917, 1375.2985977151725, 23485.295441136794, 30141.547876781427, 30937.381252374376, 31859.840700796347, 32241.35517289688, 51647.0, 52158.21762673487, 52231.0, 52366.21450678319, 52374.73812630912, 590477.1904561799, 595928.0, 596239.0188049031, 596619.0, 597274.0, 25839.483210336064, 26183.73816261813, 26449.60325595079, 27142.0, 38425.5417012459, 32380.0, 38353.80823095867, 38904.416433754086, 46834.29748553706, 48222.51777482321, 37455.625443745215, 38225.04437389087, 38474.96012918603, 39061.804690976365, 39400.40899386569, 32420.83789581037, 37938.62061379351, 38693.0, 38878.805605971786, 39196.608033920435, 36375.0, 36805.44791828071, 37317.44023839699, 37477.6252237485, 37563.87308380753, 33493.49759753557, 33589.66410335866, 34288.142796430286, 34431.0, 34470.82764586161, 24947.87526062358, 25034.0, 25110.74889251084, 25703.357416064748, 25746.485070298113], "value": 19902.991585155858, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.6611725453765, 359.3262632563941, 369.82987825600225, 383.7467271600744, 402.949631296088, 20392.388228353153, 21059.736415815063, 21084.367468975935, 21964.341069767932, 22147.0, 20932.848693321863, 21065.47336316593, 21152.894235528838, 21457.67813482795, 21548.56902861946, 4767.380240568721, 5201.505856943584, 5908.615939963899, 5986.918582896364, 6232.439080482755, 4175.895602609944, 4438.423005009345, 6133.11069894865, 6182.319944806068, 6191.071339299118, 4443.5556444355625, 6186.63084164464, 6816.079829223041, 6868.7252509899545, 6899.862002759942, 5505.953868764922, 6646.867062658768, 6698.4641228701585, 6811.114555107853, 6827.48793840464, 5910.497607703351, 5918.852028699274, 5925.377835327315, 5932.822015339547, 5937.940620593782, 26721.331966700793, 26998.325041873915, 27491.0, 27822.3044423889, 27844.303892402648, 2051.0360130738527, 2165.4261620670536, 2225.799678028985, 2550.2094350751327, 2741.684706258786, 12943.288119153443, 17026.829731702772, 17157.0, 17297.394591189248, 17565.648687026187, 1685.9578510537212, 2010.1758279105497, 2075.989620051905, 2110.482931681732, 2163.610550100981, 128259.96750406199, 144422.0, 147042.0, 148606.25696871555, 148905.0, 21227.0, 21629.56740865174, 21735.456613584785, 21798.782012179676, 21913.452163696034, 28685.85256589713, 35133.82433087869, 35184.29631407409, 35265.11837204039, 35307.4703879442, 10642.574297028186, 10846.94576527128, 11057.834132488018, 11074.335539867634, 11253.831192532118, 9062.378187124483, 9066.22937050345, 9101.499417532099, 9277.814443711088, 9279.164875161268, 7964.442489025712, 9527.714168574954, 9795.412275263507, 9948.0, 9953.452560109263, 3339.148517128117, 3649.464090847946, 3834.2906562286066, 3841.654251117413, 3842.2315536892734, 370.2613286493463, 380.3856771314307, 388.646331838026, 835.4987007795396, 892.8839250897341, 11578.0, 11654.0, 11824.645260642195, 11964.64106076819, 11984.460699268553, 352.99294014119573, 427.5382586806304, 435.9149965756647, 438.0, 868.662260119409, 10690.363088726766, 10722.26432665107, 10810.675679729464, 11204.991550760471, 11372.658820235407, 634827.0, 646573.0, 647685.0, 661007.0, 668273.0, 126577.0, 134429.0, 135340.0, 137460.94121058483, 140953.0, 428.5692878656992, 449.566168647257, 451.67028069509445, 454.2708952131847, 454.58405558913887, 11110.0, 11189.272697274651, 11246.381449020382, 11421.172612381979, 11522.251053681484, 53715.73142134264, 55485.7225713885, 56201.0, 57758.0, 57793.711031444574, 82103.17896820956, 82826.58586707027, 83411.16588834034, 83557.74663379932, 83887.99736040924, 7759.495632783851, 7816.257455541714, 7970.163132871197, 8028.4781489203015, 8072.112067672669, 9624.951875240858, 12181.39093045362, 12354.135210535536, 12524.937375313066, 12838.614841554769, 10712.464376781285, 10749.301295415773, 10864.967828056322, 11005.889941100806, 11006.0, 9523.809523809347, 10432.269741118049, 10676.733081673021, 10912.399818010086, 10967.287126336763, 10851.457427128455, 10988.670339889815, 10992.670219893416, 11124.721881953015, 11178.329300242012, 8115.310198633182, 8178.591070446335, 8319.584020798815, 8351.665933362598, 8372.581370931308, 8327.541985190872, 10508.737281568023, 10887.564497420015, 11311.377874216998, 11321.433928303388], "value": 9927.239368457353, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1287.922724636522, 1303.876131767482, 1345.0, 1603.9037657740537, 1870.551067743742, 7491.925080749191, 7810.297073263405, 8539.018012928515, 8774.561271936409, 8811.911880881189, 8661.480311181334, 8752.78118047049, 8764.0, 8765.211130998208, 8803.075677053914, 2373.857568545884, 2396.8921398537022, 2397.5324811661735, 2397.5684376812255, 2400.0039983406864, 2334.8015418689433, 2378.7740164684415, 2387.510560335127, 2389.6296074108454, 2391.246757271454, 2156.4069880782754, 2184.8252139828855, 2351.8588884666888, 2374.7387787343378, 2377.9167729129485, 2339.6841426407386, 2348.906043758256, 2360.4806942472715, 2365.219477572397, 2365.3022358404314, 2312.7264112764, 2337.357226762637, 2337.812974961999, 2341.4497593065616, 2347.969006809115, 8701.390902636818, 8720.127987201293, 8766.561671916379, 8812.779680507974, 8849.115088491164, 5188.8183913563225, 5395.811146609889, 5402.864928376783, 5404.891902161935, 5410.513053825174, 3657.5428071491065, 3663.468797024443, 3729.533808273966, 3730.0301921500336, 3731.0, 1059.1685526861454, 1062.0, 1077.4289626497969, 1090.7491277006334, 1094.3324572011068, 11640.650780476599, 11690.123240756977, 11806.940965295118, 11819.64541063778, 11835.0, 3870.4968354113785, 4351.673624478177, 4574.199515084913, 4622.815087396467, 4638.002829391697, 9081.273498120134, 9087.363884528151, 9093.636254549743, 9099.81800363989, 9103.499307538017, 5199.0, 5209.192575150858, 5214.227162764678, 5216.21756736485, 5232.39827419844, 4312.202242585132, 4394.516603173648, 4569.908601827945, 4777.116233496814, 4877.12211801879, 3737.588865224823, 3758.548974123068, 3817.732758706864, 3832.2527107213828, 3855.267499175148, 984.3011461862158, 996.9501524923725, 1172.202902026613, 1183.5206741269799, 1185.2532904270427, 903.4443817052597, 922.796984663373, 931.7344556801279, 937.3689779783128, 976.8192884316425, 5724.341700704473, 5878.764849405976, 5906.586538942232, 5974.163617093611, 6231.688415579203, 435.1536261970437, 439.3760859579405, 440.79943625650253, 444.4977175791335, 454.2255454450154, 3987.5414327352146, 3988.0, 4073.1446396256524, 4102.097538541516, 4138.110306284163, 10218.233632477593, 10289.3311934724, 10602.151827853753, 10772.138228941665, 10923.672289831158, 10613.097886679565, 10885.727856803485, 10889.401082940514, 10893.618723344804, 10933.070688991365, 7902.3678105751405, 8252.33981281496, 8257.876131858027, 8280.71017514384, 8288.171182881664, 5953.880922381441, 5966.0454327307425, 6085.817425477243, 6133.631982080911, 6159.322474527888, 9261.212796912338, 10089.445080520642, 10092.192624589869, 10132.898671013196, 10140.442275674908, 8362.832743345221, 8838.337124715543, 9483.383580067273, 9489.57296921654, 10422.0, 6960.408365289007, 7334.486585939147, 7485.887711684438, 7544.6227688616445, 7552.018237629293, 8125.918740812753, 8137.186281371815, 8144.348452123697, 8232.259096681328, 8232.876506852283, 7393.963030184815, 7417.777466676009, 7464.104307483137, 7526.0, 7563.319301262913, 7267.6366181691765, 7273.9636301818155, 7294.702810246207, 7443.851122977619, 7493.587852667937, 6744.460443164633, 6907.827304317432, 6964.094667693167, 6980.197277313174, 6986.720531178696, 4981.352424184833, 5040.647154699136, 5067.290579318972, 5152.098382783073, 5270.49930256625, 4671.462781780138, 4961.107000739901, 5075.766662668414, 5096.796128154832, 5106.208537676666], "value": 4444.713645303003, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.7436209962635, 293.4395304967513, 299.93224122125235, 310.11462275204303, 311.38967623458024, 4576.97711511442, 4758.143534163849, 5108.0, 5184.844454666361, 5212.348456442945, 4824.445188803289, 4853.660243782937, 4916.80332786689, 5023.146065168921, 5150.793968241275, 3951.802409879509, 3964.9976761735284, 3966.643002129808, 3991.700622453313, 4022.738521996061, 3945.4081887716784, 3971.960280397188, 3993.0650971535897, 3997.5402828674783, 4019.1157945252, 3585.4801053847177, 3693.722970777189, 3888.7555982085746, 3943.0, 3996.520417549898, 3234.304624505719, 3285.4578994466005, 3831.5976822433663, 3913.3542965410675, 3954.9123990902444, 3249.447593909041, 3394.1684287349512, 3426.5374174486374, 3434.055634700452, 3482.9651703483146, 3894.6563435614667, 4259.76571288579, 4918.2376731606655, 4940.308356830046, 4958.87602809929, 3317.1872891141584, 3346.4311067118647, 3935.330993731073, 4113.280175969194, 4119.198944717591, 5441.918371224395, 5458.699771512565, 5461.2354270402175, 5488.396276409591, 5492.423295553949, 3630.8729194478333, 3658.2134841009047, 3669.412893936984, 3679.951213904051, 3697.5932647408763, 5344.385395679507, 5542.445755424425, 5577.776888924438, 5601.535324457226, 5676.659400435987, 3005.8497075146156, 3726.919193433928, 3736.252749450121, 3737.2338670572467, 3802.885913422602, 3358.7648864579733, 3819.4461803038403, 3822.9235415292096, 3831.080540670275, 3845.3462911304587, 3423.9144021399666, 4375.8030888609455, 4450.5104438511735, 4450.955490445054, 4453.933191002073, 4855.8057677693205, 5262.60530460217, 5445.782168713286, 5464.863378415492, 5510.889782204334, 1140.3158105137018, 1282.352412031918, 1294.7539967406162, 1297.9026573006875, 1309.6641425745877, 774.2567135549823, 778.7118766056482, 835.1690068381878, 856.8928883889514, 888.6445421831319, 4579.061292435043, 4907.037185125907, 4984.252362145635, 4993.850184494471, 5020.017093248147, 4569.223232050559, 4591.632669386441, 5079.187330027178, 5531.0, 5570.832875013756, 4521.208788462006, 4729.976350118227, 4742.857714268577, 4759.4764575896625, 4779.354787103766, 2585.2890455124616, 2858.3140046388717, 2878.4387044526534, 2886.729838870932, 2913.533834586456, 5684.658920464785, 5696.943030569724, 5716.79991200306, 5730.713464326767, 5761.827145185651, 4574.255446732001, 5467.316585426822, 5572.3870374258795, 5670.489655930904, 5773.268019594105, 5042.773075211624, 5248.845254044104, 5766.500709815395, 5771.567132465082, 5785.421457854265, 4901.313816065683, 5233.345831771028, 5297.125974214211, 5504.91742623869, 5640.85897852557, 4185.748855068706, 5032.949670503248, 5111.539961403491, 5258.342707161605, 5484.862878428071, 5197.740112994259, 5237.324056302099, 5489.92946375453, 5769.53843692512, 5776.104703770922, 3621.5835178953703, 3722.4416337549583, 3743.1577894974503, 3754.0614846288427, 3786.8674596388996, 5507.5593952484505, 5511.614187006871, 5545.08882836514, 5566.97216513931, 5591.329040515164, 5355.973220133875, 5357.901630165885, 5361.919571206513, 5431.456854314537, 5472.534834538949, 4635.860924172281, 5169.147090729987, 5413.648112872651, 5443.8094666686475, 5449.591280653887, 5286.418493965738, 5292.708901010481, 5299.7615107321035, 5317.282166907418, 5335.67985920846, 5154.278401023935, 5219.2953951216095, 5358.785648574013, 5449.291592093003, 5509.906235630435, 5363.222332761806, 5376.220448035091, 5395.028894798974, 5412.242286079873, 5419.160030195326], "value": 3928.3507795022288, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.8522252938149, 724.8006798130514, 750.2047829300941, 809.3606051219537, 829.1625458287128, 13445.865541344583, 16680.66638667226, 16689.49931502057, 16690.749638755427, 16703.248353824078, 15011.249437528108, 15230.39078436864, 15256.0, 15268.618284542892, 15272.38910443581, 667.2393471442559, 686.2896901706735, 826.3843436639712, 834.1450013735923, 854.7051267312798, 830.9252167304942, 839.8116664919132, 841.3227351981652, 841.4572600672562, 845.4420082745389, 905.7599736069947, 905.868649045888, 905.8867641544807, 906.4380084347681, 908.3278374003256, 897.2149369301861, 897.8698088777156, 898.6764764684708, 898.8786513820615, 898.9999999999967, 515.706047552895, 655.0501772429948, 669.1635455680399, 671.788386658206, 674.0765151742096, 14877.553673389815, 14893.77659335111, 14910.0, 14913.2543372832, 14916.403343866232, 12232.837880401348, 13479.258640774753, 14152.858471415257, 14208.0, 14379.0, 12401.875981240162, 12467.952235113333, 12708.173968692005, 13178.011649126258, 13385.933070334684, 4554.686958158702, 4571.794269257858, 4597.977010114962, 4800.783964721561, 4804.687695299829, 108102.0, 116646.0, 116719.41640291829, 118007.0, 119179.0, 11129.666110016713, 11245.493952772145, 11331.376774277494, 11346.659600212008, 11514.424278786195, 22263.666045009337, 22274.0, 22326.44183895383, 22335.888320558293, 22362.776372236396, 7869.527828330204, 8139.918600814034, 8160.102388737234, 8223.301019413297, 8313.625886835107, 4359.891002724958, 4493.887652808706, 4542.432195975503, 4583.129205450954, 4688.038952014829, 3381.3406385755034, 3458.654134586521, 3510.069831494656, 3514.5606799150105, 3524.2775231077567, 647.2653538234044, 654.1986067067899, 654.5450911616476, 662.1292999705469, 670.4870773858003, 569.4800163836529, 589.4074773656664, 601.9217501724747, 602.2020822410324, 633.9492840572743, 7759.961200193963, 8814.823703525894, 9001.729948101567, 9030.0, 9190.678326258549, 537.873599704077, 561.093833458969, 568.7469076261024, 612.7977767336769, 623.7816764132608, 4055.878323650295, 4269.295566231599, 4391.890202744893, 4400.625946794558, 4405.647548196137, 124930.0, 130380.0, 164272.0, 168470.0, 173257.0, 93374.53312733393, 114822.0, 119679.0, 121211.0, 125151.37424313002, 70094.0, 70506.0, 99245.50377248212, 103089.0, 106795.0, 9264.305177111608, 9523.047695230422, 9634.855477167708, 9952.55213515379, 9964.0, 45526.77236613796, 48628.75685621549, 63327.667233276305, 64265.67867160821, 66108.0, 95071.52464237635, 99365.50317248367, 99385.0, 99640.0, 101078.49460752649, 13177.27524986135, 13234.338283086, 13246.668833279246, 13677.86322136766, 13902.16587004783, 14532.927335363256, 14689.853101469276, 14828.7775683367, 14976.176310303037, 15121.924390377977, 9366.391184572982, 12210.633680989584, 13774.0, 14021.439142434188, 14585.124892506483, 13814.37835297394, 13918.304084795922, 14015.50945716895, 14217.857821421654, 14622.341994610055, 12259.080568957543, 12362.258264504158, 12813.0, 13284.86715132875, 13330.471717418957, 10344.741381465523, 11385.373804440835, 11684.707188454107, 11765.352905590275, 12001.699957500783, 9483.814523184601, 11199.104071674083, 11442.713932151762, 11688.0, 11697.649070527897], "value": 7795.5681620656815, "unit": "score"}], "name": "dromaeo_css", "value": 7323.320573891, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 113.0, 114.0, 107.0, 114.0, 112.0, 112.0, 114.0, 113.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 113.0, 109.0, 164.0, 109.0, 107.0, 128.0, 111.0, 132.0, 132.0], "value": 124.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 184.0, 184.0, 185.0, 180.0, 183.0, 183.0, 182.0, 184.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 61.0, 88.0, 59.0, 58.0, 66.0, 68.0, 84.0, 59.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 92.0, 93.0, 71.0, 70.0, 68.0, 96.0, 71.0, 68.0, 107.0], "value": 82.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 88.0, 88.0, 88.0, 86.0, 88.0, 83.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 108.0, 94.0, 103.0, 141.0, 93.0, 94.0, 94.0, 92.0, 93.0], "value": 104.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 86.0, 85.0, 85.0, 83.0, 102.0, 142.0, 136.0, 87.0], "value": 97.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 54.0, 53.0, 52.0, 56.0, 52.0, 53.0, 54.0, 53.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 71.0, 77.0, 72.0, 70.0, 68.0, 69.0, 67.0, 69.0, 69.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 85.0, 87.0, 89.0, 89.0, 89.0, 87.0, 83.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 138.0, 137.0, 132.0, 138.0, 148.0, 150.0, 153.0, 217.0, 139.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 175.0, 234.0, 173.0, 169.0, 169.0, 168.0, 231.0, 216.0], "value": 187.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 66.0, 67.0, 66.0, 67.0, 68.0, 68.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1471.8999999999999, "alertThreshold": 2.0}]} 21:07:31 INFO - Return code: 0 21:07:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:07:31 INFO - # TBPL SUCCESS # 21:07:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:07:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:07:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:07:31 INFO - 2016-08-17 21:07:31.149 screenresolution[1659:9700] starting screenresolution argv=screenresolution get 21:07:31 INFO - 2016-08-17 21:07:31.152 screenresolution[1659:9700] Display 0: 1600x1200x32@60 21:07:31 INFO - 2016-08-17 21:07:31.158 screenresolution[1660:9703] starting screenresolution argv=screenresolution list 21:07:31 INFO - Available Modes on Display 0 21:07:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:07:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:07:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:07:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:07:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:07:31 INFO - Intel Iris: 21:07:31 INFO - Chipset Model: Intel Iris 21:07:31 INFO - Type: GPU 21:07:31 INFO - Bus: Built-In 21:07:31 INFO - VRAM (Dynamic, Max): 1536 MB 21:07:31 INFO - Vendor: Intel (0x8086) 21:07:31 INFO - Device ID: 0x0a2e 21:07:31 INFO - Revision ID: 0x0009 21:07:31 INFO - Displays: 21:07:31 INFO - Display: 21:07:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:07:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:07:31 INFO - Display Serial Number: 1600x1200 60 21:07:31 INFO - Main Display: Yes 21:07:31 INFO - Mirror: Off 21:07:31 INFO - Online: Yes 21:07:31 INFO - Rotation: Supported 21:07:31 INFO - Return code: 0 21:07:31 INFO - Running post-action listener: _resource_record_post_action 21:07:31 INFO - [mozharness: 2016-08-18 04:07:31.394376Z] Finished run-tests step (success) 21:07:31 INFO - Running post-run listener: _resource_record_post_run 21:07:31 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 38061056; Write bytes: 704529408; Read time: 379; Write time: 2092 21:07:31 INFO - TinderboxPrint: CPU usage
53.2% 21:07:31 INFO - TinderboxPrint: I/O read bytes / time
38,061,056 / 379 21:07:31 INFO - TinderboxPrint: I/O write bytes / time
704,529,408 / 2,092 21:07:31 INFO - TinderboxPrint: CPU idle
2,272.1 (46.8%) 21:07:31 INFO - TinderboxPrint: CPU system
966.3 (19.9%) 21:07:31 INFO - TinderboxPrint: CPU user
1,619.4 (33.3%) 21:07:31 INFO - TinderboxPrint: Swap in / out
443,072,512 / 0 21:07:31 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 399944192; Write bytes: 386722816; Read time: 29645; Write time: 688 21:07:31 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 31030272; Write bytes: 294664192; Read time: 291; Write time: 1358 21:07:31 INFO - Running post-run listener: _upload_blobber_files 21:07:31 INFO - Blob upload gear active. 21:07:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:07:31 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 21:07:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:07:32 INFO - (blobuploader) - INFO - Open directory for files ... 21:07:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:07:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:07:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:07:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:07:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:07:32 INFO - (blobuploader) - INFO - Done attempting. 21:07:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:07:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:07:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:07:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:07:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:07:33 INFO - (blobuploader) - INFO - Done attempting. 21:07:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:07:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:07:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:07:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:07:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:07:36 INFO - (blobuploader) - INFO - Done attempting. 21:07:36 INFO - (blobuploader) - INFO - Iteration through files over. 21:07:36 INFO - Return code: 0 21:07:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:07:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:07:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e69bce692001257e8db3fcb39d044ead55110cff4b1f5721c5b01451eb2a1f90ff197e8181947c1d70f8a3c8df160b4b48549ae48713dc188f8b9f2465d2cd0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4956f463e429389af3b03f12d296b6f85a2f5ea4e4b4e869aeb2af2df1f33f6e7e3d1792834655cc6dc92fec60bf93c1add9daeea1501f36af584ba956c20058", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bb796b1e72ae94d8bc3ff70fbf3860af4141965804950ea2c0d27a88c2dae7e3fdf14d003572c222a02426a9de5aff89a21ff1010f70f5617711d79a8cb0ef64"} 21:07:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:07:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:07:36 INFO - Contents: 21:07:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e69bce692001257e8db3fcb39d044ead55110cff4b1f5721c5b01451eb2a1f90ff197e8181947c1d70f8a3c8df160b4b48549ae48713dc188f8b9f2465d2cd0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4956f463e429389af3b03f12d296b6f85a2f5ea4e4b4e869aeb2af2df1f33f6e7e3d1792834655cc6dc92fec60bf93c1add9daeea1501f36af584ba956c20058", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bb796b1e72ae94d8bc3ff70fbf3860af4141965804950ea2c0d27a88c2dae7e3fdf14d003572c222a02426a9de5aff89a21ff1010f70f5617711d79a8cb0ef64"} 21:07:36 INFO - Running post-run listener: copy_logs_to_upload_dir 21:07:36 INFO - Copying logs to upload dir... 21:07:36 INFO - mkdir: /builds/slave/test/build/upload/logs 21:07:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.946508 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-17 21:07:36.462459) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 21:07:36.465739) ========= 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.YpwcQhCbbf/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.89etxRhU6T/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e69bce692001257e8db3fcb39d044ead55110cff4b1f5721c5b01451eb2a1f90ff197e8181947c1d70f8a3c8df160b4b48549ae48713dc188f8b9f2465d2cd0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4956f463e429389af3b03f12d296b6f85a2f5ea4e4b4e869aeb2af2df1f33f6e7e3d1792834655cc6dc92fec60bf93c1add9daeea1501f36af584ba956c20058", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bb796b1e72ae94d8bc3ff70fbf3860af4141965804950ea2c0d27a88c2dae7e3fdf14d003572c222a02426a9de5aff89a21ff1010f70f5617711d79a8cb0ef64"} build_url:https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010955 build_url: 'https://queue.taskcluster.net/v1/task/LTFGAEPWTGqi2GadF3GrVQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e69bce692001257e8db3fcb39d044ead55110cff4b1f5721c5b01451eb2a1f90ff197e8181947c1d70f8a3c8df160b4b48549ae48713dc188f8b9f2465d2cd0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4956f463e429389af3b03f12d296b6f85a2f5ea4e4b4e869aeb2af2df1f33f6e7e3d1792834655cc6dc92fec60bf93c1add9daeea1501f36af584ba956c20058", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bb796b1e72ae94d8bc3ff70fbf3860af4141965804950ea2c0d27a88c2dae7e3fdf14d003572c222a02426a9de5aff89a21ff1010f70f5617711d79a8cb0ef64"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 21:07:36.497900) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 21:07:36.498209) ========= 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.YpwcQhCbbf/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.89etxRhU6T/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.005204 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 21:07:36.547267) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 21:07:36.547610) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 21:07:36.547939) ========= ========= Total master_lag: 0.58 =========