builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0138 starttime: 1467254041.03 results: success (0) buildid: 20160629172328 builduid: 9b81e4cd3e5749d8b0543a6f8f348c3f revision: 0edb9df3c97ff7d205a99f8af607f99a858f140b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 19:34:01.031873) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 19:34:01.032428) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 19:34:01.032780) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 19:34:01.058449) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 19:34:01.059015) ========= 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.AWnZKQNJxh/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.1ShII1b3TD/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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-06-29 19:34:01-- 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% 163M=0s 2016-06-29 19:34:01 (163 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.458990 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 19:34:01.541871) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 19:34:01.542217) ========= 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.AWnZKQNJxh/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.1ShII1b3TD/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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.028701 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 19:34:01.593893) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 19:34:01.594345) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 0edb9df3c97ff7d205a99f8af607f99a858f140b --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 0edb9df3c97ff7d205a99f8af607f99a858f140b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AWnZKQNJxh/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.1ShII1b3TD/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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-06-29 19:34:01,668 truncating revision to first 12 chars 2016-06-29 19:34:01,668 Setting DEBUG logging. 2016-06-29 19:34:01,668 attempt 1/10 2016-06-29 19:34:01,668 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/0edb9df3c97f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 19:34:02,908 unpacking tar archive at: autoland-0edb9df3c97f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.509074 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 19:34:03.125357) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 19:34:03.125681) ========= script_repo_revision: 0edb9df3c97ff7d205a99f8af607f99a858f140b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 19:34:03.126134) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 19:34:03.126460) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 19:34:03.147094) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-06-29 19:34:03.147512) ========= /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.AWnZKQNJxh/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.1ShII1b3TD/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 19:34:03 INFO - MultiFileLogger online at 20160629 19:34:03 in /builds/slave/test 19:34:03 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 19:34:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:34:03 INFO - {'append_to_log': False, 19:34:03 INFO - 'base_work_dir': '/builds/slave/test', 19:34:03 INFO - 'blob_upload_branch': 'Autoland', 19:34:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:34:03 INFO - 'branch': 'Autoland', 19:34:03 INFO - 'buildbot_json_path': 'buildprops.json', 19:34:03 INFO - 'config_files': ('talos/mac_config.py',), 19:34:03 INFO - 'default_actions': ('clobber', 19:34:03 INFO - 'read-buildbot-config', 19:34:03 INFO - 'download-and-extract', 19:34:03 INFO - 'populate-webroot', 19:34:03 INFO - 'create-virtualenv', 19:34:03 INFO - 'install', 19:34:03 INFO - 'run-tests'), 19:34:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:34:03 INFO - 'download_minidump_stackwalk': True, 19:34:03 INFO - 'download_symbols': 'ondemand', 19:34:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:34:03 INFO - 'tooltool.py': '/tools/tooltool.py', 19:34:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:34:03 INFO - '/tools/misc-python/virtualenv.py')}, 19:34:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:34:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:34:03 INFO - 'installer_path': 'installer.exe', 19:34:03 INFO - 'log_level': 'info', 19:34:03 INFO - 'log_name': 'talos', 19:34:03 INFO - 'log_to_console': True, 19:34:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:34:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:34:03 INFO - 'opt_config_files': (), 19:34:03 INFO - 'pip_index': False, 19:34:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:34:03 INFO - 'cmd': ('bash', 19:34:03 INFO - '-c', 19:34:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:34:03 INFO - 'enabled': True, 19:34:03 INFO - 'halt_on_failure': False, 19:34:03 INFO - 'name': 'check_screen_resolution'},), 19:34:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:34:03 INFO - 'cmd': ('bash', 19:34:03 INFO - '-c', 19:34:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:34:03 INFO - 'enabled': True, 19:34:03 INFO - 'halt_on_failure': False, 19:34:03 INFO - 'name': 'check_screen_resolution'},), 19:34:03 INFO - 'run_cmd_checks_enabled': True, 19:34:03 INFO - 'sps_profile': False, 19:34:03 INFO - 'sps_profile_interval': 0, 19:34:03 INFO - 'suite': 'dromaeojs', 19:34:03 INFO - 'system_bits': '32', 19:34:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:34:03 INFO - 'title': 't-yosemite-r7-0138', 19:34:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:34:03 INFO - 'use_talos_json': True, 19:34:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:34:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:34:03 INFO - 'work_dir': 'build'} 19:34:03 INFO - ##### 19:34:03 INFO - ##### Running clobber step. 19:34:03 INFO - ##### 19:34:03 INFO - Running pre-action listener: _resource_record_pre_action 19:34:03 INFO - Running main action method: clobber 19:34:03 INFO - rmtree: /builds/slave/test/build 19:34:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:34:05 INFO - Running post-action listener: _resource_record_post_action 19:34:05 INFO - ##### 19:34:05 INFO - ##### Finished clobber step (success) 19:34:05 INFO - ##### 19:34:05 INFO - ##### 19:34:05 INFO - ##### Running read-buildbot-config step. 19:34:05 INFO - ##### 19:34:05 INFO - Running pre-action listener: _resource_record_pre_action 19:34:05 INFO - Running main action method: read_buildbot_config 19:34:05 INFO - Using buildbot properties: 19:34:05 INFO - { 19:34:05 INFO - "project": "", 19:34:05 INFO - "product": "firefox", 19:34:05 INFO - "script_repo_revision": "production", 19:34:05 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 19:34:05 INFO - "repository": "", 19:34:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 19:34:05 INFO - "buildid": "20160629172328", 19:34:05 INFO - "pgo_build": "False", 19:34:05 INFO - "basedir": "/builds/slave/test", 19:34:05 INFO - "buildnumber": 2, 19:34:05 INFO - "slavename": "t-yosemite-r7-0138", 19:34:05 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 19:34:05 INFO - "platform": "yosemite_r7", 19:34:05 INFO - "scheduler": "tests-autoland-macosx64-talos", 19:34:05 INFO - "branch": "autoland", 19:34:05 INFO - "repo_path": "integration/autoland", 19:34:05 INFO - "revision": "0edb9df3c97ff7d205a99f8af607f99a858f140b", 19:34:05 INFO - "stage_platform": "macosx64", 19:34:05 INFO - "builduid": "9b81e4cd3e5749d8b0543a6f8f348c3f", 19:34:05 INFO - "slavebuilddir": "test" 19:34:05 INFO - } 19:34:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 19:34:05 INFO - Running post-action listener: _resource_record_post_action 19:34:05 INFO - ##### 19:34:05 INFO - ##### Finished read-buildbot-config step (success) 19:34:05 INFO - ##### 19:34:05 INFO - ##### 19:34:05 INFO - ##### Running download-and-extract step. 19:34:05 INFO - ##### 19:34:05 INFO - Running pre-action listener: _resource_record_pre_action 19:34:05 INFO - Running main action method: download_and_extract 19:34:05 INFO - mkdir: /builds/slave/test/build/tests 19:34:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:34:05 INFO - https://queue.taskcluster.net/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:34:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 19:34:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 19:34:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 19:34:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 19:34:17 INFO - Downloaded 1199 bytes. 19:34:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 19:34:17 INFO - Using the following test package requirements: 19:34:17 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 19:34:17 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:34:17 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 19:34:17 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:34:17 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 19:34:17 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:34:17 INFO - u'jsshell-mac.zip'], 19:34:17 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:34:17 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 19:34:17 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 19:34:17 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:34:17 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 19:34:17 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:34:17 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 19:34:17 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:34:17 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 19:34:17 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:34:17 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 19:34:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:34:17 INFO - https://queue.taskcluster.net/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:34:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:34:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:34:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:34:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:34:21 INFO - Downloaded 18788551 bytes. 19:34:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:34:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:34:24 INFO - Return code: 0 19:34:24 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:34:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:34:24 INFO - https://queue.taskcluster.net/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:34:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:34:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:34:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:34:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:34:37 INFO - Downloaded 18788551 bytes. 19:34:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:34:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:34:41 INFO - Return code: 0 19:34:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:34:41 INFO - https://queue.taskcluster.net/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:34:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 19:34:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 19:34:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 19:34:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:34:42 INFO - Downloaded 14405245 bytes. 19:34:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:34:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:34:42 INFO - Return code: 0 19:34: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:34:42 INFO - https://queue.taskcluster.net/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:34:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:34:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:34:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 19:34:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 19:34:45 INFO - Downloaded 112462599 bytes. 19:34:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:34:45 INFO - mkdir: /builds/slave/test/properties 19:34:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:34:45 INFO - Writing to file /builds/slave/test/properties/build_url 19:34:45 INFO - Contents: 19:34:45 INFO - build_url:https://queue.taskcluster.net/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:34:46 INFO - Running post-action listener: _resource_record_post_action 19:34:46 INFO - Running post-action listener: set_extra_try_arguments 19:34:46 INFO - ##### 19:34:46 INFO - ##### Finished download-and-extract step (success) 19:34:46 INFO - ##### 19:34:46 INFO - ##### 19:34:46 INFO - ##### Running populate-webroot step. 19:34:46 INFO - ##### 19:34:46 INFO - Running pre-action listener: _resource_record_pre_action 19:34:46 INFO - Running main action method: populate_webroot 19:34:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:34:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:34:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:34:46 INFO - '--tppagecycles', 19:34:46 INFO - '7'], 19:34:46 INFO - 'tests': ['tsvgm']}}, 19:34:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:34:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:34:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:34:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:34:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:34:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:34:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:34:46 INFO - 'talos_options': ['--disable-e10s'], 19:34:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:34:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:34:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:34:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:34:46 INFO - 'talos_options': ['--disable-e10s'], 19:34:46 INFO - 'tests': ['damp', 'tps']}, 19:34:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:34:46 INFO - 'tests': ['damp', 'tps']}, 19:34:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:34:46 INFO - 'tests': ['dromaeo_dom']}, 19:34:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:34:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:34:46 INFO - 'tests': ['basic_compositor_video']}, 19:34:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:34:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:34:46 INFO - 'tests': ['a11yr', 19:34:46 INFO - 'ts_paint', 19:34:46 INFO - 'tpaint', 19:34:46 INFO - 'sessionrestore', 19:34:46 INFO - 'sessionrestore_no_auto_restore', 19:34:46 INFO - 'tabpaint']}, 19:34:46 INFO - 'other-e10s': {'tests': ['a11yr', 19:34:46 INFO - 'ts_paint', 19:34:46 INFO - 'tpaint', 19:34:46 INFO - 'sessionrestore', 19:34:46 INFO - 'sessionrestore_no_auto_restore', 19:34:46 INFO - 'tabpaint']}, 19:34:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:34:46 INFO - 'ts_paint', 19:34:46 INFO - 'tpaint', 19:34:46 INFO - 'sessionrestore', 19:34:46 INFO - 'sessionrestore_no_auto_restore', 19:34:46 INFO - 'tabpaint']}, 19:34:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:34:46 INFO - 'ts_paint', 19:34:46 INFO - 'tpaint', 19:34:46 INFO - 'sessionrestore', 19:34:46 INFO - 'sessionrestore_no_auto_restore', 19:34:46 INFO - 'tabpaint']}, 19:34:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:34:46 INFO - 'tests': ['a11yr', 19:34:46 INFO - 'ts_paint', 19:34:46 INFO - 'tpaint', 19:34:46 INFO - 'sessionrestore', 19:34:46 INFO - 'sessionrestore_no_auto_restore', 19:34:46 INFO - 'tabpaint']}, 19:34:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:34:46 INFO - 'tests': ['a11yr', 19:34:46 INFO - 'ts_paint', 19:34:46 INFO - 'tpaint', 19:34:46 INFO - 'sessionrestore', 19:34:46 INFO - 'sessionrestore_no_auto_restore', 19:34:46 INFO - 'tabpaint']}, 19:34:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:34:46 INFO - 'tests': ['tsvgx', 19:34:46 INFO - 'tsvgr_opacity', 19:34:46 INFO - 'tart', 19:34:46 INFO - 'tscrollx', 19:34:46 INFO - 'cart']}, 19:34:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:34:46 INFO - 'tsvgr_opacity', 19:34:46 INFO - 'tart', 19:34:46 INFO - 'tscrollx', 19:34:46 INFO - 'cart']}, 19:34:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:34:46 INFO - 'talos_options': ['--disable-e10s'], 19:34:46 INFO - 'tests': ['tp5o']}, 19:34:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:34:46 INFO - 'tests': ['tp5o']}, 19:34:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:34:46 INFO - 'talos_options': ['--disable-e10s', 19:34:46 INFO - '--xperf_path', 19:34:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:34:46 INFO - 'tests': ['tp5n']}, 19:34:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:34:46 INFO - 'talos_options': ['--xperf_path', 19:34:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:34:46 INFO - 'tests': ['tp5n']}}, 19:34:46 INFO - 'talos.zip': {'path': '', 19:34:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:34:46 INFO - Running post-action listener: _resource_record_post_action 19:34:46 INFO - ##### 19:34:46 INFO - ##### Finished populate-webroot step (success) 19:34:46 INFO - ##### 19:34:46 INFO - ##### 19:34:46 INFO - ##### Running create-virtualenv step. 19:34:46 INFO - ##### 19:34:46 INFO - Running pre-action listener: _resource_record_pre_action 19:34:46 INFO - Running main action method: create_virtualenv 19:34:46 INFO - Creating virtualenv /builds/slave/test/build/venv 19:34:46 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:34:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:34:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:34:46 INFO - Using real prefix '/tools/python27' 19:34:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:34:47 INFO - Installing distribute.............................................................................................................................................................................................done. 19:34:50 INFO - Installing pip.................done. 19:34:50 INFO - Return code: 0 19:34:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:34:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:34:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1089f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a87618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb9a49b080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108956d30>, '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 0x1086bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10894eb20>, '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.AWnZKQNJxh/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.1ShII1b3TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:34:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:34:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:34:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWnZKQNJxh/Render', 19:34:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:34:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:34:50 INFO - 'HOME': '/Users/cltbld', 19:34:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:34:50 INFO - 'LOGNAME': 'cltbld', 19:34:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:50 INFO - 'MOZ_NO_REMOTE': '1', 19:34:50 INFO - 'NO_EM_RESTART': '1', 19:34:50 INFO - 'PAGER': '/bin/cat', 19:34:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:34:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:50 INFO - 'PWD': '/builds/slave/test', 19:34:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:34:50 INFO - 'SHELL': '/bin/bash', 19:34:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ShII1b3TD/Listeners', 19:34:50 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 19:34:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:34:50 INFO - 'USER': 'cltbld', 19:34:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:34:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:34:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:50 INFO - 'XPC_FLAGS': '0x0', 19:34:50 INFO - 'XPC_SERVICE_NAME': '0', 19:34:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:34:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:34:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:34:50 INFO - Cleaning up... 19:34:50 INFO - Return code: 0 19:34:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:34:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:34:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1089f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a87618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb9a49b080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108956d30>, '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 0x1086bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10894eb20>, '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.AWnZKQNJxh/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.1ShII1b3TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:34:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:34:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:34:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWnZKQNJxh/Render', 19:34:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:34:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:34:50 INFO - 'HOME': '/Users/cltbld', 19:34:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:34:50 INFO - 'LOGNAME': 'cltbld', 19:34:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:50 INFO - 'MOZ_NO_REMOTE': '1', 19:34:50 INFO - 'NO_EM_RESTART': '1', 19:34:50 INFO - 'PAGER': '/bin/cat', 19:34:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:34:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:50 INFO - 'PWD': '/builds/slave/test', 19:34:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:34:50 INFO - 'SHELL': '/bin/bash', 19:34:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ShII1b3TD/Listeners', 19:34:50 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 19:34:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:34:50 INFO - 'USER': 'cltbld', 19:34:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:34:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:34:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:50 INFO - 'XPC_FLAGS': '0x0', 19:34:50 INFO - 'XPC_SERVICE_NAME': '0', 19:34:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:34:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:34:50 INFO - Downloading/unpacking psutil>=3.1.1 19:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:34:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:34:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:34:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:34:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:34:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:34:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:34:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:34:54 INFO - Installing collected packages: psutil 19:34:54 INFO - Running setup.py install for psutil 19:34:54 INFO - building 'psutil._psutil_osx' extension 19:34:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:34:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:34:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:34:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:34:55 INFO - building 'psutil._psutil_posix' extension 19:34:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 19:34:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:34:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:34:55 INFO - ^ 19:34:55 INFO - 1 warning generated. 19:34:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 19:34:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:34:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:34:55 INFO - Successfully installed psutil 19:34:55 INFO - Cleaning up... 19:34:55 INFO - Return code: 0 19:34:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:34:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:34:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:34:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:34:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1089f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a87618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb9a49b080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108956d30>, '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 0x1086bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10894eb20>, '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.AWnZKQNJxh/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.1ShII1b3TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:34:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:34:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:34:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWnZKQNJxh/Render', 19:34:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:34:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:34:55 INFO - 'HOME': '/Users/cltbld', 19:34:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:34:55 INFO - 'LOGNAME': 'cltbld', 19:34:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:55 INFO - 'MOZ_NO_REMOTE': '1', 19:34:55 INFO - 'NO_EM_RESTART': '1', 19:34:55 INFO - 'PAGER': '/bin/cat', 19:34:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:34:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:55 INFO - 'PWD': '/builds/slave/test', 19:34:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:34:55 INFO - 'SHELL': '/bin/bash', 19:34:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ShII1b3TD/Listeners', 19:34:55 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 19:34:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:34:55 INFO - 'USER': 'cltbld', 19:34:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:34:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:34:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:55 INFO - 'XPC_FLAGS': '0x0', 19:34:55 INFO - 'XPC_SERVICE_NAME': '0', 19:34:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:34:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:34:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:34:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:34:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:34:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:34:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:34:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:34:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:34:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:34:58 INFO - Installing collected packages: mozsystemmonitor 19:34:58 INFO - Running setup.py install for mozsystemmonitor 19:34:58 INFO - Successfully installed mozsystemmonitor 19:34:58 INFO - Cleaning up... 19:34:58 INFO - Return code: 0 19:34:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:34:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:34:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:34:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:34:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:58 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 0x1089f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a87618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb9a49b080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108956d30>, '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 0x1086bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10894eb20>, '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.AWnZKQNJxh/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.1ShII1b3TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:34:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:34:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:34:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWnZKQNJxh/Render', 19:34:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:34:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:34:58 INFO - 'HOME': '/Users/cltbld', 19:34:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:34:58 INFO - 'LOGNAME': 'cltbld', 19:34:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:58 INFO - 'MOZ_NO_REMOTE': '1', 19:34:58 INFO - 'NO_EM_RESTART': '1', 19:34:58 INFO - 'PAGER': '/bin/cat', 19:34:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:34:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:58 INFO - 'PWD': '/builds/slave/test', 19:34:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:34:58 INFO - 'SHELL': '/bin/bash', 19:34:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ShII1b3TD/Listeners', 19:34:58 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 19:34:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:34:58 INFO - 'USER': 'cltbld', 19:34:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:34:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:34:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:58 INFO - 'XPC_FLAGS': '0x0', 19:34:58 INFO - 'XPC_SERVICE_NAME': '0', 19:34:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:34:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:34:58 INFO - Downloading/unpacking jsonschema==2.5.1 19:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:34:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:34:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:35:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:01 INFO - Downloading functools32-3.2.3-2.tar.gz 19:35:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:35:01 INFO - warning: no files found matching '*.txt' 19:35:01 INFO - no previously-included directories found matching 'build' 19:35:01 INFO - no previously-included directories found matching 'dist' 19:35:01 INFO - no previously-included directories found matching '.git*' 19:35:01 INFO - Installing collected packages: jsonschema, functools32 19:35:01 INFO - Running setup.py install for functools32 19:35:02 INFO - warning: no files found matching '*.txt' 19:35:02 INFO - no previously-included directories found matching 'build' 19:35:02 INFO - no previously-included directories found matching 'dist' 19:35:02 INFO - no previously-included directories found matching '.git*' 19:35:02 INFO - Successfully installed jsonschema functools32 19:35:02 INFO - Cleaning up... 19:35:02 INFO - Return code: 0 19:35:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1089f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a87618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb9a49b080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108956d30>, '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 0x1086bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10894eb20>, '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.AWnZKQNJxh/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.1ShII1b3TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:35:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:35:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:35:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWnZKQNJxh/Render', 19:35:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:02 INFO - 'HOME': '/Users/cltbld', 19:35:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:02 INFO - 'LOGNAME': 'cltbld', 19:35:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:02 INFO - 'MOZ_NO_REMOTE': '1', 19:35:02 INFO - 'NO_EM_RESTART': '1', 19:35:02 INFO - 'PAGER': '/bin/cat', 19:35:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:02 INFO - 'PWD': '/builds/slave/test', 19:35:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:02 INFO - 'SHELL': '/bin/bash', 19:35:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ShII1b3TD/Listeners', 19:35:02 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 19:35:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:02 INFO - 'USER': 'cltbld', 19:35:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:02 INFO - 'XPC_FLAGS': '0x0', 19:35:02 INFO - 'XPC_SERVICE_NAME': '0', 19:35:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:02 INFO - Downloading/unpacking blobuploader==1.2.4 19:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:04 INFO - Downloading blobuploader-1.2.4.tar.gz 19:35:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:35:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:35:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:05 INFO - Downloading docopt-0.6.1.tar.gz 19:35:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:35:06 INFO - Installing collected packages: blobuploader, requests, docopt 19:35:06 INFO - Running setup.py install for blobuploader 19:35:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:35:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:35:06 INFO - Running setup.py install for requests 19:35:06 INFO - Running setup.py install for docopt 19:35:06 INFO - Successfully installed blobuploader requests docopt 19:35:06 INFO - Cleaning up... 19:35:06 INFO - Return code: 0 19:35:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:35:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:07 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 0x1089f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a87618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb9a49b080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108956d30>, '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 0x1086bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10894eb20>, '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.AWnZKQNJxh/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.1ShII1b3TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:35:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:35:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:35:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWnZKQNJxh/Render', 19:35:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:07 INFO - 'HOME': '/Users/cltbld', 19:35:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:07 INFO - 'LOGNAME': 'cltbld', 19:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:07 INFO - 'MOZ_NO_REMOTE': '1', 19:35:07 INFO - 'NO_EM_RESTART': '1', 19:35:07 INFO - 'PAGER': '/bin/cat', 19:35:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:07 INFO - 'PWD': '/builds/slave/test', 19:35:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:07 INFO - 'SHELL': '/bin/bash', 19:35:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ShII1b3TD/Listeners', 19:35:07 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 19:35:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:07 INFO - 'USER': 'cltbld', 19:35:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:07 INFO - 'XPC_FLAGS': '0x0', 19:35:07 INFO - 'XPC_SERVICE_NAME': '0', 19:35:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:35:07 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-b1h0Ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:35:07 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-RZKHtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:35:07 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-IAFT63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:35:07 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-F0kM7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:35:07 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-8KiSUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:35:08 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-KLVHfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:35:08 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-a1RWh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:35:08 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-7HvosN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:35:08 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-PiZYXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:35:08 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-54N_VZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:35:08 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-43Bmi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:35:08 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-HdB4pt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:35:08 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-5e2jTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:35:08 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-LMAhU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:35:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:08 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-_EPRFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:35:09 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-3RFqKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:35:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:35:09 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-K9IfC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:35:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:35:09 INFO - Running setup.py install for manifestparser 19:35:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:35:09 INFO - Running setup.py install for mozcrash 19:35:09 INFO - Running setup.py install for mozdebug 19:35:09 INFO - Running setup.py install for mozdevice 19:35:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:35:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:35:09 INFO - Running setup.py install for mozfile 19:35:10 INFO - Running setup.py install for mozhttpd 19:35:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:35:10 INFO - Running setup.py install for mozinfo 19:35:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:35:10 INFO - Running setup.py install for mozInstall 19:35:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:35:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:35:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:35:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:35:10 INFO - Running setup.py install for mozleak 19:35:10 INFO - Running setup.py install for mozlog 19:35:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:35:10 INFO - Running setup.py install for moznetwork 19:35:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:35:10 INFO - Running setup.py install for mozprocess 19:35:11 INFO - Running setup.py install for mozprofile 19:35:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:35:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:35:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:35:11 INFO - Running setup.py install for mozrunner 19:35:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:35:11 INFO - Running setup.py install for mozscreenshot 19:35:11 INFO - Running setup.py install for moztest 19:35:11 INFO - Running setup.py install for mozversion 19:35:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:35:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:35:11 INFO - Cleaning up... 19:35:11 INFO - Return code: 0 19:35:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:35:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:11 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 0x1089f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a87618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb9a49b080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108956d30>, '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 0x1086bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10894eb20>, '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.AWnZKQNJxh/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.1ShII1b3TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:35:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:35:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:35:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWnZKQNJxh/Render', 19:35:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:11 INFO - 'HOME': '/Users/cltbld', 19:35:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:11 INFO - 'LOGNAME': 'cltbld', 19:35:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:11 INFO - 'MOZ_NO_REMOTE': '1', 19:35:11 INFO - 'NO_EM_RESTART': '1', 19:35:11 INFO - 'PAGER': '/bin/cat', 19:35:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:11 INFO - 'PWD': '/builds/slave/test', 19:35:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:11 INFO - 'SHELL': '/bin/bash', 19:35:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ShII1b3TD/Listeners', 19:35:11 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 19:35:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:11 INFO - 'USER': 'cltbld', 19:35:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:11 INFO - 'XPC_FLAGS': '0x0', 19:35:11 INFO - 'XPC_SERVICE_NAME': '0', 19:35:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:35:12 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-3ImsRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:35:12 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:35:12 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-lbnXrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:35:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:35:12 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-teZOsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:35:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:35:12 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-zQBoof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:35:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:35:12 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-dyKJAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:35:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:35:12 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-3eD38q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:35:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:35:12 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-WiKjNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:35:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:35:12 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-rOacwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:35:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:35:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:35:12 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-d6jB2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:35:13 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-5w_bVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:35:13 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-AjOtN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:35:13 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-sXy3le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:35:13 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-4SRJcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:35:13 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-WCgyMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:13 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-2em8NC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:35:13 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-qRgInr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:35:13 INFO - Running setup.py (path:/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/pip-ltyxGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:35:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:16 INFO - Downloading blessings-1.6.tar.gz 19:35:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:35:16 INFO - Installing collected packages: blessings 19:35:16 INFO - Running setup.py install for blessings 19:35:16 INFO - Successfully installed blessings 19:35:16 INFO - Cleaning up... 19:35:16 INFO - Return code: 0 19:35:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:35:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:35:16 INFO - Reading from file tmpfile_stdout 19:35:16 INFO - Current package versions: 19:35:16 INFO - blessings == 1.6 19:35:16 INFO - blobuploader == 1.2.4 19:35:16 INFO - docopt == 0.6.1 19:35:16 INFO - functools32 == 3.2.3-2 19:35:16 INFO - jsonschema == 2.5.1 19:35:16 INFO - manifestparser == 1.1 19:35:16 INFO - mozInstall == 1.12 19:35:16 INFO - mozcrash == 0.17 19:35:16 INFO - mozdebug == 0.1 19:35:16 INFO - mozdevice == 0.48 19:35:16 INFO - mozfile == 1.2 19:35:16 INFO - mozhttpd == 0.7 19:35:16 INFO - mozinfo == 0.9 19:35:16 INFO - mozleak == 0.1 19:35:16 INFO - mozlog == 3.2 19:35:16 INFO - moznetwork == 0.27 19:35:16 INFO - mozprocess == 0.22 19:35:16 INFO - mozprofile == 0.28 19:35:16 INFO - mozrunner == 6.11 19:35:16 INFO - mozscreenshot == 0.1 19:35:16 INFO - mozsystemmonitor == 0.3 19:35:16 INFO - moztest == 0.7 19:35:16 INFO - mozversion == 1.4 19:35:16 INFO - psutil == 3.1.1 19:35:16 INFO - requests == 1.2.3 19:35:16 INFO - wsgiref == 0.1.2 19:35:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:17 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 0x1089f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a87618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb9a49b080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108956d30>, '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 0x1086bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10894eb20>, '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.AWnZKQNJxh/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.1ShII1b3TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:35:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:35:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:35:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWnZKQNJxh/Render', 19:35:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:17 INFO - 'HOME': '/Users/cltbld', 19:35:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:17 INFO - 'LOGNAME': 'cltbld', 19:35:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:17 INFO - 'MOZ_NO_REMOTE': '1', 19:35:17 INFO - 'NO_EM_RESTART': '1', 19:35:17 INFO - 'PAGER': '/bin/cat', 19:35:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:17 INFO - 'PWD': '/builds/slave/test', 19:35:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:17 INFO - 'SHELL': '/bin/bash', 19:35:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ShII1b3TD/Listeners', 19:35:17 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 19:35:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:17 INFO - 'USER': 'cltbld', 19:35:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:17 INFO - 'XPC_FLAGS': '0x0', 19:35:17 INFO - 'XPC_SERVICE_NAME': '0', 19:35:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:35:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:35:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:35:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:35:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:35:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:35:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:35:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:35:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:35:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:35:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:35:17 INFO - Cleaning up... 19:35:17 INFO - Return code: 0 19:35:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:35:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:17 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 0x1089f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a87618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb9a49b080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108956d30>, '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 0x1086bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10894eb20>, '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.AWnZKQNJxh/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.1ShII1b3TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:35:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:35:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:35:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWnZKQNJxh/Render', 19:35:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:17 INFO - 'HOME': '/Users/cltbld', 19:35:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:17 INFO - 'LOGNAME': 'cltbld', 19:35:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:17 INFO - 'MOZ_NO_REMOTE': '1', 19:35:17 INFO - 'NO_EM_RESTART': '1', 19:35:17 INFO - 'PAGER': '/bin/cat', 19:35:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:17 INFO - 'PWD': '/builds/slave/test', 19:35:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:17 INFO - 'SHELL': '/bin/bash', 19:35:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ShII1b3TD/Listeners', 19:35:17 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 19:35:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:17 INFO - 'USER': 'cltbld', 19:35:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:17 INFO - 'XPC_FLAGS': '0x0', 19:35:17 INFO - 'XPC_SERVICE_NAME': '0', 19:35:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:35:17 INFO - Cleaning up... 19:35:17 INFO - Return code: 0 19:35:17 INFO - Running post-action listener: _resource_record_post_action 19:35:17 INFO - Running post-action listener: _start_resource_monitoring 19:35:17 INFO - Starting resource monitoring. 19:35:17 INFO - ##### 19:35:17 INFO - ##### Finished create-virtualenv step (success) 19:35:17 INFO - ##### 19:35:17 INFO - ##### 19:35:17 INFO - ##### Running install step. 19:35:17 INFO - ##### 19:35:17 INFO - Running pre-action listener: _resource_record_pre_action 19:35:17 INFO - Running main action method: install 19:35:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:35:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:35:17 INFO - Reading from file tmpfile_stdout 19:35:17 INFO - Detecting whether we're running mozinstall >=1.0... 19:35:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:35:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:35:17 INFO - Reading from file tmpfile_stdout 19:35:17 INFO - Output received: 19:35:17 INFO - Usage: mozinstall [options] installer 19:35:17 INFO - Options: 19:35:17 INFO - -h, --help show this help message and exit 19:35:17 INFO - -d DEST, --destination=DEST 19:35:17 INFO - Directory to install application into. [default: 19:35:17 INFO - "/builds/slave/test"] 19:35:17 INFO - --app=APP Application being installed. [default: firefox] 19:35:17 INFO - mkdir: /builds/slave/test/build/application 19:35:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:35:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:35:52 INFO - Reading from file tmpfile_stdout 19:35:52 INFO - Output received: 19:35:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:35:52 INFO - Running post-action listener: _resource_record_post_action 19:35:52 INFO - ##### 19:35:52 INFO - ##### Finished install step (success) 19:35:52 INFO - ##### 19:35:52 INFO - ##### 19:35:52 INFO - ##### Running run-tests step. 19:35:52 INFO - ##### 19:35:52 INFO - Running pre-action listener: _resource_record_pre_action 19:35:52 INFO - Running main action method: run_tests 19:35:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:35:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:35:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:35:52 INFO - 2016-06-29 19:35:52.402 screenresolution[1638:7910] starting screenresolution argv=screenresolution get 19:35:52 INFO - 2016-06-29 19:35:52.404 screenresolution[1638:7910] Display 0: 1600x1200x32@60 19:35:52 INFO - 2016-06-29 19:35:52.411 screenresolution[1639:7913] starting screenresolution argv=screenresolution list 19:35:52 INFO - Available Modes on Display 0 19:35:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:35:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:35:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:35:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:35:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:35:52 INFO - Intel Iris: 19:35:52 INFO - Chipset Model: Intel Iris 19:35:52 INFO - Type: GPU 19:35:52 INFO - Bus: Built-In 19:35:52 INFO - VRAM (Dynamic, Max): 1536 MB 19:35:52 INFO - Vendor: Intel (0x8086) 19:35:52 INFO - Device ID: 0x0a2e 19:35:52 INFO - Revision ID: 0x0009 19:35:52 INFO - Displays: 19:35:52 INFO - Display: 19:35:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:35:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:35:52 INFO - Display Serial Number: 1600x1200 60 19:35:52 INFO - Main Display: Yes 19:35:52 INFO - Mirror: Off 19:35:52 INFO - Online: Yes 19:35:52 INFO - Rotation: Supported 19:35:52 INFO - Return code: 0 19:35:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:35:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:35:52 INFO - Python 2.7.3 19:35:52 INFO - Return code: 0 19:35:52 INFO - grabbing minidump binary from tooltool 19:35:52 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:52 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 0x108956d30>, '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 0x1086bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10894eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:35:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:35:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 19:35:52 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:35:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:35:52 INFO - Return code: 0 19:35:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:35:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:35:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:35:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:35:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:35:52 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/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0138', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:35:52 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/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0138 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:35:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWnZKQNJxh/Render', 19:35:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:52 INFO - 'HOME': '/Users/cltbld', 19:35:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:52 INFO - 'LOGNAME': 'cltbld', 19:35:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:35:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:35:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:52 INFO - 'MOZ_NO_REMOTE': '1', 19:35:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:35:52 INFO - 'NO_EM_RESTART': '1', 19:35:52 INFO - 'PAGER': '/bin/cat', 19:35:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:52 INFO - 'PWD': '/builds/slave/test', 19:35:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:35:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:52 INFO - 'SHELL': '/bin/bash', 19:35:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ShII1b3TD/Listeners', 19:35:52 INFO - 'TMPDIR': '/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/', 19:35:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:52 INFO - 'USER': 'cltbld', 19:35:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:52 INFO - 'XPC_FLAGS': '0x0', 19:35:52 INFO - 'XPC_SERVICE_NAME': '0', 19:35:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:52 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/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0138', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:35:52 INFO - mozversion application_buildid: 20160629172328 19:35:52 INFO - mozversion application_changeset: 0edb9df3c97ff7d205a99f8af607f99a858f140b 19:35:53 INFO - mozversion application_display_name: Nightly 19:35:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:35:53 INFO - mozversion application_name: Firefox 19:35:53 INFO - mozversion application_remotingname: firefox 19:35:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 19:35:53 INFO - mozversion application_vendor: Mozilla 19:35:53 INFO - mozversion application_version: 50.0a1 19:35:53 INFO - mozversion platform_buildid: 20160629172328 19:35:53 INFO - mozversion platform_changeset: 0edb9df3c97ff7d205a99f8af607f99a858f140b 19:35:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 19:35:53 INFO - mozversion platform_version: 50.0a1 19:35:53 INFO - using testdate: 1467254153 19:35:53 INFO - actual date: 1467254153 19:35:53 INFO - starting webserver on 'localhost:49265' 19:35:53 INFO - SUITE-START | Running 2 tests 19:35:53 INFO - TEST-START | dromaeo_css 19:35:53 INFO - operating with platform_type : mac_ 19:35:53 INFO - Initialising browser for dromaeo_css test... 19:35:53 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/tmpinwqoy/profile http://localhost:49265/getInfo.html) 19:35:55 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 19:35:55 INFO - PROCESS | 1649 | colorDepth:24 19:35:55 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 19:35:55 INFO - PROCESS | 1649 | __metrics 19:35:55 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:35:55 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:35:55 INFO - TEST-INFO | 1649: exit 0 19:35:55 INFO - Browser initialized. 19:35:55 INFO - Running cycle 1/1 for dromaeo_css test... 19:35:55 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/tmpinwqoy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:36:07 INFO - PROCESS | 1652 | RSS: Main: 243683328 19:36:07 INFO - PROCESS | 1652 | 19:36:59 INFO - PROCESS | 1652 | 1467254219394 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:39:08 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49265/tests/dromaeo/cssquery-ext.html) 19:39:10 INFO - PROCESS | 1652 | RSS: Main: 346447872 19:39:10 INFO - PROCESS | 1652 | 19:42:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-ext.html (next: http://localhost:49265/tests/dromaeo/cssquery-jquery.html) 19:42:13 INFO - PROCESS | 1652 | RSS: Main: 378269696 19:42:13 INFO - PROCESS | 1652 | 19:45:16 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49265/tests/dromaeo/cssquery-mootools.html) 19:45:16 INFO - PROCESS | 1652 | RSS: Main: 368168960 19:45:16 INFO - PROCESS | 1652 | 19:48:18 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49265/tests/dromaeo/cssquery-prototype.html) 19:48:19 INFO - PROCESS | 1652 | RSS: Main: 367792128 19:48:19 INFO - PROCESS | 1652 | 19:51:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49265/tests/dromaeo/cssquery-yui.html) 19:51:23 INFO - PROCESS | 1652 | RSS: Main: 334110720 19:51:23 INFO - PROCESS | 1652 | 19:54:26 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-yui.html (next: http://localhost:49265/tests/dromaeo/cssquery-dojo.html) 19:54:26 INFO - PROCESS | 1652 | RSS: Main: 393576448 19:54:26 INFO - PROCESS | 1652 | 19:54:26 INFO - PROCESS | 1652 | __start_tp_report 19:54:26 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 19:54:26 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 19:54:26 INFO - PROCESS | 1652 | |i|pagename|runs| 19:54:26 INFO - PROCESS | 1652 | |0;dojo.html;3374.358871814355;3404.659534046594;3418.1967237699123;3419.9316013679727;3457.4813777933305;5670.404607516203;5733.312002559699;5736.426357364261;5852.297724273093;5865.384134665857;5749.482546570808;5798.072308430652;5827.096799996008;5831.271091113611;5892.88214235715;5301.602379821509;5332.120200166968;5348.491893270133;5362.91955620666;5388.649737767052;5304.416514183436;5316.229146773716;5324.467553244664;5373.838784836461;5383.623146379755;34.65895463067491;5276.261323414725;5362.35651721794;5363.517283444489;5434.103372943479;5226.634135610509;5226.973865130688;5234.8691282717855;5283.075461794209;5311.2298716686255;5222.6605270657665;5235.947640523622;5247;5248.422673505911;5288.68267903927;5624.521915637135;5751.568632352591;5843.912341314884;5861.6189947653265;5925.614835035752;5032.521910418504;5900.852478688067;6263.404976527223;6281.59169653971;6301.905471417932;5827.941720582782;6209.782657607007;6222.3466536013975;6228.439440450381;6238.87522249557;5745.2818397700285;5842.094475356326;5955.285365756094;5959.493443057346;6021.7892373767145;6203.658798766111;6244.65654389013;6268.404501572343;6303.873922521524;6316.30520642729;6254.499640028786;6257.217847769029;6262.780802671885;6283.560150789401;6284.088807123033;6096.603720758137;6219.937800622027;6243.937560624336;6289.528285378615;6315.905261421082;6196.225471816023;6255.030470277115;6266.905996410058;6272.40412160844;6296.716647750863;6265.185525881698;6265.404786545271;6267.467265282502;6270.278917924497;6296.055591661196;4946.851594452172;4984.601231901439;5015.14742493777;5030.874228144253;5054.393472783289;4469.463664360298;4545.772711364419;4556.997460558672;4581.725096494221;4582.152301824173;5802.506786923074;5827.096799996008;5871.7651293947765;5902.704864756745;5934.762609495572;6198.659073750987;6222.471089957405;6255.093011513396;6264.561480696399;6275.215598050244;5860.3260625027815;5868.6446980890505;5874.970625146847;5876.941230587639;5877.147813567009;5372.704501252469;5414.6209765316835;5438.3473983121485;5483.7806487740045;5493.340799104133;6290.874182516324;6356.77751278703;6358.1734374531015;6362.968185159045;6366.554341196164;6196.442320191204;6234.127222188988;6239.438450539473;6248.781292654917;6249.7500099997305;6184.567080304336;6221.5333849962335;6266.122742815918;6267.9971204607045;6329.367063293634;6233.252009758858;6240.219972503437;6251.749930002749;6272.717727702356;6287.528435367274;6177.567570270038;6229.626222426669;6232.439080482778;6242.313345531897;6256.436920677161;6013.947559177039;6041.761438904926;6069.180660610761;6119.296280927572;6127.234095738033;5967.761289548543;6059.727312271047;6084.482818960438;6108.633481991094;6125.663088529996;5705.258316418839;5838.007538718343;5943.346231914399;5944.970275148769;5964.910526342195;6051.606645568024;6098.626220916368;6111.2465848185075;6114.175715401237;6122.062734707273;5944.613600115978;5957.4042595740075;5969.611975221596;5972.850678733066;6000.249968753906 19:54:26 INFO - PROCESS | 1652 | |1;ext.html;519.3974989012745;546.2789118363759;546.5627498001597;555.2365497441018;564.1622191046297;29192.416151676982;31116.066518004438;31129.066128016166;31130.68869311306;31205.219869503275;41386.37920431188;42611;42672;45333.7733311333;45431;9528.666496672618;9705.272104592148;9712.611495540204;9717.174040206592;9718.36830606012;8723.432976856515;8925.910740892605;9619.615215391375;9711.660091896785;9724.319297649168;11653.475593598308;11868.821967670492;11869.050475961902;12080.09399295052;12139.57511487098;10828.18788590851;11453.427328633577;11803.881961180365;11863.169578129535;11901.94049029758;7577.355924746404;10159.339642923187;11409.42952852354;11712.648620541397;11773.764524709457;39539.40690889639;39838.60161398378;39857.80071099655;39861.00347491307;39882.202355953006;3412.8634854605784;3447.052060683307;3738.532683414573;3939.4484772131923;4146.771927543984;30885.073447796378;32389.838050809594;32410.351792964244;32492.6750732492;32492.675073249437;3317.90046298609;3330.6669333066743;3363.024722830376;3384.4923261510726;3420.6579342065843;476805;477974;478596;480677;481113;60431.395686043455;60853.69573152106;60872.69563652154;60891.08663370053;60933.69533152306;63429.36570634327;63571;63649;63733.68133159305;63758.0436293456;16207.756883646754;16387.91806040986;16399.590010249838;16427.5893102671;16440.6711865762;11168.21822472419;12704.3012634306;12748.342715447117;12771.10602257833;12804.42380092898;14615.634609134857;14998.025128366622;16363.918180409022;16735.07957062349;16793.83206167947;6073.271207455134;6981.232064472904;7026.543274687129;7035.683394247271;7072.5402848814665;873.7204094689638;1046.4976811130548;1075.424647813416;1146.3179408252076;1152.5217034930577;28921;29828.850855745583;29869.70130298669;30122.24694382614;30617.08148755541;1085.8208395614636;1096.17786660005;1118.9608363707232;1144.5822274869613;1160.8665003524534;15617.531474055797;15829.841701582836;15830;16272.837271627131;16282.615977641795;585142;589030;589303;592081;596974;51235.975280494924;57788.13317800236;57971;58209.12686309624;58892.41107588955;1386.216787515071;1395.1001603965385;1411.1956184974672;1412.2091628687556;1413.5617958432713;25586.61620075663;29701.257468562595;30200.69799301979;31765.841170794924;31772.523412148374;45852.541474584825;56478.15282770679;58034.83930321285;58194;60651;511898;522573;640270;643587.4744384112;855248;25595.488090238465;25837.741622583533;26142.607860882483;26171;26214.60678089792;46459;46887.765561171975;46938.29592556046;47592;48215;38814.80592597019;39164.6083539161;39193.608063919;39757.40363894486;40009;37161.814190928875;38123.046923827125;38418.615813841505;38867;39782.00544986283;37248.81375593105;37676.093263885545;37903;37916.62083379241;38150.04624884289;32615.67384326221;34194.82902585471;34210;34224.486632700035;34416.65583344134;24921.25236242843;25116.874415627804;25631;25691.614625780254;25931.481370372865 19:54:26 INFO - PROCESS | 1652 | |2;jquery.html;361.07924791780954;381.93888977763555;388.64050753053436;392.3545767212935;410.1059689876069;15257.237138143104;15304.693906121885;20301.695474567856;20573.588528229426;20597.794022059792;20247;20424.28120126367;20973;21106.261280855175;21375.893120534376;5962;5988.401159884021;6169.19800425944;6206.503479721611;6218.378162183791;4231.70378073535;4465.37484752135;5945.16767652529;6604.405603495685;6964.477664175181;6633.800985970404;6878.931210687879;6927.9653601731925;6948.444124470056;6951.7834378983725;6831.146106736633;6858.382745552874;6874.931250687479;6880.862382752317;6885.586864788103;3769.773813571167;5586.162075688639;5918.348981612019;5923.17075609415;5982.820515384546;24851.62722559163;25873.353166170808;26154.215373538635;26305.605415918777;26483.73516264851;2213.0594497338566;2254.391314345134;2260.7174103237094;2467.6421918821848;2834.1922552072765;15653.373865045385;15898.841011589851;16124.951878128002;16290.185490725535;16600.003999760054;1870.672632289344;1883.1714045819933;1940.1851222486448;1988.015932113613;2129.5634394948997;119464.40267798692;123669.38165309206;125746;126208;126270.36864815617;11055.11559075272;11435.371054592077;11458.770824583462;11551.306921584732;11563.30620162793;12283.57007504733;12816.55142070023;14365.281735913162;14513.274336283144;14514.274286285643;7749.535027898344;7869.842603147905;8584.356173286904;8611.956940215385;8700.260477859327;8561.272291855263;8578.914210857936;8602.526861022703;8737.825243495221;8740.737777866674;4486.282194848874;4580.679352445297;4588.931166032512;4633.189191891405;4678.742669153229;1591.7214487464648;1789.4452719656688;1802.5767854699088;1936.8644194906503;1978.1592823050435;818.4475479051616;854.1671869926772;946.4557879219476;973.0026722609241;992.6624947517879;18731.876087434797;19549;19742.81543107418;20358.59282814365;22041.00815463308;801.7795106345685;929.2380248196414;948.2698322291797;949.3971328206501;955.3121752338305;9338.467132560672;11489.482973266224;11595.594154204562;11615.709607259718;11630.302181869114;535039;588680.0565997228;589775;599569;608720;131199.34400327937;131945;134392.92053096057;135056;135117;970.1995853420956;982.7199248214367;983.3165949665057;1001.2066334925477;1002.834532302162;17916.731249031014;19253;19482.12330445105;19542.729722567987;19586.118624661638;45459.77270113628;52980.67548311323;55209.89580208293;55434;56413;76061.61969190119;77815.22184778079;78181;78707.60646196733;79027;8060.193980602127;8245.95877020611;8256.711015114442;8484;8574.185452382013;11893.167478276784;12222.449989750305;12489.063320250836;13285.800712989507;13594.388252528463;11590.652280431263;11593;11607.709807254887;11759.588414405454;11776;11463.770724585629;11467.655970320904;11484.827727583926;11522.942385288019;11821.231619944676;10690.144788416752;10890.29213101146;11739;11764.235324703866;11911.463984120908;7093.858122837618;7527.661255243418;7548;8175.713850015459;8446.282066024456;8293.709720159766;10688.091512221237;11073.895959767618;11833;11937.940310298392 19:54:26 INFO - PROCESS | 1652 | |3;mootools.html;1489.947851825186;1504.7517159668655;1700.5238533210697;1748.7202047672367;1896.7629046369204;7277.126744790633;8387.203215694515;8778.473291602508;8778.736637900858;8803.691870784523;7611.733589324374;7851;8224.876626850602;8424.95787521063;8572.485650860937;2312.8265380096477;2344.308429013445;2345.9560495579476;2350.424146084203;2351.0243249051623;2293.552757212344;2304.066852924568;2354.505553833697;2354.517323948595;2363.598188307983;2066.2664754012335;2136.626090434168;2137.732783402075;2138.871667699935;2143.228437762404;2258.7402448718362;2325.546518428907;2327.499587588668;2341.9648705269433;2342.063174730122;2316.490372118148;2328.6157783965627;2342.636891281854;2346.77705617966;2346.97653023471;7660.540367577907;7870.921290787076;8356.122607126286;8694.913050869536;8722.694705685271;5045.621578381636;5227;5229;5229.241759944786;5242.711650859202;3542.8405721742324;3555.306715190539;3619.819009049537;3623.6376362363817;3624.4563315502887;970.9320347575673;1080.6649938519083;1082.10726150925;1091.0725882999466;1098.3903933317006;11031.724206894893;11176.441177941151;11252.943735281353;11257.099432045417;11258.887411125866;4495.820167193341;4496.775161241991;4705.529447055267;4729.337892694956;4729.952700472952;9736.659216927374;9750.463724495079;9755.073268039667;9756.07317304855;9757.316987810927;5203.037438073893;5204.869878253074;5205.2192171173865;5208.921866172011;5210.97394513025;4651.86044418668;4757.833475828329;4767.976160119178;4771.141194584939;4789.209780386197;3729.067733066733;3734.1971476132217;3778.8677396290996;3817.8091095445116;3828.712846536521;1232.9198602090835;1241.9813702794465;1243.9253644781343;1255.4852510470664;1258.9487777705717;779.7855589712761;806.5967016491754;883.7847959056297;960.5053397500293;975.9072888075622;5683.0338842396895;5712.400197979236;5755.424457554211;5960.523158147338;6016.849578760566;842.3219308456751;941.6280569175137;956.3879117364892;969.9572959069029;1003.6687892995294;3736.850525978985;3833.310004199215;3905.7851818150275;3907.316219661548;3911.1395492991496;10113.5448904798;10429.530671119817;10584.470776461147;10591.84112238317;10594.470276486145;10536.051755341903;10546.367217966947;10547.472626368804;10549.20880933933;10555.89444105549;6450.903236451363;6845.623490707959;7815.413843961725;7946.284834364935;8117.878231826528;5076.670016448919;5096.388433388017;5375.274337964324;5808;5863.296404431496;9403.717888463358;9696.5151742414;9708.805823883342;9725.6596019139;9970.750731231778;8593.95703021481;9806.460644664327;9946.900530994597;9955.153811926068;9981.950090249502;7343.816404589928;7431.516951398142;7515.398768098648;7523.3229009389415;7526.32263096324;8139.593020348841;8145.755627331189;8223.835523289621;8226.424150309422;8237.670493180445;6057.818265452043;7389.852202956018;7516.135644400964;7516.323530882247;7556.433267505069;7131.572105673677;7336.339729424164;7365.410767138509;7369.631518424165;7528.322450979438;6862.828429289308;6878.759243426456;6906.620135892575;6965.79102626922;6983.44132469391;4920.6801557898625;4924.089043526959;4949.529794669501;5000.099982003128;5004.699718016931;4951.851444456672;4996.100701873697;5012.523810238022;5024.924626130538;5110.029094472038 19:54:26 INFO - PROCESS | 1652 | |4;prototype.html;298.83713376209965;308.82088388734536;311.09316342860564;314.3775324856785;317.576035991951;4788.569028787413;5350.250964864912;5512.669239845607;5584.553235741141;5595.49640532352;5135.5121263480005;5457.590680698943;5537.750801213945;5554.361248456429;5560.832225232696;3737.9065523361996;3758.3798673218885;3976.6619837313865;4034.930743353014;4090.3455447128467;4071.3689378146287;4150.485072948367;4177.665786737054;4185.100203456256;4193.853215137471;3529.3470707918987;4137.793110344486;4154.31453810121;4164.437800896885;4176.269152898246;3632.291703117893;3874.786886721229;3946.2502124596376;4003.83984640614;4145.419641250227;3135.498320268746;3581.8896142196004;3599.1541987632886;3601.7658852174786;3609.7005078171837;4660.906781864343;5219.817306394296;5294.623397916531;5334.293026231608;5347.973260133675;4527.592516673482;4710.411198600175;4777.235642297216;4779.211430114027;4794.304825800275;4478.664100192498;4667.9766601166775;5662.094064949588;5690.630109042899;5739.540836733093;3983.820728067244;4031.4355990161403;4038.9394159087637;4043.4541336919433;4052.5947405259535;5364.597655175839;5815.4879731270175;6023.307319658252;6028.246469191351;6031.853947749957;3861.362875125629;4124.8556300529335;4139.2135494256;4161.583841615814;4161.667066634662;3958.6936311017294;4140.896477588084;4170.562090980464;4177.665786737054;4219.261629214875;4205.894852628648;4512.9774351128035;4629.8148074077335;4792.520747925179;4802.399700037496;5165.380154381498;5496.477834605706;5646;5746.655200687972;5823.621464604787;1322.3123975532862;1375.1336657905456;1386.729587730403;1429.4425174182077;1444.0469290268372;822.3133683374449;865.2947847504264;869.3349587565517;881.9029906710257;884.0584777212248;4233.280342341809;4493.348464472633;5140.717260550706;5167.302414174113;5329.307190065267;5035.446100928968;5159.916417552281;5675.5459563234845;5736.996025695486;5996.760129594854;4344.869653910388;5115.56517696;5359.758810853521;5363.230134055752;5366.543843773245;2813.1560531840364;3052.977252620397;3131.4363414585137;3133.6396314423678;3146.229173852421;5505.944940550623;5982.910256346158;6122.479589234965;6167.784127555514;6186.659733714599;5781.797637082682;6021.939780602225;6158.692065396713;6164.660943648142;6222.471089957405;5686.521504408884;6142.754289828356;6148.938510614837;6193.349698281706;6220.813375598739;5564.19319198722;5940.762369505172;5983.820485385271;6010.827888561864;6141.170941922962;5280.86797830042;5616.66300022;5677.261955945701;5931.813637272477;5938.168656388196;5226.451222621722;5462.481064298885;6172.580306529434;6199.597026193283;6251.6561589113035;3699.7040236780454;3759.172981943968;3817.694584433294;3934.370500719871;3977.6134319408125;5724.542036637143;5969.432903874125;6016.5487588431915;6055.031195008777;6090.573659843769;5502.504774570308;5778.393268706894;5805.564582656233;5832.854178645568;5838.883222335445;5671;5696.5535850809665;5712.485876271156;5734.7132643368495;5736.68448235351;4743;4826.469088400343;4965.379327584052;5208.921866171935;5560.944390556043;4862.489438609036;5283.260343551829;5404.783808647768;5570.24801651788;5594.804181853616;5495.972520137374;5553.555715542828;5576.302962129734;5595.188697638901;5624.353199382123 19:54:26 INFO - PROCESS | 1652 | |5;yui.html;703.4337358426468;704.5138854190608;750.639692947385;789.2975252025698;818.161384580805;15156.848431515682;16271.186440677948;16282.592935176626;16298.429554965578;16314.918425407872;14589.124652520855;14694.632634184123;14731.558053258392;14763.188024658639;14766.926165369185;682.3415404135009;700.8318003679132;828.7555171224503;832.3507664022072;849.2738708404318;849.1890244816186;858.8582883824162;859.669027424443;859.7205908079899;860.1054902900976;900.2392977933636;914.7804526913559;915.2997956563235;916.564631799893;916.7983043730369;711.6761873347666;745.8508298340299;748.4050180106829;915.7802127489388;917.7384445433053;544.727636181909;545.4463719324889;634.8571571396399;667.552739664426;670.4904272752694;13916.652083697887;14138.717225655531;14175.36210870503;14261.572152835432;14301.856981430157;13899.722005559934;14122.646933826634;14134.293285335692;14171.716565668732;14202.147871127765;13071.542496012595;13129.540466083738;13145.671358216121;13159.210447373092;13321.800172997317;4659.091477161955;4667.089917466095;4711.104890070876;4861.756912154378;4883.438404583448;105171.47414262957;106246.93753062525;109685;109759;111860.44069779599;11014.779704405866;11137.276077054965;11183.384913829817;11192.552297908156;11294;21604.56790864174;22464;22606.982685779178;22621.88689056544;22626.094956201567;8323.791905202419;8329.916700833035;8349.791255218668;8385.245327920517;8509.702160424355;4323.329883868006;4380.214097480421;4476.955230447719;4520.683552151318;4602.1025899949855;3547.2728090741334;3665.578458477256;3668.1013151777984;3670.871519496805;3673.045008297808;646.9805905822833;661.4212564006494;668.4719071933118;669.7454967112466;672.1832972937904;541.1557969567488;551.9779208831602;553.9113741801373;613.5152038597864;660.6529038923869;8491.19333663301;9402.38884472507;9613.567389467351;9651.469169195761;9736.805263894825;642.3544337940433;660.8513084555937;662.1292999705372;669.9129113215348;677.6239187251123;3601.9099522512147;4410.514843367227;4533.478649955231;4543.091381723669;4624.075184962953;130693.34653326673;130762;133143;133635;134315;80507.7923831143;98273;116347;116532;119731.40134299448;105014;106015;106471;107012;107113;7466.136770998293;8379.748607541784;8463.66145354179;8622.655093796178;8820.426672266281;52329.21506177486;52713.945721084594;55070;59026.409735903806;60558.69720651369;74064.25935740574;86718.83202919978;86904;87545;87799.24401511806;11249.606263780728;13265.535706250235;13673.179609223478;13866.306684665524;13867;15143.318550665466;15151.393944242107;15415.768763468333;15617.531474055797;15861.365545378056;12906.67733306675;14298.57104286873;14362.779163771196;14439.061461005002;14754.336054877342;13574.79637805414;13610.115342502706;13840.584782456543;14026.368813403626;14688.118712877262;13853.37659805291;13978.580642580739;13994.230317332238;14032.649183770487;14038.859611403755;11130.554777808797;11316.660500185008;11624.6512604622;11667.299962002307;11676.88323116792;10270.897291027293;11182.440877956233;11484;11577.710557236136;11819.822702659296 19:54:26 INFO - PROCESS | 1652 | __end_tp_report 19:54:26 INFO - PROCESS | 1652 | __start_cc_report 19:54:26 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1073 19:54:26 INFO - PROCESS | 1652 | __end_cc_report 19:54:26 INFO - PROCESS | 1652 | __startTimestamp1467255266920__endTimestamp 19:54:26 INFO - PROCESS | 1652 | 19:54:26 INFO - PROCESS | 1652 | ------- Summary: start ------- 19:54:26 INFO - PROCESS | 1652 | Number of tests: 6 19:54:26 INFO - PROCESS | 1652 | 19:54:26 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5757.19 Median:5959.49 stddev:758.83 (12.7%) stddev-sans-first:737.77 19:54:26 INFO - PROCESS | 1652 | Values: 3374.4 3404.7 3418.2 3419.9 3457.5 5670.4 5733.3 5736.4 5852.3 5865.4 5749.5 5798.1 5827.1 5831.3 5892.9 5301.6 5332.1 5348.5 5362.9 5388.6 5304.4 5316.2 5324.5 5373.8 5383.6 34.7 5276.3 5362.4 5363.5 5434.1 5226.6 5227.0 5234.9 5283.1 5311.2 5222.7 5235.9 5247.0 5248.4 5288.7 5624.5 5751.6 5843.9 5861.6 5925.6 5032.5 5900.9 6263.4 6281.6 6301.9 5827.9 6209.8 6222.3 6228.4 6238.9 5745.3 5842.1 5955.3 5959.5 6021.8 6203.7 6244.7 6268.4 6303.9 6316.3 6254.5 6257.2 6262.8 6283.6 6284.1 6096.6 6219.9 6243.9 6289.5 6315.9 6196.2 6255.0 6266.9 6272.4 6296.7 6265.2 6265.4 6267.5 6270.3 6296.1 4946.9 4984.6 5015.1 5030.9 5054.4 4469.5 4545.8 4557.0 4581.7 4582.2 5802.5 5827.1 5871.8 5902.7 5934.8 6198.7 6222.5 6255.1 6264.6 6275.2 5860.3 5868.6 5875.0 5876.9 5877.1 5372.7 5414.6 5438.3 5483.8 5493.3 6290.9 6356.8 6358.2 6363.0 6366.6 6196.4 6234.1 6239.4 6248.8 6249.8 6184.6 6221.5 6266.1 6268.0 6329.4 6233.3 6240.2 6251.7 6272.7 6287.5 6177.6 6229.6 6232.4 6242.3 6256.4 6013.9 6041.8 6069.2 6119.3 6127.2 5967.8 6059.7 6084.5 6108.6 6125.7 5705.3 5838.0 5943.3 5945.0 5964.9 6051.6 6098.6 6111.2 6114.2 6122.1 5944.6 5957.4 5969.6 5972.9 6000.2 19:54:26 INFO - PROCESS | 1652 | 19:54:26 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:70200.34 Median:37205.31 stddev:154181.50 (414.4%) stddev-sans-first:154525.25 19:54:26 INFO - PROCESS | 1652 | Values: 519.4 546.3 546.6 555.2 564.2 29192.4 31116.1 31129.1 31130.7 31205.2 41386.4 42611.0 42672.0 45333.8 45431.0 9528.7 9705.3 9712.6 9717.2 9718.4 8723.4 8925.9 9619.6 9711.7 9724.3 11653.5 11868.8 11869.1 12080.1 12139.6 10828.2 11453.4 11803.9 11863.2 11901.9 7577.4 10159.3 11409.4 11712.6 11773.8 39539.4 39838.6 39857.8 39861.0 39882.2 3412.9 3447.1 3738.5 3939.4 4146.8 30885.1 32389.8 32410.4 32492.7 32492.7 3317.9 3330.7 3363.0 3384.5 3420.7 476805.0 477974.0 478596.0 480677.0 481113.0 60431.4 60853.7 60872.7 60891.1 60933.7 63429.4 63571.0 63649.0 63733.7 63758.0 16207.8 16387.9 16399.6 16427.6 16440.7 11168.2 12704.3 12748.3 12771.1 12804.4 14615.6 14998.0 16363.9 16735.1 16793.8 6073.3 6981.2 7026.5 7035.7 7072.5 873.7 1046.5 1075.4 1146.3 1152.5 28921.0 29828.9 29869.7 30122.2 30617.1 1085.8 1096.2 1119.0 1144.6 1160.9 15617.5 15829.8 15830.0 16272.8 16282.6 585142.0 589030.0 589303.0 592081.0 596974.0 51236.0 57788.1 57971.0 58209.1 58892.4 1386.2 1395.1 1411.2 1412.2 1413.6 25586.6 29701.3 30200.7 31765.8 31772.5 45852.5 56478.2 58034.8 58194.0 60651.0 511898.0 522573.0 640270.0 643587.5 855248.0 25595.5 25837.7 26142.6 26171.0 26214.6 46459.0 46887.8 46938.3 47592.0 48215.0 38814.8 39164.6 39193.6 39757.4 40009.0 37161.8 38123.0 38418.6 38867.0 39782.0 37248.8 37676.1 37903.0 37916.6 38150.0 32615.7 34194.8 34210.0 34224.5 34416.7 24921.3 25116.9 25631.0 25691.6 25931.5 19:54:26 INFO - PROCESS | 1652 | 19:54:26 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:35167.07 Median:26013.78 stddev:98014.36 (376.8%) stddev-sans-first:98254.48 19:54:26 INFO - PROCESS | 1652 | Values: 361.1 381.9 388.6 392.4 410.1 15257.2 15304.7 20301.7 20573.6 20597.8 20247.0 20424.3 20973.0 21106.3 21375.9 5962.0 5988.4 6169.2 6206.5 6218.4 4231.7 4465.4 5945.2 6604.4 6964.5 6633.8 6878.9 6928.0 6948.4 6951.8 6831.1 6858.4 6874.9 6880.9 6885.6 3769.8 5586.2 5918.3 5923.2 5982.8 24851.6 25873.4 26154.2 26305.6 26483.7 2213.1 2254.4 2260.7 2467.6 2834.2 15653.4 15898.8 16125.0 16290.2 16600.0 1870.7 1883.2 1940.2 1988.0 2129.6 119464.4 123669.4 125746.0 126208.0 126270.4 11055.1 11435.4 11458.8 11551.3 11563.3 12283.6 12816.6 14365.3 14513.3 14514.3 7749.5 7869.8 8584.4 8612.0 8700.3 8561.3 8578.9 8602.5 8737.8 8740.7 4486.3 4580.7 4588.9 4633.2 4678.7 1591.7 1789.4 1802.6 1936.9 1978.2 818.4 854.2 946.5 973.0 992.7 18731.9 19549.0 19742.8 20358.6 22041.0 801.8 929.2 948.3 949.4 955.3 9338.5 11489.5 11595.6 11615.7 11630.3 535039.0 588680.1 589775.0 599569.0 608720.0 131199.3 131945.0 134392.9 135056.0 135117.0 970.2 982.7 983.3 1001.2 1002.8 17916.7 19253.0 19482.1 19542.7 19586.1 45459.8 52980.7 55209.9 55434.0 56413.0 76061.6 77815.2 78181.0 78707.6 79027.0 8060.2 8246.0 8256.7 8484.0 8574.2 11893.2 12222.4 12489.1 13285.8 13594.4 11590.7 11593.0 11607.7 11759.6 11776.0 11463.8 11467.7 11484.8 11522.9 11821.2 10690.1 10890.3 11739.0 11764.2 11911.5 7093.9 7527.7 7548.0 8175.7 8446.3 8293.7 10688.1 11073.9 11833.0 11937.9 19:54:26 INFO - PROCESS | 1652 | 19:54:26 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5561.27 Median:5086.53 stddev:3086.97 (60.7%) stddev-sans-first:3080.46 19:54:26 INFO - PROCESS | 1652 | Values: 1489.9 1504.8 1700.5 1748.7 1896.8 7277.1 8387.2 8778.5 8778.7 8803.7 7611.7 7851.0 8224.9 8425.0 8572.5 2312.8 2344.3 2346.0 2350.4 2351.0 2293.6 2304.1 2354.5 2354.5 2363.6 2066.3 2136.6 2137.7 2138.9 2143.2 2258.7 2325.5 2327.5 2342.0 2342.1 2316.5 2328.6 2342.6 2346.8 2347.0 7660.5 7870.9 8356.1 8694.9 8722.7 5045.6 5227.0 5229.0 5229.2 5242.7 3542.8 3555.3 3619.8 3623.6 3624.5 970.9 1080.7 1082.1 1091.1 1098.4 11031.7 11176.4 11252.9 11257.1 11258.9 4495.8 4496.8 4705.5 4729.3 4730.0 9736.7 9750.5 9755.1 9756.1 9757.3 5203.0 5204.9 5205.2 5208.9 5211.0 4651.9 4757.8 4768.0 4771.1 4789.2 3729.1 3734.2 3778.9 3817.8 3828.7 1232.9 1242.0 1243.9 1255.5 1258.9 779.8 806.6 883.8 960.5 975.9 5683.0 5712.4 5755.4 5960.5 6016.8 842.3 941.6 956.4 970.0 1003.7 3736.9 3833.3 3905.8 3907.3 3911.1 10113.5 10429.5 10584.5 10591.8 10594.5 10536.1 10546.4 10547.5 10549.2 10555.9 6450.9 6845.6 7815.4 7946.3 8117.9 5076.7 5096.4 5375.3 5808.0 5863.3 9403.7 9696.5 9708.8 9725.7 9970.8 8594.0 9806.5 9946.9 9955.2 9982.0 7343.8 7431.5 7515.4 7523.3 7526.3 8139.6 8145.8 8223.8 8226.4 8237.7 6057.8 7389.9 7516.1 7516.3 7556.4 7131.6 7336.3 7365.4 7369.6 7528.3 6862.8 6878.8 6906.6 6965.8 6983.4 4920.7 4924.1 4949.5 5000.1 5004.7 4951.9 4996.1 5012.5 5024.9 5110.0 19:54:26 INFO - PROCESS | 1652 | 19:54:26 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4659.65 Median:5253.66 stddev:1434.09 (27.3%) stddev-sans-first:1400.26 19:54:26 INFO - PROCESS | 1652 | Values: 298.8 308.8 311.1 314.4 317.6 4788.6 5350.3 5512.7 5584.6 5595.5 5135.5 5457.6 5537.8 5554.4 5560.8 3737.9 3758.4 3976.7 4034.9 4090.3 4071.4 4150.5 4177.7 4185.1 4193.9 3529.3 4137.8 4154.3 4164.4 4176.3 3632.3 3874.8 3946.3 4003.8 4145.4 3135.5 3581.9 3599.2 3601.8 3609.7 4660.9 5219.8 5294.6 5334.3 5348.0 4527.6 4710.4 4777.2 4779.2 4794.3 4478.7 4668.0 5662.1 5690.6 5739.5 3983.8 4031.4 4038.9 4043.5 4052.6 5364.6 5815.5 6023.3 6028.2 6031.9 3861.4 4124.9 4139.2 4161.6 4161.7 3958.7 4140.9 4170.6 4177.7 4219.3 4205.9 4513.0 4629.8 4792.5 4802.4 5165.4 5496.5 5646.0 5746.7 5823.6 1322.3 1375.1 1386.7 1429.4 1444.0 822.3 865.3 869.3 881.9 884.1 4233.3 4493.3 5140.7 5167.3 5329.3 5035.4 5159.9 5675.5 5737.0 5996.8 4344.9 5115.6 5359.8 5363.2 5366.5 2813.2 3053.0 3131.4 3133.6 3146.2 5505.9 5982.9 6122.5 6167.8 6186.7 5781.8 6021.9 6158.7 6164.7 6222.5 5686.5 6142.8 6148.9 6193.3 6220.8 5564.2 5940.8 5983.8 6010.8 6141.2 5280.9 5616.7 5677.3 5931.8 5938.2 5226.5 5462.5 6172.6 6199.6 6251.7 3699.7 3759.2 3817.7 3934.4 3977.6 5724.5 5969.4 6016.5 6055.0 6090.6 5502.5 5778.4 5805.6 5832.9 5838.9 5671.0 5696.6 5712.5 5734.7 5736.7 4743.0 4826.5 4965.4 5208.9 5560.9 4862.5 5283.3 5404.8 5570.2 5594.8 5496.0 5553.6 5576.3 5595.2 5624.4 19:54:26 INFO - PROCESS | 1652 | 19:54:26 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:23426.57 Median:3666.84 stddev:35840.70 (977.4%) stddev-sans-first:35900.63 19:54:26 INFO - PROCESS | 1652 | Values: 703.4 704.5 750.6 789.3 818.2 15156.8 16271.2 16282.6 16298.4 16314.9 14589.1 14694.6 14731.6 14763.2 14766.9 682.3 700.8 828.8 832.4 849.3 849.2 858.9 859.7 859.7 860.1 900.2 914.8 915.3 916.6 916.8 711.7 745.9 748.4 915.8 917.7 544.7 545.4 634.9 667.6 670.5 13916.7 14138.7 14175.4 14261.6 14301.9 13899.7 14122.6 14134.3 14171.7 14202.1 13071.5 13129.5 13145.7 13159.2 13321.8 4659.1 4667.1 4711.1 4861.8 4883.4 105171.5 106246.9 109685.0 109759.0 111860.4 11014.8 11137.3 11183.4 11192.6 11294.0 21604.6 22464.0 22607.0 22621.9 22626.1 8323.8 8329.9 8349.8 8385.2 8509.7 4323.3 4380.2 4477.0 4520.7 4602.1 3547.3 3665.6 3668.1 3670.9 3673.0 647.0 661.4 668.5 669.7 672.2 541.2 552.0 553.9 613.5 660.7 8491.2 9402.4 9613.6 9651.5 9736.8 642.4 660.9 662.1 669.9 677.6 3601.9 4410.5 4533.5 4543.1 4624.1 130693.3 130762.0 133143.0 133635.0 134315.0 80507.8 98273.0 116347.0 116532.0 119731.4 105014.0 106015.0 106471.0 107012.0 107113.0 7466.1 8379.7 8463.7 8622.7 8820.4 52329.2 52713.9 55070.0 59026.4 60558.7 74064.3 86718.8 86904.0 87545.0 87799.2 11249.6 13265.5 13673.2 13866.3 13867.0 15143.3 15151.4 15415.8 15617.5 15861.4 12906.7 14298.6 14362.8 14439.1 14754.3 13574.8 13610.1 13840.6 14026.4 14688.1 13853.4 13978.6 13994.2 14032.6 14038.9 11130.6 11316.7 11624.7 11667.3 11676.9 10270.9 11182.4 11484.0 11577.7 11819.8 19:54:26 INFO - PROCESS | 1652 | -------- Summary: end -------- 19:54:26 INFO - PROCESS | 1652 | 19:54:27 INFO - TEST-INFO | 1652: exit 0 19:54:27 INFO - TEST-OK | dromaeo_css | took 1114487ms 19:54:27 INFO - TEST-START | kraken 19:54:27 INFO - operating with platform_type : mac_ 19:54:27 INFO - Initialising browser for kraken test... 19:54:27 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/tmpGupjlf/profile http://localhost:49265/getInfo.html) 19:54:29 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 19:54:29 INFO - PROCESS | 1657 | colorDepth:24 19:54:29 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 19:54:29 INFO - PROCESS | 1657 | __metrics 19:54:29 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:54:29 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:54:30 INFO - TEST-INFO | 1657: exit 0 19:54:30 INFO - Browser initialized. 19:54:30 INFO - Running cycle 1/1 for kraken test... 19:54:30 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/T/tmpGupjlf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:54:42 INFO - PROCESS | 1658 | RSS: Main: 243892224 19:54:42 INFO - PROCESS | 1658 | 19:54:44 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 19:54:45 INFO - PROCESS | 1658 | RSS: Main: 294072320 19:54:45 INFO - PROCESS | 1658 | 19:54:48 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 19:54:49 INFO - PROCESS | 1658 | RSS: Main: 304222208 19:54:49 INFO - PROCESS | 1658 | 19:54:52 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 19:54:53 INFO - PROCESS | 1658 | RSS: Main: 388034560 19:54:53 INFO - PROCESS | 1658 | 19:54:55 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 19:54:56 INFO - PROCESS | 1658 | RSS: Main: 289853440 19:54:56 INFO - PROCESS | 1658 | 19:54:58 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:54:58 INFO - PROCESS | 1658 | RSS: Main: 293376000 19:54:58 INFO - PROCESS | 1658 | 19:55:01 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 19:55:02 INFO - PROCESS | 1658 | RSS: Main: 331943936 19:55:02 INFO - PROCESS | 1658 | 19:55:05 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 19:55:05 INFO - PROCESS | 1658 | RSS: Main: 333946880 19:55:05 INFO - PROCESS | 1658 | 19:55:08 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 19:55:09 INFO - PROCESS | 1658 | RSS: Main: 331083776 19:55:09 INFO - PROCESS | 1658 | 19:55:10 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:55:11 INFO - PROCESS | 1658 | RSS: Main: 313032704 19:55:11 INFO - PROCESS | 1658 | 19:55:13 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:55:13 INFO - PROCESS | 1658 | RSS: Main: 319549440 19:55:13 INFO - PROCESS | 1658 | 19:55:16 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:55:16 INFO - PROCESS | 1658 | RSS: Main: 317227008 19:55:16 INFO - PROCESS | 1658 | 19:55:19 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:55:20 INFO - PROCESS | 1658 | RSS: Main: 325996544 19:55:20 INFO - PROCESS | 1658 | 19:55:23 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:55:23 INFO - PROCESS | 1658 | RSS: Main: 328339456 19:55:23 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 19:55:25 INFO - PROCESS | 1658 | RSS: Main: 371920896 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | __start_tp_report 19:55:25 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 19:55:25 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 19:55:25 INFO - PROCESS | 1658 | |i|pagename|runs| 19:55:25 INFO - PROCESS | 1658 | |0;ai-astar;102;113;114;112;114;111;113;113;112;113 19:55:25 INFO - PROCESS | 1658 | |1;audio-beat-detection;145;108;107;160;106;116;148;125;165;105 19:55:25 INFO - PROCESS | 1658 | |2;audio-dft;183;185;188;185;195;252;193;184;183;182 19:55:25 INFO - PROCESS | 1658 | |3;audio-fft;78;74;58;107;57;57;62;80;71;57 19:55:25 INFO - PROCESS | 1658 | |4;audio-oscillator;86;89;94;69;70;70;93;71;68;101 19:55:25 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;87;85;89;85;87;83;87;85;88;121 19:55:25 INFO - PROCESS | 1658 | |6;imaging-darkroom;94;108;139;109;93;93;97;94;93;93 19:55:25 INFO - PROCESS | 1658 | |7;imaging-desaturate;86;86;87;87;84;87;83;87;95;137 19:55:25 INFO - PROCESS | 1658 | |8;json-parse-financial;53;54;53;55;55;55;59;54;55;55 19:55:25 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;66;67;76;79;72;71;71;72;70;72 19:55:25 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;85;85;81;80;95;95;92;93;81;79 19:55:25 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;138;136;138;135;136;151;124;143;237;145 19:55:25 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;165;172;160;167;162;167;166;164;166;163 19:55:25 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;68;66;67;65;65;67;66;67;68;72 19:55:25 INFO - PROCESS | 1658 | __end_tp_report 19:55:25 INFO - PROCESS | 1658 | __start_cc_report 19:55:25 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,2035 19:55:25 INFO - PROCESS | 1658 | __end_cc_report 19:55:25 INFO - PROCESS | 1658 | __startTimestamp1467255325679__endTimestamp 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | ------- Summary: start ------- 19:55:25 INFO - PROCESS | 1658 | Number of tests: 14 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.53 (3.1%) stddev-sans-first:0.97 19:55:25 INFO - PROCESS | 1658 | Values: 102.0 113.0 114.0 112.0 114.0 111.0 113.0 113.0 112.0 113.0 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:135.00 stddev:23.77 (17.6%) stddev-sans-first:24.45 19:55:25 INFO - PROCESS | 1658 | Values: 145.0 108.0 107.0 160.0 106.0 116.0 148.0 125.0 165.0 105.0 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:193.00 Median:186.50 stddev:21.19 (11.4%) stddev-sans-first:22.16 19:55:25 INFO - PROCESS | 1658 | Values: 183.0 185.0 188.0 185.0 195.0 252.0 193.0 184.0 183.0 182.0 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:70.10 Median:66.50 stddev:15.86 (23.9%) stddev-sans-first:16.57 19:55:25 INFO - PROCESS | 1658 | Values: 78.0 74.0 58.0 107.0 57.0 57.0 62.0 80.0 71.0 57.0 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:78.50 stddev:12.72 (16.2%) stddev-sans-first:13.37 19:55:25 INFO - PROCESS | 1658 | Values: 86.0 89.0 94.0 69.0 70.0 70.0 93.0 71.0 68.0 101.0 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:87.00 stddev:11.14 (12.8%) stddev-sans-first:11.77 19:55:25 INFO - PROCESS | 1658 | Values: 87.0 85.0 89.0 85.0 87.0 83.0 87.0 85.0 88.0 121.0 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:93.00 stddev:14.63 (15.7%) stddev-sans-first:15.28 19:55:25 INFO - PROCESS | 1658 | Values: 94.0 108.0 139.0 109.0 93.0 93.0 97.0 94.0 93.0 93.0 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:91.90 Median:87.00 stddev:16.16 (18.6%) stddev-sans-first:17.00 19:55:25 INFO - PROCESS | 1658 | Values: 86.0 86.0 87.0 87.0 84.0 87.0 83.0 87.0 95.0 137.0 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:1.69 (3.1%) stddev-sans-first:1.66 19:55:25 INFO - PROCESS | 1658 | Values: 53.0 54.0 53.0 55.0 55.0 55.0 59.0 54.0 55.0 55.0 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.00 stddev:3.81 (5.3%) stddev-sans-first:3.46 19:55:25 INFO - PROCESS | 1658 | Values: 66.0 67.0 76.0 79.0 72.0 71.0 71.0 72.0 70.0 72.0 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:88.50 stddev:6.50 (7.3%) stddev-sans-first:6.87 19:55:25 INFO - PROCESS | 1658 | Values: 85.0 85.0 81.0 80.0 95.0 95.0 92.0 93.0 81.0 79.0 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:140.50 stddev:31.97 (22.8%) stddev-sans-first:33.69 19:55:25 INFO - PROCESS | 1658 | Values: 138.0 136.0 138.0 135.0 136.0 151.0 124.0 143.0 237.0 145.0 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:166.00 stddev:3.29 (2.0%) stddev-sans-first:3.49 19:55:25 INFO - PROCESS | 1658 | Values: 165.0 172.0 160.0 167.0 162.0 167.0 166.0 164.0 166.0 163.0 19:55:25 INFO - PROCESS | 1658 | 19:55:25 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:2.02 (3.0%) stddev-sans-first:2.12 19:55:25 INFO - PROCESS | 1658 | Values: 68.0 66.0 67.0 65.0 65.0 67.0 66.0 67.0 68.0 72.0 19:55:25 INFO - PROCESS | 1658 | -------- Summary: end -------- 19:55:25 INFO - PROCESS | 1658 | 19:55:26 INFO - TEST-INFO | 1658: exit 0 19:55:26 INFO - TEST-OK | kraken | took 59026ms 19:55:26 INFO - SUITE-END | took 1173s 19:55:26 INFO - Completed test suite (00:19:33) 19:55:26 INFO - javascript benchmark 19:55:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3374.358871814355, 3404.659534046594, 3418.1967237699123, 3419.9316013679727, 3457.4813777933305, 5670.404607516203, 5733.312002559699, 5736.426357364261, 5852.297724273093, 5865.384134665857, 5749.482546570808, 5798.072308430652, 5827.096799996008, 5831.271091113611, 5892.88214235715, 5301.602379821509, 5332.120200166968, 5348.491893270133, 5362.91955620666, 5388.649737767052, 5304.416514183436, 5316.229146773716, 5324.467553244664, 5373.838784836461, 5383.623146379755, 34.65895463067491, 5276.261323414725, 5362.35651721794, 5363.517283444489, 5434.103372943479, 5226.634135610509, 5226.973865130688, 5234.8691282717855, 5283.075461794209, 5311.2298716686255, 5222.6605270657665, 5235.947640523622, 5247.0, 5248.422673505911, 5288.68267903927, 5624.521915637135, 5751.568632352591, 5843.912341314884, 5861.6189947653265, 5925.614835035752, 5032.521910418504, 5900.852478688067, 6263.404976527223, 6281.59169653971, 6301.905471417932, 5827.941720582782, 6209.782657607007, 6222.3466536013975, 6228.439440450381, 6238.87522249557, 5745.2818397700285, 5842.094475356326, 5955.285365756094, 5959.493443057346, 6021.7892373767145, 6203.658798766111, 6244.65654389013, 6268.404501572343, 6303.873922521524, 6316.30520642729, 6254.499640028786, 6257.217847769029, 6262.780802671885, 6283.560150789401, 6284.088807123033, 6096.603720758137, 6219.937800622027, 6243.937560624336, 6289.528285378615, 6315.905261421082, 6196.225471816023, 6255.030470277115, 6266.905996410058, 6272.40412160844, 6296.716647750863, 6265.185525881698, 6265.404786545271, 6267.467265282502, 6270.278917924497, 6296.055591661196, 4946.851594452172, 4984.601231901439, 5015.14742493777, 5030.874228144253, 5054.393472783289, 4469.463664360298, 4545.772711364419, 4556.997460558672, 4581.725096494221, 4582.152301824173, 5802.506786923074, 5827.096799996008, 5871.7651293947765, 5902.704864756745, 5934.762609495572, 6198.659073750987, 6222.471089957405, 6255.093011513396, 6264.561480696399, 6275.215598050244, 5860.3260625027815, 5868.6446980890505, 5874.970625146847, 5876.941230587639, 5877.147813567009, 5372.704501252469, 5414.6209765316835, 5438.3473983121485, 5483.7806487740045, 5493.340799104133, 6290.874182516324, 6356.77751278703, 6358.1734374531015, 6362.968185159045, 6366.554341196164, 6196.442320191204, 6234.127222188988, 6239.438450539473, 6248.781292654917, 6249.7500099997305, 6184.567080304336, 6221.5333849962335, 6266.122742815918, 6267.9971204607045, 6329.367063293634, 6233.252009758858, 6240.219972503437, 6251.749930002749, 6272.717727702356, 6287.528435367274, 6177.567570270038, 6229.626222426669, 6232.439080482778, 6242.313345531897, 6256.436920677161, 6013.947559177039, 6041.761438904926, 6069.180660610761, 6119.296280927572, 6127.234095738033, 5967.761289548543, 6059.727312271047, 6084.482818960438, 6108.633481991094, 6125.663088529996, 5705.258316418839, 5838.007538718343, 5943.346231914399, 5944.970275148769, 5964.910526342195, 6051.606645568024, 6098.626220916368, 6111.2465848185075, 6114.175715401237, 6122.062734707273, 5944.613600115978, 5957.4042595740075, 5969.611975221596, 5972.850678733066, 6000.249968753906], "value": 5713.129087284025, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.3974989012745, 546.2789118363759, 546.5627498001597, 555.2365497441018, 564.1622191046297, 29192.416151676982, 31116.066518004438, 31129.066128016166, 31130.68869311306, 31205.219869503275, 41386.37920431188, 42611.0, 42672.0, 45333.7733311333, 45431.0, 9528.666496672618, 9705.272104592148, 9712.611495540204, 9717.174040206592, 9718.36830606012, 8723.432976856515, 8925.910740892605, 9619.615215391375, 9711.660091896785, 9724.319297649168, 11653.475593598308, 11868.821967670492, 11869.050475961902, 12080.09399295052, 12139.57511487098, 10828.18788590851, 11453.427328633577, 11803.881961180365, 11863.169578129535, 11901.94049029758, 7577.355924746404, 10159.339642923187, 11409.42952852354, 11712.648620541397, 11773.764524709457, 39539.40690889639, 39838.60161398378, 39857.80071099655, 39861.00347491307, 39882.202355953006, 3412.8634854605784, 3447.052060683307, 3738.532683414573, 3939.4484772131923, 4146.771927543984, 30885.073447796378, 32389.838050809594, 32410.351792964244, 32492.6750732492, 32492.675073249437, 3317.90046298609, 3330.6669333066743, 3363.024722830376, 3384.4923261510726, 3420.6579342065843, 476805.0, 477974.0, 478596.0, 480677.0, 481113.0, 60431.395686043455, 60853.69573152106, 60872.69563652154, 60891.08663370053, 60933.69533152306, 63429.36570634327, 63571.0, 63649.0, 63733.68133159305, 63758.0436293456, 16207.756883646754, 16387.91806040986, 16399.590010249838, 16427.5893102671, 16440.6711865762, 11168.21822472419, 12704.3012634306, 12748.342715447117, 12771.10602257833, 12804.42380092898, 14615.634609134857, 14998.025128366622, 16363.918180409022, 16735.07957062349, 16793.83206167947, 6073.271207455134, 6981.232064472904, 7026.543274687129, 7035.683394247271, 7072.5402848814665, 873.7204094689638, 1046.4976811130548, 1075.424647813416, 1146.3179408252076, 1152.5217034930577, 28921.0, 29828.850855745583, 29869.70130298669, 30122.24694382614, 30617.08148755541, 1085.8208395614636, 1096.17786660005, 1118.9608363707232, 1144.5822274869613, 1160.8665003524534, 15617.531474055797, 15829.841701582836, 15830.0, 16272.837271627131, 16282.615977641795, 585142.0, 589030.0, 589303.0, 592081.0, 596974.0, 51235.975280494924, 57788.13317800236, 57971.0, 58209.12686309624, 58892.41107588955, 1386.216787515071, 1395.1001603965385, 1411.1956184974672, 1412.2091628687556, 1413.5617958432713, 25586.61620075663, 29701.257468562595, 30200.69799301979, 31765.841170794924, 31772.523412148374, 45852.541474584825, 56478.15282770679, 58034.83930321285, 58194.0, 60651.0, 511898.0, 522573.0, 640270.0, 643587.4744384112, 855248.0, 25595.488090238465, 25837.741622583533, 26142.607860882483, 26171.0, 26214.60678089792, 46459.0, 46887.765561171975, 46938.29592556046, 47592.0, 48215.0, 38814.80592597019, 39164.6083539161, 39193.608063919, 39757.40363894486, 40009.0, 37161.814190928875, 38123.046923827125, 38418.615813841505, 38867.0, 39782.00544986283, 37248.81375593105, 37676.093263885545, 37903.0, 37916.62083379241, 38150.04624884289, 32615.67384326221, 34194.82902585471, 34210.0, 34224.486632700035, 34416.65583344134, 24921.25236242843, 25116.874415627804, 25631.0, 25691.614625780254, 25931.481370372865], "value": 20256.112191879252, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.07924791780954, 381.93888977763555, 388.64050753053436, 392.3545767212935, 410.1059689876069, 15257.237138143104, 15304.693906121885, 20301.695474567856, 20573.588528229426, 20597.794022059792, 20247.0, 20424.28120126367, 20973.0, 21106.261280855175, 21375.893120534376, 5962.0, 5988.401159884021, 6169.19800425944, 6206.503479721611, 6218.378162183791, 4231.70378073535, 4465.37484752135, 5945.16767652529, 6604.405603495685, 6964.477664175181, 6633.800985970404, 6878.931210687879, 6927.9653601731925, 6948.444124470056, 6951.7834378983725, 6831.146106736633, 6858.382745552874, 6874.931250687479, 6880.862382752317, 6885.586864788103, 3769.773813571167, 5586.162075688639, 5918.348981612019, 5923.17075609415, 5982.820515384546, 24851.62722559163, 25873.353166170808, 26154.215373538635, 26305.605415918777, 26483.73516264851, 2213.0594497338566, 2254.391314345134, 2260.7174103237094, 2467.6421918821848, 2834.1922552072765, 15653.373865045385, 15898.841011589851, 16124.951878128002, 16290.185490725535, 16600.003999760054, 1870.672632289344, 1883.1714045819933, 1940.1851222486448, 1988.015932113613, 2129.5634394948997, 119464.40267798692, 123669.38165309206, 125746.0, 126208.0, 126270.36864815617, 11055.11559075272, 11435.371054592077, 11458.770824583462, 11551.306921584732, 11563.30620162793, 12283.57007504733, 12816.55142070023, 14365.281735913162, 14513.274336283144, 14514.274286285643, 7749.535027898344, 7869.842603147905, 8584.356173286904, 8611.956940215385, 8700.260477859327, 8561.272291855263, 8578.914210857936, 8602.526861022703, 8737.825243495221, 8740.737777866674, 4486.282194848874, 4580.679352445297, 4588.931166032512, 4633.189191891405, 4678.742669153229, 1591.7214487464648, 1789.4452719656688, 1802.5767854699088, 1936.8644194906503, 1978.1592823050435, 818.4475479051616, 854.1671869926772, 946.4557879219476, 973.0026722609241, 992.6624947517879, 18731.876087434797, 19549.0, 19742.81543107418, 20358.59282814365, 22041.00815463308, 801.7795106345685, 929.2380248196414, 948.2698322291797, 949.3971328206501, 955.3121752338305, 9338.467132560672, 11489.482973266224, 11595.594154204562, 11615.709607259718, 11630.302181869114, 535039.0, 588680.0565997228, 589775.0, 599569.0, 608720.0, 131199.34400327937, 131945.0, 134392.92053096057, 135056.0, 135117.0, 970.1995853420956, 982.7199248214367, 983.3165949665057, 1001.2066334925477, 1002.834532302162, 17916.731249031014, 19253.0, 19482.12330445105, 19542.729722567987, 19586.118624661638, 45459.77270113628, 52980.67548311323, 55209.89580208293, 55434.0, 56413.0, 76061.61969190119, 77815.22184778079, 78181.0, 78707.60646196733, 79027.0, 8060.193980602127, 8245.95877020611, 8256.711015114442, 8484.0, 8574.185452382013, 11893.167478276784, 12222.449989750305, 12489.063320250836, 13285.800712989507, 13594.388252528463, 11590.652280431263, 11593.0, 11607.709807254887, 11759.588414405454, 11776.0, 11463.770724585629, 11467.655970320904, 11484.827727583926, 11522.942385288019, 11821.231619944676, 10690.144788416752, 10890.29213101146, 11739.0, 11764.235324703866, 11911.463984120908, 7093.858122837618, 7527.661255243418, 7548.0, 8175.713850015459, 8446.282066024456, 8293.709720159766, 10688.091512221237, 11073.895959767618, 11833.0, 11937.940310298392], "value": 9793.916798953698, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1489.947851825186, 1504.7517159668655, 1700.5238533210697, 1748.7202047672367, 1896.7629046369204, 7277.126744790633, 8387.203215694515, 8778.473291602508, 8778.736637900858, 8803.691870784523, 7611.733589324374, 7851.0, 8224.876626850602, 8424.95787521063, 8572.485650860937, 2312.8265380096477, 2344.308429013445, 2345.9560495579476, 2350.424146084203, 2351.0243249051623, 2293.552757212344, 2304.066852924568, 2354.505553833697, 2354.517323948595, 2363.598188307983, 2066.2664754012335, 2136.626090434168, 2137.732783402075, 2138.871667699935, 2143.228437762404, 2258.7402448718362, 2325.546518428907, 2327.499587588668, 2341.9648705269433, 2342.063174730122, 2316.490372118148, 2328.6157783965627, 2342.636891281854, 2346.77705617966, 2346.97653023471, 7660.540367577907, 7870.921290787076, 8356.122607126286, 8694.913050869536, 8722.694705685271, 5045.621578381636, 5227.0, 5229.0, 5229.241759944786, 5242.711650859202, 3542.8405721742324, 3555.306715190539, 3619.819009049537, 3623.6376362363817, 3624.4563315502887, 970.9320347575673, 1080.6649938519083, 1082.10726150925, 1091.0725882999466, 1098.3903933317006, 11031.724206894893, 11176.441177941151, 11252.943735281353, 11257.099432045417, 11258.887411125866, 4495.820167193341, 4496.775161241991, 4705.529447055267, 4729.337892694956, 4729.952700472952, 9736.659216927374, 9750.463724495079, 9755.073268039667, 9756.07317304855, 9757.316987810927, 5203.037438073893, 5204.869878253074, 5205.2192171173865, 5208.921866172011, 5210.97394513025, 4651.86044418668, 4757.833475828329, 4767.976160119178, 4771.141194584939, 4789.209780386197, 3729.067733066733, 3734.1971476132217, 3778.8677396290996, 3817.8091095445116, 3828.712846536521, 1232.9198602090835, 1241.9813702794465, 1243.9253644781343, 1255.4852510470664, 1258.9487777705717, 779.7855589712761, 806.5967016491754, 883.7847959056297, 960.5053397500293, 975.9072888075622, 5683.0338842396895, 5712.400197979236, 5755.424457554211, 5960.523158147338, 6016.849578760566, 842.3219308456751, 941.6280569175137, 956.3879117364892, 969.9572959069029, 1003.6687892995294, 3736.850525978985, 3833.310004199215, 3905.7851818150275, 3907.316219661548, 3911.1395492991496, 10113.5448904798, 10429.530671119817, 10584.470776461147, 10591.84112238317, 10594.470276486145, 10536.051755341903, 10546.367217966947, 10547.472626368804, 10549.20880933933, 10555.89444105549, 6450.903236451363, 6845.623490707959, 7815.413843961725, 7946.284834364935, 8117.878231826528, 5076.670016448919, 5096.388433388017, 5375.274337964324, 5808.0, 5863.296404431496, 9403.717888463358, 9696.5151742414, 9708.805823883342, 9725.6596019139, 9970.750731231778, 8593.95703021481, 9806.460644664327, 9946.900530994597, 9955.153811926068, 9981.950090249502, 7343.816404589928, 7431.516951398142, 7515.398768098648, 7523.3229009389415, 7526.32263096324, 8139.593020348841, 8145.755627331189, 8223.835523289621, 8226.424150309422, 8237.670493180445, 6057.818265452043, 7389.852202956018, 7516.135644400964, 7516.323530882247, 7556.433267505069, 7131.572105673677, 7336.339729424164, 7365.410767138509, 7369.631518424165, 7528.322450979438, 6862.828429289308, 6878.759243426456, 6906.620135892575, 6965.79102626922, 6983.44132469391, 4920.6801557898625, 4924.089043526959, 4949.529794669501, 5000.099982003128, 5004.699718016931, 4951.851444456672, 4996.100701873697, 5012.523810238022, 5024.924626130538, 5110.029094472038], "value": 4490.3030908761675, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.83713376209965, 308.82088388734536, 311.09316342860564, 314.3775324856785, 317.576035991951, 4788.569028787413, 5350.250964864912, 5512.669239845607, 5584.553235741141, 5595.49640532352, 5135.5121263480005, 5457.590680698943, 5537.750801213945, 5554.361248456429, 5560.832225232696, 3737.9065523361996, 3758.3798673218885, 3976.6619837313865, 4034.930743353014, 4090.3455447128467, 4071.3689378146287, 4150.485072948367, 4177.665786737054, 4185.100203456256, 4193.853215137471, 3529.3470707918987, 4137.793110344486, 4154.31453810121, 4164.437800896885, 4176.269152898246, 3632.291703117893, 3874.786886721229, 3946.2502124596376, 4003.83984640614, 4145.419641250227, 3135.498320268746, 3581.8896142196004, 3599.1541987632886, 3601.7658852174786, 3609.7005078171837, 4660.906781864343, 5219.817306394296, 5294.623397916531, 5334.293026231608, 5347.973260133675, 4527.592516673482, 4710.411198600175, 4777.235642297216, 4779.211430114027, 4794.304825800275, 4478.664100192498, 4667.9766601166775, 5662.094064949588, 5690.630109042899, 5739.540836733093, 3983.820728067244, 4031.4355990161403, 4038.9394159087637, 4043.4541336919433, 4052.5947405259535, 5364.597655175839, 5815.4879731270175, 6023.307319658252, 6028.246469191351, 6031.853947749957, 3861.362875125629, 4124.8556300529335, 4139.2135494256, 4161.583841615814, 4161.667066634662, 3958.6936311017294, 4140.896477588084, 4170.562090980464, 4177.665786737054, 4219.261629214875, 4205.894852628648, 4512.9774351128035, 4629.8148074077335, 4792.520747925179, 4802.399700037496, 5165.380154381498, 5496.477834605706, 5646.0, 5746.655200687972, 5823.621464604787, 1322.3123975532862, 1375.1336657905456, 1386.729587730403, 1429.4425174182077, 1444.0469290268372, 822.3133683374449, 865.2947847504264, 869.3349587565517, 881.9029906710257, 884.0584777212248, 4233.280342341809, 4493.348464472633, 5140.717260550706, 5167.302414174113, 5329.307190065267, 5035.446100928968, 5159.916417552281, 5675.5459563234845, 5736.996025695486, 5996.760129594854, 4344.869653910388, 5115.56517696, 5359.758810853521, 5363.230134055752, 5366.543843773245, 2813.1560531840364, 3052.977252620397, 3131.4363414585137, 3133.6396314423678, 3146.229173852421, 5505.944940550623, 5982.910256346158, 6122.479589234965, 6167.784127555514, 6186.659733714599, 5781.797637082682, 6021.939780602225, 6158.692065396713, 6164.660943648142, 6222.471089957405, 5686.521504408884, 6142.754289828356, 6148.938510614837, 6193.349698281706, 6220.813375598739, 5564.19319198722, 5940.762369505172, 5983.820485385271, 6010.827888561864, 6141.170941922962, 5280.86797830042, 5616.66300022, 5677.261955945701, 5931.813637272477, 5938.168656388196, 5226.451222621722, 5462.481064298885, 6172.580306529434, 6199.597026193283, 6251.6561589113035, 3699.7040236780454, 3759.172981943968, 3817.694584433294, 3934.370500719871, 3977.6134319408125, 5724.542036637143, 5969.432903874125, 6016.5487588431915, 6055.031195008777, 6090.573659843769, 5502.504774570308, 5778.393268706894, 5805.564582656233, 5832.854178645568, 5838.883222335445, 5671.0, 5696.5535850809665, 5712.485876271156, 5734.7132643368495, 5736.68448235351, 4743.0, 4826.469088400343, 4965.379327584052, 5208.921866171935, 5560.944390556043, 4862.489438609036, 5283.260343551829, 5404.783808647768, 5570.24801651788, 5594.804181853616, 5495.972520137374, 5553.555715542828, 5576.302962129734, 5595.188697638901, 5624.353199382123], "value": 4197.917620849496, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.4337358426468, 704.5138854190608, 750.639692947385, 789.2975252025698, 818.161384580805, 15156.848431515682, 16271.186440677948, 16282.592935176626, 16298.429554965578, 16314.918425407872, 14589.124652520855, 14694.632634184123, 14731.558053258392, 14763.188024658639, 14766.926165369185, 682.3415404135009, 700.8318003679132, 828.7555171224503, 832.3507664022072, 849.2738708404318, 849.1890244816186, 858.8582883824162, 859.669027424443, 859.7205908079899, 860.1054902900976, 900.2392977933636, 914.7804526913559, 915.2997956563235, 916.564631799893, 916.7983043730369, 711.6761873347666, 745.8508298340299, 748.4050180106829, 915.7802127489388, 917.7384445433053, 544.727636181909, 545.4463719324889, 634.8571571396399, 667.552739664426, 670.4904272752694, 13916.652083697887, 14138.717225655531, 14175.36210870503, 14261.572152835432, 14301.856981430157, 13899.722005559934, 14122.646933826634, 14134.293285335692, 14171.716565668732, 14202.147871127765, 13071.542496012595, 13129.540466083738, 13145.671358216121, 13159.210447373092, 13321.800172997317, 4659.091477161955, 4667.089917466095, 4711.104890070876, 4861.756912154378, 4883.438404583448, 105171.47414262957, 106246.93753062525, 109685.0, 109759.0, 111860.44069779599, 11014.779704405866, 11137.276077054965, 11183.384913829817, 11192.552297908156, 11294.0, 21604.56790864174, 22464.0, 22606.982685779178, 22621.88689056544, 22626.094956201567, 8323.791905202419, 8329.916700833035, 8349.791255218668, 8385.245327920517, 8509.702160424355, 4323.329883868006, 4380.214097480421, 4476.955230447719, 4520.683552151318, 4602.1025899949855, 3547.2728090741334, 3665.578458477256, 3668.1013151777984, 3670.871519496805, 3673.045008297808, 646.9805905822833, 661.4212564006494, 668.4719071933118, 669.7454967112466, 672.1832972937904, 541.1557969567488, 551.9779208831602, 553.9113741801373, 613.5152038597864, 660.6529038923869, 8491.19333663301, 9402.38884472507, 9613.567389467351, 9651.469169195761, 9736.805263894825, 642.3544337940433, 660.8513084555937, 662.1292999705372, 669.9129113215348, 677.6239187251123, 3601.9099522512147, 4410.514843367227, 4533.478649955231, 4543.091381723669, 4624.075184962953, 130693.34653326673, 130762.0, 133143.0, 133635.0, 134315.0, 80507.7923831143, 98273.0, 116347.0, 116532.0, 119731.40134299448, 105014.0, 106015.0, 106471.0, 107012.0, 107113.0, 7466.136770998293, 8379.748607541784, 8463.66145354179, 8622.655093796178, 8820.426672266281, 52329.21506177486, 52713.945721084594, 55070.0, 59026.409735903806, 60558.69720651369, 74064.25935740574, 86718.83202919978, 86904.0, 87545.0, 87799.24401511806, 11249.606263780728, 13265.535706250235, 13673.179609223478, 13866.306684665524, 13867.0, 15143.318550665466, 15151.393944242107, 15415.768763468333, 15617.531474055797, 15861.365545378056, 12906.67733306675, 14298.57104286873, 14362.779163771196, 14439.061461005002, 14754.336054877342, 13574.79637805414, 13610.115342502706, 13840.584782456543, 14026.368813403626, 14688.118712877262, 13853.37659805291, 13978.580642580739, 13994.230317332238, 14032.649183770487, 14038.859611403755, 11130.554777808797, 11316.660500185008, 11624.6512604622, 11667.299962002307, 11676.88323116792, 10270.897291027293, 11182.440877956233, 11484.0, 11577.710557236136, 11819.822702659296], "value": 7768.650373029234, "unit": "score"}], "name": "dromaeo_css", "value": 7413.352920201098, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 114.0, 112.0, 114.0, 111.0, 113.0, 113.0, 112.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 108.0, 107.0, 160.0, 106.0, 116.0, 148.0, 125.0, 165.0, 105.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 188.0, 185.0, 195.0, 252.0, 193.0, 184.0, 183.0, 182.0], "value": 193.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 58.0, 107.0, 57.0, 57.0, 62.0, 80.0, 71.0, 57.0], "value": 70.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 94.0, 69.0, 70.0, 70.0, 93.0, 71.0, 68.0, 101.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 89.0, 85.0, 87.0, 83.0, 87.0, 85.0, 88.0, 121.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 108.0, 139.0, 109.0, 93.0, 93.0, 97.0, 94.0, 93.0, 93.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 87.0, 84.0, 87.0, 83.0, 87.0, 95.0, 137.0], "value": 91.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 53.0, 55.0, 55.0, 55.0, 59.0, 54.0, 55.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 76.0, 79.0, 72.0, 71.0, 71.0, 72.0, 70.0, 72.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 81.0, 80.0, 95.0, 95.0, 92.0, 93.0, 81.0, 79.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 138.0, 135.0, 136.0, 151.0, 124.0, 143.0, 237.0, 145.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 172.0, 160.0, 167.0, 162.0, 167.0, 166.0, 164.0, 166.0, 163.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 65.0, 65.0, 67.0, 66.0, 67.0, 68.0, 72.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1460.8999999999999, "alertThreshold": 2.0}]} 19:55:26 INFO - Return code: 0 19:55:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:55:26 INFO - # TBPL SUCCESS # 19:55:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:55:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:55:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:55:27 INFO - 2016-06-29 19:55:27.001 screenresolution[1663:10244] starting screenresolution argv=screenresolution get 19:55:27 INFO - 2016-06-29 19:55:27.004 screenresolution[1663:10244] Display 0: 1600x1200x32@60 19:55:27 INFO - 2016-06-29 19:55:27.010 screenresolution[1664:10249] starting screenresolution argv=screenresolution list 19:55:27 INFO - Available Modes on Display 0 19:55:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:55:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:55:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:55:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:55:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:55:27 INFO - Intel Iris: 19:55:27 INFO - Chipset Model: Intel Iris 19:55:27 INFO - Type: GPU 19:55:27 INFO - Bus: Built-In 19:55:27 INFO - VRAM (Dynamic, Max): 1536 MB 19:55:27 INFO - Vendor: Intel (0x8086) 19:55:27 INFO - Device ID: 0x0a2e 19:55:27 INFO - Revision ID: 0x0009 19:55:27 INFO - Displays: 19:55:27 INFO - Display: 19:55:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:55:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:55:27 INFO - Display Serial Number: 1600x1200 60 19:55:27 INFO - Main Display: Yes 19:55:27 INFO - Mirror: Off 19:55:27 INFO - Online: Yes 19:55:27 INFO - Rotation: Supported 19:55:27 INFO - Return code: 0 19:55:27 INFO - Running post-action listener: _resource_record_post_action 19:55:27 INFO - ##### 19:55:27 INFO - ##### Finished run-tests step (success) 19:55:27 INFO - ##### 19:55:27 INFO - Running post-run listener: _resource_record_post_run 19:55:27 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 32681472; Write bytes: 705670656; Read time: 320; Write time: 2055 19:55:27 INFO - TinderboxPrint: CPU usage
53.6% 19:55:27 INFO - TinderboxPrint: I/O read bytes / time
32,681,472 / 320 19:55:27 INFO - TinderboxPrint: I/O write bytes / time
705,670,656 / 2,055 19:55:27 INFO - TinderboxPrint: CPU idle
2,245.5 (46.4%) 19:55:27 INFO - TinderboxPrint: CPU system
961.8 (19.9%) 19:55:27 INFO - TinderboxPrint: CPU user
1,630.3 (33.7%) 19:55:27 INFO - TinderboxPrint: Swap in / out
436,015,104 / 0 19:55:27 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390130176; Write bytes: 385029632; Read time: 29221; Write time: 764 19:55:27 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 31759872; Write bytes: 295352320; Read time: 307; Write time: 1242 19:55:27 INFO - Running post-run listener: _upload_blobber_files 19:55:27 INFO - Blob upload gear active. 19:55:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:55:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:55:27 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'] 19:55:27 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 19:55:28 INFO - (blobuploader) - INFO - Open directory for files ... 19:55:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:55:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:55:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:55:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:55:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:55:29 INFO - (blobuploader) - INFO - Done attempting. 19:55:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:55:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:55:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:55:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:55:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:55:30 INFO - (blobuploader) - INFO - Done attempting. 19:55:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:55:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:55:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:55:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:55:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:55:32 INFO - (blobuploader) - INFO - Done attempting. 19:55:32 INFO - (blobuploader) - INFO - Iteration through files over. 19:55:32 INFO - Return code: 0 19:55:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:55:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:55:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b3d377accfbf5ae475fb44002586d2b79ecc044607b3e36cc6e2c8515a1002dce810d36a74b48f1b26ca840676bb4685ab5451a4bd80a76f41ca2e372a3c03bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8b83323525521020da61d47d5e6e586d92d65fee59acf9565a5cbd1232ca42e698d98179e8d1ec9803644d7e45e2989249a4cbdb4e4f5f49ccce6e7f7b6a5f4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c11fcd40e6379788d160f5387120c3d52d1e55f9bebd854df9f8dd36bd7cad8bb87f2681c5138c24720548540baf15545ecb51124f86695bff05681f8c6a53b4"} 19:55:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:55:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:55:32 INFO - Contents: 19:55:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b3d377accfbf5ae475fb44002586d2b79ecc044607b3e36cc6e2c8515a1002dce810d36a74b48f1b26ca840676bb4685ab5451a4bd80a76f41ca2e372a3c03bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8b83323525521020da61d47d5e6e586d92d65fee59acf9565a5cbd1232ca42e698d98179e8d1ec9803644d7e45e2989249a4cbdb4e4f5f49ccce6e7f7b6a5f4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c11fcd40e6379788d160f5387120c3d52d1e55f9bebd854df9f8dd36bd7cad8bb87f2681c5138c24720548540baf15545ecb51124f86695bff05681f8c6a53b4"} 19:55:32 INFO - Running post-run listener: copy_logs_to_upload_dir 19:55:32 INFO - Copying logs to upload dir... 19:55:32 INFO - mkdir: /builds/slave/test/build/upload/logs 19:55:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.435901 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-06-29 19:55:32.622931) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 19:55:32.626274) ========= 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.AWnZKQNJxh/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.1ShII1b3TD/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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/b3d377accfbf5ae475fb44002586d2b79ecc044607b3e36cc6e2c8515a1002dce810d36a74b48f1b26ca840676bb4685ab5451a4bd80a76f41ca2e372a3c03bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8b83323525521020da61d47d5e6e586d92d65fee59acf9565a5cbd1232ca42e698d98179e8d1ec9803644d7e45e2989249a4cbdb4e4f5f49ccce6e7f7b6a5f4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c11fcd40e6379788d160f5387120c3d52d1e55f9bebd854df9f8dd36bd7cad8bb87f2681c5138c24720548540baf15545ecb51124f86695bff05681f8c6a53b4"} build_url:https://queue.taskcluster.net/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/FZWJZsy1S7u1deICNuuL-A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b3d377accfbf5ae475fb44002586d2b79ecc044607b3e36cc6e2c8515a1002dce810d36a74b48f1b26ca840676bb4685ab5451a4bd80a76f41ca2e372a3c03bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8b83323525521020da61d47d5e6e586d92d65fee59acf9565a5cbd1232ca42e698d98179e8d1ec9803644d7e45e2989249a4cbdb4e4f5f49ccce6e7f7b6a5f4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c11fcd40e6379788d160f5387120c3d52d1e55f9bebd854df9f8dd36bd7cad8bb87f2681c5138c24720548540baf15545ecb51124f86695bff05681f8c6a53b4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 19:55:32.660237) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 19:55:32.660612) ========= 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.AWnZKQNJxh/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.1ShII1b3TD/Listeners TMPDIR=/var/folders/lc/vwq10pn53dlcxm0t40pjj8vm00000w/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.005133 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 19:55:32.710201) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 19:55:32.710563) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 19:55:32.710948) ========= ========= Total master_lag: 0.18 =========