builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0318 starttime: 1468865885.89 results: success (0) buildid: 20160718081125 builduid: 287f226fbb2b4168b64a0bc1976de0e5 revision: cde56ead650f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 11:18:05.889503) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 11:18:05.889972) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-07-18 11:18:05.890274) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-07-18 11:18:10.685290) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 11:18:10.685777) ========= 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.kOK3OP0IDi/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.37OOkvei1s/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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-18 11:18:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.4M=0.001s 2016-07-18 11:18:10 (17.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.149336 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 11:18:10.861557) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 11:18:10.861899) ========= 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.kOK3OP0IDi/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.37OOkvei1s/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.025887 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 11:18:10.914951) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-18 11:18:10.915358) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cde56ead650f --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 cde56ead650f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kOK3OP0IDi/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.37OOkvei1s/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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-18 11:18:11,000 Setting DEBUG logging. 2016-07-18 11:18:11,000 attempt 1/10 2016-07-18 11:18:11,000 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cde56ead650f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 11:18:13,030 unpacking tar archive at: mozilla-central-cde56ead650f/testing/mozharness/ program finished with exit code 0 elapsedTime=2.422443 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-18 11:18:13.360385) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 11:18:13.360710) ========= script_repo_revision: cde56ead650f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 11:18:13.361083) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 11:18:13.361383) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 11:18:13.380114) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-18 11:18:13.380538) ========= /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.kOK3OP0IDi/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.37OOkvei1s/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:18:13 INFO - MultiFileLogger online at 20160718 11:18:13 in /builds/slave/test 11:18:13 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 11:18:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:18:13 INFO - {'append_to_log': False, 11:18:13 INFO - 'base_work_dir': '/builds/slave/test', 11:18:13 INFO - 'blob_upload_branch': 'Firefox', 11:18:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:18:13 INFO - 'branch': 'Firefox', 11:18:13 INFO - 'buildbot_json_path': 'buildprops.json', 11:18:13 INFO - 'config_files': ('talos/mac_config.py',), 11:18:13 INFO - 'default_actions': ('clobber', 11:18:13 INFO - 'read-buildbot-config', 11:18:13 INFO - 'download-and-extract', 11:18:13 INFO - 'populate-webroot', 11:18:13 INFO - 'create-virtualenv', 11:18:13 INFO - 'install', 11:18:13 INFO - 'run-tests'), 11:18:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:18:13 INFO - 'download_minidump_stackwalk': True, 11:18:13 INFO - 'download_symbols': 'ondemand', 11:18:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:18:13 INFO - 'tooltool.py': '/tools/tooltool.py', 11:18:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:18:13 INFO - '/tools/misc-python/virtualenv.py')}, 11:18:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:18:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:18:13 INFO - 'installer_path': 'installer.exe', 11:18:13 INFO - 'log_level': 'info', 11:18:13 INFO - 'log_name': 'talos', 11:18:13 INFO - 'log_to_console': True, 11:18:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:18:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:18:13 INFO - 'opt_config_files': (), 11:18:13 INFO - 'pip_index': False, 11:18:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:18:13 INFO - 'cmd': ('bash', 11:18:13 INFO - '-c', 11:18:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:18:13 INFO - 'enabled': True, 11:18:13 INFO - 'halt_on_failure': False, 11:18:13 INFO - 'name': 'check_screen_resolution'},), 11:18:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:18:13 INFO - 'cmd': ('bash', 11:18:13 INFO - '-c', 11:18:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:18:13 INFO - 'enabled': True, 11:18:13 INFO - 'halt_on_failure': False, 11:18:13 INFO - 'name': 'check_screen_resolution'},), 11:18:13 INFO - 'run_cmd_checks_enabled': True, 11:18:13 INFO - 'sps_profile': False, 11:18:13 INFO - 'sps_profile_interval': 0, 11:18:13 INFO - 'suite': 'dromaeojs', 11:18:13 INFO - 'system_bits': '32', 11:18:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:18:13 INFO - 'title': 't-yosemite-r7-0318', 11:18:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:18:13 INFO - 'use_talos_json': True, 11:18:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:18:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:18:13 INFO - 'work_dir': 'build'} 11:18:13 INFO - ##### 11:18:13 INFO - ##### Running clobber step. 11:18:13 INFO - ##### 11:18:13 INFO - Running pre-action listener: _resource_record_pre_action 11:18:13 INFO - Running main action method: clobber 11:18:13 INFO - rmtree: /builds/slave/test/build 11:18:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:18:15 INFO - Running post-action listener: _resource_record_post_action 11:18:15 INFO - ##### 11:18:15 INFO - ##### Finished clobber step (success) 11:18:15 INFO - ##### 11:18:15 INFO - ##### 11:18:15 INFO - ##### Running read-buildbot-config step. 11:18:15 INFO - ##### 11:18:15 INFO - Running pre-action listener: _resource_record_pre_action 11:18:15 INFO - Running main action method: read_buildbot_config 11:18:15 INFO - Using buildbot properties: 11:18:15 INFO - { 11:18:15 INFO - "project": "", 11:18:15 INFO - "product": "firefox", 11:18:15 INFO - "script_repo_revision": "production", 11:18:15 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 11:18:15 INFO - "repository": "", 11:18:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 11:18:15 INFO - "buildid": "20160718081125", 11:18:15 INFO - "stage_platform": "macosx64", 11:18:15 INFO - "basedir": "/builds/slave/test", 11:18:15 INFO - "nightly_build": "True", 11:18:15 INFO - "buildnumber": 15, 11:18:15 INFO - "slavename": "t-yosemite-r7-0318", 11:18:15 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:18:15 INFO - "platform": "yosemite_r7", 11:18:15 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:18:15 INFO - "branch": "mozilla-central", 11:18:15 INFO - "repo_path": "mozilla-central", 11:18:15 INFO - "revision": "cde56ead650f", 11:18:15 INFO - "pgo_build": "False", 11:18:15 INFO - "builduid": "287f226fbb2b4168b64a0bc1976de0e5", 11:18:15 INFO - "slavebuilddir": "test" 11:18:15 INFO - } 11:18:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 11:18:15 INFO - Running post-action listener: _resource_record_post_action 11:18:15 INFO - ##### 11:18:15 INFO - ##### Finished read-buildbot-config step (success) 11:18:15 INFO - ##### 11:18:15 INFO - ##### 11:18:15 INFO - ##### Running download-and-extract step. 11:18:15 INFO - ##### 11:18:15 INFO - Running pre-action listener: _resource_record_pre_action 11:18:15 INFO - Running main action method: download_and_extract 11:18:15 INFO - mkdir: /builds/slave/test/build/tests 11:18:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 11:18:15 INFO - https://queue.taskcluster.net/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:18:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 11:18:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 11:18:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/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 11:18:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/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 11:18:17 INFO - Downloaded 1199 bytes. 11:18:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 11:18:17 INFO - Using the following test package requirements: 11:18:17 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 11:18:17 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:18:17 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 11:18:17 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:18:17 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 11:18:17 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:18:17 INFO - u'jsshell-mac.zip'], 11:18:17 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:18:17 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 11:18:17 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 11:18:17 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:18:17 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 11:18:17 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:18:17 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 11:18:17 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:18:17 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 11:18:17 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:18:17 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 11:18:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:18: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')]} 11:18:17 INFO - https://queue.taskcluster.net/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:18:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:18:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:18:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/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 11:18:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/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 11:18:19 INFO - Downloaded 18835409 bytes. 11:18: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'] 11:18: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 11:18:22 INFO - Return code: 0 11:18: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 11:18: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')]} 11:18:22 INFO - https://queue.taskcluster.net/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:18:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:18:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:18:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/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 11:18:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/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 11:18:23 INFO - Downloaded 18835409 bytes. 11:18:23 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'] 11:18:23 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 11:18:27 INFO - Return code: 0 11:18:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:27 INFO - https://queue.taskcluster.net/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:18:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:18:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:18:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/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 11:18:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/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 11:18:28 INFO - Downloaded 14403317 bytes. 11:18:28 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'] 11:18:28 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 11:18:29 INFO - Return code: 0 11:18:29 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')]} 11:18:29 INFO - https://queue.taskcluster.net/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:18:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:18:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:18:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 11:18:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/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 11:18:32 INFO - Downloaded 113021783 bytes. 11:18:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:18:32 INFO - mkdir: /builds/slave/test/properties 11:18:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:18:32 INFO - Writing to file /builds/slave/test/properties/build_url 11:18:32 INFO - Contents: 11:18:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:18:33 INFO - Running post-action listener: _resource_record_post_action 11:18:33 INFO - Running post-action listener: set_extra_try_arguments 11:18:33 INFO - ##### 11:18:33 INFO - ##### Finished download-and-extract step (success) 11:18:33 INFO - ##### 11:18:33 INFO - ##### 11:18:33 INFO - ##### Running populate-webroot step. 11:18:33 INFO - ##### 11:18:33 INFO - Running pre-action listener: _resource_record_pre_action 11:18:33 INFO - Running main action method: populate_webroot 11:18:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:18:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:18:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:18:33 INFO - '--tppagecycles', 11:18:33 INFO - '7'], 11:18:33 INFO - 'tests': ['tsvgm']}}, 11:18:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:18:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:18:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:18:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:18:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:18:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:18:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:18:33 INFO - 'talos_options': ['--disable-e10s'], 11:18:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:18:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:18:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:18:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:18:33 INFO - 'talos_options': ['--disable-e10s'], 11:18:33 INFO - 'tests': ['damp', 'tps']}, 11:18:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:18:33 INFO - 'tests': ['damp', 'tps']}, 11:18:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:18:33 INFO - 'tests': ['dromaeo_dom']}, 11:18:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:18:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:18:33 INFO - 'tests': ['basic_compositor_video']}, 11:18:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:18:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:18:33 INFO - 'tests': ['a11yr', 11:18:33 INFO - 'ts_paint', 11:18:33 INFO - 'tpaint', 11:18:33 INFO - 'sessionrestore', 11:18:33 INFO - 'sessionrestore_no_auto_restore', 11:18:33 INFO - 'tabpaint']}, 11:18:33 INFO - 'other-e10s': {'tests': ['a11yr', 11:18:33 INFO - 'ts_paint', 11:18:33 INFO - 'tpaint', 11:18:33 INFO - 'sessionrestore', 11:18:33 INFO - 'sessionrestore_no_auto_restore', 11:18:33 INFO - 'tabpaint']}, 11:18:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:18:33 INFO - 'ts_paint', 11:18:33 INFO - 'tpaint', 11:18:33 INFO - 'sessionrestore', 11:18:33 INFO - 'sessionrestore_no_auto_restore', 11:18:33 INFO - 'tabpaint']}, 11:18:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:18:33 INFO - 'ts_paint', 11:18:33 INFO - 'tpaint', 11:18:33 INFO - 'sessionrestore', 11:18:33 INFO - 'sessionrestore_no_auto_restore', 11:18:33 INFO - 'tabpaint']}, 11:18:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:18:33 INFO - 'tests': ['a11yr', 11:18:33 INFO - 'ts_paint', 11:18:33 INFO - 'tpaint', 11:18:33 INFO - 'sessionrestore', 11:18:33 INFO - 'sessionrestore_no_auto_restore', 11:18:33 INFO - 'tabpaint']}, 11:18:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:18:33 INFO - 'tests': ['a11yr', 11:18:33 INFO - 'ts_paint', 11:18:33 INFO - 'tpaint', 11:18:33 INFO - 'sessionrestore', 11:18:33 INFO - 'sessionrestore_no_auto_restore', 11:18:33 INFO - 'tabpaint']}, 11:18:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:18:33 INFO - 'tests': ['tsvgx', 11:18:33 INFO - 'tsvgr_opacity', 11:18:33 INFO - 'tart', 11:18:33 INFO - 'tscrollx', 11:18:33 INFO - 'cart']}, 11:18:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:18:33 INFO - 'tsvgr_opacity', 11:18:33 INFO - 'tart', 11:18:33 INFO - 'tscrollx', 11:18:33 INFO - 'cart']}, 11:18:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:18:33 INFO - 'talos_options': ['--disable-e10s'], 11:18:33 INFO - 'tests': ['tp5o']}, 11:18:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:18:33 INFO - 'tests': ['tp5o']}, 11:18:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:18:33 INFO - 'talos_options': ['--disable-e10s', 11:18:33 INFO - '--xperf_path', 11:18:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:18:33 INFO - 'tests': ['tp5n']}, 11:18:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:18:33 INFO - 'talos_options': ['--xperf_path', 11:18:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:18:33 INFO - 'tests': ['tp5n']}}, 11:18:33 INFO - 'talos.zip': {'path': '', 11:18:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:18:33 INFO - Running post-action listener: _resource_record_post_action 11:18:33 INFO - ##### 11:18:33 INFO - ##### Finished populate-webroot step (success) 11:18:33 INFO - ##### 11:18:33 INFO - ##### 11:18:33 INFO - ##### Running create-virtualenv step. 11:18:33 INFO - ##### 11:18:33 INFO - Running pre-action listener: _resource_record_pre_action 11:18:33 INFO - Running main action method: create_virtualenv 11:18:33 INFO - Creating virtualenv /builds/slave/test/build/venv 11:18:33 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 11:18:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:18:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:18:33 INFO - Using real prefix '/tools/python27' 11:18:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:18:34 INFO - Installing distribute.............................................................................................................................................................................................done. 11:18:37 INFO - Installing pip.................done. 11:18:37 INFO - Return code: 0 11:18:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108189a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10821e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92d146a1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080edd30>, '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 0x107e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080e5b20>, '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.kOK3OP0IDi/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.37OOkvei1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:18:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:18:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOK3OP0IDi/Render', 11:18:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:37 INFO - 'HOME': '/Users/cltbld', 11:18:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:37 INFO - 'LOGNAME': 'cltbld', 11:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:37 INFO - 'MOZ_NO_REMOTE': '1', 11:18:37 INFO - 'NO_EM_RESTART': '1', 11:18:37 INFO - 'PAGER': '/bin/cat', 11:18:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:37 INFO - 'PWD': '/builds/slave/test', 11:18:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:37 INFO - 'SHELL': '/bin/bash', 11:18:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37OOkvei1s/Listeners', 11:18:37 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:18:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:37 INFO - 'USER': 'cltbld', 11:18:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:37 INFO - 'XPC_FLAGS': '0x0', 11:18:37 INFO - 'XPC_SERVICE_NAME': '0', 11:18:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:37 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 11:18:37 INFO - Cleaning up... 11:18:37 INFO - Return code: 0 11:18:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108189a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10821e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92d146a1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080edd30>, '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 0x107e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080e5b20>, '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.kOK3OP0IDi/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.37OOkvei1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:18:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:18:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOK3OP0IDi/Render', 11:18:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:37 INFO - 'HOME': '/Users/cltbld', 11:18:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:37 INFO - 'LOGNAME': 'cltbld', 11:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:37 INFO - 'MOZ_NO_REMOTE': '1', 11:18:37 INFO - 'NO_EM_RESTART': '1', 11:18:37 INFO - 'PAGER': '/bin/cat', 11:18:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:37 INFO - 'PWD': '/builds/slave/test', 11:18:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:37 INFO - 'SHELL': '/bin/bash', 11:18:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37OOkvei1s/Listeners', 11:18:37 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:18:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:37 INFO - 'USER': 'cltbld', 11:18:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:37 INFO - 'XPC_FLAGS': '0x0', 11:18:37 INFO - 'XPC_SERVICE_NAME': '0', 11:18:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:37 INFO - Downloading/unpacking psutil>=3.1.1 11:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:18:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:18:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:18:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:18:40 INFO - Installing collected packages: psutil 11:18:40 INFO - Running setup.py install for psutil 11:18:40 INFO - building 'psutil._psutil_osx' extension 11:18:40 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 11:18:41 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 11:18:41 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 11:18:41 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 11:18:41 INFO - building 'psutil._psutil_posix' extension 11:18:41 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 11:18:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:18:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:18:41 INFO - ^ 11:18:41 INFO - 1 warning generated. 11:18:41 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 11:18:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:18:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:18:41 INFO - Successfully installed psutil 11:18:41 INFO - Cleaning up... 11:18:41 INFO - Return code: 0 11:18:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:41 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 0x108189a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10821e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92d146a1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080edd30>, '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 0x107e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080e5b20>, '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.kOK3OP0IDi/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.37OOkvei1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:18:41 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 11:18:41 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 11:18:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOK3OP0IDi/Render', 11:18:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:41 INFO - 'HOME': '/Users/cltbld', 11:18:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:41 INFO - 'LOGNAME': 'cltbld', 11:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:41 INFO - 'MOZ_NO_REMOTE': '1', 11:18:41 INFO - 'NO_EM_RESTART': '1', 11:18:41 INFO - 'PAGER': '/bin/cat', 11:18:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:41 INFO - 'PWD': '/builds/slave/test', 11:18:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:41 INFO - 'SHELL': '/bin/bash', 11:18:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37OOkvei1s/Listeners', 11:18:41 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:18:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:41 INFO - 'USER': 'cltbld', 11:18:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:41 INFO - 'XPC_FLAGS': '0x0', 11:18:41 INFO - 'XPC_SERVICE_NAME': '0', 11:18:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:18:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:18:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:18:44 INFO - Installing collected packages: mozsystemmonitor 11:18:44 INFO - Running setup.py install for mozsystemmonitor 11:18:44 INFO - Successfully installed mozsystemmonitor 11:18:44 INFO - Cleaning up... 11:18:44 INFO - Return code: 0 11:18:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:18:44 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')]} 11:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:44 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')]} 11:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:44 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 0x108189a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10821e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92d146a1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080edd30>, '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 0x107e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080e5b20>, '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.kOK3OP0IDi/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.37OOkvei1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:18:44 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 11:18:44 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 11:18:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOK3OP0IDi/Render', 11:18:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:44 INFO - 'HOME': '/Users/cltbld', 11:18:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:44 INFO - 'LOGNAME': 'cltbld', 11:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:44 INFO - 'MOZ_NO_REMOTE': '1', 11:18:44 INFO - 'NO_EM_RESTART': '1', 11:18:44 INFO - 'PAGER': '/bin/cat', 11:18:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:44 INFO - 'PWD': '/builds/slave/test', 11:18:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:44 INFO - 'SHELL': '/bin/bash', 11:18:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37OOkvei1s/Listeners', 11:18:44 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:18:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:44 INFO - 'USER': 'cltbld', 11:18:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:44 INFO - 'XPC_FLAGS': '0x0', 11:18:44 INFO - 'XPC_SERVICE_NAME': '0', 11:18:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:45 INFO - Downloading/unpacking jsonschema==2.5.1 11:18:45 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 11:18:45 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 11:18:45 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 11:18:45 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 11:18:45 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 11:18:45 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 11:18:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:18:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:18:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:48 INFO - Downloading functools32-3.2.3-2.tar.gz 11:18:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:18:48 INFO - warning: no files found matching '*.txt' 11:18:48 INFO - no previously-included directories found matching 'build' 11:18:48 INFO - no previously-included directories found matching 'dist' 11:18:48 INFO - no previously-included directories found matching '.git*' 11:18:48 INFO - Installing collected packages: jsonschema, functools32 11:18:48 INFO - Running setup.py install for functools32 11:18:48 INFO - warning: no files found matching '*.txt' 11:18:48 INFO - no previously-included directories found matching 'build' 11:18:48 INFO - no previously-included directories found matching 'dist' 11:18:48 INFO - no previously-included directories found matching '.git*' 11:18:48 INFO - Successfully installed jsonschema functools32 11:18:48 INFO - Cleaning up... 11:18:48 INFO - Return code: 0 11:18:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:48 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 0x108189a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10821e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92d146a1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080edd30>, '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 0x107e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080e5b20>, '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.kOK3OP0IDi/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.37OOkvei1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:18:48 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 11:18:48 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 11:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOK3OP0IDi/Render', 11:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:48 INFO - 'HOME': '/Users/cltbld', 11:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:48 INFO - 'LOGNAME': 'cltbld', 11:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:48 INFO - 'MOZ_NO_REMOTE': '1', 11:18:48 INFO - 'NO_EM_RESTART': '1', 11:18:48 INFO - 'PAGER': '/bin/cat', 11:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:48 INFO - 'PWD': '/builds/slave/test', 11:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:48 INFO - 'SHELL': '/bin/bash', 11:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37OOkvei1s/Listeners', 11:18:48 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:48 INFO - 'USER': 'cltbld', 11:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:48 INFO - 'XPC_FLAGS': '0x0', 11:18:48 INFO - 'XPC_SERVICE_NAME': '0', 11:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:48 INFO - Downloading/unpacking blobuploader==1.2.4 11:18:48 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 11:18:48 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 11:18:48 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 11:18:48 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 11:18:48 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 11:18:48 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 11:18:51 INFO - Downloading blobuploader-1.2.4.tar.gz 11:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:18:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:18: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 11:18: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 11:18: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 11:18: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 11:18: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 11:18: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 11:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:18:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:18:52 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 11:18:52 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 11:18:52 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 11:18:52 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 11:18:52 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 11:18:52 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 11:18:52 INFO - Downloading docopt-0.6.1.tar.gz 11:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:18:52 INFO - Installing collected packages: blobuploader, requests, docopt 11:18:52 INFO - Running setup.py install for blobuploader 11:18:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:18:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:18:52 INFO - Running setup.py install for requests 11:18:53 INFO - Running setup.py install for docopt 11:18:53 INFO - Successfully installed blobuploader requests docopt 11:18:53 INFO - Cleaning up... 11:18:53 INFO - Return code: 0 11:18:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:53 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 0x108189a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10821e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92d146a1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080edd30>, '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 0x107e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080e5b20>, '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.kOK3OP0IDi/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.37OOkvei1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:18:53 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 11:18:53 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 11:18:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOK3OP0IDi/Render', 11:18:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:53 INFO - 'HOME': '/Users/cltbld', 11:18:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:53 INFO - 'LOGNAME': 'cltbld', 11:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:53 INFO - 'MOZ_NO_REMOTE': '1', 11:18:53 INFO - 'NO_EM_RESTART': '1', 11:18:53 INFO - 'PAGER': '/bin/cat', 11:18:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:53 INFO - 'PWD': '/builds/slave/test', 11:18:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:53 INFO - 'SHELL': '/bin/bash', 11:18:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37OOkvei1s/Listeners', 11:18:53 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:18:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:53 INFO - 'USER': 'cltbld', 11:18:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:53 INFO - 'XPC_FLAGS': '0x0', 11:18:53 INFO - 'XPC_SERVICE_NAME': '0', 11:18:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:18:53 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-1jCWv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:18:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-08XheH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:18:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-qMzXSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:18:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-C9Q8MG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:18:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-dBCuAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:18:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-5b67xM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:18:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-SWT_69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:18:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-SIkd6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:18:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-cc8H40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:18:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-PhGdSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:18:54 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ymYvGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:18:55 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-y0xJUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:18:55 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-EpE48w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:18:55 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-a7yPO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:18:55 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-QaOyTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:18:55 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-BMuD1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:18:55 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Xz7WBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:18:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:18:55 INFO - Running setup.py install for manifestparser 11:18:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:18:55 INFO - Running setup.py install for mozcrash 11:18:55 INFO - Running setup.py install for mozdebug 11:18:56 INFO - Running setup.py install for mozdevice 11:18:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:18:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:18:56 INFO - Running setup.py install for mozfile 11:18:56 INFO - Running setup.py install for mozhttpd 11:18:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:18:56 INFO - Running setup.py install for mozinfo 11:18:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:18:56 INFO - Running setup.py install for mozInstall 11:18:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:18:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:18:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:18:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:18:56 INFO - Running setup.py install for mozleak 11:18:56 INFO - Running setup.py install for mozlog 11:18:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:18:57 INFO - Running setup.py install for moznetwork 11:18:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:18:57 INFO - Running setup.py install for mozprocess 11:18:57 INFO - Running setup.py install for mozprofile 11:18:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:18:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:18:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:18:57 INFO - Running setup.py install for mozrunner 11:18:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:18:57 INFO - Running setup.py install for mozscreenshot 11:18:57 INFO - Running setup.py install for moztest 11:18:58 INFO - Running setup.py install for mozversion 11:18:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:18:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:18:58 INFO - Cleaning up... 11:18:58 INFO - Return code: 0 11:18:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:18: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')]} 11:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18: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')]} 11:18:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:58 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 0x108189a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10821e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92d146a1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080edd30>, '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 0x107e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080e5b20>, '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.kOK3OP0IDi/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.37OOkvei1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:18:58 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 11:18:58 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 11:18:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOK3OP0IDi/Render', 11:18:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:58 INFO - 'HOME': '/Users/cltbld', 11:18:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:58 INFO - 'LOGNAME': 'cltbld', 11:18:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:58 INFO - 'MOZ_NO_REMOTE': '1', 11:18:58 INFO - 'NO_EM_RESTART': '1', 11:18:58 INFO - 'PAGER': '/bin/cat', 11:18:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:58 INFO - 'PWD': '/builds/slave/test', 11:18:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:58 INFO - 'SHELL': '/bin/bash', 11:18:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37OOkvei1s/Listeners', 11:18:58 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:18:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:58 INFO - 'USER': 'cltbld', 11:18:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:58 INFO - 'XPC_FLAGS': '0x0', 11:18:58 INFO - 'XPC_SERVICE_NAME': '0', 11:18:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:18:58 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-71wJIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:18:58 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)) 11:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:18:58 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-JYCeHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:18:58 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)) 11:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:18:58 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-DdDxOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:18:58 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)) 11:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:18:58 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-wX5W_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:18:58 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)) 11:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:18:58 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-cBTLjs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:18:59 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)) 11:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:18:59 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-1EFrFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:18:59 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)) 11:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:18:59 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-im0eXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:18:59 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)) 11:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:18:59 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-sE7n6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:18:59 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)) 11:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:18:59 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-6pYcyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:18:59 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)) 11:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:18:59 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-CZ6sU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:18:59 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)) 11:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:18:59 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-0VmNXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:18:59 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)) 11:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:18:59 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-AMZrbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:18:59 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)) 11:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:18:59 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-FjvLzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:18:59 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)) 11:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:18:59 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-PFoBkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:19:00 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)) 11:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-GPfUpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:00 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)) 11:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:19:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-KFJRPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:19:00 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)) 11:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:19:00 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-L7VBLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:19:00 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)) 11:19:00 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)) 11:19:00 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)) 11:19:00 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)) 11:19:00 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)) 11:19:00 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)) 11:19:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:19:00 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 11:19:00 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 11:19:00 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 11:19:00 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 11:19:00 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 11:19:00 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 11:19:02 INFO - Downloading blessings-1.6.tar.gz 11:19:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:19:02 INFO - Installing collected packages: blessings 11:19:02 INFO - Running setup.py install for blessings 11:19:03 INFO - Successfully installed blessings 11:19:03 INFO - Cleaning up... 11:19:03 INFO - Return code: 0 11:19:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:19:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:19:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:19:03 INFO - Reading from file tmpfile_stdout 11:19:03 INFO - Current package versions: 11:19:03 INFO - blessings == 1.6 11:19:03 INFO - blobuploader == 1.2.4 11:19:03 INFO - docopt == 0.6.1 11:19:03 INFO - functools32 == 3.2.3-2 11:19:03 INFO - jsonschema == 2.5.1 11:19:03 INFO - manifestparser == 1.1 11:19:03 INFO - mozInstall == 1.12 11:19:03 INFO - mozcrash == 0.17 11:19:03 INFO - mozdebug == 0.1 11:19:03 INFO - mozdevice == 0.48 11:19:03 INFO - mozfile == 1.2 11:19:03 INFO - mozhttpd == 0.7 11:19:03 INFO - mozinfo == 0.9 11:19:03 INFO - mozleak == 0.1 11:19:03 INFO - mozlog == 3.2 11:19:03 INFO - moznetwork == 0.27 11:19:03 INFO - mozprocess == 0.23 11:19:03 INFO - mozprofile == 0.28 11:19:03 INFO - mozrunner == 6.12 11:19:03 INFO - mozscreenshot == 0.1 11:19:03 INFO - mozsystemmonitor == 0.3 11:19:03 INFO - moztest == 0.7 11:19:03 INFO - mozversion == 1.4 11:19:03 INFO - psutil == 3.1.1 11:19:03 INFO - requests == 1.2.3 11:19:03 INFO - wsgiref == 0.1.2 11:19:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:19:03 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')]} 11:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:03 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')]} 11:19:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:03 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 0x108189a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10821e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92d146a1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080edd30>, '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 0x107e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080e5b20>, '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.kOK3OP0IDi/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.37OOkvei1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:19:03 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 11:19:03 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 11:19:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOK3OP0IDi/Render', 11:19:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:03 INFO - 'HOME': '/Users/cltbld', 11:19:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:03 INFO - 'LOGNAME': 'cltbld', 11:19:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:03 INFO - 'MOZ_NO_REMOTE': '1', 11:19:03 INFO - 'NO_EM_RESTART': '1', 11:19:03 INFO - 'PAGER': '/bin/cat', 11:19:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:03 INFO - 'PWD': '/builds/slave/test', 11:19:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:03 INFO - 'SHELL': '/bin/bash', 11:19:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37OOkvei1s/Listeners', 11:19:03 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:19:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:03 INFO - 'USER': 'cltbld', 11:19:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:03 INFO - 'XPC_FLAGS': '0x0', 11:19:03 INFO - 'XPC_SERVICE_NAME': '0', 11:19:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:03 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)) 11:19:03 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)) 11:19:03 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)) 11:19:03 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)) 11:19:03 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)) 11:19:03 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)) 11:19:03 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)) 11:19:03 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)) 11:19:03 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)) 11:19:03 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)) 11:19:03 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)) 11:19:03 INFO - Cleaning up... 11:19:03 INFO - Return code: 0 11:19:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:19:03 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')]} 11:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:03 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')]} 11:19:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:03 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 0x108189a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10821e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92d146a1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080edd30>, '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 0x107e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080e5b20>, '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.kOK3OP0IDi/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.37OOkvei1s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:19:03 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 11:19:03 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 11:19:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOK3OP0IDi/Render', 11:19:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:03 INFO - 'HOME': '/Users/cltbld', 11:19:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:03 INFO - 'LOGNAME': 'cltbld', 11:19:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:03 INFO - 'MOZ_NO_REMOTE': '1', 11:19:03 INFO - 'NO_EM_RESTART': '1', 11:19:03 INFO - 'PAGER': '/bin/cat', 11:19:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:03 INFO - 'PWD': '/builds/slave/test', 11:19:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:03 INFO - 'SHELL': '/bin/bash', 11:19:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37OOkvei1s/Listeners', 11:19:03 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:19:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:03 INFO - 'USER': 'cltbld', 11:19:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:03 INFO - 'XPC_FLAGS': '0x0', 11:19:03 INFO - 'XPC_SERVICE_NAME': '0', 11:19:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:19:04 INFO - Cleaning up... 11:19:04 INFO - Return code: 0 11:19:04 INFO - Running post-action listener: _resource_record_post_action 11:19:04 INFO - Running post-action listener: _start_resource_monitoring 11:19:04 INFO - Starting resource monitoring. 11:19:04 INFO - ##### 11:19:04 INFO - ##### Finished create-virtualenv step (success) 11:19:04 INFO - ##### 11:19:04 INFO - ##### 11:19:04 INFO - ##### Running install step. 11:19:04 INFO - ##### 11:19:04 INFO - Running pre-action listener: _resource_record_pre_action 11:19:04 INFO - Running main action method: install 11:19:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:19:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:19:04 INFO - Reading from file tmpfile_stdout 11:19:04 INFO - Detecting whether we're running mozinstall >=1.0... 11:19:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:19:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:19:04 INFO - Reading from file tmpfile_stdout 11:19:04 INFO - Output received: 11:19:04 INFO - Usage: mozinstall [options] installer 11:19:04 INFO - Options: 11:19:04 INFO - -h, --help show this help message and exit 11:19:04 INFO - -d DEST, --destination=DEST 11:19:04 INFO - Directory to install application into. [default: 11:19:04 INFO - "/builds/slave/test"] 11:19:04 INFO - --app=APP Application being installed. [default: firefox] 11:19:04 INFO - mkdir: /builds/slave/test/build/application 11:19:04 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'] 11:19:04 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 11:19:38 INFO - Reading from file tmpfile_stdout 11:19:38 INFO - Output received: 11:19:38 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 11:19:38 INFO - Running post-action listener: _resource_record_post_action 11:19:38 INFO - ##### 11:19:38 INFO - ##### Finished install step (success) 11:19:38 INFO - ##### 11:19:38 INFO - ##### 11:19:38 INFO - ##### Running run-tests step. 11:19:38 INFO - ##### 11:19:38 INFO - Running pre-action listener: _resource_record_pre_action 11:19:38 INFO - Running main action method: run_tests 11:19:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:19:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:19:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:19:39 INFO - 2016-07-18 11:19:38.999 screenresolution[1645:7635] starting screenresolution argv=screenresolution get 11:19:39 INFO - 2016-07-18 11:19:39.001 screenresolution[1645:7635] Display 0: 1600x1200x32@60 11:19:39 INFO - 2016-07-18 11:19:39.007 screenresolution[1646:7637] starting screenresolution argv=screenresolution list 11:19:39 INFO - Available Modes on Display 0 11:19:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:19:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:19:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:19:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:19:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:19:39 INFO - Intel Iris: 11:19:39 INFO - Chipset Model: Intel Iris 11:19:39 INFO - Type: GPU 11:19:39 INFO - Bus: Built-In 11:19:39 INFO - VRAM (Dynamic, Max): 1536 MB 11:19:39 INFO - Vendor: Intel (0x8086) 11:19:39 INFO - Device ID: 0x0a2e 11:19:39 INFO - Revision ID: 0x0009 11:19:39 INFO - Displays: 11:19:39 INFO - Display: 11:19:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:19:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:19:39 INFO - Display Serial Number: 1600x1200 60 11:19:39 INFO - Main Display: Yes 11:19:39 INFO - Mirror: Off 11:19:39 INFO - Online: Yes 11:19:39 INFO - Rotation: Supported 11:19:39 INFO - Return code: 0 11:19:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:19:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:19:39 INFO - Python 2.7.3 11:19:39 INFO - Return code: 0 11:19:39 INFO - grabbing minidump binary from tooltool 11:19:39 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')]} 11:19:39 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 0x1080edd30>, '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 0x107e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1080e5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:19:39 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 11:19:39 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 11:19:39 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 11:19:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:19:39 INFO - Return code: 0 11:19:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:19:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:19:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:19:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:19:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:19:39 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 11:19:39 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0318 --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 11:19:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kOK3OP0IDi/Render', 11:19:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:39 INFO - 'HOME': '/Users/cltbld', 11:19:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:39 INFO - 'LOGNAME': 'cltbld', 11:19:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:19:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:39 INFO - 'MOZ_NO_REMOTE': '1', 11:19:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:19:39 INFO - 'NO_EM_RESTART': '1', 11:19:39 INFO - 'PAGER': '/bin/cat', 11:19:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:39 INFO - 'PWD': '/builds/slave/test', 11:19:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:19:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:39 INFO - 'SHELL': '/bin/bash', 11:19:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37OOkvei1s/Listeners', 11:19:39 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:19:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:39 INFO - 'USER': 'cltbld', 11:19:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:39 INFO - 'XPC_FLAGS': '0x0', 11:19:39 INFO - 'XPC_SERVICE_NAME': '0', 11:19:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:39 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 11:19:39 INFO - mozversion application_buildid: 20160718081125 11:19:39 INFO - mozversion application_changeset: cde56ead650fd302be1d440507485b9abf7c163a 11:19:39 INFO - mozversion application_display_name: Nightly 11:19:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:19:39 INFO - mozversion application_name: Firefox 11:19:39 INFO - mozversion application_remotingname: firefox 11:19:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:19:39 INFO - mozversion application_vendor: Mozilla 11:19:39 INFO - mozversion application_version: 50.0a1 11:19:39 INFO - mozversion platform_buildid: 20160718081125 11:19:39 INFO - mozversion platform_changeset: cde56ead650fd302be1d440507485b9abf7c163a 11:19:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:19:39 INFO - mozversion platform_version: 50.0a1 11:19:39 INFO - using testdate: 1468865979 11:19:39 INFO - actual date: 1468865979 11:19:39 INFO - starting webserver on 'localhost:49273' 11:19:39 INFO - SUITE-START | Running 2 tests 11:19:39 INFO - TEST-START | dromaeo_css 11:19:39 INFO - operating with platform_type : mac_ 11:19:39 INFO - Initialising browser for dromaeo_css test... 11:19:39 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmp4N2y6T/profile http://localhost:49273/getInfo.html) 11:19:42 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 11:19:42 INFO - PROCESS | 1656 | colorDepth:24 11:19:42 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 11:19:42 INFO - PROCESS | 1656 | __metrics 11:19:42 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:19:42 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:19:43 INFO - TEST-INFO | 1656: exit 0 11:19:43 INFO - Browser initialized. 11:19:43 INFO - Running cycle 1/1 for dromaeo_css test... 11:19:43 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmp4N2y6T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:19:55 INFO - PROCESS | 1659 | RSS: Main: 245178368 11:19:55 INFO - PROCESS | 1659 | 11:20:46 INFO - PROCESS | 1659 | 1468866046346 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] 11:22:57 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 11:22:58 INFO - PROCESS | 1659 | RSS: Main: 364158976 11:22:58 INFO - PROCESS | 1659 | 11:26:01 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 11:26:02 INFO - PROCESS | 1659 | RSS: Main: 377667584 11:26:02 INFO - PROCESS | 1659 | 11:29:04 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 11:29:05 INFO - PROCESS | 1659 | RSS: Main: 373190656 11:29:05 INFO - PROCESS | 1659 | 11:32:07 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 11:32:08 INFO - PROCESS | 1659 | RSS: Main: 366641152 11:32:08 INFO - PROCESS | 1659 | 11:35:11 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 11:35:12 INFO - PROCESS | 1659 | RSS: Main: 337399808 11:35:12 INFO - PROCESS | 1659 | 11:38:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 11:38:15 INFO - PROCESS | 1659 | RSS: Main: 384065536 11:38:15 INFO - PROCESS | 1659 | 11:38:15 INFO - PROCESS | 1659 | __start_tp_report 11:38:15 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 11:38:15 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 11:38:15 INFO - PROCESS | 1659 | |i|pagename|runs| 11:38:15 INFO - PROCESS | 1659 | |0;dojo.html;2787.38677490952;3371.5954085509734;3372.9156771080734;3408.4546472564393;3427.971608517444;5654.208410822488;5693.971530142354;5763.049096899017;5787.276590426197;5808.680522571262;5728.79949201778;5738.42615738426;5858.7949421770245;5933.584649074566;5955.940440595593;5192.1952097424955;5209.661372010826;5260.763265653055;5297.417284098746;5346.732663366835;5315.335583052119;5339.813106541272;5351.143816989283;5369.221462887879;5373.274607927939;5152.046871328816;5204.817831375922;5215.530602245816;5274.657147285453;5284.973575132119;5160.277561141444;5168.483151684802;5251.868703282448;5276.498732620395;5322.0154271459905;5130.153524668425;5154.355705536808;5223.059849227137;5230.189320655304;5273.156294992783;5736.713164341808;5795.3045634523705;5807.390224026501;5835.416458354173;5839.708014599253;6130.386961303879;6177.351378105324;6240.063990401431;6249.9062514062325;6281.811545653638;6267.05994100884;6292.244930608311;6324.968375158141;6335.176427064452;6336.303006669263;5912.881742365129;5916.4675179233645;5919.260092488439;5921.67430791302;5932.169496270526;6212.0371342441995;6230.096635987757;6292.119103325538;6299.96850015747;6337.397947195009;5107.591392688576;5194.3247377840635;6202.162708034448;6204.224471941007;6238.189035425366;6103.549811064039;6240.375962403723;6289.7169627366875;6323.715432805535;6348.492120630339;6137.987232106652;6150.677604315094;6219.720112594944;6223.782167624111;6280.7487700491465;6236.376362363727;6265.71804268809;6299.716512756937;6321.620702757849;6324.3043265240785;4908.288298196741;5023.924641130386;5028.522290382408;5043.3191519145175;5064.873378165502;4399.934000989988;4430.955690443118;4451.933221001687;4474.574915383098;4494.865154045384;5658.97170514153;5683.943160568424;5700.429957004267;5754.395788442153;5772.3938986406665;6144.556029333103;6185.783497577653;6281.528885333618;6302.747890084437;6315.778947736807;5638.6052976292085;5658.943410565842;5679.574031947621;5690.374058853523;5713.228714123623;5485.725713714299;5489.34127904654;5489.972550137224;5490.450954904478;5511.944880551143;6239.032949892774;6299.5275354348605;6333.90499142504;6337.493000560036;6337.936620633827;6216.067589861557;6307.43233109004;6316.968415157895;6320.62076275425;6323.272823625342;6341.936580634319;6347.270063942706;6353.650549219838;6358.459530939737;6371.044343348535;6046.1535385045245;6099.400579572161;6256.906146407717;6264;6291.465225455888;6154.984427569401;6160.414760597736;6290.370962903673;6307.432331090224;6323.1463752394675;6121.755129794758;6148.292946311052;6151.630902145886;6155.4767844731905;6164.4143806338325;6005.189299444519;6068.241469816273;6071.605345652519;6091.238595175603;6147.784827531015;5939.316978547349;5983.162357269899;6004.309504407049;6068.4841788448475;6078.635281883101;6075.756969721162;6146.477549408171;6151.6001459904965;6153.4769544587425;6180.227471566054;5788.160716695971;5882.470577648032;5989.071693887455;5999.850003749767;6007.249093863267 11:38:15 INFO - PROCESS | 1659 | |1;ext.html;498.5139488998225;525.3275806967082;528.9550388217001;533.4292307231265;552.5579536370905;30233.395332093343;42111;43498.782506087424;43528.564714352884;45779.31331030029;39645.00887477815;43508.477203297894;44773.328400074024;45180.54819451804;45359.86600334993;8919.910800892005;9811.509424528764;9981.351212171221;9983.400995940267;9995.75010624733;8919.375643704943;12020.218685785396;12252.938735306356;12261.203021803598;12418.254904705747;12201.267923924594;12225.38873056348;12293.81559276607;12306.138570300083;12318.692032699164;8741.125887411272;9081;12129.272243665364;12233.082518811036;12233.449494772756;9087.772805679911;9119.224865886341;11713.180077394585;12017.579384721581;12059.638210853689;42870.92822679427;43263.56736432658;43265;43337.56662433367;43545.56454435447;3696.464012718168;4171.749695018309;4223.134257477241;4268.231718290706;4334.739915605074;32835;32969.01092967215;33028.66971330304;33036.00891973245;33051.6694833051;3313.8177400242976;3321.8339083045994;3334.3164651246434;3349.882754103595;3437.9828100859586;372366;373441;374009;375197;375417.99999999726;58865.70567147137;64172.67913660495;64324.678376608754;64362.356376436575;64396.678016609614;66886.66556667186;67025.6648716763;67077;67149.66425167843;67152.66423667948;16323.591910202102;16878.91560542214;16921.661566768595;16938.745918811226;16947.576310592332;11748.35384053868;11969.426573286784;12778.872211277954;13110.082294239312;13159.60521184366;17443.389481368093;18013.7297940531;18075.54811129706;18075.819241807414;18110.366137185134;6878.3465570770695;6886.173659160932;6895.55178913379;6905.861882762317;6962;889.888763904512;971.8396464583406;975.2052077556768;1003.3578509753763;1166.4342793745027;31243.37513249722;31354;31660.050198494082;32080.03759887207;32107.197320066716;1101.3061771084326;1122.8484154639182;1130.9773804523863;1141.303804679145;1197.5569039455456;16309.67380652404;16363.672726545641;16543.751843722355;16660.41688540895;16845.241964111643;688184;691615;693099;694215;694854;62417;62596;62722.68638656777;62862;62951.37048629547;1268.2960956668953;1374.8625137486172;1376.8829649479705;1394.3376895974332;1422.9501967431504;32559.18602034968;33038.33923321568;33109.503357449175;33342.49986250257;33453.14210586873;52493.21260181024;52897.206541901134;53475.19787203273;53600.73199633977;53703;556922;559195;559209;559525;560187;25338;42588.57411425846;43061.35407968946;44023;44161;41120;50052.74973625254;50245.74877125591;50247.748761255956;51345.229821553454;40865.79567102146;41358.1728365425;41376.58623413727;42564.5743542573;42662.93342666458;41734.582654174286;41793.106189326274;41842.58157418387;42392.788036059625;42437.36343954905;39541;40196.39705404363;40449.595504044584;40481.59518404778;40554.79722601368;34831.651683483855;35674.28651427009;36591;36630.8168459156;37052.629473705994;26408.603870941566;26427.60358594661;26544;27063.1881043569;27264.454710906066 11:38:15 INFO - PROCESS | 1659 | |2;jquery.html;359.3352298248243;383.0825173708967;383.8963479860438;390.7206347461565;409.93236116040833;15483.168732087759;16827.747583786237;20718.067686954084;21277.893610531963;21329.25347612832;21421.892890535564;21736.565268694616;21746.238881639147;21775;21790.01944912479;4229.175310814392;5834.241548598687;5843.2695913010875;6072.423119803634;6118.11287363332;4348.913021739563;4351.781501179675;4358.978205108986;4365.869023929271;4391.934120988188;6438.839029024265;6798.966005169992;6834.350736680032;6869.210040845317;7840.921590784104;7933.167017463141;8089.31240844526;8211.096779354297;8221.712240071629;8231.794205144859;7912.683492660287;7945.960270198612;7952.761417157494;7957.5225486471;7969.242921922408;29390.412191756044;30016.69983300183;30842.766289348398;30883.536746948816;31163.53254701181;2401.0515846240637;2448.204333591572;2531.936701582457;2535.378832186108;2589.4562141950214;16339.591510212222;16702.83297167025;17331.873428227125;17678.46964591064;18084.095795210185;2021.1309137071053;2114.534802343482;2122.7028216049766;2172.576347612216;2195.2755590655206;131562.3421882894;133150.99999999904;135277;141409;148043;16809.6638067238;22000.44998875009;22085.380543712392;22316.218932337615;22417.10331586751;36410.63589364125;36412.81793591068;36473;36549.90350289496;36603;11016.283941543774;11202.21584489078;11272.154588405903;11313.886861131283;11317.547298108148;8500.957495212608;9181.035991220826;9225.630974761069;9319.487428191378;9337.953310233406;9925.972578363668;9958.502074896227;10089.041541053588;10107.382818748965;10118.190544756402;2979.702029797003;2987.4025194961096;3731.402975523903;3740.372937772078;3840.3663395539425;876.6668665906915;924.8751418558543;951.7715748220377;975.3513913247752;995.9253056020827;20302;21664.133434662435;21664.35006949794;21676;21757.782422175893;898.820235952812;928.5589345061042;964.320154291237;1005.4771518810178;1025.7281820317621;10923.508442120125;10968.890311096788;11320;11359.943200283946;11463.535339901795;597718;598968;603534;610890;611289;123961;128564.35717821351;132228;135580;138063;958.4872093430121;978.9951050244703;999.4253304350028;1000.6547741029333;1003.2626019875531;15084.547463576111;19113.426597202106;19130.904345478182;19244.84530928149;19349.592684788724;48598.02803943831;49923.500764991884;56518.43481565296;56616.43383566112;57480.42519574918;81717;82656.58671706603;83952;84361;85093.57453212695;8007.15924827908;8322.708705195531;8380.036295826058;8476.915230847859;8586.313094952513;12250.509979600716;13199.67000824987;13200.933995330346;13421.66445838862;13462.990275729164;11184.432492507769;11400.087992960709;11510.07919366432;11669.066474682175;11698.883011170119;11238.55045798159;11325;11512.654620361403;11616.825747613962;11685.721284349811;11535.077193824642;11614.187006909435;11740;11800.763984720086;11900.285982841056;8309.95845020771;8387.706430274911;8393.748187554384;8440.324774018187;8553.529555874487;8421.621027053674;10671.35971841692;11568.24806387582;11661.416929153678;11691.064714822624 11:38:15 INFO - PROCESS | 1659 | |3;mootools.html;1355.1801160298019;1558;1759.9560010999724;1924.6824273994791;1938.5832046110074;8485.745427637174;8732.214100730933;8941.821163576724;8947.150020748026;8951.507667078307;7824.295813376795;8074.313683336909;8401.87397189041;8650.783730406743;8651.351148663844;2315.7568455312203;2344.4490544721975;2346.436855154759;2347.225415612852;2347.284078356109;2223.0663121489015;2324.535092981402;2333.6266197408418;2368.27767530903;2370.0756704885107;1867.4491025147547;2126.797954194349;2265.3883451468096;2326.9767302327014;2336.310788317442;2303.7005189325446;2308.722953245613;2331.4870728439714;2337.2637619149914;2341.7775311345395;2340.4265954841003;2362.7755363240462;2364.1725396111397;2364.2788949370433;2371.833971621987;8920.375573709844;8982.236509896667;8982.326325525546;8985.326100542485;8986.730398088068;4318.82724691012;5291.947080529184;5351.598630102758;5359.892802143974;5368.248445217672;3456.2050728332374;3596.820158992066;3621.6378362163837;3669.908252293714;3671.3391589513876;1065.1053115383038;1090.5801266512372;1095.11842966412;1097.7749561339906;1106.5629076514813;11790;11797.469113889896;11808.055355571534;11810.645680629596;11818.527258909548;4507.932381014288;4536.160810250114;4750.144973904661;4750.857474275777;4774.474807771142;9936.403815771077;9948.403095814274;9954.004599539989;9954.30319877616;9957.502124893726;5334.333208348957;5345.33183352081;5351.839444816662;5357.062514060024;5358.5981051421295;4714.222153344728;4920.188168952082;4930.605551555867;4934.802607895644;4942.283368911488;3537.1157210697324;3773.8679146230297;3775.4525593789294;3845.1732877431486;3891.3968334908136;942.8497233375272;1039.740064983754;1078.3314345106085;1123.3372310336777;1137.243732917617;780.1223623423648;802.0776107476332;869.626060793852;886.822635472908;953.5518306395983;5199.948000520022;6005.459508644243;6045.697715114226;6085.604435711664;6206.937930620727;966.8066386722684;973.5834361004862;981.8527220916919;992.3450522655013;1027.2347101409498;3945;4078.632923036941;4079.326911059642;4124.298869192245;4174.123434078817;10584.523696433678;10788.730281742863;10793.622223222306;10903.127749779998;10903.509342079626;9790;10854.240203185858;10854.62008829703;10859.619913302997;10859.945700271448;7991.960040199761;8362.49825010489;8380.958095209486;8447.141628841646;8478.194571515696;5947.613405128653;6058.636481811105;6132.846678833065;6195.81412557624;6304.148939893055;9830.508474576385;9869.11177993984;10057.798844023226;10107.898921010696;10144.036316549917;10199.184065274909;10312.329698569569;10324.896751032393;10330.896691032993;10336.328138670962;7631.465797394351;7634.847303053797;7668.501547399401;7775.339096176888;7783.7275695352655;8331.958340208503;8382.413231073766;8400.41197116196;8447.028591711785;8455.619497122521;6376.872462550816;7452.254774522505;7536.8492630148185;7681;7701.768946931601;7439.888401674088;7451.962740186481;7462.029936108272;7559.697612095674;7573.545587264782;7093.255208203271;7113.786586402416;7147.714091436284;7168.319009694071;7169.139703235645;4624.722516648877;4985.92802547454;5028.572571331331;5175.938932518749;5235.240890070842;4955.380577427822;4992.600591952708;4995.425526064549;4998.1253280676465;5086.491350864884 11:38:15 INFO - PROCESS | 1659 | |4;prototype.html;306.4208645659702;310.33742958783;319.26887427790354;323.85264704559427;324.3545344763921;4896.608271338288;5333.333333333333;5543.25166102576;5557.194206840006;5569.944300556993;4450.955490445094;5314.92027619586;5416.808302173526;5498.508447136068;5501.4223506531835;4204.474440694913;4241.957580424202;4268.530461649216;4277.93583096253;4323.589259020388;4129.401236820674;4244;4258.190943720699;4303.311470164758;4341.891452713676;3443.896683099511;3489.877854275101;4170.436991006206;4210.6420954218775;4238.830446782125;4222.4799072333935;4272.722273052257;4283.721558098714;4285.392977633387;4293.48478182617;2698.7840972722133;3315.8891771256417;3407.5399821024093;3477.3566890125408;3623.673869351771;4649.488556258809;5357.223202635635;5365.987754592028;5367.543758780508;5399.2980912481125;3970.7021973352116;4777.331173635694;4790.353302304179;4800.95199048012;4818.879528011793;5064.924026139611;5514.531264842493;5804.5910946956965;5842.53936515871;5883.52931765462;3934.291827471054;3961.9397913085036;3976.4830572025453;3985.2228815381013;3998.660113890323;5884.117382392633;6073.058675905242;6228.346023667495;6258.87482250357;6275.905861412082;3743.6256374362347;4005.859794907164;4007.719459637856;4012.117334186474;4035.1526179502634;4056.492938382702;4087.8773636790943;4090.652294554937;4099.651529619956;4102.548719640837;4728.338032675429;4748.715077095385;4765.142274390643;4765.92766627513;4770.0936822004405;4896.57239932049;4971.90056198874;5550.861228469321;5679.801206957819;5835.153902684172;1250.3810613746255;1250.424804589899;1273.891719203878;1319.4985905356095;1394.9790753138711;908.3459908865606;916.935814492979;930.3952430919957;932.1796818799548;941.2470023980925;4609.861704148881;4882.2903288675125;5172.637915345965;5245.344331958505;5295.523402893758;5550;5757.280339957505;5810;5820.603055266705;5896.233489646319;5173.861750414902;5209.6239159106535;5318.44156363584;5341.813036543703;5370.946290537123;2198.9445066368235;2207.260567709803;2275.9388622302977;2562.372893212786;2641.511320405731;4265.888385249322;4497.572730590654;4692.577668009895;4854;4983.8006479741125;4614;5110.386753589593;5175.689458632494;5604.523615492729;5644.3508996465935;5228.816991405359;6136.815895523141;6177.4749146323065;6207.875842483216;6239.719212635442;5270.8945821084135;5628.073401830943;6058.545609079249;6068.302145253352;6108.267007958895;4946;5680.261565996394;5922;5929.458340831501;5941.732622031933;4929.975350123226;6163.8150855474405;6201.573638063361;6210.223722034746;6213.440790328711;3360.899173024813;3742.681872040907;4020.8391664334267;4041.7372870764484;4051.2505186540584;4464.910701785881;5363.383210930637;5836.824895253149;5927.40725927421;5971.91042134377;5096.770645320896;5722.513586345207;5824.835032993334;5835.328937172109;5893.9115913260475;4645.6283497319455;5085.440601533903;5799.333076696065;5827.475527202572;5858.736356863866;4761.40482439695;4792.760361981817;5319.813806516753;5721.942780572141;5730.828075157752;5781.248437703071;5837.824865254049;5870.823875283749;5878.441548052928;5889.823305300848;5536.456488221941;5661.905095114834;5744.5011420032415;5803.216565763568;5873.236479257669 11:38:15 INFO - PROCESS | 1659 | |5;yui.html;736.3520102309968;738.6196109003864;776.0842206196689;811.2982270336161;866.0300463480902;14891.776623350645;16050.83949160508;16066.437674681389;16070.678586428265;16111;14455.78316325256;14627;14635.048721833073;14695.853041469582;14758.409663613442;681.9079424277721;855.8801767752519;887.2857349833381;891.3047822698273;891.7725979875155;858.1418581418582;870.4864130163203;873.4715497124243;873.7771868173606;875.2429148786285;729.6570611812466;932.4498545857942;933.2347475070445;933.9673111441101;934.4580143516778;908.7773495493614;934.7429456899407;935.0415823780611;940.6472572785206;944.1031020530455;701.5194591704699;702.420503084957;703;704.1550139832221;704.5385272646424;14852.70294594102;14870.959032867704;14883;14891.47879824201;14894.627634309121;13738.65653358664;13791;13904.443822247098;14018.018738688295;14037.859621403757;12073.215241009306;12611.684707882285;12628.494860205581;12948.870511294861;13149.342532873317;4539.795709193094;4560.498345182027;4562.429696287964;4755.619550435957;4779.976100119512;105950;106780.46609766979;109272.4536377313;110075.89924100737;111995;9547.2839537035;10072.848907266396;11200.775984480428;11256.943715281535;11341.489632966537;21285;21576.892115539635;21582.244621438174;21594;21624.459388515414;7889.17163697815;7926.920730792734;8001.799955001055;8088.71689490865;8157.306628936488;4429.911401771947;4483.14820184164;4522.638188944877;4524.434445694288;4686.7656617169005;3405.0465869556565;3484.7038001770134;3524.8942531724088;3526.9118272043406;3528.3648943190474;632.7374139732052;644.716324817079;645.961242325462;646.0825627608807;653.6372313366032;547.2693953572026;560.5202266017717;583.0408977232513;626.4956709848616;632.253940350391;8208.753737387888;8476.745697629081;9024.232940200025;9143.451392916446;9230.353875228804;531.2376739379004;533.5518164741558;572.796657186701;586.7066466766616;620.1999420747276;4487.439070116236;4658.566403033816;4727.83918401365;4792.113459010095;4799.136155491975;127747.72252277544;130740;132359;150009;150610;56847;119037;120442.3977880105;123495;123794;92140.53929730308;112973.74052518903;114257;117779.2333115004;117833;9902.108810207115;10168.644097456849;10187.439690817077;10188.134008609351;10188.745281368025;62425.751484970955;63422.68288658527;63722;64330.391740205;65752.67123664351;97008.0299196999;97716.51141744245;99551.5067273977;100872;101477;11169.776604468028;13204.405801739136;13375.732485350043;13476;13967.710128183437;15075.924620376827;15083.849161508244;15346.386144554093;15452.768208476657;15828.28772705208;13630.318484075558;13695.246761428218;13750.174989500661;13803;13993.760998240232;13856.93071534636;14176.716465670836;14182.149071055357;14402.783958240845;14518.346674399467;13266.602001939957;13530.323483825967;13810.240436776074;14054.156750594997;14297.785533217218;10022.799544009225;11168.329900205688;11529;11785.410729463663;11863.644090677293;10615.31000484966;11326.546938122712;11382.317060976367;11574.263860421004;11688.47401866901 11:38:15 INFO - PROCESS | 1659 | __end_tp_report 11:38:15 INFO - PROCESS | 1659 | __start_cc_report 11:38:15 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1333 11:38:15 INFO - PROCESS | 1659 | __end_cc_report 11:38:15 INFO - PROCESS | 1659 | __startTimestamp1468867095491__endTimestamp 11:38:15 INFO - PROCESS | 1659 | 11:38:15 INFO - PROCESS | 1659 | ------- Summary: start ------- 11:38:15 INFO - PROCESS | 1659 | Number of tests: 6 11:38:15 INFO - PROCESS | 1659 | 11:38:15 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5773.02 Median:5999.85 stddev:650.28 (10.8%) stddev-sans-first:608.63 11:38:15 INFO - PROCESS | 1659 | Values: 2787.4 3371.6 3372.9 3408.5 3428.0 5654.2 5694.0 5763.0 5787.3 5808.7 5728.8 5738.4 5858.8 5933.6 5955.9 5192.2 5209.7 5260.8 5297.4 5346.7 5315.3 5339.8 5351.1 5369.2 5373.3 5152.0 5204.8 5215.5 5274.7 5285.0 5160.3 5168.5 5251.9 5276.5 5322.0 5130.2 5154.4 5223.1 5230.2 5273.2 5736.7 5795.3 5807.4 5835.4 5839.7 6130.4 6177.4 6240.1 6249.9 6281.8 6267.1 6292.2 6325.0 6335.2 6336.3 5912.9 5916.5 5919.3 5921.7 5932.2 6212.0 6230.1 6292.1 6300.0 6337.4 5107.6 5194.3 6202.2 6204.2 6238.2 6103.5 6240.4 6289.7 6323.7 6348.5 6138.0 6150.7 6219.7 6223.8 6280.7 6236.4 6265.7 6299.7 6321.6 6324.3 4908.3 5023.9 5028.5 5043.3 5064.9 4399.9 4431.0 4451.9 4474.6 4494.9 5659.0 5683.9 5700.4 5754.4 5772.4 6144.6 6185.8 6281.5 6302.7 6315.8 5638.6 5658.9 5679.6 5690.4 5713.2 5485.7 5489.3 5490.0 5490.5 5511.9 6239.0 6299.5 6333.9 6337.5 6337.9 6216.1 6307.4 6317.0 6320.6 6323.3 6341.9 6347.3 6353.7 6358.5 6371.0 6046.2 6099.4 6256.9 6264.0 6291.5 6155.0 6160.4 6290.4 6307.4 6323.1 6121.8 6148.3 6151.6 6155.5 6164.4 6005.2 6068.2 6071.6 6091.2 6147.8 5939.3 5983.2 6004.3 6068.5 6078.6 6075.8 6146.5 6151.6 6153.5 6180.2 5788.2 5882.5 5989.1 5999.9 6007.2 11:38:15 INFO - PROCESS | 1659 | 11:38:15 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:69733.11 Median:40710.30 stddev:148892.61 (365.7%) stddev-sans-first:149219.55 11:38:15 INFO - PROCESS | 1659 | Values: 498.5 525.3 529.0 533.4 552.6 30233.4 42111.0 43498.8 43528.6 45779.3 39645.0 43508.5 44773.3 45180.5 45359.9 8919.9 9811.5 9981.4 9983.4 9995.8 8919.4 12020.2 12252.9 12261.2 12418.3 12201.3 12225.4 12293.8 12306.1 12318.7 8741.1 9081.0 12129.3 12233.1 12233.4 9087.8 9119.2 11713.2 12017.6 12059.6 42870.9 43263.6 43265.0 43337.6 43545.6 3696.5 4171.7 4223.1 4268.2 4334.7 32835.0 32969.0 33028.7 33036.0 33051.7 3313.8 3321.8 3334.3 3349.9 3438.0 372366.0 373441.0 374009.0 375197.0 375418.0 58865.7 64172.7 64324.7 64362.4 64396.7 66886.7 67025.7 67077.0 67149.7 67152.7 16323.6 16878.9 16921.7 16938.7 16947.6 11748.4 11969.4 12778.9 13110.1 13159.6 17443.4 18013.7 18075.5 18075.8 18110.4 6878.3 6886.2 6895.6 6905.9 6962.0 889.9 971.8 975.2 1003.4 1166.4 31243.4 31354.0 31660.1 32080.0 32107.2 1101.3 1122.8 1131.0 1141.3 1197.6 16309.7 16363.7 16543.8 16660.4 16845.2 688184.0 691615.0 693099.0 694215.0 694854.0 62417.0 62596.0 62722.7 62862.0 62951.4 1268.3 1374.9 1376.9 1394.3 1423.0 32559.2 33038.3 33109.5 33342.5 33453.1 52493.2 52897.2 53475.2 53600.7 53703.0 556922.0 559195.0 559209.0 559525.0 560187.0 25338.0 42588.6 43061.4 44023.0 44161.0 41120.0 50052.7 50245.7 50247.7 51345.2 40865.8 41358.2 41376.6 42564.6 42662.9 41734.6 41793.1 41842.6 42392.8 42437.4 39541.0 40196.4 40449.6 40481.6 40554.8 34831.7 35674.3 36591.0 36630.8 37052.6 26408.6 26427.6 26544.0 27063.2 27264.5 11:38:15 INFO - PROCESS | 1659 | 11:38:15 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:37741.50 Median:15989.93 stddev:101198.36 (632.9%) stddev-sans-first:101443.32 11:38:15 INFO - PROCESS | 1659 | Values: 359.3 383.1 383.9 390.7 409.9 15483.2 16827.7 20718.1 21277.9 21329.3 21421.9 21736.6 21746.2 21775.0 21790.0 4229.2 5834.2 5843.3 6072.4 6118.1 4348.9 4351.8 4359.0 4365.9 4391.9 6438.8 6799.0 6834.4 6869.2 7840.9 7933.2 8089.3 8211.1 8221.7 8231.8 7912.7 7946.0 7952.8 7957.5 7969.2 29390.4 30016.7 30842.8 30883.5 31163.5 2401.1 2448.2 2531.9 2535.4 2589.5 16339.6 16702.8 17331.9 17678.5 18084.1 2021.1 2114.5 2122.7 2172.6 2195.3 131562.3 133151.0 135277.0 141409.0 148043.0 16809.7 22000.4 22085.4 22316.2 22417.1 36410.6 36412.8 36473.0 36549.9 36603.0 11016.3 11202.2 11272.2 11313.9 11317.5 8501.0 9181.0 9225.6 9319.5 9338.0 9926.0 9958.5 10089.0 10107.4 10118.2 2979.7 2987.4 3731.4 3740.4 3840.4 876.7 924.9 951.8 975.4 995.9 20302.0 21664.1 21664.4 21676.0 21757.8 898.8 928.6 964.3 1005.5 1025.7 10923.5 10968.9 11320.0 11359.9 11463.5 597718.0 598968.0 603534.0 610890.0 611289.0 123961.0 128564.4 132228.0 135580.0 138063.0 958.5 979.0 999.4 1000.7 1003.3 15084.5 19113.4 19130.9 19244.8 19349.6 48598.0 49923.5 56518.4 56616.4 57480.4 81717.0 82656.6 83952.0 84361.0 85093.6 8007.2 8322.7 8380.0 8476.9 8586.3 12250.5 13199.7 13200.9 13421.7 13463.0 11184.4 11400.1 11510.1 11669.1 11698.9 11238.6 11325.0 11512.7 11616.8 11685.7 11535.1 11614.2 11740.0 11800.8 11900.3 8310.0 8387.7 8393.7 8440.3 8553.5 8421.6 10671.4 11568.2 11661.4 11691.1 11:38:15 INFO - PROCESS | 1659 | 11:38:15 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5744.54 Median:4989.26 stddev:3240.82 (65.0%) stddev-sans-first:3233.13 11:38:15 INFO - PROCESS | 1659 | Values: 1355.2 1558.0 1760.0 1924.7 1938.6 8485.7 8732.2 8941.8 8947.2 8951.5 7824.3 8074.3 8401.9 8650.8 8651.4 2315.8 2344.4 2346.4 2347.2 2347.3 2223.1 2324.5 2333.6 2368.3 2370.1 1867.4 2126.8 2265.4 2327.0 2336.3 2303.7 2308.7 2331.5 2337.3 2341.8 2340.4 2362.8 2364.2 2364.3 2371.8 8920.4 8982.2 8982.3 8985.3 8986.7 4318.8 5291.9 5351.6 5359.9 5368.2 3456.2 3596.8 3621.6 3669.9 3671.3 1065.1 1090.6 1095.1 1097.8 1106.6 11790.0 11797.5 11808.1 11810.6 11818.5 4507.9 4536.2 4750.1 4750.9 4774.5 9936.4 9948.4 9954.0 9954.3 9957.5 5334.3 5345.3 5351.8 5357.1 5358.6 4714.2 4920.2 4930.6 4934.8 4942.3 3537.1 3773.9 3775.5 3845.2 3891.4 942.8 1039.7 1078.3 1123.3 1137.2 780.1 802.1 869.6 886.8 953.6 5199.9 6005.5 6045.7 6085.6 6206.9 966.8 973.6 981.9 992.3 1027.2 3945.0 4078.6 4079.3 4124.3 4174.1 10584.5 10788.7 10793.6 10903.1 10903.5 9790.0 10854.2 10854.6 10859.6 10859.9 7992.0 8362.5 8381.0 8447.1 8478.2 5947.6 6058.6 6132.8 6195.8 6304.1 9830.5 9869.1 10057.8 10107.9 10144.0 10199.2 10312.3 10324.9 10330.9 10336.3 7631.5 7634.8 7668.5 7775.3 7783.7 8332.0 8382.4 8400.4 8447.0 8455.6 6376.9 7452.3 7536.8 7681.0 7701.8 7439.9 7452.0 7462.0 7559.7 7573.5 7093.3 7113.8 7147.7 7168.3 7169.1 4624.7 4985.9 5028.6 5175.9 5235.2 4955.4 4992.6 4995.4 4998.1 5086.5 11:38:15 INFO - PROCESS | 1659 | 11:38:15 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4613.19 Median:5091.11 stddev:1447.16 (28.4%) stddev-sans-first:1414.66 11:38:15 INFO - PROCESS | 1659 | Values: 306.4 310.3 319.3 323.9 324.4 4896.6 5333.3 5543.3 5557.2 5569.9 4451.0 5314.9 5416.8 5498.5 5501.4 4204.5 4242.0 4268.5 4277.9 4323.6 4129.4 4244.0 4258.2 4303.3 4341.9 3443.9 3489.9 4170.4 4210.6 4238.8 4222.5 4272.7 4283.7 4285.4 4293.5 2698.8 3315.9 3407.5 3477.4 3623.7 4649.5 5357.2 5366.0 5367.5 5399.3 3970.7 4777.3 4790.4 4801.0 4818.9 5064.9 5514.5 5804.6 5842.5 5883.5 3934.3 3961.9 3976.5 3985.2 3998.7 5884.1 6073.1 6228.3 6258.9 6275.9 3743.6 4005.9 4007.7 4012.1 4035.2 4056.5 4087.9 4090.7 4099.7 4102.5 4728.3 4748.7 4765.1 4765.9 4770.1 4896.6 4971.9 5550.9 5679.8 5835.2 1250.4 1250.4 1273.9 1319.5 1395.0 908.3 916.9 930.4 932.2 941.2 4609.9 4882.3 5172.6 5245.3 5295.5 5550.0 5757.3 5810.0 5820.6 5896.2 5173.9 5209.6 5318.4 5341.8 5370.9 2198.9 2207.3 2275.9 2562.4 2641.5 4265.9 4497.6 4692.6 4854.0 4983.8 4614.0 5110.4 5175.7 5604.5 5644.4 5228.8 6136.8 6177.5 6207.9 6239.7 5270.9 5628.1 6058.5 6068.3 6108.3 4946.0 5680.3 5922.0 5929.5 5941.7 4930.0 6163.8 6201.6 6210.2 6213.4 3360.9 3742.7 4020.8 4041.7 4051.3 4464.9 5363.4 5836.8 5927.4 5971.9 5096.8 5722.5 5824.8 5835.3 5893.9 4645.6 5085.4 5799.3 5827.5 5858.7 4761.4 4792.8 5319.8 5721.9 5730.8 5781.2 5837.8 5870.8 5878.4 5889.8 5536.5 5661.9 5744.5 5803.2 5873.2 11:38:15 INFO - PROCESS | 1659 | 11:38:15 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:24393.18 Median:21588.12 stddev:38003.62 (176.0%) stddev-sans-first:38068.72 11:38:15 INFO - PROCESS | 1659 | Values: 736.4 738.6 776.1 811.3 866.0 14891.8 16050.8 16066.4 16070.7 16111.0 14455.8 14627.0 14635.0 14695.9 14758.4 681.9 855.9 887.3 891.3 891.8 858.1 870.5 873.5 873.8 875.2 729.7 932.4 933.2 934.0 934.5 908.8 934.7 935.0 940.6 944.1 701.5 702.4 703.0 704.2 704.5 14852.7 14871.0 14883.0 14891.5 14894.6 13738.7 13791.0 13904.4 14018.0 14037.9 12073.2 12611.7 12628.5 12948.9 13149.3 4539.8 4560.5 4562.4 4755.6 4780.0 105950.0 106780.5 109272.5 110075.9 111995.0 9547.3 10072.8 11200.8 11256.9 11341.5 21285.0 21576.9 21582.2 21594.0 21624.5 7889.2 7926.9 8001.8 8088.7 8157.3 4429.9 4483.1 4522.6 4524.4 4686.8 3405.0 3484.7 3524.9 3526.9 3528.4 632.7 644.7 646.0 646.1 653.6 547.3 560.5 583.0 626.5 632.3 8208.8 8476.7 9024.2 9143.5 9230.4 531.2 533.6 572.8 586.7 620.2 4487.4 4658.6 4727.8 4792.1 4799.1 127747.7 130740.0 132359.0 150009.0 150610.0 56847.0 119037.0 120442.4 123495.0 123794.0 92140.5 112973.7 114257.0 117779.2 117833.0 9902.1 10168.6 10187.4 10188.1 10188.7 62425.8 63422.7 63722.0 64330.4 65752.7 97008.0 97716.5 99551.5 100872.0 101477.0 11169.8 13204.4 13375.7 13476.0 13967.7 15075.9 15083.8 15346.4 15452.8 15828.3 13630.3 13695.2 13750.2 13803.0 13993.8 13856.9 14176.7 14182.1 14402.8 14518.3 13266.6 13530.3 13810.2 14054.2 14297.8 10022.8 11168.3 11529.0 11785.4 11863.6 10615.3 11326.5 11382.3 11574.3 11688.5 11:38:15 INFO - PROCESS | 1659 | -------- Summary: end -------- 11:38:15 INFO - PROCESS | 1659 | 11:38:16 INFO - TEST-INFO | 1659: exit 0 11:38:16 INFO - TEST-OK | dromaeo_css | took 1116491ms 11:38:16 INFO - TEST-START | kraken 11:38:16 INFO - operating with platform_type : mac_ 11:38:16 INFO - Initialising browser for kraken test... 11:38:16 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpbEfagX/profile http://localhost:49273/getInfo.html) 11:38:18 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 11:38:18 INFO - PROCESS | 1666 | colorDepth:24 11:38:18 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 11:38:18 INFO - PROCESS | 1666 | __metrics 11:38:18 INFO - PROCESS | 1666 | 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" 11:38:18 INFO - PROCESS | 1666 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:38:18 INFO - TEST-INFO | 1666: exit 0 11:38:18 INFO - Browser initialized. 11:38:18 INFO - Running cycle 1/1 for kraken test... 11:38:18 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpbEfagX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:38:31 INFO - PROCESS | 1668 | RSS: Main: 245030912 11:38:31 INFO - PROCESS | 1668 | 11:38:33 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 11:38:34 INFO - PROCESS | 1668 | RSS: Main: 294633472 11:38:34 INFO - PROCESS | 1668 | 11:38:37 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 11:38:37 INFO - PROCESS | 1668 | RSS: Main: 305549312 11:38:37 INFO - PROCESS | 1668 | 11:38:41 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 11:38:42 INFO - PROCESS | 1668 | RSS: Main: 393056256 11:38:42 INFO - PROCESS | 1668 | 11:38:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 11:38:44 INFO - PROCESS | 1668 | RSS: Main: 292642816 11:38:44 INFO - PROCESS | 1668 | 11:38:46 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:38:47 INFO - PROCESS | 1668 | RSS: Main: 296624128 11:38:47 INFO - PROCESS | 1668 | 11:38:50 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 11:38:51 INFO - PROCESS | 1668 | RSS: Main: 333824000 11:38:51 INFO - PROCESS | 1668 | 11:38:53 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 11:38:54 INFO - PROCESS | 1668 | RSS: Main: 335163392 11:38:54 INFO - PROCESS | 1668 | 11:38:57 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 11:38:58 INFO - PROCESS | 1668 | RSS: Main: 334106624 11:38:58 INFO - PROCESS | 1668 | 11:38:59 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:39:00 INFO - PROCESS | 1668 | RSS: Main: 312078336 11:39:00 INFO - PROCESS | 1668 | 11:39:02 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:39:02 INFO - PROCESS | 1668 | RSS: Main: 318386176 11:39:02 INFO - PROCESS | 1668 | 11:39:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:39:05 INFO - PROCESS | 1668 | RSS: Main: 315998208 11:39:05 INFO - PROCESS | 1668 | 11:39:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:39:09 INFO - PROCESS | 1668 | RSS: Main: 325132288 11:39:09 INFO - PROCESS | 1668 | 11:39:12 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:39:12 INFO - PROCESS | 1668 | RSS: Main: 327573504 11:39:12 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 11:39:14 INFO - PROCESS | 1668 | RSS: Main: 371802112 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | __start_tp_report 11:39:14 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 11:39:14 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 11:39:14 INFO - PROCESS | 1668 | |i|pagename|runs| 11:39:14 INFO - PROCESS | 1668 | |0;ai-astar;100;113;112;112;111;112;111;112;111;112 11:39:14 INFO - PROCESS | 1668 | |1;audio-beat-detection;140;107;104;157;105;112;156;137;171;107 11:39:14 INFO - PROCESS | 1668 | |2;audio-dft;178;185;182;181;185;184;184;187;194;181 11:39:14 INFO - PROCESS | 1668 | |3;audio-fft;79;77;57;85;57;58;60;55;125;80 11:39:14 INFO - PROCESS | 1668 | |4;audio-oscillator;85;70;111;101;126;79;95;69;67;96 11:39:14 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;121;85;87;85;86;84;88;85;90;129 11:39:14 INFO - PROCESS | 1668 | |6;imaging-darkroom;92;93;92;93;93;92;94;93;93;94 11:39:14 INFO - PROCESS | 1668 | |7;imaging-desaturate;85;84;84;84;84;85;84;84;85;84 11:39:14 INFO - PROCESS | 1668 | |8;json-parse-financial;54;53;52;52;55;72;80;78;52;53 11:39:14 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;66;67;82;76;70;69;72;71;69;70 11:39:14 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;88;84;84;83;83;82;82;83;90;100 11:39:14 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;138;153;146;156;134;133;131;134;222;144 11:39:14 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;167;164;164;165;164;162;164;164;161;163 11:39:14 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;60;83;90;83;65;67;66;66;65;67 11:39:14 INFO - PROCESS | 1668 | __end_tp_report 11:39:14 INFO - PROCESS | 1668 | __start_cc_report 11:39:14 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,2060 11:39:14 INFO - PROCESS | 1668 | __end_cc_report 11:39:14 INFO - PROCESS | 1668 | __startTimestamp1468867154817__endTimestamp 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | ------- Summary: start ------- 11:39:14 INFO - PROCESS | 1668 | Number of tests: 14 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:110.60 Median:112.00 stddev:3.78 (3.4%) stddev-sans-first:0.67 11:39:14 INFO - PROCESS | 1668 | Values: 100.0 113.0 112.0 112.0 111.0 112.0 111.0 112.0 111.0 112.0 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:138.50 stddev:25.64 (18.5%) stddev-sans-first:26.92 11:39:14 INFO - PROCESS | 1668 | Values: 140.0 107.0 104.0 157.0 105.0 112.0 156.0 137.0 171.0 107.0 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.50 stddev:4.33 (2.3%) stddev-sans-first:3.99 11:39:14 INFO - PROCESS | 1668 | Values: 178.0 185.0 182.0 181.0 185.0 184.0 184.0 187.0 194.0 181.0 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:73.30 Median:68.50 stddev:21.55 (31.5%) stddev-sans-first:22.75 11:39:14 INFO - PROCESS | 1668 | Values: 79.0 77.0 57.0 85.0 57.0 58.0 60.0 55.0 125.0 80.0 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:74.50 stddev:19.54 (26.2%) stddev-sans-first:20.64 11:39:14 INFO - PROCESS | 1668 | Values: 85.0 70.0 111.0 101.0 126.0 79.0 95.0 69.0 67.0 96.0 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:94.00 Median:85.50 stddev:16.54 (19.3%) stddev-sans-first:14.37 11:39:14 INFO - PROCESS | 1668 | Values: 121.0 85.0 87.0 85.0 86.0 84.0 88.0 85.0 90.0 129.0 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 11:39:14 INFO - PROCESS | 1668 | Values: 92.0 93.0 92.0 93.0 93.0 92.0 94.0 93.0 93.0 94.0 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.00 stddev:0.48 (0.6%) stddev-sans-first:0.44 11:39:14 INFO - PROCESS | 1668 | Values: 85.0 84.0 84.0 84.0 84.0 85.0 84.0 84.0 85.0 84.0 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:54.50 stddev:11.64 (21.4%) stddev-sans-first:12.13 11:39:14 INFO - PROCESS | 1668 | Values: 54.0 53.0 52.0 52.0 55.0 72.0 80.0 78.0 52.0 53.0 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:70.50 stddev:4.69 (6.6%) stddev-sans-first:4.58 11:39:14 INFO - PROCESS | 1668 | Values: 66.0 67.0 82.0 76.0 70.0 69.0 72.0 71.0 69.0 70.0 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:83.50 stddev:5.61 (6.7%) stddev-sans-first:5.89 11:39:14 INFO - PROCESS | 1668 | Values: 88.0 84.0 84.0 83.0 83.0 82.0 82.0 83.0 90.0 100.0 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:149.10 Median:145.00 stddev:27.04 (18.6%) stddev-sans-first:28.38 11:39:14 INFO - PROCESS | 1668 | Values: 138.0 153.0 146.0 156.0 134.0 133.0 131.0 134.0 222.0 144.0 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.80 Median:164.00 stddev:1.62 (1.0%) stddev-sans-first:1.24 11:39:14 INFO - PROCESS | 1668 | Values: 167.0 164.0 164.0 165.0 164.0 162.0 164.0 164.0 161.0 163.0 11:39:14 INFO - PROCESS | 1668 | 11:39:14 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:67.00 stddev:10.13 (15.1%) stddev-sans-first:9.90 11:39:14 INFO - PROCESS | 1668 | Values: 60.0 83.0 90.0 83.0 65.0 67.0 66.0 66.0 65.0 67.0 11:39:14 INFO - PROCESS | 1668 | -------- Summary: end -------- 11:39:14 INFO - PROCESS | 1668 | 11:39:15 INFO - TEST-INFO | 1668: exit 0 11:39:15 INFO - TEST-OK | kraken | took 59575ms 11:39:15 INFO - SUITE-END | took 1176s 11:39:15 INFO - Completed test suite (00:19:36) 11:39:15 INFO - javascript benchmark 11:39:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2787.38677490952, 3371.5954085509734, 3372.9156771080734, 3408.4546472564393, 3427.971608517444, 5654.208410822488, 5693.971530142354, 5763.049096899017, 5787.276590426197, 5808.680522571262, 5728.79949201778, 5738.42615738426, 5858.7949421770245, 5933.584649074566, 5955.940440595593, 5192.1952097424955, 5209.661372010826, 5260.763265653055, 5297.417284098746, 5346.732663366835, 5315.335583052119, 5339.813106541272, 5351.143816989283, 5369.221462887879, 5373.274607927939, 5152.046871328816, 5204.817831375922, 5215.530602245816, 5274.657147285453, 5284.973575132119, 5160.277561141444, 5168.483151684802, 5251.868703282448, 5276.498732620395, 5322.0154271459905, 5130.153524668425, 5154.355705536808, 5223.059849227137, 5230.189320655304, 5273.156294992783, 5736.713164341808, 5795.3045634523705, 5807.390224026501, 5835.416458354173, 5839.708014599253, 6130.386961303879, 6177.351378105324, 6240.063990401431, 6249.9062514062325, 6281.811545653638, 6267.05994100884, 6292.244930608311, 6324.968375158141, 6335.176427064452, 6336.303006669263, 5912.881742365129, 5916.4675179233645, 5919.260092488439, 5921.67430791302, 5932.169496270526, 6212.0371342441995, 6230.096635987757, 6292.119103325538, 6299.96850015747, 6337.397947195009, 5107.591392688576, 5194.3247377840635, 6202.162708034448, 6204.224471941007, 6238.189035425366, 6103.549811064039, 6240.375962403723, 6289.7169627366875, 6323.715432805535, 6348.492120630339, 6137.987232106652, 6150.677604315094, 6219.720112594944, 6223.782167624111, 6280.7487700491465, 6236.376362363727, 6265.71804268809, 6299.716512756937, 6321.620702757849, 6324.3043265240785, 4908.288298196741, 5023.924641130386, 5028.522290382408, 5043.3191519145175, 5064.873378165502, 4399.934000989988, 4430.955690443118, 4451.933221001687, 4474.574915383098, 4494.865154045384, 5658.97170514153, 5683.943160568424, 5700.429957004267, 5754.395788442153, 5772.3938986406665, 6144.556029333103, 6185.783497577653, 6281.528885333618, 6302.747890084437, 6315.778947736807, 5638.6052976292085, 5658.943410565842, 5679.574031947621, 5690.374058853523, 5713.228714123623, 5485.725713714299, 5489.34127904654, 5489.972550137224, 5490.450954904478, 5511.944880551143, 6239.032949892774, 6299.5275354348605, 6333.90499142504, 6337.493000560036, 6337.936620633827, 6216.067589861557, 6307.43233109004, 6316.968415157895, 6320.62076275425, 6323.272823625342, 6341.936580634319, 6347.270063942706, 6353.650549219838, 6358.459530939737, 6371.044343348535, 6046.1535385045245, 6099.400579572161, 6256.906146407717, 6264.0, 6291.465225455888, 6154.984427569401, 6160.414760597736, 6290.370962903673, 6307.432331090224, 6323.1463752394675, 6121.755129794758, 6148.292946311052, 6151.630902145886, 6155.4767844731905, 6164.4143806338325, 6005.189299444519, 6068.241469816273, 6071.605345652519, 6091.238595175603, 6147.784827531015, 5939.316978547349, 5983.162357269899, 6004.309504407049, 6068.4841788448475, 6078.635281883101, 6075.756969721162, 6146.477549408171, 6151.6001459904965, 6153.4769544587425, 6180.227471566054, 5788.160716695971, 5882.470577648032, 5989.071693887455, 5999.850003749767, 6007.249093863267], "value": 5730.011076203169, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.5139488998225, 525.3275806967082, 528.9550388217001, 533.4292307231265, 552.5579536370905, 30233.395332093343, 42111.0, 43498.782506087424, 43528.564714352884, 45779.31331030029, 39645.00887477815, 43508.477203297894, 44773.328400074024, 45180.54819451804, 45359.86600334993, 8919.910800892005, 9811.509424528764, 9981.351212171221, 9983.400995940267, 9995.75010624733, 8919.375643704943, 12020.218685785396, 12252.938735306356, 12261.203021803598, 12418.254904705747, 12201.267923924594, 12225.38873056348, 12293.81559276607, 12306.138570300083, 12318.692032699164, 8741.125887411272, 9081.0, 12129.272243665364, 12233.082518811036, 12233.449494772756, 9087.772805679911, 9119.224865886341, 11713.180077394585, 12017.579384721581, 12059.638210853689, 42870.92822679427, 43263.56736432658, 43265.0, 43337.56662433367, 43545.56454435447, 3696.464012718168, 4171.749695018309, 4223.134257477241, 4268.231718290706, 4334.739915605074, 32835.0, 32969.01092967215, 33028.66971330304, 33036.00891973245, 33051.6694833051, 3313.8177400242976, 3321.8339083045994, 3334.3164651246434, 3349.882754103595, 3437.9828100859586, 372366.0, 373441.0, 374009.0, 375197.0, 375417.99999999726, 58865.70567147137, 64172.67913660495, 64324.678376608754, 64362.356376436575, 64396.678016609614, 66886.66556667186, 67025.6648716763, 67077.0, 67149.66425167843, 67152.66423667948, 16323.591910202102, 16878.91560542214, 16921.661566768595, 16938.745918811226, 16947.576310592332, 11748.35384053868, 11969.426573286784, 12778.872211277954, 13110.082294239312, 13159.60521184366, 17443.389481368093, 18013.7297940531, 18075.54811129706, 18075.819241807414, 18110.366137185134, 6878.3465570770695, 6886.173659160932, 6895.55178913379, 6905.861882762317, 6962.0, 889.888763904512, 971.8396464583406, 975.2052077556768, 1003.3578509753763, 1166.4342793745027, 31243.37513249722, 31354.0, 31660.050198494082, 32080.03759887207, 32107.197320066716, 1101.3061771084326, 1122.8484154639182, 1130.9773804523863, 1141.303804679145, 1197.5569039455456, 16309.67380652404, 16363.672726545641, 16543.751843722355, 16660.41688540895, 16845.241964111643, 688184.0, 691615.0, 693099.0, 694215.0, 694854.0, 62417.0, 62596.0, 62722.68638656777, 62862.0, 62951.37048629547, 1268.2960956668953, 1374.8625137486172, 1376.8829649479705, 1394.3376895974332, 1422.9501967431504, 32559.18602034968, 33038.33923321568, 33109.503357449175, 33342.49986250257, 33453.14210586873, 52493.21260181024, 52897.206541901134, 53475.19787203273, 53600.73199633977, 53703.0, 556922.0, 559195.0, 559209.0, 559525.0, 560187.0, 25338.0, 42588.57411425846, 43061.35407968946, 44023.0, 44161.0, 41120.0, 50052.74973625254, 50245.74877125591, 50247.748761255956, 51345.229821553454, 40865.79567102146, 41358.1728365425, 41376.58623413727, 42564.5743542573, 42662.93342666458, 41734.582654174286, 41793.106189326274, 41842.58157418387, 42392.788036059625, 42437.36343954905, 39541.0, 40196.39705404363, 40449.595504044584, 40481.59518404778, 40554.79722601368, 34831.651683483855, 35674.28651427009, 36591.0, 36630.8168459156, 37052.629473705994, 26408.603870941566, 26427.60358594661, 26544.0, 27063.1881043569, 27264.454710906066], "value": 21227.11076238087, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.3352298248243, 383.0825173708967, 383.8963479860438, 390.7206347461565, 409.93236116040833, 15483.168732087759, 16827.747583786237, 20718.067686954084, 21277.893610531963, 21329.25347612832, 21421.892890535564, 21736.565268694616, 21746.238881639147, 21775.0, 21790.01944912479, 4229.175310814392, 5834.241548598687, 5843.2695913010875, 6072.423119803634, 6118.11287363332, 4348.913021739563, 4351.781501179675, 4358.978205108986, 4365.869023929271, 4391.934120988188, 6438.839029024265, 6798.966005169992, 6834.350736680032, 6869.210040845317, 7840.921590784104, 7933.167017463141, 8089.31240844526, 8211.096779354297, 8221.712240071629, 8231.794205144859, 7912.683492660287, 7945.960270198612, 7952.761417157494, 7957.5225486471, 7969.242921922408, 29390.412191756044, 30016.69983300183, 30842.766289348398, 30883.536746948816, 31163.53254701181, 2401.0515846240637, 2448.204333591572, 2531.936701582457, 2535.378832186108, 2589.4562141950214, 16339.591510212222, 16702.83297167025, 17331.873428227125, 17678.46964591064, 18084.095795210185, 2021.1309137071053, 2114.534802343482, 2122.7028216049766, 2172.576347612216, 2195.2755590655206, 131562.3421882894, 133150.99999999904, 135277.0, 141409.0, 148043.0, 16809.6638067238, 22000.44998875009, 22085.380543712392, 22316.218932337615, 22417.10331586751, 36410.63589364125, 36412.81793591068, 36473.0, 36549.90350289496, 36603.0, 11016.283941543774, 11202.21584489078, 11272.154588405903, 11313.886861131283, 11317.547298108148, 8500.957495212608, 9181.035991220826, 9225.630974761069, 9319.487428191378, 9337.953310233406, 9925.972578363668, 9958.502074896227, 10089.041541053588, 10107.382818748965, 10118.190544756402, 2979.702029797003, 2987.4025194961096, 3731.402975523903, 3740.372937772078, 3840.3663395539425, 876.6668665906915, 924.8751418558543, 951.7715748220377, 975.3513913247752, 995.9253056020827, 20302.0, 21664.133434662435, 21664.35006949794, 21676.0, 21757.782422175893, 898.820235952812, 928.5589345061042, 964.320154291237, 1005.4771518810178, 1025.7281820317621, 10923.508442120125, 10968.890311096788, 11320.0, 11359.943200283946, 11463.535339901795, 597718.0, 598968.0, 603534.0, 610890.0, 611289.0, 123961.0, 128564.35717821351, 132228.0, 135580.0, 138063.0, 958.4872093430121, 978.9951050244703, 999.4253304350028, 1000.6547741029333, 1003.2626019875531, 15084.547463576111, 19113.426597202106, 19130.904345478182, 19244.84530928149, 19349.592684788724, 48598.02803943831, 49923.500764991884, 56518.43481565296, 56616.43383566112, 57480.42519574918, 81717.0, 82656.58671706603, 83952.0, 84361.0, 85093.57453212695, 8007.15924827908, 8322.708705195531, 8380.036295826058, 8476.915230847859, 8586.313094952513, 12250.509979600716, 13199.67000824987, 13200.933995330346, 13421.66445838862, 13462.990275729164, 11184.432492507769, 11400.087992960709, 11510.07919366432, 11669.066474682175, 11698.883011170119, 11238.55045798159, 11325.0, 11512.654620361403, 11616.825747613962, 11685.721284349811, 11535.077193824642, 11614.187006909435, 11740.0, 11800.763984720086, 11900.285982841056, 8309.95845020771, 8387.706430274911, 8393.748187554384, 8440.324774018187, 8553.529555874487, 8421.621027053674, 10671.35971841692, 11568.24806387582, 11661.416929153678, 11691.064714822624], "value": 11085.687295414065, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.1801160298019, 1558.0, 1759.9560010999724, 1924.6824273994791, 1938.5832046110074, 8485.745427637174, 8732.214100730933, 8941.821163576724, 8947.150020748026, 8951.507667078307, 7824.295813376795, 8074.313683336909, 8401.87397189041, 8650.783730406743, 8651.351148663844, 2315.7568455312203, 2344.4490544721975, 2346.436855154759, 2347.225415612852, 2347.284078356109, 2223.0663121489015, 2324.535092981402, 2333.6266197408418, 2368.27767530903, 2370.0756704885107, 1867.4491025147547, 2126.797954194349, 2265.3883451468096, 2326.9767302327014, 2336.310788317442, 2303.7005189325446, 2308.722953245613, 2331.4870728439714, 2337.2637619149914, 2341.7775311345395, 2340.4265954841003, 2362.7755363240462, 2364.1725396111397, 2364.2788949370433, 2371.833971621987, 8920.375573709844, 8982.236509896667, 8982.326325525546, 8985.326100542485, 8986.730398088068, 4318.82724691012, 5291.947080529184, 5351.598630102758, 5359.892802143974, 5368.248445217672, 3456.2050728332374, 3596.820158992066, 3621.6378362163837, 3669.908252293714, 3671.3391589513876, 1065.1053115383038, 1090.5801266512372, 1095.11842966412, 1097.7749561339906, 1106.5629076514813, 11790.0, 11797.469113889896, 11808.055355571534, 11810.645680629596, 11818.527258909548, 4507.932381014288, 4536.160810250114, 4750.144973904661, 4750.857474275777, 4774.474807771142, 9936.403815771077, 9948.403095814274, 9954.004599539989, 9954.30319877616, 9957.502124893726, 5334.333208348957, 5345.33183352081, 5351.839444816662, 5357.062514060024, 5358.5981051421295, 4714.222153344728, 4920.188168952082, 4930.605551555867, 4934.802607895644, 4942.283368911488, 3537.1157210697324, 3773.8679146230297, 3775.4525593789294, 3845.1732877431486, 3891.3968334908136, 942.8497233375272, 1039.740064983754, 1078.3314345106085, 1123.3372310336777, 1137.243732917617, 780.1223623423648, 802.0776107476332, 869.626060793852, 886.822635472908, 953.5518306395983, 5199.948000520022, 6005.459508644243, 6045.697715114226, 6085.604435711664, 6206.937930620727, 966.8066386722684, 973.5834361004862, 981.8527220916919, 992.3450522655013, 1027.2347101409498, 3945.0, 4078.632923036941, 4079.326911059642, 4124.298869192245, 4174.123434078817, 10584.523696433678, 10788.730281742863, 10793.622223222306, 10903.127749779998, 10903.509342079626, 9790.0, 10854.240203185858, 10854.62008829703, 10859.619913302997, 10859.945700271448, 7991.960040199761, 8362.49825010489, 8380.958095209486, 8447.141628841646, 8478.194571515696, 5947.613405128653, 6058.636481811105, 6132.846678833065, 6195.81412557624, 6304.148939893055, 9830.508474576385, 9869.11177993984, 10057.798844023226, 10107.898921010696, 10144.036316549917, 10199.184065274909, 10312.329698569569, 10324.896751032393, 10330.896691032993, 10336.328138670962, 7631.465797394351, 7634.847303053797, 7668.501547399401, 7775.339096176888, 7783.7275695352655, 8331.958340208503, 8382.413231073766, 8400.41197116196, 8447.028591711785, 8455.619497122521, 6376.872462550816, 7452.254774522505, 7536.8492630148185, 7681.0, 7701.768946931601, 7439.888401674088, 7451.962740186481, 7462.029936108272, 7559.697612095674, 7573.545587264782, 7093.255208203271, 7113.786586402416, 7147.714091436284, 7168.319009694071, 7169.139703235645, 4624.722516648877, 4985.92802547454, 5028.572571331331, 5175.938932518749, 5235.240890070842, 4955.380577427822, 4992.600591952708, 4995.425526064549, 4998.1253280676465, 5086.491350864884], "value": 4590.575419398896, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.4208645659702, 310.33742958783, 319.26887427790354, 323.85264704559427, 324.3545344763921, 4896.608271338288, 5333.333333333333, 5543.25166102576, 5557.194206840006, 5569.944300556993, 4450.955490445094, 5314.92027619586, 5416.808302173526, 5498.508447136068, 5501.4223506531835, 4204.474440694913, 4241.957580424202, 4268.530461649216, 4277.93583096253, 4323.589259020388, 4129.401236820674, 4244.0, 4258.190943720699, 4303.311470164758, 4341.891452713676, 3443.896683099511, 3489.877854275101, 4170.436991006206, 4210.6420954218775, 4238.830446782125, 4222.4799072333935, 4272.722273052257, 4283.721558098714, 4285.392977633387, 4293.48478182617, 2698.7840972722133, 3315.8891771256417, 3407.5399821024093, 3477.3566890125408, 3623.673869351771, 4649.488556258809, 5357.223202635635, 5365.987754592028, 5367.543758780508, 5399.2980912481125, 3970.7021973352116, 4777.331173635694, 4790.353302304179, 4800.95199048012, 4818.879528011793, 5064.924026139611, 5514.531264842493, 5804.5910946956965, 5842.53936515871, 5883.52931765462, 3934.291827471054, 3961.9397913085036, 3976.4830572025453, 3985.2228815381013, 3998.660113890323, 5884.117382392633, 6073.058675905242, 6228.346023667495, 6258.87482250357, 6275.905861412082, 3743.6256374362347, 4005.859794907164, 4007.719459637856, 4012.117334186474, 4035.1526179502634, 4056.492938382702, 4087.8773636790943, 4090.652294554937, 4099.651529619956, 4102.548719640837, 4728.338032675429, 4748.715077095385, 4765.142274390643, 4765.92766627513, 4770.0936822004405, 4896.57239932049, 4971.90056198874, 5550.861228469321, 5679.801206957819, 5835.153902684172, 1250.3810613746255, 1250.424804589899, 1273.891719203878, 1319.4985905356095, 1394.9790753138711, 908.3459908865606, 916.935814492979, 930.3952430919957, 932.1796818799548, 941.2470023980925, 4609.861704148881, 4882.2903288675125, 5172.637915345965, 5245.344331958505, 5295.523402893758, 5550.0, 5757.280339957505, 5810.0, 5820.603055266705, 5896.233489646319, 5173.861750414902, 5209.6239159106535, 5318.44156363584, 5341.813036543703, 5370.946290537123, 2198.9445066368235, 2207.260567709803, 2275.9388622302977, 2562.372893212786, 2641.511320405731, 4265.888385249322, 4497.572730590654, 4692.577668009895, 4854.0, 4983.8006479741125, 4614.0, 5110.386753589593, 5175.689458632494, 5604.523615492729, 5644.3508996465935, 5228.816991405359, 6136.815895523141, 6177.4749146323065, 6207.875842483216, 6239.719212635442, 5270.8945821084135, 5628.073401830943, 6058.545609079249, 6068.302145253352, 6108.267007958895, 4946.0, 5680.261565996394, 5922.0, 5929.458340831501, 5941.732622031933, 4929.975350123226, 6163.8150855474405, 6201.573638063361, 6210.223722034746, 6213.440790328711, 3360.899173024813, 3742.681872040907, 4020.8391664334267, 4041.7372870764484, 4051.2505186540584, 4464.910701785881, 5363.383210930637, 5836.824895253149, 5927.40725927421, 5971.91042134377, 5096.770645320896, 5722.513586345207, 5824.835032993334, 5835.328937172109, 5893.9115913260475, 4645.6283497319455, 5085.440601533903, 5799.333076696065, 5827.475527202572, 5858.736356863866, 4761.40482439695, 4792.760361981817, 5319.813806516753, 5721.942780572141, 5730.828075157752, 5781.248437703071, 5837.824865254049, 5870.823875283749, 5878.441548052928, 5889.823305300848, 5536.456488221941, 5661.905095114834, 5744.5011420032415, 5803.216565763568, 5873.236479257669], "value": 4151.466915747438, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.3520102309968, 738.6196109003864, 776.0842206196689, 811.2982270336161, 866.0300463480902, 14891.776623350645, 16050.83949160508, 16066.437674681389, 16070.678586428265, 16111.0, 14455.78316325256, 14627.0, 14635.048721833073, 14695.853041469582, 14758.409663613442, 681.9079424277721, 855.8801767752519, 887.2857349833381, 891.3047822698273, 891.7725979875155, 858.1418581418582, 870.4864130163203, 873.4715497124243, 873.7771868173606, 875.2429148786285, 729.6570611812466, 932.4498545857942, 933.2347475070445, 933.9673111441101, 934.4580143516778, 908.7773495493614, 934.7429456899407, 935.0415823780611, 940.6472572785206, 944.1031020530455, 701.5194591704699, 702.420503084957, 703.0, 704.1550139832221, 704.5385272646424, 14852.70294594102, 14870.959032867704, 14883.0, 14891.47879824201, 14894.627634309121, 13738.65653358664, 13791.0, 13904.443822247098, 14018.018738688295, 14037.859621403757, 12073.215241009306, 12611.684707882285, 12628.494860205581, 12948.870511294861, 13149.342532873317, 4539.795709193094, 4560.498345182027, 4562.429696287964, 4755.619550435957, 4779.976100119512, 105950.0, 106780.46609766979, 109272.4536377313, 110075.89924100737, 111995.0, 9547.2839537035, 10072.848907266396, 11200.775984480428, 11256.943715281535, 11341.489632966537, 21285.0, 21576.892115539635, 21582.244621438174, 21594.0, 21624.459388515414, 7889.17163697815, 7926.920730792734, 8001.799955001055, 8088.71689490865, 8157.306628936488, 4429.911401771947, 4483.14820184164, 4522.638188944877, 4524.434445694288, 4686.7656617169005, 3405.0465869556565, 3484.7038001770134, 3524.8942531724088, 3526.9118272043406, 3528.3648943190474, 632.7374139732052, 644.716324817079, 645.961242325462, 646.0825627608807, 653.6372313366032, 547.2693953572026, 560.5202266017717, 583.0408977232513, 626.4956709848616, 632.253940350391, 8208.753737387888, 8476.745697629081, 9024.232940200025, 9143.451392916446, 9230.353875228804, 531.2376739379004, 533.5518164741558, 572.796657186701, 586.7066466766616, 620.1999420747276, 4487.439070116236, 4658.566403033816, 4727.83918401365, 4792.113459010095, 4799.136155491975, 127747.72252277544, 130740.0, 132359.0, 150009.0, 150610.0, 56847.0, 119037.0, 120442.3977880105, 123495.0, 123794.0, 92140.53929730308, 112973.74052518903, 114257.0, 117779.2333115004, 117833.0, 9902.108810207115, 10168.644097456849, 10187.439690817077, 10188.134008609351, 10188.745281368025, 62425.751484970955, 63422.68288658527, 63722.0, 64330.391740205, 65752.67123664351, 97008.0299196999, 97716.51141744245, 99551.5067273977, 100872.0, 101477.0, 11169.776604468028, 13204.405801739136, 13375.732485350043, 13476.0, 13967.710128183437, 15075.924620376827, 15083.849161508244, 15346.386144554093, 15452.768208476657, 15828.28772705208, 13630.318484075558, 13695.246761428218, 13750.174989500661, 13803.0, 13993.760998240232, 13856.93071534636, 14176.716465670836, 14182.149071055357, 14402.783958240845, 14518.346674399467, 13266.602001939957, 13530.323483825967, 13810.240436776074, 14054.156750594997, 14297.785533217218, 10022.799544009225, 11168.329900205688, 11529.0, 11785.410729463663, 11863.644090677293, 10615.31000484966, 11326.546938122712, 11382.317060976367, 11574.263860421004, 11688.47401866901], "value": 7895.434506650307, "unit": "score"}], "name": "dromaeo_css", "value": 7665.677976869859, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 113.0, 112.0, 112.0, 111.0, 112.0, 111.0, 112.0, 111.0, 112.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 107.0, 104.0, 157.0, 105.0, 112.0, 156.0, 137.0, 171.0, 107.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 185.0, 182.0, 181.0, 185.0, 184.0, 184.0, 187.0, 194.0, 181.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 57.0, 85.0, 57.0, 58.0, 60.0, 55.0, 125.0, 80.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 111.0, 101.0, 126.0, 79.0, 95.0, 69.0, 67.0, 96.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 85.0, 87.0, 85.0, 86.0, 84.0, 88.0, 85.0, 90.0, 129.0], "value": 94.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 93.0, 93.0, 92.0, 94.0, 93.0, 93.0, 94.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 84.0, 84.0, 85.0, 84.0, 84.0, 85.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 52.0, 52.0, 55.0, 72.0, 80.0, 78.0, 52.0, 53.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 82.0, 76.0, 70.0, 69.0, 72.0, 71.0, 69.0, 70.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 84.0, 83.0, 83.0, 82.0, 82.0, 83.0, 90.0, 100.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 153.0, 146.0, 156.0, 134.0, 133.0, 131.0, 134.0, 222.0, 144.0], "value": 149.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 164.0, 165.0, 164.0, 162.0, 164.0, 164.0, 161.0, 163.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 83.0, 90.0, 83.0, 65.0, 67.0, 66.0, 66.0, 65.0, 67.0], "value": 71.2, "unit": "score"}], "name": "kraken", "value": 1460.0, "alertThreshold": 2.0}]} 11:39:16 INFO - Return code: 0 11:39:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:39:16 INFO - # TBPL SUCCESS # 11:39:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:39:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:39:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:39:16 INFO - 2016-07-18 11:39:16.153 screenresolution[1672:9748] starting screenresolution argv=screenresolution get 11:39:16 INFO - 2016-07-18 11:39:16.156 screenresolution[1672:9748] Display 0: 1600x1200x32@60 11:39:16 INFO - 2016-07-18 11:39:16.162 screenresolution[1673:9751] starting screenresolution argv=screenresolution list 11:39:16 INFO - Available Modes on Display 0 11:39:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:39:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:39:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:39:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:39:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:39:16 INFO - Intel Iris: 11:39:16 INFO - Chipset Model: Intel Iris 11:39:16 INFO - Type: GPU 11:39:16 INFO - Bus: Built-In 11:39:16 INFO - VRAM (Dynamic, Max): 1536 MB 11:39:16 INFO - Vendor: Intel (0x8086) 11:39:16 INFO - Device ID: 0x0a2e 11:39:16 INFO - Revision ID: 0x0009 11:39:16 INFO - Displays: 11:39:16 INFO - Display: 11:39:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:39:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:39:16 INFO - Display Serial Number: 1600x1200 60 11:39:16 INFO - Main Display: Yes 11:39:16 INFO - Mirror: Off 11:39:16 INFO - Online: Yes 11:39:16 INFO - Rotation: Supported 11:39:16 INFO - Return code: 0 11:39:16 INFO - Running post-action listener: _resource_record_post_action 11:39:16 INFO - ##### 11:39:16 INFO - ##### Finished run-tests step (success) 11:39:16 INFO - ##### 11:39:16 INFO - Running post-run listener: _resource_record_post_run 11:39:16 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 36632064; Write bytes: 705111040; Read time: 235; Write time: 1939 11:39:16 INFO - TinderboxPrint: CPU usage
53.4% 11:39:16 INFO - TinderboxPrint: I/O read bytes / time
36,632,064 / 235 11:39:16 INFO - TinderboxPrint: I/O write bytes / time
705,111,040 / 1,939 11:39:16 INFO - TinderboxPrint: CPU idle
2,260.4 (46.6%) 11:39:16 INFO - TinderboxPrint: CPU system
960.1 (19.8%) 11:39:16 INFO - TinderboxPrint: CPU user
1,625.2 (33.5%) 11:39:16 INFO - TinderboxPrint: Swap in / out
444,518,400 / 0 11:39:16 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392059392; Write bytes: 382687232; Read time: 28548; Write time: 809 11:39:16 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30379520; Write bytes: 293051392; Read time: 182; Write time: 1068 11:39:16 INFO - Running post-run listener: _upload_blobber_files 11:39:16 INFO - Blob upload gear active. 11:39:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:39:16 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 11:39:16 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'] 11:39:16 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 11:39:17 INFO - (blobuploader) - INFO - Open directory for files ... 11:39:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:39:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:39:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:39:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:39:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:39:18 INFO - (blobuploader) - INFO - Done attempting. 11:39:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:39:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:39:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:39:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:39:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:39:19 INFO - (blobuploader) - INFO - Done attempting. 11:39:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:39:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:39:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:39:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:39:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:39:23 INFO - (blobuploader) - INFO - Done attempting. 11:39:23 INFO - (blobuploader) - INFO - Iteration through files over. 11:39:23 INFO - Return code: 0 11:39:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:39:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:39:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f38931ad920b9663488242efcf20850969b7d6093370091189f6e3ff413be042b6c868df1bc71aa986cf95869a24e1d5f7952aa6b9523e16f9286696d83543d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93ae487b7ca0e689287894dd6d49f8242d9cd42b8b73fdc6561da824a4e1e568796434fad10380c06c2683f76a8c5a14935d434c34ae750b347932a30ba238a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fe9a1b25403f2189753e6110e823292789208149ed35b5cff5ce02a2332f7f0836443f9677a6bc677fd86a25664bf9726fc80602f28fc1dfe91efb33a84b9cc"} 11:39:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:39:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:39:23 INFO - Contents: 11:39:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f38931ad920b9663488242efcf20850969b7d6093370091189f6e3ff413be042b6c868df1bc71aa986cf95869a24e1d5f7952aa6b9523e16f9286696d83543d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93ae487b7ca0e689287894dd6d49f8242d9cd42b8b73fdc6561da824a4e1e568796434fad10380c06c2683f76a8c5a14935d434c34ae750b347932a30ba238a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fe9a1b25403f2189753e6110e823292789208149ed35b5cff5ce02a2332f7f0836443f9677a6bc677fd86a25664bf9726fc80602f28fc1dfe91efb33a84b9cc"} 11:39:23 INFO - Running post-run listener: copy_logs_to_upload_dir 11:39:23 INFO - Copying logs to upload dir... 11:39:23 INFO - mkdir: /builds/slave/test/build/upload/logs 11:39:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.660019 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-18 11:39:23.080871) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 11:39:23.084774) ========= 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.kOK3OP0IDi/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.37OOkvei1s/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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/f38931ad920b9663488242efcf20850969b7d6093370091189f6e3ff413be042b6c868df1bc71aa986cf95869a24e1d5f7952aa6b9523e16f9286696d83543d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93ae487b7ca0e689287894dd6d49f8242d9cd42b8b73fdc6561da824a4e1e568796434fad10380c06c2683f76a8c5a14935d434c34ae750b347932a30ba238a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fe9a1b25403f2189753e6110e823292789208149ed35b5cff5ce02a2332f7f0836443f9677a6bc677fd86a25664bf9726fc80602f28fc1dfe91efb33a84b9cc"} build_url:https://queue.taskcluster.net/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011200 build_url: 'https://queue.taskcluster.net/v1/task/Yf82bKNpTNKS-wfJU1qtIQ/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/f38931ad920b9663488242efcf20850969b7d6093370091189f6e3ff413be042b6c868df1bc71aa986cf95869a24e1d5f7952aa6b9523e16f9286696d83543d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93ae487b7ca0e689287894dd6d49f8242d9cd42b8b73fdc6561da824a4e1e568796434fad10380c06c2683f76a8c5a14935d434c34ae750b347932a30ba238a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fe9a1b25403f2189753e6110e823292789208149ed35b5cff5ce02a2332f7f0836443f9677a6bc677fd86a25664bf9726fc80602f28fc1dfe91efb33a84b9cc"}' ========= master_lag: 0.28 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 11:39:23.377929) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 11:39:23.378534) ========= 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.kOK3OP0IDi/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.37OOkvei1s/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.004924 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 11:39:23.428461) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 11:39:23.428775) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 11:39:23.429149) ========= ========= Total master_lag: 0.44 =========