builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0338 starttime: 1468747671.23 results: success (0) buildid: 20160717001753 builduid: a92869d540e44da4bb7360d699ff9bc7 revision: ef5f932101e5b833b2429407cb0873471b4d764e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 02:27:51.228318) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 02:27:51.228698) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 02:27:51.228967) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 02:27:51.297034) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 02:27:51.297332) ========= 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.a2AOlmx3uO/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.UrCdrOePGk/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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-17 02:27:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.9M=0.001s 2016-07-17 02:27:51 (14.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.522233 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 02:27:51.846942) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 02:27:51.847286) ========= 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.a2AOlmx3uO/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.UrCdrOePGk/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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.025637 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 02:27:51.896482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 02:27:51.896862) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ef5f932101e5b833b2429407cb0873471b4d764e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ef5f932101e5b833b2429407cb0873471b4d764e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.a2AOlmx3uO/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.UrCdrOePGk/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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-17 02:27:51,978 truncating revision to first 12 chars 2016-07-17 02:27:51,978 Setting DEBUG logging. 2016-07-17 02:27:51,978 attempt 1/10 2016-07-17 02:27:51,979 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ef5f932101e5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-17 02:27:52,296 unpacking tar archive at: mozilla-central-ef5f932101e5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.573646 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 02:27:52.492301) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 02:27:52.492599) ========= script_repo_revision: ef5f932101e5b833b2429407cb0873471b4d764e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 02:27:52.492981) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 02:27:52.493267) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 02:27:52.511207) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 44 secs) (at 2016-07-17 02:27:52.511545) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.a2AOlmx3uO/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.UrCdrOePGk/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 02:27:52 INFO - MultiFileLogger online at 20160717 02:27:52 in /builds/slave/test 02:27:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:27:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:27:52 INFO - {'append_to_log': False, 02:27:52 INFO - 'base_work_dir': '/builds/slave/test', 02:27:52 INFO - 'blob_upload_branch': 'Firefox', 02:27:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:27:52 INFO - 'branch': 'Firefox', 02:27:52 INFO - 'buildbot_json_path': 'buildprops.json', 02:27:52 INFO - 'config_files': ('talos/mac_config.py',), 02:27:52 INFO - 'default_actions': ('clobber', 02:27:52 INFO - 'read-buildbot-config', 02:27:52 INFO - 'download-and-extract', 02:27:52 INFO - 'populate-webroot', 02:27:52 INFO - 'create-virtualenv', 02:27:52 INFO - 'install', 02:27:52 INFO - 'run-tests'), 02:27:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:27:52 INFO - 'download_minidump_stackwalk': True, 02:27:52 INFO - 'download_symbols': 'ondemand', 02:27:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:27:52 INFO - 'tooltool.py': '/tools/tooltool.py', 02:27:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:27:52 INFO - '/tools/misc-python/virtualenv.py')}, 02:27:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:27:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:27:52 INFO - 'installer_path': 'installer.exe', 02:27:52 INFO - 'log_level': 'info', 02:27:52 INFO - 'log_name': 'talos', 02:27:52 INFO - 'log_to_console': True, 02:27:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:27:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:27:52 INFO - 'opt_config_files': (), 02:27:52 INFO - 'pip_index': False, 02:27:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:52 INFO - 'cmd': ('bash', 02:27:52 INFO - '-c', 02:27:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:52 INFO - 'enabled': True, 02:27:52 INFO - 'halt_on_failure': False, 02:27:52 INFO - 'name': 'check_screen_resolution'},), 02:27:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:52 INFO - 'cmd': ('bash', 02:27:52 INFO - '-c', 02:27:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:52 INFO - 'enabled': True, 02:27:52 INFO - 'halt_on_failure': False, 02:27:52 INFO - 'name': 'check_screen_resolution'},), 02:27:52 INFO - 'run_cmd_checks_enabled': True, 02:27:52 INFO - 'sps_profile': False, 02:27:52 INFO - 'sps_profile_interval': 0, 02:27:52 INFO - 'suite': 'dromaeojs', 02:27:52 INFO - 'system_bits': '32', 02:27:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:27:52 INFO - 'title': 't-yosemite-r7-0338', 02:27:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:27:52 INFO - 'use_talos_json': True, 02:27:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:27:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:27:52 INFO - 'work_dir': 'build'} 02:27:52 INFO - ##### 02:27:52 INFO - ##### Running clobber step. 02:27:52 INFO - ##### 02:27:52 INFO - Running pre-action listener: _resource_record_pre_action 02:27:52 INFO - Running main action method: clobber 02:27:52 INFO - rmtree: /builds/slave/test/build 02:27:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:27:54 INFO - Running post-action listener: _resource_record_post_action 02:27:54 INFO - ##### 02:27:54 INFO - ##### Finished clobber step (success) 02:27:54 INFO - ##### 02:27:54 INFO - ##### 02:27:54 INFO - ##### Running read-buildbot-config step. 02:27:54 INFO - ##### 02:27:54 INFO - Running pre-action listener: _resource_record_pre_action 02:27:54 INFO - Running main action method: read_buildbot_config 02:27:54 INFO - Using buildbot properties: 02:27:54 INFO - { 02:27:54 INFO - "project": "", 02:27:54 INFO - "product": "firefox", 02:27:54 INFO - "script_repo_revision": "production", 02:27:54 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 02:27:54 INFO - "repository": "", 02:27:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 02:27:54 INFO - "buildid": "20160717001753", 02:27:54 INFO - "pgo_build": "False", 02:27:54 INFO - "basedir": "/builds/slave/test", 02:27:54 INFO - "buildnumber": 19, 02:27:54 INFO - "slavename": "t-yosemite-r7-0338", 02:27:54 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:27:54 INFO - "platform": "yosemite_r7", 02:27:54 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:27:54 INFO - "branch": "mozilla-central", 02:27:54 INFO - "repo_path": "mozilla-central", 02:27:54 INFO - "revision": "ef5f932101e5b833b2429407cb0873471b4d764e", 02:27:54 INFO - "stage_platform": "macosx64", 02:27:54 INFO - "builduid": "a92869d540e44da4bb7360d699ff9bc7", 02:27:54 INFO - "slavebuilddir": "test" 02:27:54 INFO - } 02:27:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 02:27:54 INFO - Running post-action listener: _resource_record_post_action 02:27:54 INFO - ##### 02:27:54 INFO - ##### Finished read-buildbot-config step (success) 02:27:54 INFO - ##### 02:27:54 INFO - ##### 02:27:54 INFO - ##### Running download-and-extract step. 02:27:54 INFO - ##### 02:27:54 INFO - Running pre-action listener: _resource_record_pre_action 02:27:54 INFO - Running main action method: download_and_extract 02:27:54 INFO - mkdir: /builds/slave/test/build/tests 02:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:27:54 INFO - https://queue.taskcluster.net/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:27:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 02:27:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 02:27:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/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 02:27:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/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 02:28:17 INFO - Downloaded 1199 bytes. 02:28:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 02:28:17 INFO - Using the following test package requirements: 02:28:17 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 02:28:17 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:28:17 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 02:28:17 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:28:17 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 02:28:17 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:28:17 INFO - u'jsshell-mac.zip'], 02:28:17 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:28:17 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 02:28:17 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 02:28:17 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:28:17 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 02:28:17 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:28:17 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 02:28:17 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:28:17 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 02:28:17 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:28:17 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 02:28:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:28: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')]} 02:28:17 INFO - https://queue.taskcluster.net/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:28:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:28:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:28:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/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 02:28:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/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 02:28:19 INFO - Downloaded 18829611 bytes. 02:28:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:28:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:28:22 INFO - Return code: 0 02:28:22 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 02:28:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:28:22 INFO - https://queue.taskcluster.net/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:28:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:28:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:28:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/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 02:28:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/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 02:28:36 INFO - Downloaded 18829611 bytes. 02:28:36 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'] 02:28:36 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 02:28:40 INFO - Return code: 0 02:28:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:40 INFO - https://queue.taskcluster.net/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:28:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:28:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:28:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/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 02:28:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/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 02:28:41 INFO - Downloaded 14403317 bytes. 02:28:41 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'] 02:28:41 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 02:28:42 INFO - Return code: 0 02:28:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:42 INFO - https://queue.taskcluster.net/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 02:28:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:28:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:28:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 02:28:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/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 02:28:45 INFO - Downloaded 112747651 bytes. 02:28:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:28:45 INFO - mkdir: /builds/slave/test/properties 02:28:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:28:45 INFO - Writing to file /builds/slave/test/properties/build_url 02:28:45 INFO - Contents: 02:28:45 INFO - build_url:https://queue.taskcluster.net/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:28:46 INFO - Running post-action listener: _resource_record_post_action 02:28:46 INFO - Running post-action listener: set_extra_try_arguments 02:28:46 INFO - ##### 02:28:46 INFO - ##### Finished download-and-extract step (success) 02:28:46 INFO - ##### 02:28:46 INFO - ##### 02:28:46 INFO - ##### Running populate-webroot step. 02:28:46 INFO - ##### 02:28:46 INFO - Running pre-action listener: _resource_record_pre_action 02:28:46 INFO - Running main action method: populate_webroot 02:28:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:28:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:28:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:28:46 INFO - '--tppagecycles', 02:28:46 INFO - '7'], 02:28:46 INFO - 'tests': ['tsvgm']}}, 02:28:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:28:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:28:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:28:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:28:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:46 INFO - 'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:28:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:28:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:46 INFO - 'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['damp', 'tps']}, 02:28:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:46 INFO - 'tests': ['damp', 'tps']}, 02:28:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['dromaeo_dom']}, 02:28:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:28:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['basic_compositor_video']}, 02:28:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:28:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['a11yr', 02:28:46 INFO - 'ts_paint', 02:28:46 INFO - 'tpaint', 02:28:46 INFO - 'sessionrestore', 02:28:46 INFO - 'sessionrestore_no_auto_restore', 02:28:46 INFO - 'tabpaint']}, 02:28:46 INFO - 'other-e10s': {'tests': ['a11yr', 02:28:46 INFO - 'ts_paint', 02:28:46 INFO - 'tpaint', 02:28:46 INFO - 'sessionrestore', 02:28:46 INFO - 'sessionrestore_no_auto_restore', 02:28:46 INFO - 'tabpaint']}, 02:28:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:28:46 INFO - 'ts_paint', 02:28:46 INFO - 'tpaint', 02:28:46 INFO - 'sessionrestore', 02:28:46 INFO - 'sessionrestore_no_auto_restore', 02:28:46 INFO - 'tabpaint']}, 02:28:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:28:46 INFO - 'ts_paint', 02:28:46 INFO - 'tpaint', 02:28:46 INFO - 'sessionrestore', 02:28:46 INFO - 'sessionrestore_no_auto_restore', 02:28:46 INFO - 'tabpaint']}, 02:28:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['a11yr', 02:28:46 INFO - 'ts_paint', 02:28:46 INFO - 'tpaint', 02:28:46 INFO - 'sessionrestore', 02:28:46 INFO - 'sessionrestore_no_auto_restore', 02:28:46 INFO - 'tabpaint']}, 02:28:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['a11yr', 02:28:46 INFO - 'ts_paint', 02:28:46 INFO - 'tpaint', 02:28:46 INFO - 'sessionrestore', 02:28:46 INFO - 'sessionrestore_no_auto_restore', 02:28:46 INFO - 'tabpaint']}, 02:28:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['tsvgx', 02:28:46 INFO - 'tsvgr_opacity', 02:28:46 INFO - 'tart', 02:28:46 INFO - 'tscrollx', 02:28:46 INFO - 'cart']}, 02:28:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:28:46 INFO - 'tsvgr_opacity', 02:28:46 INFO - 'tart', 02:28:46 INFO - 'tscrollx', 02:28:46 INFO - 'cart']}, 02:28:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:46 INFO - 'talos_options': ['--disable-e10s'], 02:28:46 INFO - 'tests': ['tp5o']}, 02:28:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:46 INFO - 'tests': ['tp5o']}, 02:28:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:46 INFO - 'talos_options': ['--disable-e10s', 02:28:46 INFO - '--xperf_path', 02:28:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:28:46 INFO - 'tests': ['tp5n']}, 02:28:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:28:46 INFO - 'talos_options': ['--xperf_path', 02:28:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:28:46 INFO - 'tests': ['tp5n']}}, 02:28:46 INFO - 'talos.zip': {'path': '', 02:28:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:28:46 INFO - Running post-action listener: _resource_record_post_action 02:28:46 INFO - ##### 02:28:46 INFO - ##### Finished populate-webroot step (success) 02:28:46 INFO - ##### 02:28:46 INFO - ##### 02:28:46 INFO - ##### Running create-virtualenv step. 02:28:46 INFO - ##### 02:28:46 INFO - Running pre-action listener: _resource_record_pre_action 02:28:46 INFO - Running main action method: create_virtualenv 02:28:46 INFO - Creating virtualenv /builds/slave/test/build/venv 02:28:46 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:28:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:28:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:28:47 INFO - Using real prefix '/tools/python27' 02:28:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:28:47 INFO - Installing distribute.............................................................................................................................................................................................done. 02:28:50 INFO - Installing pip.................done. 02:28:50 INFO - Return code: 0 02:28:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b84fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5cac84880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b3d30>, '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 0x10b518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7abb20>, '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.a2AOlmx3uO/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.UrCdrOePGk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 02:28:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:28:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a2AOlmx3uO/Render', 02:28:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:50 INFO - 'HOME': '/Users/cltbld', 02:28:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:50 INFO - 'LOGNAME': 'cltbld', 02:28:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:50 INFO - 'MOZ_NO_REMOTE': '1', 02:28:50 INFO - 'NO_EM_RESTART': '1', 02:28:50 INFO - 'PAGER': '/bin/cat', 02:28:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:50 INFO - 'PWD': '/builds/slave/test', 02:28:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:50 INFO - 'SHELL': '/bin/bash', 02:28:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrCdrOePGk/Listeners', 02:28:50 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 02:28:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:50 INFO - 'USER': 'cltbld', 02:28:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:50 INFO - 'XPC_FLAGS': '0x0', 02:28:50 INFO - 'XPC_SERVICE_NAME': '0', 02:28:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:28:50 INFO - Cleaning up... 02:28:50 INFO - Return code: 0 02:28:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b84fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5cac84880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b3d30>, '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 0x10b518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7abb20>, '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.a2AOlmx3uO/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.UrCdrOePGk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 02:28:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:28:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a2AOlmx3uO/Render', 02:28:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:50 INFO - 'HOME': '/Users/cltbld', 02:28:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:50 INFO - 'LOGNAME': 'cltbld', 02:28:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:50 INFO - 'MOZ_NO_REMOTE': '1', 02:28:50 INFO - 'NO_EM_RESTART': '1', 02:28:50 INFO - 'PAGER': '/bin/cat', 02:28:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:50 INFO - 'PWD': '/builds/slave/test', 02:28:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:50 INFO - 'SHELL': '/bin/bash', 02:28:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrCdrOePGk/Listeners', 02:28:50 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 02:28:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:50 INFO - 'USER': 'cltbld', 02:28:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:50 INFO - 'XPC_FLAGS': '0x0', 02:28:50 INFO - 'XPC_SERVICE_NAME': '0', 02:28:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:51 INFO - Downloading/unpacking psutil>=3.1.1 02:28:51 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 02:28:51 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 02:28:51 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 02:28:51 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 02:28:51 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 02:28:51 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 02:28:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:28:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:54 INFO - Installing collected packages: psutil 02:28:54 INFO - Running setup.py install for psutil 02:28:54 INFO - building 'psutil._psutil_osx' extension 02:28:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:28:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:28:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:28:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:28:55 INFO - building 'psutil._psutil_posix' extension 02:28:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:28:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:28:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:28:55 INFO - ^ 02:28:55 INFO - 1 warning generated. 02:28:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:28:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:55 INFO - Successfully installed psutil 02:28:55 INFO - Cleaning up... 02:28:55 INFO - Return code: 0 02:28:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b84fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5cac84880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b3d30>, '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 0x10b518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7abb20>, '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.a2AOlmx3uO/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.UrCdrOePGk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 02:28:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:28:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:28:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a2AOlmx3uO/Render', 02:28:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:55 INFO - 'HOME': '/Users/cltbld', 02:28:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:55 INFO - 'LOGNAME': 'cltbld', 02:28:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:55 INFO - 'MOZ_NO_REMOTE': '1', 02:28:55 INFO - 'NO_EM_RESTART': '1', 02:28:55 INFO - 'PAGER': '/bin/cat', 02:28:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:55 INFO - 'PWD': '/builds/slave/test', 02:28:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:55 INFO - 'SHELL': '/bin/bash', 02:28:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrCdrOePGk/Listeners', 02:28:55 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 02:28:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:55 INFO - 'USER': 'cltbld', 02:28:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:55 INFO - 'XPC_FLAGS': '0x0', 02:28:55 INFO - 'XPC_SERVICE_NAME': '0', 02:28:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:28:56 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 02:28:56 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 02:28:56 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 02:28:56 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 02:28:56 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 02:28:56 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 02:28:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:28:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:28:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:28:58 INFO - Installing collected packages: mozsystemmonitor 02:28:58 INFO - Running setup.py install for mozsystemmonitor 02:28:58 INFO - Successfully installed mozsystemmonitor 02:28:58 INFO - Cleaning up... 02:28:58 INFO - Return code: 0 02:28:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:28:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b84fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5cac84880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b3d30>, '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 0x10b518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7abb20>, '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.a2AOlmx3uO/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.UrCdrOePGk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 02:28:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:28:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:28:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a2AOlmx3uO/Render', 02:28:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:58 INFO - 'HOME': '/Users/cltbld', 02:28:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:58 INFO - 'LOGNAME': 'cltbld', 02:28:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:58 INFO - 'MOZ_NO_REMOTE': '1', 02:28:58 INFO - 'NO_EM_RESTART': '1', 02:28:58 INFO - 'PAGER': '/bin/cat', 02:28:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:58 INFO - 'PWD': '/builds/slave/test', 02:28:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:58 INFO - 'SHELL': '/bin/bash', 02:28:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrCdrOePGk/Listeners', 02:28:58 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 02:28:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:58 INFO - 'USER': 'cltbld', 02:28:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:58 INFO - 'XPC_FLAGS': '0x0', 02:28:58 INFO - 'XPC_SERVICE_NAME': '0', 02:28:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:59 INFO - Downloading/unpacking jsonschema==2.5.1 02:28:59 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 02:28:59 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 02:28:59 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 02:28:59 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 02:28:59 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 02:28:59 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 02:29:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:29:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:02 INFO - Downloading functools32-3.2.3-2.tar.gz 02:29:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:29:02 INFO - warning: no files found matching '*.txt' 02:29:02 INFO - no previously-included directories found matching 'build' 02:29:02 INFO - no previously-included directories found matching 'dist' 02:29:02 INFO - no previously-included directories found matching '.git*' 02:29:02 INFO - Installing collected packages: jsonschema, functools32 02:29:02 INFO - Running setup.py install for functools32 02:29:02 INFO - warning: no files found matching '*.txt' 02:29:02 INFO - no previously-included directories found matching 'build' 02:29:02 INFO - no previously-included directories found matching 'dist' 02:29:02 INFO - no previously-included directories found matching '.git*' 02:29:02 INFO - Successfully installed jsonschema functools32 02:29:02 INFO - Cleaning up... 02:29:02 INFO - Return code: 0 02:29:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b84fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5cac84880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b3d30>, '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 0x10b518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7abb20>, '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.a2AOlmx3uO/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.UrCdrOePGk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 02:29:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:29:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:29:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a2AOlmx3uO/Render', 02:29:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:02 INFO - 'HOME': '/Users/cltbld', 02:29:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:02 INFO - 'LOGNAME': 'cltbld', 02:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:02 INFO - 'MOZ_NO_REMOTE': '1', 02:29:02 INFO - 'NO_EM_RESTART': '1', 02:29:02 INFO - 'PAGER': '/bin/cat', 02:29:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:02 INFO - 'PWD': '/builds/slave/test', 02:29:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:02 INFO - 'SHELL': '/bin/bash', 02:29:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrCdrOePGk/Listeners', 02:29:02 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 02:29:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:02 INFO - 'USER': 'cltbld', 02:29:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:02 INFO - 'XPC_FLAGS': '0x0', 02:29:02 INFO - 'XPC_SERVICE_NAME': '0', 02:29:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:02 INFO - Downloading/unpacking blobuploader==1.2.4 02:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:05 INFO - Downloading blobuploader-1.2.4.tar.gz 02:29:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:29:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:29:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:06 INFO - Downloading docopt-0.6.1.tar.gz 02:29:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:29:06 INFO - Installing collected packages: blobuploader, requests, docopt 02:29:06 INFO - Running setup.py install for blobuploader 02:29:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:29:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:29:06 INFO - Running setup.py install for requests 02:29:07 INFO - Running setup.py install for docopt 02:29:07 INFO - Successfully installed blobuploader requests docopt 02:29:07 INFO - Cleaning up... 02:29:07 INFO - Return code: 0 02:29:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b84fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5cac84880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b3d30>, '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 0x10b518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7abb20>, '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.a2AOlmx3uO/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.UrCdrOePGk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 02:29:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:29:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a2AOlmx3uO/Render', 02:29:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:07 INFO - 'HOME': '/Users/cltbld', 02:29:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:07 INFO - 'LOGNAME': 'cltbld', 02:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:07 INFO - 'MOZ_NO_REMOTE': '1', 02:29:07 INFO - 'NO_EM_RESTART': '1', 02:29:07 INFO - 'PAGER': '/bin/cat', 02:29:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:07 INFO - 'PWD': '/builds/slave/test', 02:29:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:07 INFO - 'SHELL': '/bin/bash', 02:29:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrCdrOePGk/Listeners', 02:29:07 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 02:29:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:07 INFO - 'USER': 'cltbld', 02:29:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:07 INFO - 'XPC_FLAGS': '0x0', 02:29:07 INFO - 'XPC_SERVICE_NAME': '0', 02:29:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:07 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-TjP2vG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:07 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-dVC6Ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:07 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-fE6h8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:07 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-ChjTT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:08 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-tur92i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:08 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-yDFIbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:08 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-DJ9QRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:08 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-rGBDxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:08 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-v4KqTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:08 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-pP2ZVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:08 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-fhRkL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:08 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-t9cUem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:08 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-Z4QmEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:08 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-ewN9Qo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:09 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-CPrxNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:09 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-jt9qou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:09 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-PK90kP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:29:09 INFO - Running setup.py install for manifestparser 02:29:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:29:09 INFO - Running setup.py install for mozcrash 02:29:09 INFO - Running setup.py install for mozdebug 02:29:09 INFO - Running setup.py install for mozdevice 02:29:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:29:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:29:09 INFO - Running setup.py install for mozfile 02:29:10 INFO - Running setup.py install for mozhttpd 02:29:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:29:10 INFO - Running setup.py install for mozinfo 02:29:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:29:10 INFO - Running setup.py install for mozInstall 02:29:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:29:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:29:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:29:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:29:10 INFO - Running setup.py install for mozleak 02:29:10 INFO - Running setup.py install for mozlog 02:29:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:29:10 INFO - Running setup.py install for moznetwork 02:29:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:29:11 INFO - Running setup.py install for mozprocess 02:29:11 INFO - Running setup.py install for mozprofile 02:29:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:29:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:29:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:29:11 INFO - Running setup.py install for mozrunner 02:29:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:29:11 INFO - Running setup.py install for mozscreenshot 02:29:11 INFO - Running setup.py install for moztest 02:29:11 INFO - Running setup.py install for mozversion 02:29:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:29:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:29:11 INFO - Cleaning up... 02:29:11 INFO - Return code: 0 02:29:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b84fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5cac84880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b3d30>, '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 0x10b518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7abb20>, '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.a2AOlmx3uO/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.UrCdrOePGk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 02:29:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:29:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:29:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a2AOlmx3uO/Render', 02:29:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:11 INFO - 'HOME': '/Users/cltbld', 02:29:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:11 INFO - 'LOGNAME': 'cltbld', 02:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:11 INFO - 'MOZ_NO_REMOTE': '1', 02:29:11 INFO - 'NO_EM_RESTART': '1', 02:29:11 INFO - 'PAGER': '/bin/cat', 02:29:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:11 INFO - 'PWD': '/builds/slave/test', 02:29:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:11 INFO - 'SHELL': '/bin/bash', 02:29:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrCdrOePGk/Listeners', 02:29:11 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 02:29:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:11 INFO - 'USER': 'cltbld', 02:29:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:11 INFO - 'XPC_FLAGS': '0x0', 02:29:11 INFO - 'XPC_SERVICE_NAME': '0', 02:29:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:12 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-XGwxT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:12 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:12 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-BoPZjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:12 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-SwKjoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:12 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-NAHAWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:12 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-cAKdUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:12 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-Vsc89p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:12 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-YoVx1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:13 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)) 02:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:13 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-Df9OPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:13 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)) 02:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:13 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-H1EVIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:13 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-Gr7Y0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:13 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-Bm4fNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:13 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-qTE6LJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:13 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-YIxrPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:13 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-UzBoXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:13 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-MN7q9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:13 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-IBBzln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:13 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-pTQeMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:14 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)) 02:29:14 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)) 02:29:14 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)) 02:29:14 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)) 02:29:14 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)) 02:29:14 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)) 02:29:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:14 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 02:29:14 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 02:29:14 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 02:29:14 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 02:29:14 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 02:29:14 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 02:29:16 INFO - Downloading blessings-1.6.tar.gz 02:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:29:16 INFO - Installing collected packages: blessings 02:29:16 INFO - Running setup.py install for blessings 02:29:16 INFO - Successfully installed blessings 02:29:16 INFO - Cleaning up... 02:29:16 INFO - Return code: 0 02:29:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:29:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:17 INFO - Reading from file tmpfile_stdout 02:29:17 INFO - Current package versions: 02:29:17 INFO - blessings == 1.6 02:29:17 INFO - blobuploader == 1.2.4 02:29:17 INFO - docopt == 0.6.1 02:29:17 INFO - functools32 == 3.2.3-2 02:29:17 INFO - jsonschema == 2.5.1 02:29:17 INFO - manifestparser == 1.1 02:29:17 INFO - mozInstall == 1.12 02:29:17 INFO - mozcrash == 0.17 02:29:17 INFO - mozdebug == 0.1 02:29:17 INFO - mozdevice == 0.48 02:29:17 INFO - mozfile == 1.2 02:29:17 INFO - mozhttpd == 0.7 02:29:17 INFO - mozinfo == 0.9 02:29:17 INFO - mozleak == 0.1 02:29:17 INFO - mozlog == 3.2 02:29:17 INFO - moznetwork == 0.27 02:29:17 INFO - mozprocess == 0.23 02:29:17 INFO - mozprofile == 0.28 02:29:17 INFO - mozrunner == 6.12 02:29:17 INFO - mozscreenshot == 0.1 02:29:17 INFO - mozsystemmonitor == 0.3 02:29:17 INFO - moztest == 0.7 02:29:17 INFO - mozversion == 1.4 02:29:17 INFO - psutil == 3.1.1 02:29:17 INFO - requests == 1.2.3 02:29:17 INFO - wsgiref == 0.1.2 02:29:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b84fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5cac84880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b3d30>, '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 0x10b518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7abb20>, '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.a2AOlmx3uO/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.UrCdrOePGk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 02:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:29:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a2AOlmx3uO/Render', 02:29:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:17 INFO - 'HOME': '/Users/cltbld', 02:29:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:17 INFO - 'LOGNAME': 'cltbld', 02:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:17 INFO - 'MOZ_NO_REMOTE': '1', 02:29:17 INFO - 'NO_EM_RESTART': '1', 02:29:17 INFO - 'PAGER': '/bin/cat', 02:29:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:17 INFO - 'PWD': '/builds/slave/test', 02:29:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:17 INFO - 'SHELL': '/bin/bash', 02:29:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrCdrOePGk/Listeners', 02:29:17 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 02:29:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:17 INFO - 'USER': 'cltbld', 02:29:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:17 INFO - 'XPC_FLAGS': '0x0', 02:29:17 INFO - 'XPC_SERVICE_NAME': '0', 02:29:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:29:17 INFO - Cleaning up... 02:29:17 INFO - Return code: 0 02:29:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b84fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5cac84880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b3d30>, '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 0x10b518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7abb20>, '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.a2AOlmx3uO/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.UrCdrOePGk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 02:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:29:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a2AOlmx3uO/Render', 02:29:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:17 INFO - 'HOME': '/Users/cltbld', 02:29:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:17 INFO - 'LOGNAME': 'cltbld', 02:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:17 INFO - 'MOZ_NO_REMOTE': '1', 02:29:17 INFO - 'NO_EM_RESTART': '1', 02:29:17 INFO - 'PAGER': '/bin/cat', 02:29:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:17 INFO - 'PWD': '/builds/slave/test', 02:29:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:17 INFO - 'SHELL': '/bin/bash', 02:29:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrCdrOePGk/Listeners', 02:29:17 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 02:29:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:17 INFO - 'USER': 'cltbld', 02:29:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:17 INFO - 'XPC_FLAGS': '0x0', 02:29:17 INFO - 'XPC_SERVICE_NAME': '0', 02:29:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:29:17 INFO - Cleaning up... 02:29:17 INFO - Return code: 0 02:29:17 INFO - Running post-action listener: _resource_record_post_action 02:29:17 INFO - Running post-action listener: _start_resource_monitoring 02:29:17 INFO - Starting resource monitoring. 02:29:17 INFO - ##### 02:29:17 INFO - ##### Finished create-virtualenv step (success) 02:29:17 INFO - ##### 02:29:17 INFO - ##### 02:29:17 INFO - ##### Running install step. 02:29:17 INFO - ##### 02:29:17 INFO - Running pre-action listener: _resource_record_pre_action 02:29:17 INFO - Running main action method: install 02:29:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:17 INFO - Reading from file tmpfile_stdout 02:29:17 INFO - Detecting whether we're running mozinstall >=1.0... 02:29:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:29:18 INFO - Reading from file tmpfile_stdout 02:29:18 INFO - Output received: 02:29:18 INFO - Usage: mozinstall [options] installer 02:29:18 INFO - Options: 02:29:18 INFO - -h, --help show this help message and exit 02:29:18 INFO - -d DEST, --destination=DEST 02:29:18 INFO - Directory to install application into. [default: 02:29:18 INFO - "/builds/slave/test"] 02:29:18 INFO - --app=APP Application being installed. [default: firefox] 02:29:18 INFO - mkdir: /builds/slave/test/build/application 02:29:18 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'] 02:29:18 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 02:29:52 INFO - Reading from file tmpfile_stdout 02:29:52 INFO - Output received: 02:29:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:29:52 INFO - Running post-action listener: _resource_record_post_action 02:29:52 INFO - ##### 02:29:52 INFO - ##### Finished install step (success) 02:29:52 INFO - ##### 02:29:52 INFO - ##### 02:29:52 INFO - ##### Running run-tests step. 02:29:52 INFO - ##### 02:29:52 INFO - Running pre-action listener: _resource_record_pre_action 02:29:52 INFO - Running main action method: run_tests 02:29:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:29:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:29:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:29:52 INFO - 2016-07-17 02:29:52.660 screenresolution[1665:16166] starting screenresolution argv=screenresolution get 02:29:52 INFO - 2016-07-17 02:29:52.663 screenresolution[1665:16166] Display 0: 1600x1200x32@60 02:29:52 INFO - 2016-07-17 02:29:52.669 screenresolution[1666:16169] starting screenresolution argv=screenresolution list 02:29:52 INFO - Available Modes on Display 0 02:29:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:29:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:29:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:29:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:29:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:29:52 INFO - Intel Iris: 02:29:52 INFO - Chipset Model: Intel Iris 02:29:52 INFO - Type: GPU 02:29:52 INFO - Bus: Built-In 02:29:52 INFO - VRAM (Dynamic, Max): 1536 MB 02:29:52 INFO - Vendor: Intel (0x8086) 02:29:52 INFO - Device ID: 0x0a2e 02:29:52 INFO - Revision ID: 0x0009 02:29:52 INFO - Displays: 02:29:52 INFO - Display: 02:29:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:29:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:29:52 INFO - Display Serial Number: 1600x1200 60 02:29:52 INFO - Main Display: Yes 02:29:52 INFO - Mirror: Off 02:29:52 INFO - Online: Yes 02:29:52 INFO - Rotation: Supported 02:29:52 INFO - Return code: 0 02:29:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:29:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:29:52 INFO - Python 2.7.3 02:29:52 INFO - Return code: 0 02:29:52 INFO - grabbing minidump binary from tooltool 02:29: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')]} 02:29:52 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b3d30>, '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 0x10b518db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b7abb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:29:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:29:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:29:52 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:29:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:29:53 INFO - Return code: 0 02:29:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:29:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:29:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:29:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:29:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:29:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0338', '--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 02:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0338 --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 02:29:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a2AOlmx3uO/Render', 02:29:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:53 INFO - 'HOME': '/Users/cltbld', 02:29:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:53 INFO - 'LOGNAME': 'cltbld', 02:29:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:29:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:53 INFO - 'MOZ_NO_REMOTE': '1', 02:29:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:29:53 INFO - 'NO_EM_RESTART': '1', 02:29:53 INFO - 'PAGER': '/bin/cat', 02:29:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:53 INFO - 'PWD': '/builds/slave/test', 02:29:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:29:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:53 INFO - 'SHELL': '/bin/bash', 02:29:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrCdrOePGk/Listeners', 02:29:53 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 02:29:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:53 INFO - 'USER': 'cltbld', 02:29:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:53 INFO - 'XPC_FLAGS': '0x0', 02:29:53 INFO - 'XPC_SERVICE_NAME': '0', 02:29:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0338', '--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 02:29:53 INFO - mozversion application_buildid: 20160717001753 02:29:53 INFO - mozversion application_changeset: ef5f932101e5b833b2429407cb0873471b4d764e 02:29:53 INFO - mozversion application_display_name: Nightly 02:29:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:29:53 INFO - mozversion application_name: Firefox 02:29:53 INFO - mozversion application_remotingname: firefox 02:29:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:29:53 INFO - mozversion application_vendor: Mozilla 02:29:53 INFO - mozversion application_version: 50.0a1 02:29:53 INFO - mozversion platform_buildid: 20160717001753 02:29:53 INFO - mozversion platform_changeset: ef5f932101e5b833b2429407cb0873471b4d764e 02:29:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:29:53 INFO - mozversion platform_version: 50.0a1 02:29:53 INFO - using testdate: 1468747793 02:29:53 INFO - actual date: 1468747793 02:29:53 INFO - starting webserver on 'localhost:49271' 02:29:53 INFO - SUITE-START | Running 2 tests 02:29:53 INFO - TEST-START | dromaeo_css 02:29:53 INFO - operating with platform_type : mac_ 02:29:53 INFO - Initialising browser for dromaeo_css test... 02:29:53 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpkMtEO3/profile http://localhost:49271/getInfo.html) 02:29:55 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 02:29:55 INFO - PROCESS | 1676 | colorDepth:24 02:29:55 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 02:29:55 INFO - PROCESS | 1676 | __metrics 02:29:55 INFO - PROCESS | 1676 | 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" 02:29:55 INFO - PROCESS | 1676 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:29:56 INFO - TEST-INFO | 1676: exit 0 02:29:56 INFO - Browser initialized. 02:29:56 INFO - Running cycle 1/1 for dromaeo_css test... 02:29:56 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpkMtEO3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:30:08 INFO - PROCESS | 1679 | RSS: Main: 243552256 02:30:08 INFO - PROCESS | 1679 | 02:30:59 INFO - PROCESS | 1679 | 1468747859716 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] 02:33:10 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 02:33:12 INFO - PROCESS | 1679 | RSS: Main: 350687232 02:33:12 INFO - PROCESS | 1679 | 02:36:15 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 02:36:16 INFO - PROCESS | 1679 | RSS: Main: 381681664 02:36:16 INFO - PROCESS | 1679 | 02:39:18 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 02:39:18 INFO - PROCESS | 1679 | RSS: Main: 370569216 02:39:18 INFO - PROCESS | 1679 | 02:42:21 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 02:42:21 INFO - PROCESS | 1679 | RSS: Main: 372293632 02:42:21 INFO - PROCESS | 1679 | 02:45:25 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 02:45:25 INFO - PROCESS | 1679 | RSS: Main: 343175168 02:45:25 INFO - PROCESS | 1679 | 02:48:28 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 02:48:29 INFO - PROCESS | 1679 | RSS: Main: 395972608 02:48:29 INFO - PROCESS | 1679 | 02:48:29 INFO - PROCESS | 1679 | __start_tp_report 02:48:29 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 02:48:29 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 02:48:29 INFO - PROCESS | 1679 | |i|pagename|runs| 02:48:29 INFO - PROCESS | 1679 | |0;dojo.html;3272.132884785532;3279.6720327967223;3289.4572395554733;3300.1584595928034;3328.7836290641108;5504.064309067458;5604.3835178130375;5604.9719751401235;5648.6893220872835;5650.0677388231;5609.915851262234;5640.435956404358;5697;5730.255066841315;5743.9712801436035;5089.46560611136;5096.337476128098;5154.046501397234;5163.225516172567;5262.842114736564;5224.817131400383;5262.315898933152;5289.233061206123;5290.7354632268425;5301.469853014687;5094.898102037975;5112.693238405707;5134.435212126663;5141.357330333708;5195.714235717034;5173.3533308336455;5185.714785686786;5186.40356359016;5189.247559103909;5197.7401129943355;5099.133147364957;5107.7446127693465;5143.27994080829;5143.537081662668;5146.562542183919;5559.19391688207;5562.554995600342;5616.691081990489;5724.313082430135;5730.598858079936;6162.630242185483;6165.7533698652;6185.381461853824;6189.566730328879;6191.59754615953;6020.729067191987;6182.443580077815;6226.2839773426185;6243.375662433766;6252.906206406908;5619.522340601013;5692.316921969349;5723.656580605177;5732.7706891723865;5739.196512488255;6347.428731414195;6365.936340636627;6372.522060845363;6373.139626150503;6388.297287298394;5903.3801450847905;6080.346370212959;6099.695015249308;6103.962326404522;6104.428909088145;6120.447133218794;6134.2332208473945;6176.629402235881;6176.907346389808;6235.657038862815;4461.420015398043;5766.942330576724;6110.511159107261;6114.205153330038;6140.293866205444;5928.703564821742;6127.938720612826;6160.045192995093;6164.291106522717;6194.659293738888;4831.9275210871865;4869.878253043631;4869.9513004870205;4871.123197824426;4874.6344024197615;4350.869473915787;4374.321980093091;4404.757738324359;4422.0050488640445;4431.379606855043;4771.260454629538;5715.371309155989;5733.140028995601;5762.625429347079;5805.82582522525;6198.845028874314;6272.905906411407;6285.899967505668;6296.307406185316;6307.432331090132;5572.526335261548;5622.381538030813;5651.576131790132;5737.110747834093;5742.454466825645;5369.4362091980565;5378.596605254622;5385.8114965976;5405.378381486101;5444.91832622511;6310.242770867525;6312.337204593635;6345.650989195547;6348.111264422984;6375.521835862236;6139.29398119222;6154.076888466766;6167.6916154193;6245.5628106032145;6273.9334313165955;6315.5894866833505;6321.399467050623;6360.713767880549;6364.77723279683;6373.107764913008;6154.19995400613;6176.258848938156;6176.3514830943905;6181.629102253879;6185.195924529782;5840.240768700041;6108.75564977396;6131.96934015327;6230.345813689497;6241;5923.49650279731;5956.612820166675;5973.761049557969;5980.940190598212;5986.760529578768;4928.9156385595115;5696.772129114789;5927.9703601481715;5967.552433567586;5980.880382392241;4882.82910098159;5664.745086471036;5833.125031245348;5857.150713146655;5876.764929402776;6048.092786082123;6056.939430605814;6059.818205453844;6072.969635151796;6093.634381937098;5855.151003104611;5871.559633027455;5874.118882167709;5877.177195192591;5879.147523608967 02:48:29 INFO - PROCESS | 1679 | |1;ext.html;482.9058333624943;509.43198333857737;529.1322231540273;529.3859123416835;558.9714924538848;30346.84826575867;41807;42759.57240427595;42978.35532467012;45253;43718.781406093;43977.7801110994;44055.67832965008;44080.89797755058;44361.77819110908;8713.738587842343;9683.225341972642;9861.506924653775;9864.654737084204;9866.950665246664;8672.13278672131;11685.7662846743;11768.99963503099;11782.351970641612;12145.696357591043;11919.344436056013;11964.461599228056;11987.880121198807;11990.7601847963;12005.339706316192;8801.295896328278;11709.473073711704;11804.350760708157;11825.645230643095;11909.98765104967;11814.17300788945;11889.28664280146;11895.10786690993;11931.522739090426;11958.641240762705;42112;42319.1536169278;42358.729238122905;42359.9410014749;42377.152456951;3702.444633305026;3704.833282502294;4214.325707886754;4287.978560107211;4410.404595379615;27745;32625.184370390692;33597;33633.15917102068;33738;3331.7001469867587;3333.233356328058;3343.4483310253777;3368.5620869286836;3375.3755555222115;489382;491549;491572;491711;492888;63390.36609633937;63516;63768.04347934785;63815;63970.040449393295;67925.32074679289;67989.66005169942;67989.98015029778;67996;68036.6598167006;15349.769753453707;17025.06362150069;17218.225179866935;17223.483295501155;17233.655326893644;11847.933685968304;12151.756964860653;12948.741025179632;13167;13188.538401155913;18508.629827403376;18728.625427491374;18736.625267494575;19017.524561886064;19110.948897810475;7566.659500322498;7592.924070759333;7633;7659.310662040443;7685.346745526581;896.4934811831295;908.5729707037682;1058.523664351045;1074.978500429987;1145.8115000349294;30973.69026309753;31108.533371999438;31137.532937005963;31655.525167122512;31737.523937140963;1003.544859953071;1100.862392200975;1112.4159816096615;1128.0693427921997;1131.185546406586;12071.637850864488;16054;16139.919300403422;16268.75596866048;16450;648601;663994;665541;668516;668864;60863.08705369423;62375.6881215591;62625.68687156535;62673.686631566554;62802.74394512176;1122.3041714137287;1265.822784810109;1403.3264033263886;1405.8523854995283;1419.4889839657715;31888.840555797073;32237.677623223466;32439.02682919516;32506.86225982079;33552.664473354955;58129.70935145297;58216;59012;59042.70478647579;59162.40837591741;551688;556901;557188;557467;558565;24563.754362456148;27033.59449608297;27340.863295683394;27896;27962.300942475787;49628;50823;51010;51302.74348628233;51383;38627.80686096552;39830.40254396128;40274.59725402826;42535;44428.55571444244;41066.38400424056;41686.58313416949;41982.16035679208;42746.717598472096;42915.57084429116;40051.19897601973;40261.99345016281;40596.79701601473;41027.38458923059;41117.58882411138;34814;35807.64192358043;36696.81651591725;36832.63167368292;36835.815820920725;24643.630345544472;25071.87464062668;26673.466530669666;27013.324666883484;27178.32054198661 02:48:29 INFO - PROCESS | 1679 | |2;jquery.html;326.3375348043472;359.33522982482395;385.78781670081446;393.6476853216373;412.21267379305516;13176.868231317683;15625.687486250268;20997;21590.568188636236;21663.45841353963;21815.563688726215;22048.228312009083;22208.0006399712;22373.10507579695;22549.887250563766;5592.468715472024;5948.7323070461825;6162.445379915806;6179.412955769195;6201.348858369874;6096.35988221237;6120.60216085953;6190.69046547673;6191.4118158774845;6196.9690151549175;5965.850853728648;6058.091286307045;6118.8470288242925;6132.018876979684;6158.107074474198;5798.971005144989;6017.849553761169;6033.48715359195;6074.757009719628;6090.725917333709;5287.07476191665;5323;5327.653702509363;5330.9733451332495;5336.9733151334385;27328;27358.589621155697;27404.725952740417;27685.861570692217;27850.442991140062;1952.726618273443;2238.876861772602;2284.303287497313;2331.3821837213154;2538.2639034680105;15425.691486170326;16211.51365459038;16538.58653533671;16770.916145419196;16909.83090169107;1508.4117194294233;1821.0712536606293;1866.413974787035;1871.214090082155;1908.6182763447366;119494.40252798681;121630;122861;123983;124910;9214.815703685888;9996;10150.847737283946;11437.59968401102;11557.653270401734;14048.438062477386;14152.080114792505;14181.716365672628;14188.929055354658;14198;8790.95604521986;8949.239314658196;9005.729828105168;9036.954815225881;9050.049744776836;9056;9068.818623627623;9078.682246121487;9196.448213107235;9222.584983675752;6779.559328643622;6918.0314755934205;6922.550034247758;6932.341427564374;6948.374646281859;1581.9209039548207;1826.4246762269843;1866.9346572869883;1867.5144462439864;1870.373424902673;931.7763736703278;956.3353469338742;960.1118964957386;1003.94478303694;1035.5605708065796;23208.4197895054;23497.434308812248;23550.411239719146;23969;24003.15988940379;887.7647423432795;934.4206591913057;972.1736523954723;982.5922242269378;1021.0963297481782;11603.76792464146;11945.701357466132;12242.85345870768;12307.830756078158;12363.567275145328;323152;324897;325112;618434;625838;84363.15636843676;135905;144919;152624;155004;904.5432056811268;949.6467533764359;950.5864948747317;989.2778271861438;1007.5818535307765;15485.612859678598;17093.829061709224;19607.509812254808;19711.60576788485;20193.697094543888;45657.08685826331;56555.58611034757;56744.432555675565;60398.69800650969;60650.09024864719;87211.1278887203;87749.56125219334;87931.56034219787;88088;88106.55946720226;7990.280874721303;8273.33813294947;8322.250997410261;8354.707585234488;8541;10586.735331616772;13018.218906865619;13063.346832658519;13075.869241307466;13125.474981000652;11093.611723589636;11145.498452569816;11203.047740942111;11280.830787538358;11336.886631133582;9900.801983960137;10915.017648411682;11149.386783726648;11158.88841111611;11198.552057917917;11507.827382589436;11583.189176757884;11630.534778609099;11780.05759539252;11854.822177667515;6444.967775161094;7477.588732619758;7539.886901696588;7563.319301262913;8496.490210587384;8421.115782842713;10163.881972983114;11059.778804423706;11829.17195796286;11848.052155827685 02:48:29 INFO - PROCESS | 1679 | |3;mootools.html;1386.7157232767272;1501.8798496120312;1549.5351394581623;1770.9911450442746;1926.7495225620667;7643.350315223206;8490.787730306738;8725.956370218157;8734.781630459234;8855.5572221389;8481.279091277233;8706.738797836058;8709.695160669378;8711.651533938635;8712.520811355389;2090.832733381326;2292.0946226240626;2350.2244259394442;2369.905203791855;2382.65451509531;2371.1937941100023;2399.1243196233336;2401.771831675988;2402.327348342458;2412.0231306320966;2002;2319.5013072189477;2352.6706261123454;2355.3522781235124;2359.0445869422824;2024.4230394337617;2264.083046366224;2312.9074837006583;2340.648902664597;2346.507233480971;2332.8833558322185;2354.693889794333;2356.1282325539487;2357.705286839145;2361.5985282502015;8653.091425400306;8711.520866352348;8740.8688869667;8744.256738177199;8744.912550874473;5127.256547800548;5291.735413229323;5294.523492885659;5309.548688361494;5324.174752913266;3415.453527435623;3420.3501334746566;3576.1059735066233;3580.2481478889463;3596.5504311961004;1032.9610247598239;1049.1449468683;1055.1137044882337;1071.5338827610015;1074.1031238915516;10295.3822770634;10939.890601094046;11475.77048459026;11478.540858365655;11481.598144065001;4495.550444955478;4497.8200871964755;4726.834560790356;4727.267273572533;4733;9834.508274586242;9835.803283934425;9836.06557377048;9837.55730992107;9838.016198380104;5291;5292.285541451854;5295.814646487354;5301.5493683037375;5312.840614781563;4360.825566977285;4505.098980203972;4712.952870471251;4854.466008739036;4867.853964381075;3325.068980685412;3742.288965096623;3869.4776205212497;3947.2697550953167;3991.3214753491975;1096.5668560918393;1237.7091383524864;1246.8254444377612;1252.918560293578;1260.8865202131851;740.5445650924643;890.2210565754964;938.1462868789505;944.5749412764146;977.745786095606;5964.31410387811;5966.701664916737;6025.728842202111;6071.574989750675;6183.319834818167;942.1285311087217;945.5319616789733;950.8573713942826;953.6757502449201;957.8898426680881;4018.417329487208;4060.553339132693;4073.2668119738732;4130.814113364906;4154.543000269968;10223.386596804216;10455.477226138815;10548.156147508182;10549.683509494727;10557.63048293306;10410.167186625204;10504.42225677595;10525.578976840841;10533.420661863518;10533.420661863518;7299.671514781848;8012;8080.27277545012;8097.149799271109;8100.270975612106;6006.459418652343;6028.1861948638125;6152.78465253714;6172.321044684992;6242.906356404748;9606.903930960601;9854.605815767289;9905.801883962424;9929.659495967951;9950.850737238803;9493.85759213626;9629.374090684083;10051.396916185053;10090.486427036003;10103.191744660262;8039.477433966774;8091.312238459562;8150.062742784656;8195.139510351566;8202.507849528809;7980.720674776355;8069.031716194094;8081.676732930617;8129.75610731679;8140.226678465537;7419.851602967802;7439.330460258603;7461.4776965614055;7495.550266983998;7550.584717840352;7260.491765576359;7326.633668316669;7397.704091836266;7439.51643143194;7499.325060744559;6921.342472465108;6935.445164386736;7004.4046256068805;7011.1586609607175;7011.719531218898;4897.681650692693;5019.698818070928;5040.747962601782;5107.744612769421;5127.333446652061;4911.582515486224;5052.8484145475695;5116.309298244838;5125.92311115341;5233.319668443078 02:48:29 INFO - PROCESS | 1679 | |4;prototype.html;258.46884652040063;297.91062681195643;310.7529514036341;314.44500456693925;316.9461191597428;4657.184992626294;5218.034663587239;5237.37151541815;5319.281896943911;5347.369052439005;5265.26206372686;5277.70972596507;5306.416294207634;5314;5336.199570064492;4062.21262644437;4089.9795501022454;4114.115465174987;4126.257273690734;4139.847279414703;3721.888343349704;3972.4835771349685;4016.0361513236758;4152.792360381969;4155.750654960697;3448.6723761242642;3629.6733294003666;3912.767478244344;3980.24375368679;3992.8402863885553;3992.860249891254;3995.4406383106098;3999.8400063997406;4041.2725709372303;4048.210598933209;2661.4144888124583;3167.0886455227774;3255.886043988473;3395.6774106459848;3401.795892246473;4683.086798074377;5110.904529143034;5153.098207813618;5171.7672704728375;5178.844634660966;4585.977070114628;4600.17196904557;4604.217283061887;4611.423572053493;4626.421697287839;5131.768375589869;5192.741157596458;5497.45025497447;5547.972260138674;5571.359293681238;3406.863725450979;3766.794625719771;3769.9623003769884;3808.7143464240016;3815.9427608585893;5857;5901.085331773582;5915.964706176402;5922.4077592240865;5925.377835327272;3545.964540354615;3618.2582570572968;3816.923661526754;3830.9464897153507;3834.213986132836;3304.520844477537;3694.4827724118645;3853.687787327637;3866.8259928303296;3901.785401802928;3880.7865567394065;4513.727547528081;4554.658400619901;4567.954320456753;4611.953880461219;4883.95116048835;5104.948950510522;5626.549876009909;5629.155626656035;5683.431656834284;1124.9381284029457;1224.2776761710634;1326.323574976769;1334.946602135923;1336.3785839584639;771.2441807029143;781.008119687993;783.4633276205817;834.9206825351582;835.4653022065883;4055.3778488604794;4147.838605190552;4342.348647702806;4419.867403977886;4439.800208990603;4123.567025462343;4302.397664326934;4815.630571912363;4839.540243676915;4843.782029808596;3511.877084302037;3521.925812627122;3624.6077588791272;4087.2847251730827;4406.779661017001;2291.667708182304;2362.751911049315;2493.763092506209;2514.7862431693147;2541.8474891506567;4075.7962101894786;4697.671163018625;4758.476567577563;4775.808967641325;4980.5019498049905;4386.868393948187;4765.785539650724;4834.806607735652;4889.584385327287;4926.916078462804;4627.791749371287;4694.624430045588;4899.583535399531;4940.604751619933;4943.456219815817;4239.745615263094;4661.813527458864;4704.317873908332;4726.550977657117;4762.571368576706;4082.9792551862033;4404.8416276025155;4689.467687448876;4697.815515785176;4775.42694876617;4072.287349713729;4106.788497393217;4230.582754777184;4364.664904276197;4941.43173535048;2625.816192866481;3301.438755411538;3341.465365541502;3348.9906056365985;3374.2913988062783;4075.7758323292505;4175.436316097288;4685.8594242172785;4788.209945359116;4823.011282687111;4124.546299906948;4414;4636.86089417318;4640.69835460694;4766.570028991358;4594.5864872161665;4992.902980748022;5533.225348451139;5568.248286481436;5568.582356323373;5411.972940135274;5418.106012508049;5427.267318911896;5441.347038355423;5555.666660000413;4836.880212263141;5320.414754377093;5349.358077030781;5405.081196175302;5433.755481003285;4058.6865486672314;5312.893742125213;5403.83788486346;5406.513413792777;5424.430434804289 02:48:29 INFO - PROCESS | 1679 | |5;yui.html;745.3105877063716;777.5451360953838;799.3445374792668;859.0722020218163;876.7282142535819;13567.525136620221;16296;16299.348026078973;16307;16345.346186152554;14781.630459238524;14909.32908019139;14925.402983880658;14930.477433289838;14945.92527037366;710.6979533698184;731.082491473203;883.9911600884005;910.3400034974624;914.4376208631677;886.7162507997443;902.3052249767675;903.905089965554;905.8777065096226;905.9139381758722;955.6846240740539;956.7273327101777;959.3524371049549;959.8128364968834;963.4797209506858;757.4054367321626;927.3369540778376;939.4738946190155;940.0458534587399;952.3999880075577;698.4935921456903;698.6995591895483;698.9690206944766;699.7061234281614;700.4536461559991;15013.69972600542;15065.774013389699;15070;15072.171030593314;15089.849101508955;14199.36102875373;14322;14360.56918292453;14367.78448323276;14388.496402625962;11999.100067494974;12489.875101248963;12824.294663793487;13016;13585.252811095386;4546.227141385939;4617.769111544443;4620.561046700558;4669.369635099252;4831;107838;109812.4509377456;110517;110953;111807.44096279547;11271.54913803455;11458.255213411101;11598;11672.941635291769;11812.645620631394;22828.31515054518;22847.200347987742;22859.771402286096;22892.542149157256;22909.656355154686;8002.5998700064765;8218.383621228431;8303.128171542023;8380.958095209608;8397.034341050736;4411.933820992687;4550.908981820345;4592.655550833634;4664.997025639504;4693.530646935279;3120.781545291853;3370.191154123042;3519.5921631347665;3563.6792688658147;3563.7327200460068;519.6076961893822;632.1087266953573;650.9251436084911;651.5178767711859;653.2846533046306;541.9078756611385;547.6741338903346;633.467886974934;638.8594509207967;663.5023732200849;8519.446235994641;9380.718578442658;9451.669191578261;9821.263405244636;9941.701748947544;629.3863483103937;663.8838203314401;684.6884667476286;697.4315932514984;698.678607840389;4570.451545814523;4688.531146885352;4757.440357456249;4766.785494652749;4821.493743156988;122478;122911.77088229182;127946.36026819992;135059;139357.45782879388;78330;122953;127535.36232318779;128247.35876320745;129087.35456322659;114946;117220.41389793166;117532;118197;119739;7780.688772449039;8666.739997800076;8716.259117975043;8721.520316382659;8968.744375787264;55479.445205547425;59474;61632;62443.751124976334;62966.68516657387;72146;80771.19228807637;85776.57111714402;87661;90844.54577727069;13075.411606477921;13184.934075329562;13240;13529.729405412034;13821.308934553434;12391.442385092512;14823.184724840237;15113.924430377778;15279.847201527842;15562.688746224785;13972.441102355791;14084.577462676136;14110.788338175142;14344.426222951382;14776.261186940395;13942.163470191821;13954.23251721165;13982.091164074303;14586;14632.34154463073;13793.586192414243;13907.72184556283;13927.676870697267;13984.860151398356;14311.57065288043;11180.396891119246;11471;11648.88351116478;11941.402929853646;12359.938200308941;9209.309301802257;10356.637517686844;11002.724931876766;11728.296302221894;11747.353895535485 02:48:29 INFO - PROCESS | 1679 | __end_tp_report 02:48:29 INFO - PROCESS | 1679 | __start_cc_report 02:48:29 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,1151 02:48:29 INFO - PROCESS | 1679 | __end_cc_report 02:48:29 INFO - PROCESS | 1679 | __startTimestamp1468748909104__endTimestamp 02:48:29 INFO - PROCESS | 1679 | 02:48:29 INFO - PROCESS | 1679 | ------- Summary: start ------- 02:48:29 INFO - PROCESS | 1679 | Number of tests: 6 02:48:29 INFO - PROCESS | 1679 | 02:48:29 INFO - PROCESS | 1679 | [#0] dojo.html Cycles:165 Average:5670.82 Median:5840.24 stddev:655.47 (11.2%) stddev-sans-first:629.89 02:48:29 INFO - PROCESS | 1679 | Values: 3272.1 3279.7 3289.5 3300.2 3328.8 5504.1 5604.4 5605.0 5648.7 5650.1 5609.9 5640.4 5697.0 5730.3 5744.0 5089.5 5096.3 5154.0 5163.2 5262.8 5224.8 5262.3 5289.2 5290.7 5301.5 5094.9 5112.7 5134.4 5141.4 5195.7 5173.4 5185.7 5186.4 5189.2 5197.7 5099.1 5107.7 5143.3 5143.5 5146.6 5559.2 5562.6 5616.7 5724.3 5730.6 6162.6 6165.8 6185.4 6189.6 6191.6 6020.7 6182.4 6226.3 6243.4 6252.9 5619.5 5692.3 5723.7 5732.8 5739.2 6347.4 6365.9 6372.5 6373.1 6388.3 5903.4 6080.3 6099.7 6104.0 6104.4 6120.4 6134.2 6176.6 6176.9 6235.7 4461.4 5766.9 6110.5 6114.2 6140.3 5928.7 6127.9 6160.0 6164.3 6194.7 4831.9 4869.9 4870.0 4871.1 4874.6 4350.9 4374.3 4404.8 4422.0 4431.4 4771.3 5715.4 5733.1 5762.6 5805.8 6198.8 6272.9 6285.9 6296.3 6307.4 5572.5 5622.4 5651.6 5737.1 5742.5 5369.4 5378.6 5385.8 5405.4 5444.9 6310.2 6312.3 6345.7 6348.1 6375.5 6139.3 6154.1 6167.7 6245.6 6273.9 6315.6 6321.4 6360.7 6364.8 6373.1 6154.2 6176.3 6176.4 6181.6 6185.2 5840.2 6108.8 6132.0 6230.3 6241.0 5923.5 5956.6 5973.8 5980.9 5986.8 4928.9 5696.8 5928.0 5967.6 5980.9 4882.8 5664.7 5833.1 5857.2 5876.8 6048.1 6056.9 6059.8 6073.0 6093.6 5855.2 5871.6 5874.1 5877.2 5879.1 02:48:29 INFO - PROCESS | 1679 | 02:48:29 INFO - PROCESS | 1679 | [#1] ext.html Cycles:180 Average:71920.67 Median:39940.80 stddev:153294.33 (383.8%) stddev-sans-first:153630.52 02:48:29 INFO - PROCESS | 1679 | Values: 482.9 509.4 529.1 529.4 559.0 30346.8 41807.0 42759.6 42978.4 45253.0 43718.8 43977.8 44055.7 44080.9 44361.8 8713.7 9683.2 9861.5 9864.7 9867.0 8672.1 11685.8 11769.0 11782.4 12145.7 11919.3 11964.5 11987.9 11990.8 12005.3 8801.3 11709.5 11804.4 11825.6 11910.0 11814.2 11889.3 11895.1 11931.5 11958.6 42112.0 42319.2 42358.7 42359.9 42377.2 3702.4 3704.8 4214.3 4288.0 4410.4 27745.0 32625.2 33597.0 33633.2 33738.0 3331.7 3333.2 3343.4 3368.6 3375.4 489382.0 491549.0 491572.0 491711.0 492888.0 63390.4 63516.0 63768.0 63815.0 63970.0 67925.3 67989.7 67990.0 67996.0 68036.7 15349.8 17025.1 17218.2 17223.5 17233.7 11847.9 12151.8 12948.7 13167.0 13188.5 18508.6 18728.6 18736.6 19017.5 19110.9 7566.7 7592.9 7633.0 7659.3 7685.3 896.5 908.6 1058.5 1075.0 1145.8 30973.7 31108.5 31137.5 31655.5 31737.5 1003.5 1100.9 1112.4 1128.1 1131.2 12071.6 16054.0 16139.9 16268.8 16450.0 648601.0 663994.0 665541.0 668516.0 668864.0 60863.1 62375.7 62625.7 62673.7 62802.7 1122.3 1265.8 1403.3 1405.9 1419.5 31888.8 32237.7 32439.0 32506.9 33552.7 58129.7 58216.0 59012.0 59042.7 59162.4 551688.0 556901.0 557188.0 557467.0 558565.0 24563.8 27033.6 27340.9 27896.0 27962.3 49628.0 50823.0 51010.0 51302.7 51383.0 38627.8 39830.4 40274.6 42535.0 44428.6 41066.4 41686.6 41982.2 42746.7 42915.6 40051.2 40262.0 40596.8 41027.4 41117.6 34814.0 35807.6 36696.8 36832.6 36835.8 24643.6 25071.9 26673.5 27013.3 27178.3 02:48:29 INFO - PROCESS | 1679 | 02:48:29 INFO - PROCESS | 1679 | [#2] jquery.html Cycles:180 Average:31789.93 Median:27381.66 stddev:80273.32 (293.2%) stddev-sans-first:80463.75 02:48:29 INFO - PROCESS | 1679 | Values: 326.3 359.3 385.8 393.6 412.2 13176.9 15625.7 20997.0 21590.6 21663.5 21815.6 22048.2 22208.0 22373.1 22549.9 5592.5 5948.7 6162.4 6179.4 6201.3 6096.4 6120.6 6190.7 6191.4 6197.0 5965.9 6058.1 6118.8 6132.0 6158.1 5799.0 6017.8 6033.5 6074.8 6090.7 5287.1 5323.0 5327.7 5331.0 5337.0 27328.0 27358.6 27404.7 27685.9 27850.4 1952.7 2238.9 2284.3 2331.4 2538.3 15425.7 16211.5 16538.6 16770.9 16909.8 1508.4 1821.1 1866.4 1871.2 1908.6 119494.4 121630.0 122861.0 123983.0 124910.0 9214.8 9996.0 10150.8 11437.6 11557.7 14048.4 14152.1 14181.7 14188.9 14198.0 8791.0 8949.2 9005.7 9037.0 9050.0 9056.0 9068.8 9078.7 9196.4 9222.6 6779.6 6918.0 6922.6 6932.3 6948.4 1581.9 1826.4 1866.9 1867.5 1870.4 931.8 956.3 960.1 1003.9 1035.6 23208.4 23497.4 23550.4 23969.0 24003.2 887.8 934.4 972.2 982.6 1021.1 11603.8 11945.7 12242.9 12307.8 12363.6 323152.0 324897.0 325112.0 618434.0 625838.0 84363.2 135905.0 144919.0 152624.0 155004.0 904.5 949.6 950.6 989.3 1007.6 15485.6 17093.8 19607.5 19711.6 20193.7 45657.1 56555.6 56744.4 60398.7 60650.1 87211.1 87749.6 87931.6 88088.0 88106.6 7990.3 8273.3 8322.3 8354.7 8541.0 10586.7 13018.2 13063.3 13075.9 13125.5 11093.6 11145.5 11203.0 11280.8 11336.9 9900.8 10915.0 11149.4 11158.9 11198.6 11507.8 11583.2 11630.5 11780.1 11854.8 6445.0 7477.6 7539.9 7563.3 8496.5 8421.1 10163.9 11059.8 11829.2 11848.1 02:48:29 INFO - PROCESS | 1679 | 02:48:29 INFO - PROCESS | 1679 | [#3] mootools.html Cycles:180 Average:5677.00 Median:5126.59 stddev:3142.68 (61.3%) stddev-sans-first:3134.96 02:48:29 INFO - PROCESS | 1679 | Values: 1386.7 1501.9 1549.5 1771.0 1926.7 7643.4 8490.8 8726.0 8734.8 8855.6 8481.3 8706.7 8709.7 8711.7 8712.5 2090.8 2292.1 2350.2 2369.9 2382.7 2371.2 2399.1 2401.8 2402.3 2412.0 2002.0 2319.5 2352.7 2355.4 2359.0 2024.4 2264.1 2312.9 2340.6 2346.5 2332.9 2354.7 2356.1 2357.7 2361.6 8653.1 8711.5 8740.9 8744.3 8744.9 5127.3 5291.7 5294.5 5309.5 5324.2 3415.5 3420.4 3576.1 3580.2 3596.6 1033.0 1049.1 1055.1 1071.5 1074.1 10295.4 10939.9 11475.8 11478.5 11481.6 4495.6 4497.8 4726.8 4727.3 4733.0 9834.5 9835.8 9836.1 9837.6 9838.0 5291.0 5292.3 5295.8 5301.5 5312.8 4360.8 4505.1 4713.0 4854.5 4867.9 3325.1 3742.3 3869.5 3947.3 3991.3 1096.6 1237.7 1246.8 1252.9 1260.9 740.5 890.2 938.1 944.6 977.7 5964.3 5966.7 6025.7 6071.6 6183.3 942.1 945.5 950.9 953.7 957.9 4018.4 4060.6 4073.3 4130.8 4154.5 10223.4 10455.5 10548.2 10549.7 10557.6 10410.2 10504.4 10525.6 10533.4 10533.4 7299.7 8012.0 8080.3 8097.1 8100.3 6006.5 6028.2 6152.8 6172.3 6242.9 9606.9 9854.6 9905.8 9929.7 9950.9 9493.9 9629.4 10051.4 10090.5 10103.2 8039.5 8091.3 8150.1 8195.1 8202.5 7980.7 8069.0 8081.7 8129.8 8140.2 7419.9 7439.3 7461.5 7495.6 7550.6 7260.5 7326.6 7397.7 7439.5 7499.3 6921.3 6935.4 7004.4 7011.2 7011.7 4897.7 5019.7 5040.7 5107.7 5127.3 4911.6 5052.8 5116.3 5125.9 5233.3 02:48:29 INFO - PROCESS | 1679 | 02:48:29 INFO - PROCESS | 1679 | [#4] prototype.html Cycles:180 Average:4167.90 Median:4590.28 stddev:1277.81 (27.8%) stddev-sans-first:1247.25 02:48:29 INFO - PROCESS | 1679 | Values: 258.5 297.9 310.8 314.4 316.9 4657.2 5218.0 5237.4 5319.3 5347.4 5265.3 5277.7 5306.4 5314.0 5336.2 4062.2 4090.0 4114.1 4126.3 4139.8 3721.9 3972.5 4016.0 4152.8 4155.8 3448.7 3629.7 3912.8 3980.2 3992.8 3992.9 3995.4 3999.8 4041.3 4048.2 2661.4 3167.1 3255.9 3395.7 3401.8 4683.1 5110.9 5153.1 5171.8 5178.8 4586.0 4600.2 4604.2 4611.4 4626.4 5131.8 5192.7 5497.5 5548.0 5571.4 3406.9 3766.8 3770.0 3808.7 3815.9 5857.0 5901.1 5916.0 5922.4 5925.4 3546.0 3618.3 3816.9 3830.9 3834.2 3304.5 3694.5 3853.7 3866.8 3901.8 3880.8 4513.7 4554.7 4568.0 4612.0 4884.0 5104.9 5626.5 5629.2 5683.4 1124.9 1224.3 1326.3 1334.9 1336.4 771.2 781.0 783.5 834.9 835.5 4055.4 4147.8 4342.3 4419.9 4439.8 4123.6 4302.4 4815.6 4839.5 4843.8 3511.9 3521.9 3624.6 4087.3 4406.8 2291.7 2362.8 2493.8 2514.8 2541.8 4075.8 4697.7 4758.5 4775.8 4980.5 4386.9 4765.8 4834.8 4889.6 4926.9 4627.8 4694.6 4899.6 4940.6 4943.5 4239.7 4661.8 4704.3 4726.6 4762.6 4083.0 4404.8 4689.5 4697.8 4775.4 4072.3 4106.8 4230.6 4364.7 4941.4 2625.8 3301.4 3341.5 3349.0 3374.3 4075.8 4175.4 4685.9 4788.2 4823.0 4124.5 4414.0 4636.9 4640.7 4766.6 4594.6 4992.9 5533.2 5568.2 5568.6 5412.0 5418.1 5427.3 5441.3 5555.7 4836.9 5320.4 5349.4 5405.1 5433.8 4058.7 5312.9 5403.8 5406.5 5424.4 02:48:29 INFO - PROCESS | 1679 | 02:48:29 INFO - PROCESS | 1679 | [#5] yui.html Cycles:180 Average:24145.59 Median:3541.64 stddev:37323.79 (1053.9%) stddev-sans-first:37387.13 02:48:29 INFO - PROCESS | 1679 | Values: 745.3 777.5 799.3 859.1 876.7 13567.5 16296.0 16299.3 16307.0 16345.3 14781.6 14909.3 14925.4 14930.5 14945.9 710.7 731.1 884.0 910.3 914.4 886.7 902.3 903.9 905.9 905.9 955.7 956.7 959.4 959.8 963.5 757.4 927.3 939.5 940.0 952.4 698.5 698.7 699.0 699.7 700.5 15013.7 15065.8 15070.0 15072.2 15089.8 14199.4 14322.0 14360.6 14367.8 14388.5 11999.1 12489.9 12824.3 13016.0 13585.3 4546.2 4617.8 4620.6 4669.4 4831.0 107838.0 109812.5 110517.0 110953.0 111807.4 11271.5 11458.3 11598.0 11672.9 11812.6 22828.3 22847.2 22859.8 22892.5 22909.7 8002.6 8218.4 8303.1 8381.0 8397.0 4411.9 4550.9 4592.7 4665.0 4693.5 3120.8 3370.2 3519.6 3563.7 3563.7 519.6 632.1 650.9 651.5 653.3 541.9 547.7 633.5 638.9 663.5 8519.4 9380.7 9451.7 9821.3 9941.7 629.4 663.9 684.7 697.4 698.7 4570.5 4688.5 4757.4 4766.8 4821.5 122478.0 122911.8 127946.4 135059.0 139357.5 78330.0 122953.0 127535.4 128247.4 129087.4 114946.0 117220.4 117532.0 118197.0 119739.0 7780.7 8666.7 8716.3 8721.5 8968.7 55479.4 59474.0 61632.0 62443.8 62966.7 72146.0 80771.2 85776.6 87661.0 90844.5 13075.4 13184.9 13240.0 13529.7 13821.3 12391.4 14823.2 15113.9 15279.8 15562.7 13972.4 14084.6 14110.8 14344.4 14776.3 13942.2 13954.2 13982.1 14586.0 14632.3 13793.6 13907.7 13927.7 13984.9 14311.6 11180.4 11471.0 11648.9 11941.4 12359.9 9209.3 10356.6 11002.7 11728.3 11747.4 02:48:29 INFO - PROCESS | 1679 | -------- Summary: end -------- 02:48:29 INFO - PROCESS | 1679 | 02:48:29 INFO - TEST-INFO | 1679: exit 0 02:48:29 INFO - TEST-OK | dromaeo_css | took 1116467ms 02:48:29 INFO - TEST-START | kraken 02:48:29 INFO - operating with platform_type : mac_ 02:48:29 INFO - Initialising browser for kraken test... 02:48:29 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpKHws3V/profile http://localhost:49271/getInfo.html) 02:48:31 INFO - PROCESS | 1684 | __metrics Screen width/height:1600/1200 02:48:31 INFO - PROCESS | 1684 | colorDepth:24 02:48:31 INFO - PROCESS | 1684 | Browser inner width/height: 1024/689 02:48:31 INFO - PROCESS | 1684 | __metrics 02:48:32 INFO - PROCESS | 1684 | 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" 02:48:32 INFO - PROCESS | 1684 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:48:32 INFO - TEST-INFO | 1684: exit 0 02:48:32 INFO - Browser initialized. 02:48:32 INFO - Running cycle 1/1 for kraken test... 02:48:32 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpKHws3V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:48:44 INFO - PROCESS | 1685 | RSS: Main: 243695616 02:48:44 INFO - PROCESS | 1685 | 02:48:47 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 02:48:48 INFO - PROCESS | 1685 | RSS: Main: 294084608 02:48:48 INFO - PROCESS | 1685 | 02:48:50 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 02:48:51 INFO - PROCESS | 1685 | RSS: Main: 304590848 02:48:51 INFO - PROCESS | 1685 | 02:48:54 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 02:48:55 INFO - PROCESS | 1685 | RSS: Main: 389742592 02:48:55 INFO - PROCESS | 1685 | 02:48:57 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 02:48:58 INFO - PROCESS | 1685 | RSS: Main: 290942976 02:48:58 INFO - PROCESS | 1685 | 02:49:00 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:49:00 INFO - PROCESS | 1685 | RSS: Main: 294633472 02:49:00 INFO - PROCESS | 1685 | 02:49:03 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 02:49:04 INFO - PROCESS | 1685 | RSS: Main: 331350016 02:49:04 INFO - PROCESS | 1685 | 02:49:07 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 02:49:07 INFO - PROCESS | 1685 | RSS: Main: 333238272 02:49:07 INFO - PROCESS | 1685 | 02:49:10 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 02:49:11 INFO - PROCESS | 1685 | RSS: Main: 331456512 02:49:11 INFO - PROCESS | 1685 | 02:49:12 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:49:13 INFO - PROCESS | 1685 | RSS: Main: 311644160 02:49:13 INFO - PROCESS | 1685 | 02:49:15 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:49:15 INFO - PROCESS | 1685 | RSS: Main: 318406656 02:49:15 INFO - PROCESS | 1685 | 02:49:18 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:49:18 INFO - PROCESS | 1685 | RSS: Main: 315637760 02:49:18 INFO - PROCESS | 1685 | 02:49:21 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:49:22 INFO - PROCESS | 1685 | RSS: Main: 325754880 02:49:22 INFO - PROCESS | 1685 | 02:49:25 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:49:26 INFO - PROCESS | 1685 | RSS: Main: 328212480 02:49:26 INFO - PROCESS | 1685 | 02:49:27 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 02:49:28 INFO - PROCESS | 1685 | RSS: Main: 372264960 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | __start_tp_report 02:49:28 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 02:49:28 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 02:49:28 INFO - PROCESS | 1685 | |i|pagename|runs| 02:49:28 INFO - PROCESS | 1685 | |0;ai-astar;101;114;114;114;112;114;113;112;112;114 02:49:28 INFO - PROCESS | 1685 | |1;audio-beat-detection;144;108;109;160;108;108;131;106;141;109 02:49:28 INFO - PROCESS | 1685 | |2;audio-dft;182;186;183;186;183;181;179;185;182;182 02:49:28 INFO - PROCESS | 1685 | |3;audio-fft;76;74;59;86;57;57;64;65;83;56 02:49:28 INFO - PROCESS | 1685 | |4;audio-oscillator;84;85;91;72;71;67;96;69;70;98 02:49:28 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;89;85;89;85;89;84;88;86;88;85 02:49:28 INFO - PROCESS | 1685 | |6;imaging-darkroom;131;136;95;95;95;94;95;95;97;94 02:49:28 INFO - PROCESS | 1685 | |7;imaging-desaturate;85;86;85;84;85;84;86;84;84;83 02:49:28 INFO - PROCESS | 1685 | |8;json-parse-financial;56;56;55;53;54;53;53;53;53;53 02:49:28 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;68;74;75;77;75;75;71;71;72;74 02:49:28 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;83;95;98;104;78;81;85;85;84;78 02:49:28 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;137;136;144;136;145;147;149;147;249;156 02:49:28 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;168;232;184;169;168;169;169;170;168;167 02:49:28 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;67;67;67;68;67;68;68;67;67;65 02:49:28 INFO - PROCESS | 1685 | __end_tp_report 02:49:28 INFO - PROCESS | 1685 | __start_cc_report 02:49:28 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,2009 02:49:28 INFO - PROCESS | 1685 | __end_cc_report 02:49:28 INFO - PROCESS | 1685 | __startTimestamp1468748968111__endTimestamp 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | ------- Summary: start ------- 02:49:28 INFO - PROCESS | 1685 | Number of tests: 14 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:112.00 Median:114.00 stddev:3.97 (3.5%) stddev-sans-first:0.97 02:49:28 INFO - PROCESS | 1685 | Values: 101.0 114.0 114.0 114.0 112.0 114.0 113.0 112.0 112.0 114.0 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:122.40 Median:120.00 stddev:19.86 (16.6%) stddev-sans-first:19.47 02:49:28 INFO - PROCESS | 1685 | Values: 144.0 108.0 109.0 160.0 108.0 108.0 131.0 106.0 141.0 109.0 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.00 stddev:2.23 (1.2%) stddev-sans-first:2.35 02:49:28 INFO - PROCESS | 1685 | Values: 182.0 186.0 183.0 186.0 183.0 181.0 179.0 185.0 182.0 182.0 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:67.70 Median:69.50 stddev:11.26 (16.2%) stddev-sans-first:11.53 02:49:28 INFO - PROCESS | 1685 | Values: 76.0 74.0 59.0 86.0 57.0 57.0 64.0 65.0 83.0 56.0 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:80.30 Median:84.50 stddev:11.91 (14.1%) stddev-sans-first:12.55 02:49:28 INFO - PROCESS | 1685 | Values: 84.0 85.0 91.0 72.0 71.0 67.0 96.0 69.0 70.0 98.0 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:88.00 stddev:1.99 (2.3%) stddev-sans-first:1.94 02:49:28 INFO - PROCESS | 1685 | Values: 89.0 85.0 89.0 85.0 89.0 84.0 88.0 86.0 88.0 85.0 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:102.70 Median:95.00 stddev:16.30 (17.2%) stddev-sans-first:13.69 02:49:28 INFO - PROCESS | 1685 | Values: 131.0 136.0 95.0 95.0 95.0 94.0 95.0 95.0 97.0 94.0 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:0.97 (1.1%) stddev-sans-first:1.01 02:49:28 INFO - PROCESS | 1685 | Values: 85.0 86.0 85.0 84.0 85.0 84.0 86.0 84.0 84.0 83.0 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:53.50 stddev:1.29 (2.4%) stddev-sans-first:1.12 02:49:28 INFO - PROCESS | 1685 | Values: 56.0 56.0 55.0 53.0 54.0 53.0 53.0 53.0 53.0 53.0 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:73.20 Median:74.50 stddev:2.66 (3.6%) stddev-sans-first:2.05 02:49:28 INFO - PROCESS | 1685 | Values: 68.0 74.0 75.0 77.0 75.0 75.0 71.0 71.0 72.0 74.0 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:84.50 stddev:8.85 (10.5%) stddev-sans-first:9.26 02:49:28 INFO - PROCESS | 1685 | Values: 83.0 95.0 98.0 104.0 78.0 81.0 85.0 85.0 84.0 78.0 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:154.60 Median:147.00 stddev:33.77 (23.0%) stddev-sans-first:35.22 02:49:28 INFO - PROCESS | 1685 | Values: 137.0 136.0 144.0 136.0 145.0 147.0 149.0 147.0 249.0 156.0 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.40 Median:169.00 stddev:20.15 (11.9%) stddev-sans-first:21.14 02:49:28 INFO - PROCESS | 1685 | Values: 168.0 232.0 184.0 169.0 168.0 169.0 169.0 170.0 168.0 167.0 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 02:49:28 INFO - PROCESS | 1685 | Values: 67.0 67.0 67.0 68.0 67.0 68.0 68.0 67.0 67.0 65.0 02:49:28 INFO - PROCESS | 1685 | -------- Summary: end -------- 02:49:28 INFO - PROCESS | 1685 | 02:49:28 INFO - TEST-INFO | 1685: exit 0 02:49:28 INFO - TEST-OK | kraken | took 59200ms 02:49:28 INFO - SUITE-END | took 1175s 02:49:29 INFO - Completed test suite (00:19:36) 02:49:29 INFO - javascript benchmark 02:49:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3272.132884785532, 3279.6720327967223, 3289.4572395554733, 3300.1584595928034, 3328.7836290641108, 5504.064309067458, 5604.3835178130375, 5604.9719751401235, 5648.6893220872835, 5650.0677388231, 5609.915851262234, 5640.435956404358, 5697.0, 5730.255066841315, 5743.9712801436035, 5089.46560611136, 5096.337476128098, 5154.046501397234, 5163.225516172567, 5262.842114736564, 5224.817131400383, 5262.315898933152, 5289.233061206123, 5290.7354632268425, 5301.469853014687, 5094.898102037975, 5112.693238405707, 5134.435212126663, 5141.357330333708, 5195.714235717034, 5173.3533308336455, 5185.714785686786, 5186.40356359016, 5189.247559103909, 5197.7401129943355, 5099.133147364957, 5107.7446127693465, 5143.27994080829, 5143.537081662668, 5146.562542183919, 5559.19391688207, 5562.554995600342, 5616.691081990489, 5724.313082430135, 5730.598858079936, 6162.630242185483, 6165.7533698652, 6185.381461853824, 6189.566730328879, 6191.59754615953, 6020.729067191987, 6182.443580077815, 6226.2839773426185, 6243.375662433766, 6252.906206406908, 5619.522340601013, 5692.316921969349, 5723.656580605177, 5732.7706891723865, 5739.196512488255, 6347.428731414195, 6365.936340636627, 6372.522060845363, 6373.139626150503, 6388.297287298394, 5903.3801450847905, 6080.346370212959, 6099.695015249308, 6103.962326404522, 6104.428909088145, 6120.447133218794, 6134.2332208473945, 6176.629402235881, 6176.907346389808, 6235.657038862815, 4461.420015398043, 5766.942330576724, 6110.511159107261, 6114.205153330038, 6140.293866205444, 5928.703564821742, 6127.938720612826, 6160.045192995093, 6164.291106522717, 6194.659293738888, 4831.9275210871865, 4869.878253043631, 4869.9513004870205, 4871.123197824426, 4874.6344024197615, 4350.869473915787, 4374.321980093091, 4404.757738324359, 4422.0050488640445, 4431.379606855043, 4771.260454629538, 5715.371309155989, 5733.140028995601, 5762.625429347079, 5805.82582522525, 6198.845028874314, 6272.905906411407, 6285.899967505668, 6296.307406185316, 6307.432331090132, 5572.526335261548, 5622.381538030813, 5651.576131790132, 5737.110747834093, 5742.454466825645, 5369.4362091980565, 5378.596605254622, 5385.8114965976, 5405.378381486101, 5444.91832622511, 6310.242770867525, 6312.337204593635, 6345.650989195547, 6348.111264422984, 6375.521835862236, 6139.29398119222, 6154.076888466766, 6167.6916154193, 6245.5628106032145, 6273.9334313165955, 6315.5894866833505, 6321.399467050623, 6360.713767880549, 6364.77723279683, 6373.107764913008, 6154.19995400613, 6176.258848938156, 6176.3514830943905, 6181.629102253879, 6185.195924529782, 5840.240768700041, 6108.75564977396, 6131.96934015327, 6230.345813689497, 6241.0, 5923.49650279731, 5956.612820166675, 5973.761049557969, 5980.940190598212, 5986.760529578768, 4928.9156385595115, 5696.772129114789, 5927.9703601481715, 5967.552433567586, 5980.880382392241, 4882.82910098159, 5664.745086471036, 5833.125031245348, 5857.150713146655, 5876.764929402776, 6048.092786082123, 6056.939430605814, 6059.818205453844, 6072.969635151796, 6093.634381937098, 5855.151003104611, 5871.559633027455, 5874.118882167709, 5877.177195192591, 5879.147523608967], "value": 5629.078510840623, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.9058333624943, 509.43198333857737, 529.1322231540273, 529.3859123416835, 558.9714924538848, 30346.84826575867, 41807.0, 42759.57240427595, 42978.35532467012, 45253.0, 43718.781406093, 43977.7801110994, 44055.67832965008, 44080.89797755058, 44361.77819110908, 8713.738587842343, 9683.225341972642, 9861.506924653775, 9864.654737084204, 9866.950665246664, 8672.13278672131, 11685.7662846743, 11768.99963503099, 11782.351970641612, 12145.696357591043, 11919.344436056013, 11964.461599228056, 11987.880121198807, 11990.7601847963, 12005.339706316192, 8801.295896328278, 11709.473073711704, 11804.350760708157, 11825.645230643095, 11909.98765104967, 11814.17300788945, 11889.28664280146, 11895.10786690993, 11931.522739090426, 11958.641240762705, 42112.0, 42319.1536169278, 42358.729238122905, 42359.9410014749, 42377.152456951, 3702.444633305026, 3704.833282502294, 4214.325707886754, 4287.978560107211, 4410.404595379615, 27745.0, 32625.184370390692, 33597.0, 33633.15917102068, 33738.0, 3331.7001469867587, 3333.233356328058, 3343.4483310253777, 3368.5620869286836, 3375.3755555222115, 489382.0, 491549.0, 491572.0, 491711.0, 492888.0, 63390.36609633937, 63516.0, 63768.04347934785, 63815.0, 63970.040449393295, 67925.32074679289, 67989.66005169942, 67989.98015029778, 67996.0, 68036.6598167006, 15349.769753453707, 17025.06362150069, 17218.225179866935, 17223.483295501155, 17233.655326893644, 11847.933685968304, 12151.756964860653, 12948.741025179632, 13167.0, 13188.538401155913, 18508.629827403376, 18728.625427491374, 18736.625267494575, 19017.524561886064, 19110.948897810475, 7566.659500322498, 7592.924070759333, 7633.0, 7659.310662040443, 7685.346745526581, 896.4934811831295, 908.5729707037682, 1058.523664351045, 1074.978500429987, 1145.8115000349294, 30973.69026309753, 31108.533371999438, 31137.532937005963, 31655.525167122512, 31737.523937140963, 1003.544859953071, 1100.862392200975, 1112.4159816096615, 1128.0693427921997, 1131.185546406586, 12071.637850864488, 16054.0, 16139.919300403422, 16268.75596866048, 16450.0, 648601.0, 663994.0, 665541.0, 668516.0, 668864.0, 60863.08705369423, 62375.6881215591, 62625.68687156535, 62673.686631566554, 62802.74394512176, 1122.3041714137287, 1265.822784810109, 1403.3264033263886, 1405.8523854995283, 1419.4889839657715, 31888.840555797073, 32237.677623223466, 32439.02682919516, 32506.86225982079, 33552.664473354955, 58129.70935145297, 58216.0, 59012.0, 59042.70478647579, 59162.40837591741, 551688.0, 556901.0, 557188.0, 557467.0, 558565.0, 24563.754362456148, 27033.59449608297, 27340.863295683394, 27896.0, 27962.300942475787, 49628.0, 50823.0, 51010.0, 51302.74348628233, 51383.0, 38627.80686096552, 39830.40254396128, 40274.59725402826, 42535.0, 44428.55571444244, 41066.38400424056, 41686.58313416949, 41982.16035679208, 42746.717598472096, 42915.57084429116, 40051.19897601973, 40261.99345016281, 40596.79701601473, 41027.38458923059, 41117.58882411138, 34814.0, 35807.64192358043, 36696.81651591725, 36832.63167368292, 36835.815820920725, 24643.630345544472, 25071.87464062668, 26673.466530669666, 27013.324666883484, 27178.32054198661], "value": 21193.05192893264, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.3375348043472, 359.33522982482395, 385.78781670081446, 393.6476853216373, 412.21267379305516, 13176.868231317683, 15625.687486250268, 20997.0, 21590.568188636236, 21663.45841353963, 21815.563688726215, 22048.228312009083, 22208.0006399712, 22373.10507579695, 22549.887250563766, 5592.468715472024, 5948.7323070461825, 6162.445379915806, 6179.412955769195, 6201.348858369874, 6096.35988221237, 6120.60216085953, 6190.69046547673, 6191.4118158774845, 6196.9690151549175, 5965.850853728648, 6058.091286307045, 6118.8470288242925, 6132.018876979684, 6158.107074474198, 5798.971005144989, 6017.849553761169, 6033.48715359195, 6074.757009719628, 6090.725917333709, 5287.07476191665, 5323.0, 5327.653702509363, 5330.9733451332495, 5336.9733151334385, 27328.0, 27358.589621155697, 27404.725952740417, 27685.861570692217, 27850.442991140062, 1952.726618273443, 2238.876861772602, 2284.303287497313, 2331.3821837213154, 2538.2639034680105, 15425.691486170326, 16211.51365459038, 16538.58653533671, 16770.916145419196, 16909.83090169107, 1508.4117194294233, 1821.0712536606293, 1866.413974787035, 1871.214090082155, 1908.6182763447366, 119494.40252798681, 121630.0, 122861.0, 123983.0, 124910.0, 9214.815703685888, 9996.0, 10150.847737283946, 11437.59968401102, 11557.653270401734, 14048.438062477386, 14152.080114792505, 14181.716365672628, 14188.929055354658, 14198.0, 8790.95604521986, 8949.239314658196, 9005.729828105168, 9036.954815225881, 9050.049744776836, 9056.0, 9068.818623627623, 9078.682246121487, 9196.448213107235, 9222.584983675752, 6779.559328643622, 6918.0314755934205, 6922.550034247758, 6932.341427564374, 6948.374646281859, 1581.9209039548207, 1826.4246762269843, 1866.9346572869883, 1867.5144462439864, 1870.373424902673, 931.7763736703278, 956.3353469338742, 960.1118964957386, 1003.94478303694, 1035.5605708065796, 23208.4197895054, 23497.434308812248, 23550.411239719146, 23969.0, 24003.15988940379, 887.7647423432795, 934.4206591913057, 972.1736523954723, 982.5922242269378, 1021.0963297481782, 11603.76792464146, 11945.701357466132, 12242.85345870768, 12307.830756078158, 12363.567275145328, 323152.0, 324897.0, 325112.0, 618434.0, 625838.0, 84363.15636843676, 135905.0, 144919.0, 152624.0, 155004.0, 904.5432056811268, 949.6467533764359, 950.5864948747317, 989.2778271861438, 1007.5818535307765, 15485.612859678598, 17093.829061709224, 19607.509812254808, 19711.60576788485, 20193.697094543888, 45657.08685826331, 56555.58611034757, 56744.432555675565, 60398.69800650969, 60650.09024864719, 87211.1278887203, 87749.56125219334, 87931.56034219787, 88088.0, 88106.55946720226, 7990.280874721303, 8273.33813294947, 8322.250997410261, 8354.707585234488, 8541.0, 10586.735331616772, 13018.218906865619, 13063.346832658519, 13075.869241307466, 13125.474981000652, 11093.611723589636, 11145.498452569816, 11203.047740942111, 11280.830787538358, 11336.886631133582, 9900.801983960137, 10915.017648411682, 11149.386783726648, 11158.88841111611, 11198.552057917917, 11507.827382589436, 11583.189176757884, 11630.534778609099, 11780.05759539252, 11854.822177667515, 6444.967775161094, 7477.588732619758, 7539.886901696588, 7563.319301262913, 8496.490210587384, 8421.115782842713, 10163.881972983114, 11059.778804423706, 11829.17195796286, 11848.052155827685], "value": 9872.79525930866, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.7157232767272, 1501.8798496120312, 1549.5351394581623, 1770.9911450442746, 1926.7495225620667, 7643.350315223206, 8490.787730306738, 8725.956370218157, 8734.781630459234, 8855.5572221389, 8481.279091277233, 8706.738797836058, 8709.695160669378, 8711.651533938635, 8712.520811355389, 2090.832733381326, 2292.0946226240626, 2350.2244259394442, 2369.905203791855, 2382.65451509531, 2371.1937941100023, 2399.1243196233336, 2401.771831675988, 2402.327348342458, 2412.0231306320966, 2002.0, 2319.5013072189477, 2352.6706261123454, 2355.3522781235124, 2359.0445869422824, 2024.4230394337617, 2264.083046366224, 2312.9074837006583, 2340.648902664597, 2346.507233480971, 2332.8833558322185, 2354.693889794333, 2356.1282325539487, 2357.705286839145, 2361.5985282502015, 8653.091425400306, 8711.520866352348, 8740.8688869667, 8744.256738177199, 8744.912550874473, 5127.256547800548, 5291.735413229323, 5294.523492885659, 5309.548688361494, 5324.174752913266, 3415.453527435623, 3420.3501334746566, 3576.1059735066233, 3580.2481478889463, 3596.5504311961004, 1032.9610247598239, 1049.1449468683, 1055.1137044882337, 1071.5338827610015, 1074.1031238915516, 10295.3822770634, 10939.890601094046, 11475.77048459026, 11478.540858365655, 11481.598144065001, 4495.550444955478, 4497.8200871964755, 4726.834560790356, 4727.267273572533, 4733.0, 9834.508274586242, 9835.803283934425, 9836.06557377048, 9837.55730992107, 9838.016198380104, 5291.0, 5292.285541451854, 5295.814646487354, 5301.5493683037375, 5312.840614781563, 4360.825566977285, 4505.098980203972, 4712.952870471251, 4854.466008739036, 4867.853964381075, 3325.068980685412, 3742.288965096623, 3869.4776205212497, 3947.2697550953167, 3991.3214753491975, 1096.5668560918393, 1237.7091383524864, 1246.8254444377612, 1252.918560293578, 1260.8865202131851, 740.5445650924643, 890.2210565754964, 938.1462868789505, 944.5749412764146, 977.745786095606, 5964.31410387811, 5966.701664916737, 6025.728842202111, 6071.574989750675, 6183.319834818167, 942.1285311087217, 945.5319616789733, 950.8573713942826, 953.6757502449201, 957.8898426680881, 4018.417329487208, 4060.553339132693, 4073.2668119738732, 4130.814113364906, 4154.543000269968, 10223.386596804216, 10455.477226138815, 10548.156147508182, 10549.683509494727, 10557.63048293306, 10410.167186625204, 10504.42225677595, 10525.578976840841, 10533.420661863518, 10533.420661863518, 7299.671514781848, 8012.0, 8080.27277545012, 8097.149799271109, 8100.270975612106, 6006.459418652343, 6028.1861948638125, 6152.78465253714, 6172.321044684992, 6242.906356404748, 9606.903930960601, 9854.605815767289, 9905.801883962424, 9929.659495967951, 9950.850737238803, 9493.85759213626, 9629.374090684083, 10051.396916185053, 10090.486427036003, 10103.191744660262, 8039.477433966774, 8091.312238459562, 8150.062742784656, 8195.139510351566, 8202.507849528809, 7980.720674776355, 8069.031716194094, 8081.676732930617, 8129.75610731679, 8140.226678465537, 7419.851602967802, 7439.330460258603, 7461.4776965614055, 7495.550266983998, 7550.584717840352, 7260.491765576359, 7326.633668316669, 7397.704091836266, 7439.51643143194, 7499.325060744559, 6921.342472465108, 6935.445164386736, 7004.4046256068805, 7011.1586609607175, 7011.719531218898, 4897.681650692693, 5019.698818070928, 5040.747962601782, 5107.744612769421, 5127.333446652061, 4911.582515486224, 5052.8484145475695, 5116.309298244838, 5125.92311115341, 5233.319668443078], "value": 4567.033579345947, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [258.46884652040063, 297.91062681195643, 310.7529514036341, 314.44500456693925, 316.9461191597428, 4657.184992626294, 5218.034663587239, 5237.37151541815, 5319.281896943911, 5347.369052439005, 5265.26206372686, 5277.70972596507, 5306.416294207634, 5314.0, 5336.199570064492, 4062.21262644437, 4089.9795501022454, 4114.115465174987, 4126.257273690734, 4139.847279414703, 3721.888343349704, 3972.4835771349685, 4016.0361513236758, 4152.792360381969, 4155.750654960697, 3448.6723761242642, 3629.6733294003666, 3912.767478244344, 3980.24375368679, 3992.8402863885553, 3992.860249891254, 3995.4406383106098, 3999.8400063997406, 4041.2725709372303, 4048.210598933209, 2661.4144888124583, 3167.0886455227774, 3255.886043988473, 3395.6774106459848, 3401.795892246473, 4683.086798074377, 5110.904529143034, 5153.098207813618, 5171.7672704728375, 5178.844634660966, 4585.977070114628, 4600.17196904557, 4604.217283061887, 4611.423572053493, 4626.421697287839, 5131.768375589869, 5192.741157596458, 5497.45025497447, 5547.972260138674, 5571.359293681238, 3406.863725450979, 3766.794625719771, 3769.9623003769884, 3808.7143464240016, 3815.9427608585893, 5857.0, 5901.085331773582, 5915.964706176402, 5922.4077592240865, 5925.377835327272, 3545.964540354615, 3618.2582570572968, 3816.923661526754, 3830.9464897153507, 3834.213986132836, 3304.520844477537, 3694.4827724118645, 3853.687787327637, 3866.8259928303296, 3901.785401802928, 3880.7865567394065, 4513.727547528081, 4554.658400619901, 4567.954320456753, 4611.953880461219, 4883.95116048835, 5104.948950510522, 5626.549876009909, 5629.155626656035, 5683.431656834284, 1124.9381284029457, 1224.2776761710634, 1326.323574976769, 1334.946602135923, 1336.3785839584639, 771.2441807029143, 781.008119687993, 783.4633276205817, 834.9206825351582, 835.4653022065883, 4055.3778488604794, 4147.838605190552, 4342.348647702806, 4419.867403977886, 4439.800208990603, 4123.567025462343, 4302.397664326934, 4815.630571912363, 4839.540243676915, 4843.782029808596, 3511.877084302037, 3521.925812627122, 3624.6077588791272, 4087.2847251730827, 4406.779661017001, 2291.667708182304, 2362.751911049315, 2493.763092506209, 2514.7862431693147, 2541.8474891506567, 4075.7962101894786, 4697.671163018625, 4758.476567577563, 4775.808967641325, 4980.5019498049905, 4386.868393948187, 4765.785539650724, 4834.806607735652, 4889.584385327287, 4926.916078462804, 4627.791749371287, 4694.624430045588, 4899.583535399531, 4940.604751619933, 4943.456219815817, 4239.745615263094, 4661.813527458864, 4704.317873908332, 4726.550977657117, 4762.571368576706, 4082.9792551862033, 4404.8416276025155, 4689.467687448876, 4697.815515785176, 4775.42694876617, 4072.287349713729, 4106.788497393217, 4230.582754777184, 4364.664904276197, 4941.43173535048, 2625.816192866481, 3301.438755411538, 3341.465365541502, 3348.9906056365985, 3374.2913988062783, 4075.7758323292505, 4175.436316097288, 4685.8594242172785, 4788.209945359116, 4823.011282687111, 4124.546299906948, 4414.0, 4636.86089417318, 4640.69835460694, 4766.570028991358, 4594.5864872161665, 4992.902980748022, 5533.225348451139, 5568.248286481436, 5568.582356323373, 5411.972940135274, 5418.106012508049, 5427.267318911896, 5441.347038355423, 5555.666660000413, 4836.880212263141, 5320.414754377093, 5349.358077030781, 5405.081196175302, 5433.755481003285, 4058.6865486672314, 5312.893742125213, 5403.83788486346, 5406.513413792777, 5424.430434804289], "value": 3766.870619433138, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.3105877063716, 777.5451360953838, 799.3445374792668, 859.0722020218163, 876.7282142535819, 13567.525136620221, 16296.0, 16299.348026078973, 16307.0, 16345.346186152554, 14781.630459238524, 14909.32908019139, 14925.402983880658, 14930.477433289838, 14945.92527037366, 710.6979533698184, 731.082491473203, 883.9911600884005, 910.3400034974624, 914.4376208631677, 886.7162507997443, 902.3052249767675, 903.905089965554, 905.8777065096226, 905.9139381758722, 955.6846240740539, 956.7273327101777, 959.3524371049549, 959.8128364968834, 963.4797209506858, 757.4054367321626, 927.3369540778376, 939.4738946190155, 940.0458534587399, 952.3999880075577, 698.4935921456903, 698.6995591895483, 698.9690206944766, 699.7061234281614, 700.4536461559991, 15013.69972600542, 15065.774013389699, 15070.0, 15072.171030593314, 15089.849101508955, 14199.36102875373, 14322.0, 14360.56918292453, 14367.78448323276, 14388.496402625962, 11999.100067494974, 12489.875101248963, 12824.294663793487, 13016.0, 13585.252811095386, 4546.227141385939, 4617.769111544443, 4620.561046700558, 4669.369635099252, 4831.0, 107838.0, 109812.4509377456, 110517.0, 110953.0, 111807.44096279547, 11271.54913803455, 11458.255213411101, 11598.0, 11672.941635291769, 11812.645620631394, 22828.31515054518, 22847.200347987742, 22859.771402286096, 22892.542149157256, 22909.656355154686, 8002.5998700064765, 8218.383621228431, 8303.128171542023, 8380.958095209608, 8397.034341050736, 4411.933820992687, 4550.908981820345, 4592.655550833634, 4664.997025639504, 4693.530646935279, 3120.781545291853, 3370.191154123042, 3519.5921631347665, 3563.6792688658147, 3563.7327200460068, 519.6076961893822, 632.1087266953573, 650.9251436084911, 651.5178767711859, 653.2846533046306, 541.9078756611385, 547.6741338903346, 633.467886974934, 638.8594509207967, 663.5023732200849, 8519.446235994641, 9380.718578442658, 9451.669191578261, 9821.263405244636, 9941.701748947544, 629.3863483103937, 663.8838203314401, 684.6884667476286, 697.4315932514984, 698.678607840389, 4570.451545814523, 4688.531146885352, 4757.440357456249, 4766.785494652749, 4821.493743156988, 122478.0, 122911.77088229182, 127946.36026819992, 135059.0, 139357.45782879388, 78330.0, 122953.0, 127535.36232318779, 128247.35876320745, 129087.35456322659, 114946.0, 117220.41389793166, 117532.0, 118197.0, 119739.0, 7780.688772449039, 8666.739997800076, 8716.259117975043, 8721.520316382659, 8968.744375787264, 55479.445205547425, 59474.0, 61632.0, 62443.751124976334, 62966.68516657387, 72146.0, 80771.19228807637, 85776.57111714402, 87661.0, 90844.54577727069, 13075.411606477921, 13184.934075329562, 13240.0, 13529.729405412034, 13821.308934553434, 12391.442385092512, 14823.184724840237, 15113.924430377778, 15279.847201527842, 15562.688746224785, 13972.441102355791, 14084.577462676136, 14110.788338175142, 14344.426222951382, 14776.261186940395, 13942.163470191821, 13954.23251721165, 13982.091164074303, 14586.0, 14632.34154463073, 13793.586192414243, 13907.72184556283, 13927.676870697267, 13984.860151398356, 14311.57065288043, 11180.396891119246, 11471.0, 11648.88351116478, 11941.402929853646, 12359.938200308941, 9209.309301802257, 10356.637517686844, 11002.724931876766, 11728.296302221894, 11747.353895535485], "value": 7950.051791240481, "unit": "score"}], "name": "dromaeo_css", "value": 7376.520117017007, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 114.0, 114.0, 112.0, 114.0, 113.0, 112.0, 112.0, 114.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 108.0, 109.0, 160.0, 108.0, 108.0, 131.0, 106.0, 141.0, 109.0], "value": 122.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 186.0, 183.0, 186.0, 183.0, 181.0, 179.0, 185.0, 182.0, 182.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 59.0, 86.0, 57.0, 57.0, 64.0, 65.0, 83.0, 56.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 91.0, 72.0, 71.0, 67.0, 96.0, 69.0, 70.0, 98.0], "value": 80.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 85.0, 89.0, 84.0, 88.0, 86.0, 88.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 136.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 97.0, 94.0], "value": 102.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 84.0, 85.0, 84.0, 86.0, 84.0, 84.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 53.0, 54.0, 53.0, 53.0, 53.0, 53.0, 53.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 75.0, 77.0, 75.0, 75.0, 71.0, 71.0, 72.0, 74.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 95.0, 98.0, 104.0, 78.0, 81.0, 85.0, 85.0, 84.0, 78.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 144.0, 136.0, 145.0, 147.0, 149.0, 147.0, 249.0, 156.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 232.0, 184.0, 169.0, 168.0, 169.0, 169.0, 170.0, 168.0, 167.0], "value": 176.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 67.0, 68.0, 68.0, 67.0, 67.0, 65.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1451.6999999999998, "alertThreshold": 2.0}]} 02:49:29 INFO - Return code: 0 02:49:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:49:29 INFO - # TBPL SUCCESS # 02:49:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:49:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:49:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:49:29 INFO - 2016-07-17 02:49:29.427 screenresolution[1689:18114] starting screenresolution argv=screenresolution get 02:49:29 INFO - 2016-07-17 02:49:29.429 screenresolution[1689:18114] Display 0: 1600x1200x32@60 02:49:29 INFO - 2016-07-17 02:49:29.435 screenresolution[1690:18117] starting screenresolution argv=screenresolution list 02:49:29 INFO - Available Modes on Display 0 02:49:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:49:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:49:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:49:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:49:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:49:29 INFO - Intel Iris: 02:49:29 INFO - Chipset Model: Intel Iris 02:49:29 INFO - Type: GPU 02:49:29 INFO - Bus: Built-In 02:49:29 INFO - VRAM (Dynamic, Max): 1536 MB 02:49:29 INFO - Vendor: Intel (0x8086) 02:49:29 INFO - Device ID: 0x0a2e 02:49:29 INFO - Revision ID: 0x0009 02:49:29 INFO - Displays: 02:49:29 INFO - Display: 02:49:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:49:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:49:29 INFO - Display Serial Number: 1600x1200 60 02:49:29 INFO - Main Display: Yes 02:49:29 INFO - Mirror: Off 02:49:29 INFO - Online: Yes 02:49:29 INFO - Rotation: Supported 02:49:29 INFO - Return code: 0 02:49:29 INFO - Running post-action listener: _resource_record_post_action 02:49:29 INFO - ##### 02:49:29 INFO - ##### Finished run-tests step (success) 02:49:29 INFO - ##### 02:49:29 INFO - Running post-run listener: _resource_record_post_run 02:49:29 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31444480; Write bytes: 705744896; Read time: 192; Write time: 1946 02:49:29 INFO - TinderboxPrint: CPU usage
53.6% 02:49:29 INFO - TinderboxPrint: I/O read bytes / time
31,444,480 / 192 02:49:29 INFO - TinderboxPrint: I/O write bytes / time
705,744,896 / 1,946 02:49:29 INFO - TinderboxPrint: CPU idle
2,246.4 (46.4%) 02:49:29 INFO - TinderboxPrint: CPU system
968.9 (20.0%) 02:49:29 INFO - TinderboxPrint: CPU user
1,630.3 (33.6%) 02:49:29 INFO - TinderboxPrint: Swap in / out
434,503,680 / 0 02:49:29 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 388499968; Write bytes: 383188480; Read time: 29303; Write time: 796 02:49:29 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30522880; Write bytes: 295264768; Read time: 181; Write time: 1089 02:49:30 INFO - Running post-run listener: _upload_blobber_files 02:49:30 INFO - Blob upload gear active. 02:49:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:49:30 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 02:49:30 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:49:30 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:49:30 INFO - (blobuploader) - INFO - Open directory for files ... 02:49:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:49:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:49:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:31 INFO - (blobuploader) - INFO - Done attempting. 02:49:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:49:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:49:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:32 INFO - (blobuploader) - INFO - Done attempting. 02:49:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:49:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:49:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:35 INFO - (blobuploader) - INFO - Done attempting. 02:49:35 INFO - (blobuploader) - INFO - Iteration through files over. 02:49:35 INFO - Return code: 0 02:49:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:49:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:49:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5182dbb2e45d66bfd559c50c4d6d8d4311bc3ab1bb8298e351abbe1fa553abbe46b1f247f32cb3d9061c4a1a7e820f0317d5637d71d635eda9588d8f0c54898", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6317c9363b838455b8b8082266acfc6a53631ae81aa8f1285553867438fa2b74b1409c51a79449e34a10fc89cce3969cf7fef840b5ca59af0c74c364fc6bc2dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c0d9cfd78d2de389f467aeeb5c5ed84bca14cfe22baf9a6645968f35e8cdc0d0c5c9d09006b5af7236fb120f49a2e7e0e86a04a26318a5db681273f354d4525"} 02:49:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:49:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:49:35 INFO - Contents: 02:49:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5182dbb2e45d66bfd559c50c4d6d8d4311bc3ab1bb8298e351abbe1fa553abbe46b1f247f32cb3d9061c4a1a7e820f0317d5637d71d635eda9588d8f0c54898", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6317c9363b838455b8b8082266acfc6a53631ae81aa8f1285553867438fa2b74b1409c51a79449e34a10fc89cce3969cf7fef840b5ca59af0c74c364fc6bc2dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c0d9cfd78d2de389f467aeeb5c5ed84bca14cfe22baf9a6645968f35e8cdc0d0c5c9d09006b5af7236fb120f49a2e7e0e86a04a26318a5db681273f354d4525"} 02:49:35 INFO - Running post-run listener: copy_logs_to_upload_dir 02:49:35 INFO - Copying logs to upload dir... 02:49:35 INFO - mkdir: /builds/slave/test/build/upload/logs 02:49:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1303.424229 ========= master_lag: 0.79 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 44 secs) (at 2016-07-17 02:49:36.721543) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 02:49:36.725439) ========= 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.a2AOlmx3uO/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.UrCdrOePGk/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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/Firefox/sha512/b5182dbb2e45d66bfd559c50c4d6d8d4311bc3ab1bb8298e351abbe1fa553abbe46b1f247f32cb3d9061c4a1a7e820f0317d5637d71d635eda9588d8f0c54898", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6317c9363b838455b8b8082266acfc6a53631ae81aa8f1285553867438fa2b74b1409c51a79449e34a10fc89cce3969cf7fef840b5ca59af0c74c364fc6bc2dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c0d9cfd78d2de389f467aeeb5c5ed84bca14cfe22baf9a6645968f35e8cdc0d0c5c9d09006b5af7236fb120f49a2e7e0e86a04a26318a5db681273f354d4525"} build_url:https://queue.taskcluster.net/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011442 build_url: 'https://queue.taskcluster.net/v1/task/fEuiFTCcS_GiTXIo7U-jOQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5182dbb2e45d66bfd559c50c4d6d8d4311bc3ab1bb8298e351abbe1fa553abbe46b1f247f32cb3d9061c4a1a7e820f0317d5637d71d635eda9588d8f0c54898", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6317c9363b838455b8b8082266acfc6a53631ae81aa8f1285553867438fa2b74b1409c51a79449e34a10fc89cce3969cf7fef840b5ca59af0c74c364fc6bc2dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c0d9cfd78d2de389f467aeeb5c5ed84bca14cfe22baf9a6645968f35e8cdc0d0c5c9d09006b5af7236fb120f49a2e7e0e86a04a26318a5db681273f354d4525"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 02:49:36.766279) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 02:49:36.766603) ========= 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.a2AOlmx3uO/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.UrCdrOePGk/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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.005176 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 02:49:36.815243) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 02:49:36.815591) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 02:49:36.815948) ========= ========= Total master_lag: 0.93 =========