builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0288 starttime: 1467943886.4 results: success (0) buildid: 20160707171626 builduid: d476c4c6f83f4ca48e110f6177a61747 revision: 4e3c16ea8fced03ab4ef572f16d4aa4464981e2c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 19:11:26.398636) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 19:11:26.399249) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 19:11:26.399548) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 19:11:26.422358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 19:11:26.422740) ========= 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.y7acSPNGKO/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.yKKwhjdiGW/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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-07 19:11:26-- 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% 154M=0s 2016-07-07 19:11:26 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131217 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 19:11:26.575188) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 19:11:26.575499) ========= 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.y7acSPNGKO/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.yKKwhjdiGW/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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.020355 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 19:11:26.623703) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 19:11:26.624092) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4e3c16ea8fced03ab4ef572f16d4aa4464981e2c --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 4e3c16ea8fced03ab4ef572f16d4aa4464981e2c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y7acSPNGKO/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.yKKwhjdiGW/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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-07 19:11:26,703 truncating revision to first 12 chars 2016-07-07 19:11:26,703 Setting DEBUG logging. 2016-07-07 19:11:26,703 attempt 1/10 2016-07-07 19:11:26,703 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4e3c16ea8fce?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-07 19:11:27,460 unpacking tar archive at: mozilla-central-4e3c16ea8fce/testing/mozharness/ program finished with exit code 0 elapsedTime=1.050742 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 19:11:27.695829) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 19:11:27.696164) ========= script_repo_revision: 4e3c16ea8fced03ab4ef572f16d4aa4464981e2c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 19:11:27.696543) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 19:11:27.696808) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 19:11:27.712752) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-07-07 19:11:27.713151) ========= /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.y7acSPNGKO/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.yKKwhjdiGW/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 19:11:27 INFO - MultiFileLogger online at 20160707 19:11:27 in /builds/slave/test 19:11:27 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 19:11:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:11:27 INFO - {'append_to_log': False, 19:11:27 INFO - 'base_work_dir': '/builds/slave/test', 19:11:27 INFO - 'blob_upload_branch': 'Firefox', 19:11:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:11:27 INFO - 'branch': 'Firefox', 19:11:27 INFO - 'buildbot_json_path': 'buildprops.json', 19:11:27 INFO - 'config_files': ('talos/mac_config.py',), 19:11:27 INFO - 'default_actions': ('clobber', 19:11:27 INFO - 'read-buildbot-config', 19:11:27 INFO - 'download-and-extract', 19:11:27 INFO - 'populate-webroot', 19:11:27 INFO - 'create-virtualenv', 19:11:27 INFO - 'install', 19:11:27 INFO - 'run-tests'), 19:11:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:11:27 INFO - 'download_minidump_stackwalk': True, 19:11:27 INFO - 'download_symbols': 'ondemand', 19:11:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:11:27 INFO - 'tooltool.py': '/tools/tooltool.py', 19:11:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:11:27 INFO - '/tools/misc-python/virtualenv.py')}, 19:11:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:11:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:11:27 INFO - 'installer_path': 'installer.exe', 19:11:27 INFO - 'log_level': 'info', 19:11:27 INFO - 'log_name': 'talos', 19:11:27 INFO - 'log_to_console': True, 19:11:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:11:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:11:27 INFO - 'opt_config_files': (), 19:11:27 INFO - 'pip_index': False, 19:11:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:11:27 INFO - 'cmd': ('bash', 19:11:27 INFO - '-c', 19:11:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:11:27 INFO - 'enabled': True, 19:11:27 INFO - 'halt_on_failure': False, 19:11:27 INFO - 'name': 'check_screen_resolution'},), 19:11:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:11:27 INFO - 'cmd': ('bash', 19:11:27 INFO - '-c', 19:11:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:11:27 INFO - 'enabled': True, 19:11:27 INFO - 'halt_on_failure': False, 19:11:27 INFO - 'name': 'check_screen_resolution'},), 19:11:27 INFO - 'run_cmd_checks_enabled': True, 19:11:27 INFO - 'sps_profile': False, 19:11:27 INFO - 'sps_profile_interval': 0, 19:11:27 INFO - 'suite': 'dromaeojs', 19:11:27 INFO - 'system_bits': '32', 19:11:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:11:27 INFO - 'title': 't-yosemite-r7-0288', 19:11:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:11:27 INFO - 'use_talos_json': True, 19:11:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:11:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:11:27 INFO - 'work_dir': 'build'} 19:11:27 INFO - ##### 19:11:27 INFO - ##### Running clobber step. 19:11:27 INFO - ##### 19:11:27 INFO - Running pre-action listener: _resource_record_pre_action 19:11:27 INFO - Running main action method: clobber 19:11:27 INFO - rmtree: /builds/slave/test/build 19:11:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:11:28 INFO - Running post-action listener: _resource_record_post_action 19:11:28 INFO - ##### 19:11:28 INFO - ##### Finished clobber step (success) 19:11:28 INFO - ##### 19:11:28 INFO - ##### 19:11:28 INFO - ##### Running read-buildbot-config step. 19:11:28 INFO - ##### 19:11:28 INFO - Running pre-action listener: _resource_record_pre_action 19:11:28 INFO - Running main action method: read_buildbot_config 19:11:28 INFO - Using buildbot properties: 19:11:28 INFO - { 19:11:28 INFO - "project": "", 19:11:28 INFO - "product": "firefox", 19:11:28 INFO - "script_repo_revision": "production", 19:11:28 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:11:28 INFO - "repository": "", 19:11:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:11:28 INFO - "buildid": "20160707171626", 19:11:28 INFO - "pgo_build": "False", 19:11:28 INFO - "basedir": "/builds/slave/test", 19:11:28 INFO - "buildnumber": 6, 19:11:28 INFO - "slavename": "t-yosemite-r7-0288", 19:11:28 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 19:11:28 INFO - "platform": "yosemite_r7", 19:11:28 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:11:28 INFO - "branch": "mozilla-central", 19:11:28 INFO - "repo_path": "mozilla-central", 19:11:28 INFO - "revision": "4e3c16ea8fced03ab4ef572f16d4aa4464981e2c", 19:11:28 INFO - "stage_platform": "macosx64", 19:11:28 INFO - "builduid": "d476c4c6f83f4ca48e110f6177a61747", 19:11:28 INFO - "slavebuilddir": "test" 19:11:28 INFO - } 19:11:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 19:11:28 INFO - Running post-action listener: _resource_record_post_action 19:11:28 INFO - ##### 19:11:28 INFO - ##### Finished read-buildbot-config step (success) 19:11:28 INFO - ##### 19:11:28 INFO - ##### 19:11:28 INFO - ##### Running download-and-extract step. 19:11:28 INFO - ##### 19:11:28 INFO - Running pre-action listener: _resource_record_pre_action 19:11:28 INFO - Running main action method: download_and_extract 19:11:28 INFO - mkdir: /builds/slave/test/build/tests 19:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:11:28 INFO - https://queue.taskcluster.net/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:11:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 19:11:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 19:11:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 19:11:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 19:11:29 INFO - Downloaded 1199 bytes. 19:11:29 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 19:11:29 INFO - Using the following test package requirements: 19:11:29 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 19:11:29 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:11:29 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 19:11:29 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:11:29 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 19:11:29 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:11:29 INFO - u'jsshell-mac.zip'], 19:11:29 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:11:29 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 19:11:29 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 19:11:29 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:11:29 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 19:11:29 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:11:29 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 19:11:29 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:11:29 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 19:11:29 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:11:29 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 19:11:29 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:11: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')]} 19:11:29 INFO - https://queue.taskcluster.net/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:11:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:11:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:11:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:11:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:11:30 INFO - Downloaded 18806269 bytes. 19:11:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:11:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:11:33 INFO - Return code: 0 19:11:33 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:11:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:11:33 INFO - https://queue.taskcluster.net/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:11:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:11:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:11:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:11:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:11:35 INFO - Downloaded 18806269 bytes. 19:11:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:11:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:11:39 INFO - Return code: 0 19:11: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')]} 19:11:39 INFO - https://queue.taskcluster.net/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:11:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 19:11:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 19:11:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 19:11:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:11:40 INFO - Downloaded 14403333 bytes. 19:11:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:11:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:11:40 INFO - Return code: 0 19:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:11:40 INFO - https://queue.taskcluster.net/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:11:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:11:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:11:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 19:11:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 19:11:43 INFO - Downloaded 112446031 bytes. 19:11:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:11:43 INFO - mkdir: /builds/slave/test/properties 19:11:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:11:43 INFO - Writing to file /builds/slave/test/properties/build_url 19:11:43 INFO - Contents: 19:11:43 INFO - build_url:https://queue.taskcluster.net/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:11:44 INFO - Running post-action listener: _resource_record_post_action 19:11:44 INFO - Running post-action listener: set_extra_try_arguments 19:11:44 INFO - ##### 19:11:44 INFO - ##### Finished download-and-extract step (success) 19:11:44 INFO - ##### 19:11:44 INFO - ##### 19:11:44 INFO - ##### Running populate-webroot step. 19:11:44 INFO - ##### 19:11:44 INFO - Running pre-action listener: _resource_record_pre_action 19:11:44 INFO - Running main action method: populate_webroot 19:11:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:11:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:11:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:11:44 INFO - '--tppagecycles', 19:11:44 INFO - '7'], 19:11:44 INFO - 'tests': ['tsvgm']}}, 19:11:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:11:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:11:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:11:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:11:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:11:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:11:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:44 INFO - 'talos_options': ['--disable-e10s'], 19:11:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:11:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:11:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:44 INFO - 'talos_options': ['--disable-e10s'], 19:11:44 INFO - 'tests': ['damp', 'tps']}, 19:11:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:44 INFO - 'tests': ['damp', 'tps']}, 19:11:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:11:44 INFO - 'tests': ['dromaeo_dom']}, 19:11:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:11:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:11:44 INFO - 'tests': ['basic_compositor_video']}, 19:11:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:11:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:11:44 INFO - 'tests': ['a11yr', 19:11:44 INFO - 'ts_paint', 19:11:44 INFO - 'tpaint', 19:11:44 INFO - 'sessionrestore', 19:11:44 INFO - 'sessionrestore_no_auto_restore', 19:11:44 INFO - 'tabpaint']}, 19:11:44 INFO - 'other-e10s': {'tests': ['a11yr', 19:11:44 INFO - 'ts_paint', 19:11:44 INFO - 'tpaint', 19:11:44 INFO - 'sessionrestore', 19:11:44 INFO - 'sessionrestore_no_auto_restore', 19:11:44 INFO - 'tabpaint']}, 19:11:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:11:44 INFO - 'ts_paint', 19:11:44 INFO - 'tpaint', 19:11:44 INFO - 'sessionrestore', 19:11:44 INFO - 'sessionrestore_no_auto_restore', 19:11:44 INFO - 'tabpaint']}, 19:11:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:11:44 INFO - 'ts_paint', 19:11:44 INFO - 'tpaint', 19:11:44 INFO - 'sessionrestore', 19:11:44 INFO - 'sessionrestore_no_auto_restore', 19:11:44 INFO - 'tabpaint']}, 19:11:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:11:44 INFO - 'tests': ['a11yr', 19:11:44 INFO - 'ts_paint', 19:11:44 INFO - 'tpaint', 19:11:44 INFO - 'sessionrestore', 19:11:44 INFO - 'sessionrestore_no_auto_restore', 19:11:44 INFO - 'tabpaint']}, 19:11:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:11:44 INFO - 'tests': ['a11yr', 19:11:44 INFO - 'ts_paint', 19:11:44 INFO - 'tpaint', 19:11:44 INFO - 'sessionrestore', 19:11:44 INFO - 'sessionrestore_no_auto_restore', 19:11:44 INFO - 'tabpaint']}, 19:11:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:11:44 INFO - 'tests': ['tsvgx', 19:11:44 INFO - 'tsvgr_opacity', 19:11:44 INFO - 'tart', 19:11:44 INFO - 'tscrollx', 19:11:44 INFO - 'cart']}, 19:11:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:11:44 INFO - 'tsvgr_opacity', 19:11:44 INFO - 'tart', 19:11:44 INFO - 'tscrollx', 19:11:44 INFO - 'cart']}, 19:11:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:44 INFO - 'talos_options': ['--disable-e10s'], 19:11:44 INFO - 'tests': ['tp5o']}, 19:11:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:44 INFO - 'tests': ['tp5o']}, 19:11:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:44 INFO - 'talos_options': ['--disable-e10s', 19:11:44 INFO - '--xperf_path', 19:11:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:11:44 INFO - 'tests': ['tp5n']}, 19:11:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:44 INFO - 'talos_options': ['--xperf_path', 19:11:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:11:44 INFO - 'tests': ['tp5n']}}, 19:11:44 INFO - 'talos.zip': {'path': '', 19:11:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:11:44 INFO - Running post-action listener: _resource_record_post_action 19:11:44 INFO - ##### 19:11:44 INFO - ##### Finished populate-webroot step (success) 19:11:44 INFO - ##### 19:11:44 INFO - ##### 19:11:44 INFO - ##### Running create-virtualenv step. 19:11:44 INFO - ##### 19:11:44 INFO - Running pre-action listener: _resource_record_pre_action 19:11:44 INFO - Running main action method: create_virtualenv 19:11:44 INFO - Creating virtualenv /builds/slave/test/build/venv 19:11:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:11:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:11:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:11:44 INFO - Using real prefix '/tools/python27' 19:11:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:11:45 INFO - Installing distribute.............................................................................................................................................................................................done. 19:11:47 INFO - Installing pip.................done. 19:11:47 INFO - Return code: 0 19:11:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:11:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:47 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 0x103542a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba15dddd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034a6d30>, '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 0x10320bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10349eb20>, '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.y7acSPNGKO/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.yKKwhjdiGW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:11:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:11:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y7acSPNGKO/Render', 19:11:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:47 INFO - 'HOME': '/Users/cltbld', 19:11:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:47 INFO - 'LOGNAME': 'cltbld', 19:11:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:47 INFO - 'MOZ_NO_REMOTE': '1', 19:11:47 INFO - 'NO_EM_RESTART': '1', 19:11:47 INFO - 'PAGER': '/bin/cat', 19:11:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:47 INFO - 'PWD': '/builds/slave/test', 19:11:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:47 INFO - 'SHELL': '/bin/bash', 19:11:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKKwhjdiGW/Listeners', 19:11:47 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 19:11:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:47 INFO - 'USER': 'cltbld', 19:11:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:47 INFO - 'XPC_FLAGS': '0x0', 19:11:47 INFO - 'XPC_SERVICE_NAME': '0', 19:11:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:11:48 INFO - Cleaning up... 19:11:48 INFO - Return code: 0 19:11:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:11: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')]} 19:11:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11: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')]} 19:11:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11: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', '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 0x103542a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba15dddd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034a6d30>, '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 0x10320bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10349eb20>, '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.y7acSPNGKO/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.yKKwhjdiGW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:11: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', 'psutil>=3.1.1'] in /builds/slave/test/build 19:11: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 psutil>=3.1.1 19:11:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y7acSPNGKO/Render', 19:11:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:48 INFO - 'HOME': '/Users/cltbld', 19:11:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:48 INFO - 'LOGNAME': 'cltbld', 19:11:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:48 INFO - 'MOZ_NO_REMOTE': '1', 19:11:48 INFO - 'NO_EM_RESTART': '1', 19:11:48 INFO - 'PAGER': '/bin/cat', 19:11:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:48 INFO - 'PWD': '/builds/slave/test', 19:11:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:48 INFO - 'SHELL': '/bin/bash', 19:11:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKKwhjdiGW/Listeners', 19:11:48 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 19:11:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:48 INFO - 'USER': 'cltbld', 19:11:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:48 INFO - 'XPC_FLAGS': '0x0', 19:11:48 INFO - 'XPC_SERVICE_NAME': '0', 19:11:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:48 INFO - Downloading/unpacking psutil>=3.1.1 19:11: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 19:11: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 19:11: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 19:11: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 19:11: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 19:11: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 19:11:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:11:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:11:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:11:51 INFO - Installing collected packages: psutil 19:11:51 INFO - Running setup.py install for psutil 19:11:51 INFO - building 'psutil._psutil_osx' extension 19:11:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:11:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:11:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:11:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:11:52 INFO - building 'psutil._psutil_posix' extension 19:11:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 19:11:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:11:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:11:52 INFO - ^ 19:11:52 INFO - 1 warning generated. 19:11:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 19:11:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:11:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:11:52 INFO - Successfully installed psutil 19:11:52 INFO - Cleaning up... 19:11:52 INFO - Return code: 0 19:11:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:11:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:52 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 0x103542a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba15dddd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034a6d30>, '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 0x10320bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10349eb20>, '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.y7acSPNGKO/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.yKKwhjdiGW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:11:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:11:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y7acSPNGKO/Render', 19:11:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:52 INFO - 'HOME': '/Users/cltbld', 19:11:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:52 INFO - 'LOGNAME': 'cltbld', 19:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:52 INFO - 'MOZ_NO_REMOTE': '1', 19:11:52 INFO - 'NO_EM_RESTART': '1', 19:11:52 INFO - 'PAGER': '/bin/cat', 19:11:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:52 INFO - 'PWD': '/builds/slave/test', 19:11:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:52 INFO - 'SHELL': '/bin/bash', 19:11:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKKwhjdiGW/Listeners', 19:11:52 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 19:11:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:52 INFO - 'USER': 'cltbld', 19:11:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:52 INFO - 'XPC_FLAGS': '0x0', 19:11:52 INFO - 'XPC_SERVICE_NAME': '0', 19:11:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:11: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 19:11: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 19:11: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 19:11: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 19:11: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 19:11: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 19:11:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:11:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:11:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:11:55 INFO - Installing collected packages: mozsystemmonitor 19:11:55 INFO - Running setup.py install for mozsystemmonitor 19:11:55 INFO - Successfully installed mozsystemmonitor 19:11:55 INFO - Cleaning up... 19:11:55 INFO - Return code: 0 19:11:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103542a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba15dddd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034a6d30>, '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 0x10320bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10349eb20>, '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.y7acSPNGKO/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.yKKwhjdiGW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:11:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:11:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:11:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y7acSPNGKO/Render', 19:11:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:55 INFO - 'HOME': '/Users/cltbld', 19:11:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:55 INFO - 'LOGNAME': 'cltbld', 19:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:55 INFO - 'MOZ_NO_REMOTE': '1', 19:11:55 INFO - 'NO_EM_RESTART': '1', 19:11:55 INFO - 'PAGER': '/bin/cat', 19:11:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:55 INFO - 'PWD': '/builds/slave/test', 19:11:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:55 INFO - 'SHELL': '/bin/bash', 19:11:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKKwhjdiGW/Listeners', 19:11:55 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 19:11:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:55 INFO - 'USER': 'cltbld', 19:11:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:55 INFO - 'XPC_FLAGS': '0x0', 19:11:55 INFO - 'XPC_SERVICE_NAME': '0', 19:11:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:55 INFO - Downloading/unpacking jsonschema==2.5.1 19:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:11:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:58 INFO - Downloading functools32-3.2.3-2.tar.gz 19:11:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:11:58 INFO - warning: no files found matching '*.txt' 19:11:58 INFO - no previously-included directories found matching 'build' 19:11:58 INFO - no previously-included directories found matching 'dist' 19:11:58 INFO - no previously-included directories found matching '.git*' 19:11:58 INFO - Installing collected packages: jsonschema, functools32 19:11:58 INFO - Running setup.py install for functools32 19:11:58 INFO - warning: no files found matching '*.txt' 19:11:58 INFO - no previously-included directories found matching 'build' 19:11:58 INFO - no previously-included directories found matching 'dist' 19:11:58 INFO - no previously-included directories found matching '.git*' 19:11:58 INFO - Successfully installed jsonschema functools32 19:11:58 INFO - Cleaning up... 19:11:58 INFO - Return code: 0 19:11:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:11:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:11:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103542a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba15dddd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034a6d30>, '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 0x10320bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10349eb20>, '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.y7acSPNGKO/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.yKKwhjdiGW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:11:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:11:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:11:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y7acSPNGKO/Render', 19:11:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:11:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:11:58 INFO - 'HOME': '/Users/cltbld', 19:11:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:11:58 INFO - 'LOGNAME': 'cltbld', 19:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:58 INFO - 'MOZ_NO_REMOTE': '1', 19:11:58 INFO - 'NO_EM_RESTART': '1', 19:11:58 INFO - 'PAGER': '/bin/cat', 19:11:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:58 INFO - 'PWD': '/builds/slave/test', 19:11:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:11:58 INFO - 'SHELL': '/bin/bash', 19:11:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKKwhjdiGW/Listeners', 19:11:58 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 19:11:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:11:58 INFO - 'USER': 'cltbld', 19:11:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:11:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:58 INFO - 'XPC_FLAGS': '0x0', 19:11:58 INFO - 'XPC_SERVICE_NAME': '0', 19:11:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:59 INFO - Downloading/unpacking blobuploader==1.2.4 19:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:01 INFO - Downloading blobuploader-1.2.4.tar.gz 19:12:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:12:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:12:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:02 INFO - Downloading docopt-0.6.1.tar.gz 19:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:12:02 INFO - Installing collected packages: blobuploader, requests, docopt 19:12:02 INFO - Running setup.py install for blobuploader 19:12:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:12:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:12:02 INFO - Running setup.py install for requests 19:12:03 INFO - Running setup.py install for docopt 19:12:03 INFO - Successfully installed blobuploader requests docopt 19:12:03 INFO - Cleaning up... 19:12:03 INFO - Return code: 0 19:12:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12: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')]} 19:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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')]} 19:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:03 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 0x103542a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba15dddd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034a6d30>, '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 0x10320bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10349eb20>, '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.y7acSPNGKO/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.yKKwhjdiGW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:12:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y7acSPNGKO/Render', 19:12:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:03 INFO - 'HOME': '/Users/cltbld', 19:12:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:03 INFO - 'LOGNAME': 'cltbld', 19:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:03 INFO - 'MOZ_NO_REMOTE': '1', 19:12:03 INFO - 'NO_EM_RESTART': '1', 19:12:03 INFO - 'PAGER': '/bin/cat', 19:12:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:03 INFO - 'PWD': '/builds/slave/test', 19:12:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:03 INFO - 'SHELL': '/bin/bash', 19:12:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKKwhjdiGW/Listeners', 19:12:03 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 19:12:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:03 INFO - 'USER': 'cltbld', 19:12:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:03 INFO - 'XPC_FLAGS': '0x0', 19:12:03 INFO - 'XPC_SERVICE_NAME': '0', 19:12:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:12:03 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-iCWAEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:12:04 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-p14UuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:12:04 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-43yE1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:12:04 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-k5IQp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:12:04 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-W9yFyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:12:04 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-BxPZso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:12:04 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-O7VEyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:12:04 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-OPISN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:12:04 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-K230Vo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:12:04 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-1K36ot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:12:05 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-4My5C7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:12:05 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-NkbHX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:12:05 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-7nzjps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:12:05 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-vuRnMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:05 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-FkY5dA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:12:05 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-VNTR50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:12:05 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-C4d6L7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:12:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:12:05 INFO - Running setup.py install for manifestparser 19:12:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:12:05 INFO - Running setup.py install for mozcrash 19:12:06 INFO - Running setup.py install for mozdebug 19:12:06 INFO - Running setup.py install for mozdevice 19:12:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:12:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:12:06 INFO - Running setup.py install for mozfile 19:12:06 INFO - Running setup.py install for mozhttpd 19:12:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:12:06 INFO - Running setup.py install for mozinfo 19:12:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:12:06 INFO - Running setup.py install for mozInstall 19:12:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:12:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:12:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:12:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:12:06 INFO - Running setup.py install for mozleak 19:12:07 INFO - Running setup.py install for mozlog 19:12:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:12:07 INFO - Running setup.py install for moznetwork 19:12:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:12:07 INFO - Running setup.py install for mozprocess 19:12:07 INFO - Running setup.py install for mozprofile 19:12:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:12:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:12:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:12:07 INFO - Running setup.py install for mozrunner 19:12:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:12:07 INFO - Running setup.py install for mozscreenshot 19:12:08 INFO - Running setup.py install for moztest 19:12:08 INFO - Running setup.py install for mozversion 19:12:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:12:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:12:08 INFO - Cleaning up... 19:12:08 INFO - Return code: 0 19:12:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:08 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 0x103542a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba15dddd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034a6d30>, '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 0x10320bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10349eb20>, '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.y7acSPNGKO/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.yKKwhjdiGW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:12:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:12:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y7acSPNGKO/Render', 19:12:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:08 INFO - 'HOME': '/Users/cltbld', 19:12:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:08 INFO - 'LOGNAME': 'cltbld', 19:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:08 INFO - 'MOZ_NO_REMOTE': '1', 19:12:08 INFO - 'NO_EM_RESTART': '1', 19:12:08 INFO - 'PAGER': '/bin/cat', 19:12:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:08 INFO - 'PWD': '/builds/slave/test', 19:12:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:08 INFO - 'SHELL': '/bin/bash', 19:12:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKKwhjdiGW/Listeners', 19:12:08 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 19:12:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:08 INFO - 'USER': 'cltbld', 19:12:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:08 INFO - 'XPC_FLAGS': '0x0', 19:12:08 INFO - 'XPC_SERVICE_NAME': '0', 19:12:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:12:08 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-hq6SQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:12:08 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-9hsI4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:12:08 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-vWS9Zr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:12:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:12:09 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-O3Qv0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:12:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:12:09 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-p1pKVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:12:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:12:09 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-azZBmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:12:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:12:09 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-hIv4G8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:12:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:12:09 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-2fP99c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:12:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:12:09 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-gvC3aZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:12:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:12:09 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-umS9sQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:12:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:12:09 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-RN_JIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:12:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:12:09 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-GAKV4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:12:10 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)) 19:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:12:10 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-Dv2hNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:12:10 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-2oRMx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:12:10 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)) 19:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:10 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-r2CP5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:12:10 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-xG2vmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:12:10 INFO - Running setup.py (path:/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/pip-buBn4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:12:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:12:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:12 INFO - Downloading blessings-1.6.tar.gz 19:12:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:12:13 INFO - Installing collected packages: blessings 19:12:13 INFO - Running setup.py install for blessings 19:12:13 INFO - Successfully installed blessings 19:12:13 INFO - Cleaning up... 19:12:13 INFO - Return code: 0 19:12:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:12:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:12:13 INFO - Reading from file tmpfile_stdout 19:12:13 INFO - Current package versions: 19:12:13 INFO - blessings == 1.6 19:12:13 INFO - blobuploader == 1.2.4 19:12:13 INFO - docopt == 0.6.1 19:12:13 INFO - functools32 == 3.2.3-2 19:12:13 INFO - jsonschema == 2.5.1 19:12:13 INFO - manifestparser == 1.1 19:12:13 INFO - mozInstall == 1.12 19:12:13 INFO - mozcrash == 0.17 19:12:13 INFO - mozdebug == 0.1 19:12:13 INFO - mozdevice == 0.48 19:12:13 INFO - mozfile == 1.2 19:12:13 INFO - mozhttpd == 0.7 19:12:13 INFO - mozinfo == 0.9 19:12:13 INFO - mozleak == 0.1 19:12:13 INFO - mozlog == 3.2 19:12:13 INFO - moznetwork == 0.27 19:12:13 INFO - mozprocess == 0.23 19:12:13 INFO - mozprofile == 0.28 19:12:13 INFO - mozrunner == 6.12 19:12:13 INFO - mozscreenshot == 0.1 19:12:13 INFO - mozsystemmonitor == 0.3 19:12:13 INFO - moztest == 0.7 19:12:13 INFO - mozversion == 1.4 19:12:13 INFO - psutil == 3.1.1 19:12:13 INFO - requests == 1.2.3 19:12:13 INFO - wsgiref == 0.1.2 19:12:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:13 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 0x103542a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba15dddd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034a6d30>, '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 0x10320bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10349eb20>, '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.y7acSPNGKO/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.yKKwhjdiGW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:12:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:12:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y7acSPNGKO/Render', 19:12:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:13 INFO - 'HOME': '/Users/cltbld', 19:12:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:13 INFO - 'LOGNAME': 'cltbld', 19:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:13 INFO - 'MOZ_NO_REMOTE': '1', 19:12:13 INFO - 'NO_EM_RESTART': '1', 19:12:13 INFO - 'PAGER': '/bin/cat', 19:12:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:13 INFO - 'PWD': '/builds/slave/test', 19:12:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:13 INFO - 'SHELL': '/bin/bash', 19:12:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKKwhjdiGW/Listeners', 19:12:13 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 19:12:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:13 INFO - 'USER': 'cltbld', 19:12:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:13 INFO - 'XPC_FLAGS': '0x0', 19:12:13 INFO - 'XPC_SERVICE_NAME': '0', 19:12:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:12:13 INFO - Cleaning up... 19:12:13 INFO - Return code: 0 19:12:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:13 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 0x103542a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035d7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ba15dddd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034a6d30>, '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 0x10320bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10349eb20>, '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.y7acSPNGKO/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.yKKwhjdiGW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:12:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:12:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y7acSPNGKO/Render', 19:12:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:13 INFO - 'HOME': '/Users/cltbld', 19:12:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:13 INFO - 'LOGNAME': 'cltbld', 19:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:13 INFO - 'MOZ_NO_REMOTE': '1', 19:12:13 INFO - 'NO_EM_RESTART': '1', 19:12:13 INFO - 'PAGER': '/bin/cat', 19:12:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:13 INFO - 'PWD': '/builds/slave/test', 19:12:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:13 INFO - 'SHELL': '/bin/bash', 19:12:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKKwhjdiGW/Listeners', 19:12:13 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 19:12:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:13 INFO - 'USER': 'cltbld', 19:12:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:13 INFO - 'XPC_FLAGS': '0x0', 19:12:13 INFO - 'XPC_SERVICE_NAME': '0', 19:12:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:12:14 INFO - Cleaning up... 19:12:14 INFO - Return code: 0 19:12:14 INFO - Running post-action listener: _resource_record_post_action 19:12:14 INFO - Running post-action listener: _start_resource_monitoring 19:12:14 INFO - Starting resource monitoring. 19:12:14 INFO - ##### 19:12:14 INFO - ##### Finished create-virtualenv step (success) 19:12:14 INFO - ##### 19:12:14 INFO - ##### 19:12:14 INFO - ##### Running install step. 19:12:14 INFO - ##### 19:12:14 INFO - Running pre-action listener: _resource_record_pre_action 19:12:14 INFO - Running main action method: install 19:12:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:12:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:12:14 INFO - Reading from file tmpfile_stdout 19:12:14 INFO - Detecting whether we're running mozinstall >=1.0... 19:12:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:12:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:12:14 INFO - Reading from file tmpfile_stdout 19:12:14 INFO - Output received: 19:12:14 INFO - Usage: mozinstall [options] installer 19:12:14 INFO - Options: 19:12:14 INFO - -h, --help show this help message and exit 19:12:14 INFO - -d DEST, --destination=DEST 19:12:14 INFO - Directory to install application into. [default: 19:12:14 INFO - "/builds/slave/test"] 19:12:14 INFO - --app=APP Application being installed. [default: firefox] 19:12:14 INFO - mkdir: /builds/slave/test/build/application 19:12:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:12:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:12:48 INFO - Reading from file tmpfile_stdout 19:12:48 INFO - Output received: 19:12:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:12:48 INFO - Running post-action listener: _resource_record_post_action 19:12:48 INFO - ##### 19:12:48 INFO - ##### Finished install step (success) 19:12:48 INFO - ##### 19:12:48 INFO - ##### 19:12:48 INFO - ##### Running run-tests step. 19:12:48 INFO - ##### 19:12:48 INFO - Running pre-action listener: _resource_record_pre_action 19:12:48 INFO - Running main action method: run_tests 19:12:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:12:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:12:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:12:48 INFO - 2016-07-07 19:12:48.877 screenresolution[1909:8898] starting screenresolution argv=screenresolution get 19:12:48 INFO - 2016-07-07 19:12:48.880 screenresolution[1909:8898] Display 0: 1600x1200x32@60 19:12:48 INFO - 2016-07-07 19:12:48.886 screenresolution[1910:8900] starting screenresolution argv=screenresolution list 19:12:48 INFO - Available Modes on Display 0 19:12:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:12:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:12:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:12:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:12:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:12:49 INFO - Intel Iris: 19:12:49 INFO - Chipset Model: Intel Iris 19:12:49 INFO - Type: GPU 19:12:49 INFO - Bus: Built-In 19:12:49 INFO - VRAM (Dynamic, Max): 1536 MB 19:12:49 INFO - Vendor: Intel (0x8086) 19:12:49 INFO - Device ID: 0x0a2e 19:12:49 INFO - Revision ID: 0x0009 19:12:49 INFO - Displays: 19:12:49 INFO - Display: 19:12:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:12:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:12:49 INFO - Display Serial Number: 1600x1200 60 19:12:49 INFO - Main Display: Yes 19:12:49 INFO - Mirror: Off 19:12:49 INFO - Online: Yes 19:12:49 INFO - Rotation: Supported 19:12:49 INFO - Return code: 0 19:12:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:12:49 INFO - Python 2.7.3 19:12:49 INFO - Return code: 0 19:12:49 INFO - grabbing minidump binary from tooltool 19:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:49 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 0x1034a6d30>, '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 0x10320bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10349eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:12:49 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:12:49 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 19:12:49 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:12:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:12:49 INFO - Return code: 0 19:12:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:12:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:12:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:12:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:12:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:12:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0288', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0288 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:12:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y7acSPNGKO/Render', 19:12:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:12:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:12:49 INFO - 'HOME': '/Users/cltbld', 19:12:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:12:49 INFO - 'LOGNAME': 'cltbld', 19:12:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:12:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:49 INFO - 'MOZ_NO_REMOTE': '1', 19:12:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:12:49 INFO - 'NO_EM_RESTART': '1', 19:12:49 INFO - 'PAGER': '/bin/cat', 19:12:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:49 INFO - 'PWD': '/builds/slave/test', 19:12:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:12:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:12:49 INFO - 'SHELL': '/bin/bash', 19:12:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKKwhjdiGW/Listeners', 19:12:49 INFO - 'TMPDIR': '/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/', 19:12:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:12:49 INFO - 'USER': 'cltbld', 19:12:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:12:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:49 INFO - 'XPC_FLAGS': '0x0', 19:12:49 INFO - 'XPC_SERVICE_NAME': '0', 19:12:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:12:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0288', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:12:49 INFO - mozversion application_buildid: 20160707171626 19:12:49 INFO - mozversion application_changeset: 4e3c16ea8fced03ab4ef572f16d4aa4464981e2c 19:12:49 INFO - mozversion application_display_name: Nightly 19:12:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:12:49 INFO - mozversion application_name: Firefox 19:12:49 INFO - mozversion application_remotingname: firefox 19:12:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:12:49 INFO - mozversion application_vendor: Mozilla 19:12:49 INFO - mozversion application_version: 50.0a1 19:12:49 INFO - mozversion platform_buildid: 20160707171626 19:12:49 INFO - mozversion platform_changeset: 4e3c16ea8fced03ab4ef572f16d4aa4464981e2c 19:12:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:12:49 INFO - mozversion platform_version: 50.0a1 19:12:49 INFO - using testdate: 1467943969 19:12:49 INFO - actual date: 1467943969 19:12:49 INFO - starting webserver on 'localhost:49432' 19:12:49 INFO - SUITE-START | Running 2 tests 19:12:49 INFO - TEST-START | dromaeo_css 19:12:49 INFO - operating with platform_type : mac_ 19:12:49 INFO - Initialising browser for dromaeo_css test... 19:12:49 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpNlB2lH/profile http://localhost:49432/getInfo.html) 19:12:51 INFO - PROCESS | 1920 | __metrics Screen width/height:1600/1200 19:12:51 INFO - PROCESS | 1920 | colorDepth:24 19:12:51 INFO - PROCESS | 1920 | Browser inner width/height: 1024/689 19:12:51 INFO - PROCESS | 1920 | __metrics 19:12:51 INFO - PROCESS | 1920 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:12:51 INFO - PROCESS | 1920 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:12:52 INFO - TEST-INFO | 1920: exit 0 19:12:52 INFO - Browser initialized. 19:12:52 INFO - Running cycle 1/1 for dromaeo_css test... 19:12:52 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpNlB2lH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:13:04 INFO - PROCESS | 1922 | RSS: Main: 243699712 19:13:04 INFO - PROCESS | 1922 | 19:13:56 INFO - PROCESS | 1922 | 1467944036163 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:16:06 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49432/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49432/tests/dromaeo/cssquery-ext.html) 19:16:07 INFO - PROCESS | 1922 | RSS: Main: 350642176 19:16:07 INFO - PROCESS | 1922 | 19:19:10 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49432/tests/dromaeo/cssquery-ext.html (next: http://localhost:49432/tests/dromaeo/cssquery-jquery.html) 19:19:10 INFO - PROCESS | 1922 | RSS: Main: 382713856 19:19:10 INFO - PROCESS | 1922 | 19:22:13 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49432/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49432/tests/dromaeo/cssquery-mootools.html) 19:22:13 INFO - PROCESS | 1922 | RSS: Main: 369766400 19:22:13 INFO - PROCESS | 1922 | 19:25:16 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49432/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49432/tests/dromaeo/cssquery-prototype.html) 19:25:16 INFO - PROCESS | 1922 | RSS: Main: 369692672 19:25:16 INFO - PROCESS | 1922 | 19:28:20 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49432/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49432/tests/dromaeo/cssquery-yui.html) 19:28:20 INFO - PROCESS | 1922 | RSS: Main: 333406208 19:28:20 INFO - PROCESS | 1922 | 19:31:23 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:49432/tests/dromaeo/cssquery-yui.html (next: http://localhost:49432/tests/dromaeo/cssquery-dojo.html) 19:31:24 INFO - PROCESS | 1922 | RSS: Main: 403423232 19:31:24 INFO - PROCESS | 1922 | 19:31:24 INFO - PROCESS | 1922 | __start_tp_report 19:31:24 INFO - PROCESS | 1922 | _x_x_mozilla_page_load 19:31:24 INFO - PROCESS | 1922 | _x_x_mozilla_page_load_details 19:31:24 INFO - PROCESS | 1922 | |i|pagename|runs| 19:31:24 INFO - PROCESS | 1922 | |0;dojo.html;3286.5070239464076;3339.5992480902296;3355.1108956126623;3365.158710322419;3400.489926511023;5521.309836270466;5654.321481422235;5661.348944871336;5766;5785.595008349412;5644.350899646542;5693.715314234292;5769.798057068004;5796.739146738396;5829.358770535237;5133.845984620449;5176.042432150046;5243.161094224924;5281.9207711884355;5355.116405793039;5357.383900851393;5375.3280839895015;5389.461053894599;5404.621676482648;5424.159255315412;4328.350747387885;5146.253793199984;5218.843434696965;5237.8428647140645;5279.102552566073;5154.38147422308;5197.870053248661;5228.686278823283;5232.973835130838;5303.23103150045;5161;5199.272101905736;5222.32109825724;5238.188080847474;5296.867578310535;5721.370649228582;5735.426457354273;5822.854428639318;5830.941690583082;5836.6789826559525;6119.694015299217;6165.106059621375;6250.624962502265;6264.154339164245;6320.873582528324;6232.719527621222;6245.313015568284;6250.781222657185;6298.338674439164;6313.463355614778;5709.800156994485;5896.469317761422;5907.556933229982;5937.287525496968;5965.403459654;5224.425313215544;6157.538184636081;6228.688565571703;6249.562530622813;6259.18630578031;6167;6172.197614310111;6185.41238582334;6189.133521307021;6189.659568723673;6033.3061697905305;6226.40849119333;6269.811905642838;6292.05619157121;6293.77971770995;6079;6103.155845961327;6104.277264894407;6112.959454150325;6120.751898419022;6103.938960610426;6144.341027922436;6166.383361663798;6279.560430769893;6282.371762823681;4983.6760610560195;4985.277134815431;5000.099982003274;5006.799728010838;5035.748212589356;4500.819967201341;4519.163954668397;4523.796429160696;4542.364069030338;4551.112533056088;5578.079616863253;5645.040343141676;5716;5730.627509211888;5751.1373294006235;6216.597460910316;6263.906041409383;6270.2789179244055;6294.307626161119;6319.399657032574;5718.370979192286;5740.598158128891;5750.396208398141;5762.510186634099;5789.363170051291;4622.86131416058;5303.867403314871;5390.51485366319;5473.151661492475;5487.478689524489;6251.499880009497;6284;6319.873602527831;6325.272593651789;6332.1135041094285;6127.250613123855;6314.305426403;6348.174737284124;6364.904526432199;6370.90443643355;5390.19147127934;6227.937720622917;6236.251649802053;6263.404976527223;6315.55791094619;6201.865617979785;6220.688965551613;6246.5002799775;6247.12540244357;6249.812505624838;6141.938580614136;6164.075388691732;6331.96834015827;6343.746250150127;6364.586301890362;6006;6103.05402662588;6116.113163591343;6121.148138557471;6123.740204357283;6034.547408944259;6071.908921366095;6076.817695469143;6078.908816367846;6092.482139018233;5718.370979192368;5834.212381328466;5881.235439392852;5974.850628734142;5990.910136347871;6144.324124346408;6161.630302181883;6168.0439531872635;6178.073289006685;6186.505079593531;5804.94195058044;5970.402959703995;5970.641761494324;5981.910271345846;6006.399360063959 19:31:24 INFO - PROCESS | 1922 | |1;ext.html;502.77626456226994;515.2091539486888;520.8463503266537;529.9321866439128;536.5787856532621;30525.542116868237;30926.53610195846;30966.690333096663;31098;41768;42529.7873510632;45173.54826451735;45226;45324.54675453244;45452;9584.76038099048;9781.413115213074;9781.706548803511;9781.706548803548;9786.26603004774;9514.286428517855;9642.566084526214;9644.75888102796;9648.614055437774;9668.468234247113;9604;9649.372790768577;9649.662261820838;9661.178799802025;9677.564509597085;9567.952160239189;9608.375455595364;9629.51852407384;9643.614255429775;9677.612895484172;9591.904080959172;9636.566354513994;9640.987696291859;9644.276679249015;9663;41958.58041419577;42299.57700422987;42301.57698423038;42333.78833105846;42378.364324535156;3455.6889879910927;4006.6193711597352;4228.894277643053;4286.507051689065;4409.250427427345;31851.84074079638;32165.035048948568;32732.018039458853;33466.83266583676;33499.49750753741;3283.1627934876456;3312.12350899589;3360.714339281164;3393.8472768725464;3395.7283417326553;539249;543212.2839385817;545942;546077;548090;63743;64895;64914.67542662256;64980.35019649743;65019;67700.66149669318;67703.32296677069;67738.66130669414;67786.32213677898;67865.66067169633;16506.75239871403;16752;16760.58098547546;16764.413245536347;16765.497035088967;12975.805362919564;13061.934690326487;13067.803982940264;13069.803952940714;13074.542391016268;16725.6654866902;18160.455186344432;18204.36284730028;18242.726359104625;18281.451556453063;5727.4845263926445;6849.965750171317;7057.788266352018;7103.431725461951;7104.163751574092;901.3059943843205;968.6367612145445;1034.1519953638094;1043.1289872956168;1055.0662663542819;30810.53784193239;31156.844215778776;31224.063278101694;31920.840395797873;32008.199795005312;982.886967998675;1046.6912260883057;1059.6715018344337;1153.4809335798925;1157.780021795856;15525.146116963675;16297;16596.85889887576;16604.750928735837;16636.83542152062;575591;578406;580336;583110;583940;62844.68577657083;63118.368816311246;63284;63305;63822;1281.2312612432652;1412.25150670145;1424.3946322812762;1445.5085271007908;1459.4381163252224;32003.519947200344;32333.030009099766;32461.18847028749;32485.67514324921;33263.33473330568;53626.73186634042;53929;53968;53983.19025214546;54059;531240;550764;551348;551601;551777;30926.53610195894;36383.81808090943;41901.580984189764;42050;44179.33730994102;49274.50725492842;49639.80290254442;50665.493345066076;50730.731731707005;50883;39584.01039974024;40014;41746.165076698904;41936.580634194484;42098.36852447277;40845;40858.59141408548;41274.1745165089;41454;42292.577074228866;39162.82511524659;39392.60607393889;40039.79980100081;40256.59743402529;40302.59697402988;33222.667773321955;35672.821635891654;36101.81949090238;36209.913702588965;36263.27473450569;25203.495930081663;25788.871055644602;26110.216693499224;26243.60634590521;26925.653717313664 19:31:24 INFO - PROCESS | 1922 | |2;jquery.html;357.43703666724883;382.582984546844;391.2292783217063;391.344497965907;408.47714924896144;15280.54158375249;16015.878888477811;20135.899320503413;20221.696674549894;21215;20486.873221972786;20959.895200523977;21316.253931112413;21579.70521768695;21617.56764864706;5457.317835270591;5854.092615644582;5970.925233457998;5993.61041532299;6003.6697981611;5904.852378690545;5965.791197308095;6038.305594856582;6040.275166979968;6047.546434017444;5598.552115830744;5833.679147646878;5841.970790146043;5866.6480011199255;5974.103884417329;5653.802116925929;5826.796062137826;5834.095715164156;5910.940890591082;5923.289205295349;5066.69599824008;5075.289459475676;5101.260317254015;5103.438621751641;5115.539601435851;27206.911723531033;27373.726262737317;27440.862795686095;27656.446871062668;27732.584011239847;2332.346942856003;2356.5640356534095;2512.7612876776675;2528.84826910386;3009.7058264946063;14626.04930679513;15573.37706491739;15632.687346253126;16081.356745730274;16426.917865410716;1793.6143729098308;1867.4677716850633;1870.485616455472;1919.184346652667;1957.5595491014408;119370;123965.38017309856;124099.13851292238;124941;127670.99999999907;10863.076638485818;11176.27354221973;11207;11348.659540213806;11487.0810335173;13695.520656776966;13839.792403113961;13841.792373114411;13862;13887.236202008986;8606.31149508038;8705.738827835175;8707.956460217658;8737.21365077146;8764.605592748341;8793.208611224893;8816.603252853636;8832.690855820016;8921.973972993059;8968.596413161424;6795.898061529081;6808.5914845109455;6818.420434263044;6823.249442561314;6834.624095674686;1875.1655513296453;1886.735856980024;1887.6224755049045;1892.7255547945472;1893.3562588719626;699.5627732667083;737.7934178430048;876.307716903639;928.4475736936512;992.245893121233;20246.898765506077;22774.886125569265;22848.543029139324;22860.742659153908;22864.885675571517;887.0021226120614;936.3164889630472;979.7697541077841;997.4165113408592;1053.1732589916878;10681.57273709043;11528.942355288338;11648.184627076023;11866.347350895783;11936.940315298541;602243;604017.8191981371;613261;618182.9090854607;619779;88495;108186;128491;131097.34451327872;140333;882.7572417585087;932.3753085432776;984.9655262065793;1009.0464511037146;1010.1968934696934;18908.14913328931;18917.243310267986;18986.525336866136;18992.52518687044;19146.23415063382;54123.18815217696;54779.71321720709;55628.007375095105;55709;56000.439995599525;80174.19825801667;82624;82967.58516207381;83166;84155.73766393386;7200.855982880418;7448.180700123091;7489.812754681177;7718.53688778675;7971.44199906001;11973.880261197624;13441.932790335986;13503.72992540124;13678.794818077538;13856.584302470941;11450.656480305604;11513.827292590451;11591.942040289745;12035.75928481443;12120.575779847662;5244.42311345744;8356.03905550845;10519.263651544625;10629.734256643645;11576.652700419001;11246.60636877738;11253.831192532283;11729.061675066145;11880.643580692593;12101.455434505293;8184.713535026245;8271.751847444586;8394.916050839414;8476.27951624119;8572.785680358042;9927;11066.336019838835;11092.556297748;11173.553057877594;11247.212695111264 19:31:24 INFO - PROCESS | 1922 | |3;mootools.html;1466.9657891186714;1472.521430535076;1757.2866455206167;1926.4798504403802;1959.8138176873201;8701.608427620757;8976.730698079067;9153.862692059616;9157.358984871062;9158.633654653806;7908.683652653901;8157.2250636189465;8171.836563268731;8269.462484938475;8960.8655870162;2130.5312831177157;2163.588918105555;2290.3701482092392;2364.8699321537397;2367.3134790910617;2348;2362.2676970139228;2363.6809030780882;2364.7280562735245;2365.27859003004;2303.332033710222;2358.7759162879497;2358.7995020423286;2358.9410264743346;2359.339384972202;1789.3647755046902;2302.090674183705;2372.2171683344454;2375.10933399975;2375.2755409600063;1998.320571005865;2056.3728062940795;2369.2892132360394;2387.4747555537756;2388.7850093491497;9017.774555636095;9072.909270907272;9074.36479446439;9086.727398178065;9087.318451116193;4703;5215.895682086375;5333.866653333697;5341.1187154119525;5405.891882162335;3472.131967008248;3548.290341931624;3614.89155325338;3625.347437461256;3628.7822730636244;1055.0609957138154;1081.686310969818;1088.3022484923545;1095.8849320821278;1785.214505617524;11509.366984815832;11786.646400607995;11796;11798.233114847508;11849.644510664608;4328.329108988112;4498.977505112453;4571.9085618287445;4587.082583483317;4587.678862479661;9118.772030699152;9156.908430915606;9225.630974761069;9234.076592340847;9237.861432078524;5195.350581177353;5213.6350455467755;5215.739213039333;5219.608529360313;5230.032443997872;4763;4881.926771098437;4889.242167464049;4895.265710143436;4900.705957642553;3558.1282585766653;3705.870294539732;3774.490443790108;3828.9808550957623;3844.154286057063;1114.57089020726;1149.4597539156582;1159.8666153392298;1161.0827446317314;1168.2406435816788;926.9165775080276;931.1712575807537;940.0787228516092;942.853857652065;1016.1007508355159;5970.76116955317;5975.282966044103;6033.456988871023;6102.633841969496;6242.469390101892;943.3443756589242;949.1030975728008;952.1763674421576;978.7014960436993;978.7602037500716;4031.637152656216;4066.5933406659688;4094.815733292009;4153.522344930311;4164.06308580573;10519.84220236697;10944;11043.558257669763;11044.282121662067;11081.944590276997;10952.123830093573;10974.286671366335;10996.670099897015;11005;11007.009369156815;7908.774140008193;8281.585920704061;8327.50034997902;8386.87419688705;8397.874031889527;4950.356453661001;6074.544409169242;6208.844778880564;6225.035119556476;6329.683515824098;9660.130588247092;9894.406335619886;10162.72965879265;10195.541200646137;10272.640457584248;9850.408975461496;10329.276950613685;10330.380177189392;10336.948315258676;10381.169506439617;7445.70217191328;7742.6128693566225;7767.53394796314;7830.686772529035;7836.333911617577;7813;8066.314363278952;8311.875321870057;8549.487030778173;8557.572121393781;7567.31894129531;7679.040119985002;7692.4230682700145;7696.7306144284685;7864.331531819631;6526.347365263626;7359.1169059713175;7470.589117598584;7662.540247585162;7684.116326622287;6953.443724502129;7142.785716428515;7179.676914538963;7207.243239459781;7233.963830180815;5071.366079240095;5080.949190508048;5122.282880396822;5196.220566914993;5235.738213089407;4952.331435256195;5023.623228257822;5121.92317115251;5166.3800343958965;5358.598105142208 19:31:24 INFO - PROCESS | 1922 | |4;prototype.html;299.8305957134219;303.43106674984404;314.7104663709387;315.39916459145337;317.48725807820364;4606.769661516928;5198.8700282492955;5392.649477783946;5423.593230507708;5435.9728201358985;5121.743912804354;5333.786648534054;5342.290467050545;5344.91982620261;5352.652077614952;4076.4496792932873;4120.835166593332;4123.505179378479;4128.60778226068;4156.233600719697;3935.704822138351;4150.211459822639;4159.646430053449;4166.291730405823;4176.807176232487;3521.559805024372;4033.415154802552;4047.4215056128132;4072.5112986441522;4077.816498257571;4088.713790034699;4095.5904409559103;4106.589341065899;4108.157827645356;4125.463689720347;3108.984455077733;3381.3575420669995;3527.6295988921306;3530.8058056806867;3544.8404821783083;4336.522982471925;4845.539673731025;4939.110620287136;5216.817411390621;5275.657082289677;3930.508686414198;4606.60843828275;4677.95322046782;4681.180793361148;4708.293755936603;4763.857084287477;4797.472278049412;4937.284093806414;5388.545092824955;5686.402927692616;3213.586022150246;3853.2678791029616;3875.2830726315724;3908.218356328746;3912.5500567434824;5102.770375333156;5962.433568810956;5993.190919225893;6018.939810601925;6023.139008970704;3540.929181416357;3711.3546095044294;3944.3491823849317;3946.1121247719034;3949.940750888739;3514.68367846895;3968.5634580196156;3999.2601368746873;4015.8393664253103;4019.799010049486;4641.210994130938;4657.951960808791;4680.127948820499;4692.6245900327895;4699.297903705383;5348.384935732441;5371.865703357447;5394.543473262197;5744.684042377626;5768.884622307531;1183.5798291606527;1200.4357951762659;1281.4105511464645;1338.1435881036145;1359.3746876436949;888.968796196409;890.1499068389802;907.8819753432011;909.3725329522608;910.6676063237003;4601.722147536991;5203.569018519937;5205.427402985669;5224.346956630421;5232.659877107976;5536.972315138399;5740.09222115094;5749.9712501437225;5808.07070868659;5876.1794338717655;4406.691531592758;4965.602751779849;5218.6868787872845;5260.579153667697;5299.152135658276;2293.346396277053;2342.1919437793936;2613.183837233105;2625.411625966301;2924.8245105293327;5926.407359264039;5953.970230148821;5960.135780311831;5966.793410645484;5980.252468441445;4811.639127065484;4877.3167980870585;4954.603631709455;5766.94233057664;5948.643081415129;5167.870803229875;5432.864178395493;5901.769538133106;5953.583249172516;6044.758209671564;4400.097979914174;5230.227979171045;5427.918581221206;5543.556515478671;5674.786833135163;4629.884252893705;4786.44955830084;5787.565932554991;5798.275215598051;5819.272590926134;5658.9151162733415;6013.789517366871;6028.246469191351;6043.969780151071;6062.726827366302;3359.798412095282;3651.744377893522;3776.905577360588;3914.6868250540456;4099.651529620016;5218.582513398995;5550.642745444807;5569.435561240728;5866.384029676993;5899.734511946886;4807.663463557657;4897.893905620617;5378.3777040345;5654.6889921054735;5754.108113242454;4185.853495127656;5608.560276279832;5632.930053121141;5682;5689.459501347365;5114.4118426379955;5524.309461317335;5554.944670471951;5581.832545023656;5588.596903331444;4715.151272770934;5335.73321333924;5664.037113690765;5672.7163641816915;5680.346760122474;4583.793729282123;4592.632589392773;5442.6462279951675;5543.667379957215;5623.268975033219 19:31:24 INFO - PROCESS | 1922 | |5;yui.html;740.55566660004;767.5816679909449;773.8800485924684;857.5369300577688;864.9697260595881;17739.55651108723;18387.90806045968;18409.44771656849;18428.078596070176;18455.538611534685;17381.82618173818;17533.12334383282;17574.736378954327;17596.20817063232;17605.64788704225;898.2006014646969;925.2459245714721;926.263620421763;926.9721908342726;927.8283517549276;723.023917711091;737.7196665267192;836.9372297077713;899.6986009686763;903.2503022491317;937.7093101138635;939.7697564096806;944.1738478831023;945.2721404518517;946.7775072857872;736.7826491185114;936.8360536906082;937.7702462896566;938.7090002099371;939.304914363373;700.9158900931869;704.3344039882313;707.5507053021317;710.3535782438008;711.0862541633976;15844.970076944961;15851.920740396225;15857.286422111032;15871.047737135808;15892.364305427769;13664.863351366459;13847;13862.93068534661;14019.228942408163;14265.144091354552;12635.620931372074;13043.41304641284;13188.538401156009;13189.802152967713;13509.932450337685;4595.885102872421;4655.394798676184;4739.028499157699;4822.324874517571;4828.782704778258;105827;107746.46126769394;115068;117956.41021794836;118120;11320.433978301216;11360.886391136148;11393.886061139448;11421.600243991421;11450.083993280517;22610.886945565497;22785.658215126787;22786;22831.31506054821;22832.31503054911;8169.305609023299;8337.791555211046;8353.624086915981;8469.830603388022;8474.788130296669;4221.197972385237;4435.756033418128;4461.884528867783;4537.56360371145;4543.454785425704;3576.123849656851;3685.2076803486843;3688;3688.243909998444;3692.48305237267;643.4498503779201;662.5858838226109;673.3165836675778;678.3792829560995;680.9114815074108;646.4860435953428;684.3848517498699;713.5718568858643;716.9318914703199;735.4447392218841;9396.201322887631;9716.611335546499;9762.170215531618;9833.606655733834;9847;658.3350815676106;685.5509641185032;692.4391243093111;698.5284932670529;706.0679902528613;4239.96120950363;4278.828846846153;4696.1546921554445;4709.67032307742;4719.622430205575;95926;97282.5135874316;140696;157082.21458892632;157732;123774;135437.87372757666;137501.3124934389;140998;141544;115120.84879151102;116750.41624791991;118182.40908795573;119841;120181;7981.24178203093;9136.132067453582;9141.757562464027;9150.63397464094;9176.587053582738;63697.68151159215;64288.678556606916;64833.35166648462;66036.66981665061;66344;90913.63629545695;95933;100725;101326;103174.96825031654;11689.88310116922;11883.78351440261;11936.343501107522;12204.511819527119;12303.876961230631;12775.872241277468;12905;13394.263315517741;13466.663333416744;13481.393337300042;10802.189835762507;12335.198212116184;12482.375881206084;12494.500219991098;12684;12211.389430528616;12358.196717213352;12515.499380024698;12786.360681965678;13009.804852927024;12274.693132671755;12304.938475307566;12371.257724536557;12413;12673.873261267268;9448.858267125861;10347.379157250589;10485.790284194425;10701;10901.182411319025;10524.631637892637;10768.19238557127;10769.192310576582;10775.838362424727;10800.45997700096 19:31:24 INFO - PROCESS | 1922 | __end_tp_report 19:31:24 INFO - PROCESS | 1922 | __start_cc_report 19:31:24 INFO - PROCESS | 1922 | _x_x_mozilla_cycle_collect,1080 19:31:24 INFO - PROCESS | 1922 | __end_cc_report 19:31:24 INFO - PROCESS | 1922 | __startTimestamp1467945084128__endTimestamp 19:31:24 INFO - PROCESS | 1922 | 19:31:24 INFO - PROCESS | 1922 | ------- Summary: start ------- 19:31:24 INFO - PROCESS | 1922 | Number of tests: 6 19:31:24 INFO - PROCESS | 1922 | 19:31:24 INFO - PROCESS | 1922 | [#0] dojo.html Cycles:165 Average:5746.48 Median:5970.64 stddev:639.42 (10.7%) stddev-sans-first:611.57 19:31:24 INFO - PROCESS | 1922 | Values: 3286.5 3339.6 3355.1 3365.2 3400.5 5521.3 5654.3 5661.3 5766.0 5785.6 5644.4 5693.7 5769.8 5796.7 5829.4 5133.8 5176.0 5243.2 5281.9 5355.1 5357.4 5375.3 5389.5 5404.6 5424.2 4328.4 5146.3 5218.8 5237.8 5279.1 5154.4 5197.9 5228.7 5233.0 5303.2 5161.0 5199.3 5222.3 5238.2 5296.9 5721.4 5735.4 5822.9 5830.9 5836.7 6119.7 6165.1 6250.6 6264.2 6320.9 6232.7 6245.3 6250.8 6298.3 6313.5 5709.8 5896.5 5907.6 5937.3 5965.4 5224.4 6157.5 6228.7 6249.6 6259.2 6167.0 6172.2 6185.4 6189.1 6189.7 6033.3 6226.4 6269.8 6292.1 6293.8 6079.0 6103.2 6104.3 6113.0 6120.8 6103.9 6144.3 6166.4 6279.6 6282.4 4983.7 4985.3 5000.1 5006.8 5035.7 4500.8 4519.2 4523.8 4542.4 4551.1 5578.1 5645.0 5716.0 5730.6 5751.1 6216.6 6263.9 6270.3 6294.3 6319.4 5718.4 5740.6 5750.4 5762.5 5789.4 4622.9 5303.9 5390.5 5473.2 5487.5 6251.5 6284.0 6319.9 6325.3 6332.1 6127.3 6314.3 6348.2 6364.9 6370.9 5390.2 6227.9 6236.3 6263.4 6315.6 6201.9 6220.7 6246.5 6247.1 6249.8 6141.9 6164.1 6332.0 6343.7 6364.6 6006.0 6103.1 6116.1 6121.1 6123.7 6034.5 6071.9 6076.8 6078.9 6092.5 5718.4 5834.2 5881.2 5974.9 5990.9 6144.3 6161.6 6168.0 6178.1 6186.5 5804.9 5970.4 5970.6 5981.9 6006.4 19:31:24 INFO - PROCESS | 1922 | 19:31:24 INFO - PROCESS | 1922 | [#1] ext.html Cycles:180 Average:70615.06 Median:42300.58 stddev:148518.24 (351.1%) stddev-sans-first:148841.58 19:31:24 INFO - PROCESS | 1922 | Values: 502.8 515.2 520.8 529.9 536.6 30525.5 30926.5 30966.7 31098.0 41768.0 42529.8 45173.5 45226.0 45324.5 45452.0 9584.8 9781.4 9781.7 9781.7 9786.3 9514.3 9642.6 9644.8 9648.6 9668.5 9604.0 9649.4 9649.7 9661.2 9677.6 9568.0 9608.4 9629.5 9643.6 9677.6 9591.9 9636.6 9641.0 9644.3 9663.0 41958.6 42299.6 42301.6 42333.8 42378.4 3455.7 4006.6 4228.9 4286.5 4409.3 31851.8 32165.0 32732.0 33466.8 33499.5 3283.2 3312.1 3360.7 3393.8 3395.7 539249.0 543212.3 545942.0 546077.0 548090.0 63743.0 64895.0 64914.7 64980.4 65019.0 67700.7 67703.3 67738.7 67786.3 67865.7 16506.8 16752.0 16760.6 16764.4 16765.5 12975.8 13061.9 13067.8 13069.8 13074.5 16725.7 18160.5 18204.4 18242.7 18281.5 5727.5 6850.0 7057.8 7103.4 7104.2 901.3 968.6 1034.2 1043.1 1055.1 30810.5 31156.8 31224.1 31920.8 32008.2 982.9 1046.7 1059.7 1153.5 1157.8 15525.1 16297.0 16596.9 16604.8 16636.8 575591.0 578406.0 580336.0 583110.0 583940.0 62844.7 63118.4 63284.0 63305.0 63822.0 1281.2 1412.3 1424.4 1445.5 1459.4 32003.5 32333.0 32461.2 32485.7 33263.3 53626.7 53929.0 53968.0 53983.2 54059.0 531240.0 550764.0 551348.0 551601.0 551777.0 30926.5 36383.8 41901.6 42050.0 44179.3 49274.5 49639.8 50665.5 50730.7 50883.0 39584.0 40014.0 41746.2 41936.6 42098.4 40845.0 40858.6 41274.2 41454.0 42292.6 39162.8 39392.6 40039.8 40256.6 40302.6 33222.7 35672.8 36101.8 36209.9 36263.3 25203.5 25788.9 26110.2 26243.6 26925.7 19:31:24 INFO - PROCESS | 1922 | 19:31:24 INFO - PROCESS | 1922 | [#2] jquery.html Cycles:180 Average:35798.67 Median:27548.65 stddev:101933.30 (370.0%) stddev-sans-first:102184.51 19:31:24 INFO - PROCESS | 1922 | Values: 357.4 382.6 391.2 391.3 408.5 15280.5 16015.9 20135.9 20221.7 21215.0 20486.9 20959.9 21316.3 21579.7 21617.6 5457.3 5854.1 5970.9 5993.6 6003.7 5904.9 5965.8 6038.3 6040.3 6047.5 5598.6 5833.7 5842.0 5866.6 5974.1 5653.8 5826.8 5834.1 5910.9 5923.3 5066.7 5075.3 5101.3 5103.4 5115.5 27206.9 27373.7 27440.9 27656.4 27732.6 2332.3 2356.6 2512.8 2528.8 3009.7 14626.0 15573.4 15632.7 16081.4 16426.9 1793.6 1867.5 1870.5 1919.2 1957.6 119370.0 123965.4 124099.1 124941.0 127671.0 10863.1 11176.3 11207.0 11348.7 11487.1 13695.5 13839.8 13841.8 13862.0 13887.2 8606.3 8705.7 8708.0 8737.2 8764.6 8793.2 8816.6 8832.7 8922.0 8968.6 6795.9 6808.6 6818.4 6823.2 6834.6 1875.2 1886.7 1887.6 1892.7 1893.4 699.6 737.8 876.3 928.4 992.2 20246.9 22774.9 22848.5 22860.7 22864.9 887.0 936.3 979.8 997.4 1053.2 10681.6 11528.9 11648.2 11866.3 11936.9 602243.0 604017.8 613261.0 618182.9 619779.0 88495.0 108186.0 128491.0 131097.3 140333.0 882.8 932.4 985.0 1009.0 1010.2 18908.1 18917.2 18986.5 18992.5 19146.2 54123.2 54779.7 55628.0 55709.0 56000.4 80174.2 82624.0 82967.6 83166.0 84155.7 7200.9 7448.2 7489.8 7718.5 7971.4 11973.9 13441.9 13503.7 13678.8 13856.6 11450.7 11513.8 11591.9 12035.8 12120.6 5244.4 8356.0 10519.3 10629.7 11576.7 11246.6 11253.8 11729.1 11880.6 12101.5 8184.7 8271.8 8394.9 8476.3 8572.8 9927.0 11066.3 11092.6 11173.6 11247.2 19:31:24 INFO - PROCESS | 1922 | 19:31:24 INFO - PROCESS | 1922 | [#3] mootools.html Cycles:180 Average:5747.79 Median:4987.98 stddev:3231.82 (64.8%) stddev-sans-first:3224.87 19:31:24 INFO - PROCESS | 1922 | Values: 1467.0 1472.5 1757.3 1926.5 1959.8 8701.6 8976.7 9153.9 9157.4 9158.6 7908.7 8157.2 8171.8 8269.5 8960.9 2130.5 2163.6 2290.4 2364.9 2367.3 2348.0 2362.3 2363.7 2364.7 2365.3 2303.3 2358.8 2358.8 2358.9 2359.3 1789.4 2302.1 2372.2 2375.1 2375.3 1998.3 2056.4 2369.3 2387.5 2388.8 9017.8 9072.9 9074.4 9086.7 9087.3 4703.0 5215.9 5333.9 5341.1 5405.9 3472.1 3548.3 3614.9 3625.3 3628.8 1055.1 1081.7 1088.3 1095.9 1785.2 11509.4 11786.6 11796.0 11798.2 11849.6 4328.3 4499.0 4571.9 4587.1 4587.7 9118.8 9156.9 9225.6 9234.1 9237.9 5195.4 5213.6 5215.7 5219.6 5230.0 4763.0 4881.9 4889.2 4895.3 4900.7 3558.1 3705.9 3774.5 3829.0 3844.2 1114.6 1149.5 1159.9 1161.1 1168.2 926.9 931.2 940.1 942.9 1016.1 5970.8 5975.3 6033.5 6102.6 6242.5 943.3 949.1 952.2 978.7 978.8 4031.6 4066.6 4094.8 4153.5 4164.1 10519.8 10944.0 11043.6 11044.3 11081.9 10952.1 10974.3 10996.7 11005.0 11007.0 7908.8 8281.6 8327.5 8386.9 8397.9 4950.4 6074.5 6208.8 6225.0 6329.7 9660.1 9894.4 10162.7 10195.5 10272.6 9850.4 10329.3 10330.4 10336.9 10381.2 7445.7 7742.6 7767.5 7830.7 7836.3 7813.0 8066.3 8311.9 8549.5 8557.6 7567.3 7679.0 7692.4 7696.7 7864.3 6526.3 7359.1 7470.6 7662.5 7684.1 6953.4 7142.8 7179.7 7207.2 7234.0 5071.4 5080.9 5122.3 5196.2 5235.7 4952.3 5023.6 5121.9 5166.4 5358.6 19:31:24 INFO - PROCESS | 1922 | 19:31:24 INFO - PROCESS | 1922 | [#4] prototype.html Cycles:180 Average:4521.69 Median:4960.10 stddev:1404.47 (28.3%) stddev-sans-first:1372.20 19:31:24 INFO - PROCESS | 1922 | Values: 299.8 303.4 314.7 315.4 317.5 4606.8 5198.9 5392.6 5423.6 5436.0 5121.7 5333.8 5342.3 5344.9 5352.7 4076.4 4120.8 4123.5 4128.6 4156.2 3935.7 4150.2 4159.6 4166.3 4176.8 3521.6 4033.4 4047.4 4072.5 4077.8 4088.7 4095.6 4106.6 4108.2 4125.5 3109.0 3381.4 3527.6 3530.8 3544.8 4336.5 4845.5 4939.1 5216.8 5275.7 3930.5 4606.6 4678.0 4681.2 4708.3 4763.9 4797.5 4937.3 5388.5 5686.4 3213.6 3853.3 3875.3 3908.2 3912.6 5102.8 5962.4 5993.2 6018.9 6023.1 3540.9 3711.4 3944.3 3946.1 3949.9 3514.7 3968.6 3999.3 4015.8 4019.8 4641.2 4658.0 4680.1 4692.6 4699.3 5348.4 5371.9 5394.5 5744.7 5768.9 1183.6 1200.4 1281.4 1338.1 1359.4 889.0 890.1 907.9 909.4 910.7 4601.7 5203.6 5205.4 5224.3 5232.7 5537.0 5740.1 5750.0 5808.1 5876.2 4406.7 4965.6 5218.7 5260.6 5299.2 2293.3 2342.2 2613.2 2625.4 2924.8 5926.4 5954.0 5960.1 5966.8 5980.3 4811.6 4877.3 4954.6 5766.9 5948.6 5167.9 5432.9 5901.8 5953.6 6044.8 4400.1 5230.2 5427.9 5543.6 5674.8 4629.9 4786.4 5787.6 5798.3 5819.3 5658.9 6013.8 6028.2 6044.0 6062.7 3359.8 3651.7 3776.9 3914.7 4099.7 5218.6 5550.6 5569.4 5866.4 5899.7 4807.7 4897.9 5378.4 5654.7 5754.1 4185.9 5608.6 5632.9 5682.0 5689.5 5114.4 5524.3 5554.9 5581.8 5588.6 4715.2 5335.7 5664.0 5672.7 5680.3 4583.8 4592.6 5442.6 5543.7 5623.3 19:31:24 INFO - PROCESS | 1922 | 19:31:24 INFO - PROCESS | 1922 | [#5] yui.html Cycles:180 Average:25218.10 Median:3630.67 stddev:39934.47 (1099.9%) stddev-sans-first:40004.21 19:31:24 INFO - PROCESS | 1922 | Values: 740.6 767.6 773.9 857.5 865.0 17739.6 18387.9 18409.4 18428.1 18455.5 17381.8 17533.1 17574.7 17596.2 17605.6 898.2 925.2 926.3 927.0 927.8 723.0 737.7 836.9 899.7 903.3 937.7 939.8 944.2 945.3 946.8 736.8 936.8 937.8 938.7 939.3 700.9 704.3 707.6 710.4 711.1 15845.0 15851.9 15857.3 15871.0 15892.4 13664.9 13847.0 13862.9 14019.2 14265.1 12635.6 13043.4 13188.5 13189.8 13509.9 4595.9 4655.4 4739.0 4822.3 4828.8 105827.0 107746.5 115068.0 117956.4 118120.0 11320.4 11360.9 11393.9 11421.6 11450.1 22610.9 22785.7 22786.0 22831.3 22832.3 8169.3 8337.8 8353.6 8469.8 8474.8 4221.2 4435.8 4461.9 4537.6 4543.5 3576.1 3685.2 3688.0 3688.2 3692.5 643.4 662.6 673.3 678.4 680.9 646.5 684.4 713.6 716.9 735.4 9396.2 9716.6 9762.2 9833.6 9847.0 658.3 685.6 692.4 698.5 706.1 4240.0 4278.8 4696.2 4709.7 4719.6 95926.0 97282.5 140696.0 157082.2 157732.0 123774.0 135437.9 137501.3 140998.0 141544.0 115120.8 116750.4 118182.4 119841.0 120181.0 7981.2 9136.1 9141.8 9150.6 9176.6 63697.7 64288.7 64833.4 66036.7 66344.0 90913.6 95933.0 100725.0 101326.0 103175.0 11689.9 11883.8 11936.3 12204.5 12303.9 12775.9 12905.0 13394.3 13466.7 13481.4 10802.2 12335.2 12482.4 12494.5 12684.0 12211.4 12358.2 12515.5 12786.4 13009.8 12274.7 12304.9 12371.3 12413.0 12673.9 9448.9 10347.4 10485.8 10701.0 10901.2 10524.6 10768.2 10769.2 10775.8 10800.5 19:31:24 INFO - PROCESS | 1922 | -------- Summary: end -------- 19:31:24 INFO - PROCESS | 1922 | 19:31:24 INFO - TEST-INFO | 1922: exit 0 19:31:24 INFO - TEST-OK | dromaeo_css | took 1115285ms 19:31:24 INFO - TEST-START | kraken 19:31:24 INFO - operating with platform_type : mac_ 19:31:24 INFO - Initialising browser for kraken test... 19:31:24 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpdou319/profile http://localhost:49432/getInfo.html) 19:31:26 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 19:31:26 INFO - PROCESS | 1928 | colorDepth:24 19:31:26 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 19:31:26 INFO - PROCESS | 1928 | __metrics 19:31:27 INFO - PROCESS | 1928 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:31:27 INFO - PROCESS | 1928 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:31:27 INFO - TEST-INFO | 1928: exit 0 19:31:27 INFO - Browser initialized. 19:31:27 INFO - Running cycle 1/1 for kraken test... 19:31:27 INFO - TEST-INFO | started process 1929 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/T/tmpdou319/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:31:39 INFO - PROCESS | 1929 | RSS: Main: 244006912 19:31:39 INFO - PROCESS | 1929 | 19:31:42 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49432/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49432/tests/kraken/driver.html?testName=audio-beat-detection) 19:31:43 INFO - PROCESS | 1929 | RSS: Main: 294449152 19:31:43 INFO - PROCESS | 1929 | 19:31:45 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49432/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49432/tests/kraken/driver.html?testName=audio-dft) 19:31:46 INFO - PROCESS | 1929 | RSS: Main: 305033216 19:31:46 INFO - PROCESS | 1929 | 19:31:50 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49432/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49432/tests/kraken/driver.html?testName=audio-fft) 19:31:50 INFO - PROCESS | 1929 | RSS: Main: 387723264 19:31:50 INFO - PROCESS | 1929 | 19:31:52 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49432/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49432/tests/kraken/driver.html?testName=audio-oscillator) 19:31:53 INFO - PROCESS | 1929 | RSS: Main: 289853440 19:31:53 INFO - PROCESS | 1929 | 19:31:55 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49432/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49432/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:31:55 INFO - PROCESS | 1929 | RSS: Main: 294539264 19:31:55 INFO - PROCESS | 1929 | 19:31:58 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49432/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49432/tests/kraken/driver.html?testName=imaging-darkroom) 19:31:59 INFO - PROCESS | 1929 | RSS: Main: 332238848 19:31:59 INFO - PROCESS | 1929 | 19:32:02 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49432/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49432/tests/kraken/driver.html?testName=imaging-desaturate) 19:32:03 INFO - PROCESS | 1929 | RSS: Main: 332038144 19:32:03 INFO - PROCESS | 1929 | 19:32:05 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49432/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49432/tests/kraken/driver.html?testName=json-parse-financial) 19:32:06 INFO - PROCESS | 1929 | RSS: Main: 332582912 19:32:06 INFO - PROCESS | 1929 | 19:32:07 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49432/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49432/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:32:08 INFO - PROCESS | 1929 | RSS: Main: 311414784 19:32:08 INFO - PROCESS | 1929 | 19:32:10 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49432/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49432/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:32:11 INFO - PROCESS | 1929 | RSS: Main: 318078976 19:32:11 INFO - PROCESS | 1929 | 19:32:13 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49432/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49432/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:32:13 INFO - PROCESS | 1929 | RSS: Main: 316923904 19:32:13 INFO - PROCESS | 1929 | 19:32:16 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49432/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49432/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:32:17 INFO - PROCESS | 1929 | RSS: Main: 324788224 19:32:17 INFO - PROCESS | 1929 | 19:32:20 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49432/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49432/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:32:20 INFO - PROCESS | 1929 | RSS: Main: 327307264 19:32:20 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49432/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49432/tests/kraken/driver.html?testName=ai-astar) 19:32:22 INFO - PROCESS | 1929 | RSS: Main: 371863552 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | __start_tp_report 19:32:22 INFO - PROCESS | 1929 | _x_x_mozilla_page_load 19:32:22 INFO - PROCESS | 1929 | _x_x_mozilla_page_load_details 19:32:22 INFO - PROCESS | 1929 | |i|pagename|runs| 19:32:22 INFO - PROCESS | 1929 | |0;ai-astar;100;112;113;114;114;113;113;113;114;114 19:32:22 INFO - PROCESS | 1929 | |1;audio-beat-detection;144;107;105;159;104;108;127;105;141;130 19:32:22 INFO - PROCESS | 1929 | |2;audio-dft;183;184;185;183;183;186;180;181;181;181 19:32:22 INFO - PROCESS | 1929 | |3;audio-fft;80;75;58;105;57;74;85;81;83;55 19:32:22 INFO - PROCESS | 1929 | |4;audio-oscillator;87;86;93;70;69;68;95;70;67;99 19:32:22 INFO - PROCESS | 1929 | |5;imaging-gaussian-blur;88;90;131;111;88;85;88;86;89;85 19:32:22 INFO - PROCESS | 1929 | |6;imaging-darkroom;134;122;93;93;93;93;93;93;113;92 19:32:22 INFO - PROCESS | 1929 | |7;imaging-desaturate;84;84;86;86;85;86;86;87;86;86 19:32:22 INFO - PROCESS | 1929 | |8;json-parse-financial;57;54;55;57;56;54;54;54;53;55 19:32:22 INFO - PROCESS | 1929 | |9;json-stringify-tinderbox;65;67;74;78;70;73;69;71;69;72 19:32:22 INFO - PROCESS | 1929 | |10;stanford-crypto-aes;83;85;83;85;81;81;80;85;86;82 19:32:22 INFO - PROCESS | 1929 | |11;stanford-crypto-ccm;151;149;143;134;133;131;134;135;177;134 19:32:22 INFO - PROCESS | 1929 | |12;stanford-crypto-pbkdf2;161;160;160;161;161;161;160;161;159;189 19:32:22 INFO - PROCESS | 1929 | |13;stanford-crypto-sha256-iterative;66;66;67;66;66;65;66;65;66;63 19:32:22 INFO - PROCESS | 1929 | __end_tp_report 19:32:22 INFO - PROCESS | 1929 | __start_cc_report 19:32:22 INFO - PROCESS | 1929 | _x_x_mozilla_cycle_collect,2008 19:32:22 INFO - PROCESS | 1929 | __end_cc_report 19:32:22 INFO - PROCESS | 1929 | __startTimestamp1467945142759__endTimestamp 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | ------- Summary: start ------- 19:32:22 INFO - PROCESS | 1929 | Number of tests: 14 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.50 stddev:4.27 (3.8%) stddev-sans-first:0.71 19:32:22 INFO - PROCESS | 1929 | Values: 100.0 112.0 113.0 114.0 114.0 113.0 113.0 113.0 114.0 114.0 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | [#1] audio-beat-detection Cycles:10 Average:123.00 Median:128.50 stddev:20.04 (15.6%) stddev-sans-first:19.77 19:32:22 INFO - PROCESS | 1929 | Values: 144.0 107.0 105.0 159.0 104.0 108.0 127.0 105.0 141.0 130.0 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:1.95 (1.1%) stddev-sans-first:2.06 19:32:22 INFO - PROCESS | 1929 | Values: 183.0 184.0 185.0 183.0 183.0 186.0 180.0 181.0 181.0 181.0 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | [#3] audio-fft Cycles:10 Average:75.30 Median:77.50 stddev:15.41 (19.9%) stddev-sans-first:16.25 19:32:22 INFO - PROCESS | 1929 | Values: 80.0 75.0 58.0 105.0 57.0 74.0 85.0 81.0 83.0 55.0 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | [#4] audio-oscillator Cycles:10 Average:80.40 Median:86.50 stddev:12.79 (14.8%) stddev-sans-first:13.34 19:32:22 INFO - PROCESS | 1929 | Values: 87.0 86.0 93.0 70.0 69.0 68.0 95.0 70.0 67.0 99.0 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | [#5] imaging-gaussian-blur Cycles:10 Average:94.10 Median:88.00 stddev:15.03 (17.1%) stddev-sans-first:15.78 19:32:22 INFO - PROCESS | 1929 | Values: 88.0 90.0 131.0 111.0 88.0 85.0 88.0 86.0 89.0 85.0 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | [#6] imaging-darkroom Cycles:10 Average:101.90 Median:93.00 stddev:15.39 (16.5%) stddev-sans-first:11.10 19:32:22 INFO - PROCESS | 1929 | Values: 134.0 122.0 93.0 93.0 93.0 93.0 93.0 93.0 113.0 92.0 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:0.97 (1.1%) stddev-sans-first:0.83 19:32:22 INFO - PROCESS | 1929 | Values: 84.0 84.0 86.0 86.0 85.0 86.0 86.0 87.0 86.0 86.0 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:1.37 (2.5%) stddev-sans-first:1.22 19:32:22 INFO - PROCESS | 1929 | Values: 57.0 54.0 55.0 57.0 56.0 54.0 54.0 54.0 53.0 55.0 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.50 stddev:3.71 (5.2%) stddev-sans-first:3.28 19:32:22 INFO - PROCESS | 1929 | Values: 65.0 67.0 74.0 78.0 70.0 73.0 69.0 71.0 69.0 72.0 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:84.00 stddev:2.08 (2.5%) stddev-sans-first:2.20 19:32:22 INFO - PROCESS | 1929 | Values: 83.0 85.0 83.0 85.0 81.0 81.0 80.0 85.0 86.0 82.0 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | [#11] stanford-crypto-ccm Cycles:10 Average:142.10 Median:139.00 stddev:14.14 (10.2%) stddev-sans-first:14.62 19:32:22 INFO - PROCESS | 1929 | Values: 151.0 149.0 143.0 134.0 133.0 131.0 134.0 135.0 177.0 134.0 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.30 Median:161.00 stddev:9.06 (5.6%) stddev-sans-first:9.57 19:32:22 INFO - PROCESS | 1929 | Values: 161.0 160.0 160.0 161.0 161.0 161.0 160.0 161.0 159.0 189.0 19:32:22 INFO - PROCESS | 1929 | 19:32:22 INFO - PROCESS | 1929 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:1.07 (1.6%) stddev-sans-first:1.13 19:32:22 INFO - PROCESS | 1929 | Values: 66.0 66.0 67.0 66.0 66.0 65.0 66.0 65.0 66.0 63.0 19:32:22 INFO - PROCESS | 1929 | -------- Summary: end -------- 19:32:22 INFO - PROCESS | 1929 | 19:32:23 INFO - TEST-INFO | 1929: exit 0 19:32:23 INFO - TEST-OK | kraken | took 58818ms 19:32:23 INFO - SUITE-END | took 1174s 19:32:23 INFO - Completed test suite (00:19:34) 19:32:23 INFO - javascript benchmark 19:32:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3286.5070239464076, 3339.5992480902296, 3355.1108956126623, 3365.158710322419, 3400.489926511023, 5521.309836270466, 5654.321481422235, 5661.348944871336, 5766.0, 5785.595008349412, 5644.350899646542, 5693.715314234292, 5769.798057068004, 5796.739146738396, 5829.358770535237, 5133.845984620449, 5176.042432150046, 5243.161094224924, 5281.9207711884355, 5355.116405793039, 5357.383900851393, 5375.3280839895015, 5389.461053894599, 5404.621676482648, 5424.159255315412, 4328.350747387885, 5146.253793199984, 5218.843434696965, 5237.8428647140645, 5279.102552566073, 5154.38147422308, 5197.870053248661, 5228.686278823283, 5232.973835130838, 5303.23103150045, 5161.0, 5199.272101905736, 5222.32109825724, 5238.188080847474, 5296.867578310535, 5721.370649228582, 5735.426457354273, 5822.854428639318, 5830.941690583082, 5836.6789826559525, 6119.694015299217, 6165.106059621375, 6250.624962502265, 6264.154339164245, 6320.873582528324, 6232.719527621222, 6245.313015568284, 6250.781222657185, 6298.338674439164, 6313.463355614778, 5709.800156994485, 5896.469317761422, 5907.556933229982, 5937.287525496968, 5965.403459654, 5224.425313215544, 6157.538184636081, 6228.688565571703, 6249.562530622813, 6259.18630578031, 6167.0, 6172.197614310111, 6185.41238582334, 6189.133521307021, 6189.659568723673, 6033.3061697905305, 6226.40849119333, 6269.811905642838, 6292.05619157121, 6293.77971770995, 6079.0, 6103.155845961327, 6104.277264894407, 6112.959454150325, 6120.751898419022, 6103.938960610426, 6144.341027922436, 6166.383361663798, 6279.560430769893, 6282.371762823681, 4983.6760610560195, 4985.277134815431, 5000.099982003274, 5006.799728010838, 5035.748212589356, 4500.819967201341, 4519.163954668397, 4523.796429160696, 4542.364069030338, 4551.112533056088, 5578.079616863253, 5645.040343141676, 5716.0, 5730.627509211888, 5751.1373294006235, 6216.597460910316, 6263.906041409383, 6270.2789179244055, 6294.307626161119, 6319.399657032574, 5718.370979192286, 5740.598158128891, 5750.396208398141, 5762.510186634099, 5789.363170051291, 4622.86131416058, 5303.867403314871, 5390.51485366319, 5473.151661492475, 5487.478689524489, 6251.499880009497, 6284.0, 6319.873602527831, 6325.272593651789, 6332.1135041094285, 6127.250613123855, 6314.305426403, 6348.174737284124, 6364.904526432199, 6370.90443643355, 5390.19147127934, 6227.937720622917, 6236.251649802053, 6263.404976527223, 6315.55791094619, 6201.865617979785, 6220.688965551613, 6246.5002799775, 6247.12540244357, 6249.812505624838, 6141.938580614136, 6164.075388691732, 6331.96834015827, 6343.746250150127, 6364.586301890362, 6006.0, 6103.05402662588, 6116.113163591343, 6121.148138557471, 6123.740204357283, 6034.547408944259, 6071.908921366095, 6076.817695469143, 6078.908816367846, 6092.482139018233, 5718.370979192368, 5834.212381328466, 5881.235439392852, 5974.850628734142, 5990.910136347871, 6144.324124346408, 6161.630302181883, 6168.0439531872635, 6178.073289006685, 6186.505079593531, 5804.94195058044, 5970.402959703995, 5970.641761494324, 5981.910271345846, 6006.399360063959], "value": 5706.033246713633, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.77626456226994, 515.2091539486888, 520.8463503266537, 529.9321866439128, 536.5787856532621, 30525.542116868237, 30926.53610195846, 30966.690333096663, 31098.0, 41768.0, 42529.7873510632, 45173.54826451735, 45226.0, 45324.54675453244, 45452.0, 9584.76038099048, 9781.413115213074, 9781.706548803511, 9781.706548803548, 9786.26603004774, 9514.286428517855, 9642.566084526214, 9644.75888102796, 9648.614055437774, 9668.468234247113, 9604.0, 9649.372790768577, 9649.662261820838, 9661.178799802025, 9677.564509597085, 9567.952160239189, 9608.375455595364, 9629.51852407384, 9643.614255429775, 9677.612895484172, 9591.904080959172, 9636.566354513994, 9640.987696291859, 9644.276679249015, 9663.0, 41958.58041419577, 42299.57700422987, 42301.57698423038, 42333.78833105846, 42378.364324535156, 3455.6889879910927, 4006.6193711597352, 4228.894277643053, 4286.507051689065, 4409.250427427345, 31851.84074079638, 32165.035048948568, 32732.018039458853, 33466.83266583676, 33499.49750753741, 3283.1627934876456, 3312.12350899589, 3360.714339281164, 3393.8472768725464, 3395.7283417326553, 539249.0, 543212.2839385817, 545942.0, 546077.0, 548090.0, 63743.0, 64895.0, 64914.67542662256, 64980.35019649743, 65019.0, 67700.66149669318, 67703.32296677069, 67738.66130669414, 67786.32213677898, 67865.66067169633, 16506.75239871403, 16752.0, 16760.58098547546, 16764.413245536347, 16765.497035088967, 12975.805362919564, 13061.934690326487, 13067.803982940264, 13069.803952940714, 13074.542391016268, 16725.6654866902, 18160.455186344432, 18204.36284730028, 18242.726359104625, 18281.451556453063, 5727.4845263926445, 6849.965750171317, 7057.788266352018, 7103.431725461951, 7104.163751574092, 901.3059943843205, 968.6367612145445, 1034.1519953638094, 1043.1289872956168, 1055.0662663542819, 30810.53784193239, 31156.844215778776, 31224.063278101694, 31920.840395797873, 32008.199795005312, 982.886967998675, 1046.6912260883057, 1059.6715018344337, 1153.4809335798925, 1157.780021795856, 15525.146116963675, 16297.0, 16596.85889887576, 16604.750928735837, 16636.83542152062, 575591.0, 578406.0, 580336.0, 583110.0, 583940.0, 62844.68577657083, 63118.368816311246, 63284.0, 63305.0, 63822.0, 1281.2312612432652, 1412.25150670145, 1424.3946322812762, 1445.5085271007908, 1459.4381163252224, 32003.519947200344, 32333.030009099766, 32461.18847028749, 32485.67514324921, 33263.33473330568, 53626.73186634042, 53929.0, 53968.0, 53983.19025214546, 54059.0, 531240.0, 550764.0, 551348.0, 551601.0, 551777.0, 30926.53610195894, 36383.81808090943, 41901.580984189764, 42050.0, 44179.33730994102, 49274.50725492842, 49639.80290254442, 50665.493345066076, 50730.731731707005, 50883.0, 39584.01039974024, 40014.0, 41746.165076698904, 41936.580634194484, 42098.36852447277, 40845.0, 40858.59141408548, 41274.1745165089, 41454.0, 42292.577074228866, 39162.82511524659, 39392.60607393889, 40039.79980100081, 40256.59743402529, 40302.59697402988, 33222.667773321955, 35672.821635891654, 36101.81949090238, 36209.913702588965, 36263.27473450569, 25203.495930081663, 25788.871055644602, 26110.216693499224, 26243.60634590521, 26925.653717313664], "value": 20755.939003191455, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.43703666724883, 382.582984546844, 391.2292783217063, 391.344497965907, 408.47714924896144, 15280.54158375249, 16015.878888477811, 20135.899320503413, 20221.696674549894, 21215.0, 20486.873221972786, 20959.895200523977, 21316.253931112413, 21579.70521768695, 21617.56764864706, 5457.317835270591, 5854.092615644582, 5970.925233457998, 5993.61041532299, 6003.6697981611, 5904.852378690545, 5965.791197308095, 6038.305594856582, 6040.275166979968, 6047.546434017444, 5598.552115830744, 5833.679147646878, 5841.970790146043, 5866.6480011199255, 5974.103884417329, 5653.802116925929, 5826.796062137826, 5834.095715164156, 5910.940890591082, 5923.289205295349, 5066.69599824008, 5075.289459475676, 5101.260317254015, 5103.438621751641, 5115.539601435851, 27206.911723531033, 27373.726262737317, 27440.862795686095, 27656.446871062668, 27732.584011239847, 2332.346942856003, 2356.5640356534095, 2512.7612876776675, 2528.84826910386, 3009.7058264946063, 14626.04930679513, 15573.37706491739, 15632.687346253126, 16081.356745730274, 16426.917865410716, 1793.6143729098308, 1867.4677716850633, 1870.485616455472, 1919.184346652667, 1957.5595491014408, 119370.0, 123965.38017309856, 124099.13851292238, 124941.0, 127670.99999999907, 10863.076638485818, 11176.27354221973, 11207.0, 11348.659540213806, 11487.0810335173, 13695.520656776966, 13839.792403113961, 13841.792373114411, 13862.0, 13887.236202008986, 8606.31149508038, 8705.738827835175, 8707.956460217658, 8737.21365077146, 8764.605592748341, 8793.208611224893, 8816.603252853636, 8832.690855820016, 8921.973972993059, 8968.596413161424, 6795.898061529081, 6808.5914845109455, 6818.420434263044, 6823.249442561314, 6834.624095674686, 1875.1655513296453, 1886.735856980024, 1887.6224755049045, 1892.7255547945472, 1893.3562588719626, 699.5627732667083, 737.7934178430048, 876.307716903639, 928.4475736936512, 992.245893121233, 20246.898765506077, 22774.886125569265, 22848.543029139324, 22860.742659153908, 22864.885675571517, 887.0021226120614, 936.3164889630472, 979.7697541077841, 997.4165113408592, 1053.1732589916878, 10681.57273709043, 11528.942355288338, 11648.184627076023, 11866.347350895783, 11936.940315298541, 602243.0, 604017.8191981371, 613261.0, 618182.9090854607, 619779.0, 88495.0, 108186.0, 128491.0, 131097.34451327872, 140333.0, 882.7572417585087, 932.3753085432776, 984.9655262065793, 1009.0464511037146, 1010.1968934696934, 18908.14913328931, 18917.243310267986, 18986.525336866136, 18992.52518687044, 19146.23415063382, 54123.18815217696, 54779.71321720709, 55628.007375095105, 55709.0, 56000.439995599525, 80174.19825801667, 82624.0, 82967.58516207381, 83166.0, 84155.73766393386, 7200.855982880418, 7448.180700123091, 7489.812754681177, 7718.53688778675, 7971.44199906001, 11973.880261197624, 13441.932790335986, 13503.72992540124, 13678.794818077538, 13856.584302470941, 11450.656480305604, 11513.827292590451, 11591.942040289745, 12035.75928481443, 12120.575779847662, 5244.42311345744, 8356.03905550845, 10519.263651544625, 10629.734256643645, 11576.652700419001, 11246.60636877738, 11253.831192532283, 11729.061675066145, 11880.643580692593, 12101.455434505293, 8184.713535026245, 8271.751847444586, 8394.916050839414, 8476.27951624119, 8572.785680358042, 9927.0, 11066.336019838835, 11092.556297748, 11173.553057877594, 11247.212695111264], "value": 9835.073594377542, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1466.9657891186714, 1472.521430535076, 1757.2866455206167, 1926.4798504403802, 1959.8138176873201, 8701.608427620757, 8976.730698079067, 9153.862692059616, 9157.358984871062, 9158.633654653806, 7908.683652653901, 8157.2250636189465, 8171.836563268731, 8269.462484938475, 8960.8655870162, 2130.5312831177157, 2163.588918105555, 2290.3701482092392, 2364.8699321537397, 2367.3134790910617, 2348.0, 2362.2676970139228, 2363.6809030780882, 2364.7280562735245, 2365.27859003004, 2303.332033710222, 2358.7759162879497, 2358.7995020423286, 2358.9410264743346, 2359.339384972202, 1789.3647755046902, 2302.090674183705, 2372.2171683344454, 2375.10933399975, 2375.2755409600063, 1998.320571005865, 2056.3728062940795, 2369.2892132360394, 2387.4747555537756, 2388.7850093491497, 9017.774555636095, 9072.909270907272, 9074.36479446439, 9086.727398178065, 9087.318451116193, 4703.0, 5215.895682086375, 5333.866653333697, 5341.1187154119525, 5405.891882162335, 3472.131967008248, 3548.290341931624, 3614.89155325338, 3625.347437461256, 3628.7822730636244, 1055.0609957138154, 1081.686310969818, 1088.3022484923545, 1095.8849320821278, 1785.214505617524, 11509.366984815832, 11786.646400607995, 11796.0, 11798.233114847508, 11849.644510664608, 4328.329108988112, 4498.977505112453, 4571.9085618287445, 4587.082583483317, 4587.678862479661, 9118.772030699152, 9156.908430915606, 9225.630974761069, 9234.076592340847, 9237.861432078524, 5195.350581177353, 5213.6350455467755, 5215.739213039333, 5219.608529360313, 5230.032443997872, 4763.0, 4881.926771098437, 4889.242167464049, 4895.265710143436, 4900.705957642553, 3558.1282585766653, 3705.870294539732, 3774.490443790108, 3828.9808550957623, 3844.154286057063, 1114.57089020726, 1149.4597539156582, 1159.8666153392298, 1161.0827446317314, 1168.2406435816788, 926.9165775080276, 931.1712575807537, 940.0787228516092, 942.853857652065, 1016.1007508355159, 5970.76116955317, 5975.282966044103, 6033.456988871023, 6102.633841969496, 6242.469390101892, 943.3443756589242, 949.1030975728008, 952.1763674421576, 978.7014960436993, 978.7602037500716, 4031.637152656216, 4066.5933406659688, 4094.815733292009, 4153.522344930311, 4164.06308580573, 10519.84220236697, 10944.0, 11043.558257669763, 11044.282121662067, 11081.944590276997, 10952.123830093573, 10974.286671366335, 10996.670099897015, 11005.0, 11007.009369156815, 7908.774140008193, 8281.585920704061, 8327.50034997902, 8386.87419688705, 8397.874031889527, 4950.356453661001, 6074.544409169242, 6208.844778880564, 6225.035119556476, 6329.683515824098, 9660.130588247092, 9894.406335619886, 10162.72965879265, 10195.541200646137, 10272.640457584248, 9850.408975461496, 10329.276950613685, 10330.380177189392, 10336.948315258676, 10381.169506439617, 7445.70217191328, 7742.6128693566225, 7767.53394796314, 7830.686772529035, 7836.333911617577, 7813.0, 8066.314363278952, 8311.875321870057, 8549.487030778173, 8557.572121393781, 7567.31894129531, 7679.040119985002, 7692.4230682700145, 7696.7306144284685, 7864.331531819631, 6526.347365263626, 7359.1169059713175, 7470.589117598584, 7662.540247585162, 7684.116326622287, 6953.443724502129, 7142.785716428515, 7179.676914538963, 7207.243239459781, 7233.963830180815, 5071.366079240095, 5080.949190508048, 5122.282880396822, 5196.220566914993, 5235.738213089407, 4952.331435256195, 5023.623228257822, 5121.92317115251, 5166.3800343958965, 5358.598105142208], "value": 4618.589853224088, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.8305957134219, 303.43106674984404, 314.7104663709387, 315.39916459145337, 317.48725807820364, 4606.769661516928, 5198.8700282492955, 5392.649477783946, 5423.593230507708, 5435.9728201358985, 5121.743912804354, 5333.786648534054, 5342.290467050545, 5344.91982620261, 5352.652077614952, 4076.4496792932873, 4120.835166593332, 4123.505179378479, 4128.60778226068, 4156.233600719697, 3935.704822138351, 4150.211459822639, 4159.646430053449, 4166.291730405823, 4176.807176232487, 3521.559805024372, 4033.415154802552, 4047.4215056128132, 4072.5112986441522, 4077.816498257571, 4088.713790034699, 4095.5904409559103, 4106.589341065899, 4108.157827645356, 4125.463689720347, 3108.984455077733, 3381.3575420669995, 3527.6295988921306, 3530.8058056806867, 3544.8404821783083, 4336.522982471925, 4845.539673731025, 4939.110620287136, 5216.817411390621, 5275.657082289677, 3930.508686414198, 4606.60843828275, 4677.95322046782, 4681.180793361148, 4708.293755936603, 4763.857084287477, 4797.472278049412, 4937.284093806414, 5388.545092824955, 5686.402927692616, 3213.586022150246, 3853.2678791029616, 3875.2830726315724, 3908.218356328746, 3912.5500567434824, 5102.770375333156, 5962.433568810956, 5993.190919225893, 6018.939810601925, 6023.139008970704, 3540.929181416357, 3711.3546095044294, 3944.3491823849317, 3946.1121247719034, 3949.940750888739, 3514.68367846895, 3968.5634580196156, 3999.2601368746873, 4015.8393664253103, 4019.799010049486, 4641.210994130938, 4657.951960808791, 4680.127948820499, 4692.6245900327895, 4699.297903705383, 5348.384935732441, 5371.865703357447, 5394.543473262197, 5744.684042377626, 5768.884622307531, 1183.5798291606527, 1200.4357951762659, 1281.4105511464645, 1338.1435881036145, 1359.3746876436949, 888.968796196409, 890.1499068389802, 907.8819753432011, 909.3725329522608, 910.6676063237003, 4601.722147536991, 5203.569018519937, 5205.427402985669, 5224.346956630421, 5232.659877107976, 5536.972315138399, 5740.09222115094, 5749.9712501437225, 5808.07070868659, 5876.1794338717655, 4406.691531592758, 4965.602751779849, 5218.6868787872845, 5260.579153667697, 5299.152135658276, 2293.346396277053, 2342.1919437793936, 2613.183837233105, 2625.411625966301, 2924.8245105293327, 5926.407359264039, 5953.970230148821, 5960.135780311831, 5966.793410645484, 5980.252468441445, 4811.639127065484, 4877.3167980870585, 4954.603631709455, 5766.94233057664, 5948.643081415129, 5167.870803229875, 5432.864178395493, 5901.769538133106, 5953.583249172516, 6044.758209671564, 4400.097979914174, 5230.227979171045, 5427.918581221206, 5543.556515478671, 5674.786833135163, 4629.884252893705, 4786.44955830084, 5787.565932554991, 5798.275215598051, 5819.272590926134, 5658.9151162733415, 6013.789517366871, 6028.246469191351, 6043.969780151071, 6062.726827366302, 3359.798412095282, 3651.744377893522, 3776.905577360588, 3914.6868250540456, 4099.651529620016, 5218.582513398995, 5550.642745444807, 5569.435561240728, 5866.384029676993, 5899.734511946886, 4807.663463557657, 4897.893905620617, 5378.3777040345, 5654.6889921054735, 5754.108113242454, 4185.853495127656, 5608.560276279832, 5632.930053121141, 5682.0, 5689.459501347365, 5114.4118426379955, 5524.309461317335, 5554.944670471951, 5581.832545023656, 5588.596903331444, 4715.151272770934, 5335.73321333924, 5664.037113690765, 5672.7163641816915, 5680.346760122474, 4583.793729282123, 4592.632589392773, 5442.6462279951675, 5543.667379957215, 5623.268975033219], "value": 4075.1171228132907, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.55566660004, 767.5816679909449, 773.8800485924684, 857.5369300577688, 864.9697260595881, 17739.55651108723, 18387.90806045968, 18409.44771656849, 18428.078596070176, 18455.538611534685, 17381.82618173818, 17533.12334383282, 17574.736378954327, 17596.20817063232, 17605.64788704225, 898.2006014646969, 925.2459245714721, 926.263620421763, 926.9721908342726, 927.8283517549276, 723.023917711091, 737.7196665267192, 836.9372297077713, 899.6986009686763, 903.2503022491317, 937.7093101138635, 939.7697564096806, 944.1738478831023, 945.2721404518517, 946.7775072857872, 736.7826491185114, 936.8360536906082, 937.7702462896566, 938.7090002099371, 939.304914363373, 700.9158900931869, 704.3344039882313, 707.5507053021317, 710.3535782438008, 711.0862541633976, 15844.970076944961, 15851.920740396225, 15857.286422111032, 15871.047737135808, 15892.364305427769, 13664.863351366459, 13847.0, 13862.93068534661, 14019.228942408163, 14265.144091354552, 12635.620931372074, 13043.41304641284, 13188.538401156009, 13189.802152967713, 13509.932450337685, 4595.885102872421, 4655.394798676184, 4739.028499157699, 4822.324874517571, 4828.782704778258, 105827.0, 107746.46126769394, 115068.0, 117956.41021794836, 118120.0, 11320.433978301216, 11360.886391136148, 11393.886061139448, 11421.600243991421, 11450.083993280517, 22610.886945565497, 22785.658215126787, 22786.0, 22831.31506054821, 22832.31503054911, 8169.305609023299, 8337.791555211046, 8353.624086915981, 8469.830603388022, 8474.788130296669, 4221.197972385237, 4435.756033418128, 4461.884528867783, 4537.56360371145, 4543.454785425704, 3576.123849656851, 3685.2076803486843, 3688.0, 3688.243909998444, 3692.48305237267, 643.4498503779201, 662.5858838226109, 673.3165836675778, 678.3792829560995, 680.9114815074108, 646.4860435953428, 684.3848517498699, 713.5718568858643, 716.9318914703199, 735.4447392218841, 9396.201322887631, 9716.611335546499, 9762.170215531618, 9833.606655733834, 9847.0, 658.3350815676106, 685.5509641185032, 692.4391243093111, 698.5284932670529, 706.0679902528613, 4239.96120950363, 4278.828846846153, 4696.1546921554445, 4709.67032307742, 4719.622430205575, 95926.0, 97282.5135874316, 140696.0, 157082.21458892632, 157732.0, 123774.0, 135437.87372757666, 137501.3124934389, 140998.0, 141544.0, 115120.84879151102, 116750.41624791991, 118182.40908795573, 119841.0, 120181.0, 7981.24178203093, 9136.132067453582, 9141.757562464027, 9150.63397464094, 9176.587053582738, 63697.68151159215, 64288.678556606916, 64833.35166648462, 66036.66981665061, 66344.0, 90913.63629545695, 95933.0, 100725.0, 101326.0, 103174.96825031654, 11689.88310116922, 11883.78351440261, 11936.343501107522, 12204.511819527119, 12303.876961230631, 12775.872241277468, 12905.0, 13394.263315517741, 13466.663333416744, 13481.393337300042, 10802.189835762507, 12335.198212116184, 12482.375881206084, 12494.500219991098, 12684.0, 12211.389430528616, 12358.196717213352, 12515.499380024698, 12786.360681965678, 13009.804852927024, 12274.693132671755, 12304.938475307566, 12371.257724536557, 12413.0, 12673.873261267268, 9448.858267125861, 10347.379157250589, 10485.790284194425, 10701.0, 10901.182411319025, 10524.631637892637, 10768.19238557127, 10769.192310576582, 10775.838362424727, 10800.45997700096], "value": 8022.79380872681, "unit": "score"}], "name": "dromaeo_css", "value": 7485.363073915548, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 113.0, 114.0, 114.0, 113.0, 113.0, 113.0, 114.0, 114.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 107.0, 105.0, 159.0, 104.0, 108.0, 127.0, 105.0, 141.0, 130.0], "value": 123.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 185.0, 183.0, 183.0, 186.0, 180.0, 181.0, 181.0, 181.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 58.0, 105.0, 57.0, 74.0, 85.0, 81.0, 83.0, 55.0], "value": 75.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 93.0, 70.0, 69.0, 68.0, 95.0, 70.0, 67.0, 99.0], "value": 80.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 131.0, 111.0, 88.0, 85.0, 88.0, 86.0, 89.0, 85.0], "value": 94.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 122.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 113.0, 92.0], "value": 101.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 86.0, 85.0, 86.0, 86.0, 87.0, 86.0, 86.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 55.0, 57.0, 56.0, 54.0, 54.0, 54.0, 53.0, 55.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 74.0, 78.0, 70.0, 73.0, 69.0, 71.0, 69.0, 72.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 83.0, 85.0, 81.0, 81.0, 80.0, 85.0, 86.0, 82.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 149.0, 143.0, 134.0, 133.0, 131.0, 134.0, 135.0, 177.0, 134.0], "value": 142.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 161.0, 161.0, 160.0, 161.0, 159.0, 189.0], "value": 163.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 66.0, 66.0, 65.0, 66.0, 65.0, 66.0, 63.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1434.7999999999997, "alertThreshold": 2.0}]} 19:32:23 INFO - Return code: 0 19:32:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:32:24 INFO - # TBPL SUCCESS # 19:32:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:32:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:32:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:32:24 INFO - 2016-07-07 19:32:24.059 screenresolution[1933:10793] starting screenresolution argv=screenresolution get 19:32:24 INFO - 2016-07-07 19:32:24.061 screenresolution[1933:10793] Display 0: 1600x1200x32@60 19:32:24 INFO - 2016-07-07 19:32:24.067 screenresolution[1934:10796] starting screenresolution argv=screenresolution list 19:32:24 INFO - Available Modes on Display 0 19:32:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:32:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:32:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:32:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:32:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:32:24 INFO - Intel Iris: 19:32:24 INFO - Chipset Model: Intel Iris 19:32:24 INFO - Type: GPU 19:32:24 INFO - Bus: Built-In 19:32:24 INFO - VRAM (Dynamic, Max): 1536 MB 19:32:24 INFO - Vendor: Intel (0x8086) 19:32:24 INFO - Device ID: 0x0a2e 19:32:24 INFO - Revision ID: 0x0009 19:32:24 INFO - Displays: 19:32:24 INFO - Display: 19:32:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:32:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:32:24 INFO - Display Serial Number: 1600x1200 60 19:32:24 INFO - Main Display: Yes 19:32:24 INFO - Mirror: Off 19:32:24 INFO - Online: Yes 19:32:24 INFO - Rotation: Supported 19:32:24 INFO - Return code: 0 19:32:24 INFO - Running post-action listener: _resource_record_post_action 19:32:24 INFO - ##### 19:32:24 INFO - ##### Finished run-tests step (success) 19:32:24 INFO - ##### 19:32:24 INFO - Running post-run listener: _resource_record_post_run 19:32:24 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 1884160; Write bytes: 702607872; Read time: 18; Write time: 1940 19:32:24 INFO - TinderboxPrint: CPU usage
53.5% 19:32:24 INFO - TinderboxPrint: I/O read bytes / time
1,884,160 / 18 19:32:24 INFO - TinderboxPrint: I/O write bytes / time
702,607,872 / 1,940 19:32:24 INFO - TinderboxPrint: CPU idle
2,250.3 (46.5%) 19:32:24 INFO - TinderboxPrint: CPU system
967.3 (20.0%) 19:32:24 INFO - TinderboxPrint: CPU user
1,620.0 (33.5%) 19:32:24 INFO - TinderboxPrint: Swap in / out
409,206,784 / 0 19:32:24 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 394365440; Write bytes: 388429312; Read time: 29106; Write time: 817 19:32:24 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 1048576; Write bytes: 292408320; Read time: 16; Write time: 1071 19:32:24 INFO - Running post-run listener: _upload_blobber_files 19:32:24 INFO - Blob upload gear active. 19:32:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:32:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:32:24 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'] 19:32:24 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 19:32:25 INFO - (blobuploader) - INFO - Open directory for files ... 19:32:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:32:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:32:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:26 INFO - (blobuploader) - INFO - Done attempting. 19:32:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:32:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:32:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:27 INFO - (blobuploader) - INFO - Done attempting. 19:32:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:32:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:32:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:30 INFO - (blobuploader) - INFO - Done attempting. 19:32:30 INFO - (blobuploader) - INFO - Iteration through files over. 19:32:30 INFO - Return code: 0 19:32:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:32:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:32:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a779a29d013973cf5a51231d26bb5a7affd5203f47ffaccdcc90fa1f7f97b24a5709dc2896de21c37404759e2d9b0e4458c5a2f05d7d7acba0532123a06364b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a6d94d10567af95f9aadcc94bb7595b514ee78f9a5a8fabb49eeb0c0b6967e5e771547a34f850396e00c64389f145f57c2121718112d5a52ad6a8d5a4608521", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b07d43e7031419728fc51924d315e0100ba96ef7fa30e6103fad8022ef2b3436ed0317935b71c21b72d6e5653346cdcdb7ff3aa77d726292a4f8a7acf59b010"} 19:32:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:32:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:32:30 INFO - Contents: 19:32:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a779a29d013973cf5a51231d26bb5a7affd5203f47ffaccdcc90fa1f7f97b24a5709dc2896de21c37404759e2d9b0e4458c5a2f05d7d7acba0532123a06364b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a6d94d10567af95f9aadcc94bb7595b514ee78f9a5a8fabb49eeb0c0b6967e5e771547a34f850396e00c64389f145f57c2121718112d5a52ad6a8d5a4608521", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b07d43e7031419728fc51924d315e0100ba96ef7fa30e6103fad8022ef2b3436ed0317935b71c21b72d6e5653346cdcdb7ff3aa77d726292a4f8a7acf59b010"} 19:32:30 INFO - Running post-run listener: copy_logs_to_upload_dir 19:32:30 INFO - Copying logs to upload dir... 19:32:30 INFO - mkdir: /builds/slave/test/build/upload/logs 19:32:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.327830 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-07-07 19:32:30.076819) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 19:32:30.080421) ========= 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.y7acSPNGKO/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.yKKwhjdiGW/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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/a779a29d013973cf5a51231d26bb5a7affd5203f47ffaccdcc90fa1f7f97b24a5709dc2896de21c37404759e2d9b0e4458c5a2f05d7d7acba0532123a06364b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a6d94d10567af95f9aadcc94bb7595b514ee78f9a5a8fabb49eeb0c0b6967e5e771547a34f850396e00c64389f145f57c2121718112d5a52ad6a8d5a4608521", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b07d43e7031419728fc51924d315e0100ba96ef7fa30e6103fad8022ef2b3436ed0317935b71c21b72d6e5653346cdcdb7ff3aa77d726292a4f8a7acf59b010"} build_url:https://queue.taskcluster.net/v1/task/IKiddsF-SAKcnvjXfyhYtQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011048 build_url: 'https://queue.taskcluster.net/v1/task/IKiddsF-SAKcnvjXfyhYtQ/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/a779a29d013973cf5a51231d26bb5a7affd5203f47ffaccdcc90fa1f7f97b24a5709dc2896de21c37404759e2d9b0e4458c5a2f05d7d7acba0532123a06364b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a6d94d10567af95f9aadcc94bb7595b514ee78f9a5a8fabb49eeb0c0b6967e5e771547a34f850396e00c64389f145f57c2121718112d5a52ad6a8d5a4608521", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b07d43e7031419728fc51924d315e0100ba96ef7fa30e6103fad8022ef2b3436ed0317935b71c21b72d6e5653346cdcdb7ff3aa77d726292a4f8a7acf59b010"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 19:32:30.113498) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 19:32:30.113961) ========= 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.y7acSPNGKO/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.yKKwhjdiGW/Listeners TMPDIR=/var/folders/pk/mm46htfn0fj4m947xrvc5yh400000w/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.004472 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 19:32:30.163289) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 19:32:30.163645) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 19:32:30.163991) ========= ========= Total master_lag: 0.17 =========