builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0236 starttime: 1472572117.73 results: success (0) buildid: 20160830065820 builduid: 970b10d5e74d4d30bf15970af8c99332 revision: fa6f91ffb4965302f316635e91d456c8103e08c2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 08:48:37.734950) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 08:48:37.736080) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 08:48:37.736719) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 08:48:37.761443) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 08:48:37.761724) ========= 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.MLahkXVfg3/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.CqTexHHOYh/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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-30 08:48:37-- 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% 37.4M=0s 2016-08-30 08:48:37 (37.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104985 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 08:48:37.890862) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 08:48:37.891346) ========= 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.MLahkXVfg3/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.CqTexHHOYh/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.025783 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 08:48:37.940427) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-30 08:48:37.940741) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev fa6f91ffb4965302f316635e91d456c8103e08c2 --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 fa6f91ffb4965302f316635e91d456c8103e08c2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MLahkXVfg3/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.CqTexHHOYh/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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-30 08:48:38,028 truncating revision to first 12 chars 2016-08-30 08:48:38,028 Setting DEBUG logging. 2016-08-30 08:48:38,028 attempt 1/10 2016-08-30 08:48:38,028 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/fa6f91ffb496?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 08:48:39,863 unpacking tar archive at: autoland-fa6f91ffb496/testing/mozharness/ program finished with exit code 0 elapsedTime=2.093674 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-30 08:48:40.057771) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 08:48:40.058112) ========= script_repo_revision: fa6f91ffb4965302f316635e91d456c8103e08c2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 08:48:40.058500) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 08:48:40.058765) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 08:48:40.077137) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-08-30 08:48:40.077424) ========= /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.MLahkXVfg3/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.CqTexHHOYh/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:48:40 INFO - MultiFileLogger online at 20160830 08:48:40 in /builds/slave/test 08:48:40 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 08:48:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:48:40 INFO - {'append_to_log': False, 08:48:40 INFO - 'base_work_dir': '/builds/slave/test', 08:48:40 INFO - 'blob_upload_branch': 'Autoland', 08:48:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:48:40 INFO - 'branch': 'Autoland', 08:48:40 INFO - 'buildbot_json_path': 'buildprops.json', 08:48:40 INFO - 'config_files': ('talos/mac_config.py',), 08:48:40 INFO - 'default_actions': ('clobber', 08:48:40 INFO - 'read-buildbot-config', 08:48:40 INFO - 'download-and-extract', 08:48:40 INFO - 'populate-webroot', 08:48:40 INFO - 'create-virtualenv', 08:48:40 INFO - 'install', 08:48:40 INFO - 'run-tests'), 08:48:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:48:40 INFO - 'download_minidump_stackwalk': True, 08:48:40 INFO - 'download_symbols': 'ondemand', 08:48:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:48:40 INFO - 'tooltool.py': '/tools/tooltool.py', 08:48:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:48:40 INFO - '/tools/misc-python/virtualenv.py')}, 08:48:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:48:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:48:40 INFO - 'installer_path': 'installer.exe', 08:48:40 INFO - 'log_level': 'info', 08:48:40 INFO - 'log_name': 'talos', 08:48:40 INFO - 'log_to_console': True, 08:48:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:48:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:48:40 INFO - 'opt_config_files': (), 08:48:40 INFO - 'pip_index': False, 08:48:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:48:40 INFO - 'cmd': ('bash', 08:48:40 INFO - '-c', 08:48:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:48:40 INFO - 'enabled': True, 08:48:40 INFO - 'halt_on_failure': False, 08:48:40 INFO - 'name': 'check_screen_resolution'},), 08:48:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:48:40 INFO - 'cmd': ('bash', 08:48:40 INFO - '-c', 08:48:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:48:40 INFO - 'enabled': True, 08:48:40 INFO - 'halt_on_failure': False, 08:48:40 INFO - 'name': 'check_screen_resolution'},), 08:48:40 INFO - 'run_cmd_checks_enabled': True, 08:48:40 INFO - 'sps_profile': False, 08:48:40 INFO - 'sps_profile_interval': 0, 08:48:40 INFO - 'suite': 'dromaeojs', 08:48:40 INFO - 'system_bits': '32', 08:48:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:48:40 INFO - 'title': 't-yosemite-r7-0236', 08:48:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:48:40 INFO - 'use_talos_json': True, 08:48:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:48:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:48:40 INFO - 'work_dir': 'build'} 08:48:40 INFO - [mozharness: 2016-08-30 15:48:40.278365Z] Running clobber step. 08:48:40 INFO - Running pre-action listener: _resource_record_pre_action 08:48:40 INFO - Running main action method: clobber 08:48:40 INFO - rmtree: /builds/slave/test/build 08:48:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:48:42 INFO - Running post-action listener: _resource_record_post_action 08:48:42 INFO - [mozharness: 2016-08-30 15:48:42.318019Z] Finished clobber step (success) 08:48:42 INFO - [mozharness: 2016-08-30 15:48:42.318153Z] Running read-buildbot-config step. 08:48:42 INFO - Running pre-action listener: _resource_record_pre_action 08:48:42 INFO - Running main action method: read_buildbot_config 08:48:42 INFO - Using buildbot properties: 08:48:42 INFO - { 08:48:42 INFO - "project": "", 08:48:42 INFO - "product": "firefox", 08:48:42 INFO - "script_repo_revision": "production", 08:48:42 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 08:48:42 INFO - "repository": "", 08:48:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 08:48:42 INFO - "buildid": "20160830065820", 08:48:42 INFO - "pgo_build": "False", 08:48:42 INFO - "basedir": "/builds/slave/test", 08:48:42 INFO - "buildnumber": 192, 08:48:42 INFO - "slavename": "t-yosemite-r7-0236", 08:48:42 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:48:42 INFO - "platform": "yosemite_r7", 08:48:42 INFO - "scheduler": "tests-autoland-macosx64-talos", 08:48:42 INFO - "branch": "autoland", 08:48:42 INFO - "repo_path": "integration/autoland", 08:48:42 INFO - "revision": "fa6f91ffb4965302f316635e91d456c8103e08c2", 08:48:42 INFO - "stage_platform": "macosx64", 08:48:42 INFO - "builduid": "970b10d5e74d4d30bf15970af8c99332", 08:48:42 INFO - "slavebuilddir": "test" 08:48:42 INFO - } 08:48:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 08:48:42 INFO - Running post-action listener: _resource_record_post_action 08:48:42 INFO - [mozharness: 2016-08-30 15:48:42.321662Z] Finished read-buildbot-config step (success) 08:48:42 INFO - [mozharness: 2016-08-30 15:48:42.321781Z] Running download-and-extract step. 08:48:42 INFO - Running pre-action listener: _resource_record_pre_action 08:48:42 INFO - Running main action method: download_and_extract 08:48:42 INFO - mkdir: /builds/slave/test/build/tests 08:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:42 INFO - trying https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 08:48:42 INFO - Downloading https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/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 08:48:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/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 08:48:43 INFO - Downloaded 1199 bytes. 08:48:43 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:48:43 INFO - Using the following test package requirements: 08:48:43 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:48:43 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:48:43 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 08:48:43 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:48:43 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 08:48:43 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:48:43 INFO - u'jsshell-mac.zip'], 08:48:43 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:48:43 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 08:48:43 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:48:43 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:48:43 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 08:48:43 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:48:43 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 08:48:43 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:48:43 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 08:48:43 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:48:43 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 08:48:43 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:48: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')]} 08:48:43 INFO - trying https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:48:43 INFO - Downloading https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/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 08:48:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/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 08:48:45 INFO - Downloaded 16380143 bytes. 08:48:45 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:48:51 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 08:48: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')]} 08:48:51 INFO - trying https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:48:51 INFO - Downloading https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/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 08:48:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/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 08:48:52 INFO - Downloaded 16380143 bytes. 08:48:52 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:59 INFO - trying https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:48:59 INFO - Downloading https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/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 08:48:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/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 08:49:01 INFO - Downloaded 14413998 bytes. 08:49:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 08:49: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')]} 08:49:01 INFO - trying https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:49:01 INFO - Downloading https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 08:49:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/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 08:49:05 INFO - Downloaded 111563657 bytes. 08:49:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:49:05 INFO - mkdir: /builds/slave/test/properties 08:49:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:49:05 INFO - Writing to file /builds/slave/test/properties/build_url 08:49:05 INFO - Contents: 08:49:05 INFO - build_url:https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:49:06 INFO - Running post-action listener: _resource_record_post_action 08:49:06 INFO - Running post-action listener: set_extra_try_arguments 08:49:06 INFO - [mozharness: 2016-08-30 15:49:06.264171Z] Finished download-and-extract step (success) 08:49:06 INFO - [mozharness: 2016-08-30 15:49:06.264293Z] Running populate-webroot step. 08:49:06 INFO - Running pre-action listener: _resource_record_pre_action 08:49:06 INFO - Running main action method: populate_webroot 08:49:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:49:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:49:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:49:06 INFO - '--tppagecycles', 08:49:06 INFO - '7'], 08:49:06 INFO - 'tests': ['tsvgm']}}, 08:49:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:49:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:49:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:49:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:49:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:49:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:49:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:06 INFO - 'talos_options': ['--disable-e10s'], 08:49:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:06 INFO - 'talos_options': ['--disable-e10s'], 08:49:06 INFO - 'tests': ['damp', 'tps']}, 08:49:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:06 INFO - 'tests': ['damp', 'tps']}, 08:49:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:49:06 INFO - 'tests': ['dromaeo_dom']}, 08:49:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:49:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:49:06 INFO - 'tests': ['basic_compositor_video']}, 08:49:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:49:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:49:06 INFO - 'tests': ['a11yr', 08:49:06 INFO - 'ts_paint', 08:49:06 INFO - 'tpaint', 08:49:06 INFO - 'sessionrestore', 08:49:06 INFO - 'sessionrestore_no_auto_restore', 08:49:06 INFO - 'tabpaint']}, 08:49:06 INFO - 'other-e10s': {'tests': ['a11yr', 08:49:06 INFO - 'ts_paint', 08:49:06 INFO - 'tpaint', 08:49:06 INFO - 'sessionrestore', 08:49:06 INFO - 'sessionrestore_no_auto_restore', 08:49:06 INFO - 'tabpaint']}, 08:49:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:49:06 INFO - 'ts_paint', 08:49:06 INFO - 'tpaint', 08:49:06 INFO - 'sessionrestore', 08:49:06 INFO - 'sessionrestore_no_auto_restore', 08:49:06 INFO - 'tabpaint']}, 08:49:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:49:06 INFO - 'ts_paint', 08:49:06 INFO - 'tpaint', 08:49:06 INFO - 'sessionrestore', 08:49:06 INFO - 'sessionrestore_no_auto_restore', 08:49:06 INFO - 'tabpaint']}, 08:49:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:49:06 INFO - 'tests': ['a11yr', 08:49:06 INFO - 'ts_paint', 08:49:06 INFO - 'tpaint', 08:49:06 INFO - 'sessionrestore', 08:49:06 INFO - 'sessionrestore_no_auto_restore', 08:49:06 INFO - 'tabpaint']}, 08:49:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:49:06 INFO - 'tests': ['a11yr', 08:49:06 INFO - 'ts_paint', 08:49:06 INFO - 'tpaint', 08:49:06 INFO - 'sessionrestore', 08:49:06 INFO - 'sessionrestore_no_auto_restore', 08:49:06 INFO - 'tabpaint']}, 08:49:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:49:06 INFO - 'tests': ['tsvgx', 08:49:06 INFO - 'tsvgr_opacity', 08:49:06 INFO - 'tart', 08:49:06 INFO - 'tscrollx', 08:49:06 INFO - 'cart']}, 08:49:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:49:06 INFO - 'tsvgr_opacity', 08:49:06 INFO - 'tart', 08:49:06 INFO - 'tscrollx', 08:49:06 INFO - 'cart']}, 08:49:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:06 INFO - 'talos_options': ['--disable-e10s'], 08:49:06 INFO - 'tests': ['tp5o']}, 08:49:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:06 INFO - 'tests': ['tp5o']}, 08:49:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:06 INFO - 'talos_options': ['--disable-e10s', 08:49:06 INFO - '--xperf_path', 08:49:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:06 INFO - 'tests': ['tp5n']}, 08:49:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:06 INFO - 'talos_options': ['--xperf_path', 08:49:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:06 INFO - 'tests': ['tp5n']}}, 08:49:06 INFO - 'talos.zip': {'path': '', 08:49:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:49:06 INFO - Running post-action listener: _resource_record_post_action 08:49:06 INFO - [mozharness: 2016-08-30 15:49:06.283672Z] Finished populate-webroot step (success) 08:49:06 INFO - [mozharness: 2016-08-30 15:49:06.283813Z] Running create-virtualenv step. 08:49:06 INFO - Running pre-action listener: _resource_record_pre_action 08:49:06 INFO - Running main action method: create_virtualenv 08:49:06 INFO - Creating virtualenv /builds/slave/test/build/venv 08:49:06 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 08:49:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:49:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:49:06 INFO - Using real prefix '/tools/python27' 08:49:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:49:07 INFO - Installing distribute.............................................................................................................................................................................................done. 08:49:09 INFO - Installing pip.................done. 08:49:09 INFO - Return code: 0 08:49:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1021eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102282618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b0d73580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102151d30>, '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 0x101eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102149b20>, '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.MLahkXVfg3/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.CqTexHHOYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:49:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:49:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MLahkXVfg3/Render', 08:49:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:09 INFO - 'HOME': '/Users/cltbld', 08:49:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:09 INFO - 'LOGNAME': 'cltbld', 08:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:09 INFO - 'MOZ_NO_REMOTE': '1', 08:49:09 INFO - 'NO_EM_RESTART': '1', 08:49:09 INFO - 'PAGER': '/bin/cat', 08:49:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:09 INFO - 'PWD': '/builds/slave/test', 08:49:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:09 INFO - 'SHELL': '/bin/bash', 08:49:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqTexHHOYh/Listeners', 08:49:09 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:49:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:09 INFO - 'USER': 'cltbld', 08:49:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:09 INFO - 'XPC_FLAGS': '0x0', 08:49:09 INFO - 'XPC_SERVICE_NAME': '0', 08:49:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:10 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 08:49:10 INFO - Cleaning up... 08:49:10 INFO - Return code: 0 08:49:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:49: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')]} 08:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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')]} 08:49:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1021eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102282618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b0d73580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102151d30>, '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 0x101eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102149b20>, '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.MLahkXVfg3/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.CqTexHHOYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:49:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:49:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:49:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MLahkXVfg3/Render', 08:49:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:10 INFO - 'HOME': '/Users/cltbld', 08:49:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:10 INFO - 'LOGNAME': 'cltbld', 08:49:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:10 INFO - 'MOZ_NO_REMOTE': '1', 08:49:10 INFO - 'NO_EM_RESTART': '1', 08:49:10 INFO - 'PAGER': '/bin/cat', 08:49:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:10 INFO - 'PWD': '/builds/slave/test', 08:49:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:10 INFO - 'SHELL': '/bin/bash', 08:49:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqTexHHOYh/Listeners', 08:49:10 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:49:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:10 INFO - 'USER': 'cltbld', 08:49:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:10 INFO - 'XPC_FLAGS': '0x0', 08:49:10 INFO - 'XPC_SERVICE_NAME': '0', 08:49:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:10 INFO - Downloading/unpacking psutil>=3.1.1 08:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:49:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:13 INFO - Installing collected packages: psutil 08:49:13 INFO - Running setup.py install for psutil 08:49:13 INFO - building 'psutil._psutil_osx' extension 08:49:13 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 08:49:14 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 08:49:14 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 08:49:14 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 08:49:14 INFO - building 'psutil._psutil_posix' extension 08:49:14 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 08:49:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:49:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:49:14 INFO - ^ 08:49:14 INFO - 1 warning generated. 08:49:14 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 08:49:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:14 INFO - Successfully installed psutil 08:49:14 INFO - Cleaning up... 08:49:15 INFO - Return code: 0 08:49:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:49: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')]} 08:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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')]} 08:49:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:15 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 0x1021eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102282618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b0d73580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102151d30>, '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 0x101eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102149b20>, '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.MLahkXVfg3/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.CqTexHHOYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:49:15 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 08:49:15 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 08:49:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MLahkXVfg3/Render', 08:49:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:15 INFO - 'HOME': '/Users/cltbld', 08:49:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:15 INFO - 'LOGNAME': 'cltbld', 08:49:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:15 INFO - 'MOZ_NO_REMOTE': '1', 08:49:15 INFO - 'NO_EM_RESTART': '1', 08:49:15 INFO - 'PAGER': '/bin/cat', 08:49:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:15 INFO - 'PWD': '/builds/slave/test', 08:49:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:15 INFO - 'SHELL': '/bin/bash', 08:49:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqTexHHOYh/Listeners', 08:49:15 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:49:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:15 INFO - 'USER': 'cltbld', 08:49:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:15 INFO - 'XPC_FLAGS': '0x0', 08:49:15 INFO - 'XPC_SERVICE_NAME': '0', 08:49:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:49:15 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 08:49:15 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 08:49:15 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 08:49:15 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 08:49:15 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 08:49:15 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 08:49:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:49:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:49:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:49:17 INFO - Installing collected packages: mozsystemmonitor 08:49:17 INFO - Running setup.py install for mozsystemmonitor 08:49:18 INFO - Successfully installed mozsystemmonitor 08:49:18 INFO - Cleaning up... 08:49:18 INFO - Return code: 0 08:49:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:18 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 0x1021eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102282618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b0d73580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102151d30>, '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 0x101eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102149b20>, '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.MLahkXVfg3/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.CqTexHHOYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:49:18 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 08:49:18 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 08:49:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MLahkXVfg3/Render', 08:49:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:18 INFO - 'HOME': '/Users/cltbld', 08:49:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:18 INFO - 'LOGNAME': 'cltbld', 08:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:18 INFO - 'MOZ_NO_REMOTE': '1', 08:49:18 INFO - 'NO_EM_RESTART': '1', 08:49:18 INFO - 'PAGER': '/bin/cat', 08:49:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:18 INFO - 'PWD': '/builds/slave/test', 08:49:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:18 INFO - 'SHELL': '/bin/bash', 08:49:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqTexHHOYh/Listeners', 08:49:18 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:49:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:18 INFO - 'USER': 'cltbld', 08:49:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:18 INFO - 'XPC_FLAGS': '0x0', 08:49:18 INFO - 'XPC_SERVICE_NAME': '0', 08:49:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:18 INFO - Downloading/unpacking jsonschema==2.5.1 08:49:18 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 08:49:18 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 08:49:18 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 08:49:18 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 08:49:18 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 08:49:18 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 08:49:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:49:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:21 INFO - Downloading functools32-3.2.3-2.tar.gz 08:49:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:49:21 INFO - warning: no files found matching '*.txt' 08:49:21 INFO - no previously-included directories found matching 'build' 08:49:21 INFO - no previously-included directories found matching 'dist' 08:49:21 INFO - no previously-included directories found matching '.git*' 08:49:21 INFO - Installing collected packages: jsonschema, functools32 08:49:21 INFO - Running setup.py install for functools32 08:49:21 INFO - warning: no files found matching '*.txt' 08:49:21 INFO - no previously-included directories found matching 'build' 08:49:21 INFO - no previously-included directories found matching 'dist' 08:49:21 INFO - no previously-included directories found matching '.git*' 08:49:21 INFO - Successfully installed jsonschema functools32 08:49:21 INFO - Cleaning up... 08:49:21 INFO - Return code: 0 08:49:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:49:21 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')]} 08:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:21 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')]} 08:49:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:21 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 0x1021eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102282618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b0d73580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102151d30>, '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 0x101eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102149b20>, '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.MLahkXVfg3/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.CqTexHHOYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:49:21 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 08:49:21 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 08:49:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MLahkXVfg3/Render', 08:49:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:21 INFO - 'HOME': '/Users/cltbld', 08:49:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:21 INFO - 'LOGNAME': 'cltbld', 08:49:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:21 INFO - 'MOZ_NO_REMOTE': '1', 08:49:21 INFO - 'NO_EM_RESTART': '1', 08:49:21 INFO - 'PAGER': '/bin/cat', 08:49:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:21 INFO - 'PWD': '/builds/slave/test', 08:49:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:21 INFO - 'SHELL': '/bin/bash', 08:49:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqTexHHOYh/Listeners', 08:49:21 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:49:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:21 INFO - 'USER': 'cltbld', 08:49:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:21 INFO - 'XPC_FLAGS': '0x0', 08:49:21 INFO - 'XPC_SERVICE_NAME': '0', 08:49:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:49:22 INFO - Cleaning up... 08:49:22 INFO - Return code: 0 08:49:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1021eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102282618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b0d73580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102151d30>, '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 0x101eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102149b20>, '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.MLahkXVfg3/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.CqTexHHOYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:49:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MLahkXVfg3/Render', 08:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:22 INFO - 'HOME': '/Users/cltbld', 08:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:22 INFO - 'LOGNAME': 'cltbld', 08:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:22 INFO - 'MOZ_NO_REMOTE': '1', 08:49:22 INFO - 'NO_EM_RESTART': '1', 08:49:22 INFO - 'PAGER': '/bin/cat', 08:49:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:22 INFO - 'PWD': '/builds/slave/test', 08:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:22 INFO - 'SHELL': '/bin/bash', 08:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqTexHHOYh/Listeners', 08:49:22 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:22 INFO - 'USER': 'cltbld', 08:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:22 INFO - 'XPC_FLAGS': '0x0', 08:49:22 INFO - 'XPC_SERVICE_NAME': '0', 08:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:22 INFO - Downloading/unpacking blobuploader==1.2.4 08:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:24 INFO - Downloading blobuploader-1.2.4.tar.gz 08:49:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:49:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:49:24 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 08:49:24 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 08:49:24 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 08:49:24 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 08:49:24 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 08:49:24 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 08:49:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:49:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:49:25 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 08:49:25 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 08:49:25 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 08:49:25 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 08:49:25 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 08:49:25 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 08:49:26 INFO - Downloading docopt-0.6.1.tar.gz 08:49:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:49:26 INFO - Installing collected packages: blobuploader, requests, docopt 08:49:26 INFO - Running setup.py install for blobuploader 08:49:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:49:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:49:26 INFO - Running setup.py install for requests 08:49:26 INFO - Running setup.py install for docopt 08:49:27 INFO - Successfully installed blobuploader requests docopt 08:49:27 INFO - Cleaning up... 08:49:27 INFO - Return code: 0 08:49:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:27 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')]} 08:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:27 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')]} 08:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:27 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 0x1021eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102282618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b0d73580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102151d30>, '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 0x101eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102149b20>, '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.MLahkXVfg3/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.CqTexHHOYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:49:27 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 08:49:27 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 08:49:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MLahkXVfg3/Render', 08:49:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:27 INFO - 'HOME': '/Users/cltbld', 08:49:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:27 INFO - 'LOGNAME': 'cltbld', 08:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:27 INFO - 'MOZ_NO_REMOTE': '1', 08:49:27 INFO - 'NO_EM_RESTART': '1', 08:49:27 INFO - 'PAGER': '/bin/cat', 08:49:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:27 INFO - 'PWD': '/builds/slave/test', 08:49:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:27 INFO - 'SHELL': '/bin/bash', 08:49:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqTexHHOYh/Listeners', 08:49:27 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:49:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:27 INFO - 'USER': 'cltbld', 08:49:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:27 INFO - 'XPC_FLAGS': '0x0', 08:49:27 INFO - 'XPC_SERVICE_NAME': '0', 08:49:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:27 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-QZmsTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:27 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-qZ_ijk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:27 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-_wxBS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:27 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-VSkweM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:27 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Gs3CNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:27 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-RuISwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:28 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-GBEKfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:28 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-B9wsfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:28 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-UFW7dI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:28 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-GjkbDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:28 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-gFcXFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:28 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-97udRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:28 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-3TagL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:28 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-lpWFYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:28 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-yNqlrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:28 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-en_r3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:29 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-hH2Xiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:49:29 INFO - Running setup.py install for manifestparser 08:49:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:49:29 INFO - Running setup.py install for mozcrash 08:49:29 INFO - Running setup.py install for mozdebug 08:49:29 INFO - Running setup.py install for mozdevice 08:49:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:49:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:49:29 INFO - Running setup.py install for mozfile 08:49:29 INFO - Running setup.py install for mozhttpd 08:49:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:49:30 INFO - Running setup.py install for mozinfo 08:49:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:49:30 INFO - Running setup.py install for mozInstall 08:49:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:49:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:49:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:49:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:49:30 INFO - Running setup.py install for mozleak 08:49:30 INFO - Running setup.py install for mozlog 08:49:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:49:30 INFO - Running setup.py install for moznetwork 08:49:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:49:30 INFO - Running setup.py install for mozprocess 08:49:30 INFO - Running setup.py install for mozprofile 08:49:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:49:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:49:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:49:31 INFO - Running setup.py install for mozrunner 08:49:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:49:31 INFO - Running setup.py install for mozscreenshot 08:49:31 INFO - Running setup.py install for moztest 08:49:31 INFO - Running setup.py install for mozversion 08:49:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:49:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:49:31 INFO - Cleaning up... 08:49:31 INFO - Return code: 0 08:49:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:31 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 0x1021eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102282618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b0d73580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102151d30>, '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 0x101eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102149b20>, '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.MLahkXVfg3/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.CqTexHHOYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:49:31 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 08:49:31 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 08:49:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MLahkXVfg3/Render', 08:49:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:31 INFO - 'HOME': '/Users/cltbld', 08:49:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:31 INFO - 'LOGNAME': 'cltbld', 08:49:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:31 INFO - 'MOZ_NO_REMOTE': '1', 08:49:31 INFO - 'NO_EM_RESTART': '1', 08:49:31 INFO - 'PAGER': '/bin/cat', 08:49:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:31 INFO - 'PWD': '/builds/slave/test', 08:49:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:31 INFO - 'SHELL': '/bin/bash', 08:49:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqTexHHOYh/Listeners', 08:49:31 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:49:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:31 INFO - 'USER': 'cltbld', 08:49:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:31 INFO - 'XPC_FLAGS': '0x0', 08:49:31 INFO - 'XPC_SERVICE_NAME': '0', 08:49:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:32 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-NQ_WHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:32 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)) 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:32 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-emHaZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:32 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)) 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:32 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-3O18GL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:32 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)) 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:32 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-51P4Gk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:32 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)) 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:32 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-i4smcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:32 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)) 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:32 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-PkhK7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:32 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)) 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:32 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-9obwlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:32 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)) 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:32 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-oIPOQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:32 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)) 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:32 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-rRITh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:32 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)) 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:32 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-r0VdOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:33 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)) 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:33 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-trNSq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:33 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)) 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:33 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-0MI3xP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:33 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)) 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:33 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-bv3sq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:33 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)) 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:33 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-eGVesu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:33 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)) 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:33 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-GW8u9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:33 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)) 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:33 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-srUCVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:33 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)) 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:33 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-7q2DEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:33 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)) 08:49:33 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)) 08:49:33 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)) 08:49:33 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)) 08:49:33 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)) 08:49:33 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)) 08:49:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:49:33 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 08:49:33 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 08:49:33 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 08:49:33 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 08:49:33 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 08:49:33 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 08:49:37 INFO - Downloading blessings-1.6.tar.gz 08:49:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:49:37 INFO - Installing collected packages: blessings 08:49:37 INFO - Running setup.py install for blessings 08:49:37 INFO - Successfully installed blessings 08:49:37 INFO - Cleaning up... 08:49:37 INFO - Return code: 0 08:49:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:49:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:38 INFO - Reading from file tmpfile_stdout 08:49:38 INFO - Current package versions: 08:49:38 INFO - blessings == 1.6 08:49:38 INFO - blobuploader == 1.2.4 08:49:38 INFO - docopt == 0.6.1 08:49:38 INFO - functools32 == 3.2.3-2 08:49:38 INFO - jsonschema == 2.5.1 08:49:38 INFO - manifestparser == 1.1 08:49:38 INFO - mozInstall == 1.12 08:49:38 INFO - mozcrash == 0.17 08:49:38 INFO - mozdebug == 0.1 08:49:38 INFO - mozdevice == 0.48 08:49:38 INFO - mozfile == 1.2 08:49:38 INFO - mozhttpd == 0.7 08:49:38 INFO - mozinfo == 0.9 08:49:38 INFO - mozleak == 0.1 08:49:38 INFO - mozlog == 3.3 08:49:38 INFO - moznetwork == 0.27 08:49:38 INFO - mozprocess == 0.23 08:49:38 INFO - mozprofile == 0.28 08:49:38 INFO - mozrunner == 6.12 08:49:38 INFO - mozscreenshot == 0.1 08:49:38 INFO - mozsystemmonitor == 0.3 08:49:38 INFO - moztest == 0.7 08:49:38 INFO - mozversion == 1.4 08:49:38 INFO - psutil == 3.1.1 08:49:38 INFO - requests == 1.2.3 08:49:38 INFO - wsgiref == 0.1.2 08:49:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:38 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')]} 08:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:38 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')]} 08:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:38 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 0x1021eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102282618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b0d73580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102151d30>, '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 0x101eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102149b20>, '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.MLahkXVfg3/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.CqTexHHOYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:49:38 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 08:49:38 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 08:49:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MLahkXVfg3/Render', 08:49:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:38 INFO - 'HOME': '/Users/cltbld', 08:49:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:38 INFO - 'LOGNAME': 'cltbld', 08:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:38 INFO - 'MOZ_NO_REMOTE': '1', 08:49:38 INFO - 'NO_EM_RESTART': '1', 08:49:38 INFO - 'PAGER': '/bin/cat', 08:49:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:38 INFO - 'PWD': '/builds/slave/test', 08:49:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:38 INFO - 'SHELL': '/bin/bash', 08:49:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqTexHHOYh/Listeners', 08:49:38 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:49:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:38 INFO - 'USER': 'cltbld', 08:49:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:38 INFO - 'XPC_FLAGS': '0x0', 08:49:38 INFO - 'XPC_SERVICE_NAME': '0', 08:49:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:38 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)) 08:49:38 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)) 08:49:38 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)) 08:49:38 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)) 08:49:38 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)) 08:49:38 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)) 08:49:38 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)) 08:49:38 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)) 08:49:38 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)) 08:49:38 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)) 08:49:38 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)) 08:49:38 INFO - Cleaning up... 08:49:38 INFO - Return code: 0 08:49:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:49:38 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')]} 08:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:38 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')]} 08:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:38 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 0x1021eda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102282618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6b0d73580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102151d30>, '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 0x101eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102149b20>, '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.MLahkXVfg3/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.CqTexHHOYh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 08:49:38 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 08:49:38 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 08:49:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MLahkXVfg3/Render', 08:49:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:38 INFO - 'HOME': '/Users/cltbld', 08:49:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:38 INFO - 'LOGNAME': 'cltbld', 08:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:38 INFO - 'MOZ_NO_REMOTE': '1', 08:49:38 INFO - 'NO_EM_RESTART': '1', 08:49:38 INFO - 'PAGER': '/bin/cat', 08:49:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:38 INFO - 'PWD': '/builds/slave/test', 08:49:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:38 INFO - 'SHELL': '/bin/bash', 08:49:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqTexHHOYh/Listeners', 08:49:38 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:49:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:38 INFO - 'USER': 'cltbld', 08:49:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:38 INFO - 'XPC_FLAGS': '0x0', 08:49:38 INFO - 'XPC_SERVICE_NAME': '0', 08:49:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:49:39 INFO - Cleaning up... 08:49:39 INFO - Return code: 0 08:49:39 INFO - Running post-action listener: _resource_record_post_action 08:49:39 INFO - Running post-action listener: _start_resource_monitoring 08:49:39 INFO - Starting resource monitoring. 08:49:39 INFO - [mozharness: 2016-08-30 15:49:39.095928Z] Finished create-virtualenv step (success) 08:49:39 INFO - [mozharness: 2016-08-30 15:49:39.096910Z] Running install step. 08:49:39 INFO - Running pre-action listener: _resource_record_pre_action 08:49:39 INFO - Running main action method: install 08:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:39 INFO - Reading from file tmpfile_stdout 08:49:39 INFO - Detecting whether we're running mozinstall >=1.0... 08:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:49:39 INFO - Reading from file tmpfile_stdout 08:49:39 INFO - Output received: 08:49:39 INFO - Usage: mozinstall [options] installer 08:49:39 INFO - Options: 08:49:39 INFO - -h, --help show this help message and exit 08:49:39 INFO - -d DEST, --destination=DEST 08:49:39 INFO - Directory to install application into. [default: 08:49:39 INFO - "/builds/slave/test"] 08:49:39 INFO - --app=APP Application being installed. [default: firefox] 08:49:39 INFO - mkdir: /builds/slave/test/build/application 08:49:39 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'] 08:49:39 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 08:50:13 INFO - Reading from file tmpfile_stdout 08:50:13 INFO - Output received: 08:50:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:50:13 INFO - Running post-action listener: _resource_record_post_action 08:50:13 INFO - [mozharness: 2016-08-30 15:50:13.414282Z] Finished install step (success) 08:50:13 INFO - [mozharness: 2016-08-30 15:50:13.414417Z] Running run-tests step. 08:50:13 INFO - Running pre-action listener: _resource_record_pre_action 08:50:13 INFO - Running main action method: run_tests 08:50:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:50:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:50:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:50:13 INFO - 2016-08-30 08:50:13.428 screenresolution[1634:7371] starting screenresolution argv=screenresolution get 08:50:13 INFO - 2016-08-30 08:50:13.431 screenresolution[1634:7371] Display 0: 1600x1200x32@60 08:50:13 INFO - 2016-08-30 08:50:13.437 screenresolution[1635:7373] starting screenresolution argv=screenresolution list 08:50:13 INFO - Available Modes on Display 0 08:50:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:50:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:50:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:50:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:50:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:50:13 INFO - Intel Iris: 08:50:13 INFO - Chipset Model: Intel Iris 08:50:13 INFO - Type: GPU 08:50:13 INFO - Bus: Built-In 08:50:13 INFO - VRAM (Dynamic, Max): 1536 MB 08:50:13 INFO - Vendor: Intel (0x8086) 08:50:13 INFO - Device ID: 0x0a2e 08:50:13 INFO - Revision ID: 0x0009 08:50:13 INFO - Displays: 08:50:13 INFO - Display: 08:50:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:50:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:50:13 INFO - Display Serial Number: 1600x1200 60 08:50:13 INFO - Main Display: Yes 08:50:13 INFO - Mirror: Off 08:50:13 INFO - Online: Yes 08:50:13 INFO - Rotation: Supported 08:50:13 INFO - Return code: 0 08:50:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:50:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:50:13 INFO - Python 2.7.3 08:50:13 INFO - Return code: 0 08:50:13 INFO - grabbing minidump binary from tooltool 08:50:13 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')]} 08:50:13 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 0x102151d30>, '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 0x101eb6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102149b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:50:13 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 08:50:13 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 08:50:13 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 08:50:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:50:13 INFO - Return code: 0 08:50:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:50:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:50:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:50:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:50:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:50:13 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/VW9_7DtoTF6Aum0Q5bGw_Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--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 08:50:13 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/VW9_7DtoTF6Aum0Q5bGw_Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0236 --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 08:50:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MLahkXVfg3/Render', 08:50:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:13 INFO - 'HOME': '/Users/cltbld', 08:50:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:13 INFO - 'LOGNAME': 'cltbld', 08:50:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:50:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:50:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:13 INFO - 'MOZ_NO_REMOTE': '1', 08:50:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:50:13 INFO - 'NO_EM_RESTART': '1', 08:50:13 INFO - 'PAGER': '/bin/cat', 08:50:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:13 INFO - 'PWD': '/builds/slave/test', 08:50:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:50:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:13 INFO - 'SHELL': '/bin/bash', 08:50:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CqTexHHOYh/Listeners', 08:50:13 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 08:50:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:13 INFO - 'USER': 'cltbld', 08:50:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:13 INFO - 'XPC_FLAGS': '0x0', 08:50:13 INFO - 'XPC_SERVICE_NAME': '0', 08:50:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:13 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/VW9_7DtoTF6Aum0Q5bGw_Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--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 08:50:14 INFO - mozversion application_buildid: 20160830065820 08:50:14 INFO - mozversion application_changeset: fa6f91ffb4965302f316635e91d456c8103e08c2 08:50:14 INFO - mozversion application_display_name: Nightly 08:50:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:50:14 INFO - mozversion application_name: Firefox 08:50:14 INFO - mozversion application_remotingname: firefox 08:50:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 08:50:14 INFO - mozversion application_vendor: Mozilla 08:50:14 INFO - mozversion application_version: 51.0a1 08:50:14 INFO - mozversion platform_buildid: 20160830065820 08:50:14 INFO - mozversion platform_changeset: fa6f91ffb4965302f316635e91d456c8103e08c2 08:50:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 08:50:14 INFO - mozversion platform_version: 51.0a1 08:50:14 INFO - using testdate: 1472572214 08:50:14 INFO - actual date: 1472572214 08:50:14 INFO - starting webserver on 'localhost:49264' 08:50:14 INFO - SUITE-START | Running 2 tests 08:50:14 INFO - TEST-START | dromaeo_css 08:50:14 INFO - operating with platform_type : mac_ 08:50:14 INFO - Initialising browser for dromaeo_css test... 08:50:14 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpWc79PP/profile http://localhost:49264/getInfo.html) 08:50:16 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 08:50:16 INFO - PROCESS | 1645 | colorDepth:24 08:50:16 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 08:50:16 INFO - PROCESS | 1645 | __metrics 08:50:16 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" 08:50:16 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] 08:50:17 INFO - TEST-INFO | 1645: exit 0 08:50:17 INFO - Browser initialized. 08:50:17 INFO - Running cycle 1/1 for dromaeo_css test... 08:50:17 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpWc79PP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:29 INFO - PROCESS | 1650 | RSS: Main: 234463232 08:50:29 INFO - PROCESS | 1650 | 08:51:20 INFO - PROCESS | 1650 | 1472572280386 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] 08:53:32 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49264/tests/dromaeo/cssquery-ext.html) 08:53:33 INFO - PROCESS | 1650 | RSS: Main: 391282688 08:53:33 INFO - PROCESS | 1650 | 08:56:36 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-ext.html (next: http://localhost:49264/tests/dromaeo/cssquery-jquery.html) 08:56:37 INFO - PROCESS | 1650 | RSS: Main: 375062528 08:56:37 INFO - PROCESS | 1650 | 08:59:39 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49264/tests/dromaeo/cssquery-mootools.html) 08:59:40 INFO - PROCESS | 1650 | RSS: Main: 332423168 08:59:40 INFO - PROCESS | 1650 | 09:02:42 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49264/tests/dromaeo/cssquery-prototype.html) 09:02:43 INFO - PROCESS | 1650 | RSS: Main: 336629760 09:02:43 INFO - PROCESS | 1650 | 09:05:47 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49264/tests/dromaeo/cssquery-yui.html) 09:05:47 INFO - PROCESS | 1650 | RSS: Main: 333426688 09:05:47 INFO - PROCESS | 1650 | 09:08:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-yui.html (next: http://localhost:49264/tests/dromaeo/cssquery-dojo.html) 09:08:51 INFO - PROCESS | 1650 | RSS: Main: 407568384 09:08:51 INFO - PROCESS | 1650 | 09:08:51 INFO - PROCESS | 1650 | __start_tp_report 09:08:51 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 09:08:51 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 09:08:51 INFO - PROCESS | 1650 | |i|pagename|runs| 09:08:51 INFO - PROCESS | 1650 | |0;dojo.html;2942.4115176964588;3328.6005679318478;3335.066181469188;3342.2647017656113;3418.4872269159623;5601.467860553241;5628.662280263182;5695.857603559908;5698.230738850254;5706.115552089423;5685.602007859451;5721.6853073080965;5722.313322401317;5731.742071606777;5737.282839645044;5176.689398636085;5215.113430716769;5216.087184742674;5249.1601343785005;5292;5259.31628888244;5274.077036518613;5283.022640811462;5287.49768771968;5287.973560132194;5200.29795977542;5226.5818734501145;5237;5246.842594722164;5279.049771041211;5292.285541451932;5316.308879845596;5322.0549819629305;5330.360356757214;5346.198070289449;5102.413222479426;5127.666701664417;5155.0978578748945;5242.501962313574;5248.580113590903;5262.210668399733;5684.8010319639025;5732.512736417368;5757.453041961007;5803.332616749086;6238.36590051665;6271.498280137578;6279.843003924892;6298.905516417258;6303.621782693053;5762.798302059407;6124.816255512342;6220.284667263232;6322.462590679797;6343.714532846033;5862.091375836752;5880;5897.64614123154;5899.115132730039;5905.822825315161;6230.906536401958;6294.527910406738;6300.086487459293;6307.621542707452;6307.9369206308265;6029.427204415573;6099.451049405575;6124.724387402578;6173.32093469718;6185.690715464299;6235.127082208494;6270.843228919313;6284.12022316876;6286.339934306923;6294.464970477561;6165.630062196283;6216;6239.500839932794;6239.937600624026;6249.812505624838;6077.574569780161;6105.15650299495;6141.938580614226;6250.0937364082865;6263.185785847902;4908.533689299511;4911.968486617779;4920.950790492121;4921.9507804921495;4924.4583095859425;4454.643628509712;4486.192485352668;4486.977565112219;4489.663275254369;4506.3916371290115;4829.95170048295;5677.17680936262;5735.397783232784;5740.512056475246;5802.419758024248;6242.2509298883515;6316.62100273985;6322.778702745381;6339.683015849189;6354.4281014708895;5760.74076666551;5776.797812076641;5787.189793428924;5792.102224155263;5839.591228613956;5362.2224777407055;5400.864978375572;5425.620206585501;5436.238926550286;5440.455954404528;6327.810165695037;6341.302456729756;6345.904811427832;6347.143135676624;6357.4596159326975;6310.7791227306825;6313.684315784284;6319.273283572448;6324;6328.715207815567;6203.379662033761;6228.096925945803;6244.906326405016;6334.954732469091;6340.334264902119;6145.66198859067;6208.751649934133;6216.347283535164;6236.563440559117;6244.906326405198;6283.780067697791;6289.465395441439;6292.244930608356;6299.811005669837;6317.747290108344;5856.238688970407;5982.790602328898;6031.728572214349;6122.295935967242;6143.047827586731;6062.181605483203;6067.150598916243;6128.264608247039;6150.415710507495;6151.323354431099;4954.578860796729;5972.671503067199;5980.671063091571;5986.730597123227;5990.161377407254;5888.440598143169;6128.571000030133;6134.202553667995;6137.140800288053;6148.4773794227995;5799.884002319845;5910.497607703393;5960.493358064613;5965.522758179422;6046.5767396281835 09:08:51 INFO - PROCESS | 1650 | |1;ext.html;499.7651103981129;520.7838746920029;526.8630156159397;530.7266757619826;538.0530266730553;31220.21949451262;40759.38860917085;41889;42083.78958105209;42086.789566052124;43929.56070439295;44371.556284437145;44525.554744452544;44528.55471445285;44540.88647783807;9630.903690963089;9821.2634052446;9829.754256143582;9834.459104749236;9848.212143028577;8762.255208307302;11906.583269585522;12195.207311524768;12200.145989780718;12370.44333005017;12123.757524849498;12124.030077593816;12201.816972745415;12211.572594959178;12216.755664886698;12162.695932601668;12239.938800305987;12241.755164896653;12243.755124897541;12250.142490025703;10034.69895903124;11977.281363118242;11989.64031079069;12021.699457513545;12054.758904821854;33678;40996.97507562305;41237.587624123975;41297;41410.585894140975;2733.2073698627573;3465.2673663168416;3615.7288203384587;3718.7396882218254;4050;30749.69250307513;31164;31787.84106079478;31849.52225716616;31907;3296.2583418730833;3296.703296703284;3325.9501107483406;3362.9159271018175;3366;502929;505595;505635;506073;510089.4495527536;61414;61737.69131154315;61851;61897;61908;63603.36396636067;63619.68190159019;63729.36270637327;63793;63863.68068159722;16346.18269086541;16585.751213731804;16615;16615.00309981405;16681.91659041697;11736.823947640793;12960.963122950141;13013.739725205443;13023.544175953795;13024.283664398548;15187.772183417257;17184.508246304467;17588.47234582939;17596.38412655551;18153.546161345806;5857.882842343214;5890.882182356415;5902.3507414184405;6971.58170509771;7054.576725396492;892.9955350223207;952.7522844060455;993.9850902236473;1041.1514615588424;1098.7143342730787;22548.66177007346;30075.248118797204;30599.541006884916;31057.534136987964;31280.84359578233;1005.6982905128432;1040.5317607076695;1055.3298655353906;1087.8694556653252;1095.3592148593002;13048.739025219442;14676.486322978644;15577;15616.531504054896;15951.410932540086;664152;705205;705311;706008;957746;51152.74423627858;52034;57299.14051289234;58439.831203375696;58940.41059589435;1384.7299776543477;1396.190209678384;1412.5056230319306;1415.8017877497364;1419.9361028753524;26594.202173934813;28787.5681864768;30506.389872202875;31365.68634313719;32274;51318;56095.87808243731;57133;57295;59980;620220;656214;658291;667971;774486;25356.746432536176;25649;25700.8714956424;25992.22023339303;25998;45079.54920450754;45493.77253113713;45647.77176114098;46167;46224.537754622026;37527.43708844315;37728.2454350906;37824.62175378211;38761.8371448857;39048.41427378535;37072.62927370799;37177.81411092927;38023.809880950415;38382.80808595939;38522.56990446134;36414.63585364113;36601.11813199136;36997.63002369942;37323.06692332605;37771.43342849804;32036.51945220777;33510.832445837616;33604.495932560545;33731.662683373834;33769.324613508084;22226;24617.753822461545;25140.62289065629;25207.74792252054;25703.871480643225 09:08:51 INFO - PROCESS | 1650 | |2;jquery.html;355.46502513716854;371.6599311629858;378.96968242540584;386.5477391452002;409.11834995584525;15348;16210.918945405272;21709.565808683816;21873.562528749455;21925.890370548146;20874.791252087474;20960.685589716166;21631.242906498275;22284.66573001402;22554.88722556389;5926.318473375563;6191.133241346215;7590.127135379446;7981.880271795899;8077.313428358567;7676.1172465166665;7963.163867793856;7978.840423191533;7995.2804247617705;8028.638711258008;8090.757277281661;8099.392545559077;8112.269895709385;8119.756407307761;8173.141820108892;7514.39884809214;7884.960575197088;7934.841303173962;7940.1265860755275;7949.324307433875;7359.785635370157;7564.848703025908;7712.3444507216955;7724.806879827993;7727.343175830061;24293.028278868824;27791.86104069487;28617.427651446855;28850.855745721346;29623.963161289466;2367.206985659806;2435.5372479229068;2516.572182728922;3312.9834350828332;3487.4420092785285;12227.266364018187;16553.751693724604;16676;17381.957082574958;17657.646847063115;1988.0854806788896;1994.5412555112405;2016.4958760309923;2066.9793302066937;2138.486763176842;72733.52925858427;92846;117577.70900309506;127870;153530;17968.281268749102;18356;21252.574948500944;21510.139594416047;21608;35213.64786352104;35323.646763532546;35356.292874142375;35383.64616353855;35435;10633.893661063445;10812.189085818594;11010.229283950206;11029.669109926715;11172.21794474379;8993.100689930954;8998.955005225063;9013.278937684969;9219.44683319003;9224.8616270756;9447.669331573363;9539;9561.521923903778;9663.226941844636;9665.468399238109;2977.56825260336;3582.3372676055014;3635.563732352082;3709.684676802502;3747.8126093695205;733.3583114774573;875.5315905990392;889.573004957611;902.9277657787361;956.3018996132862;21057.368278951657;21884;21929.342119736433;21941.01265443059;21971.780282196974;905.2531661379388;915.5193523400135;939.2345238630494;949.6486300069018;955.6681696660265;10965.067969222546;11443.713907152222;11454.828177577343;11490.712732181762;11561.42192890352;554186;561395;581842;613417;613983;132146.33926830304;135982;138314;139901;140443;978.0757184460758;979.5249304087512;985.3989066669326;1008.844872620845;1010.7574182196367;17845.46463606094;18394.72407913909;19299.649024568705;19325.4202373929;19413.51466213356;49178;51903.18338858273;53636.19545706739;54167.18749218686;54715.72642136923;75164.24835751572;75263;76118.4776304482;79442.20557794579;79599.60200198962;6446.161998940108;6966;7685.654145563351;7916.247956444128;8368.24685778283;11275.548978040786;11995.580154694544;12640.367981600699;12905.677358066123;13049.021323400593;10705.464726763787;11121.610743623934;11195.832062518906;11315.490802914053;11405.661839323086;10654.89345106539;10739.731506712082;10849.349039057683;11200.551977920792;11226.382548959886;9220.861687074565;10558.996895294935;11199.552017919517;11452.312861228353;11578.305301681927;7179.389751870941;7318.121825380776;7324.597147156957;7378.96310518444;7570.1672815989095;8968.68609598661;10787;11232.55069797199;11264.09887209005;11274.605388811353 09:08:51 INFO - PROCESS | 1650 | |3;mootools.html;1371.52682324598;1506.5405051459304;1679.9160041997898;1871.6724573199704;1892.9242830286798;8487.660493580257;9004.414713043647;9006.774830629221;9007.96408413031;9009.549522523881;7321.267873212676;8085.91914080859;8088;8174.550399728013;8786.780330491742;1937.2541571495005;2014.8085931836451;2147.1840700533853;2305.3084074777585;2387.1883559589737;2362.787349138577;2370.3718514593647;2372.1815973489206;2373.9287821365388;2374.0741110966737;2298.5977453945493;2355.705536807899;2361.291612516247;2364.9408764780846;2365.0842817317944;2323.5236776460783;2323.9309917437913;2339.040993192791;2353.976460235393;2358.2217868103485;2012.5471768851892;2298.896529665753;2321.7330007049068;2326.139328448468;2335.7547457516885;8884.600192991331;8923.687670931486;9003.684871029482;9011.684591039348;9013.233875120623;5252.107141785906;5399.811006614749;5401.891962160774;5404.459554044564;5408.296921400232;3321.4519604265265;3502.8073455960166;3540.291941611688;3627.4740162676526;3698.130939229279;1030.6044719272309;1085.8968398002178;1105.182165197756;1106.6071544601712;1110.8111621024445;11715.531378744925;11775.587854425139;11785.587504437304;11787.233829801122;11805.232659877167;4367.781610919441;4449.28811390176;4540.977295113504;4548.6361091113295;4822.565969062731;10073;10073.94963025195;10075.748106297402;10077.647282345082;10087.798244035079;5406;5412.78348866041;5416.864578385572;5418.810341638024;5419.674819510842;4782.258749893703;4928.975355123202;4960.156773348539;4966.528179822911;4980.975095124501;3553.697935675445;3685.336639404933;3750.9062273443383;3866.6713329366758;3887.1253967857006;985.8071733202762;1160.257435241446;1163.7148898519918;1164.1501703756305;1179.215821478707;754.4605606991028;805.3355981315439;887.2369762004671;887.7381172554112;920.8121523234952;4912.484189160158;5416.404195538487;5660.688662123541;5974.074018527135;6210.72051757672;909.7680091576606;943.6414162618161;944.8441007233729;953.3517208298424;973.0658567775015;3433.896983090511;3804.372278574004;3842.8462861485227;3886.9805650971566;4004.759714417144;9182;10561.155107591374;10914.454277286104;10925.344479331265;10931.18016148792;10738.194635402377;10839.891601084046;10847.891521084846;10870.18473614482;10875.78248435027;7111.64441777909;8176;8193.79515512105;8274.669013239523;8275.00699916014;5938.6436813791315;5972.313183983897;5972.402759723993;6155.815325540241;6290.937090629035;9090.863637045317;9817.165540929102;9917.206623469961;10145.18838492904;10315;8657.350698697497;9536.713898583053;9701.757456063655;10220.795584088426;10455.634052808115;7487.925120748941;7630;7636.656350464354;7639.618019098912;7646.502977306457;7534.208908064575;7638.770836874903;7833.92166078332;8363.874541881754;8473.49159050459;7491.288327608869;7566.24337566239;7568.091828980558;7630.2751238632245;7684.538927664358;6266.780662676784;7437.702491900263;7446.515976461513;7493.325600695964;7587.468877178544;6463.676816159079;6714.160729908762;6895.931040689729;6934.791956241321;6999.860002799814;3704.3517384457073;4017.2367250222364;4281.507626622978;4300.182965236595;4359.673024523237;4824.059308434962;4956.727379994135;4973.950260497493;5060.1650727631;5211.687298762086 09:08:51 INFO - PROCESS | 1650 | |4;prototype.html;289.4789379117588;298.427020121367;307.5218035954092;316.30570896881324;316.9920751981202;4591.931121033183;5288.985278391314;5309.840704778858;5332.466753324666;5347.732613369326;3854.9807250963704;5256;5261.447548007462;5307.442718514559;5312.203169524573;3834.175652234762;3853.7687738735626;3956.7823769692654;4008.939865902014;4081.2857749893797;3310.453775127113;3437.5187473753695;4032.5765794591584;4059.6549293310104;4089.5501494835544;3197.473995543518;3794.4118661607495;3982.5264652078627;3996.2007598480277;4029.9798501007454;3761.3417651910804;3820.4842346283176;3911.6088391160943;4013.6989725770686;4022.7787471689044;3088.5830412894197;3276.361109583608;3452.5166476693284;3478.7043101336417;3480.8085555294447;4480.462344518646;5134.537891589737;5145.253938178943;5153.252778347119;5156.716380599066;3806.486124373202;4374.475062992429;4562.908741825178;4573.245414506636;4582.633389328879;4784;4790.928136077961;5131.640785145041;5444.428335024805;5559.916601250984;3859.208862183246;3877.9030524236837;3885.417187421881;3909.765414075136;3987.441758153861;5431.918521222185;5758.3090029196765;5833.358330583633;5849.61977471463;5862.618929769551;3869.245497128033;3874.4188371744463;3881.7088718346236;3886.378179491268;3896.59085795993;3349.581302337208;3908.7459315144424;3956.940645890314;3958.88123356294;3960.108975480494;3956.723029387915;4590.655700822452;4601.815927362935;4616.3306320584;4619.69971951822;5208.739563021833;5529.069593115655;5545.528630066489;5548.861278468071;5577.498025177672;1092.7814437112609;1326.1645163546905;1334.3661760663608;1347.5351003903638;1352.2359866675297;672.9057931889539;733.1275781819618;818.3739439328918;827.0075908909213;842.7850898020965;3951.38753493209;3999.9400008999305;5206;5223.9477605223465;5233.528982391603;5078.415982162099;5409.83770486886;5700.315962084494;5740.311162660424;5774.624649397776;5043.52086551777;5199.896002080013;5223.582113430916;5240.371155461293;5302.522772950453;2781.5966684830587;2789.8326100434037;2955.438466691322;2993.6108305920093;3018.2605261711024;4930.802767889316;5139.152039913447;5331.507177990169;5664.745086471119;5964.85087872798;4659.860204193879;5126.820561280412;5405.8107966221;5586.16207568868;5889.8233053007625;5023.824166154167;6002.358962056768;6002.399760023963;6029.547783916136;6030.798758453133;4954.9436983651385;5008.4240312364545;5099.736168851863;5605.663660180402;5794.826939897538;4404.405405270248;5232.5552328052545;5411.07906693134;5697.4302569742695;5753.626014309224;5450.373207081077;5539.147339120275;5978.461938425563;5985.2941911555;6021.819345419644;3075.7078077582596;3719.9442008369356;3869.729118961646;3911.7848518331766;3918.2947069526617;4738.431388233434;5277.856882347339;5506.724663766876;5809.883802324014;5854.765809367748;5250.136201648513;5653.134465626251;5665.036943719495;5666.943330566806;5669.603127781016;4618.676692631483;5404.648697834628;5620.4098569649595;5640.520555752807;5652.315150303573;5525.696086715108;5529.557635389078;5534.501894829485;5543.223948647112;5543.944560554342;4720.008798152427;4998.52572976379;5323.547498462674;5662.716864156693;5704.1443783432815;4686.671932964659;5246.31797866275;5261.289725887109;5287.286216360896;5503.715957877436 09:08:51 INFO - PROCESS | 1650 | |5;yui.html;695.7877847256586;726.5386479585462;738.1437532462345;793.9444238903279;809.5385630190792;15570.844291557081;16286.104264265461;16294.266757995889;16317.265723042492;16333.754993675104;14330.426782928696;14414.207218602998;14420.855791442082;14421.351039203235;14434.494792682259;686.9278725733801;709.9112610923635;846.1665259719172;850.6129710981504;874.7987962768567;844.5692696724653;856.3577317012241;858.274757829633;859.2653281444369;861.3711990247124;925.2413021322518;925.3476299209075;925.9444433333988;926.3746970794687;929.6839074714595;719.4568101083701;888.3648191543014;913.8949020862619;921.953902304882;931.5202670624634;699.912510936133;701.113091938698;701.6456689371835;702.9789106326767;703.078966553811;14967.775483367759;15009.24953752319;15017.324220410108;15027.849721502755;15051.021683590532;13749.587512374645;14066.92966535171;14115.153090814481;14135.858641413557;14386.28068596566;12880;12943.67640808978;12999.415026323839;13189.07676462648;13621.727565448735;4027.74603237698;4545.909081818379;4602.769861506911;4704.694194877322;4725.196716558194;112993.43503282513;116467.41766291115;119180;120489;120718;10991.890081099087;11140.387278699587;11166;11400.942995285137;11685.766284674259;17833.643327133384;21994.230201943174;22096.116155353928;22133.889330553244;22148;7576;7903.723369682149;8050.718224862102;8052.798680032929;8207.343412526983;4400.669949753717;4423.058665987147;4478.664100192498;4489.6408287336935;4499.977500112544;3458.4466485362223;3592.946105808415;3624;3629.038304849217;3640.344737947195;670.4837275297994;670.5138774388627;675.1627981303149;683.54885775388;684.5926673629282;602.5601311042964;662.3707477895922;675.3617831149614;681.4922882452605;683.6581709145428;9414.29392795543;9423.293253006052;9477.66828161025;9478.90521094794;9499.620015199314;559.5383808358121;633.2780630081711;662.4733336997099;671.513182655545;682.743971010871;4251.829926802955;4598.816047358068;4600.907981840344;4636.744979026119;4727.343179481062;96402;96600;96976;148051;153386.23306883394;112981;131465;134869;137119;139311.21377572595;80389.19610803817;103129;111829.88170118358;117178;118466;8522;8562.159135785836;8835.425697329652;8971.865422018805;9131.908680913106;44564.77717611391;54669.45330546803;59227;65311.346886530526;66789.33210667831;88561.55719221363;91843.08156918346;94062.87540063492;96324.51837740766;98389;11516.596919107791;11785.233959792586;11986.580469683518;12313.87686123163;12405.689857753629;12960.740785184054;13017;13194.548151688114;13347.933260333637;13480.393382297623;10211.591536338463;10969.945150274198;13247.735045299232;13336.799948000593;13507.864921350661;13335.933320333335;13702.314884255948;13871.51449699296;14002.168873677967;14084.436622534984;13164.736705266032;13247.80128298057;13459.932700336436;13511.391987360395;13715.314234288046;10223.488825558841;10383.273170877967;10652.627158049741;10908.945455272673;11003.394813285026;10875.293105948089;11352.205345625727;11473.426328683365;11602.70993225176;11613.477393517143 09:08:51 INFO - PROCESS | 1650 | __end_tp_report 09:08:51 INFO - PROCESS | 1650 | __start_cc_report 09:08:51 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1411 09:08:51 INFO - PROCESS | 1650 | __end_cc_report 09:08:51 INFO - PROCESS | 1650 | __startTimestamp1472573331450__endTimestamp 09:08:51 INFO - PROCESS | 1650 | 09:08:51 INFO - PROCESS | 1650 | ------- Summary: start ------- 09:08:51 INFO - PROCESS | 1650 | Number of tests: 6 09:08:51 INFO - PROCESS | 1650 | 09:08:51 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5751.71 Median:5965.52 stddev:654.18 (11.0%) stddev-sans-first:617.95 09:08:51 INFO - PROCESS | 1650 | Values: 2942.4 3328.6 3335.1 3342.3 3418.5 5601.5 5628.7 5695.9 5698.2 5706.1 5685.6 5721.7 5722.3 5731.7 5737.3 5176.7 5215.1 5216.1 5249.2 5292.0 5259.3 5274.1 5283.0 5287.5 5288.0 5200.3 5226.6 5237.0 5246.8 5279.0 5292.3 5316.3 5322.1 5330.4 5346.2 5102.4 5127.7 5155.1 5242.5 5248.6 5262.2 5684.8 5732.5 5757.5 5803.3 6238.4 6271.5 6279.8 6298.9 6303.6 5762.8 6124.8 6220.3 6322.5 6343.7 5862.1 5880.0 5897.6 5899.1 5905.8 6230.9 6294.5 6300.1 6307.6 6307.9 6029.4 6099.5 6124.7 6173.3 6185.7 6235.1 6270.8 6284.1 6286.3 6294.5 6165.6 6216.0 6239.5 6239.9 6249.8 6077.6 6105.2 6141.9 6250.1 6263.2 4908.5 4912.0 4921.0 4922.0 4924.5 4454.6 4486.2 4487.0 4489.7 4506.4 4830.0 5677.2 5735.4 5740.5 5802.4 6242.3 6316.6 6322.8 6339.7 6354.4 5760.7 5776.8 5787.2 5792.1 5839.6 5362.2 5400.9 5425.6 5436.2 5440.5 6327.8 6341.3 6345.9 6347.1 6357.5 6310.8 6313.7 6319.3 6324.0 6328.7 6203.4 6228.1 6244.9 6335.0 6340.3 6145.7 6208.8 6216.3 6236.6 6244.9 6283.8 6289.5 6292.2 6299.8 6317.7 5856.2 5982.8 6031.7 6122.3 6143.0 6062.2 6067.2 6128.3 6150.4 6151.3 4954.6 5972.7 5980.7 5986.7 5990.2 5888.4 6128.6 6134.2 6137.1 6148.5 5799.9 5910.5 5960.5 5965.5 6046.6 09:08:51 INFO - PROCESS | 1650 | 09:08:51 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:76642.58 Median:20015.18 stddev:175795.16 (878.3%) stddev-sans-first:176195.35 09:08:51 INFO - PROCESS | 1650 | Values: 499.8 520.8 526.9 530.7 538.1 31220.2 40759.4 41889.0 42083.8 42086.8 43929.6 44371.6 44525.6 44528.6 44540.9 9630.9 9821.3 9829.8 9834.5 9848.2 8762.3 11906.6 12195.2 12200.1 12370.4 12123.8 12124.0 12201.8 12211.6 12216.8 12162.7 12239.9 12241.8 12243.8 12250.1 10034.7 11977.3 11989.6 12021.7 12054.8 33678.0 40997.0 41237.6 41297.0 41410.6 2733.2 3465.3 3615.7 3718.7 4050.0 30749.7 31164.0 31787.8 31849.5 31907.0 3296.3 3296.7 3326.0 3362.9 3366.0 502929.0 505595.0 505635.0 506073.0 510089.4 61414.0 61737.7 61851.0 61897.0 61908.0 63603.4 63619.7 63729.4 63793.0 63863.7 16346.2 16585.8 16615.0 16615.0 16681.9 11736.8 12961.0 13013.7 13023.5 13024.3 15187.8 17184.5 17588.5 17596.4 18153.5 5857.9 5890.9 5902.4 6971.6 7054.6 893.0 952.8 994.0 1041.2 1098.7 22548.7 30075.2 30599.5 31057.5 31280.8 1005.7 1040.5 1055.3 1087.9 1095.4 13048.7 14676.5 15577.0 15616.5 15951.4 664152.0 705205.0 705311.0 706008.0 957746.0 51152.7 52034.0 57299.1 58439.8 58940.4 1384.7 1396.2 1412.5 1415.8 1419.9 26594.2 28787.6 30506.4 31365.7 32274.0 51318.0 56095.9 57133.0 57295.0 59980.0 620220.0 656214.0 658291.0 667971.0 774486.0 25356.7 25649.0 25700.9 25992.2 25998.0 45079.5 45493.8 45647.8 46167.0 46224.5 37527.4 37728.2 37824.6 38761.8 39048.4 37072.6 37177.8 38023.8 38382.8 38522.6 36414.6 36601.1 36997.6 37323.1 37771.4 32036.5 33510.8 33604.5 33731.7 33769.3 22226.0 24617.8 25140.6 25207.7 25703.9 09:08:51 INFO - PROCESS | 1650 | 09:08:51 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:36338.48 Median:35339.97 stddev:97782.26 (276.7%) stddev-sans-first:98019.24 09:08:51 INFO - PROCESS | 1650 | Values: 355.5 371.7 379.0 386.5 409.1 15348.0 16210.9 21709.6 21873.6 21925.9 20874.8 20960.7 21631.2 22284.7 22554.9 5926.3 6191.1 7590.1 7981.9 8077.3 7676.1 7963.2 7978.8 7995.3 8028.6 8090.8 8099.4 8112.3 8119.8 8173.1 7514.4 7885.0 7934.8 7940.1 7949.3 7359.8 7564.8 7712.3 7724.8 7727.3 24293.0 27791.9 28617.4 28850.9 29624.0 2367.2 2435.5 2516.6 3313.0 3487.4 12227.3 16553.8 16676.0 17382.0 17657.6 1988.1 1994.5 2016.5 2067.0 2138.5 72733.5 92846.0 117577.7 127870.0 153530.0 17968.3 18356.0 21252.6 21510.1 21608.0 35213.6 35323.6 35356.3 35383.6 35435.0 10633.9 10812.2 11010.2 11029.7 11172.2 8993.1 8999.0 9013.3 9219.4 9224.9 9447.7 9539.0 9561.5 9663.2 9665.5 2977.6 3582.3 3635.6 3709.7 3747.8 733.4 875.5 889.6 902.9 956.3 21057.4 21884.0 21929.3 21941.0 21971.8 905.3 915.5 939.2 949.6 955.7 10965.1 11443.7 11454.8 11490.7 11561.4 554186.0 561395.0 581842.0 613417.0 613983.0 132146.3 135982.0 138314.0 139901.0 140443.0 978.1 979.5 985.4 1008.8 1010.8 17845.5 18394.7 19299.6 19325.4 19413.5 49178.0 51903.2 53636.2 54167.2 54715.7 75164.2 75263.0 76118.5 79442.2 79599.6 6446.2 6966.0 7685.7 7916.2 8368.2 11275.5 11995.6 12640.4 12905.7 13049.0 10705.5 11121.6 11195.8 11315.5 11405.7 10654.9 10739.7 10849.3 11200.6 11226.4 9220.9 10559.0 11199.6 11452.3 11578.3 7179.4 7318.1 7324.6 7379.0 7570.2 8968.7 10787.0 11232.6 11264.1 11274.6 09:08:51 INFO - PROCESS | 1650 | 09:08:51 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5646.54 Median:4942.85 stddev:3210.45 (65.0%) stddev-sans-first:3203.38 09:08:51 INFO - PROCESS | 1650 | Values: 1371.5 1506.5 1679.9 1871.7 1892.9 8487.7 9004.4 9006.8 9008.0 9009.5 7321.3 8085.9 8088.0 8174.6 8786.8 1937.3 2014.8 2147.2 2305.3 2387.2 2362.8 2370.4 2372.2 2373.9 2374.1 2298.6 2355.7 2361.3 2364.9 2365.1 2323.5 2323.9 2339.0 2354.0 2358.2 2012.5 2298.9 2321.7 2326.1 2335.8 8884.6 8923.7 9003.7 9011.7 9013.2 5252.1 5399.8 5401.9 5404.5 5408.3 3321.5 3502.8 3540.3 3627.5 3698.1 1030.6 1085.9 1105.2 1106.6 1110.8 11715.5 11775.6 11785.6 11787.2 11805.2 4367.8 4449.3 4541.0 4548.6 4822.6 10073.0 10073.9 10075.7 10077.6 10087.8 5406.0 5412.8 5416.9 5418.8 5419.7 4782.3 4929.0 4960.2 4966.5 4981.0 3553.7 3685.3 3750.9 3866.7 3887.1 985.8 1160.3 1163.7 1164.2 1179.2 754.5 805.3 887.2 887.7 920.8 4912.5 5416.4 5660.7 5974.1 6210.7 909.8 943.6 944.8 953.4 973.1 3433.9 3804.4 3842.8 3887.0 4004.8 9182.0 10561.2 10914.5 10925.3 10931.2 10738.2 10839.9 10847.9 10870.2 10875.8 7111.6 8176.0 8193.8 8274.7 8275.0 5938.6 5972.3 5972.4 6155.8 6290.9 9090.9 9817.2 9917.2 10145.2 10315.0 8657.4 9536.7 9701.8 10220.8 10455.6 7487.9 7630.0 7636.7 7639.6 7646.5 7534.2 7638.8 7833.9 8363.9 8473.5 7491.3 7566.2 7568.1 7630.3 7684.5 6266.8 7437.7 7446.5 7493.3 7587.5 6463.7 6714.2 6895.9 6934.8 6999.9 3704.4 4017.2 4281.5 4300.2 4359.7 4824.1 4956.7 4974.0 5060.2 5211.7 09:08:51 INFO - PROCESS | 1650 | 09:08:51 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4462.08 Median:5113.28 stddev:1387.25 (27.1%) stddev-sans-first:1355.33 09:08:51 INFO - PROCESS | 1650 | Values: 289.5 298.4 307.5 316.3 317.0 4591.9 5289.0 5309.8 5332.5 5347.7 3855.0 5256.0 5261.4 5307.4 5312.2 3834.2 3853.8 3956.8 4008.9 4081.3 3310.5 3437.5 4032.6 4059.7 4089.6 3197.5 3794.4 3982.5 3996.2 4030.0 3761.3 3820.5 3911.6 4013.7 4022.8 3088.6 3276.4 3452.5 3478.7 3480.8 4480.5 5134.5 5145.3 5153.3 5156.7 3806.5 4374.5 4562.9 4573.2 4582.6 4784.0 4790.9 5131.6 5444.4 5559.9 3859.2 3877.9 3885.4 3909.8 3987.4 5431.9 5758.3 5833.4 5849.6 5862.6 3869.2 3874.4 3881.7 3886.4 3896.6 3349.6 3908.7 3956.9 3958.9 3960.1 3956.7 4590.7 4601.8 4616.3 4619.7 5208.7 5529.1 5545.5 5548.9 5577.5 1092.8 1326.2 1334.4 1347.5 1352.2 672.9 733.1 818.4 827.0 842.8 3951.4 3999.9 5206.0 5223.9 5233.5 5078.4 5409.8 5700.3 5740.3 5774.6 5043.5 5199.9 5223.6 5240.4 5302.5 2781.6 2789.8 2955.4 2993.6 3018.3 4930.8 5139.2 5331.5 5664.7 5964.9 4659.9 5126.8 5405.8 5586.2 5889.8 5023.8 6002.4 6002.4 6029.5 6030.8 4954.9 5008.4 5099.7 5605.7 5794.8 4404.4 5232.6 5411.1 5697.4 5753.6 5450.4 5539.1 5978.5 5985.3 6021.8 3075.7 3719.9 3869.7 3911.8 3918.3 4738.4 5277.9 5506.7 5809.9 5854.8 5250.1 5653.1 5665.0 5666.9 5669.6 4618.7 5404.6 5620.4 5640.5 5652.3 5525.7 5529.6 5534.5 5543.2 5543.9 4720.0 4998.5 5323.5 5662.7 5704.1 4686.7 5246.3 5261.3 5287.3 5503.7 09:08:51 INFO - PROCESS | 1650 | 09:08:51 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:24105.44 Median:3834.05 stddev:38025.30 (991.8%) stddev-sans-first:38091.35 09:08:51 INFO - PROCESS | 1650 | Values: 695.8 726.5 738.1 793.9 809.5 15570.8 16286.1 16294.3 16317.3 16333.8 14330.4 14414.2 14420.9 14421.4 14434.5 686.9 709.9 846.2 850.6 874.8 844.6 856.4 858.3 859.3 861.4 925.2 925.3 925.9 926.4 929.7 719.5 888.4 913.9 922.0 931.5 699.9 701.1 701.6 703.0 703.1 14967.8 15009.2 15017.3 15027.8 15051.0 13749.6 14066.9 14115.2 14135.9 14386.3 12880.0 12943.7 12999.4 13189.1 13621.7 4027.7 4545.9 4602.8 4704.7 4725.2 112993.4 116467.4 119180.0 120489.0 120718.0 10991.9 11140.4 11166.0 11400.9 11685.8 17833.6 21994.2 22096.1 22133.9 22148.0 7576.0 7903.7 8050.7 8052.8 8207.3 4400.7 4423.1 4478.7 4489.6 4500.0 3458.4 3592.9 3624.0 3629.0 3640.3 670.5 670.5 675.2 683.5 684.6 602.6 662.4 675.4 681.5 683.7 9414.3 9423.3 9477.7 9478.9 9499.6 559.5 633.3 662.5 671.5 682.7 4251.8 4598.8 4600.9 4636.7 4727.3 96402.0 96600.0 96976.0 148051.0 153386.2 112981.0 131465.0 134869.0 137119.0 139311.2 80389.2 103129.0 111829.9 117178.0 118466.0 8522.0 8562.2 8835.4 8971.9 9131.9 44564.8 54669.5 59227.0 65311.3 66789.3 88561.6 91843.1 94062.9 96324.5 98389.0 11516.6 11785.2 11986.6 12313.9 12405.7 12960.7 13017.0 13194.5 13347.9 13480.4 10211.6 10969.9 13247.7 13336.8 13507.9 13335.9 13702.3 13871.5 14002.2 14084.4 13164.7 13247.8 13459.9 13511.4 13715.3 10223.5 10383.3 10652.6 10908.9 11003.4 10875.3 11352.2 11473.4 11602.7 11613.5 09:08:51 INFO - PROCESS | 1650 | -------- Summary: end -------- 09:08:51 INFO - PROCESS | 1650 | 09:08:52 INFO - TEST-INFO | 1650: exit 0 09:08:52 INFO - TEST-OK | dromaeo_css | took 1118039ms 09:08:52 INFO - TEST-START | kraken 09:08:52 INFO - operating with platform_type : mac_ 09:08:52 INFO - Initialising browser for kraken test... 09:08:52 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmp5BL1Bw/profile http://localhost:49264/getInfo.html) 09:08:54 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 09:08:54 INFO - PROCESS | 1656 | colorDepth:24 09:08:54 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 09:08:54 INFO - PROCESS | 1656 | __metrics 09:08:54 INFO - PROCESS | 1656 | 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" 09:08:54 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:08:55 INFO - TEST-INFO | 1656: exit 0 09:08:55 INFO - Browser initialized. 09:08:55 INFO - Running cycle 1/1 for kraken test... 09:08:55 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmp5BL1Bw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:09:07 INFO - PROCESS | 1657 | RSS: Main: 234278912 09:09:07 INFO - PROCESS | 1657 | 09:09:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-beat-detection) 09:09:11 INFO - PROCESS | 1657 | RSS: Main: 292225024 09:09:11 INFO - PROCESS | 1657 | 09:09:13 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-dft) 09:09:14 INFO - PROCESS | 1657 | RSS: Main: 288051200 09:09:14 INFO - PROCESS | 1657 | 09:09:17 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-fft) 09:09:18 INFO - PROCESS | 1657 | RSS: Main: 378433536 09:09:18 INFO - PROCESS | 1657 | 09:09:20 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-oscillator) 09:09:21 INFO - PROCESS | 1657 | RSS: Main: 280469504 09:09:21 INFO - PROCESS | 1657 | 09:09:23 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:09:23 INFO - PROCESS | 1657 | RSS: Main: 283783168 09:09:23 INFO - PROCESS | 1657 | 09:09:26 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-darkroom) 09:09:27 INFO - PROCESS | 1657 | RSS: Main: 322842624 09:09:27 INFO - PROCESS | 1657 | 09:09:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-desaturate) 09:09:30 INFO - PROCESS | 1657 | RSS: Main: 323747840 09:09:30 INFO - PROCESS | 1657 | 09:09:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49264/tests/kraken/driver.html?testName=json-parse-financial) 09:09:34 INFO - PROCESS | 1657 | RSS: Main: 321589248 09:09:34 INFO - PROCESS | 1657 | 09:09:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49264/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:09:36 INFO - PROCESS | 1657 | RSS: Main: 297717760 09:09:36 INFO - PROCESS | 1657 | 09:09:38 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:09:38 INFO - PROCESS | 1657 | RSS: Main: 302145536 09:09:38 INFO - PROCESS | 1657 | 09:09:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:09:41 INFO - PROCESS | 1657 | RSS: Main: 310276096 09:09:41 INFO - PROCESS | 1657 | 09:09:44 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:09:45 INFO - PROCESS | 1657 | RSS: Main: 313040896 09:09:45 INFO - PROCESS | 1657 | 09:09:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:09:49 INFO - PROCESS | 1657 | RSS: Main: 314437632 09:09:49 INFO - PROCESS | 1657 | 09:09:50 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49264/tests/kraken/driver.html?testName=ai-astar) 09:09:51 INFO - PROCESS | 1657 | RSS: Main: 357945344 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | __start_tp_report 09:09:51 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 09:09:51 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 09:09:51 INFO - PROCESS | 1657 | |i|pagename|runs| 09:09:51 INFO - PROCESS | 1657 | |0;ai-astar;151;165;179;112;111;112;112;112;112;112 09:09:51 INFO - PROCESS | 1657 | |1;audio-beat-detection;148;114;129;159;104;133;154;136;171;106 09:09:51 INFO - PROCESS | 1657 | |2;audio-dft;185;184;187;184;181;183;184;179;180;196 09:09:51 INFO - PROCESS | 1657 | |3;audio-fft;75;76;76;152;57;67;58;68;141;57 09:09:51 INFO - PROCESS | 1657 | |4;audio-oscillator;80;82;137;71;70;91;138;100;101;140 09:09:51 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;86;83;86;83;86;86;126;82;87;83 09:09:51 INFO - PROCESS | 1657 | |6;imaging-darkroom;92;93;93;92;92;92;97;92;91;92 09:09:51 INFO - PROCESS | 1657 | |7;imaging-desaturate;87;84;86;84;86;84;102;147;148;85 09:09:51 INFO - PROCESS | 1657 | |8;json-parse-financial;55;53;55;55;54;55;55;55;54;53 09:09:51 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;66;66;70;77;77;71;70;70;71;72 09:09:51 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;83;88;84;84;88;88;85;91;90;111 09:09:51 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;150;153;126;138;160;126;148;149;162;144 09:09:51 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;174;234;218;170;173;167;168;173;230;201 09:09:51 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;68;68;67;68;67;65;69;67;68;66 09:09:51 INFO - PROCESS | 1657 | __end_tp_report 09:09:51 INFO - PROCESS | 1657 | __start_cc_report 09:09:51 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2026 09:09:51 INFO - PROCESS | 1657 | __end_cc_report 09:09:51 INFO - PROCESS | 1657 | __startTimestamp1472573391135__endTimestamp 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | ------- Summary: start ------- 09:09:51 INFO - PROCESS | 1657 | Number of tests: 14 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:127.80 Median:112.00 stddev:26.51 (23.7%) stddev-sans-first:26.75 09:09:51 INFO - PROCESS | 1657 | Values: 151.0 165.0 179.0 112.0 111.0 112.0 112.0 112.0 112.0 112.0 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:135.40 Median:142.00 stddev:22.81 (16.1%) stddev-sans-first:23.74 09:09:51 INFO - PROCESS | 1657 | Values: 148.0 114.0 129.0 159.0 104.0 133.0 154.0 136.0 171.0 106.0 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:184.30 Median:184.00 stddev:4.76 (2.6%) stddev-sans-first:5.04 09:09:51 INFO - PROCESS | 1657 | Values: 185.0 184.0 187.0 184.0 181.0 183.0 184.0 179.0 180.0 196.0 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:82.70 Median:67.50 stddev:34.55 (51.2%) stddev-sans-first:36.53 09:09:51 INFO - PROCESS | 1657 | Values: 75.0 76.0 76.0 152.0 57.0 67.0 58.0 68.0 141.0 57.0 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:101.00 Median:70.50 stddev:27.79 (39.4%) stddev-sans-first:28.42 09:09:51 INFO - PROCESS | 1657 | Values: 80.0 82.0 137.0 71.0 70.0 91.0 138.0 100.0 101.0 140.0 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:88.80 Median:86.00 stddev:13.19 (15.3%) stddev-sans-first:13.95 09:09:51 INFO - PROCESS | 1657 | Values: 86.0 83.0 86.0 83.0 86.0 86.0 126.0 82.0 87.0 83.0 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:92.60 Median:92.00 stddev:1.65 (1.8%) stddev-sans-first:1.73 09:09:51 INFO - PROCESS | 1657 | Values: 92.0 93.0 93.0 92.0 92.0 92.0 97.0 92.0 91.0 92.0 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:99.30 Median:84.50 stddev:25.96 (30.7%) stddev-sans-first:27.15 09:09:51 INFO - PROCESS | 1657 | Values: 87.0 84.0 86.0 84.0 86.0 84.0 102.0 147.0 148.0 85.0 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:0.84 (1.5%) stddev-sans-first:0.87 09:09:51 INFO - PROCESS | 1657 | Values: 55.0 53.0 55.0 55.0 54.0 55.0 55.0 55.0 54.0 53.0 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:3.74 (5.3%) stddev-sans-first:3.50 09:09:51 INFO - PROCESS | 1657 | Values: 66.0 66.0 70.0 77.0 77.0 71.0 70.0 70.0 71.0 72.0 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:88.00 stddev:8.12 (9.2%) stddev-sans-first:8.30 09:09:51 INFO - PROCESS | 1657 | Values: 83.0 88.0 84.0 84.0 88.0 88.0 85.0 91.0 90.0 111.0 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:145.60 Median:149.50 stddev:12.46 (8.3%) stddev-sans-first:13.11 09:09:51 INFO - PROCESS | 1657 | Values: 150.0 153.0 126.0 138.0 160.0 126.0 148.0 149.0 162.0 144.0 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.80 Median:187.50 stddev:27.25 (14.5%) stddev-sans-first:28.21 09:09:51 INFO - PROCESS | 1657 | Values: 174.0 234.0 218.0 170.0 173.0 167.0 168.0 173.0 230.0 201.0 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:1.16 (1.7%) stddev-sans-first:1.20 09:09:51 INFO - PROCESS | 1657 | Values: 68.0 68.0 67.0 68.0 67.0 65.0 69.0 67.0 68.0 66.0 09:09:51 INFO - PROCESS | 1657 | -------- Summary: end -------- 09:09:51 INFO - PROCESS | 1657 | 09:09:51 INFO - TEST-INFO | 1657: exit 0 09:09:51 INFO - TEST-OK | kraken | took 59918ms 09:09:51 INFO - SUITE-END | took 1177s 09:09:52 INFO - Completed test suite (00:19:38) 09:09:52 INFO - javascript benchmark 09:09:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2942.4115176964588, 3328.6005679318478, 3335.066181469188, 3342.2647017656113, 3418.4872269159623, 5601.467860553241, 5628.662280263182, 5695.857603559908, 5698.230738850254, 5706.115552089423, 5685.602007859451, 5721.6853073080965, 5722.313322401317, 5731.742071606777, 5737.282839645044, 5176.689398636085, 5215.113430716769, 5216.087184742674, 5249.1601343785005, 5292.0, 5259.31628888244, 5274.077036518613, 5283.022640811462, 5287.49768771968, 5287.973560132194, 5200.29795977542, 5226.5818734501145, 5237.0, 5246.842594722164, 5279.049771041211, 5292.285541451932, 5316.308879845596, 5322.0549819629305, 5330.360356757214, 5346.198070289449, 5102.413222479426, 5127.666701664417, 5155.0978578748945, 5242.501962313574, 5248.580113590903, 5262.210668399733, 5684.8010319639025, 5732.512736417368, 5757.453041961007, 5803.332616749086, 6238.36590051665, 6271.498280137578, 6279.843003924892, 6298.905516417258, 6303.621782693053, 5762.798302059407, 6124.816255512342, 6220.284667263232, 6322.462590679797, 6343.714532846033, 5862.091375836752, 5880.0, 5897.64614123154, 5899.115132730039, 5905.822825315161, 6230.906536401958, 6294.527910406738, 6300.086487459293, 6307.621542707452, 6307.9369206308265, 6029.427204415573, 6099.451049405575, 6124.724387402578, 6173.32093469718, 6185.690715464299, 6235.127082208494, 6270.843228919313, 6284.12022316876, 6286.339934306923, 6294.464970477561, 6165.630062196283, 6216.0, 6239.500839932794, 6239.937600624026, 6249.812505624838, 6077.574569780161, 6105.15650299495, 6141.938580614226, 6250.0937364082865, 6263.185785847902, 4908.533689299511, 4911.968486617779, 4920.950790492121, 4921.9507804921495, 4924.4583095859425, 4454.643628509712, 4486.192485352668, 4486.977565112219, 4489.663275254369, 4506.3916371290115, 4829.95170048295, 5677.17680936262, 5735.397783232784, 5740.512056475246, 5802.419758024248, 6242.2509298883515, 6316.62100273985, 6322.778702745381, 6339.683015849189, 6354.4281014708895, 5760.74076666551, 5776.797812076641, 5787.189793428924, 5792.102224155263, 5839.591228613956, 5362.2224777407055, 5400.864978375572, 5425.620206585501, 5436.238926550286, 5440.455954404528, 6327.810165695037, 6341.302456729756, 6345.904811427832, 6347.143135676624, 6357.4596159326975, 6310.7791227306825, 6313.684315784284, 6319.273283572448, 6324.0, 6328.715207815567, 6203.379662033761, 6228.096925945803, 6244.906326405016, 6334.954732469091, 6340.334264902119, 6145.66198859067, 6208.751649934133, 6216.347283535164, 6236.563440559117, 6244.906326405198, 6283.780067697791, 6289.465395441439, 6292.244930608356, 6299.811005669837, 6317.747290108344, 5856.238688970407, 5982.790602328898, 6031.728572214349, 6122.295935967242, 6143.047827586731, 6062.181605483203, 6067.150598916243, 6128.264608247039, 6150.415710507495, 6151.323354431099, 4954.578860796729, 5972.671503067199, 5980.671063091571, 5986.730597123227, 5990.161377407254, 5888.440598143169, 6128.571000030133, 6134.202553667995, 6137.140800288053, 6148.4773794227995, 5799.884002319845, 5910.497607703393, 5960.493358064613, 5965.522758179422, 6046.5767396281835], "value": 5708.531799982928, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.7651103981129, 520.7838746920029, 526.8630156159397, 530.7266757619826, 538.0530266730553, 31220.21949451262, 40759.38860917085, 41889.0, 42083.78958105209, 42086.789566052124, 43929.56070439295, 44371.556284437145, 44525.554744452544, 44528.55471445285, 44540.88647783807, 9630.903690963089, 9821.2634052446, 9829.754256143582, 9834.459104749236, 9848.212143028577, 8762.255208307302, 11906.583269585522, 12195.207311524768, 12200.145989780718, 12370.44333005017, 12123.757524849498, 12124.030077593816, 12201.816972745415, 12211.572594959178, 12216.755664886698, 12162.695932601668, 12239.938800305987, 12241.755164896653, 12243.755124897541, 12250.142490025703, 10034.69895903124, 11977.281363118242, 11989.64031079069, 12021.699457513545, 12054.758904821854, 33678.0, 40996.97507562305, 41237.587624123975, 41297.0, 41410.585894140975, 2733.2073698627573, 3465.2673663168416, 3615.7288203384587, 3718.7396882218254, 4050.0, 30749.69250307513, 31164.0, 31787.84106079478, 31849.52225716616, 31907.0, 3296.2583418730833, 3296.703296703284, 3325.9501107483406, 3362.9159271018175, 3366.0, 502929.0, 505595.0, 505635.0, 506073.0, 510089.4495527536, 61414.0, 61737.69131154315, 61851.0, 61897.0, 61908.0, 63603.36396636067, 63619.68190159019, 63729.36270637327, 63793.0, 63863.68068159722, 16346.18269086541, 16585.751213731804, 16615.0, 16615.00309981405, 16681.91659041697, 11736.823947640793, 12960.963122950141, 13013.739725205443, 13023.544175953795, 13024.283664398548, 15187.772183417257, 17184.508246304467, 17588.47234582939, 17596.38412655551, 18153.546161345806, 5857.882842343214, 5890.882182356415, 5902.3507414184405, 6971.58170509771, 7054.576725396492, 892.9955350223207, 952.7522844060455, 993.9850902236473, 1041.1514615588424, 1098.7143342730787, 22548.66177007346, 30075.248118797204, 30599.541006884916, 31057.534136987964, 31280.84359578233, 1005.6982905128432, 1040.5317607076695, 1055.3298655353906, 1087.8694556653252, 1095.3592148593002, 13048.739025219442, 14676.486322978644, 15577.0, 15616.531504054896, 15951.410932540086, 664152.0, 705205.0, 705311.0, 706008.0, 957746.0, 51152.74423627858, 52034.0, 57299.14051289234, 58439.831203375696, 58940.41059589435, 1384.7299776543477, 1396.190209678384, 1412.5056230319306, 1415.8017877497364, 1419.9361028753524, 26594.202173934813, 28787.5681864768, 30506.389872202875, 31365.68634313719, 32274.0, 51318.0, 56095.87808243731, 57133.0, 57295.0, 59980.0, 620220.0, 656214.0, 658291.0, 667971.0, 774486.0, 25356.746432536176, 25649.0, 25700.8714956424, 25992.22023339303, 25998.0, 45079.54920450754, 45493.77253113713, 45647.77176114098, 46167.0, 46224.537754622026, 37527.43708844315, 37728.2454350906, 37824.62175378211, 38761.8371448857, 39048.41427378535, 37072.62927370799, 37177.81411092927, 38023.809880950415, 38382.80808595939, 38522.56990446134, 36414.63585364113, 36601.11813199136, 36997.63002369942, 37323.06692332605, 37771.43342849804, 32036.51945220777, 33510.832445837616, 33604.495932560545, 33731.662683373834, 33769.324613508084, 22226.0, 24617.753822461545, 25140.62289065629, 25207.74792252054, 25703.871480643225], "value": 20571.06988988347, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.46502513716854, 371.6599311629858, 378.96968242540584, 386.5477391452002, 409.11834995584525, 15348.0, 16210.918945405272, 21709.565808683816, 21873.562528749455, 21925.890370548146, 20874.791252087474, 20960.685589716166, 21631.242906498275, 22284.66573001402, 22554.88722556389, 5926.318473375563, 6191.133241346215, 7590.127135379446, 7981.880271795899, 8077.313428358567, 7676.1172465166665, 7963.163867793856, 7978.840423191533, 7995.2804247617705, 8028.638711258008, 8090.757277281661, 8099.392545559077, 8112.269895709385, 8119.756407307761, 8173.141820108892, 7514.39884809214, 7884.960575197088, 7934.841303173962, 7940.1265860755275, 7949.324307433875, 7359.785635370157, 7564.848703025908, 7712.3444507216955, 7724.806879827993, 7727.343175830061, 24293.028278868824, 27791.86104069487, 28617.427651446855, 28850.855745721346, 29623.963161289466, 2367.206985659806, 2435.5372479229068, 2516.572182728922, 3312.9834350828332, 3487.4420092785285, 12227.266364018187, 16553.751693724604, 16676.0, 17381.957082574958, 17657.646847063115, 1988.0854806788896, 1994.5412555112405, 2016.4958760309923, 2066.9793302066937, 2138.486763176842, 72733.52925858427, 92846.0, 117577.70900309506, 127870.0, 153530.0, 17968.281268749102, 18356.0, 21252.574948500944, 21510.139594416047, 21608.0, 35213.64786352104, 35323.646763532546, 35356.292874142375, 35383.64616353855, 35435.0, 10633.893661063445, 10812.189085818594, 11010.229283950206, 11029.669109926715, 11172.21794474379, 8993.100689930954, 8998.955005225063, 9013.278937684969, 9219.44683319003, 9224.8616270756, 9447.669331573363, 9539.0, 9561.521923903778, 9663.226941844636, 9665.468399238109, 2977.56825260336, 3582.3372676055014, 3635.563732352082, 3709.684676802502, 3747.8126093695205, 733.3583114774573, 875.5315905990392, 889.573004957611, 902.9277657787361, 956.3018996132862, 21057.368278951657, 21884.0, 21929.342119736433, 21941.01265443059, 21971.780282196974, 905.2531661379388, 915.5193523400135, 939.2345238630494, 949.6486300069018, 955.6681696660265, 10965.067969222546, 11443.713907152222, 11454.828177577343, 11490.712732181762, 11561.42192890352, 554186.0, 561395.0, 581842.0, 613417.0, 613983.0, 132146.33926830304, 135982.0, 138314.0, 139901.0, 140443.0, 978.0757184460758, 979.5249304087512, 985.3989066669326, 1008.844872620845, 1010.7574182196367, 17845.46463606094, 18394.72407913909, 19299.649024568705, 19325.4202373929, 19413.51466213356, 49178.0, 51903.18338858273, 53636.19545706739, 54167.18749218686, 54715.72642136923, 75164.24835751572, 75263.0, 76118.4776304482, 79442.20557794579, 79599.60200198962, 6446.161998940108, 6966.0, 7685.654145563351, 7916.247956444128, 8368.24685778283, 11275.548978040786, 11995.580154694544, 12640.367981600699, 12905.677358066123, 13049.021323400593, 10705.464726763787, 11121.610743623934, 11195.832062518906, 11315.490802914053, 11405.661839323086, 10654.89345106539, 10739.731506712082, 10849.349039057683, 11200.551977920792, 11226.382548959886, 9220.861687074565, 10558.996895294935, 11199.552017919517, 11452.312861228353, 11578.305301681927, 7179.389751870941, 7318.121825380776, 7324.597147156957, 7378.96310518444, 7570.1672815989095, 8968.68609598661, 10787.0, 11232.55069797199, 11264.09887209005, 11274.605388811353], "value": 11050.497238622285, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.52682324598, 1506.5405051459304, 1679.9160041997898, 1871.6724573199704, 1892.9242830286798, 8487.660493580257, 9004.414713043647, 9006.774830629221, 9007.96408413031, 9009.549522523881, 7321.267873212676, 8085.91914080859, 8088.0, 8174.550399728013, 8786.780330491742, 1937.2541571495005, 2014.8085931836451, 2147.1840700533853, 2305.3084074777585, 2387.1883559589737, 2362.787349138577, 2370.3718514593647, 2372.1815973489206, 2373.9287821365388, 2374.0741110966737, 2298.5977453945493, 2355.705536807899, 2361.291612516247, 2364.9408764780846, 2365.0842817317944, 2323.5236776460783, 2323.9309917437913, 2339.040993192791, 2353.976460235393, 2358.2217868103485, 2012.5471768851892, 2298.896529665753, 2321.7330007049068, 2326.139328448468, 2335.7547457516885, 8884.600192991331, 8923.687670931486, 9003.684871029482, 9011.684591039348, 9013.233875120623, 5252.107141785906, 5399.811006614749, 5401.891962160774, 5404.459554044564, 5408.296921400232, 3321.4519604265265, 3502.8073455960166, 3540.291941611688, 3627.4740162676526, 3698.130939229279, 1030.6044719272309, 1085.8968398002178, 1105.182165197756, 1106.6071544601712, 1110.8111621024445, 11715.531378744925, 11775.587854425139, 11785.587504437304, 11787.233829801122, 11805.232659877167, 4367.781610919441, 4449.28811390176, 4540.977295113504, 4548.6361091113295, 4822.565969062731, 10073.0, 10073.94963025195, 10075.748106297402, 10077.647282345082, 10087.798244035079, 5406.0, 5412.78348866041, 5416.864578385572, 5418.810341638024, 5419.674819510842, 4782.258749893703, 4928.975355123202, 4960.156773348539, 4966.528179822911, 4980.975095124501, 3553.697935675445, 3685.336639404933, 3750.9062273443383, 3866.6713329366758, 3887.1253967857006, 985.8071733202762, 1160.257435241446, 1163.7148898519918, 1164.1501703756305, 1179.215821478707, 754.4605606991028, 805.3355981315439, 887.2369762004671, 887.7381172554112, 920.8121523234952, 4912.484189160158, 5416.404195538487, 5660.688662123541, 5974.074018527135, 6210.72051757672, 909.7680091576606, 943.6414162618161, 944.8441007233729, 953.3517208298424, 973.0658567775015, 3433.896983090511, 3804.372278574004, 3842.8462861485227, 3886.9805650971566, 4004.759714417144, 9182.0, 10561.155107591374, 10914.454277286104, 10925.344479331265, 10931.18016148792, 10738.194635402377, 10839.891601084046, 10847.891521084846, 10870.18473614482, 10875.78248435027, 7111.64441777909, 8176.0, 8193.79515512105, 8274.669013239523, 8275.00699916014, 5938.6436813791315, 5972.313183983897, 5972.402759723993, 6155.815325540241, 6290.937090629035, 9090.863637045317, 9817.165540929102, 9917.206623469961, 10145.18838492904, 10315.0, 8657.350698697497, 9536.713898583053, 9701.757456063655, 10220.795584088426, 10455.634052808115, 7487.925120748941, 7630.0, 7636.656350464354, 7639.618019098912, 7646.502977306457, 7534.208908064575, 7638.770836874903, 7833.92166078332, 8363.874541881754, 8473.49159050459, 7491.288327608869, 7566.24337566239, 7568.091828980558, 7630.2751238632245, 7684.538927664358, 6266.780662676784, 7437.702491900263, 7446.515976461513, 7493.325600695964, 7587.468877178544, 6463.676816159079, 6714.160729908762, 6895.931040689729, 6934.791956241321, 6999.860002799814, 3704.3517384457073, 4017.2367250222364, 4281.507626622978, 4300.182965236595, 4359.673024523237, 4824.059308434962, 4956.727379994135, 4973.950260497493, 5060.1650727631, 5211.687298762086], "value": 4514.681268311252, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.4789379117588, 298.427020121367, 307.5218035954092, 316.30570896881324, 316.9920751981202, 4591.931121033183, 5288.985278391314, 5309.840704778858, 5332.466753324666, 5347.732613369326, 3854.9807250963704, 5256.0, 5261.447548007462, 5307.442718514559, 5312.203169524573, 3834.175652234762, 3853.7687738735626, 3956.7823769692654, 4008.939865902014, 4081.2857749893797, 3310.453775127113, 3437.5187473753695, 4032.5765794591584, 4059.6549293310104, 4089.5501494835544, 3197.473995543518, 3794.4118661607495, 3982.5264652078627, 3996.2007598480277, 4029.9798501007454, 3761.3417651910804, 3820.4842346283176, 3911.6088391160943, 4013.6989725770686, 4022.7787471689044, 3088.5830412894197, 3276.361109583608, 3452.5166476693284, 3478.7043101336417, 3480.8085555294447, 4480.462344518646, 5134.537891589737, 5145.253938178943, 5153.252778347119, 5156.716380599066, 3806.486124373202, 4374.475062992429, 4562.908741825178, 4573.245414506636, 4582.633389328879, 4784.0, 4790.928136077961, 5131.640785145041, 5444.428335024805, 5559.916601250984, 3859.208862183246, 3877.9030524236837, 3885.417187421881, 3909.765414075136, 3987.441758153861, 5431.918521222185, 5758.3090029196765, 5833.358330583633, 5849.61977471463, 5862.618929769551, 3869.245497128033, 3874.4188371744463, 3881.7088718346236, 3886.378179491268, 3896.59085795993, 3349.581302337208, 3908.7459315144424, 3956.940645890314, 3958.88123356294, 3960.108975480494, 3956.723029387915, 4590.655700822452, 4601.815927362935, 4616.3306320584, 4619.69971951822, 5208.739563021833, 5529.069593115655, 5545.528630066489, 5548.861278468071, 5577.498025177672, 1092.7814437112609, 1326.1645163546905, 1334.3661760663608, 1347.5351003903638, 1352.2359866675297, 672.9057931889539, 733.1275781819618, 818.3739439328918, 827.0075908909213, 842.7850898020965, 3951.38753493209, 3999.9400008999305, 5206.0, 5223.9477605223465, 5233.528982391603, 5078.415982162099, 5409.83770486886, 5700.315962084494, 5740.311162660424, 5774.624649397776, 5043.52086551777, 5199.896002080013, 5223.582113430916, 5240.371155461293, 5302.522772950453, 2781.5966684830587, 2789.8326100434037, 2955.438466691322, 2993.6108305920093, 3018.2605261711024, 4930.802767889316, 5139.152039913447, 5331.507177990169, 5664.745086471119, 5964.85087872798, 4659.860204193879, 5126.820561280412, 5405.8107966221, 5586.16207568868, 5889.8233053007625, 5023.824166154167, 6002.358962056768, 6002.399760023963, 6029.547783916136, 6030.798758453133, 4954.9436983651385, 5008.4240312364545, 5099.736168851863, 5605.663660180402, 5794.826939897538, 4404.405405270248, 5232.5552328052545, 5411.07906693134, 5697.4302569742695, 5753.626014309224, 5450.373207081077, 5539.147339120275, 5978.461938425563, 5985.2941911555, 6021.819345419644, 3075.7078077582596, 3719.9442008369356, 3869.729118961646, 3911.7848518331766, 3918.2947069526617, 4738.431388233434, 5277.856882347339, 5506.724663766876, 5809.883802324014, 5854.765809367748, 5250.136201648513, 5653.134465626251, 5665.036943719495, 5666.943330566806, 5669.603127781016, 4618.676692631483, 5404.648697834628, 5620.4098569649595, 5640.520555752807, 5652.315150303573, 5525.696086715108, 5529.557635389078, 5534.501894829485, 5543.223948647112, 5543.944560554342, 4720.008798152427, 4998.52572976379, 5323.547498462674, 5662.716864156693, 5704.1443783432815, 4686.671932964659, 5246.31797866275, 5261.289725887109, 5287.286216360896, 5503.715957877436], "value": 4015.7216551955376, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.7877847256586, 726.5386479585462, 738.1437532462345, 793.9444238903279, 809.5385630190792, 15570.844291557081, 16286.104264265461, 16294.266757995889, 16317.265723042492, 16333.754993675104, 14330.426782928696, 14414.207218602998, 14420.855791442082, 14421.351039203235, 14434.494792682259, 686.9278725733801, 709.9112610923635, 846.1665259719172, 850.6129710981504, 874.7987962768567, 844.5692696724653, 856.3577317012241, 858.274757829633, 859.2653281444369, 861.3711990247124, 925.2413021322518, 925.3476299209075, 925.9444433333988, 926.3746970794687, 929.6839074714595, 719.4568101083701, 888.3648191543014, 913.8949020862619, 921.953902304882, 931.5202670624634, 699.912510936133, 701.113091938698, 701.6456689371835, 702.9789106326767, 703.078966553811, 14967.775483367759, 15009.24953752319, 15017.324220410108, 15027.849721502755, 15051.021683590532, 13749.587512374645, 14066.92966535171, 14115.153090814481, 14135.858641413557, 14386.28068596566, 12880.0, 12943.67640808978, 12999.415026323839, 13189.07676462648, 13621.727565448735, 4027.74603237698, 4545.909081818379, 4602.769861506911, 4704.694194877322, 4725.196716558194, 112993.43503282513, 116467.41766291115, 119180.0, 120489.0, 120718.0, 10991.890081099087, 11140.387278699587, 11166.0, 11400.942995285137, 11685.766284674259, 17833.643327133384, 21994.230201943174, 22096.116155353928, 22133.889330553244, 22148.0, 7576.0, 7903.723369682149, 8050.718224862102, 8052.798680032929, 8207.343412526983, 4400.669949753717, 4423.058665987147, 4478.664100192498, 4489.6408287336935, 4499.977500112544, 3458.4466485362223, 3592.946105808415, 3624.0, 3629.038304849217, 3640.344737947195, 670.4837275297994, 670.5138774388627, 675.1627981303149, 683.54885775388, 684.5926673629282, 602.5601311042964, 662.3707477895922, 675.3617831149614, 681.4922882452605, 683.6581709145428, 9414.29392795543, 9423.293253006052, 9477.66828161025, 9478.90521094794, 9499.620015199314, 559.5383808358121, 633.2780630081711, 662.4733336997099, 671.513182655545, 682.743971010871, 4251.829926802955, 4598.816047358068, 4600.907981840344, 4636.744979026119, 4727.343179481062, 96402.0, 96600.0, 96976.0, 148051.0, 153386.23306883394, 112981.0, 131465.0, 134869.0, 137119.0, 139311.21377572595, 80389.19610803817, 103129.0, 111829.88170118358, 117178.0, 118466.0, 8522.0, 8562.159135785836, 8835.425697329652, 8971.865422018805, 9131.908680913106, 44564.77717611391, 54669.45330546803, 59227.0, 65311.346886530526, 66789.33210667831, 88561.55719221363, 91843.08156918346, 94062.87540063492, 96324.51837740766, 98389.0, 11516.596919107791, 11785.233959792586, 11986.580469683518, 12313.87686123163, 12405.689857753629, 12960.740785184054, 13017.0, 13194.548151688114, 13347.933260333637, 13480.393382297623, 10211.591536338463, 10969.945150274198, 13247.735045299232, 13336.799948000593, 13507.864921350661, 13335.933320333335, 13702.314884255948, 13871.51449699296, 14002.168873677967, 14084.436622534984, 13164.736705266032, 13247.80128298057, 13459.932700336436, 13511.391987360395, 13715.314234288046, 10223.488825558841, 10383.273170877967, 10652.627158049741, 10908.945455272673, 11003.394813285026, 10875.293105948089, 11352.205345625727, 11473.426328683365, 11602.70993225176, 11613.477393517143], "value": 7802.379313037115, "unit": "score"}], "name": "dromaeo_css", "value": 7538.85320302317, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 165.0, 179.0, 112.0, 111.0, 112.0, 112.0, 112.0, 112.0, 112.0], "value": 127.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 114.0, 129.0, 159.0, 104.0, 133.0, 154.0, 136.0, 171.0, 106.0], "value": 135.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 187.0, 184.0, 181.0, 183.0, 184.0, 179.0, 180.0, 196.0], "value": 184.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 152.0, 57.0, 67.0, 58.0, 68.0, 141.0, 57.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 137.0, 71.0, 70.0, 91.0, 138.0, 100.0, 101.0, 140.0], "value": 101.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 83.0, 86.0, 86.0, 126.0, 82.0, 87.0, 83.0], "value": 88.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 92.0, 92.0, 92.0, 97.0, 92.0, 91.0, 92.0], "value": 92.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 84.0, 86.0, 84.0, 102.0, 147.0, 148.0, 85.0], "value": 99.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 55.0, 55.0, 54.0, 55.0, 55.0, 55.0, 54.0, 53.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 70.0, 77.0, 77.0, 71.0, 70.0, 70.0, 71.0, 72.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 84.0, 84.0, 88.0, 88.0, 85.0, 91.0, 90.0, 111.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 153.0, 126.0, 138.0, 160.0, 126.0, 148.0, 149.0, 162.0, 144.0], "value": 145.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 234.0, 218.0, 170.0, 173.0, 167.0, 168.0, 173.0, 230.0, 201.0], "value": 190.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 68.0, 67.0, 65.0, 69.0, 67.0, 68.0, 66.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1530.1999999999998, "alertThreshold": 2.0}]} 09:09:52 INFO - Return code: 0 09:09:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:09:52 INFO - # TBPL SUCCESS # 09:09:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:09:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:09:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:09:52 INFO - 2016-08-30 09:09:52.467 screenresolution[1661:9722] starting screenresolution argv=screenresolution get 09:09:52 INFO - 2016-08-30 09:09:52.469 screenresolution[1661:9722] Display 0: 1600x1200x32@60 09:09:52 INFO - 2016-08-30 09:09:52.475 screenresolution[1662:9725] starting screenresolution argv=screenresolution list 09:09:52 INFO - Available Modes on Display 0 09:09:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:09:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:09:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:09:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:09:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:09:52 INFO - Intel Iris: 09:09:52 INFO - Chipset Model: Intel Iris 09:09:52 INFO - Type: GPU 09:09:52 INFO - Bus: Built-In 09:09:52 INFO - VRAM (Dynamic, Max): 1536 MB 09:09:52 INFO - Vendor: Intel (0x8086) 09:09:52 INFO - Device ID: 0x0a2e 09:09:52 INFO - Revision ID: 0x0009 09:09:52 INFO - Displays: 09:09:52 INFO - Display: 09:09:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:09:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:09:52 INFO - Display Serial Number: 1600x1200 60 09:09:52 INFO - Main Display: Yes 09:09:52 INFO - Mirror: Off 09:09:52 INFO - Online: Yes 09:09:52 INFO - Rotation: Supported 09:09:52 INFO - Return code: 0 09:09:52 INFO - Running post-action listener: _resource_record_post_action 09:09:52 INFO - [mozharness: 2016-08-30 16:09:52.712513Z] Finished run-tests step (success) 09:09:52 INFO - Running post-run listener: _resource_record_post_run 09:09:52 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37299200; Write bytes: 705724416; Read time: 240; Write time: 1983 09:09:52 INFO - TinderboxPrint: CPU usage
53.5% 09:09:52 INFO - TinderboxPrint: I/O read bytes / time
37,299,200 / 240 09:09:52 INFO - TinderboxPrint: I/O write bytes / time
705,724,416 / 1,983 09:09:52 INFO - TinderboxPrint: CPU idle
2,259.4 (46.5%) 09:09:52 INFO - TinderboxPrint: CPU system
974.0 (20.1%) 09:09:52 INFO - TinderboxPrint: CPU user
1,620.3 (33.4%) 09:09:52 INFO - TinderboxPrint: Swap in / out
443,129,856 / 0 09:09:52 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402952704; Write bytes: 397678592; Read time: 29973; Write time: 801 09:09:52 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 35173376; Write bytes: 295319552; Read time: 218; Write time: 1146 09:09:53 INFO - Running post-run listener: _upload_blobber_files 09:09:53 INFO - Blob upload gear active. 09:09:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:09:53 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 09:09:53 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'] 09:09:53 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 09:09:53 INFO - (blobuploader) - INFO - Open directory for files ... 09:09:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:09:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:09:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:54 INFO - (blobuploader) - INFO - Done attempting. 09:09:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:09:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:09:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:55 INFO - (blobuploader) - INFO - Done attempting. 09:09:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:09:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:09:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:58 INFO - (blobuploader) - INFO - Done attempting. 09:09:58 INFO - (blobuploader) - INFO - Iteration through files over. 09:09:58 INFO - Return code: 0 09:09:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:09:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:09:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/491fa70b489160e8fccb661535cfdfe48d4330cf5db0c6c5c4ffccc5fa7e9ae1f1cfb5a7df2cf7a2a062b5afba6d123f7568045f289906dfdb3ed28a88c69ac1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/39725d90d4faf07adf94eae0268e522a7217e2ff83f9f0806e25ca2c302a79b290e6e230362e652ef0c43ea5d18330fe5fa7aaace125bc15b70e18127ae96a42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1bb1afddc40aa3b93736b4d2cecfb6c947da3317615aad51c10a13f20902800caecf78b10a8c2ceb5e831464ee77f08372d5fc3813be86d63507f4a096c1679d"} 09:09:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:09:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:09:58 INFO - Contents: 09:09:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/491fa70b489160e8fccb661535cfdfe48d4330cf5db0c6c5c4ffccc5fa7e9ae1f1cfb5a7df2cf7a2a062b5afba6d123f7568045f289906dfdb3ed28a88c69ac1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/39725d90d4faf07adf94eae0268e522a7217e2ff83f9f0806e25ca2c302a79b290e6e230362e652ef0c43ea5d18330fe5fa7aaace125bc15b70e18127ae96a42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1bb1afddc40aa3b93736b4d2cecfb6c947da3317615aad51c10a13f20902800caecf78b10a8c2ceb5e831464ee77f08372d5fc3813be86d63507f4a096c1679d"} 09:09:58 INFO - Running post-run listener: copy_logs_to_upload_dir 09:09:58 INFO - Copying logs to upload dir... 09:09:58 INFO - mkdir: /builds/slave/test/build/upload/logs 09:09:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.089792 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-08-30 09:09:58.220548) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 09:09:58.228419) ========= 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.MLahkXVfg3/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.CqTexHHOYh/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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/491fa70b489160e8fccb661535cfdfe48d4330cf5db0c6c5c4ffccc5fa7e9ae1f1cfb5a7df2cf7a2a062b5afba6d123f7568045f289906dfdb3ed28a88c69ac1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/39725d90d4faf07adf94eae0268e522a7217e2ff83f9f0806e25ca2c302a79b290e6e230362e652ef0c43ea5d18330fe5fa7aaace125bc15b70e18127ae96a42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1bb1afddc40aa3b93736b4d2cecfb6c947da3317615aad51c10a13f20902800caecf78b10a8c2ceb5e831464ee77f08372d5fc3813be86d63507f4a096c1679d"} build_url:https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011108 build_url: 'https://queue.taskcluster.net/v1/task/VW9_7DtoTF6Aum0Q5bGw_Q/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/491fa70b489160e8fccb661535cfdfe48d4330cf5db0c6c5c4ffccc5fa7e9ae1f1cfb5a7df2cf7a2a062b5afba6d123f7568045f289906dfdb3ed28a88c69ac1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/39725d90d4faf07adf94eae0268e522a7217e2ff83f9f0806e25ca2c302a79b290e6e230362e652ef0c43ea5d18330fe5fa7aaace125bc15b70e18127ae96a42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1bb1afddc40aa3b93736b4d2cecfb6c947da3317615aad51c10a13f20902800caecf78b10a8c2ceb5e831464ee77f08372d5fc3813be86d63507f4a096c1679d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 09:09:58.263998) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 09:09:58.264342) ========= 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.MLahkXVfg3/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.CqTexHHOYh/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.005190 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 09:09:58.312780) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 09:09:58.316161) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 09:09:58.316533) ========= ========= Total master_lag: 0.19 =========