builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0361 starttime: 1468449008.68 results: success (0) buildid: 20160713131643 builduid: 4c475231baba472e9b4a877109b8bd5d revision: bb5d09eec5eee960351a3a87aa30bbe87c25298d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 15:30:08.684206) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 15:30:08.684851) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 15:30:08.685301) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 15:30:08.710319) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 15:30:08.710729) ========= 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.GyEWuI71Ix/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.XH5EovbzYO/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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-07-13 15:30:08-- 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% 59.4M=0s 2016-07-13 15:30:08 (59.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.170192 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 15:30:08.903336) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 15:30:08.903713) ========= 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.GyEWuI71Ix/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.XH5EovbzYO/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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.026051 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 15:30:08.954783) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 15:30:08.955313) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev bb5d09eec5eee960351a3a87aa30bbe87c25298d --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 bb5d09eec5eee960351a3a87aa30bbe87c25298d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GyEWuI71Ix/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.XH5EovbzYO/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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-07-13 15:30:09,038 truncating revision to first 12 chars 2016-07-13 15:30:09,038 Setting DEBUG logging. 2016-07-13 15:30:09,038 attempt 1/10 2016-07-13 15:30:09,038 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/bb5d09eec5ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 15:30:09,778 unpacking tar archive at: autoland-bb5d09eec5ee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.006629 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 15:30:09.986692) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 15:30:09.987305) ========= script_repo_revision: bb5d09eec5eee960351a3a87aa30bbe87c25298d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 15:30:09.987850) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 15:30:09.988349) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 15:30:10.009371) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-13 15:30:10.009973) ========= /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.GyEWuI71Ix/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.XH5EovbzYO/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 15:30:10 INFO - MultiFileLogger online at 20160713 15:30:10 in /builds/slave/test 15:30:10 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 15:30:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:30:10 INFO - {'append_to_log': False, 15:30:10 INFO - 'base_work_dir': '/builds/slave/test', 15:30:10 INFO - 'blob_upload_branch': 'Autoland', 15:30:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:30:10 INFO - 'branch': 'Autoland', 15:30:10 INFO - 'buildbot_json_path': 'buildprops.json', 15:30:10 INFO - 'config_files': ('talos/mac_config.py',), 15:30:10 INFO - 'default_actions': ('clobber', 15:30:10 INFO - 'read-buildbot-config', 15:30:10 INFO - 'download-and-extract', 15:30:10 INFO - 'populate-webroot', 15:30:10 INFO - 'create-virtualenv', 15:30:10 INFO - 'install', 15:30:10 INFO - 'run-tests'), 15:30:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:30:10 INFO - 'download_minidump_stackwalk': True, 15:30:10 INFO - 'download_symbols': 'ondemand', 15:30:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:30:10 INFO - 'tooltool.py': '/tools/tooltool.py', 15:30:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:30:10 INFO - '/tools/misc-python/virtualenv.py')}, 15:30:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:30:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:30:10 INFO - 'installer_path': 'installer.exe', 15:30:10 INFO - 'log_level': 'info', 15:30:10 INFO - 'log_name': 'talos', 15:30:10 INFO - 'log_to_console': True, 15:30:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:30:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:30:10 INFO - 'opt_config_files': (), 15:30:10 INFO - 'pip_index': False, 15:30:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:30:10 INFO - 'cmd': ('bash', 15:30:10 INFO - '-c', 15:30:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:30:10 INFO - 'enabled': True, 15:30:10 INFO - 'halt_on_failure': False, 15:30:10 INFO - 'name': 'check_screen_resolution'},), 15:30:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:30:10 INFO - 'cmd': ('bash', 15:30:10 INFO - '-c', 15:30:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:30:10 INFO - 'enabled': True, 15:30:10 INFO - 'halt_on_failure': False, 15:30:10 INFO - 'name': 'check_screen_resolution'},), 15:30:10 INFO - 'run_cmd_checks_enabled': True, 15:30:10 INFO - 'sps_profile': False, 15:30:10 INFO - 'sps_profile_interval': 0, 15:30:10 INFO - 'suite': 'dromaeojs', 15:30:10 INFO - 'system_bits': '32', 15:30:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:30:10 INFO - 'title': 't-yosemite-r7-0361', 15:30:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:30:10 INFO - 'use_talos_json': True, 15:30:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:30:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:30:10 INFO - 'work_dir': 'build'} 15:30:10 INFO - ##### 15:30:10 INFO - ##### Running clobber step. 15:30:10 INFO - ##### 15:30:10 INFO - Running pre-action listener: _resource_record_pre_action 15:30:10 INFO - Running main action method: clobber 15:30:10 INFO - rmtree: /builds/slave/test/build 15:30:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:30:12 INFO - Running post-action listener: _resource_record_post_action 15:30:12 INFO - ##### 15:30:12 INFO - ##### Finished clobber step (success) 15:30:12 INFO - ##### 15:30:12 INFO - ##### 15:30:12 INFO - ##### Running read-buildbot-config step. 15:30:12 INFO - ##### 15:30:12 INFO - Running pre-action listener: _resource_record_pre_action 15:30:12 INFO - Running main action method: read_buildbot_config 15:30:12 INFO - Using buildbot properties: 15:30:12 INFO - { 15:30:12 INFO - "project": "", 15:30:12 INFO - "product": "firefox", 15:30:12 INFO - "script_repo_revision": "production", 15:30:12 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 15:30:12 INFO - "repository": "", 15:30:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 15:30:12 INFO - "buildid": "20160713131643", 15:30:12 INFO - "pgo_build": "False", 15:30:12 INFO - "basedir": "/builds/slave/test", 15:30:12 INFO - "buildnumber": 14, 15:30:12 INFO - "slavename": "t-yosemite-r7-0361", 15:30:12 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:30:12 INFO - "platform": "yosemite_r7", 15:30:12 INFO - "scheduler": "tests-autoland-macosx64-talos", 15:30:12 INFO - "branch": "autoland", 15:30:12 INFO - "repo_path": "integration/autoland", 15:30:12 INFO - "revision": "bb5d09eec5eee960351a3a87aa30bbe87c25298d", 15:30:12 INFO - "stage_platform": "macosx64", 15:30:12 INFO - "builduid": "4c475231baba472e9b4a877109b8bd5d", 15:30:12 INFO - "slavebuilddir": "test" 15:30:12 INFO - } 15:30:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 15:30:12 INFO - Running post-action listener: _resource_record_post_action 15:30:12 INFO - ##### 15:30:12 INFO - ##### Finished read-buildbot-config step (success) 15:30:12 INFO - ##### 15:30:12 INFO - ##### 15:30:12 INFO - ##### Running download-and-extract step. 15:30:12 INFO - ##### 15:30:12 INFO - Running pre-action listener: _resource_record_pre_action 15:30:12 INFO - Running main action method: download_and_extract 15:30:12 INFO - mkdir: /builds/slave/test/build/tests 15:30:12 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')]} 15:30:12 INFO - https://queue.taskcluster.net/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:30:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 15:30:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 15:30:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/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 15:30:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/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 15:30:13 INFO - Downloaded 1199 bytes. 15:30:13 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 15:30:13 INFO - Using the following test package requirements: 15:30:13 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 15:30:13 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:30:13 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 15:30:13 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:30:13 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 15:30:13 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:30:13 INFO - u'jsshell-mac.zip'], 15:30:13 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:30:13 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 15:30:13 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 15:30:13 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:30:13 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 15:30:13 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:30:13 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 15:30:13 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:30:13 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 15:30:13 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:30:13 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 15:30:13 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:30:13 INFO - https://queue.taskcluster.net/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:30:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:30:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:30:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/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 15:30:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/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 15:30:14 INFO - Downloaded 18822892 bytes. 15:30:14 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'] 15:30:14 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 15:30:18 INFO - Return code: 0 15:30:18 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 15:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:30:18 INFO - https://queue.taskcluster.net/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:30:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:30:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:30:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/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 15:30:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/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 15:30:19 INFO - Downloaded 18822892 bytes. 15:30:19 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'] 15:30:19 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 15:30:23 INFO - Return code: 0 15:30:23 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')]} 15:30:23 INFO - https://queue.taskcluster.net/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:30:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 15:30:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 15:30:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/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 15:30:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/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 15:30:24 INFO - Downloaded 14403317 bytes. 15:30:24 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'] 15:30:24 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 15:30:24 INFO - Return code: 0 15:30: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')]} 15:30:24 INFO - https://queue.taskcluster.net/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:30:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 15:30:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 15:30:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 15:30:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AP3KEPuzT26gqxI-1gLeNw/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 15:30:27 INFO - Downloaded 112791610 bytes. 15:30:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 15:30:27 INFO - mkdir: /builds/slave/test/properties 15:30:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:30:27 INFO - Writing to file /builds/slave/test/properties/build_url 15:30:27 INFO - Contents: 15:30:27 INFO - build_url:https://queue.taskcluster.net/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 15:30:29 INFO - Running post-action listener: _resource_record_post_action 15:30:29 INFO - Running post-action listener: set_extra_try_arguments 15:30:29 INFO - ##### 15:30:29 INFO - ##### Finished download-and-extract step (success) 15:30:29 INFO - ##### 15:30:29 INFO - ##### 15:30:29 INFO - ##### Running populate-webroot step. 15:30:29 INFO - ##### 15:30:29 INFO - Running pre-action listener: _resource_record_pre_action 15:30:29 INFO - Running main action method: populate_webroot 15:30:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:30:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:30:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:30:29 INFO - '--tppagecycles', 15:30:29 INFO - '7'], 15:30:29 INFO - 'tests': ['tsvgm']}}, 15:30:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:30:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:30:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:30:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:30:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:30:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:30:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:29 INFO - 'talos_options': ['--disable-e10s'], 15:30:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:30:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:30:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:29 INFO - 'talos_options': ['--disable-e10s'], 15:30:29 INFO - 'tests': ['damp', 'tps']}, 15:30:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:29 INFO - 'tests': ['damp', 'tps']}, 15:30:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:30:29 INFO - 'tests': ['dromaeo_dom']}, 15:30:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:30:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:30:29 INFO - 'tests': ['basic_compositor_video']}, 15:30:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:30:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:30:29 INFO - 'tests': ['a11yr', 15:30:29 INFO - 'ts_paint', 15:30:29 INFO - 'tpaint', 15:30:29 INFO - 'sessionrestore', 15:30:29 INFO - 'sessionrestore_no_auto_restore', 15:30:29 INFO - 'tabpaint']}, 15:30:29 INFO - 'other-e10s': {'tests': ['a11yr', 15:30:29 INFO - 'ts_paint', 15:30:29 INFO - 'tpaint', 15:30:29 INFO - 'sessionrestore', 15:30:29 INFO - 'sessionrestore_no_auto_restore', 15:30:29 INFO - 'tabpaint']}, 15:30:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:30:29 INFO - 'ts_paint', 15:30:29 INFO - 'tpaint', 15:30:29 INFO - 'sessionrestore', 15:30:29 INFO - 'sessionrestore_no_auto_restore', 15:30:29 INFO - 'tabpaint']}, 15:30:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:30:29 INFO - 'ts_paint', 15:30:29 INFO - 'tpaint', 15:30:29 INFO - 'sessionrestore', 15:30:29 INFO - 'sessionrestore_no_auto_restore', 15:30:29 INFO - 'tabpaint']}, 15:30:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:30:29 INFO - 'tests': ['a11yr', 15:30:29 INFO - 'ts_paint', 15:30:29 INFO - 'tpaint', 15:30:29 INFO - 'sessionrestore', 15:30:29 INFO - 'sessionrestore_no_auto_restore', 15:30:29 INFO - 'tabpaint']}, 15:30:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:30:29 INFO - 'tests': ['a11yr', 15:30:29 INFO - 'ts_paint', 15:30:29 INFO - 'tpaint', 15:30:29 INFO - 'sessionrestore', 15:30:29 INFO - 'sessionrestore_no_auto_restore', 15:30:29 INFO - 'tabpaint']}, 15:30:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:30:29 INFO - 'tests': ['tsvgx', 15:30:29 INFO - 'tsvgr_opacity', 15:30:29 INFO - 'tart', 15:30:29 INFO - 'tscrollx', 15:30:29 INFO - 'cart']}, 15:30:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:30:29 INFO - 'tsvgr_opacity', 15:30:29 INFO - 'tart', 15:30:29 INFO - 'tscrollx', 15:30:29 INFO - 'cart']}, 15:30:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:29 INFO - 'talos_options': ['--disable-e10s'], 15:30:29 INFO - 'tests': ['tp5o']}, 15:30:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:29 INFO - 'tests': ['tp5o']}, 15:30:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:29 INFO - 'talos_options': ['--disable-e10s', 15:30:29 INFO - '--xperf_path', 15:30:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:30:29 INFO - 'tests': ['tp5n']}, 15:30:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:30:29 INFO - 'talos_options': ['--xperf_path', 15:30:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:30:29 INFO - 'tests': ['tp5n']}}, 15:30:29 INFO - 'talos.zip': {'path': '', 15:30:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:30:29 INFO - Running post-action listener: _resource_record_post_action 15:30:29 INFO - ##### 15:30:29 INFO - ##### Finished populate-webroot step (success) 15:30:29 INFO - ##### 15:30:29 INFO - ##### 15:30:29 INFO - ##### Running create-virtualenv step. 15:30:29 INFO - ##### 15:30:29 INFO - Running pre-action listener: _resource_record_pre_action 15:30:29 INFO - Running main action method: create_virtualenv 15:30:29 INFO - Creating virtualenv /builds/slave/test/build/venv 15:30:29 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 15:30:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:30:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:30:29 INFO - Using real prefix '/tools/python27' 15:30:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:30:29 INFO - Installing distribute.............................................................................................................................................................................................done. 15:30:32 INFO - Installing pip.................done. 15:30:32 INFO - Return code: 0 15:30:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:32 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 0x106e69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f804bc9a0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcdd30>, '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 0x106b32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc5b20>, '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.GyEWuI71Ix/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.XH5EovbzYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 15:30:32 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 15:30:32 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 15:30:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GyEWuI71Ix/Render', 15:30:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:32 INFO - 'HOME': '/Users/cltbld', 15:30:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:32 INFO - 'LOGNAME': 'cltbld', 15:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:32 INFO - 'MOZ_NO_REMOTE': '1', 15:30:32 INFO - 'NO_EM_RESTART': '1', 15:30:32 INFO - 'PAGER': '/bin/cat', 15:30:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:32 INFO - 'PWD': '/builds/slave/test', 15:30:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:32 INFO - 'SHELL': '/bin/bash', 15:30:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XH5EovbzYO/Listeners', 15:30:32 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 15:30:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:32 INFO - 'USER': 'cltbld', 15:30:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:32 INFO - 'XPC_FLAGS': '0x0', 15:30:32 INFO - 'XPC_SERVICE_NAME': '0', 15:30:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:33 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 15:30:33 INFO - Cleaning up... 15:30:33 INFO - Return code: 0 15:30:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:30:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:33 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 0x106e69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f804bc9a0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcdd30>, '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 0x106b32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc5b20>, '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.GyEWuI71Ix/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.XH5EovbzYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 15:30:33 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 15:30:33 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 15:30:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GyEWuI71Ix/Render', 15:30:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:33 INFO - 'HOME': '/Users/cltbld', 15:30:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:33 INFO - 'LOGNAME': 'cltbld', 15:30:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:33 INFO - 'MOZ_NO_REMOTE': '1', 15:30:33 INFO - 'NO_EM_RESTART': '1', 15:30:33 INFO - 'PAGER': '/bin/cat', 15:30:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:33 INFO - 'PWD': '/builds/slave/test', 15:30:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:33 INFO - 'SHELL': '/bin/bash', 15:30:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XH5EovbzYO/Listeners', 15:30:33 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 15:30:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:33 INFO - 'USER': 'cltbld', 15:30:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:33 INFO - 'XPC_FLAGS': '0x0', 15:30:33 INFO - 'XPC_SERVICE_NAME': '0', 15:30:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:33 INFO - Downloading/unpacking psutil>=3.1.1 15:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:30:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:30:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:30:35 INFO - Installing collected packages: psutil 15:30:35 INFO - Running setup.py install for psutil 15:30:35 INFO - building 'psutil._psutil_osx' extension 15:30:35 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 15:30:36 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 15:30:36 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 15:30:36 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 15:30:36 INFO - building 'psutil._psutil_posix' extension 15:30:36 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 15:30:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:30:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:30:36 INFO - ^ 15:30:36 INFO - 1 warning generated. 15:30:36 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 15:30:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:30:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:30:37 INFO - Successfully installed psutil 15:30:37 INFO - Cleaning up... 15:30:37 INFO - Return code: 0 15:30:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:30:37 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')]} 15:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:37 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')]} 15:30:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:37 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 0x106e69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f804bc9a0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcdd30>, '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 0x106b32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc5b20>, '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.GyEWuI71Ix/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.XH5EovbzYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 15:30:37 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 15:30:37 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 15:30:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GyEWuI71Ix/Render', 15:30:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:37 INFO - 'HOME': '/Users/cltbld', 15:30:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:37 INFO - 'LOGNAME': 'cltbld', 15:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:37 INFO - 'MOZ_NO_REMOTE': '1', 15:30:37 INFO - 'NO_EM_RESTART': '1', 15:30:37 INFO - 'PAGER': '/bin/cat', 15:30:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:37 INFO - 'PWD': '/builds/slave/test', 15:30:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:37 INFO - 'SHELL': '/bin/bash', 15:30:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XH5EovbzYO/Listeners', 15:30:37 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 15:30:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:37 INFO - 'USER': 'cltbld', 15:30:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:37 INFO - 'XPC_FLAGS': '0x0', 15:30:37 INFO - 'XPC_SERVICE_NAME': '0', 15:30:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:30:37 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 15:30:37 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 15:30:37 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 15:30:37 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 15:30:37 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 15:30:37 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 15:30:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:30:39 INFO - Installing collected packages: mozsystemmonitor 15:30:39 INFO - Running setup.py install for mozsystemmonitor 15:30:40 INFO - Successfully installed mozsystemmonitor 15:30:40 INFO - Cleaning up... 15:30:40 INFO - Return code: 0 15:30:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:30:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:40 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 0x106e69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f804bc9a0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcdd30>, '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 0x106b32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc5b20>, '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.GyEWuI71Ix/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.XH5EovbzYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 15:30:40 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 15:30:40 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 15:30:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GyEWuI71Ix/Render', 15:30:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:40 INFO - 'HOME': '/Users/cltbld', 15:30:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:40 INFO - 'LOGNAME': 'cltbld', 15:30:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:40 INFO - 'MOZ_NO_REMOTE': '1', 15:30:40 INFO - 'NO_EM_RESTART': '1', 15:30:40 INFO - 'PAGER': '/bin/cat', 15:30:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:40 INFO - 'PWD': '/builds/slave/test', 15:30:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:40 INFO - 'SHELL': '/bin/bash', 15:30:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XH5EovbzYO/Listeners', 15:30:40 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 15:30:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:40 INFO - 'USER': 'cltbld', 15:30:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:40 INFO - 'XPC_FLAGS': '0x0', 15:30:40 INFO - 'XPC_SERVICE_NAME': '0', 15:30:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:40 INFO - Downloading/unpacking jsonschema==2.5.1 15:30:40 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 15:30:40 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 15:30:40 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 15:30:40 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 15:30:40 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 15:30:40 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 15:30:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:30:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:30:42 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 15:30:42 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 15:30:42 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 15:30:42 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 15:30:42 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 15:30:42 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 15:30:43 INFO - Downloading functools32-3.2.3-2.tar.gz 15:30:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:30:43 INFO - warning: no files found matching '*.txt' 15:30:43 INFO - no previously-included directories found matching 'build' 15:30:43 INFO - no previously-included directories found matching 'dist' 15:30:43 INFO - no previously-included directories found matching '.git*' 15:30:43 INFO - Installing collected packages: jsonschema, functools32 15:30:43 INFO - Running setup.py install for functools32 15:30:43 INFO - warning: no files found matching '*.txt' 15:30:43 INFO - no previously-included directories found matching 'build' 15:30:43 INFO - no previously-included directories found matching 'dist' 15:30:43 INFO - no previously-included directories found matching '.git*' 15:30:43 INFO - Successfully installed jsonschema functools32 15:30:43 INFO - Cleaning up... 15:30:43 INFO - Return code: 0 15:30:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:43 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 0x106e69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f804bc9a0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcdd30>, '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 0x106b32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc5b20>, '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.GyEWuI71Ix/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.XH5EovbzYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 15:30:43 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 15:30:43 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 15:30:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GyEWuI71Ix/Render', 15:30:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:43 INFO - 'HOME': '/Users/cltbld', 15:30:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:43 INFO - 'LOGNAME': 'cltbld', 15:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:43 INFO - 'MOZ_NO_REMOTE': '1', 15:30:43 INFO - 'NO_EM_RESTART': '1', 15:30:43 INFO - 'PAGER': '/bin/cat', 15:30:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:43 INFO - 'PWD': '/builds/slave/test', 15:30:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:43 INFO - 'SHELL': '/bin/bash', 15:30:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XH5EovbzYO/Listeners', 15:30:43 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 15:30:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:43 INFO - 'USER': 'cltbld', 15:30:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:43 INFO - 'XPC_FLAGS': '0x0', 15:30:43 INFO - 'XPC_SERVICE_NAME': '0', 15:30:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:43 INFO - Downloading/unpacking blobuploader==1.2.4 15:30:43 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 15:30:43 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 15:30:43 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 15:30:43 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 15:30:43 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 15:30:43 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 15:30:46 INFO - Downloading blobuploader-1.2.4.tar.gz 15:30:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:30:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:30:46 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 15:30:46 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 15:30:46 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 15:30:46 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 15:30:46 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 15:30:46 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 15:30:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:30:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:30:47 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 15:30:47 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 15:30:47 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 15:30:47 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 15:30:47 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 15:30:47 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 15:30:47 INFO - Downloading docopt-0.6.1.tar.gz 15:30:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:30:47 INFO - Installing collected packages: blobuploader, requests, docopt 15:30:47 INFO - Running setup.py install for blobuploader 15:30:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:30:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:30:47 INFO - Running setup.py install for requests 15:30:48 INFO - Running setup.py install for docopt 15:30:48 INFO - Successfully installed blobuploader requests docopt 15:30:48 INFO - Cleaning up... 15:30:48 INFO - Return code: 0 15:30:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30:48 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')]} 15:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:48 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')]} 15:30:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:48 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 0x106e69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f804bc9a0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcdd30>, '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 0x106b32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc5b20>, '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.GyEWuI71Ix/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.XH5EovbzYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 15:30:48 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 15:30:48 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 15:30:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GyEWuI71Ix/Render', 15:30:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:48 INFO - 'HOME': '/Users/cltbld', 15:30:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:48 INFO - 'LOGNAME': 'cltbld', 15:30:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:48 INFO - 'MOZ_NO_REMOTE': '1', 15:30:48 INFO - 'NO_EM_RESTART': '1', 15:30:48 INFO - 'PAGER': '/bin/cat', 15:30:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:48 INFO - 'PWD': '/builds/slave/test', 15:30:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:48 INFO - 'SHELL': '/bin/bash', 15:30:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XH5EovbzYO/Listeners', 15:30:48 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 15:30:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:48 INFO - 'USER': 'cltbld', 15:30:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:48 INFO - 'XPC_FLAGS': '0x0', 15:30:48 INFO - 'XPC_SERVICE_NAME': '0', 15:30:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:30:48 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-CaYXch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:30:48 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-3Ijzc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:30:48 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-tj3oRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:30:49 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-tapi1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:30:49 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-wKmqW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:30:49 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-RX7tut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:30:49 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-9zEXlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:30:49 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-igAqGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:30:49 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-AMrZzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:30:49 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-nrm6Q4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:30:49 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-d_Iho7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:30:49 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-GldNRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:30:49 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-pWL2_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:30:50 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-_hwk2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:50 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-g_CdLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:30:50 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-27vblS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:30:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:30:50 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-77uMuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:30:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:30:50 INFO - Running setup.py install for manifestparser 15:30:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:30:50 INFO - Running setup.py install for mozcrash 15:30:50 INFO - Running setup.py install for mozdebug 15:30:50 INFO - Running setup.py install for mozdevice 15:30:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:30:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:30:51 INFO - Running setup.py install for mozfile 15:30:51 INFO - Running setup.py install for mozhttpd 15:30:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:30:51 INFO - Running setup.py install for mozinfo 15:30:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:30:51 INFO - Running setup.py install for mozInstall 15:30:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:30:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:30:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:30:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:30:51 INFO - Running setup.py install for mozleak 15:30:51 INFO - Running setup.py install for mozlog 15:30:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:30:52 INFO - Running setup.py install for moznetwork 15:30:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:30:52 INFO - Running setup.py install for mozprocess 15:30:52 INFO - Running setup.py install for mozprofile 15:30:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:30:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:30:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:30:52 INFO - Running setup.py install for mozrunner 15:30:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:30:52 INFO - Running setup.py install for mozscreenshot 15:30:52 INFO - Running setup.py install for moztest 15:30:52 INFO - Running setup.py install for mozversion 15:30:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:30:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:30:53 INFO - Cleaning up... 15:30:53 INFO - Return code: 0 15:30:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30:53 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')]} 15:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:53 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')]} 15:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:53 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 0x106e69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f804bc9a0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcdd30>, '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 0x106b32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc5b20>, '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.GyEWuI71Ix/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.XH5EovbzYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 15:30:53 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 15:30:53 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 15:30:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GyEWuI71Ix/Render', 15:30:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:53 INFO - 'HOME': '/Users/cltbld', 15:30:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:53 INFO - 'LOGNAME': 'cltbld', 15:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:53 INFO - 'MOZ_NO_REMOTE': '1', 15:30:53 INFO - 'NO_EM_RESTART': '1', 15:30:53 INFO - 'PAGER': '/bin/cat', 15:30:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:53 INFO - 'PWD': '/builds/slave/test', 15:30:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:53 INFO - 'SHELL': '/bin/bash', 15:30:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XH5EovbzYO/Listeners', 15:30:53 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 15:30:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:53 INFO - 'USER': 'cltbld', 15:30:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:53 INFO - 'XPC_FLAGS': '0x0', 15:30:53 INFO - 'XPC_SERVICE_NAME': '0', 15:30:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:30:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-013BdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:30:53 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)) 15:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:30:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-X4dF48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:30:53 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)) 15:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:30:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-wdoPzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:30:53 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)) 15:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:30:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-A6Mxpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:30:53 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)) 15:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:30:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-O_eIRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:30:53 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)) 15:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:30:53 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-xI92Zq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:30:54 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)) 15:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:30:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-xW9TeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:30:54 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)) 15:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:30:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-2g4gBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:30:54 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)) 15:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:30:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-sk70db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:30:54 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)) 15:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:30:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-HtH0_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:30:54 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)) 15:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:30:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-hIBYqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:30:54 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)) 15:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:30:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-gibCR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:30:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-1laMxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:30:54 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)) 15:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:30:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-HsQjxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:54 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-TghdtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:55 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)) 15:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:30:55 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-vkSl9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:30:55 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)) 15:30:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:30:55 INFO - Running setup.py (path:/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/pip-LLgt9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:30:55 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)) 15:30:55 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)) 15:30:55 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)) 15:30:55 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)) 15:30:55 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)) 15:30:55 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)) 15:30:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:30: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 15:30: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 15:30: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 15:30: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 15:30: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 15:30: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 15:30:57 INFO - Downloading blessings-1.6.tar.gz 15:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:30:57 INFO - Installing collected packages: blessings 15:30:57 INFO - Running setup.py install for blessings 15:30:57 INFO - Successfully installed blessings 15:30:57 INFO - Cleaning up... 15:30:58 INFO - Return code: 0 15:30:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:30:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:30:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:30:58 INFO - Reading from file tmpfile_stdout 15:30:58 INFO - Current package versions: 15:30:58 INFO - blessings == 1.6 15:30:58 INFO - blobuploader == 1.2.4 15:30:58 INFO - docopt == 0.6.1 15:30:58 INFO - functools32 == 3.2.3-2 15:30:58 INFO - jsonschema == 2.5.1 15:30:58 INFO - manifestparser == 1.1 15:30:58 INFO - mozInstall == 1.12 15:30:58 INFO - mozcrash == 0.17 15:30:58 INFO - mozdebug == 0.1 15:30:58 INFO - mozdevice == 0.48 15:30:58 INFO - mozfile == 1.2 15:30:58 INFO - mozhttpd == 0.7 15:30:58 INFO - mozinfo == 0.9 15:30:58 INFO - mozleak == 0.1 15:30:58 INFO - mozlog == 3.2 15:30:58 INFO - moznetwork == 0.27 15:30:58 INFO - mozprocess == 0.23 15:30:58 INFO - mozprofile == 0.28 15:30:58 INFO - mozrunner == 6.12 15:30:58 INFO - mozscreenshot == 0.1 15:30:58 INFO - mozsystemmonitor == 0.3 15:30:58 INFO - moztest == 0.7 15:30:58 INFO - mozversion == 1.4 15:30:58 INFO - psutil == 3.1.1 15:30:58 INFO - requests == 1.2.3 15:30:58 INFO - wsgiref == 0.1.2 15:30:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30: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')]} 15:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30: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')]} 15:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:58 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 0x106e69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f804bc9a0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcdd30>, '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 0x106b32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc5b20>, '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.GyEWuI71Ix/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.XH5EovbzYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 15:30:58 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 15:30:58 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 15:30:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GyEWuI71Ix/Render', 15:30:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:58 INFO - 'HOME': '/Users/cltbld', 15:30:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:58 INFO - 'LOGNAME': 'cltbld', 15:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:58 INFO - 'MOZ_NO_REMOTE': '1', 15:30:58 INFO - 'NO_EM_RESTART': '1', 15:30:58 INFO - 'PAGER': '/bin/cat', 15:30:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:58 INFO - 'PWD': '/builds/slave/test', 15:30:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:58 INFO - 'SHELL': '/bin/bash', 15:30:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XH5EovbzYO/Listeners', 15:30:58 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 15:30:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:58 INFO - 'USER': 'cltbld', 15:30:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:58 INFO - 'XPC_FLAGS': '0x0', 15:30:58 INFO - 'XPC_SERVICE_NAME': '0', 15:30:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:58 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)) 15:30:58 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)) 15:30:58 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)) 15:30:58 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)) 15:30:58 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)) 15:30:58 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)) 15:30:58 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)) 15:30:58 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)) 15:30:58 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)) 15:30:58 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)) 15:30:58 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)) 15:30:58 INFO - Cleaning up... 15:30:58 INFO - Return code: 0 15:30:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:30: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')]} 15:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30: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')]} 15:30:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106e69a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f804bc9a0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dcdd30>, '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 0x106b32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc5b20>, '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.GyEWuI71Ix/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.XH5EovbzYO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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 15:30: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'] in /builds/slave/test/build 15:30: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 15:30:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GyEWuI71Ix/Render', 15:30:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:58 INFO - 'HOME': '/Users/cltbld', 15:30:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:58 INFO - 'LOGNAME': 'cltbld', 15:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:58 INFO - 'MOZ_NO_REMOTE': '1', 15:30:58 INFO - 'NO_EM_RESTART': '1', 15:30:58 INFO - 'PAGER': '/bin/cat', 15:30:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:58 INFO - 'PWD': '/builds/slave/test', 15:30:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:58 INFO - 'SHELL': '/bin/bash', 15:30:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XH5EovbzYO/Listeners', 15:30:58 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 15:30:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:58 INFO - 'USER': 'cltbld', 15:30:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:58 INFO - 'XPC_FLAGS': '0x0', 15:30:58 INFO - 'XPC_SERVICE_NAME': '0', 15:30:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:30:58 INFO - Cleaning up... 15:30:58 INFO - Return code: 0 15:30:58 INFO - Running post-action listener: _resource_record_post_action 15:30:58 INFO - Running post-action listener: _start_resource_monitoring 15:30:58 INFO - Starting resource monitoring. 15:30:58 INFO - ##### 15:30:58 INFO - ##### Finished create-virtualenv step (success) 15:30:58 INFO - ##### 15:30:58 INFO - ##### 15:30:58 INFO - ##### Running install step. 15:30:58 INFO - ##### 15:30:58 INFO - Running pre-action listener: _resource_record_pre_action 15:30:58 INFO - Running main action method: install 15:30:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:30:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:30:59 INFO - Reading from file tmpfile_stdout 15:30:59 INFO - Detecting whether we're running mozinstall >=1.0... 15:30:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:30:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:30:59 INFO - Reading from file tmpfile_stdout 15:30:59 INFO - Output received: 15:30:59 INFO - Usage: mozinstall [options] installer 15:30:59 INFO - Options: 15:30:59 INFO - -h, --help show this help message and exit 15:30:59 INFO - -d DEST, --destination=DEST 15:30:59 INFO - Directory to install application into. [default: 15:30:59 INFO - "/builds/slave/test"] 15:30:59 INFO - --app=APP Application being installed. [default: firefox] 15:30:59 INFO - mkdir: /builds/slave/test/build/application 15:30:59 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'] 15:30:59 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 15:31:33 INFO - Reading from file tmpfile_stdout 15:31:33 INFO - Output received: 15:31:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:31:33 INFO - Running post-action listener: _resource_record_post_action 15:31:33 INFO - ##### 15:31:33 INFO - ##### Finished install step (success) 15:31:33 INFO - ##### 15:31:33 INFO - ##### 15:31:33 INFO - ##### Running run-tests step. 15:31:33 INFO - ##### 15:31:33 INFO - Running pre-action listener: _resource_record_pre_action 15:31:33 INFO - Running main action method: run_tests 15:31:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:31:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:31:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:31:33 INFO - 2016-07-13 15:31:33.925 screenresolution[1638:7490] starting screenresolution argv=screenresolution get 15:31:33 INFO - 2016-07-13 15:31:33.927 screenresolution[1638:7490] Display 0: 1600x1200x32@60 15:31:33 INFO - 2016-07-13 15:31:33.933 screenresolution[1639:7492] starting screenresolution argv=screenresolution list 15:31:33 INFO - Available Modes on Display 0 15:31:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:31:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:31:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:31:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:31:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:31:34 INFO - Intel Iris: 15:31:34 INFO - Chipset Model: Intel Iris 15:31:34 INFO - Type: GPU 15:31:34 INFO - Bus: Built-In 15:31:34 INFO - VRAM (Dynamic, Max): 1536 MB 15:31:34 INFO - Vendor: Intel (0x8086) 15:31:34 INFO - Device ID: 0x0a2e 15:31:34 INFO - Revision ID: 0x0009 15:31:34 INFO - Displays: 15:31:34 INFO - Display: 15:31:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:31:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:31:34 INFO - Display Serial Number: 1600x1200 60 15:31:34 INFO - Main Display: Yes 15:31:34 INFO - Mirror: Off 15:31:34 INFO - Online: Yes 15:31:34 INFO - Rotation: Supported 15:31:34 INFO - Return code: 0 15:31:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:31:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:31:34 INFO - Python 2.7.3 15:31:34 INFO - Return code: 0 15:31:34 INFO - grabbing minidump binary from tooltool 15:31:34 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')]} 15:31:34 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 0x106dcdd30>, '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 0x106b32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dc5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:31:34 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 15:31:34 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 15:31:34 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 15:31:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:31:34 INFO - Return code: 0 15:31:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:31:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:31:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:31:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:31:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:31:34 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/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0361', '--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 15:31:34 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/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0361 --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 15:31:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GyEWuI71Ix/Render', 15:31:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:31:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:31:34 INFO - 'HOME': '/Users/cltbld', 15:31:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:31:34 INFO - 'LOGNAME': 'cltbld', 15:31:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:31:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:34 INFO - 'MOZ_NO_REMOTE': '1', 15:31:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:31:34 INFO - 'NO_EM_RESTART': '1', 15:31:34 INFO - 'PAGER': '/bin/cat', 15:31:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:31:34 INFO - 'PWD': '/builds/slave/test', 15:31:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:31:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:31:34 INFO - 'SHELL': '/bin/bash', 15:31:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XH5EovbzYO/Listeners', 15:31:34 INFO - 'TMPDIR': '/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/', 15:31:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:31:34 INFO - 'USER': 'cltbld', 15:31:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:31:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:34 INFO - 'XPC_FLAGS': '0x0', 15:31:34 INFO - 'XPC_SERVICE_NAME': '0', 15:31:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:31:34 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/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0361', '--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 15:31:34 INFO - mozversion application_buildid: 20160713131643 15:31:34 INFO - mozversion application_changeset: bb5d09eec5eee960351a3a87aa30bbe87c25298d 15:31:34 INFO - mozversion application_display_name: Nightly 15:31:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:31:34 INFO - mozversion application_name: Firefox 15:31:34 INFO - mozversion application_remotingname: firefox 15:31:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:31:34 INFO - mozversion application_vendor: Mozilla 15:31:34 INFO - mozversion application_version: 50.0a1 15:31:34 INFO - mozversion platform_buildid: 20160713131643 15:31:34 INFO - mozversion platform_changeset: bb5d09eec5eee960351a3a87aa30bbe87c25298d 15:31:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:31:34 INFO - mozversion platform_version: 50.0a1 15:31:34 INFO - using testdate: 1468449094 15:31:34 INFO - actual date: 1468449094 15:31:34 INFO - starting webserver on 'localhost:49273' 15:31:34 INFO - SUITE-START | Running 2 tests 15:31:34 INFO - TEST-START | dromaeo_css 15:31:34 INFO - operating with platform_type : mac_ 15:31:34 INFO - Initialising browser for dromaeo_css test... 15:31:34 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpwazDX4/profile http://localhost:49273/getInfo.html) 15:31:37 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 15:31:37 INFO - PROCESS | 1649 | colorDepth:24 15:31:37 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 15:31:37 INFO - PROCESS | 1649 | __metrics 15:31:37 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" 15:31:37 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] 15:31:37 INFO - TEST-INFO | 1649: exit 0 15:31:37 INFO - Browser initialized. 15:31:37 INFO - Running cycle 1/1 for dromaeo_css test... 15:31:37 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpwazDX4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:31:49 INFO - PROCESS | 1652 | RSS: Main: 243621888 15:31:49 INFO - PROCESS | 1652 | 15:32:41 INFO - PROCESS | 1652 | 1468449161392 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] 15:34:51 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 15:34:52 INFO - PROCESS | 1652 | RSS: Main: 333795328 15:34:52 INFO - PROCESS | 1652 | 15:37:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 15:37:56 INFO - PROCESS | 1652 | RSS: Main: 382431232 15:37:56 INFO - PROCESS | 1652 | 15:40:58 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 15:40:59 INFO - PROCESS | 1652 | RSS: Main: 374480896 15:40:59 INFO - PROCESS | 1652 | 15:44:01 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 15:44:02 INFO - PROCESS | 1652 | RSS: Main: 371253248 15:44:02 INFO - PROCESS | 1652 | 15:47:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 15:47:06 INFO - PROCESS | 1652 | RSS: Main: 341737472 15:47:06 INFO - PROCESS | 1652 | 15:50:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 15:50:09 INFO - PROCESS | 1652 | RSS: Main: 396259328 15:50:09 INFO - PROCESS | 1652 | 15:50:09 INFO - PROCESS | 1652 | __start_tp_report 15:50:09 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 15:50:09 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 15:50:09 INFO - PROCESS | 1652 | |i|pagename|runs| 15:50:09 INFO - PROCESS | 1652 | |0;dojo.html;3306.2726200235948;3310.271740217153;3326.2349659578294;3326.2515933914856;3374;5563.777448902044;5580.553555715544;5652.689102099378;5660.094384898417;5715.371309155989;5620.859478513039;5623.184638227464;5742.196092547047;5760.971195144019;5772.365039845614;5006.4492905780335;5018.473060328668;5064.645474816764;5084.669496482736;5201.40183878855;4276.593723596269;5204.0372531081875;5209.973950130244;5209.984053109645;5234.7120908350025;5012.373453318335;5019.14674505335;5046.5710414614805;5052.267421223902;5170.793168273264;5067.417247016567;5067.670601410897;5086.720230387327;5093.439721630618;5124.769385377667;5041.470645582198;5084.110280700863;5100.69395836251;5108.897822043576;5172.6379153459275;5508.4491550845;5597.552195824324;5665.8583535411535;5675.858103547403;5678;6149.569530132892;6156.138140660311;6157.138000679908;6193.040078787795;6208.192934918477;6159.87680246397;6207.410296021871;6217.285012223607;6239.65681887496;6245.531585131088;5568.777248910039;5623.156526521055;5689.459501347365;5694.97152514243;5772.30732312121;6258.874822503615;6259.405356491126;6260.749570017239;6263.123162757218;6272;6011.458968692838;6056.956292366316;6072.028475443908;6091.5735898487565;6096.542759292982;6063.242094738158;6087.913291545794;6136.7852125175405;6155.169052177988;6158.04550294705;6092.969535152384;6150.969245153835;6158.230221222348;6161.537884658668;6182.443580077815;6113.510919126459;6186.659733714599;6189.442950134419;6205.6586887720705;6210.9068363974575;4734.502877197845;4810.062037902645;4812.35033270504;4845.63657725672;4861.951380486221;4307.741535507879;4420.138073075719;4420.955790442119;4430.246858034142;4431.423914891108;5634.830955071355;5656.688882111441;5690.459406356389;5729.427057294237;5791.681457519877;6193.194884664965;6210.921217173901;6258.749650013949;6267.467265282502;6277.403646653561;5486.149646804752;5704.686242256633;5713.200151978726;5733.168690539932;5742.08126699726;5234.450382709761;5302.09864323066;5338.652987555796;5351.464853514617;5361.544268737123;5220.190870415092;6204.100405441276;6211.596246243979;6221.129041934133;6221.346758590374;6106.72519736604;6188.133661287334;6272.341404152681;6274.780382686584;6281.937180628135;6171.814845554641;6187.907181392373;6210.068489726396;6221.626702397871;6278.874422511615;6150.600210986271;6155.938440615537;6161.44546990773;6162.476189523941;6168.537359698131;6230.345813689497;6230.470410015199;6243.188385509855;6258.092576576462;6260.7495700171485;5785.623934444247;5906.084556893688;5906.8186362726765;5973.671448070398;6023.759049638141;5931.733072011856;5964.045752679724;5976.133460648095;5983.790567330123;5988.8802223956145;5680.062789639828;5824.388439213991;5845.298564172156;5848.356680765028;5851.61964472308;5785.479306862402;5952.732127054206;5962.165296858357;5998.070299103646;6056.6971651418135;5823.505002074871;5847.298324201123;5853.531717462678;5863.061910094535;5875.618084824473 15:50:09 INFO - PROCESS | 1652 | |1;ext.html;515.2451658320559;526.184414158055;535.4832586553974;544.2190456694643;564.1622191046296;29940.550891736613;41917;43298.917527061836;43382.783086084564;43410.565894341045;43999.560004399944;44533;44667.88330291744;44675.1064978701;44841.55158448414;9539.09378609031;9704.369216000938;9713.931467538601;9716.56275467602;9732.464714440703;9593.47235902025;9706.272029597772;9712.125908668218;9720.562574684156;9721.368111072794;9661.903380966205;9739.707808765714;9739.977302383253;9745.512724363789;9755.658551950683;9657.32398732089;9703.272254580934;9709.757256068584;9718.222542196607;9718.465484398355;9579;9658.9517052415;9663.468509231989;9665.516724163763;9671.371360861593;41590.58409415897;41778.79110604458;41907.74276771702;41953.37069943953;42009.789951050356;3473.3400653875938;3609.476625889258;3778.7732736035928;4010.2981978153707;4042.252183346061;32240.032799016066;33416.6658333416;33568.49647255293;33586;33799.6620033799;3220.388126256027;3380;3400.472926696366;3423.5207071010077;3425.383430982422;528407;529338;529719.9999999962;530462;530491;63601.36398636047;63727.68136159382;64006.67996659987;64165;64254;66113;67010.99483507653;67070.99393509101;67079;67091.66454167698;17010.319587216374;17265.050422226897;17274.913625432044;17278;17312.740308895376;13202;13266.800997985038;13285.26931018784;13287.269200193889;13287.468501259842;17119.315227391013;17656.293748249926;17658.646827063385;17722.645547088985;17868.731969020475;6120.449159575571;7193.964030179815;7246.565206087652;7283.162436319785;7351.558906465522;866.5537248317122;945.5272363818091;952.0764858087643;967.260046064761;1040.6149724601946;30001.549976750368;30155.547666785016;30395.936142234914;31169.68830311668;31188.84405578003;1110.2006555280186;1114.6823155400673;1129.836173754801;1144.8683401408778;1166.2652528906735;15328.616784580474;15517.379304827906;15681.843181568267;15829.97105188159;15875.12686802237;689740.5512972403;695058;695522;696447;698279;50920;60496;61785.38214617886;61976.69011654913;62065;1119.3843386137592;1337.625464869838;1413.8303403591635;1436.9497067602583;1470.9043912145676;30358.69641303559;30707;31629.8418507906;31760;31772.841135794173;51980.74009630079;52605.473945260055;52767.73616131895;52814;52882.735586321825;552453;571789;571963;572691;572802;34800.129996749274;35737;40686.57596984092;41793.58206417897;41998.1600367997;47366;49246.50753492419;49463.752681236365;50381.99236015332;50513.74743126408;38378.80810596041;39885.80057099696;40160;40957.180856383304;41543.58456415397;40367;41082.45386872853;41566.376504353066;41586.168276633696;41916.58083419127;38659.2268154641;39253.42986280517;39818;39856;39945.60054399419;34743.82628086843;34996;35875.64124358723;36010;36048.8197559021;25056.37133586311;25247.87376063108;25748.8712556436;26016.79631100647;26216 15:50:09 INFO - PROCESS | 1652 | |2;jquery.html;356.71641045368926;377.53374582390745;387.6201322703752;388.4193131268753;410.68992910352677;15718.135502547355;15954.71999202115;19280.421587352368;21832.781672183293;21994.120235190614;20623.89688051558;21162.89418552905;21785.891070544625;21788.128474860987;22002.44993875154;5796.840631873642;5866.5600079994065;6057.878842423171;6076.118962750398;6107.7556897724035;4218.282891908383;4464.866054018385;5988.550858685616;6483.351664833526;6804.18349798025;6490.578112422678;6718.193816741998;6758.425533829631;6782.796516104525;6785.796426107225;6681.064650948871;6682.632455214961;6709.36261055199;6720.32796720324;6720.596764194115;5201.375834899798;5537.889242215133;5547.362053363834;5831.125331200354;5834.970825145847;11436;13628.727425451534;13967.930160349133;17603.559911002198;26969;2167.3931299236083;2209.4255493571627;2336.33414476875;2342.7657234276435;2425.7816796488223;10077.647282345082;10594.629187978384;10865.51105200268;12592.929600983927;15248.771268430983;1905.2569497895831;1914.5052216027393;1935.9516012099668;2003.1086166655843;2038.4699978005676;116129.4193529027;121197.39401303025;124327;126130;129224.92612205254;10278.22913281507;10457.68626941193;10905.5277537533;11023.338599684044;11859.051275897908;14781;14904.70190596182;14910.776338354934;14926.850731492546;14929.17889516087;8563.486190828446;8747.212750852454;8783.780405489913;8850.380473366802;8857;8754.781130471662;8759.12408759119;8766.561671916379;8901.376903616814;8929.508877011696;3807.2195199983935;4605.907881842411;4609.147307748078;4619.330197121399;4666.696664716783;1604.374294025331;1701.0823390343965;1787.9463616091539;1912.311567835578;1917.3893929099345;870.7474832298689;918.9724308270762;943.6791490893129;980.7695191629962;1098.1653943003216;21111.366659000254;21901.780982189975;21965.90170491469;22049.779502205092;22111;925.4447331600985;960.4765402855461;972.3339512434005;990.6928852055886;1007.3301254665565;11186.608468703556;11389.316641001566;11428;11450.599229026944;11485.540578376938;587475;591245;593201;607970;611746;107652;119195.40402298106;121583;121892;126643;979.6669132494986;1012.9746756331298;1017.8625885505362;1038.444432228754;1042.4943902207424;16827.055265328763;18331.633367332313;18877.71683424777;19671.508212294808;19715.901420492806;55920.70475791478;56424;57561.42438575561;57755.71122144363;57858.55353616193;83359.16330876948;83468;84969;85773.14226857902;85986.57006714927;6922.619255940972;7474.327310542078;7484.438667100098;7538.736144234926;7615.504992175491;10302.536385862504;13052.412641430969;13140.60578182656;13417.731645367234;13461.932690336485;11272.66182014541;11468.025217856575;11564.306141631529;11645.825312620487;11695.298282103102;11307.71730706706;11455.427228638702;11524.481398337262;11538.019268362283;11733.941330293294;11359.432028398713;11531.481083351437;11597.246178998337;11699.2980421175;11752.294862308288;7489.737859174903;7549.9245007549225;7612.276833700789;7636.541807491346;7755.961220193863;9393.154616084585;11215.271007384494;11265.662030139109;11387.089032877515;11464.484098215433 15:50:09 INFO - PROCESS | 1652 | |3;mootools.html;1391.3599744117719;1565.5146904459618;1591.5066329437873;1831.258340372149;1966.0857701168964;7777.883331750021;8237.87643185352;8517.53153576553;8802;8803.20771130598;8613.655453781841;8714.95642521788;8760.518171500566;8765.211130998208;8779.253763430115;2309.2956648222244;2332.416895776056;2337.555864385761;2341.718993720747;2343.121329501437;2300.769923007694;2326.220716060122;2350.2949115265437;2350.882455877208;2353.188150088217;2307.907683692659;2325.534893021394;2344.5076533927895;2347.1902193743217;2347.460084180631;2291.9083236670513;2331.463763334434;2332.0322066342533;2334.393057804983;2334.4514039200776;2313.5141620259596;2319.2346525646494;2320.153144102407;2320.872352020638;2326.3137374474572;8774.912250877538;8781.64873405063;8854.86717699235;8859.246964008067;8862.158094980967;5140.820071297524;5326.547243484309;5335.973320133413;5365.785368585291;5374.596905232084;3144.308252184516;3481.9651803481893;3585.533880595532;3639.6542328478754;3642.9453558196647;1060.294903888913;1079.3092420850662;1087.3258579680569;1107.496089279384;1109.2845114900845;11539.480723367386;11658;11665.825012624817;11665.941670291595;11669.70825729355;4341.9565804341555;4431;4570.245909424974;4571.222892108283;4585.9541404585525;9170.220531254918;9170.724878253663;9197.172254497127;9203.907960920438;9213.401128926598;4443;5204.8959020819375;5211.843644690665;5215.087359712035;5218.817341393033;4328.48058233014;4490.865274041784;4673.345731597579;4842.58837998767;4875.829345972945;3698.870539531157;3724.1806802503406;3787.768975083109;3816.3702989007056;3851.730378873452;1128.0580715102817;1135.0975974100609;1137.3346592243465;1146.810776221914;1152.9538818447336;645.5319893077575;817.7056259746487;927.4249965021729;950.2065775077749;991.509202944547;5938.079597662369;5944.7324870380935;5955.821325360246;5978.461938425563;6198.566100373021;988.9357191782511;1001.6744558018528;1006.436395618456;1029.4852573713144;1070.202698989258;3469.930601387958;3829.7702137871815;3866.284737323604;4066.779966010185;4101.733387329814;10084.74788130288;10500.73748156287;10639.787204255872;10646.467676616137;10662.200334974752;10577.471126443646;10603.310784799118;10618.203634727271;10677.305975111592;10683.465826708634;7995.120536740954;8069.193080692001;8213.425060245845;8249.216324449168;8262.628181731836;5868.647881127146;5953.583249172516;5991.071383935497;6001.159837622824;6067.362926892612;10014.148797352307;10062.993700629879;10068.949655251678;10114.443705596263;10144.188464922936;9956.352837065568;10107.848382274125;10362.119219866094;10367.688969330931;10390.272680912263;7576.545407275361;7609.923900761143;7703.922960770321;7713.112992005991;7737.1102323233545;8267.958660206661;8274.29668478186;8318.043425006203;8322.20939010793;8367.372447066373;7226.566406015656;7327.413806895543;7332.266773322625;7364.521306115085;7462.44031697614;7184;7193.604351760703;7260.382867456326;7307.890381644173;7329.963350183215;6952.270011648704;7032.718691252293;7091.2554181810165;7105.75129870452;7132.714691412285;5066.923996139987;5079.6698214615935;5083.110455670317;5121.769520371667;5196.376434827845;4955.603551715926;5009.323741294878;5068.417132029716;5083.313752643494;5249.89500209986 15:50:09 INFO - PROCESS | 1652 | |4;prototype.html;299.0803279914264;311.23746820290296;311.8674563310593;317.8458447652888;320.39445448103106;5090.643654944155;5144.22836574514;5146.845594632157;5176;5192.766325515351;5088.160453525174;5096.643234973553;5141.768620412081;5155.845324640258;5156.484351564851;3739.9715078353397;3780.319542482352;3885;3979.781112038837;3980.2636512245326;3291.358185153896;3866.1881004988986;3916.432117342998;3981.6814654827544;3982.880513584583;3360.8741071740997;3884.3784994400903;3898.259330727167;3928.941065884014;3935.01281852664;3153.4954407294954;3543.2106786073127;3603.027182660693;3840.2511510255513;3891.4357418174204;2843.928901777472;3424.2466657335344;3427.417339052367;3442.345954268681;3445.931761154025;4298.226319262531;4814.724098114003;5020.899582008339;5039.269305950654;5060.620453465968;4238.660907127453;4495.303227999665;4519.276915693474;4524.411826462571;4528.275475923836;4680;4926.950730492685;5391;5416.458354164592;5434.239206511092;3354.2788300515263;3557.8932632021083;3751.568569614502;3830.3296923038633;3840.615938406165;5335.399380185929;5723.284589426322;5725.685087320196;5733.48062763368;5756.568257380672;3759.2669429460993;3781.3949768036982;3781.9432708509394;3792.810359482015;3801.790901500389;3543.681068703829;3549.18368775178;3584.5160903278247;3853.402722578005;3895.8441662333757;4455.710378825431;4497.797599108048;4497.8425755098415;4505.594496495331;4505.7521836298665;4544.863654090383;5244.889320485078;5446.836594902159;5483.259759932438;5523.944760552424;1254.3978890132787;1272.6500212441467;1280.7310464802497;1313.3892739876003;1316.776148054833;734.3574372424129;773.3078894389603;791.6674996501483;805.5247404031533;819.2585709932521;4626.930596040995;4724.385829842098;4808.519148085234;5013.248012798036;5089.4147173074825;5134.2812006319145;5577.27495425592;5592.328920529562;5609.5792815539;5615.185798059259;4716.221823399169;5033;5062.594992400599;5067.290579318898;5098.566621837112;2759.5715770122165;2911.243076800047;2933.1640482462394;2938.911832645024;2954.704529547071;4338.978305108454;5607.271054762855;5667.4615911488345;5735.77056917727;5758.798442054508;5309.7079660618265;5503.532199763065;5719.113537401709;5721.742521586539;5723.685197314105;4773.498782627774;5459.508644222039;5759;5772.855678608068;5799.5070419014855;4839.516048395132;5556.194351819045;5561.777528898961;5570.637908535932;5574.9442505574425;5244.239585260192;5497.3952865184;5514.228008078952;5521.254630624975;5521.944780552304;4719.5752382285755;5715.485606295453;5724.084146536534;5733.397993210653;5734.885302293847;3151.2909595340866;3726.31063253299;3732.5894151643843;3733.8506459741307;3741.569719482294;5025.612833904012;5239.904038384677;5241.947580524146;5549.2242482405245;5647.576431767716;4893.755312234445;5381.596380271417;5391.541718953933;5403.756830942696;5452.291202143696;4358.259095953632;4543.977280113711;5106;5331.733413329395;5386.279928636014;4516.525764794781;4689.678609088523;4874.830040790256;4907.190313598359;4999.550040496373;4263.360495925512;4382.605565498993;4538.6369090473345;5363.490468405495;5387.92241551699;4969.832089458902;5092.217723796642;5316.096263635269;5336.71918739492;5340.359156901197 15:50:09 INFO - PROCESS | 1652 | |5;yui.html;750.2759836757527;837.8133072680307;843.5402705525488;855.6106971328045;860.4794099569756;15058.774118388234;16312.673746525063;16333.611643010343;16352.509424717262;17706.911465442656;16572.50282491527;17415.651686966285;17426.47720568385;17433.47699569012;17460.214290356933;704.2323866984985;882.2765332427412;889.6308032166643;901.3555307954811;901.7610333261691;720.3840716187636;757.522760660783;883.6244596046687;899.9505027223532;917.9082091790834;963.0032915932012;964.1659964131011;964.7588102974256;965.6668449384986;967.3760424526176;745.6271864067966;927.5455027036703;951.5527701980023;951.7668171298008;951.923846092311;708.0406049802494;708.9432845372357;709.3615745828744;709.6203531110882;710.0272626501674;15921.044737315799;16052.919735401365;16055.839441605667;16060.11669358185;16080.43718469848;14480.275986200753;14623.70752584953;14665.266736663125;14708.705825883422;14738.705225895423;13315.600531984055;13449;13476.730465390638;13506.392212350369;13850.238236896967;3687.3547129252543;4227.668284490375;4856.150173719585;4915.483874193194;4940.580050695695;101237;120557.99999999913;122839;123699.3815030919;123720;9547.379420337656;11281.774364512828;12053.879461205452;12066.69833254158;12205.511779528719;24065.917033733524;24073.51852962931;24113.879430603087;24119;24160.637590436156;6516.543841931019;8300.045494768057;8472.957635211907;8561.529115898686;8818.29453643707;4423.911521769546;4596.931046034312;4656.767161641905;4679.766011699401;4759.381280433521;3482.21650128719;3611.837467313977;3634.636536346397;3634.927301454009;3636.999825048133;642.3351830855042;654.228010947087;661.9536632435779;677.1772296659582;678.6979793991626;556.7661582135432;582.3448620302161;597.2534332084894;685.5715178013742;691.1636919327669;6871.209810871714;8140.871251111762;8967.372283940062;9306.162445379949;9380.24958003358;580.587782674306;594.8958932186937;647.8380404898776;671.6775947545206;672.4082807129695;4524.140413321458;4533.795979180879;4535.206338890681;4585.977070114628;4621.722696638212;136409;141299.88050179256;142657.28671356785;144696;146463.2676836609;125139;127731.36134319454;127813;128915;132159;109841;110324;112376;112483.87516124734;114230.42884785689;7692.8461430772195;8301;8760.78098047554;8922.55387230649;8941.37410381293;51464;61205.08192377029;62825;63339;63746.04380934197;56663.71668141633;66000.66999664971;88131;88673.15846245023;91053.08946910712;12166.939165304471;12266.26402415858;12328.75342493163;12573.811392828931;12738.617841464771;12622.87377126217;12703.872961270268;12873.356332183166;13283.070185086952;13320.467181312639;10538.367697938149;11574.537018519164;11932.582359617372;12470.501179953064;12477.313747743598;11713.472893719632;11997.100217483898;12255.264684118982;12412.56556020535;12439.689007774878;9878.357906736039;12167.75664486723;12516.311602861566;12559.87440125587;12605.873941260837;9063.954680226556;10334.914833942328;10521.947390263;10553.525091370753;10711.196660250356;10137.996858285005;10648.201384896318;10676.306040107369;10856.077233435246;10857.619983300547 15:50:09 INFO - PROCESS | 1652 | __end_tp_report 15:50:09 INFO - PROCESS | 1652 | __start_cc_report 15:50:09 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1317 15:50:09 INFO - PROCESS | 1652 | __end_cc_report 15:50:09 INFO - PROCESS | 1652 | __startTimestamp1468450209951__endTimestamp 15:50:09 INFO - PROCESS | 1652 | 15:50:09 INFO - PROCESS | 1652 | ------- Summary: start ------- 15:50:09 INFO - PROCESS | 1652 | Number of tests: 6 15:50:09 INFO - PROCESS | 1652 | 15:50:09 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5666.40 Median:5847.30 stddev:648.32 (11.1%) stddev-sans-first:623.31 15:50:09 INFO - PROCESS | 1652 | Values: 3306.3 3310.3 3326.2 3326.3 3374.0 5563.8 5580.6 5652.7 5660.1 5715.4 5620.9 5623.2 5742.2 5761.0 5772.4 5006.4 5018.5 5064.6 5084.7 5201.4 4276.6 5204.0 5210.0 5210.0 5234.7 5012.4 5019.1 5046.6 5052.3 5170.8 5067.4 5067.7 5086.7 5093.4 5124.8 5041.5 5084.1 5100.7 5108.9 5172.6 5508.4 5597.6 5665.9 5675.9 5678.0 6149.6 6156.1 6157.1 6193.0 6208.2 6159.9 6207.4 6217.3 6239.7 6245.5 5568.8 5623.2 5689.5 5695.0 5772.3 6258.9 6259.4 6260.7 6263.1 6272.0 6011.5 6057.0 6072.0 6091.6 6096.5 6063.2 6087.9 6136.8 6155.2 6158.0 6093.0 6151.0 6158.2 6161.5 6182.4 6113.5 6186.7 6189.4 6205.7 6210.9 4734.5 4810.1 4812.4 4845.6 4862.0 4307.7 4420.1 4421.0 4430.2 4431.4 5634.8 5656.7 5690.5 5729.4 5791.7 6193.2 6210.9 6258.7 6267.5 6277.4 5486.1 5704.7 5713.2 5733.2 5742.1 5234.5 5302.1 5338.7 5351.5 5361.5 5220.2 6204.1 6211.6 6221.1 6221.3 6106.7 6188.1 6272.3 6274.8 6281.9 6171.8 6187.9 6210.1 6221.6 6278.9 6150.6 6155.9 6161.4 6162.5 6168.5 6230.3 6230.5 6243.2 6258.1 6260.7 5785.6 5906.1 5906.8 5973.7 6023.8 5931.7 5964.0 5976.1 5983.8 5988.9 5680.1 5824.4 5845.3 5848.4 5851.6 5785.5 5952.7 5962.2 5998.1 6056.7 5823.5 5847.3 5853.5 5863.1 5875.6 15:50:09 INFO - PROCESS | 1652 | 15:50:09 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:73855.54 Median:41975.77 stddev:160826.34 (383.1%) stddev-sans-first:161183.23 15:50:09 INFO - PROCESS | 1652 | Values: 515.2 526.2 535.5 544.2 564.2 29940.6 41917.0 43298.9 43382.8 43410.6 43999.6 44533.0 44667.9 44675.1 44841.6 9539.1 9704.4 9713.9 9716.6 9732.5 9593.5 9706.3 9712.1 9720.6 9721.4 9661.9 9739.7 9740.0 9745.5 9755.7 9657.3 9703.3 9709.8 9718.2 9718.5 9579.0 9659.0 9663.5 9665.5 9671.4 41590.6 41778.8 41907.7 41953.4 42009.8 3473.3 3609.5 3778.8 4010.3 4042.3 32240.0 33416.7 33568.5 33586.0 33799.7 3220.4 3380.0 3400.5 3423.5 3425.4 528407.0 529338.0 529720.0 530462.0 530491.0 63601.4 63727.7 64006.7 64165.0 64254.0 66113.0 67011.0 67071.0 67079.0 67091.7 17010.3 17265.1 17274.9 17278.0 17312.7 13202.0 13266.8 13285.3 13287.3 13287.5 17119.3 17656.3 17658.6 17722.6 17868.7 6120.4 7194.0 7246.6 7283.2 7351.6 866.6 945.5 952.1 967.3 1040.6 30001.5 30155.5 30395.9 31169.7 31188.8 1110.2 1114.7 1129.8 1144.9 1166.3 15328.6 15517.4 15681.8 15830.0 15875.1 689740.6 695058.0 695522.0 696447.0 698279.0 50920.0 60496.0 61785.4 61976.7 62065.0 1119.4 1337.6 1413.8 1436.9 1470.9 30358.7 30707.0 31629.8 31760.0 31772.8 51980.7 52605.5 52767.7 52814.0 52882.7 552453.0 571789.0 571963.0 572691.0 572802.0 34800.1 35737.0 40686.6 41793.6 41998.2 47366.0 49246.5 49463.8 50382.0 50513.7 38378.8 39885.8 40160.0 40957.2 41543.6 40367.0 41082.5 41566.4 41586.2 41916.6 38659.2 39253.4 39818.0 39856.0 39945.6 34743.8 34996.0 35875.6 36010.0 36048.8 25056.4 25247.9 25748.9 26016.8 26216.0 15:50:09 INFO - PROCESS | 1652 | 15:50:09 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:35136.63 Median:21980.01 stddev:99913.41 (454.6%) stddev-sans-first:100159.57 15:50:09 INFO - PROCESS | 1652 | Values: 356.7 377.5 387.6 388.4 410.7 15718.1 15954.7 19280.4 21832.8 21994.1 20623.9 21162.9 21785.9 21788.1 22002.4 5796.8 5866.6 6057.9 6076.1 6107.8 4218.3 4464.9 5988.6 6483.4 6804.2 6490.6 6718.2 6758.4 6782.8 6785.8 6681.1 6682.6 6709.4 6720.3 6720.6 5201.4 5537.9 5547.4 5831.1 5835.0 11436.0 13628.7 13967.9 17603.6 26969.0 2167.4 2209.4 2336.3 2342.8 2425.8 10077.6 10594.6 10865.5 12592.9 15248.8 1905.3 1914.5 1936.0 2003.1 2038.5 116129.4 121197.4 124327.0 126130.0 129224.9 10278.2 10457.7 10905.5 11023.3 11859.1 14781.0 14904.7 14910.8 14926.9 14929.2 8563.5 8747.2 8783.8 8850.4 8857.0 8754.8 8759.1 8766.6 8901.4 8929.5 3807.2 4605.9 4609.1 4619.3 4666.7 1604.4 1701.1 1787.9 1912.3 1917.4 870.7 919.0 943.7 980.8 1098.2 21111.4 21901.8 21965.9 22049.8 22111.0 925.4 960.5 972.3 990.7 1007.3 11186.6 11389.3 11428.0 11450.6 11485.5 587475.0 591245.0 593201.0 607970.0 611746.0 107652.0 119195.4 121583.0 121892.0 126643.0 979.7 1013.0 1017.9 1038.4 1042.5 16827.1 18331.6 18877.7 19671.5 19715.9 55920.7 56424.0 57561.4 57755.7 57858.6 83359.2 83468.0 84969.0 85773.1 85986.6 6922.6 7474.3 7484.4 7538.7 7615.5 10302.5 13052.4 13140.6 13417.7 13461.9 11272.7 11468.0 11564.3 11645.8 11695.3 11307.7 11455.4 11524.5 11538.0 11733.9 11359.4 11531.5 11597.2 11699.3 11752.3 7489.7 7549.9 7612.3 7636.5 7756.0 9393.2 11215.3 11265.7 11387.1 11464.5 15:50:09 INFO - PROCESS | 1652 | 15:50:09 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5675.31 Median:4629.65 stddev:3178.32 (68.7%) stddev-sans-first:3170.92 15:50:09 INFO - PROCESS | 1652 | Values: 1391.4 1565.5 1591.5 1831.3 1966.1 7777.9 8237.9 8517.5 8802.0 8803.2 8613.7 8715.0 8760.5 8765.2 8779.3 2309.3 2332.4 2337.6 2341.7 2343.1 2300.8 2326.2 2350.3 2350.9 2353.2 2307.9 2325.5 2344.5 2347.2 2347.5 2291.9 2331.5 2332.0 2334.4 2334.5 2313.5 2319.2 2320.2 2320.9 2326.3 8774.9 8781.6 8854.9 8859.2 8862.2 5140.8 5326.5 5336.0 5365.8 5374.6 3144.3 3482.0 3585.5 3639.7 3642.9 1060.3 1079.3 1087.3 1107.5 1109.3 11539.5 11658.0 11665.8 11665.9 11669.7 4342.0 4431.0 4570.2 4571.2 4586.0 9170.2 9170.7 9197.2 9203.9 9213.4 4443.0 5204.9 5211.8 5215.1 5218.8 4328.5 4490.9 4673.3 4842.6 4875.8 3698.9 3724.2 3787.8 3816.4 3851.7 1128.1 1135.1 1137.3 1146.8 1153.0 645.5 817.7 927.4 950.2 991.5 5938.1 5944.7 5955.8 5978.5 6198.6 988.9 1001.7 1006.4 1029.5 1070.2 3469.9 3829.8 3866.3 4066.8 4101.7 10084.7 10500.7 10639.8 10646.5 10662.2 10577.5 10603.3 10618.2 10677.3 10683.5 7995.1 8069.2 8213.4 8249.2 8262.6 5868.6 5953.6 5991.1 6001.2 6067.4 10014.1 10063.0 10068.9 10114.4 10144.2 9956.4 10107.8 10362.1 10367.7 10390.3 7576.5 7609.9 7703.9 7713.1 7737.1 8268.0 8274.3 8318.0 8322.2 8367.4 7226.6 7327.4 7332.3 7364.5 7462.4 7184.0 7193.6 7260.4 7307.9 7330.0 6952.3 7032.7 7091.3 7105.8 7132.7 5066.9 5079.7 5083.1 5121.8 5196.4 4955.6 5009.3 5068.4 5083.3 5249.9 15:50:09 INFO - PROCESS | 1652 | 15:50:09 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4368.62 Median:4900.47 stddev:1344.38 (27.4%) stddev-sans-first:1312.99 15:50:09 INFO - PROCESS | 1652 | Values: 299.1 311.2 311.9 317.8 320.4 5090.6 5144.2 5146.8 5176.0 5192.8 5088.2 5096.6 5141.8 5155.8 5156.5 3740.0 3780.3 3885.0 3979.8 3980.3 3291.4 3866.2 3916.4 3981.7 3982.9 3360.9 3884.4 3898.3 3928.9 3935.0 3153.5 3543.2 3603.0 3840.3 3891.4 2843.9 3424.2 3427.4 3442.3 3445.9 4298.2 4814.7 5020.9 5039.3 5060.6 4238.7 4495.3 4519.3 4524.4 4528.3 4680.0 4927.0 5391.0 5416.5 5434.2 3354.3 3557.9 3751.6 3830.3 3840.6 5335.4 5723.3 5725.7 5733.5 5756.6 3759.3 3781.4 3781.9 3792.8 3801.8 3543.7 3549.2 3584.5 3853.4 3895.8 4455.7 4497.8 4497.8 4505.6 4505.8 4544.9 5244.9 5446.8 5483.3 5523.9 1254.4 1272.7 1280.7 1313.4 1316.8 734.4 773.3 791.7 805.5 819.3 4626.9 4724.4 4808.5 5013.2 5089.4 5134.3 5577.3 5592.3 5609.6 5615.2 4716.2 5033.0 5062.6 5067.3 5098.6 2759.6 2911.2 2933.2 2938.9 2954.7 4339.0 5607.3 5667.5 5735.8 5758.8 5309.7 5503.5 5719.1 5721.7 5723.7 4773.5 5459.5 5759.0 5772.9 5799.5 4839.5 5556.2 5561.8 5570.6 5574.9 5244.2 5497.4 5514.2 5521.3 5521.9 4719.6 5715.5 5724.1 5733.4 5734.9 3151.3 3726.3 3732.6 3733.9 3741.6 5025.6 5239.9 5241.9 5549.2 5647.6 4893.8 5381.6 5391.5 5403.8 5452.3 4358.3 4544.0 5106.0 5331.7 5386.3 4516.5 4689.7 4874.8 4907.2 4999.6 4263.4 4382.6 4538.6 5363.5 5387.9 4969.8 5092.2 5316.1 5336.7 5340.4 15:50:09 INFO - PROCESS | 1652 | 15:50:09 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:24571.21 Median:3635.96 stddev:39123.64 (1076.0%) stddev-sans-first:39192.51 15:50:09 INFO - PROCESS | 1652 | Values: 750.3 837.8 843.5 855.6 860.5 15058.8 16312.7 16333.6 16352.5 17706.9 16572.5 17415.7 17426.5 17433.5 17460.2 704.2 882.3 889.6 901.4 901.8 720.4 757.5 883.6 900.0 917.9 963.0 964.2 964.8 965.7 967.4 745.6 927.5 951.6 951.8 951.9 708.0 708.9 709.4 709.6 710.0 15921.0 16052.9 16055.8 16060.1 16080.4 14480.3 14623.7 14665.3 14708.7 14738.7 13315.6 13449.0 13476.7 13506.4 13850.2 3687.4 4227.7 4856.2 4915.5 4940.6 101237.0 120558.0 122839.0 123699.4 123720.0 9547.4 11281.8 12053.9 12066.7 12205.5 24065.9 24073.5 24113.9 24119.0 24160.6 6516.5 8300.0 8473.0 8561.5 8818.3 4423.9 4596.9 4656.8 4679.8 4759.4 3482.2 3611.8 3634.6 3634.9 3637.0 642.3 654.2 662.0 677.2 678.7 556.8 582.3 597.3 685.6 691.2 6871.2 8140.9 8967.4 9306.2 9380.2 580.6 594.9 647.8 671.7 672.4 4524.1 4533.8 4535.2 4586.0 4621.7 136409.0 141299.9 142657.3 144696.0 146463.3 125139.0 127731.4 127813.0 128915.0 132159.0 109841.0 110324.0 112376.0 112483.9 114230.4 7692.8 8301.0 8760.8 8922.6 8941.4 51464.0 61205.1 62825.0 63339.0 63746.0 56663.7 66000.7 88131.0 88673.2 91053.1 12166.9 12266.3 12328.8 12573.8 12738.6 12622.9 12703.9 12873.4 13283.1 13320.5 10538.4 11574.5 11932.6 12470.5 12477.3 11713.5 11997.1 12255.3 12412.6 12439.7 9878.4 12167.8 12516.3 12559.9 12605.9 9064.0 10334.9 10521.9 10553.5 10711.2 10138.0 10648.2 10676.3 10856.1 10857.6 15:50:09 INFO - PROCESS | 1652 | -------- Summary: end -------- 15:50:09 INFO - PROCESS | 1652 | 15:50:10 INFO - TEST-INFO | 1652: exit 0 15:50:10 INFO - TEST-OK | dromaeo_css | took 1116065ms 15:50:10 INFO - TEST-START | kraken 15:50:10 INFO - operating with platform_type : mac_ 15:50:10 INFO - Initialising browser for kraken test... 15:50:10 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpOqO_Gl/profile http://localhost:49273/getInfo.html) 15:50:12 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 15:50:12 INFO - PROCESS | 1662 | colorDepth:24 15:50:12 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 15:50:12 INFO - PROCESS | 1662 | __metrics 15:50:12 INFO - PROCESS | 1662 | 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" 15:50:12 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:50:13 INFO - TEST-INFO | 1662: exit 0 15:50:13 INFO - Browser initialized. 15:50:13 INFO - Running cycle 1/1 for kraken test... 15:50:13 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/T/tmpOqO_Gl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:50:25 INFO - PROCESS | 1663 | RSS: Main: 243707904 15:50:25 INFO - PROCESS | 1663 | 15:50:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 15:50:28 INFO - PROCESS | 1663 | RSS: Main: 293707776 15:50:28 INFO - PROCESS | 1663 | 15:50:31 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 15:50:32 INFO - PROCESS | 1663 | RSS: Main: 304017408 15:50:32 INFO - PROCESS | 1663 | 15:50:35 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 15:50:36 INFO - PROCESS | 1663 | RSS: Main: 389353472 15:50:36 INFO - PROCESS | 1663 | 15:50:38 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 15:50:38 INFO - PROCESS | 1663 | RSS: Main: 292663296 15:50:38 INFO - PROCESS | 1663 | 15:50:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:50:41 INFO - PROCESS | 1663 | RSS: Main: 296108032 15:50:41 INFO - PROCESS | 1663 | 15:50:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 15:50:45 INFO - PROCESS | 1663 | RSS: Main: 333479936 15:50:45 INFO - PROCESS | 1663 | 15:50:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 15:50:48 INFO - PROCESS | 1663 | RSS: Main: 335998976 15:50:48 INFO - PROCESS | 1663 | 15:50:51 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 15:50:51 INFO - PROCESS | 1663 | RSS: Main: 334114816 15:50:51 INFO - PROCESS | 1663 | 15:50:53 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:50:54 INFO - PROCESS | 1663 | RSS: Main: 312938496 15:50:54 INFO - PROCESS | 1663 | 15:50:56 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:50:56 INFO - PROCESS | 1663 | RSS: Main: 319131648 15:50:56 INFO - PROCESS | 1663 | 15:50:58 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:50:59 INFO - PROCESS | 1663 | RSS: Main: 317186048 15:50:59 INFO - PROCESS | 1663 | 15:51:02 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:51:02 INFO - PROCESS | 1663 | RSS: Main: 326246400 15:51:02 INFO - PROCESS | 1663 | 15:51:05 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:51:06 INFO - PROCESS | 1663 | RSS: Main: 328245248 15:51:06 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 15:51:08 INFO - PROCESS | 1663 | RSS: Main: 371609600 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | __start_tp_report 15:51:08 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 15:51:08 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 15:51:08 INFO - PROCESS | 1663 | |i|pagename|runs| 15:51:08 INFO - PROCESS | 1663 | |0;ai-astar;101;113;113;113;113;111;113;112;112;111 15:51:08 INFO - PROCESS | 1663 | |1;audio-beat-detection;144;121;105;154;108;107;128;105;137;107 15:51:08 INFO - PROCESS | 1663 | |2;audio-dft;185;254;186;185;183;182;183;181;182;183 15:51:08 INFO - PROCESS | 1663 | |3;audio-fft;79;74;57;106;57;57;61;63;125;57 15:51:08 INFO - PROCESS | 1663 | |4;audio-oscillator;81;86;94;71;70;67;95;68;68;124 15:51:08 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;90;87;91;88;90;86;90;90;134;87 15:51:08 INFO - PROCESS | 1663 | |6;imaging-darkroom;95;96;95;96;94;95;100;96;96;94 15:51:08 INFO - PROCESS | 1663 | |7;imaging-desaturate;83;81;82;84;84;83;84;85;84;86 15:51:08 INFO - PROCESS | 1663 | |8;json-parse-financial;56;56;57;55;55;55;54;55;56;55 15:51:08 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;66;65;74;77;71;72;71;71;70;73 15:51:08 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;83;81;82;83;83;82;80;84;83;85 15:51:08 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;144;148;139;135;134;134;136;135;177;136 15:51:08 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;163;224;209;169;162;164;163;162;163;162 15:51:08 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;67;66;65;67;66;68;67;68;64;84 15:51:08 INFO - PROCESS | 1663 | __end_tp_report 15:51:08 INFO - PROCESS | 1663 | __start_cc_report 15:51:08 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2034 15:51:08 INFO - PROCESS | 1663 | __end_cc_report 15:51:08 INFO - PROCESS | 1663 | __startTimestamp1468450268467__endTimestamp 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | ------- Summary: start ------- 15:51:08 INFO - PROCESS | 1663 | Number of tests: 14 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:111.20 Median:113.00 stddev:3.68 (3.3%) stddev-sans-first:0.87 15:51:08 INFO - PROCESS | 1663 | Values: 101.0 113.0 113.0 113.0 113.0 111.0 113.0 112.0 112.0 111.0 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:121.60 Median:124.50 stddev:18.23 (14.6%) stddev-sans-first:17.45 15:51:08 INFO - PROCESS | 1663 | Values: 144.0 121.0 105.0 154.0 108.0 107.0 128.0 105.0 137.0 107.0 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:190.40 Median:184.00 stddev:22.40 (12.2%) stddev-sans-first:23.67 15:51:08 INFO - PROCESS | 1663 | Values: 185.0 254.0 186.0 185.0 183.0 182.0 183.0 181.0 182.0 183.0 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:73.60 Median:59.00 stddev:23.79 (40.3%) stddev-sans-first:25.15 15:51:08 INFO - PROCESS | 1663 | Values: 79.0 74.0 57.0 106.0 57.0 57.0 61.0 63.0 125.0 57.0 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:82.40 Median:76.00 stddev:18.18 (23.9%) stddev-sans-first:19.28 15:51:08 INFO - PROCESS | 1663 | Values: 81.0 86.0 94.0 71.0 70.0 67.0 95.0 68.0 68.0 124.0 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:93.30 Median:90.00 stddev:14.40 (16.0%) stddev-sans-first:15.22 15:51:08 INFO - PROCESS | 1663 | Values: 90.0 87.0 91.0 88.0 90.0 86.0 90.0 90.0 134.0 87.0 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:95.70 Median:95.50 stddev:1.70 (1.8%) stddev-sans-first:1.79 15:51:08 INFO - PROCESS | 1663 | Values: 95.0 96.0 95.0 96.0 94.0 95.0 100.0 96.0 96.0 94.0 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:83.60 Median:84.00 stddev:1.43 (1.7%) stddev-sans-first:1.50 15:51:08 INFO - PROCESS | 1663 | Values: 83.0 81.0 82.0 84.0 84.0 83.0 84.0 85.0 84.0 86.0 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.50 stddev:0.84 (1.5%) stddev-sans-first:0.87 15:51:08 INFO - PROCESS | 1663 | Values: 56.0 56.0 57.0 55.0 55.0 55.0 54.0 55.0 56.0 55.0 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.50 stddev:3.53 (4.9%) stddev-sans-first:3.24 15:51:08 INFO - PROCESS | 1663 | Values: 66.0 65.0 74.0 77.0 71.0 72.0 71.0 71.0 70.0 73.0 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:83.00 stddev:1.43 (1.7%) stddev-sans-first:1.51 15:51:08 INFO - PROCESS | 1663 | Values: 83.0 81.0 82.0 83.0 83.0 82.0 80.0 84.0 83.0 85.0 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:141.80 Median:137.50 stddev:13.21 (9.6%) stddev-sans-first:13.99 15:51:08 INFO - PROCESS | 1663 | Values: 144.0 148.0 139.0 135.0 134.0 134.0 136.0 135.0 177.0 136.0 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.10 Median:163.50 stddev:22.72 (13.9%) stddev-sans-first:23.74 15:51:08 INFO - PROCESS | 1663 | Values: 163.0 224.0 209.0 169.0 162.0 164.0 163.0 162.0 163.0 162.0 15:51:08 INFO - PROCESS | 1663 | 15:51:08 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:67.00 stddev:5.69 (8.5%) stddev-sans-first:6.02 15:51:08 INFO - PROCESS | 1663 | Values: 67.0 66.0 65.0 67.0 66.0 68.0 67.0 68.0 64.0 84.0 15:51:08 INFO - PROCESS | 1663 | -------- Summary: end -------- 15:51:08 INFO - PROCESS | 1663 | 15:51:09 INFO - TEST-INFO | 1663: exit 0 15:51:09 INFO - TEST-OK | kraken | took 58734ms 15:51:09 INFO - SUITE-END | took 1174s 15:51:09 INFO - Completed test suite (00:19:35) 15:51:09 INFO - javascript benchmark 15:51:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3306.2726200235948, 3310.271740217153, 3326.2349659578294, 3326.2515933914856, 3374.0, 5563.777448902044, 5580.553555715544, 5652.689102099378, 5660.094384898417, 5715.371309155989, 5620.859478513039, 5623.184638227464, 5742.196092547047, 5760.971195144019, 5772.365039845614, 5006.4492905780335, 5018.473060328668, 5064.645474816764, 5084.669496482736, 5201.40183878855, 4276.593723596269, 5204.0372531081875, 5209.973950130244, 5209.984053109645, 5234.7120908350025, 5012.373453318335, 5019.14674505335, 5046.5710414614805, 5052.267421223902, 5170.793168273264, 5067.417247016567, 5067.670601410897, 5086.720230387327, 5093.439721630618, 5124.769385377667, 5041.470645582198, 5084.110280700863, 5100.69395836251, 5108.897822043576, 5172.6379153459275, 5508.4491550845, 5597.552195824324, 5665.8583535411535, 5675.858103547403, 5678.0, 6149.569530132892, 6156.138140660311, 6157.138000679908, 6193.040078787795, 6208.192934918477, 6159.87680246397, 6207.410296021871, 6217.285012223607, 6239.65681887496, 6245.531585131088, 5568.777248910039, 5623.156526521055, 5689.459501347365, 5694.97152514243, 5772.30732312121, 6258.874822503615, 6259.405356491126, 6260.749570017239, 6263.123162757218, 6272.0, 6011.458968692838, 6056.956292366316, 6072.028475443908, 6091.5735898487565, 6096.542759292982, 6063.242094738158, 6087.913291545794, 6136.7852125175405, 6155.169052177988, 6158.04550294705, 6092.969535152384, 6150.969245153835, 6158.230221222348, 6161.537884658668, 6182.443580077815, 6113.510919126459, 6186.659733714599, 6189.442950134419, 6205.6586887720705, 6210.9068363974575, 4734.502877197845, 4810.062037902645, 4812.35033270504, 4845.63657725672, 4861.951380486221, 4307.741535507879, 4420.138073075719, 4420.955790442119, 4430.246858034142, 4431.423914891108, 5634.830955071355, 5656.688882111441, 5690.459406356389, 5729.427057294237, 5791.681457519877, 6193.194884664965, 6210.921217173901, 6258.749650013949, 6267.467265282502, 6277.403646653561, 5486.149646804752, 5704.686242256633, 5713.200151978726, 5733.168690539932, 5742.08126699726, 5234.450382709761, 5302.09864323066, 5338.652987555796, 5351.464853514617, 5361.544268737123, 5220.190870415092, 6204.100405441276, 6211.596246243979, 6221.129041934133, 6221.346758590374, 6106.72519736604, 6188.133661287334, 6272.341404152681, 6274.780382686584, 6281.937180628135, 6171.814845554641, 6187.907181392373, 6210.068489726396, 6221.626702397871, 6278.874422511615, 6150.600210986271, 6155.938440615537, 6161.44546990773, 6162.476189523941, 6168.537359698131, 6230.345813689497, 6230.470410015199, 6243.188385509855, 6258.092576576462, 6260.7495700171485, 5785.623934444247, 5906.084556893688, 5906.8186362726765, 5973.671448070398, 6023.759049638141, 5931.733072011856, 5964.045752679724, 5976.133460648095, 5983.790567330123, 5988.8802223956145, 5680.062789639828, 5824.388439213991, 5845.298564172156, 5848.356680765028, 5851.61964472308, 5785.479306862402, 5952.732127054206, 5962.165296858357, 5998.070299103646, 6056.6971651418135, 5823.505002074871, 5847.298324201123, 5853.531717462678, 5863.061910094535, 5875.618084824473], "value": 5624.007862165225, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [515.2451658320559, 526.184414158055, 535.4832586553974, 544.2190456694643, 564.1622191046296, 29940.550891736613, 41917.0, 43298.917527061836, 43382.783086084564, 43410.565894341045, 43999.560004399944, 44533.0, 44667.88330291744, 44675.1064978701, 44841.55158448414, 9539.09378609031, 9704.369216000938, 9713.931467538601, 9716.56275467602, 9732.464714440703, 9593.47235902025, 9706.272029597772, 9712.125908668218, 9720.562574684156, 9721.368111072794, 9661.903380966205, 9739.707808765714, 9739.977302383253, 9745.512724363789, 9755.658551950683, 9657.32398732089, 9703.272254580934, 9709.757256068584, 9718.222542196607, 9718.465484398355, 9579.0, 9658.9517052415, 9663.468509231989, 9665.516724163763, 9671.371360861593, 41590.58409415897, 41778.79110604458, 41907.74276771702, 41953.37069943953, 42009.789951050356, 3473.3400653875938, 3609.476625889258, 3778.7732736035928, 4010.2981978153707, 4042.252183346061, 32240.032799016066, 33416.6658333416, 33568.49647255293, 33586.0, 33799.6620033799, 3220.388126256027, 3380.0, 3400.472926696366, 3423.5207071010077, 3425.383430982422, 528407.0, 529338.0, 529719.9999999962, 530462.0, 530491.0, 63601.36398636047, 63727.68136159382, 64006.67996659987, 64165.0, 64254.0, 66113.0, 67010.99483507653, 67070.99393509101, 67079.0, 67091.66454167698, 17010.319587216374, 17265.050422226897, 17274.913625432044, 17278.0, 17312.740308895376, 13202.0, 13266.800997985038, 13285.26931018784, 13287.269200193889, 13287.468501259842, 17119.315227391013, 17656.293748249926, 17658.646827063385, 17722.645547088985, 17868.731969020475, 6120.449159575571, 7193.964030179815, 7246.565206087652, 7283.162436319785, 7351.558906465522, 866.5537248317122, 945.5272363818091, 952.0764858087643, 967.260046064761, 1040.6149724601946, 30001.549976750368, 30155.547666785016, 30395.936142234914, 31169.68830311668, 31188.84405578003, 1110.2006555280186, 1114.6823155400673, 1129.836173754801, 1144.8683401408778, 1166.2652528906735, 15328.616784580474, 15517.379304827906, 15681.843181568267, 15829.97105188159, 15875.12686802237, 689740.5512972403, 695058.0, 695522.0, 696447.0, 698279.0, 50920.0, 60496.0, 61785.38214617886, 61976.69011654913, 62065.0, 1119.3843386137592, 1337.625464869838, 1413.8303403591635, 1436.9497067602583, 1470.9043912145676, 30358.69641303559, 30707.0, 31629.8418507906, 31760.0, 31772.841135794173, 51980.74009630079, 52605.473945260055, 52767.73616131895, 52814.0, 52882.735586321825, 552453.0, 571789.0, 571963.0, 572691.0, 572802.0, 34800.129996749274, 35737.0, 40686.57596984092, 41793.58206417897, 41998.1600367997, 47366.0, 49246.50753492419, 49463.752681236365, 50381.99236015332, 50513.74743126408, 38378.80810596041, 39885.80057099696, 40160.0, 40957.180856383304, 41543.58456415397, 40367.0, 41082.45386872853, 41566.376504353066, 41586.168276633696, 41916.58083419127, 38659.2268154641, 39253.42986280517, 39818.0, 39856.0, 39945.60054399419, 34743.82628086843, 34996.0, 35875.64124358723, 36010.0, 36048.8197559021, 25056.37133586311, 25247.87376063108, 25748.8712556436, 26016.79631100647, 26216.0], "value": 20826.073055321776, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.71641045368926, 377.53374582390745, 387.6201322703752, 388.4193131268753, 410.68992910352677, 15718.135502547355, 15954.71999202115, 19280.421587352368, 21832.781672183293, 21994.120235190614, 20623.89688051558, 21162.89418552905, 21785.891070544625, 21788.128474860987, 22002.44993875154, 5796.840631873642, 5866.5600079994065, 6057.878842423171, 6076.118962750398, 6107.7556897724035, 4218.282891908383, 4464.866054018385, 5988.550858685616, 6483.351664833526, 6804.18349798025, 6490.578112422678, 6718.193816741998, 6758.425533829631, 6782.796516104525, 6785.796426107225, 6681.064650948871, 6682.632455214961, 6709.36261055199, 6720.32796720324, 6720.596764194115, 5201.375834899798, 5537.889242215133, 5547.362053363834, 5831.125331200354, 5834.970825145847, 11436.0, 13628.727425451534, 13967.930160349133, 17603.559911002198, 26969.0, 2167.3931299236083, 2209.4255493571627, 2336.33414476875, 2342.7657234276435, 2425.7816796488223, 10077.647282345082, 10594.629187978384, 10865.51105200268, 12592.929600983927, 15248.771268430983, 1905.2569497895831, 1914.5052216027393, 1935.9516012099668, 2003.1086166655843, 2038.4699978005676, 116129.4193529027, 121197.39401303025, 124327.0, 126130.0, 129224.92612205254, 10278.22913281507, 10457.68626941193, 10905.5277537533, 11023.338599684044, 11859.051275897908, 14781.0, 14904.70190596182, 14910.776338354934, 14926.850731492546, 14929.17889516087, 8563.486190828446, 8747.212750852454, 8783.780405489913, 8850.380473366802, 8857.0, 8754.781130471662, 8759.12408759119, 8766.561671916379, 8901.376903616814, 8929.508877011696, 3807.2195199983935, 4605.907881842411, 4609.147307748078, 4619.330197121399, 4666.696664716783, 1604.374294025331, 1701.0823390343965, 1787.9463616091539, 1912.311567835578, 1917.3893929099345, 870.7474832298689, 918.9724308270762, 943.6791490893129, 980.7695191629962, 1098.1653943003216, 21111.366659000254, 21901.780982189975, 21965.90170491469, 22049.779502205092, 22111.0, 925.4447331600985, 960.4765402855461, 972.3339512434005, 990.6928852055886, 1007.3301254665565, 11186.608468703556, 11389.316641001566, 11428.0, 11450.599229026944, 11485.540578376938, 587475.0, 591245.0, 593201.0, 607970.0, 611746.0, 107652.0, 119195.40402298106, 121583.0, 121892.0, 126643.0, 979.6669132494986, 1012.9746756331298, 1017.8625885505362, 1038.444432228754, 1042.4943902207424, 16827.055265328763, 18331.633367332313, 18877.71683424777, 19671.508212294808, 19715.901420492806, 55920.70475791478, 56424.0, 57561.42438575561, 57755.71122144363, 57858.55353616193, 83359.16330876948, 83468.0, 84969.0, 85773.14226857902, 85986.57006714927, 6922.619255940972, 7474.327310542078, 7484.438667100098, 7538.736144234926, 7615.504992175491, 10302.536385862504, 13052.412641430969, 13140.60578182656, 13417.731645367234, 13461.932690336485, 11272.66182014541, 11468.025217856575, 11564.306141631529, 11645.825312620487, 11695.298282103102, 11307.71730706706, 11455.427228638702, 11524.481398337262, 11538.019268362283, 11733.941330293294, 11359.432028398713, 11531.481083351437, 11597.246178998337, 11699.2980421175, 11752.294862308288, 7489.737859174903, 7549.9245007549225, 7612.276833700789, 7636.541807491346, 7755.961220193863, 9393.154616084585, 11215.271007384494, 11265.662030139109, 11387.089032877515, 11464.484098215433], "value": 9640.53392794017, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.3599744117719, 1565.5146904459618, 1591.5066329437873, 1831.258340372149, 1966.0857701168964, 7777.883331750021, 8237.87643185352, 8517.53153576553, 8802.0, 8803.20771130598, 8613.655453781841, 8714.95642521788, 8760.518171500566, 8765.211130998208, 8779.253763430115, 2309.2956648222244, 2332.416895776056, 2337.555864385761, 2341.718993720747, 2343.121329501437, 2300.769923007694, 2326.220716060122, 2350.2949115265437, 2350.882455877208, 2353.188150088217, 2307.907683692659, 2325.534893021394, 2344.5076533927895, 2347.1902193743217, 2347.460084180631, 2291.9083236670513, 2331.463763334434, 2332.0322066342533, 2334.393057804983, 2334.4514039200776, 2313.5141620259596, 2319.2346525646494, 2320.153144102407, 2320.872352020638, 2326.3137374474572, 8774.912250877538, 8781.64873405063, 8854.86717699235, 8859.246964008067, 8862.158094980967, 5140.820071297524, 5326.547243484309, 5335.973320133413, 5365.785368585291, 5374.596905232084, 3144.308252184516, 3481.9651803481893, 3585.533880595532, 3639.6542328478754, 3642.9453558196647, 1060.294903888913, 1079.3092420850662, 1087.3258579680569, 1107.496089279384, 1109.2845114900845, 11539.480723367386, 11658.0, 11665.825012624817, 11665.941670291595, 11669.70825729355, 4341.9565804341555, 4431.0, 4570.245909424974, 4571.222892108283, 4585.9541404585525, 9170.220531254918, 9170.724878253663, 9197.172254497127, 9203.907960920438, 9213.401128926598, 4443.0, 5204.8959020819375, 5211.843644690665, 5215.087359712035, 5218.817341393033, 4328.48058233014, 4490.865274041784, 4673.345731597579, 4842.58837998767, 4875.829345972945, 3698.870539531157, 3724.1806802503406, 3787.768975083109, 3816.3702989007056, 3851.730378873452, 1128.0580715102817, 1135.0975974100609, 1137.3346592243465, 1146.810776221914, 1152.9538818447336, 645.5319893077575, 817.7056259746487, 927.4249965021729, 950.2065775077749, 991.509202944547, 5938.079597662369, 5944.7324870380935, 5955.821325360246, 5978.461938425563, 6198.566100373021, 988.9357191782511, 1001.6744558018528, 1006.436395618456, 1029.4852573713144, 1070.202698989258, 3469.930601387958, 3829.7702137871815, 3866.284737323604, 4066.779966010185, 4101.733387329814, 10084.74788130288, 10500.73748156287, 10639.787204255872, 10646.467676616137, 10662.200334974752, 10577.471126443646, 10603.310784799118, 10618.203634727271, 10677.305975111592, 10683.465826708634, 7995.120536740954, 8069.193080692001, 8213.425060245845, 8249.216324449168, 8262.628181731836, 5868.647881127146, 5953.583249172516, 5991.071383935497, 6001.159837622824, 6067.362926892612, 10014.148797352307, 10062.993700629879, 10068.949655251678, 10114.443705596263, 10144.188464922936, 9956.352837065568, 10107.848382274125, 10362.119219866094, 10367.688969330931, 10390.272680912263, 7576.545407275361, 7609.923900761143, 7703.922960770321, 7713.112992005991, 7737.1102323233545, 8267.958660206661, 8274.29668478186, 8318.043425006203, 8322.20939010793, 8367.372447066373, 7226.566406015656, 7327.413806895543, 7332.266773322625, 7364.521306115085, 7462.44031697614, 7184.0, 7193.604351760703, 7260.382867456326, 7307.890381644173, 7329.963350183215, 6952.270011648704, 7032.718691252293, 7091.2554181810165, 7105.75129870452, 7132.714691412285, 5066.923996139987, 5079.6698214615935, 5083.110455670317, 5121.769520371667, 5196.376434827845, 4955.603551715926, 5009.323741294878, 5068.417132029716, 5083.313752643494, 5249.89500209986], "value": 4559.402994288629, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.0803279914264, 311.23746820290296, 311.8674563310593, 317.8458447652888, 320.39445448103106, 5090.643654944155, 5144.22836574514, 5146.845594632157, 5176.0, 5192.766325515351, 5088.160453525174, 5096.643234973553, 5141.768620412081, 5155.845324640258, 5156.484351564851, 3739.9715078353397, 3780.319542482352, 3885.0, 3979.781112038837, 3980.2636512245326, 3291.358185153896, 3866.1881004988986, 3916.432117342998, 3981.6814654827544, 3982.880513584583, 3360.8741071740997, 3884.3784994400903, 3898.259330727167, 3928.941065884014, 3935.01281852664, 3153.4954407294954, 3543.2106786073127, 3603.027182660693, 3840.2511510255513, 3891.4357418174204, 2843.928901777472, 3424.2466657335344, 3427.417339052367, 3442.345954268681, 3445.931761154025, 4298.226319262531, 4814.724098114003, 5020.899582008339, 5039.269305950654, 5060.620453465968, 4238.660907127453, 4495.303227999665, 4519.276915693474, 4524.411826462571, 4528.275475923836, 4680.0, 4926.950730492685, 5391.0, 5416.458354164592, 5434.239206511092, 3354.2788300515263, 3557.8932632021083, 3751.568569614502, 3830.3296923038633, 3840.615938406165, 5335.399380185929, 5723.284589426322, 5725.685087320196, 5733.48062763368, 5756.568257380672, 3759.2669429460993, 3781.3949768036982, 3781.9432708509394, 3792.810359482015, 3801.790901500389, 3543.681068703829, 3549.18368775178, 3584.5160903278247, 3853.402722578005, 3895.8441662333757, 4455.710378825431, 4497.797599108048, 4497.8425755098415, 4505.594496495331, 4505.7521836298665, 4544.863654090383, 5244.889320485078, 5446.836594902159, 5483.259759932438, 5523.944760552424, 1254.3978890132787, 1272.6500212441467, 1280.7310464802497, 1313.3892739876003, 1316.776148054833, 734.3574372424129, 773.3078894389603, 791.6674996501483, 805.5247404031533, 819.2585709932521, 4626.930596040995, 4724.385829842098, 4808.519148085234, 5013.248012798036, 5089.4147173074825, 5134.2812006319145, 5577.27495425592, 5592.328920529562, 5609.5792815539, 5615.185798059259, 4716.221823399169, 5033.0, 5062.594992400599, 5067.290579318898, 5098.566621837112, 2759.5715770122165, 2911.243076800047, 2933.1640482462394, 2938.911832645024, 2954.704529547071, 4338.978305108454, 5607.271054762855, 5667.4615911488345, 5735.77056917727, 5758.798442054508, 5309.7079660618265, 5503.532199763065, 5719.113537401709, 5721.742521586539, 5723.685197314105, 4773.498782627774, 5459.508644222039, 5759.0, 5772.855678608068, 5799.5070419014855, 4839.516048395132, 5556.194351819045, 5561.777528898961, 5570.637908535932, 5574.9442505574425, 5244.239585260192, 5497.3952865184, 5514.228008078952, 5521.254630624975, 5521.944780552304, 4719.5752382285755, 5715.485606295453, 5724.084146536534, 5733.397993210653, 5734.885302293847, 3151.2909595340866, 3726.31063253299, 3732.5894151643843, 3733.8506459741307, 3741.569719482294, 5025.612833904012, 5239.904038384677, 5241.947580524146, 5549.2242482405245, 5647.576431767716, 4893.755312234445, 5381.596380271417, 5391.541718953933, 5403.756830942696, 5452.291202143696, 4358.259095953632, 4543.977280113711, 5106.0, 5331.733413329395, 5386.279928636014, 4516.525764794781, 4689.678609088523, 4874.830040790256, 4907.190313598359, 4999.550040496373, 4263.360495925512, 4382.605565498993, 4538.6369090473345, 5363.490468405495, 5387.92241551699, 4969.832089458902, 5092.217723796642, 5316.096263635269, 5336.71918739492, 5340.359156901197], "value": 3940.0260988639507, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.2759836757527, 837.8133072680307, 843.5402705525488, 855.6106971328045, 860.4794099569756, 15058.774118388234, 16312.673746525063, 16333.611643010343, 16352.509424717262, 17706.911465442656, 16572.50282491527, 17415.651686966285, 17426.47720568385, 17433.47699569012, 17460.214290356933, 704.2323866984985, 882.2765332427412, 889.6308032166643, 901.3555307954811, 901.7610333261691, 720.3840716187636, 757.522760660783, 883.6244596046687, 899.9505027223532, 917.9082091790834, 963.0032915932012, 964.1659964131011, 964.7588102974256, 965.6668449384986, 967.3760424526176, 745.6271864067966, 927.5455027036703, 951.5527701980023, 951.7668171298008, 951.923846092311, 708.0406049802494, 708.9432845372357, 709.3615745828744, 709.6203531110882, 710.0272626501674, 15921.044737315799, 16052.919735401365, 16055.839441605667, 16060.11669358185, 16080.43718469848, 14480.275986200753, 14623.70752584953, 14665.266736663125, 14708.705825883422, 14738.705225895423, 13315.600531984055, 13449.0, 13476.730465390638, 13506.392212350369, 13850.238236896967, 3687.3547129252543, 4227.668284490375, 4856.150173719585, 4915.483874193194, 4940.580050695695, 101237.0, 120557.99999999913, 122839.0, 123699.3815030919, 123720.0, 9547.379420337656, 11281.774364512828, 12053.879461205452, 12066.69833254158, 12205.511779528719, 24065.917033733524, 24073.51852962931, 24113.879430603087, 24119.0, 24160.637590436156, 6516.543841931019, 8300.045494768057, 8472.957635211907, 8561.529115898686, 8818.29453643707, 4423.911521769546, 4596.931046034312, 4656.767161641905, 4679.766011699401, 4759.381280433521, 3482.21650128719, 3611.837467313977, 3634.636536346397, 3634.927301454009, 3636.999825048133, 642.3351830855042, 654.228010947087, 661.9536632435779, 677.1772296659582, 678.6979793991626, 556.7661582135432, 582.3448620302161, 597.2534332084894, 685.5715178013742, 691.1636919327669, 6871.209810871714, 8140.871251111762, 8967.372283940062, 9306.162445379949, 9380.24958003358, 580.587782674306, 594.8958932186937, 647.8380404898776, 671.6775947545206, 672.4082807129695, 4524.140413321458, 4533.795979180879, 4535.206338890681, 4585.977070114628, 4621.722696638212, 136409.0, 141299.88050179256, 142657.28671356785, 144696.0, 146463.2676836609, 125139.0, 127731.36134319454, 127813.0, 128915.0, 132159.0, 109841.0, 110324.0, 112376.0, 112483.87516124734, 114230.42884785689, 7692.8461430772195, 8301.0, 8760.78098047554, 8922.55387230649, 8941.37410381293, 51464.0, 61205.08192377029, 62825.0, 63339.0, 63746.04380934197, 56663.71668141633, 66000.66999664971, 88131.0, 88673.15846245023, 91053.08946910712, 12166.939165304471, 12266.26402415858, 12328.75342493163, 12573.811392828931, 12738.617841464771, 12622.87377126217, 12703.872961270268, 12873.356332183166, 13283.070185086952, 13320.467181312639, 10538.367697938149, 11574.537018519164, 11932.582359617372, 12470.501179953064, 12477.313747743598, 11713.472893719632, 11997.100217483898, 12255.264684118982, 12412.56556020535, 12439.689007774878, 9878.357906736039, 12167.75664486723, 12516.311602861566, 12559.87440125587, 12605.873941260837, 9063.954680226556, 10334.914833942328, 10521.947390263, 10553.525091370753, 10711.196660250356, 10137.996858285005, 10648.201384896318, 10676.306040107369, 10856.077233435246, 10857.619983300547], "value": 7854.124148886455, "unit": "score"}], "name": "dromaeo_css", "value": 7362.944653715153, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 113.0, 113.0, 113.0, 111.0, 113.0, 112.0, 112.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 121.0, 105.0, 154.0, 108.0, 107.0, 128.0, 105.0, 137.0, 107.0], "value": 121.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 254.0, 186.0, 185.0, 183.0, 182.0, 183.0, 181.0, 182.0, 183.0], "value": 190.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 57.0, 106.0, 57.0, 57.0, 61.0, 63.0, 125.0, 57.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 86.0, 94.0, 71.0, 70.0, 67.0, 95.0, 68.0, 68.0, 124.0], "value": 82.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 88.0, 90.0, 86.0, 90.0, 90.0, 134.0, 87.0], "value": 93.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 94.0, 95.0, 100.0, 96.0, 96.0, 94.0], "value": 95.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 82.0, 84.0, 84.0, 83.0, 84.0, 85.0, 84.0, 86.0], "value": 83.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 55.0, 55.0, 55.0, 54.0, 55.0, 56.0, 55.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 74.0, 77.0, 71.0, 72.0, 71.0, 71.0, 70.0, 73.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 82.0, 83.0, 83.0, 82.0, 80.0, 84.0, 83.0, 85.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 148.0, 139.0, 135.0, 134.0, 134.0, 136.0, 135.0, 177.0, 136.0], "value": 141.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 224.0, 209.0, 169.0, 162.0, 164.0, 163.0, 162.0, 163.0, 162.0], "value": 174.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 65.0, 67.0, 66.0, 68.0, 67.0, 68.0, 64.0, 84.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1444.8999999999999, "alertThreshold": 2.0}]} 15:51:09 INFO - Return code: 0 15:51:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:51:09 INFO - # TBPL SUCCESS # 15:51:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:51:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:51:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:51:09 INFO - 2016-07-13 15:51:09.765 screenresolution[1667:9760] starting screenresolution argv=screenresolution get 15:51:09 INFO - 2016-07-13 15:51:09.768 screenresolution[1667:9760] Display 0: 1600x1200x32@60 15:51:09 INFO - 2016-07-13 15:51:09.774 screenresolution[1668:9763] starting screenresolution argv=screenresolution list 15:51:09 INFO - Available Modes on Display 0 15:51:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:51:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:51:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:51:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:51:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:51:10 INFO - Intel Iris: 15:51:10 INFO - Chipset Model: Intel Iris 15:51:10 INFO - Type: GPU 15:51:10 INFO - Bus: Built-In 15:51:10 INFO - VRAM (Dynamic, Max): 1536 MB 15:51:10 INFO - Vendor: Intel (0x8086) 15:51:10 INFO - Device ID: 0x0a2e 15:51:10 INFO - Revision ID: 0x0009 15:51:10 INFO - Displays: 15:51:10 INFO - Display: 15:51:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:51:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:51:10 INFO - Display Serial Number: 1600x1200 60 15:51:10 INFO - Main Display: Yes 15:51:10 INFO - Mirror: Off 15:51:10 INFO - Online: Yes 15:51:10 INFO - Rotation: Supported 15:51:10 INFO - Return code: 0 15:51:10 INFO - Running post-action listener: _resource_record_post_action 15:51:10 INFO - ##### 15:51:10 INFO - ##### Finished run-tests step (success) 15:51:10 INFO - ##### 15:51:10 INFO - Running post-run listener: _resource_record_post_run 15:51:10 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 37029376; Write bytes: 704787456; Read time: 238; Write time: 1935 15:51:10 INFO - TinderboxPrint: CPU usage
53.5% 15:51:10 INFO - TinderboxPrint: I/O read bytes / time
37,029,376 / 238 15:51:10 INFO - TinderboxPrint: I/O write bytes / time
704,787,456 / 1,935 15:51:10 INFO - TinderboxPrint: CPU idle
2,249.8 (46.5%) 15:51:10 INFO - TinderboxPrint: CPU system
967.2 (20.0%) 15:51:10 INFO - TinderboxPrint: CPU user
1,624.6 (33.6%) 15:51:10 INFO - TinderboxPrint: Swap in / out
458,342,400 / 0 15:51:10 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 407986688; Write bytes: 409233408; Read time: 30852; Write time: 856 15:51:10 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 34477568; Write bytes: 293993472; Read time: 214; Write time: 1073 15:51:10 INFO - Running post-run listener: _upload_blobber_files 15:51:10 INFO - Blob upload gear active. 15:51:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:51:10 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 15:51:10 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'] 15:51:10 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 15:51:10 INFO - (blobuploader) - INFO - Open directory for files ... 15:51:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:51:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:51:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:51:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:51:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:51:11 INFO - (blobuploader) - INFO - Done attempting. 15:51:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:51:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:51:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:51:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:51:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:51:12 INFO - (blobuploader) - INFO - Done attempting. 15:51:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:51:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:51:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:51:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:51:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:51:15 INFO - (blobuploader) - INFO - Done attempting. 15:51:15 INFO - (blobuploader) - INFO - Iteration through files over. 15:51:15 INFO - Return code: 0 15:51:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:51:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:51:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/844a47237ee71a200a75ac4c64284a85a8549181c77ed7cdd0ce6452f3cfeb30590f8b202e135f40084535200152e62d9ee61ae27600b0cab11f77d76d4d96f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/acd1882f5eae6eda3e3e34887cba98388a5f1803230a242f8e16f6319aa28993a9604196d76dec60e6480bfcbea14c2e9bed41320f81d83bf5635ac908142cff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9fa211f22b9a2a23817112d97fa028d5262a5ec09ed0ce5fd38656e72f0fffcd3360f9c50312f835f648e04a16a1558563ad81b39b4f0f05be2e0f788414bfab"} 15:51:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:51:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:51:15 INFO - Contents: 15:51:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/844a47237ee71a200a75ac4c64284a85a8549181c77ed7cdd0ce6452f3cfeb30590f8b202e135f40084535200152e62d9ee61ae27600b0cab11f77d76d4d96f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/acd1882f5eae6eda3e3e34887cba98388a5f1803230a242f8e16f6319aa28993a9604196d76dec60e6480bfcbea14c2e9bed41320f81d83bf5635ac908142cff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9fa211f22b9a2a23817112d97fa028d5262a5ec09ed0ce5fd38656e72f0fffcd3360f9c50312f835f648e04a16a1558563ad81b39b4f0f05be2e0f788414bfab"} 15:51:15 INFO - Running post-run listener: copy_logs_to_upload_dir 15:51:15 INFO - Copying logs to upload dir... 15:51:15 INFO - mkdir: /builds/slave/test/build/upload/logs 15:51:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.315314 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-13 15:51:15.382234) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 15:51:15.386462) ========= 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.GyEWuI71Ix/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.XH5EovbzYO/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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/844a47237ee71a200a75ac4c64284a85a8549181c77ed7cdd0ce6452f3cfeb30590f8b202e135f40084535200152e62d9ee61ae27600b0cab11f77d76d4d96f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/acd1882f5eae6eda3e3e34887cba98388a5f1803230a242f8e16f6319aa28993a9604196d76dec60e6480bfcbea14c2e9bed41320f81d83bf5635ac908142cff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9fa211f22b9a2a23817112d97fa028d5262a5ec09ed0ce5fd38656e72f0fffcd3360f9c50312f835f648e04a16a1558563ad81b39b4f0f05be2e0f788414bfab"} build_url:https://queue.taskcluster.net/v1/task/AP3KEPuzT26gqxI-1gLeNw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011182 build_url: 'https://queue.taskcluster.net/v1/task/AP3KEPuzT26gqxI-1gLeNw/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/844a47237ee71a200a75ac4c64284a85a8549181c77ed7cdd0ce6452f3cfeb30590f8b202e135f40084535200152e62d9ee61ae27600b0cab11f77d76d4d96f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/acd1882f5eae6eda3e3e34887cba98388a5f1803230a242f8e16f6319aa28993a9604196d76dec60e6480bfcbea14c2e9bed41320f81d83bf5635ac908142cff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9fa211f22b9a2a23817112d97fa028d5262a5ec09ed0ce5fd38656e72f0fffcd3360f9c50312f835f648e04a16a1558563ad81b39b4f0f05be2e0f788414bfab"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 15:51:15.422050) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 15:51:15.422418) ========= 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.GyEWuI71Ix/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.XH5EovbzYO/Listeners TMPDIR=/var/folders/mg/9gn67p3x3h52jpkl3qqzw4tw00000w/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.005073 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 15:51:15.470886) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 15:51:15.474234) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 15:51:15.474581) ========= ========= Total master_lag: 0.20 =========