builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0024 starttime: 1467785042.34 results: success (0) buildid: 20160705204124 builduid: 1ab00983ec734a6e97fd51941f833959 revision: a7d6bb9e7d12166470ad4210dd1f56b7b07d0dc8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 23:04:02.336785) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 23:04:02.337246) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 23:04:02.337530) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 23:04:02.420159) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 23:04:02.420495) ========= 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.4fIWY19zND/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.bPYU1HGIDG/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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-05 23:04:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.3M=0.001s 2016-07-05 23:04:02 (20.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.544885 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 23:04:02.987763) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 23:04:02.988115) ========= 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.4fIWY19zND/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.bPYU1HGIDG/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.020636 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 23:04:03.039176) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 23:04:03.039506) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a7d6bb9e7d12166470ad4210dd1f56b7b07d0dc8 --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 a7d6bb9e7d12166470ad4210dd1f56b7b07d0dc8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4fIWY19zND/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.bPYU1HGIDG/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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-05 23:04:03,114 truncating revision to first 12 chars 2016-07-05 23:04:03,114 Setting DEBUG logging. 2016-07-05 23:04:03,114 attempt 1/10 2016-07-05 23:04:03,114 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a7d6bb9e7d12?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 23:04:04,250 unpacking tar archive at: autoland-a7d6bb9e7d12/testing/mozharness/ program finished with exit code 0 elapsedTime=1.423460 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 23:04:04.488109) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 23:04:04.488386) ========= script_repo_revision: a7d6bb9e7d12166470ad4210dd1f56b7b07d0dc8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 23:04:04.488745) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 23:04:04.489017) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 23:04:04.506734) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-07-05 23:04:04.507017) ========= /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.4fIWY19zND/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.bPYU1HGIDG/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 23:04:04 INFO - MultiFileLogger online at 20160705 23:04:04 in /builds/slave/test 23:04:04 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 23:04:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:04:04 INFO - {'append_to_log': False, 23:04:04 INFO - 'base_work_dir': '/builds/slave/test', 23:04:04 INFO - 'blob_upload_branch': 'Autoland', 23:04:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:04:04 INFO - 'branch': 'Autoland', 23:04:04 INFO - 'buildbot_json_path': 'buildprops.json', 23:04:04 INFO - 'config_files': ('talos/mac_config.py',), 23:04:04 INFO - 'default_actions': ('clobber', 23:04:04 INFO - 'read-buildbot-config', 23:04:04 INFO - 'download-and-extract', 23:04:04 INFO - 'populate-webroot', 23:04:04 INFO - 'create-virtualenv', 23:04:04 INFO - 'install', 23:04:04 INFO - 'run-tests'), 23:04:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:04:04 INFO - 'download_minidump_stackwalk': True, 23:04:04 INFO - 'download_symbols': 'ondemand', 23:04:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:04:04 INFO - 'tooltool.py': '/tools/tooltool.py', 23:04:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:04:04 INFO - '/tools/misc-python/virtualenv.py')}, 23:04:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:04:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:04:04 INFO - 'installer_path': 'installer.exe', 23:04:04 INFO - 'log_level': 'info', 23:04:04 INFO - 'log_name': 'talos', 23:04:04 INFO - 'log_to_console': True, 23:04:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:04:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:04:04 INFO - 'opt_config_files': (), 23:04:04 INFO - 'pip_index': False, 23:04:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:04:04 INFO - 'cmd': ('bash', 23:04:04 INFO - '-c', 23:04:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:04:04 INFO - 'enabled': True, 23:04:04 INFO - 'halt_on_failure': False, 23:04:04 INFO - 'name': 'check_screen_resolution'},), 23:04:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:04:04 INFO - 'cmd': ('bash', 23:04:04 INFO - '-c', 23:04:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:04:04 INFO - 'enabled': True, 23:04:04 INFO - 'halt_on_failure': False, 23:04:04 INFO - 'name': 'check_screen_resolution'},), 23:04:04 INFO - 'run_cmd_checks_enabled': True, 23:04:04 INFO - 'sps_profile': False, 23:04:04 INFO - 'sps_profile_interval': 0, 23:04:04 INFO - 'suite': 'dromaeojs', 23:04:04 INFO - 'system_bits': '32', 23:04:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:04:04 INFO - 'title': 't-yosemite-r7-0024', 23:04:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:04:04 INFO - 'use_talos_json': True, 23:04:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:04:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:04:04 INFO - 'work_dir': 'build'} 23:04:04 INFO - ##### 23:04:04 INFO - ##### Running clobber step. 23:04:04 INFO - ##### 23:04:04 INFO - Running pre-action listener: _resource_record_pre_action 23:04:04 INFO - Running main action method: clobber 23:04:04 INFO - rmtree: /builds/slave/test/build 23:04:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:04:06 INFO - Running post-action listener: _resource_record_post_action 23:04:06 INFO - ##### 23:04:06 INFO - ##### Finished clobber step (success) 23:04:06 INFO - ##### 23:04:06 INFO - ##### 23:04:06 INFO - ##### Running read-buildbot-config step. 23:04:06 INFO - ##### 23:04:06 INFO - Running pre-action listener: _resource_record_pre_action 23:04:06 INFO - Running main action method: read_buildbot_config 23:04:06 INFO - Using buildbot properties: 23:04:06 INFO - { 23:04:06 INFO - "project": "", 23:04:06 INFO - "product": "firefox", 23:04:06 INFO - "script_repo_revision": "production", 23:04:06 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 23:04:06 INFO - "repository": "", 23:04:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 23:04:06 INFO - "buildid": "20160705204124", 23:04:06 INFO - "pgo_build": "False", 23:04:06 INFO - "basedir": "/builds/slave/test", 23:04:06 INFO - "buildnumber": 8, 23:04:06 INFO - "slavename": "t-yosemite-r7-0024", 23:04:06 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 23:04:06 INFO - "platform": "yosemite_r7", 23:04:06 INFO - "scheduler": "tests-autoland-macosx64-talos", 23:04:06 INFO - "branch": "autoland", 23:04:06 INFO - "repo_path": "integration/autoland", 23:04:06 INFO - "revision": "a7d6bb9e7d12166470ad4210dd1f56b7b07d0dc8", 23:04:06 INFO - "stage_platform": "macosx64", 23:04:06 INFO - "builduid": "1ab00983ec734a6e97fd51941f833959", 23:04:06 INFO - "slavebuilddir": "test" 23:04:06 INFO - } 23:04:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 23:04:06 INFO - Running post-action listener: _resource_record_post_action 23:04:06 INFO - ##### 23:04:06 INFO - ##### Finished read-buildbot-config step (success) 23:04:06 INFO - ##### 23:04:06 INFO - ##### 23:04:06 INFO - ##### Running download-and-extract step. 23:04:06 INFO - ##### 23:04:06 INFO - Running pre-action listener: _resource_record_pre_action 23:04:06 INFO - Running main action method: download_and_extract 23:04:06 INFO - mkdir: /builds/slave/test/build/tests 23:04:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:04:06 INFO - https://queue.taskcluster.net/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:04:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 23:04:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 23:04:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/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 23:04:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/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 23:04:07 INFO - Downloaded 1199 bytes. 23:04:07 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 23:04:07 INFO - Using the following test package requirements: 23:04:07 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 23:04:07 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:04:07 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 23:04:07 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:04:07 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 23:04:07 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:04:07 INFO - u'jsshell-mac.zip'], 23:04:07 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:04:07 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 23:04:07 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 23:04:07 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:04:07 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 23:04:07 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:04:07 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 23:04:07 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:04:07 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 23:04:07 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:04:07 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 23:04:07 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:04:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:04:07 INFO - https://queue.taskcluster.net/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:04:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:04:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:04:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/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 23:04:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/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 23:04:08 INFO - Downloaded 18804235 bytes. 23:04:08 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'] 23:04:08 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 23:04:11 INFO - Return code: 0 23:04:11 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 23:04:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:04:11 INFO - https://queue.taskcluster.net/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:04:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:04:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:04:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/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 23:04:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/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 23:04:13 INFO - Downloaded 18804235 bytes. 23:04:13 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'] 23:04:13 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 23:04:17 INFO - Return code: 0 23:04:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:04:17 INFO - https://queue.taskcluster.net/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:04:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 23:04:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 23:04:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/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 23:04:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/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 23:04:19 INFO - Downloaded 14403333 bytes. 23:04:19 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'] 23:04:19 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 23:04:19 INFO - Return code: 0 23:04:19 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')]} 23:04:19 INFO - https://queue.taskcluster.net/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 23:04:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:04:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:04:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 23:04:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/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 23:04:22 INFO - Downloaded 112532553 bytes. 23:04:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:04:22 INFO - mkdir: /builds/slave/test/properties 23:04:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:04:22 INFO - Writing to file /builds/slave/test/properties/build_url 23:04:22 INFO - Contents: 23:04:22 INFO - build_url:https://queue.taskcluster.net/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 23:04:23 INFO - Running post-action listener: _resource_record_post_action 23:04:23 INFO - Running post-action listener: set_extra_try_arguments 23:04:23 INFO - ##### 23:04:23 INFO - ##### Finished download-and-extract step (success) 23:04:23 INFO - ##### 23:04:23 INFO - ##### 23:04:23 INFO - ##### Running populate-webroot step. 23:04:23 INFO - ##### 23:04:23 INFO - Running pre-action listener: _resource_record_pre_action 23:04:23 INFO - Running main action method: populate_webroot 23:04:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:04:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:04:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:04:23 INFO - '--tppagecycles', 23:04:23 INFO - '7'], 23:04:23 INFO - 'tests': ['tsvgm']}}, 23:04:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:04:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:04:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:04:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:04:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:04:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:04:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:04:23 INFO - 'talos_options': ['--disable-e10s'], 23:04:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:04:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:04:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:04:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:04:23 INFO - 'talos_options': ['--disable-e10s'], 23:04:23 INFO - 'tests': ['damp', 'tps']}, 23:04:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:04:23 INFO - 'tests': ['damp', 'tps']}, 23:04:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:04:23 INFO - 'tests': ['dromaeo_dom']}, 23:04:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:04:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:04:23 INFO - 'tests': ['basic_compositor_video']}, 23:04:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:04:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:04:23 INFO - 'tests': ['a11yr', 23:04:23 INFO - 'ts_paint', 23:04:23 INFO - 'tpaint', 23:04:23 INFO - 'sessionrestore', 23:04:23 INFO - 'sessionrestore_no_auto_restore', 23:04:23 INFO - 'tabpaint']}, 23:04:23 INFO - 'other-e10s': {'tests': ['a11yr', 23:04:23 INFO - 'ts_paint', 23:04:23 INFO - 'tpaint', 23:04:23 INFO - 'sessionrestore', 23:04:23 INFO - 'sessionrestore_no_auto_restore', 23:04:23 INFO - 'tabpaint']}, 23:04:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:04:23 INFO - 'ts_paint', 23:04:23 INFO - 'tpaint', 23:04:23 INFO - 'sessionrestore', 23:04:23 INFO - 'sessionrestore_no_auto_restore', 23:04:23 INFO - 'tabpaint']}, 23:04:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:04:23 INFO - 'ts_paint', 23:04:23 INFO - 'tpaint', 23:04:23 INFO - 'sessionrestore', 23:04:23 INFO - 'sessionrestore_no_auto_restore', 23:04:23 INFO - 'tabpaint']}, 23:04:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:04:23 INFO - 'tests': ['a11yr', 23:04:23 INFO - 'ts_paint', 23:04:23 INFO - 'tpaint', 23:04:23 INFO - 'sessionrestore', 23:04:23 INFO - 'sessionrestore_no_auto_restore', 23:04:23 INFO - 'tabpaint']}, 23:04:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:04:23 INFO - 'tests': ['a11yr', 23:04:23 INFO - 'ts_paint', 23:04:23 INFO - 'tpaint', 23:04:23 INFO - 'sessionrestore', 23:04:23 INFO - 'sessionrestore_no_auto_restore', 23:04:23 INFO - 'tabpaint']}, 23:04:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:04:23 INFO - 'tests': ['tsvgx', 23:04:23 INFO - 'tsvgr_opacity', 23:04:23 INFO - 'tart', 23:04:23 INFO - 'tscrollx', 23:04:23 INFO - 'cart']}, 23:04:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:04:23 INFO - 'tsvgr_opacity', 23:04:23 INFO - 'tart', 23:04:23 INFO - 'tscrollx', 23:04:23 INFO - 'cart']}, 23:04:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:04:23 INFO - 'talos_options': ['--disable-e10s'], 23:04:23 INFO - 'tests': ['tp5o']}, 23:04:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:04:23 INFO - 'tests': ['tp5o']}, 23:04:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:04:23 INFO - 'talos_options': ['--disable-e10s', 23:04:23 INFO - '--xperf_path', 23:04:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:04:23 INFO - 'tests': ['tp5n']}, 23:04:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:04:23 INFO - 'talos_options': ['--xperf_path', 23:04:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:04:23 INFO - 'tests': ['tp5n']}}, 23:04:23 INFO - 'talos.zip': {'path': '', 23:04:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:04:23 INFO - Running post-action listener: _resource_record_post_action 23:04:23 INFO - ##### 23:04:23 INFO - ##### Finished populate-webroot step (success) 23:04:23 INFO - ##### 23:04:23 INFO - ##### 23:04:23 INFO - ##### Running create-virtualenv step. 23:04:23 INFO - ##### 23:04:23 INFO - Running pre-action listener: _resource_record_pre_action 23:04:23 INFO - Running main action method: create_virtualenv 23:04:23 INFO - Creating virtualenv /builds/slave/test/build/venv 23:04:23 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 23:04:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:04:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:04:23 INFO - Using real prefix '/tools/python27' 23:04:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:04:24 INFO - Installing distribute.............................................................................................................................................................................................done. 23:04:27 INFO - Installing pip.................done. 23:04:27 INFO - Return code: 0 23:04:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:04:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:04:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:04:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:04:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:27 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 0x10d5f5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d68a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d8d6d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d559d30>, '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 0x10d2bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d551b20>, '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.4fIWY19zND/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.bPYU1HGIDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 23:04:27 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 23:04:27 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 23:04:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4fIWY19zND/Render', 23:04:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:04:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:04:27 INFO - 'HOME': '/Users/cltbld', 23:04:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:04:27 INFO - 'LOGNAME': 'cltbld', 23:04:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:04:27 INFO - 'MOZ_NO_REMOTE': '1', 23:04:27 INFO - 'NO_EM_RESTART': '1', 23:04:27 INFO - 'PAGER': '/bin/cat', 23:04:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:04:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:04:27 INFO - 'PWD': '/builds/slave/test', 23:04:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:04:27 INFO - 'SHELL': '/bin/bash', 23:04:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bPYU1HGIDG/Listeners', 23:04:27 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 23:04:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:04:27 INFO - 'USER': 'cltbld', 23:04:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:04:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:04:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:04:27 INFO - 'XPC_FLAGS': '0x0', 23:04:27 INFO - 'XPC_SERVICE_NAME': '0', 23:04:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:04:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:04:27 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 23:04:27 INFO - Cleaning up... 23:04:27 INFO - Return code: 0 23:04:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:04:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:04:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:04:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:04:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:27 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 0x10d5f5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d68a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d8d6d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d559d30>, '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 0x10d2bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d551b20>, '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.4fIWY19zND/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.bPYU1HGIDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 23:04:27 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 23:04:27 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 23:04:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4fIWY19zND/Render', 23:04:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:04:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:04:27 INFO - 'HOME': '/Users/cltbld', 23:04:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:04:27 INFO - 'LOGNAME': 'cltbld', 23:04:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:04:27 INFO - 'MOZ_NO_REMOTE': '1', 23:04:27 INFO - 'NO_EM_RESTART': '1', 23:04:27 INFO - 'PAGER': '/bin/cat', 23:04:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:04:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:04:27 INFO - 'PWD': '/builds/slave/test', 23:04:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:04:27 INFO - 'SHELL': '/bin/bash', 23:04:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bPYU1HGIDG/Listeners', 23:04:27 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 23:04:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:04:27 INFO - 'USER': 'cltbld', 23:04:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:04:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:04:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:04:27 INFO - 'XPC_FLAGS': '0x0', 23:04:27 INFO - 'XPC_SERVICE_NAME': '0', 23:04:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:04:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:04:27 INFO - Downloading/unpacking psutil>=3.1.1 23:04:27 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 23:04:27 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 23:04:27 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 23:04:27 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 23:04:27 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 23:04:27 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 23:04:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:04:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:04:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:04:30 INFO - Installing collected packages: psutil 23:04:30 INFO - Running setup.py install for psutil 23:04:30 INFO - building 'psutil._psutil_osx' extension 23:04:30 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 23:04:31 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 23:04:31 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 23:04:31 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 23:04:31 INFO - building 'psutil._psutil_posix' extension 23:04:31 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 23:04:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:04:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:04:31 INFO - ^ 23:04:31 INFO - 1 warning generated. 23:04:31 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 23:04:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:04:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:04:31 INFO - Successfully installed psutil 23:04:31 INFO - Cleaning up... 23:04:31 INFO - Return code: 0 23:04:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:04:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:04:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:04:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:04:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:04:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:31 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 0x10d5f5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d68a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d8d6d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d559d30>, '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 0x10d2bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d551b20>, '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.4fIWY19zND/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.bPYU1HGIDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 23:04:31 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 23:04:31 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 23:04:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4fIWY19zND/Render', 23:04:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:04:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:04:31 INFO - 'HOME': '/Users/cltbld', 23:04:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:04:31 INFO - 'LOGNAME': 'cltbld', 23:04:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:04:31 INFO - 'MOZ_NO_REMOTE': '1', 23:04:31 INFO - 'NO_EM_RESTART': '1', 23:04:31 INFO - 'PAGER': '/bin/cat', 23:04:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:04:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:04:31 INFO - 'PWD': '/builds/slave/test', 23:04:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:04:31 INFO - 'SHELL': '/bin/bash', 23:04:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bPYU1HGIDG/Listeners', 23:04:31 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 23:04:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:04:31 INFO - 'USER': 'cltbld', 23:04:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:04:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:04:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:04:31 INFO - 'XPC_FLAGS': '0x0', 23:04:31 INFO - 'XPC_SERVICE_NAME': '0', 23:04:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:04:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:04:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:04:31 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 23:04:31 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 23:04:31 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 23:04:31 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 23:04:31 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 23:04:31 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 23:04:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:04:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:04:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:04:34 INFO - Installing collected packages: mozsystemmonitor 23:04:34 INFO - Running setup.py install for mozsystemmonitor 23:04:34 INFO - Successfully installed mozsystemmonitor 23:04:34 INFO - Cleaning up... 23:04:34 INFO - Return code: 0 23:04:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:04: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')]} 23:04:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:04:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04: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')]} 23:04:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:04:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:34 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 0x10d5f5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d68a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d8d6d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d559d30>, '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 0x10d2bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d551b20>, '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.4fIWY19zND/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.bPYU1HGIDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 23:04:34 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 23:04:34 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 23:04:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4fIWY19zND/Render', 23:04:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:04:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:04:34 INFO - 'HOME': '/Users/cltbld', 23:04:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:04:34 INFO - 'LOGNAME': 'cltbld', 23:04:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:04:34 INFO - 'MOZ_NO_REMOTE': '1', 23:04:34 INFO - 'NO_EM_RESTART': '1', 23:04:34 INFO - 'PAGER': '/bin/cat', 23:04:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:04:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:04:34 INFO - 'PWD': '/builds/slave/test', 23:04:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:04:34 INFO - 'SHELL': '/bin/bash', 23:04:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bPYU1HGIDG/Listeners', 23:04:34 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 23:04:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:04:34 INFO - 'USER': 'cltbld', 23:04:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:04:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:04:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:04:34 INFO - 'XPC_FLAGS': '0x0', 23:04:34 INFO - 'XPC_SERVICE_NAME': '0', 23:04:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:04:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:04:34 INFO - Downloading/unpacking jsonschema==2.5.1 23:04:34 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 23:04:34 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 23:04:34 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 23:04:34 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 23:04:34 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 23:04:34 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 23:04:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:04:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:04: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 23:04: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 23:04: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 23:04: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 23:04: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 23:04: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 23:04:37 INFO - Downloading functools32-3.2.3-2.tar.gz 23:04:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:04:37 INFO - warning: no files found matching '*.txt' 23:04:37 INFO - no previously-included directories found matching 'build' 23:04:37 INFO - no previously-included directories found matching 'dist' 23:04:37 INFO - no previously-included directories found matching '.git*' 23:04:37 INFO - Installing collected packages: jsonschema, functools32 23:04:37 INFO - Running setup.py install for functools32 23:04:38 INFO - warning: no files found matching '*.txt' 23:04:38 INFO - no previously-included directories found matching 'build' 23:04:38 INFO - no previously-included directories found matching 'dist' 23:04:38 INFO - no previously-included directories found matching '.git*' 23:04:38 INFO - Successfully installed jsonschema functools32 23:04:38 INFO - Cleaning up... 23:04:38 INFO - Return code: 0 23:04:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:04:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:04:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d5f5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d68a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d8d6d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d559d30>, '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 0x10d2bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d551b20>, '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.4fIWY19zND/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.bPYU1HGIDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 23:04:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:04:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4fIWY19zND/Render', 23:04:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:04:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:04:38 INFO - 'HOME': '/Users/cltbld', 23:04:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:04:38 INFO - 'LOGNAME': 'cltbld', 23:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:04:38 INFO - 'MOZ_NO_REMOTE': '1', 23:04:38 INFO - 'NO_EM_RESTART': '1', 23:04:38 INFO - 'PAGER': '/bin/cat', 23:04:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:04:38 INFO - 'PWD': '/builds/slave/test', 23:04:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:04:38 INFO - 'SHELL': '/bin/bash', 23:04:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bPYU1HGIDG/Listeners', 23:04:38 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 23:04:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:04:38 INFO - 'USER': 'cltbld', 23:04:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:04:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:04:38 INFO - 'XPC_FLAGS': '0x0', 23:04:38 INFO - 'XPC_SERVICE_NAME': '0', 23:04:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:04:38 INFO - Downloading/unpacking blobuploader==1.2.4 23:04:38 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 23:04:38 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 23:04:38 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 23:04:38 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 23:04:38 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 23:04:38 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 23:04:40 INFO - Downloading blobuploader-1.2.4.tar.gz 23:04:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:04:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:04:41 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 23:04:41 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 23:04:41 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 23:04:41 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 23:04:41 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 23:04:41 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 23:04:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:04:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:04:41 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 23:04:41 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 23:04:41 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 23:04:41 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 23:04:41 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 23:04:41 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 23:04:42 INFO - Downloading docopt-0.6.1.tar.gz 23:04:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:04:42 INFO - Installing collected packages: blobuploader, requests, docopt 23:04:42 INFO - Running setup.py install for blobuploader 23:04:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:04:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:04:42 INFO - Running setup.py install for requests 23:04:42 INFO - Running setup.py install for docopt 23:04:42 INFO - Successfully installed blobuploader requests docopt 23:04:42 INFO - Cleaning up... 23:04:43 INFO - Return code: 0 23:04:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:04: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')]} 23:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:04:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04: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')]} 23:04:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:04:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:43 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 0x10d5f5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d68a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d8d6d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d559d30>, '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 0x10d2bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d551b20>, '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.4fIWY19zND/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.bPYU1HGIDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 23:04:43 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 23:04:43 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 23:04:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4fIWY19zND/Render', 23:04:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:04:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:04:43 INFO - 'HOME': '/Users/cltbld', 23:04:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:04:43 INFO - 'LOGNAME': 'cltbld', 23:04:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:04:43 INFO - 'MOZ_NO_REMOTE': '1', 23:04:43 INFO - 'NO_EM_RESTART': '1', 23:04:43 INFO - 'PAGER': '/bin/cat', 23:04:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:04:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:04:43 INFO - 'PWD': '/builds/slave/test', 23:04:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:04:43 INFO - 'SHELL': '/bin/bash', 23:04:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bPYU1HGIDG/Listeners', 23:04:43 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 23:04:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:04:43 INFO - 'USER': 'cltbld', 23:04:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:04:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:04:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:04:43 INFO - 'XPC_FLAGS': '0x0', 23:04:43 INFO - 'XPC_SERVICE_NAME': '0', 23:04:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:04:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:04:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-k4tVCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:04:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-BXYAkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:04:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-WAd8YQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:04:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-525zTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:04:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-E6mPK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:04:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-5aUmX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:04:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Q2ra6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:04:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-gSNeQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:04:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-e_akwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:04:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-94NGcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:04:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-dncMSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:04:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-ridDbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:04:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-pkPOap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:04:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-oeBbYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:04:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-hpiaYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:04:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-EWWakm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:04:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-6zNuhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:04:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:04:45 INFO - Running setup.py install for manifestparser 23:04:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:04:45 INFO - Running setup.py install for mozcrash 23:04:45 INFO - Running setup.py install for mozdebug 23:04:45 INFO - Running setup.py install for mozdevice 23:04:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:04:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:04:45 INFO - Running setup.py install for mozfile 23:04:45 INFO - Running setup.py install for mozhttpd 23:04:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:04:45 INFO - Running setup.py install for mozinfo 23:04:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:04:46 INFO - Running setup.py install for mozInstall 23:04:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:04:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:04:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:04:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:04:46 INFO - Running setup.py install for mozleak 23:04:46 INFO - Running setup.py install for mozlog 23:04:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:04:46 INFO - Running setup.py install for moznetwork 23:04:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:04:46 INFO - Running setup.py install for mozprocess 23:04:46 INFO - Running setup.py install for mozprofile 23:04:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:04:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:04:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:04:47 INFO - Running setup.py install for mozrunner 23:04:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:04:47 INFO - Running setup.py install for mozscreenshot 23:04:47 INFO - Running setup.py install for moztest 23:04:47 INFO - Running setup.py install for mozversion 23:04:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:04:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:04:47 INFO - Cleaning up... 23:04:47 INFO - Return code: 0 23:04:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:04:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:04:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:04:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:47 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 0x10d5f5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d68a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d8d6d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d559d30>, '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 0x10d2bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d551b20>, '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.4fIWY19zND/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.bPYU1HGIDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 23:04:47 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 23:04:47 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 23:04:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4fIWY19zND/Render', 23:04:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:04:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:04:47 INFO - 'HOME': '/Users/cltbld', 23:04:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:04:47 INFO - 'LOGNAME': 'cltbld', 23:04:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:04:47 INFO - 'MOZ_NO_REMOTE': '1', 23:04:47 INFO - 'NO_EM_RESTART': '1', 23:04:47 INFO - 'PAGER': '/bin/cat', 23:04:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:04:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:04:47 INFO - 'PWD': '/builds/slave/test', 23:04:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:04:47 INFO - 'SHELL': '/bin/bash', 23:04:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bPYU1HGIDG/Listeners', 23:04:47 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 23:04:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:04:47 INFO - 'USER': 'cltbld', 23:04:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:04:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:04:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:04:47 INFO - 'XPC_FLAGS': '0x0', 23:04:47 INFO - 'XPC_SERVICE_NAME': '0', 23:04:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:04:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:04:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-5rparq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:04:48 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)) 23:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:04:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-LKphfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:04:48 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)) 23:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:04:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-6d26tH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:04:48 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)) 23:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:04:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-stU7OU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:04:48 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)) 23:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:04:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-64ztxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:04:48 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)) 23:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:04:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-bh21JG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:04:48 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)) 23:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:04:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-f8tiBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:04:48 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)) 23:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:04:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-l4p7JW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:04:48 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)) 23:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:04:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-9oyz4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:04:48 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)) 23:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:04:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-vO_jG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:04:49 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)) 23:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:04:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Yv3Ijj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:04:49 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)) 23:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:04:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Ht4bwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:04:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:04:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-deHzDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:04:49 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)) 23:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:04:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-7Kkuet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:04:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:04:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-s8KJKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:04:49 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)) 23:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:04:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-CMMYwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:04:49 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)) 23:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:04:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-mihKMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:04:49 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)) 23:04:49 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)) 23:04:49 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)) 23:04:49 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)) 23:04:49 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)) 23:04:49 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)) 23:04:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:04:49 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 23:04:49 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 23:04:49 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 23:04:49 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 23:04:49 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 23:04:49 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 23:04:52 INFO - Downloading blessings-1.6.tar.gz 23:04:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:04:52 INFO - Installing collected packages: blessings 23:04:52 INFO - Running setup.py install for blessings 23:04:52 INFO - Successfully installed blessings 23:04:52 INFO - Cleaning up... 23:04:52 INFO - Return code: 0 23:04:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:04:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:04:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:04:52 INFO - Reading from file tmpfile_stdout 23:04:52 INFO - Current package versions: 23:04:52 INFO - blessings == 1.6 23:04:52 INFO - blobuploader == 1.2.4 23:04:52 INFO - docopt == 0.6.1 23:04:52 INFO - functools32 == 3.2.3-2 23:04:52 INFO - jsonschema == 2.5.1 23:04:52 INFO - manifestparser == 1.1 23:04:52 INFO - mozInstall == 1.12 23:04:52 INFO - mozcrash == 0.17 23:04:52 INFO - mozdebug == 0.1 23:04:52 INFO - mozdevice == 0.48 23:04:52 INFO - mozfile == 1.2 23:04:52 INFO - mozhttpd == 0.7 23:04:52 INFO - mozinfo == 0.9 23:04:52 INFO - mozleak == 0.1 23:04:52 INFO - mozlog == 3.2 23:04:52 INFO - moznetwork == 0.27 23:04:52 INFO - mozprocess == 0.22 23:04:52 INFO - mozprofile == 0.28 23:04:52 INFO - mozrunner == 6.11 23:04:52 INFO - mozscreenshot == 0.1 23:04:52 INFO - mozsystemmonitor == 0.3 23:04:52 INFO - moztest == 0.7 23:04:52 INFO - mozversion == 1.4 23:04:52 INFO - psutil == 3.1.1 23:04:52 INFO - requests == 1.2.3 23:04:52 INFO - wsgiref == 0.1.2 23:04:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:04:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:04:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:04:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:52 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 0x10d5f5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d68a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d8d6d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d559d30>, '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 0x10d2bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d551b20>, '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.4fIWY19zND/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.bPYU1HGIDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 23:04:52 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 23:04:52 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 23:04:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4fIWY19zND/Render', 23:04:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:04:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:04:52 INFO - 'HOME': '/Users/cltbld', 23:04:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:04:52 INFO - 'LOGNAME': 'cltbld', 23:04:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:04:52 INFO - 'MOZ_NO_REMOTE': '1', 23:04:52 INFO - 'NO_EM_RESTART': '1', 23:04:52 INFO - 'PAGER': '/bin/cat', 23:04:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:04:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:04:52 INFO - 'PWD': '/builds/slave/test', 23:04:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:04:52 INFO - 'SHELL': '/bin/bash', 23:04:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bPYU1HGIDG/Listeners', 23:04:52 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 23:04:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:04:52 INFO - 'USER': 'cltbld', 23:04:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:04:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:04:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:04:52 INFO - 'XPC_FLAGS': '0x0', 23:04:52 INFO - 'XPC_SERVICE_NAME': '0', 23:04:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:04:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:04:53 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)) 23:04:53 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)) 23:04:53 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)) 23:04:53 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)) 23:04:53 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)) 23:04:53 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)) 23:04:53 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)) 23:04:53 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)) 23:04:53 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)) 23:04:53 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)) 23:04:53 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)) 23:04:53 INFO - Cleaning up... 23:04:53 INFO - Return code: 0 23:04:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:04: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')]} 23:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:04:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04: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')]} 23:04:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:04:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:04:53 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 0x10d5f5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d68a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6d8d6d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d559d30>, '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 0x10d2bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d551b20>, '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.4fIWY19zND/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.bPYU1HGIDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 23:04:53 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 23:04:53 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 23:04:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4fIWY19zND/Render', 23:04:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:04:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:04:53 INFO - 'HOME': '/Users/cltbld', 23:04:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:04:53 INFO - 'LOGNAME': 'cltbld', 23:04:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:04:53 INFO - 'MOZ_NO_REMOTE': '1', 23:04:53 INFO - 'NO_EM_RESTART': '1', 23:04:53 INFO - 'PAGER': '/bin/cat', 23:04:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:04:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:04:53 INFO - 'PWD': '/builds/slave/test', 23:04:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:04:53 INFO - 'SHELL': '/bin/bash', 23:04:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bPYU1HGIDG/Listeners', 23:04:53 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 23:04:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:04:53 INFO - 'USER': 'cltbld', 23:04:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:04:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:04:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:04:53 INFO - 'XPC_FLAGS': '0x0', 23:04:53 INFO - 'XPC_SERVICE_NAME': '0', 23:04:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:04:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:04:53 INFO - Cleaning up... 23:04:53 INFO - Return code: 0 23:04:53 INFO - Running post-action listener: _resource_record_post_action 23:04:53 INFO - Running post-action listener: _start_resource_monitoring 23:04:53 INFO - Starting resource monitoring. 23:04:53 INFO - ##### 23:04:53 INFO - ##### Finished create-virtualenv step (success) 23:04:53 INFO - ##### 23:04:53 INFO - ##### 23:04:53 INFO - ##### Running install step. 23:04:53 INFO - ##### 23:04:53 INFO - Running pre-action listener: _resource_record_pre_action 23:04:53 INFO - Running main action method: install 23:04:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:04:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:04:53 INFO - Reading from file tmpfile_stdout 23:04:53 INFO - Detecting whether we're running mozinstall >=1.0... 23:04:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:04:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:04:53 INFO - Reading from file tmpfile_stdout 23:04:53 INFO - Output received: 23:04:53 INFO - Usage: mozinstall [options] installer 23:04:53 INFO - Options: 23:04:53 INFO - -h, --help show this help message and exit 23:04:53 INFO - -d DEST, --destination=DEST 23:04:53 INFO - Directory to install application into. [default: 23:04:53 INFO - "/builds/slave/test"] 23:04:53 INFO - --app=APP Application being installed. [default: firefox] 23:04:53 INFO - mkdir: /builds/slave/test/build/application 23:04:53 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'] 23:04:53 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 23:05:28 INFO - Reading from file tmpfile_stdout 23:05:28 INFO - Output received: 23:05:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:05:28 INFO - Running post-action listener: _resource_record_post_action 23:05:28 INFO - ##### 23:05:28 INFO - ##### Finished install step (success) 23:05:28 INFO - ##### 23:05:28 INFO - ##### 23:05:28 INFO - ##### Running run-tests step. 23:05:28 INFO - ##### 23:05:28 INFO - Running pre-action listener: _resource_record_pre_action 23:05:28 INFO - Running main action method: run_tests 23:05:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:05:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:05:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:05:28 INFO - 2016-07-05 23:05:28.238 screenresolution[2209:26594] starting screenresolution argv=screenresolution get 23:05:28 INFO - 2016-07-05 23:05:28.240 screenresolution[2209:26594] Display 0: 1600x1200x32@60 23:05:28 INFO - 2016-07-05 23:05:28.246 screenresolution[2210:26596] starting screenresolution argv=screenresolution list 23:05:28 INFO - Available Modes on Display 0 23:05:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:05:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:05:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:05:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:05:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:05:28 INFO - Intel Iris: 23:05:28 INFO - Chipset Model: Intel Iris 23:05:28 INFO - Type: GPU 23:05:28 INFO - Bus: Built-In 23:05:28 INFO - VRAM (Dynamic, Max): 1536 MB 23:05:28 INFO - Vendor: Intel (0x8086) 23:05:28 INFO - Device ID: 0x0a2e 23:05:28 INFO - Revision ID: 0x0009 23:05:28 INFO - Displays: 23:05:28 INFO - Display: 23:05:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:05:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:05:28 INFO - Display Serial Number: 1600x1200 60 23:05:28 INFO - Main Display: Yes 23:05:28 INFO - Mirror: Off 23:05:28 INFO - Online: Yes 23:05:28 INFO - Rotation: Supported 23:05:28 INFO - Return code: 0 23:05:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:05:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:05:28 INFO - Python 2.7.3 23:05:28 INFO - Return code: 0 23:05:28 INFO - grabbing minidump binary from tooltool 23:05:28 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')]} 23:05:28 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 0x10d559d30>, '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 0x10d2bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d551b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:05:28 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 23:05:28 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 23:05:28 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 23:05:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:05:28 INFO - Return code: 0 23:05:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:05:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:05:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:05:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:05:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:05:28 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/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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 23:05:28 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/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0024 --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 23:05:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4fIWY19zND/Render', 23:05:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:28 INFO - 'HOME': '/Users/cltbld', 23:05:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:28 INFO - 'LOGNAME': 'cltbld', 23:05:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:05:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:28 INFO - 'MOZ_NO_REMOTE': '1', 23:05:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:05:28 INFO - 'NO_EM_RESTART': '1', 23:05:28 INFO - 'PAGER': '/bin/cat', 23:05:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:28 INFO - 'PWD': '/builds/slave/test', 23:05:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:05:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:28 INFO - 'SHELL': '/bin/bash', 23:05:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bPYU1HGIDG/Listeners', 23:05:28 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 23:05:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:28 INFO - 'USER': 'cltbld', 23:05:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:28 INFO - 'XPC_FLAGS': '0x0', 23:05:28 INFO - 'XPC_SERVICE_NAME': '0', 23:05:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:28 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/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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 23:05:28 INFO - mozversion application_buildid: 20160705204124 23:05:28 INFO - mozversion application_changeset: a7d6bb9e7d12166470ad4210dd1f56b7b07d0dc8 23:05:28 INFO - mozversion application_display_name: Nightly 23:05:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:05:28 INFO - mozversion application_name: Firefox 23:05:28 INFO - mozversion application_remotingname: firefox 23:05:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 23:05:28 INFO - mozversion application_vendor: Mozilla 23:05:28 INFO - mozversion application_version: 50.0a1 23:05:28 INFO - mozversion platform_buildid: 20160705204124 23:05:28 INFO - mozversion platform_changeset: a7d6bb9e7d12166470ad4210dd1f56b7b07d0dc8 23:05:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 23:05:28 INFO - mozversion platform_version: 50.0a1 23:05:28 INFO - using testdate: 1467785128 23:05:28 INFO - actual date: 1467785128 23:05:28 INFO - starting webserver on 'localhost:55819' 23:05:28 INFO - SUITE-START | Running 2 tests 23:05:28 INFO - TEST-START | dromaeo_css 23:05:28 INFO - operating with platform_type : mac_ 23:05:28 INFO - Initialising browser for dromaeo_css test... 23:05:28 INFO - TEST-INFO | started process 2220 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpRVz2pc/profile http://localhost:55819/getInfo.html) 23:05:31 INFO - PROCESS | 2220 | __metrics Screen width/height:1600/1200 23:05:31 INFO - PROCESS | 2220 | colorDepth:24 23:05:31 INFO - PROCESS | 2220 | Browser inner width/height: 1024/689 23:05:31 INFO - PROCESS | 2220 | __metrics 23:05:31 INFO - PROCESS | 2220 | 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" 23:05:31 INFO - PROCESS | 2220 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:05:31 INFO - TEST-INFO | 2220: exit 0 23:05:31 INFO - Browser initialized. 23:05:31 INFO - Running cycle 1/1 for dromaeo_css test... 23:05:31 INFO - TEST-INFO | started process 2222 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpRVz2pc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:05:43 INFO - PROCESS | 2222 | RSS: Main: 243634176 23:05:43 INFO - PROCESS | 2222 | 23:06:35 INFO - PROCESS | 2222 | 1467785195098 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] 23:08:47 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:55819/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55819/tests/dromaeo/cssquery-ext.html) 23:08:48 INFO - PROCESS | 2222 | RSS: Main: 383758336 23:08:48 INFO - PROCESS | 2222 | 23:11:51 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:55819/tests/dromaeo/cssquery-ext.html (next: http://localhost:55819/tests/dromaeo/cssquery-jquery.html) 23:11:52 INFO - PROCESS | 2222 | RSS: Main: 381857792 23:11:52 INFO - PROCESS | 2222 | 23:14:54 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:55819/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55819/tests/dromaeo/cssquery-mootools.html) 23:14:55 INFO - PROCESS | 2222 | RSS: Main: 373194752 23:14:55 INFO - PROCESS | 2222 | 23:17:57 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:55819/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55819/tests/dromaeo/cssquery-prototype.html) 23:17:58 INFO - PROCESS | 2222 | RSS: Main: 372756480 23:17:58 INFO - PROCESS | 2222 | 23:21:01 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:55819/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55819/tests/dromaeo/cssquery-yui.html) 23:21:01 INFO - PROCESS | 2222 | RSS: Main: 343552000 23:21:01 INFO - PROCESS | 2222 | 23:24:05 INFO - PROCESS | 2222 | Cycle 1(1): loaded http://localhost:55819/tests/dromaeo/cssquery-yui.html (next: http://localhost:55819/tests/dromaeo/cssquery-dojo.html) 23:24:05 INFO - PROCESS | 2222 | RSS: Main: 381132800 23:24:05 INFO - PROCESS | 2222 | 23:24:05 INFO - PROCESS | 2222 | __start_tp_report 23:24:05 INFO - PROCESS | 2222 | _x_x_mozilla_page_load 23:24:05 INFO - PROCESS | 2222 | _x_x_mozilla_page_load_details 23:24:05 INFO - PROCESS | 2222 | |i|pagename|runs| 23:24:05 INFO - PROCESS | 2222 | |0;dojo.html;3377.7128944039755;3381.7970921744695;3395.4737015762557;3426.5888093428794;3449.6377880322566;5695.914561281574;5744.9138262926035;5779.450952159548;5814.7674093036285;5828.300603927524;5713.942860571393;5788.160716696087;5848.766049358021;5852;5873.853153671161;4193.329067349225;5139.8458046258675;5206.062908676437;5224.111900976843;5316.548093412064;5330.92003619946;5336.67979921206;5356.223347614594;5378.542823859977;5386.138217885139;4552.954470455303;5021.397432308128;5148.073346797575;5196.220566914993;5212.7914883405;5242.030224408459;5243.68537887728;5245.737713114368;5248.029114613771;5249.632525723201;3797.411401232813;5131.102057139986;5184.688918664854;5208.427073021964;5225.059489291926;5739.856503587402;5780.855478613026;5796.101604251347;5835.679037652927;5848.122781582797;6199.690015499207;6278;6293.056041593752;6333.7466501339895;6338.651374174418;6146.907796383058;6264.718087686065;6287.276963149251;6296.338884417116;6303.401176888188;5738.913916291259;5794.855128621818;5797.159411885253;5950.404959504058;5971.522278217775;6267.091271765659;6326.620402775849;6362.745490180433;6367.872642547216;6388.936110638834;6243.344448832898;6244.656543890039;6247.781327653511;6257.030160325156;6259.6870156492;6087.621241152041;6112.108359148532;6260.248770147611;6269.843253918689;6302.621842689453;6248.5313601479165;6297.4017468340435;6299.0866324382705;6303.936960630335;6307.117003619497;6313.526485513605;6320.399562041599;6342.334054924167;6347.714352854133;6355.459785918156;4890.462049174588;4910.508949105061;4925.75371231437;4934.827281045146;5021.297018417425;4355.912881742347;4495.168393847149;4502.257127573979;4528.45658520973;4546.386237857913;5173.663711858717;5748.913766293509;5787.594868359259;5839.919614871263;5843.503302219274;6284.560080794392;6309.432151106423;6323.241211054703;6325.177726895474;6326.620402775849;5782.942170578324;5852.092925596539;5871.853203669943;5876.706164691748;5903.3801450847905;5364.329458817648;5424.674519528841;5428.972855135778;5457.399686034533;5463.3717122530625;6280.623162610258;6350.904736428957;6370.458511026429;6377.139086223486;6395.136656551399;6240.251169859646;6243.875122497434;6283.622982621057;6297.275813281532;6313.652749098844;6226.097215903759;6281.309056003927;6284.245890492987;6380.3619638035825;6385.914394552844;6178.320384757583;6305.054241863573;6305.4009869062365;6316.747330106744;6327.462165715781;6283.842903927439;6294.087357333253;6301.684915754286;6304.5271604628915;6313.2424109107205;5282.31329927107;5498.285222921155;6096.738354098959;6214.782482613267;6235.064740288993;6123.142760013513;6136.365905528456;6138.817583427312;6162;6168.290646575701;6079.848003799763;6086.087877025824;6086.253530685642;6096.557912315282;6132.049532322497;5260.76372052575;6049.848753781014;6082.939170608237;6171.228596425447;6218.347073557392;6048.909266361096;6105.786297479567;6118.969405152946;6120.17377654011;6136.7852125175405 23:24:05 INFO - PROCESS | 2222 | |1;ext.html;498.3322348053608;523.2517499975035;536.0645673300091;539.7409243563087;543.2665901033603;30868.845655771718;31220.907268245617;32702.836485817596;42731;44032.77983610085;44790.32814507785;45295.54704452954;45368.31947520781;45417.864553386185;45474;8250.298724608416;9895.950520247388;9914.355566888165;9915.454649994248;9916.25628077897;8758.781030474225;8941.776455588597;9696.563654635524;11737.354445505494;12068.879311206907;12137.696557586043;12205;12277.877221227807;12291.815622765665;12297.81553276697;8739.038705742363;12050.879491205063;12101.213421127686;12110.515579376814;12117.33354665493;9606.807863842754;11855.229410088317;11887.227330223508;12221.266723996589;12236.449359778831;42989.57010429887;43310.13379732388;43349.133017339795;43395.91510212238;43416;3006.4738670732754;3535.063208249816;3588.9102772430897;3689.2805902848954;3865.729398942075;32543.349133017444;32863.507047394305;33314.6668533314;33568.83215583931;33572;3155.8106513609255;3313.453280208763;3368.848401821899;3427.091820667525;3456.913577160591;522149;525474;525813.3709331467;526071;526133;66999.99500007504;67275;67694;67735;67795.98306025413;36033.8198309012;36061.27877442436;36130.096747580996;37838.81080594634;37904.24191516154;17571.912140439217;17615.823841761674;17703.645927081387;17754.467365978784;17781.644367112585;12071.637850864488;12319.69200769988;13247.933760331136;13316.933415333055;13323.533676321476;17136;20346.796532034787;20608.484787880123;20656;20664.48338791542;8130.390220733469;8163.632636531371;8214.671413143527;8225.547594882219;8233.711820086388;894.9239314658197;969.6363863551169;1018.0582960761266;1065.4619417194422;1094.2012330998416;31173.376532469225;31239;31347;31487.842560787507;31897;1075.5482697267166;1076.6716151573846;1125.201107213871;1140.1961617059953;1276.9233845969272;16691;17124.14379281031;17503.212355444037;17696.646067078844;17708.291668333124;499252;595041;597333;598136;600362;64221.35778642247;64327.35672643307;64339;64343.03485447722;64513.677431612545;1296.500847173845;1371.0197208995617;1420.2614640386942;1423.9074460160057;1459.5183589415467;28585.571216431355;32422.675773241965;32455;32539.83730081429;32588.022359329258;53792.731036344565;54232.45767542432;54717;54896.45103548913;55051.72474137764;554050;556645.2167739136;557185;559234;560124;23318.3004509865;23660.290191294287;24844.254672359857;27669.584956226074;27989.440211195255;49289.260661090826;50734.746326268134;51208.23187652114;51336.71658208575;51366.48633513617;31332.37335253328;38947.415788763756;41329;41643;42430;38721.8383448497;39127.804360978014;40987.7950610245;41336.1732765349;42159.78920105503;39035.6096439032;39561;39837.80081099576;39903;40012.399814003395;31005.534916975812;32635.836820815744;34663;35599.64400355963;36038;25970.35074123162;26125.65811163169;26132;26662.7333726668;26768.59847102334 23:24:05 INFO - PROCESS | 2222 | |2;jquery.html;362.4997503445245;385.1910986927452;394.33357625612706;398.02483914409703;416.98749037528864;15848.603784905383;18353.908230458845;21255.36233912985;21956.99999999998;21977.90110494477;21242.04410801514;21518.89240553799;21587.67618485724;21588.136474541;21595;5807.680577568232;5906.822795316137;6136.815895523141;6155.322914479404;6171;5963.075723262879;5982.970085149568;6039.788607398742;6043.365446628107;6058.818235452944;5731.312242530902;5898.705064746746;5902.675352855591;5904.291485021803;5928.88142237155;5739.885202295973;5979.970100149493;6008.218931538894;6033.668148251888;6045.183900173464;4477.843275485342;5210.739463026872;5211.1922651989;5227;5235.921461178085;25925.092621758147;25974.87012564925;26110;26415.226401227977;26493.73506264932;1920.3566805120302;2263.2757517594378;2318.8840557972035;2495;3045.8629361678777;10076.647317343932;10204.336718113298;10316.48417579118;10766.24636275461;11312.547498100066;1800.801911789702;1902.7906930237664;1913.2155816115326;1971.6746736788414;1980.3959792263354;117873;123306.38346808299;123645;124035.3798231003;124107.75892241052;9681.564329605184;11360.20478566509;11512.424378781028;11714.121440891968;11836.934675879213;14536.982411231113;14725.779113313309;14747.336369863382;14758.262086895613;14769.261536923112;8618.224359807522;8663.95668021656;8688.695895643623;8710.869336959951;8763.693270735494;7367.064067075073;8528.40301178924;8684.56577171139;8733.51965641883;8851.513166775889;4083.8973477161;4159.459270294903;4611.515790841981;4615.653825963066;4683.1336202802595;1897.9430617081234;1922.9807701923082;1940.417874637603;1943.3392646500258;1945.9416217513497;727.3376761908517;827.5903427803275;844.9197326254119;862.8662557303502;944.187998321443;21178.576428471653;21988.120475180815;22063.779362206493;22135.557288854132;22163;943.9339246252696;950.8573713942964;953.7234202081457;962.2782912815388;983.2527279267808;8607.226078148604;11588.536458541732;11712.418823458895;11714.472848721827;11720.296782193096;620389;627301;631454;640088;643127;117351;145833.27083364516;147162.26418867838;148598.25700871425;150266;1000.0699349604937;1002.588938535197;1005.9849102263472;1048.9213309001855;1051.3113910388709;19356;19632.607347852678;20379.116128677284;21054.26310079201;21312.680309795676;59069.40930590811;59624;59708;59828.70085649544;60739.69630151821;57811;62100.13699589019;80370;82811.5859420699;90908.18183636157;8138.83722325562;8175.632096555788;8207.097219305751;8400.075991640793;8443.282321002784;12404.379781010733;13259.403326850123;13301.866981330062;13689.86310136886;13759.587212383645;11434.542618295174;11554.94222528882;11768.470418831344;11800.645980620595;11830.231034982698;5169.508896654812;9779.630851680913;11216.943915280372;11267.380294083903;11558.075353971493;10134;11740.002099821611;11810;12011.357501349688;12054.939725301318;8235.752927411948;8290.751277461686;8293.460925039853;8375.30579939551;8557.058723540531;8270.297024752963;8912.598933047899;10497.370157790252;11777.234479758788;11803.527858885893 23:24:05 INFO - PROCESS | 2222 | |3;mootools.html;1537.469572997316;1579.0999130495616;1783.1440908363984;1896.37419651515;1927.3736035788363;8745.737627871158;9086.000539940618;9229.584668689908;9233.076692330764;9246.4452132872;8024.638891249893;8262.215089566496;8668.43655162414;8907.10928907109;9037.502937338444;2376.6435034744795;2393.3418309964813;2400.627902675088;2404.0744313439277;2414.1791790791126;2361.9409514762183;2378.8929498172624;2378.9048438062455;2381.9285421437385;2419.3104965084954;2131.5949969505823;2341.8829058547003;2399.7720216579396;2405.6872606561124;2407.542566912281;2391.5934291170543;2396.3769419950763;2410.0841680161425;2410.4937963027787;2419.4435279885547;2356.8468049576722;2387.212219967407;2390.486045500226;2395.2574701842486;2395.401149712572;8968;9028.8645670315;9033.503157326344;9038.954805225998;9047.819043619156;5276.92084618731;5446.210299506589;5471.945280547184;5475.863103422406;5479.150731636602;3557.5908770491465;3619.149499867529;3692.464592634053;3763.397856342999;3768.397056470979;1082.4154956323575;1093.775775965925;1101.260009185485;1115.9107271418347;1115.966521004371;12192.51229950801;12211.816822747665;12222.388880555936;12237.265764054186;12240.510379584806;4670.929936050962;4796.784144713496;4813.375535134969;4890.119778439915;4934.777934992862;10062.547185376676;10086.949565252127;10095.293329466867;10110.191184705205;10120.69637910849;5435.374931882805;5442.482964118402;5443.101888188484;5444.128939369682;5448.291722076105;4245.426867372865;4434.467863856294;4595.379623750818;4928.852134435972;4974.925376119361;4020.2763502569233;4029.49631296088;4129.401236820644;4166.125113726091;4166.479190101238;1082.5290998415715;1087.483445363462;1301.8307620009339;1321.7686904791624;1322.2529270961875;945.8061097475002;950.1543626172712;953.9046095390405;958.324381311174;995.049727510226;6070.514358851281;6071.2714474263375;6213.937860621336;6256.874862502725;6296.653684047421;432.7403557865256;438.7257963772642;444.34459172719977;1029.613894789454;1038.200585549123;3880.3209438348176;4083.9183216335505;4121.855735049259;4222.746635201897;4237.894052648709;10693.411862347646;11150.386728729998;11150.888491115147;11164.776704465865;11177.49701263445;10964.451777411097;11102;11124.777504449865;11132.220744547803;11134.944325278322;7981.401394895406;8066.274035336848;8103.068147163034;8116.310113640407;8118.2693557580105;5812.622179558315;5916.49709774674;6121.326654068138;6123.2958209804665;6230.968845155745;8552.187542183483;9445.535941929013;10094.747631308983;10353.689389318333;10496.107830834464;10364.170866330825;10533.736656583647;10653.786924261627;10684.83972740394;10707.0898973585;7660.616969151632;7698.499597526143;7743.4966727162555;7811.257930496594;7845.3723702104835;8404.831903362021;8433.072362040293;8544.829103418022;8555.272801811916;8577;7471.252874712485;7635.961820190863;7652.961735191288;7811;7814.57019863913;7435.851282974202;7516.549007059594;7517.812054698677;7656.770296891103;7682.884756728766;7186.029885965328;7219.711211551689;7242.493025488376;7244.963775181091;7258.637068146677;4964.4539100698175;5109.20807274873;5118.38579370478;5175.24041825781;5191.8182863599595;5190.506901844319;5229.111051121243;5246.632735708464;5309.442508536548;5360.035193665021 23:24:05 INFO - PROCESS | 2222 | |4;prototype.html;299.52226199212265;305.52795930287704;313.1419909448112;316.66908081055294;316.66908081055294;4634.721916684997;4816.975915120423;5356.268303165384;5432.831941132656;5579.888402231953;4756.571908528232;4829.90340193196;5449.482299181572;5482.972585137079;5614.550835933126;3639.090227443139;3806.695464362844;4176.415301857743;4189.895252618679;4191.455110835587;3708.332500149972;3950.1902110067513;4158.708890377675;4194.1479793740245;4197.622214000755;4031.415444760508;4107.17856428714;4155.210510003105;4163.375493675958;4178.623923846869;3394.96605033949;3404;4128.504579450455;4142.79286035697;4156.605122513356;2831.603575499432;3585.731070169748;3589.46158076288;3602.3155600435834;3642.5993140754495;5187.351581052369;5346.3960811756315;5374.301340825707;5428.050091234058;5461.15352120418;3918.8824335269987;4099.672026237924;4868.610511159099;4882.095153383731;4885.828995985124;5310.840674779763;5758.136279558058;5783.570900221917;5792.710364481759;5897.083447879456;2835.53213719736;3465.6707612776745;3811.790351530665;3980.8901282320417;3980.925150209456;5723.6246957512585;5945.64326140433;5995.790147344822;6025.9096113558335;6030.66831324277;3961.425593288957;3962.920741585152;3969.861054863066;3971.4042893566198;3976.741511801724;3351.6983471487683;4045;4116.538628786798;4122.793860307032;4131.586841315844;4376.7811609419405;4743.264793956942;4792.2092854678585;4817.494163112893;4830.2513110467935;5322.973385133049;5422.2072157065195;5658.321001479849;5784.4504772046585;5823.388544202798;1133.8412622232895;1255.5793809074062;1258.5595041735319;1396.1483495068;1404.8735613794809;783.4202690009351;822.2435359469183;828.5732847583499;866.8514218660174;869.7651634058768;4235.8442959995;4269.847141271839;4446.377507149002;4520.32195170727;4566.711967383479;4416.514183439819;4862.586680132157;4916.606671466346;4981.252812078144;5009.699418034929;4395.846145384896;4537.954620453819;4538.252772682541;4539.704919180309;4581.091007210379;2832.3202431416726;2906.3606006678497;3158.494640857498;3172.7461803056162;3184.633767116811;5237.73811309433;5917.852053698692;6192;6192.907106393408;6219.906701399483;5416.674999500043;5420.349558053059;5825.1844741736195;6130.693465326716;6246.6892546950185;5222.556082732935;5417.647852889629;6274.090256912722;6284.5600807943;6342.714577843916;5560.6080291021635;6057.849008688511;6058.757954619205;6083.087536869505;6108.35862234459;5456.263404440349;5973.970130149321;5979.730912108878;5982.4316689914385;5996.220491336098;5716.5998380113;5782.942170578409;5851.420116568417;5993.61041532299;6285.842853928689;3219.758518111104;3950.30869597825;3985.342418500915;3988.282109220368;3993.92012159761;5057.671251368649;5910.231669882888;6054.546908741959;6079.361667024898;6079.756809727562;4712.646551508719;4848.321235027029;5825.70871456434;5841.357450680337;5864.712762022729;5767.971160144172;5796.391378905154;5827.708614569339;5844.931411009255;5851.736671849691;4492.348609451677;5343.759530821045;5712.485876271156;5714.714264286686;5728.341240757366;5011.5489605935645;5827.79602713903;5848;5851.414858514285;5863.299643103453;4568.0407114505;5254.264402983509;5645.9631363575245;5719.942800571941;5749.8562535936935 23:24:05 INFO - PROCESS | 2222 | |5;yui.html;748.6369110981174;754.4530215593692;774.7443343696581;855.2680821336792;866.5450638414835;17582.73625895611;18306.359277425294;18392.908035459837;18420.447386578406;18505.7224141638;17090.65818683629;17170.570735731613;17223.22495487703;17237.82762172378;17257.568560785985;708.518207618819;871.9476831390106;897.1477096758073;897.905719899411;899.2266650680442;734.9963250183741;871.2289623683023;881.1276835932445;901.8827552418177;905;789.9960500197491;932.4451951089125;943.3113826906363;945;946.7680418297526;745.2063552316765;770.7533589251442;935.8596210568469;937.1144268666079;938.723076692374;685.2599192871687;686.6669665212368;686.8488932434856;703.3634560722554;705.1150805738816;15356.769648455282;15426.460073897471;15449.845501545065;15450.768238476432;15485.535433937;12367.010639148846;13741;13926.93036534811;13960.650983725489;14162;12714.74570508594;13237.470501179941;13286.20282783036;13368.665783355396;13514.748237163552;4611.792469338888;4674.532546745332;4771.427428708543;4840.9515904840855;4851.320815085891;104329;105914;106260.40609390865;106999.46500267526;108960;10066.496675166212;10516.947415263028;10680.145588352912;11160.553577856957;11400.973912347763;21503.139874405162;21517.89241053816;21556.676649850266;21559.56880862374;21616.243431479823;7886.526808391515;7982.880256796153;8135.796605084802;8160.306373958161;8205.302549283379;4523.570260825217;4583.060472603109;4657.976710116428;4658.324542941255;4824.638152138604;3562.323158599858;3710.109573702292;3710.9814450927574;3715.888523344304;3717.01499102738;671.1040760584674;677.5088061155722;677.6239187251123;692.8198668346165;693.594247365297;584.4944123333286;634.2135751667898;668.8696103584925;696.4289282788167;708.7377670261933;8642.222200001905;9120.817583648422;9350.345475816763;9497.620095196253;9645.565949532147;627.7049786600381;652.1880259077384;689.7689274093135;694.9687264073128;698.3749544157951;4594.104149690778;4618.538146185355;4624.907501849944;4631.861044168681;4714.622830173578;90125;90377.54811225901;90536;90799.54600227089;91216;130131;130373;133087;133093.33453332866;134582.32708836393;113910.43044784889;114654;115346.42326788312;116259;118010.40994795143;6909.930900690929;7742.806429839299;8076.353891688534;8923.821523569362;9029.142231487998;52158;53471.732641336544;64545;66781.33046673678;67430;78643.60678196572;78717;85570.1442985587;90119.09880901108;93029;13054.216746995211;13514.256715880721;13981.161130332213;14247.21640309793;14388.136711797326;12798.552050678181;15644.374225030871;16000.279987400361;16227.94518356303;16338.264778085253;14303.21332326732;14332.426702931765;14491.710165796414;14703.785147621034;14765.335559899615;13974.161550307013;14585.489507867174;14633.487827925972;14749.778753318495;15107.622309441913;14149.292535373395;14442.277886105443;14618.488352907598;14644.70710585761;14655.340509677304;11324.263922845319;11485.19603627738;11634.76730465403;11715.824262636237;11937.701557461132;11391.601293954673;11503.252288601214;11736.354500502553;11740.354280514312;11834.408279585814 23:24:05 INFO - PROCESS | 2222 | __end_tp_report 23:24:05 INFO - PROCESS | 2222 | __start_cc_report 23:24:05 INFO - PROCESS | 2222 | _x_x_mozilla_cycle_collect,1498 23:24:05 INFO - PROCESS | 2222 | __end_cc_report 23:24:05 INFO - PROCESS | 2222 | __startTimestamp1467786245643__endTimestamp 23:24:05 INFO - PROCESS | 2222 | 23:24:05 INFO - PROCESS | 2222 | ------- Summary: start ------- 23:24:05 INFO - PROCESS | 2222 | Number of tests: 6 23:24:05 INFO - PROCESS | 2222 | 23:24:05 INFO - PROCESS | 2222 | [#0] dojo.html Cycles:165 Average:5774.95 Median:6086.09 stddev:683.27 (11.2%) stddev-sans-first:658.98 23:24:05 INFO - PROCESS | 2222 | Values: 3377.7 3381.8 3395.5 3426.6 3449.6 5695.9 5744.9 5779.5 5814.8 5828.3 5713.9 5788.2 5848.8 5852.0 5873.9 4193.3 5139.8 5206.1 5224.1 5316.5 5330.9 5336.7 5356.2 5378.5 5386.1 4553.0 5021.4 5148.1 5196.2 5212.8 5242.0 5243.7 5245.7 5248.0 5249.6 3797.4 5131.1 5184.7 5208.4 5225.1 5739.9 5780.9 5796.1 5835.7 5848.1 6199.7 6278.0 6293.1 6333.7 6338.7 6146.9 6264.7 6287.3 6296.3 6303.4 5738.9 5794.9 5797.2 5950.4 5971.5 6267.1 6326.6 6362.7 6367.9 6388.9 6243.3 6244.7 6247.8 6257.0 6259.7 6087.6 6112.1 6260.2 6269.8 6302.6 6248.5 6297.4 6299.1 6303.9 6307.1 6313.5 6320.4 6342.3 6347.7 6355.5 4890.5 4910.5 4925.8 4934.8 5021.3 4355.9 4495.2 4502.3 4528.5 4546.4 5173.7 5748.9 5787.6 5839.9 5843.5 6284.6 6309.4 6323.2 6325.2 6326.6 5782.9 5852.1 5871.9 5876.7 5903.4 5364.3 5424.7 5429.0 5457.4 5463.4 6280.6 6350.9 6370.5 6377.1 6395.1 6240.3 6243.9 6283.6 6297.3 6313.7 6226.1 6281.3 6284.2 6380.4 6385.9 6178.3 6305.1 6305.4 6316.7 6327.5 6283.8 6294.1 6301.7 6304.5 6313.2 5282.3 5498.3 6096.7 6214.8 6235.1 6123.1 6136.4 6138.8 6162.0 6168.3 6079.8 6086.1 6086.3 6096.6 6132.0 5260.8 6049.8 6082.9 6171.2 6218.3 6048.9 6105.8 6119.0 6120.2 6136.8 23:24:05 INFO - PROCESS | 2222 | 23:24:05 INFO - PROCESS | 2222 | [#1] ext.html Cycles:180 Average:69522.49 Median:36049.64 stddev:147730.89 (409.8%) stddev-sans-first:148054.41 23:24:05 INFO - PROCESS | 2222 | Values: 498.3 523.3 536.1 539.7 543.3 30868.8 31220.9 32702.8 42731.0 44032.8 44790.3 45295.5 45368.3 45417.9 45474.0 8250.3 9896.0 9914.4 9915.5 9916.3 8758.8 8941.8 9696.6 11737.4 12068.9 12137.7 12205.0 12277.9 12291.8 12297.8 8739.0 12050.9 12101.2 12110.5 12117.3 9606.8 11855.2 11887.2 12221.3 12236.4 42989.6 43310.1 43349.1 43395.9 43416.0 3006.5 3535.1 3588.9 3689.3 3865.7 32543.3 32863.5 33314.7 33568.8 33572.0 3155.8 3313.5 3368.8 3427.1 3456.9 522149.0 525474.0 525813.4 526071.0 526133.0 67000.0 67275.0 67694.0 67735.0 67796.0 36033.8 36061.3 36130.1 37838.8 37904.2 17571.9 17615.8 17703.6 17754.5 17781.6 12071.6 12319.7 13247.9 13316.9 13323.5 17136.0 20346.8 20608.5 20656.0 20664.5 8130.4 8163.6 8214.7 8225.5 8233.7 894.9 969.6 1018.1 1065.5 1094.2 31173.4 31239.0 31347.0 31487.8 31897.0 1075.5 1076.7 1125.2 1140.2 1276.9 16691.0 17124.1 17503.2 17696.6 17708.3 499252.0 595041.0 597333.0 598136.0 600362.0 64221.4 64327.4 64339.0 64343.0 64513.7 1296.5 1371.0 1420.3 1423.9 1459.5 28585.6 32422.7 32455.0 32539.8 32588.0 53792.7 54232.5 54717.0 54896.5 55051.7 554050.0 556645.2 557185.0 559234.0 560124.0 23318.3 23660.3 24844.3 27669.6 27989.4 49289.3 50734.7 51208.2 51336.7 51366.5 31332.4 38947.4 41329.0 41643.0 42430.0 38721.8 39127.8 40987.8 41336.2 42159.8 39035.6 39561.0 39837.8 39903.0 40012.4 31005.5 32635.8 34663.0 35599.6 36038.0 25970.4 26125.7 26132.0 26662.7 26768.6 23:24:05 INFO - PROCESS | 2222 | 23:24:05 INFO - PROCESS | 2222 | [#2] jquery.html Cycles:180 Average:36722.76 Median:26262.61 stddev:105749.05 (402.7%) stddev-sans-first:106010.46 23:24:05 INFO - PROCESS | 2222 | Values: 362.5 385.2 394.3 398.0 417.0 15848.6 18353.9 21255.4 21957.0 21977.9 21242.0 21518.9 21587.7 21588.1 21595.0 5807.7 5906.8 6136.8 6155.3 6171.0 5963.1 5983.0 6039.8 6043.4 6058.8 5731.3 5898.7 5902.7 5904.3 5928.9 5739.9 5980.0 6008.2 6033.7 6045.2 4477.8 5210.7 5211.2 5227.0 5235.9 25925.1 25974.9 26110.0 26415.2 26493.7 1920.4 2263.3 2318.9 2495.0 3045.9 10076.6 10204.3 10316.5 10766.2 11312.5 1800.8 1902.8 1913.2 1971.7 1980.4 117873.0 123306.4 123645.0 124035.4 124107.8 9681.6 11360.2 11512.4 11714.1 11836.9 14537.0 14725.8 14747.3 14758.3 14769.3 8618.2 8664.0 8688.7 8710.9 8763.7 7367.1 8528.4 8684.6 8733.5 8851.5 4083.9 4159.5 4611.5 4615.7 4683.1 1897.9 1923.0 1940.4 1943.3 1945.9 727.3 827.6 844.9 862.9 944.2 21178.6 21988.1 22063.8 22135.6 22163.0 943.9 950.9 953.7 962.3 983.3 8607.2 11588.5 11712.4 11714.5 11720.3 620389.0 627301.0 631454.0 640088.0 643127.0 117351.0 145833.3 147162.3 148598.3 150266.0 1000.1 1002.6 1006.0 1048.9 1051.3 19356.0 19632.6 20379.1 21054.3 21312.7 59069.4 59624.0 59708.0 59828.7 60739.7 57811.0 62100.1 80370.0 82811.6 90908.2 8138.8 8175.6 8207.1 8400.1 8443.3 12404.4 13259.4 13301.9 13689.9 13759.6 11434.5 11554.9 11768.5 11800.6 11830.2 5169.5 9779.6 11216.9 11267.4 11558.1 10134.0 11740.0 11810.0 12011.4 12054.9 8235.8 8290.8 8293.5 8375.3 8557.1 8270.3 8912.6 10497.4 11777.2 11803.5 23:24:05 INFO - PROCESS | 2222 | 23:24:05 INFO - PROCESS | 2222 | [#3] mootools.html Cycles:180 Average:5855.71 Median:4633.15 stddev:3303.13 (71.3%) stddev-sans-first:3296.45 23:24:05 INFO - PROCESS | 2222 | Values: 1537.5 1579.1 1783.1 1896.4 1927.4 8745.7 9086.0 9229.6 9233.1 9246.4 8024.6 8262.2 8668.4 8907.1 9037.5 2376.6 2393.3 2400.6 2404.1 2414.2 2361.9 2378.9 2378.9 2381.9 2419.3 2131.6 2341.9 2399.8 2405.7 2407.5 2391.6 2396.4 2410.1 2410.5 2419.4 2356.8 2387.2 2390.5 2395.3 2395.4 8968.0 9028.9 9033.5 9039.0 9047.8 5276.9 5446.2 5471.9 5475.9 5479.2 3557.6 3619.1 3692.5 3763.4 3768.4 1082.4 1093.8 1101.3 1115.9 1116.0 12192.5 12211.8 12222.4 12237.3 12240.5 4670.9 4796.8 4813.4 4890.1 4934.8 10062.5 10086.9 10095.3 10110.2 10120.7 5435.4 5442.5 5443.1 5444.1 5448.3 4245.4 4434.5 4595.4 4928.9 4974.9 4020.3 4029.5 4129.4 4166.1 4166.5 1082.5 1087.5 1301.8 1321.8 1322.3 945.8 950.2 953.9 958.3 995.0 6070.5 6071.3 6213.9 6256.9 6296.7 432.7 438.7 444.3 1029.6 1038.2 3880.3 4083.9 4121.9 4222.7 4237.9 10693.4 11150.4 11150.9 11164.8 11177.5 10964.5 11102.0 11124.8 11132.2 11134.9 7981.4 8066.3 8103.1 8116.3 8118.3 5812.6 5916.5 6121.3 6123.3 6231.0 8552.2 9445.5 10094.7 10353.7 10496.1 10364.2 10533.7 10653.8 10684.8 10707.1 7660.6 7698.5 7743.5 7811.3 7845.4 8404.8 8433.1 8544.8 8555.3 8577.0 7471.3 7636.0 7653.0 7811.0 7814.6 7435.9 7516.5 7517.8 7656.8 7682.9 7186.0 7219.7 7242.5 7245.0 7258.6 4964.5 5109.2 5118.4 5175.2 5191.8 5190.5 5229.1 5246.6 5309.4 5360.0 23:24:05 INFO - PROCESS | 2222 | 23:24:05 INFO - PROCESS | 2222 | [#4] prototype.html Cycles:180 Average:4592.00 Median:4948.93 stddev:1456.11 (29.4%) stddev-sans-first:1424.10 23:24:05 INFO - PROCESS | 2222 | Values: 299.5 305.5 313.1 316.7 316.7 4634.7 4817.0 5356.3 5432.8 5579.9 4756.6 4829.9 5449.5 5483.0 5614.6 3639.1 3806.7 4176.4 4189.9 4191.5 3708.3 3950.2 4158.7 4194.1 4197.6 4031.4 4107.2 4155.2 4163.4 4178.6 3395.0 3404.0 4128.5 4142.8 4156.6 2831.6 3585.7 3589.5 3602.3 3642.6 5187.4 5346.4 5374.3 5428.1 5461.2 3918.9 4099.7 4868.6 4882.1 4885.8 5310.8 5758.1 5783.6 5792.7 5897.1 2835.5 3465.7 3811.8 3980.9 3980.9 5723.6 5945.6 5995.8 6025.9 6030.7 3961.4 3962.9 3969.9 3971.4 3976.7 3351.7 4045.0 4116.5 4122.8 4131.6 4376.8 4743.3 4792.2 4817.5 4830.3 5323.0 5422.2 5658.3 5784.5 5823.4 1133.8 1255.6 1258.6 1396.1 1404.9 783.4 822.2 828.6 866.9 869.8 4235.8 4269.8 4446.4 4520.3 4566.7 4416.5 4862.6 4916.6 4981.3 5009.7 4395.8 4538.0 4538.3 4539.7 4581.1 2832.3 2906.4 3158.5 3172.7 3184.6 5237.7 5917.9 6192.0 6192.9 6219.9 5416.7 5420.3 5825.2 6130.7 6246.7 5222.6 5417.6 6274.1 6284.6 6342.7 5560.6 6057.8 6058.8 6083.1 6108.4 5456.3 5974.0 5979.7 5982.4 5996.2 5716.6 5782.9 5851.4 5993.6 6285.8 3219.8 3950.3 3985.3 3988.3 3993.9 5057.7 5910.2 6054.5 6079.4 6079.8 4712.6 4848.3 5825.7 5841.4 5864.7 5768.0 5796.4 5827.7 5844.9 5851.7 4492.3 5343.8 5712.5 5714.7 5728.3 5011.5 5827.8 5848.0 5851.4 5863.3 4568.0 5254.3 5646.0 5719.9 5749.9 23:24:05 INFO - PROCESS | 2222 | 23:24:05 INFO - PROCESS | 2222 | [#5] yui.html Cycles:180 Average:23539.98 Median:4553.32 stddev:35440.47 (778.3%) stddev-sans-first:35498.58 23:24:05 INFO - PROCESS | 2222 | Values: 748.6 754.5 774.7 855.3 866.5 17582.7 18306.4 18392.9 18420.4 18505.7 17090.7 17170.6 17223.2 17237.8 17257.6 708.5 871.9 897.1 897.9 899.2 735.0 871.2 881.1 901.9 905.0 790.0 932.4 943.3 945.0 946.8 745.2 770.8 935.9 937.1 938.7 685.3 686.7 686.8 703.4 705.1 15356.8 15426.5 15449.8 15450.8 15485.5 12367.0 13741.0 13926.9 13960.7 14162.0 12714.7 13237.5 13286.2 13368.7 13514.7 4611.8 4674.5 4771.4 4841.0 4851.3 104329.0 105914.0 106260.4 106999.5 108960.0 10066.5 10516.9 10680.1 11160.6 11401.0 21503.1 21517.9 21556.7 21559.6 21616.2 7886.5 7982.9 8135.8 8160.3 8205.3 4523.6 4583.1 4658.0 4658.3 4824.6 3562.3 3710.1 3711.0 3715.9 3717.0 671.1 677.5 677.6 692.8 693.6 584.5 634.2 668.9 696.4 708.7 8642.2 9120.8 9350.3 9497.6 9645.6 627.7 652.2 689.8 695.0 698.4 4594.1 4618.5 4624.9 4631.9 4714.6 90125.0 90377.5 90536.0 90799.5 91216.0 130131.0 130373.0 133087.0 133093.3 134582.3 113910.4 114654.0 115346.4 116259.0 118010.4 6909.9 7742.8 8076.4 8923.8 9029.1 52158.0 53471.7 64545.0 66781.3 67430.0 78643.6 78717.0 85570.1 90119.1 93029.0 13054.2 13514.3 13981.2 14247.2 14388.1 12798.6 15644.4 16000.3 16227.9 16338.3 14303.2 14332.4 14491.7 14703.8 14765.3 13974.2 14585.5 14633.5 14749.8 15107.6 14149.3 14442.3 14618.5 14644.7 14655.3 11324.3 11485.2 11634.8 11715.8 11937.7 11391.6 11503.3 11736.4 11740.4 11834.4 23:24:05 INFO - PROCESS | 2222 | -------- Summary: end -------- 23:24:05 INFO - PROCESS | 2222 | 23:24:06 INFO - TEST-INFO | 2222: exit 0 23:24:06 INFO - TEST-OK | dromaeo_css | took 1117549ms 23:24:06 INFO - TEST-START | kraken 23:24:06 INFO - operating with platform_type : mac_ 23:24:06 INFO - Initialising browser for kraken test... 23:24:06 INFO - TEST-INFO | started process 2225 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpB4nC0y/profile http://localhost:55819/getInfo.html) 23:24:08 INFO - PROCESS | 2225 | __metrics Screen width/height:1600/1200 23:24:08 INFO - PROCESS | 2225 | colorDepth:24 23:24:08 INFO - PROCESS | 2225 | Browser inner width/height: 1024/689 23:24:08 INFO - PROCESS | 2225 | __metrics 23:24:08 INFO - PROCESS | 2225 | 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" 23:24:08 INFO - PROCESS | 2225 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:24:09 INFO - TEST-INFO | 2225: exit 0 23:24:09 INFO - Browser initialized. 23:24:09 INFO - Running cycle 1/1 for kraken test... 23:24:09 INFO - TEST-INFO | started process 2226 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpB4nC0y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:24:21 INFO - PROCESS | 2226 | RSS: Main: 244920320 23:24:21 INFO - PROCESS | 2226 | 23:24:24 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:55819/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55819/tests/kraken/driver.html?testName=audio-beat-detection) 23:24:25 INFO - PROCESS | 2226 | RSS: Main: 294318080 23:24:25 INFO - PROCESS | 2226 | 23:24:27 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:55819/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55819/tests/kraken/driver.html?testName=audio-dft) 23:24:28 INFO - PROCESS | 2226 | RSS: Main: 304861184 23:24:28 INFO - PROCESS | 2226 | 23:24:31 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:55819/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55819/tests/kraken/driver.html?testName=audio-fft) 23:24:32 INFO - PROCESS | 2226 | RSS: Main: 387932160 23:24:32 INFO - PROCESS | 2226 | 23:24:34 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:55819/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55819/tests/kraken/driver.html?testName=audio-oscillator) 23:24:35 INFO - PROCESS | 2226 | RSS: Main: 291430400 23:24:35 INFO - PROCESS | 2226 | 23:24:37 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:55819/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55819/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:24:37 INFO - PROCESS | 2226 | RSS: Main: 295596032 23:24:37 INFO - PROCESS | 2226 | 23:24:40 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:55819/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55819/tests/kraken/driver.html?testName=imaging-darkroom) 23:24:41 INFO - PROCESS | 2226 | RSS: Main: 333373440 23:24:41 INFO - PROCESS | 2226 | 23:24:44 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:55819/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55819/tests/kraken/driver.html?testName=imaging-desaturate) 23:24:44 INFO - PROCESS | 2226 | RSS: Main: 334397440 23:24:44 INFO - PROCESS | 2226 | 23:24:47 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:55819/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55819/tests/kraken/driver.html?testName=json-parse-financial) 23:24:48 INFO - PROCESS | 2226 | RSS: Main: 331550720 23:24:48 INFO - PROCESS | 2226 | 23:24:49 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:55819/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55819/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:24:50 INFO - PROCESS | 2226 | RSS: Main: 311971840 23:24:50 INFO - PROCESS | 2226 | 23:24:52 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:55819/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55819/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:24:53 INFO - PROCESS | 2226 | RSS: Main: 318816256 23:24:53 INFO - PROCESS | 2226 | 23:24:55 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:55819/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55819/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:24:56 INFO - PROCESS | 2226 | RSS: Main: 318750720 23:24:56 INFO - PROCESS | 2226 | 23:24:58 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:55819/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55819/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:24:59 INFO - PROCESS | 2226 | RSS: Main: 325476352 23:24:59 INFO - PROCESS | 2226 | 23:25:02 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:55819/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55819/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:25:03 INFO - PROCESS | 2226 | RSS: Main: 326885376 23:25:03 INFO - PROCESS | 2226 | 23:25:04 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:55819/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55819/tests/kraken/driver.html?testName=ai-astar) 23:25:05 INFO - PROCESS | 2226 | RSS: Main: 370204672 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | __start_tp_report 23:25:05 INFO - PROCESS | 2226 | _x_x_mozilla_page_load 23:25:05 INFO - PROCESS | 2226 | _x_x_mozilla_page_load_details 23:25:05 INFO - PROCESS | 2226 | |i|pagename|runs| 23:25:05 INFO - PROCESS | 2226 | |0;ai-astar;102;117;162;167;111;112;112;111;111;109 23:25:05 INFO - PROCESS | 2226 | |1;audio-beat-detection;139;107;106;158;110;109;130;106;160;138 23:25:05 INFO - PROCESS | 2226 | |2;audio-dft;177;184;187;185;183;183;184;185;183;185 23:25:05 INFO - PROCESS | 2226 | |3;audio-fft;80;75;58;87;59;59;62;63;93;56 23:25:05 INFO - PROCESS | 2226 | |4;audio-oscillator;85;83;92;69;71;67;95;70;67;99 23:25:05 INFO - PROCESS | 2226 | |5;imaging-gaussian-blur;88;87;88;85;87;85;89;85;87;85 23:25:05 INFO - PROCESS | 2226 | |6;imaging-darkroom;101;93;93;94;93;93;95;99;94;93 23:25:05 INFO - PROCESS | 2226 | |7;imaging-desaturate;87;122;134;90;85;83;100;119;86;86 23:25:05 INFO - PROCESS | 2226 | |8;json-parse-financial;56;55;54;55;54;54;55;53;53;54 23:25:05 INFO - PROCESS | 2226 | |9;json-stringify-tinderbox;66;70;79;77;70;99;73;69;69;71 23:25:05 INFO - PROCESS | 2226 | |10;stanford-crypto-aes;84;82;82;85;85;84;86;92;96;87 23:25:05 INFO - PROCESS | 2226 | |11;stanford-crypto-ccm;136;136;137;143;145;150;149;135;185;138 23:25:05 INFO - PROCESS | 2226 | |12;stanford-crypto-pbkdf2;164;163;164;163;161;164;222;200;163;161 23:25:05 INFO - PROCESS | 2226 | |13;stanford-crypto-sha256-iterative;67;65;66;69;79;82;85;84;60;63 23:25:05 INFO - PROCESS | 2226 | __end_tp_report 23:25:05 INFO - PROCESS | 2226 | __start_cc_report 23:25:05 INFO - PROCESS | 2226 | _x_x_mozilla_cycle_collect,2012 23:25:05 INFO - PROCESS | 2226 | __end_cc_report 23:25:05 INFO - PROCESS | 2226 | __startTimestamp1467786305202__endTimestamp 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | ------- Summary: start ------- 23:25:05 INFO - PROCESS | 2226 | Number of tests: 14 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | [#0] ai-astar Cycles:10 Average:121.40 Median:112.00 stddev:23.04 (20.6%) stddev-sans-first:23.35 23:25:05 INFO - PROCESS | 2226 | Values: 102.0 117.0 162.0 167.0 111.0 112.0 112.0 111.0 111.0 109.0 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:134.00 stddev:21.64 (16.1%) stddev-sans-first:22.46 23:25:05 INFO - PROCESS | 2226 | Values: 139.0 107.0 106.0 158.0 110.0 109.0 130.0 106.0 160.0 138.0 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.50 stddev:2.63 (1.4%) stddev-sans-first:1.32 23:25:05 INFO - PROCESS | 2226 | Values: 177.0 184.0 187.0 185.0 183.0 183.0 184.0 185.0 183.0 185.0 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | [#3] audio-fft Cycles:10 Average:69.20 Median:69.00 stddev:13.46 (19.5%) stddev-sans-first:13.70 23:25:05 INFO - PROCESS | 2226 | Values: 80.0 75.0 58.0 87.0 59.0 59.0 62.0 63.0 93.0 56.0 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:84.00 stddev:12.49 (14.9%) stddev-sans-first:13.10 23:25:05 INFO - PROCESS | 2226 | Values: 85.0 83.0 92.0 69.0 71.0 67.0 95.0 70.0 67.0 99.0 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.00 stddev:1.51 (1.7%) stddev-sans-first:1.51 23:25:05 INFO - PROCESS | 2226 | Values: 88.0 87.0 88.0 85.0 87.0 85.0 89.0 85.0 87.0 85.0 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:93.50 stddev:2.86 (3.1%) stddev-sans-first:1.96 23:25:05 INFO - PROCESS | 2226 | Values: 101.0 93.0 93.0 94.0 93.0 93.0 95.0 99.0 94.0 93.0 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:85.50 stddev:18.77 (21.9%) stddev-sans-first:19.38 23:25:05 INFO - PROCESS | 2226 | Values: 87.0 122.0 134.0 90.0 85.0 83.0 100.0 119.0 86.0 86.0 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.50 stddev:0.95 (1.7%) stddev-sans-first:0.78 23:25:05 INFO - PROCESS | 2226 | Values: 56.0 55.0 54.0 55.0 54.0 54.0 55.0 53.0 53.0 54.0 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | [#9] json-stringify-tinderbox Cycles:10 Average:74.30 Median:72.00 stddev:9.51 (13.2%) stddev-sans-first:9.60 23:25:05 INFO - PROCESS | 2226 | Values: 66.0 70.0 79.0 77.0 70.0 99.0 73.0 69.0 69.0 71.0 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:85.50 stddev:4.45 (5.2%) stddev-sans-first:4.64 23:25:05 INFO - PROCESS | 2226 | Values: 84.0 82.0 82.0 85.0 85.0 84.0 86.0 92.0 96.0 87.0 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:144.00 stddev:14.98 (10.4%) stddev-sans-first:15.49 23:25:05 INFO - PROCESS | 2226 | Values: 136.0 136.0 137.0 143.0 145.0 150.0 149.0 135.0 185.0 138.0 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.50 Median:164.00 stddev:20.97 (12.8%) stddev-sans-first:22.02 23:25:05 INFO - PROCESS | 2226 | Values: 164.0 163.0 164.0 163.0 161.0 164.0 222.0 200.0 163.0 161.0 23:25:05 INFO - PROCESS | 2226 | 23:25:05 INFO - PROCESS | 2226 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.00 Median:74.00 stddev:9.46 (12.8%) stddev-sans-first:9.86 23:25:05 INFO - PROCESS | 2226 | Values: 67.0 65.0 66.0 69.0 79.0 82.0 85.0 84.0 60.0 63.0 23:25:05 INFO - PROCESS | 2226 | -------- Summary: end -------- 23:25:05 INFO - PROCESS | 2226 | 23:25:06 INFO - TEST-INFO | 2226: exit 0 23:25:06 INFO - TEST-OK | kraken | took 59699ms 23:25:06 INFO - SUITE-END | took 1177s 23:25:06 INFO - Completed test suite (00:19:37) 23:25:06 INFO - javascript benchmark 23:25:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3377.7128944039755, 3381.7970921744695, 3395.4737015762557, 3426.5888093428794, 3449.6377880322566, 5695.914561281574, 5744.9138262926035, 5779.450952159548, 5814.7674093036285, 5828.300603927524, 5713.942860571393, 5788.160716696087, 5848.766049358021, 5852.0, 5873.853153671161, 4193.329067349225, 5139.8458046258675, 5206.062908676437, 5224.111900976843, 5316.548093412064, 5330.92003619946, 5336.67979921206, 5356.223347614594, 5378.542823859977, 5386.138217885139, 4552.954470455303, 5021.397432308128, 5148.073346797575, 5196.220566914993, 5212.7914883405, 5242.030224408459, 5243.68537887728, 5245.737713114368, 5248.029114613771, 5249.632525723201, 3797.411401232813, 5131.102057139986, 5184.688918664854, 5208.427073021964, 5225.059489291926, 5739.856503587402, 5780.855478613026, 5796.101604251347, 5835.679037652927, 5848.122781582797, 6199.690015499207, 6278.0, 6293.056041593752, 6333.7466501339895, 6338.651374174418, 6146.907796383058, 6264.718087686065, 6287.276963149251, 6296.338884417116, 6303.401176888188, 5738.913916291259, 5794.855128621818, 5797.159411885253, 5950.404959504058, 5971.522278217775, 6267.091271765659, 6326.620402775849, 6362.745490180433, 6367.872642547216, 6388.936110638834, 6243.344448832898, 6244.656543890039, 6247.781327653511, 6257.030160325156, 6259.6870156492, 6087.621241152041, 6112.108359148532, 6260.248770147611, 6269.843253918689, 6302.621842689453, 6248.5313601479165, 6297.4017468340435, 6299.0866324382705, 6303.936960630335, 6307.117003619497, 6313.526485513605, 6320.399562041599, 6342.334054924167, 6347.714352854133, 6355.459785918156, 4890.462049174588, 4910.508949105061, 4925.75371231437, 4934.827281045146, 5021.297018417425, 4355.912881742347, 4495.168393847149, 4502.257127573979, 4528.45658520973, 4546.386237857913, 5173.663711858717, 5748.913766293509, 5787.594868359259, 5839.919614871263, 5843.503302219274, 6284.560080794392, 6309.432151106423, 6323.241211054703, 6325.177726895474, 6326.620402775849, 5782.942170578324, 5852.092925596539, 5871.853203669943, 5876.706164691748, 5903.3801450847905, 5364.329458817648, 5424.674519528841, 5428.972855135778, 5457.399686034533, 5463.3717122530625, 6280.623162610258, 6350.904736428957, 6370.458511026429, 6377.139086223486, 6395.136656551399, 6240.251169859646, 6243.875122497434, 6283.622982621057, 6297.275813281532, 6313.652749098844, 6226.097215903759, 6281.309056003927, 6284.245890492987, 6380.3619638035825, 6385.914394552844, 6178.320384757583, 6305.054241863573, 6305.4009869062365, 6316.747330106744, 6327.462165715781, 6283.842903927439, 6294.087357333253, 6301.684915754286, 6304.5271604628915, 6313.2424109107205, 5282.31329927107, 5498.285222921155, 6096.738354098959, 6214.782482613267, 6235.064740288993, 6123.142760013513, 6136.365905528456, 6138.817583427312, 6162.0, 6168.290646575701, 6079.848003799763, 6086.087877025824, 6086.253530685642, 6096.557912315282, 6132.049532322497, 5260.76372052575, 6049.848753781014, 6082.939170608237, 6171.228596425447, 6218.347073557392, 6048.909266361096, 6105.786297479567, 6118.969405152946, 6120.17377654011, 6136.7852125175405], "value": 5730.530491343301, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.3322348053608, 523.2517499975035, 536.0645673300091, 539.7409243563087, 543.2665901033603, 30868.845655771718, 31220.907268245617, 32702.836485817596, 42731.0, 44032.77983610085, 44790.32814507785, 45295.54704452954, 45368.31947520781, 45417.864553386185, 45474.0, 8250.298724608416, 9895.950520247388, 9914.355566888165, 9915.454649994248, 9916.25628077897, 8758.781030474225, 8941.776455588597, 9696.563654635524, 11737.354445505494, 12068.879311206907, 12137.696557586043, 12205.0, 12277.877221227807, 12291.815622765665, 12297.81553276697, 8739.038705742363, 12050.879491205063, 12101.213421127686, 12110.515579376814, 12117.33354665493, 9606.807863842754, 11855.229410088317, 11887.227330223508, 12221.266723996589, 12236.449359778831, 42989.57010429887, 43310.13379732388, 43349.133017339795, 43395.91510212238, 43416.0, 3006.4738670732754, 3535.063208249816, 3588.9102772430897, 3689.2805902848954, 3865.729398942075, 32543.349133017444, 32863.507047394305, 33314.6668533314, 33568.83215583931, 33572.0, 3155.8106513609255, 3313.453280208763, 3368.848401821899, 3427.091820667525, 3456.913577160591, 522149.0, 525474.0, 525813.3709331467, 526071.0, 526133.0, 66999.99500007504, 67275.0, 67694.0, 67735.0, 67795.98306025413, 36033.8198309012, 36061.27877442436, 36130.096747580996, 37838.81080594634, 37904.24191516154, 17571.912140439217, 17615.823841761674, 17703.645927081387, 17754.467365978784, 17781.644367112585, 12071.637850864488, 12319.69200769988, 13247.933760331136, 13316.933415333055, 13323.533676321476, 17136.0, 20346.796532034787, 20608.484787880123, 20656.0, 20664.48338791542, 8130.390220733469, 8163.632636531371, 8214.671413143527, 8225.547594882219, 8233.711820086388, 894.9239314658197, 969.6363863551169, 1018.0582960761266, 1065.4619417194422, 1094.2012330998416, 31173.376532469225, 31239.0, 31347.0, 31487.842560787507, 31897.0, 1075.5482697267166, 1076.6716151573846, 1125.201107213871, 1140.1961617059953, 1276.9233845969272, 16691.0, 17124.14379281031, 17503.212355444037, 17696.646067078844, 17708.291668333124, 499252.0, 595041.0, 597333.0, 598136.0, 600362.0, 64221.35778642247, 64327.35672643307, 64339.0, 64343.03485447722, 64513.677431612545, 1296.500847173845, 1371.0197208995617, 1420.2614640386942, 1423.9074460160057, 1459.5183589415467, 28585.571216431355, 32422.675773241965, 32455.0, 32539.83730081429, 32588.022359329258, 53792.731036344565, 54232.45767542432, 54717.0, 54896.45103548913, 55051.72474137764, 554050.0, 556645.2167739136, 557185.0, 559234.0, 560124.0, 23318.3004509865, 23660.290191294287, 24844.254672359857, 27669.584956226074, 27989.440211195255, 49289.260661090826, 50734.746326268134, 51208.23187652114, 51336.71658208575, 51366.48633513617, 31332.37335253328, 38947.415788763756, 41329.0, 41643.0, 42430.0, 38721.8383448497, 39127.804360978014, 40987.7950610245, 41336.1732765349, 42159.78920105503, 39035.6096439032, 39561.0, 39837.80081099576, 39903.0, 40012.399814003395, 31005.534916975812, 32635.836820815744, 34663.0, 35599.64400355963, 36038.0, 25970.35074123162, 26125.65811163169, 26132.0, 26662.7333726668, 26768.59847102334], "value": 20750.33333270723, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.4997503445245, 385.1910986927452, 394.33357625612706, 398.02483914409703, 416.98749037528864, 15848.603784905383, 18353.908230458845, 21255.36233912985, 21956.99999999998, 21977.90110494477, 21242.04410801514, 21518.89240553799, 21587.67618485724, 21588.136474541, 21595.0, 5807.680577568232, 5906.822795316137, 6136.815895523141, 6155.322914479404, 6171.0, 5963.075723262879, 5982.970085149568, 6039.788607398742, 6043.365446628107, 6058.818235452944, 5731.312242530902, 5898.705064746746, 5902.675352855591, 5904.291485021803, 5928.88142237155, 5739.885202295973, 5979.970100149493, 6008.218931538894, 6033.668148251888, 6045.183900173464, 4477.843275485342, 5210.739463026872, 5211.1922651989, 5227.0, 5235.921461178085, 25925.092621758147, 25974.87012564925, 26110.0, 26415.226401227977, 26493.73506264932, 1920.3566805120302, 2263.2757517594378, 2318.8840557972035, 2495.0, 3045.8629361678777, 10076.647317343932, 10204.336718113298, 10316.48417579118, 10766.24636275461, 11312.547498100066, 1800.801911789702, 1902.7906930237664, 1913.2155816115326, 1971.6746736788414, 1980.3959792263354, 117873.0, 123306.38346808299, 123645.0, 124035.3798231003, 124107.75892241052, 9681.564329605184, 11360.20478566509, 11512.424378781028, 11714.121440891968, 11836.934675879213, 14536.982411231113, 14725.779113313309, 14747.336369863382, 14758.262086895613, 14769.261536923112, 8618.224359807522, 8663.95668021656, 8688.695895643623, 8710.869336959951, 8763.693270735494, 7367.064067075073, 8528.40301178924, 8684.56577171139, 8733.51965641883, 8851.513166775889, 4083.8973477161, 4159.459270294903, 4611.515790841981, 4615.653825963066, 4683.1336202802595, 1897.9430617081234, 1922.9807701923082, 1940.417874637603, 1943.3392646500258, 1945.9416217513497, 727.3376761908517, 827.5903427803275, 844.9197326254119, 862.8662557303502, 944.187998321443, 21178.576428471653, 21988.120475180815, 22063.779362206493, 22135.557288854132, 22163.0, 943.9339246252696, 950.8573713942964, 953.7234202081457, 962.2782912815388, 983.2527279267808, 8607.226078148604, 11588.536458541732, 11712.418823458895, 11714.472848721827, 11720.296782193096, 620389.0, 627301.0, 631454.0, 640088.0, 643127.0, 117351.0, 145833.27083364516, 147162.26418867838, 148598.25700871425, 150266.0, 1000.0699349604937, 1002.588938535197, 1005.9849102263472, 1048.9213309001855, 1051.3113910388709, 19356.0, 19632.607347852678, 20379.116128677284, 21054.26310079201, 21312.680309795676, 59069.40930590811, 59624.0, 59708.0, 59828.70085649544, 60739.69630151821, 57811.0, 62100.13699589019, 80370.0, 82811.5859420699, 90908.18183636157, 8138.83722325562, 8175.632096555788, 8207.097219305751, 8400.075991640793, 8443.282321002784, 12404.379781010733, 13259.403326850123, 13301.866981330062, 13689.86310136886, 13759.587212383645, 11434.542618295174, 11554.94222528882, 11768.470418831344, 11800.645980620595, 11830.231034982698, 5169.508896654812, 9779.630851680913, 11216.943915280372, 11267.380294083903, 11558.075353971493, 10134.0, 11740.002099821611, 11810.0, 12011.357501349688, 12054.939725301318, 8235.752927411948, 8290.751277461686, 8293.460925039853, 8375.30579939551, 8557.058723540531, 8270.297024752963, 8912.598933047899, 10497.370157790252, 11777.234479758788, 11803.527858885893], "value": 9716.563637378607, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1537.469572997316, 1579.0999130495616, 1783.1440908363984, 1896.37419651515, 1927.3736035788363, 8745.737627871158, 9086.000539940618, 9229.584668689908, 9233.076692330764, 9246.4452132872, 8024.638891249893, 8262.215089566496, 8668.43655162414, 8907.10928907109, 9037.502937338444, 2376.6435034744795, 2393.3418309964813, 2400.627902675088, 2404.0744313439277, 2414.1791790791126, 2361.9409514762183, 2378.8929498172624, 2378.9048438062455, 2381.9285421437385, 2419.3104965084954, 2131.5949969505823, 2341.8829058547003, 2399.7720216579396, 2405.6872606561124, 2407.542566912281, 2391.5934291170543, 2396.3769419950763, 2410.0841680161425, 2410.4937963027787, 2419.4435279885547, 2356.8468049576722, 2387.212219967407, 2390.486045500226, 2395.2574701842486, 2395.401149712572, 8968.0, 9028.8645670315, 9033.503157326344, 9038.954805225998, 9047.819043619156, 5276.92084618731, 5446.210299506589, 5471.945280547184, 5475.863103422406, 5479.150731636602, 3557.5908770491465, 3619.149499867529, 3692.464592634053, 3763.397856342999, 3768.397056470979, 1082.4154956323575, 1093.775775965925, 1101.260009185485, 1115.9107271418347, 1115.966521004371, 12192.51229950801, 12211.816822747665, 12222.388880555936, 12237.265764054186, 12240.510379584806, 4670.929936050962, 4796.784144713496, 4813.375535134969, 4890.119778439915, 4934.777934992862, 10062.547185376676, 10086.949565252127, 10095.293329466867, 10110.191184705205, 10120.69637910849, 5435.374931882805, 5442.482964118402, 5443.101888188484, 5444.128939369682, 5448.291722076105, 4245.426867372865, 4434.467863856294, 4595.379623750818, 4928.852134435972, 4974.925376119361, 4020.2763502569233, 4029.49631296088, 4129.401236820644, 4166.125113726091, 4166.479190101238, 1082.5290998415715, 1087.483445363462, 1301.8307620009339, 1321.7686904791624, 1322.2529270961875, 945.8061097475002, 950.1543626172712, 953.9046095390405, 958.324381311174, 995.049727510226, 6070.514358851281, 6071.2714474263375, 6213.937860621336, 6256.874862502725, 6296.653684047421, 432.7403557865256, 438.7257963772642, 444.34459172719977, 1029.613894789454, 1038.200585549123, 3880.3209438348176, 4083.9183216335505, 4121.855735049259, 4222.746635201897, 4237.894052648709, 10693.411862347646, 11150.386728729998, 11150.888491115147, 11164.776704465865, 11177.49701263445, 10964.451777411097, 11102.0, 11124.777504449865, 11132.220744547803, 11134.944325278322, 7981.401394895406, 8066.274035336848, 8103.068147163034, 8116.310113640407, 8118.2693557580105, 5812.622179558315, 5916.49709774674, 6121.326654068138, 6123.2958209804665, 6230.968845155745, 8552.187542183483, 9445.535941929013, 10094.747631308983, 10353.689389318333, 10496.107830834464, 10364.170866330825, 10533.736656583647, 10653.786924261627, 10684.83972740394, 10707.0898973585, 7660.616969151632, 7698.499597526143, 7743.4966727162555, 7811.257930496594, 7845.3723702104835, 8404.831903362021, 8433.072362040293, 8544.829103418022, 8555.272801811916, 8577.0, 7471.252874712485, 7635.961820190863, 7652.961735191288, 7811.0, 7814.57019863913, 7435.851282974202, 7516.549007059594, 7517.812054698677, 7656.770296891103, 7682.884756728766, 7186.029885965328, 7219.711211551689, 7242.493025488376, 7244.963775181091, 7258.637068146677, 4964.4539100698175, 5109.20807274873, 5118.38579370478, 5175.24041825781, 5191.8182863599595, 5190.506901844319, 5229.111051121243, 5246.632735708464, 5309.442508536548, 5360.035193665021], "value": 4664.961160673501, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.52226199212265, 305.52795930287704, 313.1419909448112, 316.66908081055294, 316.66908081055294, 4634.721916684997, 4816.975915120423, 5356.268303165384, 5432.831941132656, 5579.888402231953, 4756.571908528232, 4829.90340193196, 5449.482299181572, 5482.972585137079, 5614.550835933126, 3639.090227443139, 3806.695464362844, 4176.415301857743, 4189.895252618679, 4191.455110835587, 3708.332500149972, 3950.1902110067513, 4158.708890377675, 4194.1479793740245, 4197.622214000755, 4031.415444760508, 4107.17856428714, 4155.210510003105, 4163.375493675958, 4178.623923846869, 3394.96605033949, 3404.0, 4128.504579450455, 4142.79286035697, 4156.605122513356, 2831.603575499432, 3585.731070169748, 3589.46158076288, 3602.3155600435834, 3642.5993140754495, 5187.351581052369, 5346.3960811756315, 5374.301340825707, 5428.050091234058, 5461.15352120418, 3918.8824335269987, 4099.672026237924, 4868.610511159099, 4882.095153383731, 4885.828995985124, 5310.840674779763, 5758.136279558058, 5783.570900221917, 5792.710364481759, 5897.083447879456, 2835.53213719736, 3465.6707612776745, 3811.790351530665, 3980.8901282320417, 3980.925150209456, 5723.6246957512585, 5945.64326140433, 5995.790147344822, 6025.9096113558335, 6030.66831324277, 3961.425593288957, 3962.920741585152, 3969.861054863066, 3971.4042893566198, 3976.741511801724, 3351.6983471487683, 4045.0, 4116.538628786798, 4122.793860307032, 4131.586841315844, 4376.7811609419405, 4743.264793956942, 4792.2092854678585, 4817.494163112893, 4830.2513110467935, 5322.973385133049, 5422.2072157065195, 5658.321001479849, 5784.4504772046585, 5823.388544202798, 1133.8412622232895, 1255.5793809074062, 1258.5595041735319, 1396.1483495068, 1404.8735613794809, 783.4202690009351, 822.2435359469183, 828.5732847583499, 866.8514218660174, 869.7651634058768, 4235.8442959995, 4269.847141271839, 4446.377507149002, 4520.32195170727, 4566.711967383479, 4416.514183439819, 4862.586680132157, 4916.606671466346, 4981.252812078144, 5009.699418034929, 4395.846145384896, 4537.954620453819, 4538.252772682541, 4539.704919180309, 4581.091007210379, 2832.3202431416726, 2906.3606006678497, 3158.494640857498, 3172.7461803056162, 3184.633767116811, 5237.73811309433, 5917.852053698692, 6192.0, 6192.907106393408, 6219.906701399483, 5416.674999500043, 5420.349558053059, 5825.1844741736195, 6130.693465326716, 6246.6892546950185, 5222.556082732935, 5417.647852889629, 6274.090256912722, 6284.5600807943, 6342.714577843916, 5560.6080291021635, 6057.849008688511, 6058.757954619205, 6083.087536869505, 6108.35862234459, 5456.263404440349, 5973.970130149321, 5979.730912108878, 5982.4316689914385, 5996.220491336098, 5716.5998380113, 5782.942170578409, 5851.420116568417, 5993.61041532299, 6285.842853928689, 3219.758518111104, 3950.30869597825, 3985.342418500915, 3988.282109220368, 3993.92012159761, 5057.671251368649, 5910.231669882888, 6054.546908741959, 6079.361667024898, 6079.756809727562, 4712.646551508719, 4848.321235027029, 5825.70871456434, 5841.357450680337, 5864.712762022729, 5767.971160144172, 5796.391378905154, 5827.708614569339, 5844.931411009255, 5851.736671849691, 4492.348609451677, 5343.759530821045, 5712.485876271156, 5714.714264286686, 5728.341240757366, 5011.5489605935645, 5827.79602713903, 5848.0, 5851.414858514285, 5863.299643103453, 4568.0407114505, 5254.264402983509, 5645.9631363575245, 5719.942800571941, 5749.8562535936935], "value": 4125.462827814805, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.6369110981174, 754.4530215593692, 774.7443343696581, 855.2680821336792, 866.5450638414835, 17582.73625895611, 18306.359277425294, 18392.908035459837, 18420.447386578406, 18505.7224141638, 17090.65818683629, 17170.570735731613, 17223.22495487703, 17237.82762172378, 17257.568560785985, 708.518207618819, 871.9476831390106, 897.1477096758073, 897.905719899411, 899.2266650680442, 734.9963250183741, 871.2289623683023, 881.1276835932445, 901.8827552418177, 905.0, 789.9960500197491, 932.4451951089125, 943.3113826906363, 945.0, 946.7680418297526, 745.2063552316765, 770.7533589251442, 935.8596210568469, 937.1144268666079, 938.723076692374, 685.2599192871687, 686.6669665212368, 686.8488932434856, 703.3634560722554, 705.1150805738816, 15356.769648455282, 15426.460073897471, 15449.845501545065, 15450.768238476432, 15485.535433937, 12367.010639148846, 13741.0, 13926.93036534811, 13960.650983725489, 14162.0, 12714.74570508594, 13237.470501179941, 13286.20282783036, 13368.665783355396, 13514.748237163552, 4611.792469338888, 4674.532546745332, 4771.427428708543, 4840.9515904840855, 4851.320815085891, 104329.0, 105914.0, 106260.40609390865, 106999.46500267526, 108960.0, 10066.496675166212, 10516.947415263028, 10680.145588352912, 11160.553577856957, 11400.973912347763, 21503.139874405162, 21517.89241053816, 21556.676649850266, 21559.56880862374, 21616.243431479823, 7886.526808391515, 7982.880256796153, 8135.796605084802, 8160.306373958161, 8205.302549283379, 4523.570260825217, 4583.060472603109, 4657.976710116428, 4658.324542941255, 4824.638152138604, 3562.323158599858, 3710.109573702292, 3710.9814450927574, 3715.888523344304, 3717.01499102738, 671.1040760584674, 677.5088061155722, 677.6239187251123, 692.8198668346165, 693.594247365297, 584.4944123333286, 634.2135751667898, 668.8696103584925, 696.4289282788167, 708.7377670261933, 8642.222200001905, 9120.817583648422, 9350.345475816763, 9497.620095196253, 9645.565949532147, 627.7049786600381, 652.1880259077384, 689.7689274093135, 694.9687264073128, 698.3749544157951, 4594.104149690778, 4618.538146185355, 4624.907501849944, 4631.861044168681, 4714.622830173578, 90125.0, 90377.54811225901, 90536.0, 90799.54600227089, 91216.0, 130131.0, 130373.0, 133087.0, 133093.33453332866, 134582.32708836393, 113910.43044784889, 114654.0, 115346.42326788312, 116259.0, 118010.40994795143, 6909.930900690929, 7742.806429839299, 8076.353891688534, 8923.821523569362, 9029.142231487998, 52158.0, 53471.732641336544, 64545.0, 66781.33046673678, 67430.0, 78643.60678196572, 78717.0, 85570.1442985587, 90119.09880901108, 93029.0, 13054.216746995211, 13514.256715880721, 13981.161130332213, 14247.21640309793, 14388.136711797326, 12798.552050678181, 15644.374225030871, 16000.279987400361, 16227.94518356303, 16338.264778085253, 14303.21332326732, 14332.426702931765, 14491.710165796414, 14703.785147621034, 14765.335559899615, 13974.161550307013, 14585.489507867174, 14633.487827925972, 14749.778753318495, 15107.622309441913, 14149.292535373395, 14442.277886105443, 14618.488352907598, 14644.70710585761, 14655.340509677304, 11324.263922845319, 11485.19603627738, 11634.76730465403, 11715.824262636237, 11937.701557461132, 11391.601293954673, 11503.252288601214, 11736.354500502553, 11740.354280514312, 11834.408279585814], "value": 7989.155693476157, "unit": "score"}], "name": "dromaeo_css", "value": 7497.793327676749, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 117.0, 162.0, 167.0, 111.0, 112.0, 112.0, 111.0, 111.0, 109.0], "value": 121.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 107.0, 106.0, 158.0, 110.0, 109.0, 130.0, 106.0, 160.0, 138.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 184.0, 187.0, 185.0, 183.0, 183.0, 184.0, 185.0, 183.0, 185.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 58.0, 87.0, 59.0, 59.0, 62.0, 63.0, 93.0, 56.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 92.0, 69.0, 71.0, 67.0, 95.0, 70.0, 67.0, 99.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 85.0, 87.0, 85.0, 89.0, 85.0, 87.0, 85.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 93.0, 93.0, 94.0, 93.0, 93.0, 95.0, 99.0, 94.0, 93.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 122.0, 134.0, 90.0, 85.0, 83.0, 100.0, 119.0, 86.0, 86.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 55.0, 54.0, 54.0, 55.0, 53.0, 53.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 79.0, 77.0, 70.0, 99.0, 73.0, 69.0, 69.0, 71.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 82.0, 85.0, 85.0, 84.0, 86.0, 92.0, 96.0, 87.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 137.0, 143.0, 145.0, 150.0, 149.0, 135.0, 185.0, 138.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 163.0, 161.0, 164.0, 222.0, 200.0, 163.0, 161.0], "value": 172.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 69.0, 79.0, 82.0, 85.0, 84.0, 60.0, 63.0], "value": 72.0, "unit": "score"}], "name": "kraken", "value": 1465.7, "alertThreshold": 2.0}]} 23:25:06 INFO - Return code: 0 23:25:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:25:06 INFO - # TBPL SUCCESS # 23:25:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:25:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:25:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:25:06 INFO - 2016-07-05 23:25:06.532 screenresolution[2230:28465] starting screenresolution argv=screenresolution get 23:25:06 INFO - 2016-07-05 23:25:06.534 screenresolution[2230:28465] Display 0: 1600x1200x32@60 23:25:06 INFO - 2016-07-05 23:25:06.541 screenresolution[2231:28468] starting screenresolution argv=screenresolution list 23:25:06 INFO - Available Modes on Display 0 23:25:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:25:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:25:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:25:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:25:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:25:06 INFO - Intel Iris: 23:25:06 INFO - Chipset Model: Intel Iris 23:25:06 INFO - Type: GPU 23:25:06 INFO - Bus: Built-In 23:25:06 INFO - VRAM (Dynamic, Max): 1536 MB 23:25:06 INFO - Vendor: Intel (0x8086) 23:25:06 INFO - Device ID: 0x0a2e 23:25:06 INFO - Revision ID: 0x0009 23:25:06 INFO - Displays: 23:25:06 INFO - Display: 23:25:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:25:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:25:06 INFO - Display Serial Number: 1600x1200 60 23:25:06 INFO - Main Display: Yes 23:25:06 INFO - Mirror: Off 23:25:06 INFO - Online: Yes 23:25:06 INFO - Rotation: Supported 23:25:06 INFO - Return code: 0 23:25:06 INFO - Running post-action listener: _resource_record_post_action 23:25:06 INFO - ##### 23:25:06 INFO - ##### Finished run-tests step (success) 23:25:06 INFO - ##### 23:25:06 INFO - Running post-run listener: _resource_record_post_run 23:25:06 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 524288; Write bytes: 703821824; Read time: 6; Write time: 1973 23:25:06 INFO - TinderboxPrint: CPU usage
53.4% 23:25:06 INFO - TinderboxPrint: I/O read bytes / time
524,288 / 6 23:25:06 INFO - TinderboxPrint: I/O write bytes / time
703,821,824 / 1,973 23:25:06 INFO - TinderboxPrint: CPU idle
2,262.1 (46.6%) 23:25:06 INFO - TinderboxPrint: CPU system
968.2 (20.0%) 23:25:06 INFO - TinderboxPrint: CPU user
1,619.3 (33.4%) 23:25:06 INFO - TinderboxPrint: Swap in / out
407,060,480 / 0 23:25:06 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390058496; Write bytes: 385668608; Read time: 28978; Write time: 721 23:25:06 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 524288; Write bytes: 292827648; Read time: 6; Write time: 1205 23:25:07 INFO - Running post-run listener: _upload_blobber_files 23:25:07 INFO - Blob upload gear active. 23:25:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:25:07 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 23:25:07 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'] 23:25:07 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 23:25:07 INFO - (blobuploader) - INFO - Open directory for files ... 23:25:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:25:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:25:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:25:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:25:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:25:08 INFO - (blobuploader) - INFO - Done attempting. 23:25:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:25:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:25:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:25:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:25:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:25:09 INFO - (blobuploader) - INFO - Done attempting. 23:25:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:25:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:25:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:25:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:25:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:25:12 INFO - (blobuploader) - INFO - Done attempting. 23:25:12 INFO - (blobuploader) - INFO - Iteration through files over. 23:25:12 INFO - Return code: 0 23:25:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:25:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:25:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f5735f70579038ebfbfaa3fdf2c33b91026a774e0223e138553c9f03cb24d5ec8d7942be9cc7016b6958f94e9d808536046e684e73a641d075082550c2c25510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/96ef04c4736306033045bd1c56e8af2f22f3ceafb1681e5c8986c3a53794f4940be6b97d2daa0cc5cf8a01597d0ceab1ef7c68f7d9607968e0ef3314c9db249d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b024cff780e2850b27f21270ee1360ac99e0dbe859c5712c5cf3b5836449f7af85c336c72ad949939175ab979d98eb725a03279a7e32e68883dfbb04723a0c3c"} 23:25:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:25:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:25:12 INFO - Contents: 23:25:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f5735f70579038ebfbfaa3fdf2c33b91026a774e0223e138553c9f03cb24d5ec8d7942be9cc7016b6958f94e9d808536046e684e73a641d075082550c2c25510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/96ef04c4736306033045bd1c56e8af2f22f3ceafb1681e5c8986c3a53794f4940be6b97d2daa0cc5cf8a01597d0ceab1ef7c68f7d9607968e0ef3314c9db249d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b024cff780e2850b27f21270ee1360ac99e0dbe859c5712c5cf3b5836449f7af85c336c72ad949939175ab979d98eb725a03279a7e32e68883dfbb04723a0c3c"} 23:25:12 INFO - Running post-run listener: copy_logs_to_upload_dir 23:25:12 INFO - Copying logs to upload dir... 23:25:12 INFO - mkdir: /builds/slave/test/build/upload/logs 23:25:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.995917 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-07-05 23:25:12.539137) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 23:25:12.544490) ========= 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.4fIWY19zND/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.bPYU1HGIDG/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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/f5735f70579038ebfbfaa3fdf2c33b91026a774e0223e138553c9f03cb24d5ec8d7942be9cc7016b6958f94e9d808536046e684e73a641d075082550c2c25510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/96ef04c4736306033045bd1c56e8af2f22f3ceafb1681e5c8986c3a53794f4940be6b97d2daa0cc5cf8a01597d0ceab1ef7c68f7d9607968e0ef3314c9db249d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b024cff780e2850b27f21270ee1360ac99e0dbe859c5712c5cf3b5836449f7af85c336c72ad949939175ab979d98eb725a03279a7e32e68883dfbb04723a0c3c"} build_url:https://queue.taskcluster.net/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011693 build_url: 'https://queue.taskcluster.net/v1/task/HjtKd0j6TXKF8vt6S4aNQQ/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/f5735f70579038ebfbfaa3fdf2c33b91026a774e0223e138553c9f03cb24d5ec8d7942be9cc7016b6958f94e9d808536046e684e73a641d075082550c2c25510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/96ef04c4736306033045bd1c56e8af2f22f3ceafb1681e5c8986c3a53794f4940be6b97d2daa0cc5cf8a01597d0ceab1ef7c68f7d9607968e0ef3314c9db249d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b024cff780e2850b27f21270ee1360ac99e0dbe859c5712c5cf3b5836449f7af85c336c72ad949939175ab979d98eb725a03279a7e32e68883dfbb04723a0c3c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 23:25:12.577838) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 23:25:12.578174) ========= 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.4fIWY19zND/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.bPYU1HGIDG/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.005446 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 23:25:12.626840) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 23:25:12.627160) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 23:25:12.627483) ========= ========= Total master_lag: 0.18 =========