builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0057 starttime: 1468861866.14 results: success (0) buildid: 20160718080700 builduid: 858cb703baf440e88d6a5ac4edc3b256 revision: 279f4307531af445827f59f6706f2c245403dcae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 10:11:06.144507) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 10:11:06.144955) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 10:11:06.145231) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 10:11:06.169406) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:11:06.169769) ========= 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.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners TMPDIR=/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-18 10:11:06-- 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% 8.37M=0.001s 2016-07-18 10:11:06 (8.37 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105874 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:11:06.297422) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:11:06.297733) ========= 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.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners TMPDIR=/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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.025123 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:11:06.346844) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 10:11:06.347233) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 279f4307531af445827f59f6706f2c245403dcae --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 279f4307531af445827f59f6706f2c245403dcae --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners TMPDIR=/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-18 10:11:06,421 truncating revision to first 12 chars 2016-07-18 10:11:06,421 Setting DEBUG logging. 2016-07-18 10:11:06,421 attempt 1/10 2016-07-18 10:11:06,421 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/279f4307531a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 10:11:07,710 unpacking tar archive at: mozilla-central-279f4307531a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.561497 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 10:11:07.931024) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 10:11:07.931375) ========= script_repo_revision: 279f4307531af445827f59f6706f2c245403dcae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 10:11:07.931784) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 10:11:07.932144) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 10:11:07.954451) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-18 10:11:07.954928) ========= /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.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners TMPDIR=/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 10:11:08 INFO - MultiFileLogger online at 20160718 10:11:08 in /builds/slave/test 10:11:08 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 10:11:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:11:08 INFO - {'append_to_log': False, 10:11:08 INFO - 'base_work_dir': '/builds/slave/test', 10:11:08 INFO - 'blob_upload_branch': 'Firefox', 10:11:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:11:08 INFO - 'branch': 'Firefox', 10:11:08 INFO - 'buildbot_json_path': 'buildprops.json', 10:11:08 INFO - 'config_files': ('talos/mac_config.py',), 10:11:08 INFO - 'default_actions': ('clobber', 10:11:08 INFO - 'read-buildbot-config', 10:11:08 INFO - 'download-and-extract', 10:11:08 INFO - 'populate-webroot', 10:11:08 INFO - 'create-virtualenv', 10:11:08 INFO - 'install', 10:11:08 INFO - 'run-tests'), 10:11:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:11:08 INFO - 'download_minidump_stackwalk': True, 10:11:08 INFO - 'download_symbols': 'ondemand', 10:11:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:11:08 INFO - 'tooltool.py': '/tools/tooltool.py', 10:11:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:11:08 INFO - '/tools/misc-python/virtualenv.py')}, 10:11:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:11:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:11:08 INFO - 'installer_path': 'installer.exe', 10:11:08 INFO - 'log_level': 'info', 10:11:08 INFO - 'log_name': 'talos', 10:11:08 INFO - 'log_to_console': True, 10:11:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:11:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:11:08 INFO - 'opt_config_files': (), 10:11:08 INFO - 'pip_index': False, 10:11:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:11:08 INFO - 'cmd': ('bash', 10:11:08 INFO - '-c', 10:11:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:11:08 INFO - 'enabled': True, 10:11:08 INFO - 'halt_on_failure': False, 10:11:08 INFO - 'name': 'check_screen_resolution'},), 10:11:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:11:08 INFO - 'cmd': ('bash', 10:11:08 INFO - '-c', 10:11:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:11:08 INFO - 'enabled': True, 10:11:08 INFO - 'halt_on_failure': False, 10:11:08 INFO - 'name': 'check_screen_resolution'},), 10:11:08 INFO - 'run_cmd_checks_enabled': True, 10:11:08 INFO - 'sps_profile': False, 10:11:08 INFO - 'sps_profile_interval': 0, 10:11:08 INFO - 'suite': 'dromaeojs', 10:11:08 INFO - 'system_bits': '32', 10:11:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:11:08 INFO - 'title': 't-yosemite-r7-0057', 10:11:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:11:08 INFO - 'use_talos_json': True, 10:11:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:11:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:11:08 INFO - 'work_dir': 'build'} 10:11:08 INFO - ##### 10:11:08 INFO - ##### Running clobber step. 10:11:08 INFO - ##### 10:11:08 INFO - Running pre-action listener: _resource_record_pre_action 10:11:08 INFO - Running main action method: clobber 10:11:08 INFO - rmtree: /builds/slave/test/build 10:11:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:11:08 INFO - Running post-action listener: _resource_record_post_action 10:11:08 INFO - ##### 10:11:08 INFO - ##### Finished clobber step (success) 10:11:08 INFO - ##### 10:11:08 INFO - ##### 10:11:08 INFO - ##### Running read-buildbot-config step. 10:11:08 INFO - ##### 10:11:08 INFO - Running pre-action listener: _resource_record_pre_action 10:11:08 INFO - Running main action method: read_buildbot_config 10:11:08 INFO - Using buildbot properties: 10:11:08 INFO - { 10:11:08 INFO - "project": "", 10:11:08 INFO - "product": "firefox", 10:11:08 INFO - "script_repo_revision": "production", 10:11:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:11:08 INFO - "repository": "", 10:11:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:11:08 INFO - "buildid": "20160718080700", 10:11:08 INFO - "pgo_build": "False", 10:11:08 INFO - "basedir": "/builds/slave/test", 10:11:08 INFO - "buildnumber": 1, 10:11:08 INFO - "slavename": "t-yosemite-r7-0057", 10:11:08 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:11:08 INFO - "platform": "yosemite_r7", 10:11:08 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:11:08 INFO - "branch": "mozilla-central", 10:11:08 INFO - "repo_path": "mozilla-central", 10:11:08 INFO - "revision": "279f4307531af445827f59f6706f2c245403dcae", 10:11:08 INFO - "stage_platform": "macosx64", 10:11:08 INFO - "builduid": "858cb703baf440e88d6a5ac4edc3b256", 10:11:08 INFO - "slavebuilddir": "test" 10:11:08 INFO - } 10:11:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 10:11:08 INFO - Running post-action listener: _resource_record_post_action 10:11:08 INFO - ##### 10:11:08 INFO - ##### Finished read-buildbot-config step (success) 10:11:08 INFO - ##### 10:11:08 INFO - ##### 10:11:08 INFO - ##### Running download-and-extract step. 10:11:08 INFO - ##### 10:11:08 INFO - Running pre-action listener: _resource_record_pre_action 10:11:08 INFO - Running main action method: download_and_extract 10:11:08 INFO - mkdir: /builds/slave/test/build/tests 10:11: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')]} 10:11:08 INFO - https://queue.taskcluster.net/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:11:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:11:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:11:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/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 10:11:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/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 10:11:10 INFO - Downloaded 1199 bytes. 10:11:10 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:11:10 INFO - Using the following test package requirements: 10:11:10 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:11:10 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:11:10 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 10:11:10 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:11:10 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 10:11:10 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:11:10 INFO - u'jsshell-mac.zip'], 10:11:10 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:11:10 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 10:11:10 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:11:10 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:11:10 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 10:11:10 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:11:10 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 10:11:10 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:11:10 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 10:11:10 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:11:10 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 10:11:10 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:11:10 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')]} 10:11:10 INFO - https://queue.taskcluster.net/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:11:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:11:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:11:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/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 10:11:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/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 10:11:12 INFO - Downloaded 18835320 bytes. 10:11:12 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'] 10:11:12 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 10:11:15 INFO - Return code: 0 10:11:15 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 10:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:11:15 INFO - https://queue.taskcluster.net/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:11:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:11:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:11:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/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 10:11:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/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 10:11:16 INFO - Downloaded 18835320 bytes. 10:11:16 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'] 10:11:16 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 10:11:20 INFO - Return code: 0 10:11:20 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')]} 10:11:20 INFO - https://queue.taskcluster.net/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:11:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:11:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:11:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/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 10:11:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/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 10:11:21 INFO - Downloaded 14403317 bytes. 10:11:21 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'] 10:11:21 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 10:11:21 INFO - Return code: 0 10:11:21 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')]} 10:11:21 INFO - https://queue.taskcluster.net/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:11:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:11:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:11:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 10:11:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N0nih6YsTGama-aLQ0cFhQ/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 10:11:24 INFO - Downloaded 112834747 bytes. 10:11:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:11:24 INFO - mkdir: /builds/slave/test/properties 10:11:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:11:24 INFO - Writing to file /builds/slave/test/properties/build_url 10:11:24 INFO - Contents: 10:11:24 INFO - build_url:https://queue.taskcluster.net/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:11:26 INFO - Running post-action listener: _resource_record_post_action 10:11:26 INFO - Running post-action listener: set_extra_try_arguments 10:11:26 INFO - ##### 10:11:26 INFO - ##### Finished download-and-extract step (success) 10:11:26 INFO - ##### 10:11:26 INFO - ##### 10:11:26 INFO - ##### Running populate-webroot step. 10:11:26 INFO - ##### 10:11:26 INFO - Running pre-action listener: _resource_record_pre_action 10:11:26 INFO - Running main action method: populate_webroot 10:11:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:11:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:11:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:11:26 INFO - '--tppagecycles', 10:11:26 INFO - '7'], 10:11:26 INFO - 'tests': ['tsvgm']}}, 10:11:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:11:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:11:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:11:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:11:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:11:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:11:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:26 INFO - 'talos_options': ['--disable-e10s'], 10:11:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:11:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:11:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:26 INFO - 'talos_options': ['--disable-e10s'], 10:11:26 INFO - 'tests': ['damp', 'tps']}, 10:11:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:26 INFO - 'tests': ['damp', 'tps']}, 10:11:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:11:26 INFO - 'tests': ['dromaeo_dom']}, 10:11:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:11:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:11:26 INFO - 'tests': ['basic_compositor_video']}, 10:11:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:11:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:11:26 INFO - 'tests': ['a11yr', 10:11:26 INFO - 'ts_paint', 10:11:26 INFO - 'tpaint', 10:11:26 INFO - 'sessionrestore', 10:11:26 INFO - 'sessionrestore_no_auto_restore', 10:11:26 INFO - 'tabpaint']}, 10:11:26 INFO - 'other-e10s': {'tests': ['a11yr', 10:11:26 INFO - 'ts_paint', 10:11:26 INFO - 'tpaint', 10:11:26 INFO - 'sessionrestore', 10:11:26 INFO - 'sessionrestore_no_auto_restore', 10:11:26 INFO - 'tabpaint']}, 10:11:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:11:26 INFO - 'ts_paint', 10:11:26 INFO - 'tpaint', 10:11:26 INFO - 'sessionrestore', 10:11:26 INFO - 'sessionrestore_no_auto_restore', 10:11:26 INFO - 'tabpaint']}, 10:11:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:11:26 INFO - 'ts_paint', 10:11:26 INFO - 'tpaint', 10:11:26 INFO - 'sessionrestore', 10:11:26 INFO - 'sessionrestore_no_auto_restore', 10:11:26 INFO - 'tabpaint']}, 10:11:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:11:26 INFO - 'tests': ['a11yr', 10:11:26 INFO - 'ts_paint', 10:11:26 INFO - 'tpaint', 10:11:26 INFO - 'sessionrestore', 10:11:26 INFO - 'sessionrestore_no_auto_restore', 10:11:26 INFO - 'tabpaint']}, 10:11:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:11:26 INFO - 'tests': ['a11yr', 10:11:26 INFO - 'ts_paint', 10:11:26 INFO - 'tpaint', 10:11:26 INFO - 'sessionrestore', 10:11:26 INFO - 'sessionrestore_no_auto_restore', 10:11:26 INFO - 'tabpaint']}, 10:11:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:11:26 INFO - 'tests': ['tsvgx', 10:11:26 INFO - 'tsvgr_opacity', 10:11:26 INFO - 'tart', 10:11:26 INFO - 'tscrollx', 10:11:26 INFO - 'cart']}, 10:11:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:11:26 INFO - 'tsvgr_opacity', 10:11:26 INFO - 'tart', 10:11:26 INFO - 'tscrollx', 10:11:26 INFO - 'cart']}, 10:11:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:26 INFO - 'talos_options': ['--disable-e10s'], 10:11:26 INFO - 'tests': ['tp5o']}, 10:11:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:26 INFO - 'tests': ['tp5o']}, 10:11:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:26 INFO - 'talos_options': ['--disable-e10s', 10:11:26 INFO - '--xperf_path', 10:11:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:11:26 INFO - 'tests': ['tp5n']}, 10:11:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:11:26 INFO - 'talos_options': ['--xperf_path', 10:11:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:11:26 INFO - 'tests': ['tp5n']}}, 10:11:26 INFO - 'talos.zip': {'path': '', 10:11:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:11:26 INFO - Running post-action listener: _resource_record_post_action 10:11:26 INFO - ##### 10:11:26 INFO - ##### Finished populate-webroot step (success) 10:11:26 INFO - ##### 10:11:26 INFO - ##### 10:11:26 INFO - ##### Running create-virtualenv step. 10:11:26 INFO - ##### 10:11:26 INFO - Running pre-action listener: _resource_record_pre_action 10:11:26 INFO - Running main action method: create_virtualenv 10:11:26 INFO - Creating virtualenv /builds/slave/test/build/venv 10:11:26 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 10:11:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:11:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:11:26 INFO - Using real prefix '/tools/python27' 10:11:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:11:27 INFO - Installing distribute.............................................................................................................................................................................................done. 10:11:30 INFO - Installing pip.................done. 10:11:30 INFO - Return code: 0 10:11:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:11:30 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')]} 10:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:30 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')]} 10:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:30 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 0x10db0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3148f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da72d30>, '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 0x10d7d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da6ab20>, '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.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 10:11:30 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 10:11:30 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 10:11:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kswAVs0NKZ/Render', 10:11:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:30 INFO - 'HOME': '/Users/cltbld', 10:11:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:30 INFO - 'LOGNAME': 'cltbld', 10:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:30 INFO - 'MOZ_NO_REMOTE': '1', 10:11:30 INFO - 'NO_EM_RESTART': '1', 10:11:30 INFO - 'PAGER': '/bin/cat', 10:11:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:30 INFO - 'PWD': '/builds/slave/test', 10:11:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:30 INFO - 'SHELL': '/bin/bash', 10:11:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pq2Yt9ZauA/Listeners', 10:11:30 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 10:11:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:30 INFO - 'USER': 'cltbld', 10:11:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:30 INFO - 'XPC_FLAGS': '0x0', 10:11:30 INFO - 'XPC_SERVICE_NAME': '0', 10:11:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:30 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 10:11:30 INFO - Cleaning up... 10:11:30 INFO - Return code: 0 10:11:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:11:30 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')]} 10:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:30 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')]} 10:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:30 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 0x10db0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3148f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da72d30>, '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 0x10d7d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da6ab20>, '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.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 10:11:30 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 10:11:30 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 10:11:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kswAVs0NKZ/Render', 10:11:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:30 INFO - 'HOME': '/Users/cltbld', 10:11:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:30 INFO - 'LOGNAME': 'cltbld', 10:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:30 INFO - 'MOZ_NO_REMOTE': '1', 10:11:30 INFO - 'NO_EM_RESTART': '1', 10:11:30 INFO - 'PAGER': '/bin/cat', 10:11:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:30 INFO - 'PWD': '/builds/slave/test', 10:11:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:30 INFO - 'SHELL': '/bin/bash', 10:11:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pq2Yt9ZauA/Listeners', 10:11:30 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 10:11:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:30 INFO - 'USER': 'cltbld', 10:11:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:30 INFO - 'XPC_FLAGS': '0x0', 10:11:30 INFO - 'XPC_SERVICE_NAME': '0', 10:11:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:30 INFO - Downloading/unpacking psutil>=3.1.1 10:11:30 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 10:11:30 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 10:11:30 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 10:11:30 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 10:11:30 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 10:11:30 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 10:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:11:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:11:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:11:33 INFO - Installing collected packages: psutil 10:11:33 INFO - Running setup.py install for psutil 10:11:33 INFO - building 'psutil._psutil_osx' extension 10:11:33 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 10:11:34 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 10:11:34 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 10:11:34 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 10:11:34 INFO - building 'psutil._psutil_posix' extension 10:11:34 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 10:11:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:11:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:11:34 INFO - ^ 10:11:34 INFO - 1 warning generated. 10:11:34 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 10:11:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:11:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:11:34 INFO - Successfully installed psutil 10:11:34 INFO - Cleaning up... 10:11:34 INFO - Return code: 0 10:11:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10db0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3148f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da72d30>, '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 0x10d7d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da6ab20>, '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.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 10:11:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:11:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kswAVs0NKZ/Render', 10:11:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:34 INFO - 'HOME': '/Users/cltbld', 10:11:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:34 INFO - 'LOGNAME': 'cltbld', 10:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:34 INFO - 'MOZ_NO_REMOTE': '1', 10:11:34 INFO - 'NO_EM_RESTART': '1', 10:11:34 INFO - 'PAGER': '/bin/cat', 10:11:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:34 INFO - 'PWD': '/builds/slave/test', 10:11:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:34 INFO - 'SHELL': '/bin/bash', 10:11:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pq2Yt9ZauA/Listeners', 10:11:34 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 10:11:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:34 INFO - 'USER': 'cltbld', 10:11:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:34 INFO - 'XPC_FLAGS': '0x0', 10:11:34 INFO - 'XPC_SERVICE_NAME': '0', 10:11:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:11:37 INFO - Installing collected packages: mozsystemmonitor 10:11:37 INFO - Running setup.py install for mozsystemmonitor 10:11:37 INFO - Successfully installed mozsystemmonitor 10:11:37 INFO - Cleaning up... 10:11:37 INFO - Return code: 0 10:11:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10db0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3148f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da72d30>, '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 0x10d7d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da6ab20>, '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.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 10:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:11:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kswAVs0NKZ/Render', 10:11:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:37 INFO - 'HOME': '/Users/cltbld', 10:11:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:37 INFO - 'LOGNAME': 'cltbld', 10:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:37 INFO - 'MOZ_NO_REMOTE': '1', 10:11:37 INFO - 'NO_EM_RESTART': '1', 10:11:37 INFO - 'PAGER': '/bin/cat', 10:11:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:37 INFO - 'PWD': '/builds/slave/test', 10:11:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:37 INFO - 'SHELL': '/bin/bash', 10:11:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pq2Yt9ZauA/Listeners', 10:11:37 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 10:11:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:37 INFO - 'USER': 'cltbld', 10:11:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:37 INFO - 'XPC_FLAGS': '0x0', 10:11:37 INFO - 'XPC_SERVICE_NAME': '0', 10:11:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:37 INFO - Downloading/unpacking jsonschema==2.5.1 10:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:11:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:40 INFO - Downloading functools32-3.2.3-2.tar.gz 10:11:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:11:40 INFO - warning: no files found matching '*.txt' 10:11:40 INFO - no previously-included directories found matching 'build' 10:11:40 INFO - no previously-included directories found matching 'dist' 10:11:40 INFO - no previously-included directories found matching '.git*' 10:11:40 INFO - Installing collected packages: jsonschema, functools32 10:11:40 INFO - Running setup.py install for functools32 10:11:40 INFO - warning: no files found matching '*.txt' 10:11:40 INFO - no previously-included directories found matching 'build' 10:11:40 INFO - no previously-included directories found matching 'dist' 10:11:40 INFO - no previously-included directories found matching '.git*' 10:11:40 INFO - Successfully installed jsonschema functools32 10:11:40 INFO - Cleaning up... 10:11:40 INFO - Return code: 0 10:11:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10: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')]} 10:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10: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')]} 10:11:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10db0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3148f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da72d30>, '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 0x10d7d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da6ab20>, '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.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 10:11:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:11:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:11:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kswAVs0NKZ/Render', 10:11:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:40 INFO - 'HOME': '/Users/cltbld', 10:11:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:40 INFO - 'LOGNAME': 'cltbld', 10:11:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:40 INFO - 'MOZ_NO_REMOTE': '1', 10:11:40 INFO - 'NO_EM_RESTART': '1', 10:11:40 INFO - 'PAGER': '/bin/cat', 10:11:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:40 INFO - 'PWD': '/builds/slave/test', 10:11:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:40 INFO - 'SHELL': '/bin/bash', 10:11:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pq2Yt9ZauA/Listeners', 10:11:40 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 10:11:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:40 INFO - 'USER': 'cltbld', 10:11:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:40 INFO - 'XPC_FLAGS': '0x0', 10:11:40 INFO - 'XPC_SERVICE_NAME': '0', 10:11:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:41 INFO - Downloading/unpacking blobuploader==1.2.4 10:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:43 INFO - Downloading blobuploader-1.2.4.tar.gz 10:11:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:11:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:11:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:11:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:11:44 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 10:11:44 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 10:11:44 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 10:11:44 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 10:11:44 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 10:11:44 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 10:11:44 INFO - Downloading docopt-0.6.1.tar.gz 10:11:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:11:44 INFO - Installing collected packages: blobuploader, requests, docopt 10:11:44 INFO - Running setup.py install for blobuploader 10:11:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:11:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:11:45 INFO - Running setup.py install for requests 10:11:45 INFO - Running setup.py install for docopt 10:11:45 INFO - Successfully installed blobuploader requests docopt 10:11:45 INFO - Cleaning up... 10:11:45 INFO - Return code: 0 10:11:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:11:45 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')]} 10:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:45 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')]} 10:11:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:45 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 0x10db0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3148f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da72d30>, '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 0x10d7d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da6ab20>, '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.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 10:11:45 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 10:11:45 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 10:11:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kswAVs0NKZ/Render', 10:11:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:45 INFO - 'HOME': '/Users/cltbld', 10:11:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:45 INFO - 'LOGNAME': 'cltbld', 10:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:45 INFO - 'MOZ_NO_REMOTE': '1', 10:11:45 INFO - 'NO_EM_RESTART': '1', 10:11:45 INFO - 'PAGER': '/bin/cat', 10:11:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:45 INFO - 'PWD': '/builds/slave/test', 10:11:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:45 INFO - 'SHELL': '/bin/bash', 10:11:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pq2Yt9ZauA/Listeners', 10:11:45 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 10:11:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:45 INFO - 'USER': 'cltbld', 10:11:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:45 INFO - 'XPC_FLAGS': '0x0', 10:11:45 INFO - 'XPC_SERVICE_NAME': '0', 10:11:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:11:46 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-mYrImH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:11:46 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-rpmqWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:11:46 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-wTrp34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:11:46 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-fVmgmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:11:46 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-VMR697-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:11:46 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-ZNGmyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:11:46 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-Fg5pIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:11:46 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-uVXshI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:11:46 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-3IjoBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:11:46 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-veMhfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:11:47 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-oYqUKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:11:47 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-Nlb6Q9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:11:47 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-xH_bWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:11:47 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-Gczjd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:47 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-nVxHdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:11:47 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-tT2ivf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:11:47 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-5vEJ8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:11:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:11:47 INFO - Running setup.py install for manifestparser 10:11:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:11:47 INFO - Running setup.py install for mozcrash 10:11:48 INFO - Running setup.py install for mozdebug 10:11:48 INFO - Running setup.py install for mozdevice 10:11:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:11:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:11:48 INFO - Running setup.py install for mozfile 10:11:48 INFO - Running setup.py install for mozhttpd 10:11:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:11:48 INFO - Running setup.py install for mozinfo 10:11:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:11:48 INFO - Running setup.py install for mozInstall 10:11:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:11:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:11:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:11:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:11:48 INFO - Running setup.py install for mozleak 10:11:49 INFO - Running setup.py install for mozlog 10:11:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:11:49 INFO - Running setup.py install for moznetwork 10:11:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:11:49 INFO - Running setup.py install for mozprocess 10:11:49 INFO - Running setup.py install for mozprofile 10:11:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:11:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:11:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:11:49 INFO - Running setup.py install for mozrunner 10:11:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:11:49 INFO - Running setup.py install for mozscreenshot 10:11:50 INFO - Running setup.py install for moztest 10:11:50 INFO - Running setup.py install for mozversion 10:11:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:11:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:11:50 INFO - Cleaning up... 10:11:50 INFO - Return code: 0 10:11:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:50 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 0x10db0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3148f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da72d30>, '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 0x10d7d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da6ab20>, '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.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 10:11:50 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 10:11:50 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 10:11:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kswAVs0NKZ/Render', 10:11:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:50 INFO - 'HOME': '/Users/cltbld', 10:11:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:50 INFO - 'LOGNAME': 'cltbld', 10:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:50 INFO - 'MOZ_NO_REMOTE': '1', 10:11:50 INFO - 'NO_EM_RESTART': '1', 10:11:50 INFO - 'PAGER': '/bin/cat', 10:11:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:50 INFO - 'PWD': '/builds/slave/test', 10:11:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:50 INFO - 'SHELL': '/bin/bash', 10:11:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pq2Yt9ZauA/Listeners', 10:11:50 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 10:11:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:50 INFO - 'USER': 'cltbld', 10:11:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:50 INFO - 'XPC_FLAGS': '0x0', 10:11:50 INFO - 'XPC_SERVICE_NAME': '0', 10:11:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:11:50 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-Ary4Jy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:11:50 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)) 10:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:11:50 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-a2CMeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:11:50 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)) 10:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:11:50 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-_FrYwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:11:50 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)) 10:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:11:50 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-_ABl49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:11:51 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)) 10:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:11:51 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-ooxzeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:11:51 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)) 10:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:11:51 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-jodcuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:11:51 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)) 10:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:11:51 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-8ooXME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:11:51 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)) 10:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:11:51 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-BhfY_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:11:51 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)) 10:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:11:51 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-kVQrVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:11:51 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)) 10:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:11:51 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-aI8kt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:11:51 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)) 10:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:11:51 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-rOsHkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:11:51 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)) 10:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:11:51 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-4AqGn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:11:51 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)) 10:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:11:51 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-tCpOht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:11:52 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)) 10:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:11:52 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-87ZBjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:11:52 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)) 10:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:52 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-9bPhmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:52 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)) 10:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:11:52 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-GTFNVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:11:52 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)) 10:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:11:52 INFO - Running setup.py (path:/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/pip-y5100g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:11:52 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)) 10:11:52 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)) 10:11:52 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)) 10:11:52 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)) 10:11:52 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)) 10:11:52 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)) 10:11:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10: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 10: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 10: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 10: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 10: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 10: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 10:11:54 INFO - Downloading blessings-1.6.tar.gz 10:11:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:11:55 INFO - Installing collected packages: blessings 10:11:55 INFO - Running setup.py install for blessings 10:11:55 INFO - Successfully installed blessings 10:11:55 INFO - Cleaning up... 10:11:55 INFO - Return code: 0 10:11:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:11:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:11:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:11:55 INFO - Reading from file tmpfile_stdout 10:11:55 INFO - Current package versions: 10:11:55 INFO - blessings == 1.6 10:11:55 INFO - blobuploader == 1.2.4 10:11:55 INFO - docopt == 0.6.1 10:11:55 INFO - functools32 == 3.2.3-2 10:11:55 INFO - jsonschema == 2.5.1 10:11:55 INFO - manifestparser == 1.1 10:11:55 INFO - mozInstall == 1.12 10:11:55 INFO - mozcrash == 0.17 10:11:55 INFO - mozdebug == 0.1 10:11:55 INFO - mozdevice == 0.48 10:11:55 INFO - mozfile == 1.2 10:11:55 INFO - mozhttpd == 0.7 10:11:55 INFO - mozinfo == 0.9 10:11:55 INFO - mozleak == 0.1 10:11:55 INFO - mozlog == 3.2 10:11:55 INFO - moznetwork == 0.27 10:11:55 INFO - mozprocess == 0.23 10:11:55 INFO - mozprofile == 0.28 10:11:55 INFO - mozrunner == 6.12 10:11:55 INFO - mozscreenshot == 0.1 10:11:55 INFO - mozsystemmonitor == 0.3 10:11:55 INFO - moztest == 0.7 10:11:55 INFO - mozversion == 1.4 10:11:55 INFO - psutil == 3.1.1 10:11:55 INFO - requests == 1.2.3 10:11:55 INFO - wsgiref == 0.1.2 10:11:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10: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')]} 10:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10: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')]} 10:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:55 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 0x10db0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3148f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da72d30>, '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 0x10d7d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da6ab20>, '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.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 10:11:55 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 10:11:55 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 10:11:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kswAVs0NKZ/Render', 10:11:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:55 INFO - 'HOME': '/Users/cltbld', 10:11:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:55 INFO - 'LOGNAME': 'cltbld', 10:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:55 INFO - 'MOZ_NO_REMOTE': '1', 10:11:55 INFO - 'NO_EM_RESTART': '1', 10:11:55 INFO - 'PAGER': '/bin/cat', 10:11:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:55 INFO - 'PWD': '/builds/slave/test', 10:11:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:55 INFO - 'SHELL': '/bin/bash', 10:11:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pq2Yt9ZauA/Listeners', 10:11:55 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 10:11:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:55 INFO - 'USER': 'cltbld', 10:11:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:55 INFO - 'XPC_FLAGS': '0x0', 10:11:55 INFO - 'XPC_SERVICE_NAME': '0', 10:11:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:55 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)) 10:11:55 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)) 10:11:55 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)) 10:11:55 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)) 10:11:55 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)) 10:11:55 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)) 10:11:55 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)) 10:11:55 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)) 10:11:55 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)) 10:11:55 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)) 10:11:55 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)) 10:11:55 INFO - Cleaning up... 10:11:55 INFO - Return code: 0 10:11:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10: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')]} 10:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10: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')]} 10:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10db0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dba3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad3148f630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da72d30>, '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 0x10d7d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da6ab20>, '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.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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 10: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'] in /builds/slave/test/build 10: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 10:11:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kswAVs0NKZ/Render', 10:11:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:55 INFO - 'HOME': '/Users/cltbld', 10:11:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:55 INFO - 'LOGNAME': 'cltbld', 10:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:55 INFO - 'MOZ_NO_REMOTE': '1', 10:11:55 INFO - 'NO_EM_RESTART': '1', 10:11:55 INFO - 'PAGER': '/bin/cat', 10:11:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:55 INFO - 'PWD': '/builds/slave/test', 10:11:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:55 INFO - 'SHELL': '/bin/bash', 10:11:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pq2Yt9ZauA/Listeners', 10:11:55 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 10:11:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:55 INFO - 'USER': 'cltbld', 10:11:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:55 INFO - 'XPC_FLAGS': '0x0', 10:11:55 INFO - 'XPC_SERVICE_NAME': '0', 10:11:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:11:56 INFO - Cleaning up... 10:11:56 INFO - Return code: 0 10:11:56 INFO - Running post-action listener: _resource_record_post_action 10:11:56 INFO - Running post-action listener: _start_resource_monitoring 10:11:56 INFO - Starting resource monitoring. 10:11:56 INFO - ##### 10:11:56 INFO - ##### Finished create-virtualenv step (success) 10:11:56 INFO - ##### 10:11:56 INFO - ##### 10:11:56 INFO - ##### Running install step. 10:11:56 INFO - ##### 10:11:56 INFO - Running pre-action listener: _resource_record_pre_action 10:11:56 INFO - Running main action method: install 10:11:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:11:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:11:56 INFO - Reading from file tmpfile_stdout 10:11:56 INFO - Detecting whether we're running mozinstall >=1.0... 10:11:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:11:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:11:56 INFO - Reading from file tmpfile_stdout 10:11:56 INFO - Output received: 10:11:56 INFO - Usage: mozinstall [options] installer 10:11:56 INFO - Options: 10:11:56 INFO - -h, --help show this help message and exit 10:11:56 INFO - -d DEST, --destination=DEST 10:11:56 INFO - Directory to install application into. [default: 10:11:56 INFO - "/builds/slave/test"] 10:11:56 INFO - --app=APP Application being installed. [default: firefox] 10:11:56 INFO - mkdir: /builds/slave/test/build/application 10:11:56 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'] 10:11:56 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 10:12:31 INFO - Reading from file tmpfile_stdout 10:12:31 INFO - Output received: 10:12:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:12:31 INFO - Running post-action listener: _resource_record_post_action 10:12:31 INFO - ##### 10:12:31 INFO - ##### Finished install step (success) 10:12:31 INFO - ##### 10:12:31 INFO - ##### 10:12:31 INFO - ##### Running run-tests step. 10:12:31 INFO - ##### 10:12:31 INFO - Running pre-action listener: _resource_record_pre_action 10:12:31 INFO - Running main action method: run_tests 10:12:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:12:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:12:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:12:31 INFO - 2016-07-18 10:12:31.106 screenresolution[6414:106899] starting screenresolution argv=screenresolution get 10:12:31 INFO - 2016-07-18 10:12:31.109 screenresolution[6414:106899] Display 0: 1600x1200x32@60 10:12:31 INFO - 2016-07-18 10:12:31.115 screenresolution[6415:106901] starting screenresolution argv=screenresolution list 10:12:31 INFO - Available Modes on Display 0 10:12:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:12:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:12:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:12:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:12:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:12:31 INFO - Intel Iris: 10:12:31 INFO - Chipset Model: Intel Iris 10:12:31 INFO - Type: GPU 10:12:31 INFO - Bus: Built-In 10:12:31 INFO - VRAM (Dynamic, Max): 1536 MB 10:12:31 INFO - Vendor: Intel (0x8086) 10:12:31 INFO - Device ID: 0x0a2e 10:12:31 INFO - Revision ID: 0x0009 10:12:31 INFO - Displays: 10:12:31 INFO - Display: 10:12:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:12:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:12:31 INFO - Display Serial Number: 1600x1200 60 10:12:31 INFO - Main Display: Yes 10:12:31 INFO - Mirror: Off 10:12:31 INFO - Online: Yes 10:12:31 INFO - Rotation: Supported 10:12:31 INFO - Return code: 0 10:12:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:12:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:12:31 INFO - Python 2.7.3 10:12:31 INFO - Return code: 0 10:12:31 INFO - grabbing minidump binary from tooltool 10:12:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:12:31 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 0x10da72d30>, '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 0x10d7d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da6ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:12:31 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 10:12:31 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 10:12:31 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 10:12:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:12:31 INFO - Return code: 0 10:12:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:12:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:12:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:12:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:12:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:12:31 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/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0057', '--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 10:12:31 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/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0057 --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 10:12:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kswAVs0NKZ/Render', 10:12:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:31 INFO - 'HOME': '/Users/cltbld', 10:12:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:31 INFO - 'LOGNAME': 'cltbld', 10:12:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:12:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:12:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:31 INFO - 'MOZ_NO_REMOTE': '1', 10:12:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:12:31 INFO - 'NO_EM_RESTART': '1', 10:12:31 INFO - 'PAGER': '/bin/cat', 10:12:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:31 INFO - 'PWD': '/builds/slave/test', 10:12:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:12:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:31 INFO - 'SHELL': '/bin/bash', 10:12:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pq2Yt9ZauA/Listeners', 10:12:31 INFO - 'TMPDIR': '/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/', 10:12:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:31 INFO - 'USER': 'cltbld', 10:12:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:31 INFO - 'XPC_FLAGS': '0x0', 10:12:31 INFO - 'XPC_SERVICE_NAME': '0', 10:12:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:31 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/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0057', '--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 10:12:31 INFO - mozversion application_buildid: 20160718080700 10:12:31 INFO - mozversion application_changeset: 279f4307531af445827f59f6706f2c245403dcae 10:12:31 INFO - mozversion application_display_name: Nightly 10:12:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:12:31 INFO - mozversion application_name: Firefox 10:12:31 INFO - mozversion application_remotingname: firefox 10:12:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:12:31 INFO - mozversion application_vendor: Mozilla 10:12:31 INFO - mozversion application_version: 50.0a1 10:12:31 INFO - mozversion platform_buildid: 20160718080700 10:12:31 INFO - mozversion platform_changeset: 279f4307531af445827f59f6706f2c245403dcae 10:12:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:12:31 INFO - mozversion platform_version: 50.0a1 10:12:31 INFO - using testdate: 1468861951 10:12:31 INFO - actual date: 1468861951 10:12:31 INFO - starting webserver on 'localhost:57521' 10:12:31 INFO - SUITE-START | Running 2 tests 10:12:31 INFO - TEST-START | dromaeo_css 10:12:31 INFO - operating with platform_type : mac_ 10:12:31 INFO - Initialising browser for dromaeo_css test... 10:12:31 INFO - TEST-INFO | started process 6425 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/tmpywAxj7/profile http://localhost:57521/getInfo.html) 10:12:34 INFO - PROCESS | 6425 | __metrics Screen width/height:1600/1200 10:12:34 INFO - PROCESS | 6425 | colorDepth:24 10:12:34 INFO - PROCESS | 6425 | Browser inner width/height: 1024/689 10:12:34 INFO - PROCESS | 6425 | __metrics 10:12:34 INFO - PROCESS | 6425 | 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" 10:12:34 INFO - PROCESS | 6425 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:12:34 INFO - TEST-INFO | 6425: exit 0 10:12:34 INFO - Browser initialized. 10:12:34 INFO - Running cycle 1/1 for dromaeo_css test... 10:12:34 INFO - TEST-INFO | started process 6429 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/tmpywAxj7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:12:46 INFO - PROCESS | 6429 | RSS: Main: 243818496 10:12:46 INFO - PROCESS | 6429 | 10:13:38 INFO - PROCESS | 6429 | 1468862018468 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] 10:15:49 INFO - PROCESS | 6429 | Cycle 1(1): loaded http://localhost:57521/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57521/tests/dromaeo/cssquery-ext.html) 10:15:50 INFO - PROCESS | 6429 | RSS: Main: 352993280 10:15:50 INFO - PROCESS | 6429 | 10:18:53 INFO - PROCESS | 6429 | Cycle 1(1): loaded http://localhost:57521/tests/dromaeo/cssquery-ext.html (next: http://localhost:57521/tests/dromaeo/cssquery-jquery.html) 10:18:53 INFO - PROCESS | 6429 | RSS: Main: 380579840 10:18:53 INFO - PROCESS | 6429 | 10:21:56 INFO - PROCESS | 6429 | Cycle 1(1): loaded http://localhost:57521/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57521/tests/dromaeo/cssquery-mootools.html) 10:21:56 INFO - PROCESS | 6429 | RSS: Main: 371228672 10:21:56 INFO - PROCESS | 6429 | 10:24:59 INFO - PROCESS | 6429 | Cycle 1(1): loaded http://localhost:57521/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57521/tests/dromaeo/cssquery-prototype.html) 10:24:59 INFO - PROCESS | 6429 | RSS: Main: 371781632 10:24:59 INFO - PROCESS | 6429 | 10:28:02 INFO - PROCESS | 6429 | Cycle 1(1): loaded http://localhost:57521/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57521/tests/dromaeo/cssquery-yui.html) 10:28:03 INFO - PROCESS | 6429 | RSS: Main: 339410944 10:28:03 INFO - PROCESS | 6429 | 10:31:07 INFO - PROCESS | 6429 | Cycle 1(1): loaded http://localhost:57521/tests/dromaeo/cssquery-yui.html (next: http://localhost:57521/tests/dromaeo/cssquery-dojo.html) 10:31:07 INFO - PROCESS | 6429 | RSS: Main: 428748800 10:31:07 INFO - PROCESS | 6429 | 10:31:07 INFO - PROCESS | 6429 | __start_tp_report 10:31:07 INFO - PROCESS | 6429 | _x_x_mozilla_page_load 10:31:07 INFO - PROCESS | 6429 | _x_x_mozilla_page_load_details 10:31:07 INFO - PROCESS | 6429 | |i|pagename|runs| 10:31:07 INFO - PROCESS | 6429 | |0;dojo.html;3140.7958482698623;3305.520699498571;3311.9337613247735;3342.5153352763864;3371.039253812664;5580.6372585782;5620.915686264704;5683.318001839785;5694.743736531856;5710.628809127404;5687.6303040302355;5705.030144875371;5783.045797443417;5788.986927287719;5835.328937172227;5107.259447380127;5155.458676838915;5212.609054320922;5223.764930578114;5320.733963301839;5310.362756469229;5346.064438723208;5347.759350829222;5351.598630102739;5380.596455265852;4908.950910490903;5168.508991645806;5186.870328241824;5211.374635043781;5218.347706536683;5174.948250517522;5179.249008893727;5180.611454140954;5289.3123893893935;5316.4683531646915;5175.068487672217;5179.171332586806;5186.325777648882;5256.132738098209;5280.683159010433;5615.719214039282;5628.099504079328;5746.25298711169;5751.884962300773;5816.8836623267725;5106.846794596168;6115.327313995457;6190.009598464224;6193.411625895533;6206.627602343874;6198.81403557894;6252;6270.717817698214;6283.088952101965;6291.7797877074545;5514.91727624082;5632.802851900205;5713.342965558931;5757.165211044375;5808.796692115798;6307.959186734138;6319.083732858801;6339.334369891095;6344.8413789654705;6345.8730825384155;6132.63204207749;6133.846653833601;6134.263888333428;6162.876742465126;6194.101855230966;6120.096412477688;6121.4529735759925;6163.691815409212;6224.564280500412;6242.906356404657;5489.752961116759;5995.770866972261;6122.295935967331;6240.095186197976;6241.875162496724;6182.381761823782;6244.1570387997035;6279.937200627935;6281.9948808190475;6291.02489114188;4895.951040489621;4916.311716359713;4939.90120197594;4978.452370239271;4992.151334273182;4432.246518091933;4477.932831007537;4511.8195272189405;4524.389207457017;4524.5701658342405;5725.055365864586;5734.5125664319;5735.397783232784;5742.7702891883855;5765.94234057654;6246.570969704821;6255.56211065221;6271.310155882849;6285.151504546919;6292.937070629327;5578.24693666358;5633.887322253614;5637.661740295595;5639.887202255932;5681.147827825859;5431.293931788921;5447.782088716486;5455.754491047913;5460.754266058037;5474.178873169057;5392.137258038773;6333.1766870306565;6357.777477788348;6368.745250190033;6368.745250190126;6059.666718330534;6163.3836616338;6187.783427580013;6249.125122482947;6315.494760419064;6313.210848643919;6322.46259067966;6324.209473815773;6338.11266422692;6363.236411630634;6118.785842495491;6194.659293738888;6199.969000154971;6225.128482012612;6225.470834979077;6129.264488261437;6184.8763024738355;6227.221597300338;6238.844028899314;6296.748130074929;5955.970220148872;6127.234095738033;6128.417800308964;6137.018077107641;6161.84595385119;6073.817785466443;6095.634261944298;6112.877742445037;6122.510199184144;6134.263888333428;5763;5828.70856457167;5837.241158649348;5892.528597712087;5895.380984996686;5969.104634304889;6018.578699490994;6029.969850150721;6030.336662967158;6073.726682299395;5770.855728606818;5816.185733997159;5850.151727999501;5855.7364918579615;5861.208736820475 10:31:07 INFO - PROCESS | 6429 | |1;ext.html;484.83757941089743;506.12945733338654;524.1639584862144;530.3000039947271;548.3693752184989;38292.808535957316;38916.61083389165;40493.79753101238;40881;42064.36903446451;43743;43788.781056094755;43843.78078109605;43917.341239881425;43943.56056439435;9588.664396746133;9771.853422198672;9786.26603004774;9791.461469619167;9795.216382689403;9699.127078562928;9785.608575656965;9791.020897910188;9791.755206119833;9791.804163916717;9785.217182625373;9839.262055345878;9841.45871977041;9843.36018158821;9850.802983940317;9743.610255589769;9755.463449510275;9791.118799308026;9793.314467987244;9794.999999999964;9671.758206044835;9747.317687761859;9752.414855108716;9753.756156096084;9767.218622510252;41420.964475888046;41662.583374166476;41710.79144604288;41811.1637767246;41860.372094418606;3679;3681.1717363593243;3970.285348637244;4048.817803198863;4063.227986682521;31709.8414507926;32146.678533214836;32226.516602250984;32302.838485807657;32878.506822397685;3290.2596915693775;3292.522584225274;3295.505674148873;3302.190963214004;3457.343104810078;532180;532454;534808;535453;536199;61778;62202.68898655568;62233;62255.6887215561;62283.6885815568;35116.82441587776;35564;35567.82216088903;37051.88844334674;65151;16823.66352672964;16905.577360566083;16993;17014.31942722302;17048.82951170497;13000.479980800663;13098.803517947239;13099.803502947463;13114.60656180316;13124.950003999656;18947.957862317704;19549.511262218275;19724.30964916221;19744.901275493532;19832.305869294793;6386.744530218739;6999.23008469068;7142.78572642652;8102.230288122619;8196.13940536247;905.2667339455063;966.3332300712486;987.2595553334999;1056.3292309383596;1068.0707784227773;31011.84494077515;31129;31452.52821207684;31555.842220789207;31719.365612688078;883.3110174064241;1050.6742909698019;1115.4924509348227;1126.1610100475198;1208.945597448117;15355.97162809028;15614.84385156134;15637.296321665552;15876.60308492297;16139.919300403422;699307;702565;709795;709975;713318;59132.70433647804;59469;59556.70221648864;59715.402845971854;59782.701086494286;1175.735459521601;1267.6387229639693;1290.3354772292278;1323.1994643240894;1413.6960553481051;32103.357932841678;32131.51802723008;32221.67778322187;32225.838870805495;32674.673253267163;51421.48578514167;51567.74216129045;52251.95496090133;52255.73872130767;52292.73853630707;548583;551248;551607;552782;553093;23942.880285599156;26785.464290714466;27171.86414067917;27268.31829204286;27334.726652734014;49043.264351035476;49623.50376496333;49989;50149.74925125351;50652.74673626608;39102.60897391103;40221.994450138984;40812.1837563253;41418.1716365665;42237.36643950405;40304.99237519086;40558.594414056664;40577;41106.588934111474;41290.79354603208;39544;39703.80148099356;40201.39697904592;40492;42439;33338;33796;35286;36152.819235903655;36210.27579448449;25828.062895283303;26135;26365;26458.206253812416;26920 10:31:07 INFO - PROCESS | 6429 | |2;jquery.html;367.280130943351;384.5808069204567;391.47151345683324;395.1070580488096;417.3385184482595;15537.145456999875;15603.531894043168;20495.87272700001;20996;21138.89430552849;20432.693509597368;20576.588468230664;20926.686099708517;21280.36158915235;22035.559288814213;5220.42802137122;5833.679147646888;6075.422834830684;6078.088286757;6089.6955152242435;4209.747415155085;4427.092446048552;5949.762009519614;6427;6768.729250829961;6455.935440645604;6768.7630932917355;6768.7969360919005;6771.525993180479;6788.287229840869;6594.901076483857;6677.131972843499;6687.364700353459;6712.093867327946;6724.193096828363;5134.871628209287;5442.727863606843;5451.100568406208;5836.095405212199;5844.620099693507;13485.865141348559;13705.246211458365;13722.588322350346;17499.737503937453;25599.360015999562;2207.668849672546;2222.5999320122373;2255.8308126890383;2743.779018336841;2744.341358074048;9581.952090239574;10055.949720251425;10060.94969525155;10554.208434367376;10569.3129946554;1826.1873466307497;1892.8391086757642;1920.270297287022;1996.7204591357222;1999.7600287965392;98174;128157;128613.35693321568;134350.2824858763;139854;11248.412865041086;11420.657380278604;11606.825897611543;11675.065994720402;11869.46587403569;14641.48754793577;14749.336279867432;14758.704825903636;14792.852071479147;14811.629709257355;8533.146685331541;8676.6529338827;8727.956360218159;8782.780430489289;8812.603432845537;8607.741767746978;8620.439671421338;8621.22408983182;8740.344474164463;8770.736877893672;4503.279475284005;4577.5880170784785;4582.152301824173;4651.67438279324;4657.86026419208;1582.968340633204;1654.983450165483;1750.0296735905044;1830.8260715232032;1925.6424220924214;390.4631132193234;397.73351854257714;877.4735158904733;900.4192295969063;992.1417973453056;11896.643100706993;12261.448234829453;12266.634931496646;12344;12392.690182745504;437.4182337491129;442.23272622000803;450.83995181710674;463.29579039859226;476.53061734191795;9991.550380232908;12066.818997715041;12365.876341236652;12427.37863106841;12675.11274210815;617793;624364;629705;636465;639854;132481;135400;137144.2284137317;141063.294683528;143393.28303358625;988.4020167798523;1017.8473229015707;1034.1726618705156;1034.7464871106627;1036.5387402605768;20420.183192672128;20860.791392085885;20865.478363041046;20888.37334879956;20890.582188356453;58328.125078124714;58478;58520.4147958532;58548;59451.702741487745;87021.56489217513;89054.55472722594;89750.55124724595;89778;89924.10075899419;6400.295967443484;8343;8422.157784221774;8626.568671566522;8704.956475217583;10353.223508236762;13411.262380569162;13616;13667.38496767628;13844.515441959484;11565.34992379402;11732.178747487595;11751.823722644338;11873.346965916957;11973.940130299292;11614.593489227836;11618.244814087057;11785.528578856749;11880.524779008743;11914.285142891455;11196.66410007701;11718.531258749554;12031.4585843639;12039.75920481568;12068.75862482763;5855;7584.582847943416;7640;7663.540187588763;8592.183742544448;8205.179482051746;9902.702918912444;11496.310221386744;11608.01331886799;11806.940965295118 10:31:07 INFO - PROCESS | 6429 | |3;mootools.html;1260.7100366915604;1544.2741911301687;1575.9527214183572;1758.9120543972806;1883.7833649130355;7502.774916752499;7949.960250198756;7950.40371972103;7957.323627491663;7958.641861116249;8078.515289082659;8351.4989100654;8352.039515455725;8455.830883382328;8487.193716596928;2296.9770302297015;2300.6779050932882;2332.3469428559947;2333.6616190652344;2337.041812856729;2285.4172186092524;2319.5708793873105;2323.8140948724144;2325.7209134903837;2332.230363979891;2263.977360226393;2300.6779050932882;2301.723793144825;2315.2128276386024;2317.3627252505526;2279.1225378229337;2279.1453205048106;2279.2022792022826;2279.31620513846;2280.133549251282;1947.7857435682063;2261.1746712450004;2266.77332266772;2267.682524446579;2277.9886100569556;8499.787505312417;8514.701985430542;8550.657973681045;8561.614727337223;8570.871436928452;4978.6514943953935;4981.402231732215;5123.077845987721;5131.4098878629065;5138.511841375063;3395.898123056312;3443.9827800860835;3538.097785064818;3539.94690079649;3543.344481270973;1028.0850043461326;1046.5552140340326;1046.984295235572;1053.5522402978704;1070.2187403195633;11072.003519683187;11161.497732602053;11163.553457861757;11166.274192177483;11184.776304473866;4117.547069822317;4370;4383.583559561836;4393.670474714345;4397.560243975641;8733.344999124964;8841.336899732545;8846.955765221133;8854.513001784964;8854.822903541894;5020.698758074527;5022.196448568212;5028.120078986163;5029.06962211992;5030.094582975026;3954.5847685992558;4243.7029407941145;4653.511381304914;4716.434027916672;4744.905101897943;3604.9279014419567;3624.655657712513;3680.3743363628246;3688.9631103689157;3723.236736469017;1120.523777394604;1122.6014764758556;1133.0822034152363;1136.5851464215502;1136.9488373023235;591.4596806715168;804.6982381606897;923.3444254579324;954.5561313989028;974.0697633759743;5629.408912064257;5720.628159169724;5796.188533605298;5874.676892770854;5880.0591905295805;434.8977990172307;477.86858613881463;492.9334539837148;497.965142440033;649.9447546958562;3187.9681203188134;3949.822257998397;4014.8394064237104;4025.516937967404;4042.15114825884;9987.700368988797;10498.370097794157;10499.107575856138;10516.894831051592;10537.367757934548;10056.34633748804;10466.424346660855;10470.842937355947;10477.633282835064;10494.790104197873;7739.380849532024;7977.321927636216;8014.278714915686;8088.9595552021865;8104.554249516215;5046.722430266371;5746.569007324384;5853.590248682552;5873.823785286449;5893.705314734332;9533.618655253713;9579.617834394905;9708.271879608916;9717.951410242904;9738.513074346396;9555.331126821056;9875.654352097643;9887.505624718591;9893.159081477866;9905.405675659484;7356.668949897375;7474.289942455458;7504.6247687614305;7507.061617297838;7531.510451820614;7677.078750549969;7921.485103468256;7985;8074.596270186585;8146.307563857138;6317.873642527215;7347.412207023318;7357.1907090218965;7463.701451941862;7515.849683006419;7179.461540384387;7269.963650181715;7331.303526165006;7387.224341444071;7399.889001664872;6817.5909445433435;6859.382655561023;6935.445164386938;6935.791926242221;6937.271586483346;4921.3356196912955;4928.753562321798;4953.2570114483115;5009.173486374707;5016.097102521581;4972.776225069808;5020.046191223656;5053.772580233825;5072.923906141485;5103.5661968733075 10:31:07 INFO - PROCESS | 6429 | |4;prototype.html;303.3114829337404;314.31008935386836;317.0599173450718;319.43141208648615;322.061191626409;4714.962708204198;5414.590507224287;5431.429699881515;5474.644148130369;5533.916991245134;4523.615492683118;4755.928661070078;4836.37127173467;5395.7032363220005;5443.918341224875;3930.646241838234;3976.9262299179204;4050.250703619825;4178.456800615931;4194.4547208862805;3421.743369247304;4117.2177286315655;4129.421880936671;4145.834166633331;4154.127633197032;4157.875263742093;4158.438610787551;4164.333706606943;4165.250254954107;4193.287141185991;3430.451127819562;3787.621237876218;3882.378819388898;4071.735337203102;4084.3458399348165;3529.29414117175;3550.50878630973;3558.5373901392654;3588.5155504006884;3597.94603080954;5253.605979551511;5281.78518940642;5308.840734777924;5314.787408503656;5335.1730481775385;4467.709598876063;4733.9763301183275;4746.5080832562835;4750.45369782476;4782.928256076161;4697.976510117427;5357.812476563302;5642.178910544728;5702.885942281173;5715.6284841485585;3300.752443566742;3451.947156117383;3900.7659540427667;3950.5851885551892;3995.2808494470983;5030.974845125751;5541.944580554183;5788.768449262025;5818.679972601505;5949.9702501487645;3566.894262778495;3953.367461206193;3969.702272329587;3992.3811809169624;4021.8592349267633;3595.1012246938267;3965.493112617187;4050.3519436890547;4060.127072679388;4077.571854955246;4225.81677130404;4579.610733087725;4667.019925815617;4720.103180395714;4757;5110.058177892452;5729.166666666627;5729.799457018984;5736.881308144872;5751;1303.1855090568395;1395.8464568897414;1395.9948836837461;1396.0785881318282;1398.8461269260374;772.9372113344152;860.9354298427643;878.3544095090103;879.9308839759594;906.7143849687327;5069.214271787879;5205.29728486657;5229.215617657305;5253.264542963988;5255.372313843079;4862.930155365814;5693.772249109989;5740;5763.423657634287;5787.032408980883;4504;4983.700977941335;5084.593232541388;5215.817446389358;5285.867853303698;2582.341502916744;3015.517517197238;3044.0106965236387;3051.61854768154;3052.9084127476212;5945.91892837512;5981.401859814071;6000.219971403777;6023.698815059317;6029.879402411927;5249.976254630386;5464.9180262296095;5591.41290164535;5898.4101589840675;5989.520838332923;5130.30740849988;5942.762289508458;5990.7903223386975;6022.879542409128;6078.513718902565;5041.747912604282;5556.805511807229;5783.467381143916;5790.7973220937065;5825.446582574648;4450.933236001398;5126.9743651283;5177.945012442677;5486.835394938158;5780.479756821906;5613.1860880171325;6057.818265452043;6060.151578778887;6072;6090.390960903874;3306.7850589711593;3673.651003154696;3930.9017274568364;3978.1645854370904;3983.585827031362;5120.564751996122;5900.911486327795;5909.438603332676;5933.436323549256;5951.750132472056;4783.808647654055;5001.324821149244;5791.391903850035;5793.623414478046;5819.854503637443;5452.991196628636;5677.801276955287;5681.977244096102;5699.5155411790465;5711.029125048834;5305.283786688748;5543.223948647112;5558.694271814928;5573.832785016456;5588.804391846428;5539.861503462445;5596.664200148004;5667.631603945731;5742.3109226891365;5757.827265182052;5126.846459546572;5195.5583775378;5618.7190640467;5636.661800291996;5658.660480371191 10:31:07 INFO - PROCESS | 6429 | |5;yui.html;749.3817600479605;785.4855069929198;794.3724457678435;818.1491249100935;847.0321564026982;15372.154531500762;16086.034837909734;16202.432914847983;16221.432249871257;16223.756643650326;14156.646083847909;14930.104193748355;14947.925260373682;14970.775438368433;15103.244837758122;708.9432845372357;731.3454458259881;731.6232140447673;874.1040433555593;882.9072947340533;698.8637215742907;891.6522556203086;892.3307519360479;894.1773568317163;895.0601868038566;935.672514619881;938.474454305591;938.7512309238058;939.8120375924842;941.5762906691982;739.0134170881852;912.0742446416892;938.7840796616782;941.8210539997447;943.7451887990272;695.537467584056;696.8048946295046;697.8988046733195;698.1029377250209;700.3766647683566;14949;14968;14977.475788347356;14977.700445991019;15030;13970.441182352795;13983.510577129751;14064.859351406458;14090.788638170436;14109.294535273195;11598.49219601455;12551.560695375618;12596.748065038739;12685.429155688016;12809.423575939012;4490.146872094324;4581.358609794632;4589.082183563267;4689.119042543149;4744.952550474485;96905;98628.0137198626;98967;99545.50227248817;104737.47631261795;11156.553737850558;11165.944170279097;11178.835429905408;11275.94362028201;11523.53905843757;21779.891100544395;21808;21822.236221732164;21825.454363641034;21848.89075554644;6660;7753.689852405841;7945.682172713143;8098.028236611526;8106.797330066677;4279.593438623326;4341.6526677865695;4422.601965823092;4447.577480139446;4522.072975040109;3358.445856433661;3467.8612855486003;3468.4277094279664;3470.7743996640643;3472.73954453417;569.8347479230977;643.3791391307411;651.6546230497844;651.8109748172976;653.330336405179;493.3438526759401;641.1985018726592;675.598019178588;696.7805141380449;720.0711082703255;8058.435909486397;9278.39690420134;9534.713958581253;9553.61785528585;9658.3722058066;518.2925306956054;568.4770011589347;631.5200447659812;663.9575865890506;682.853186564891;4234.280172370704;4591.265397536378;4604.516525764813;4628.60656844172;4629.236176030984;135325;139410.30294848664;141010;141903.2904835469;146531;80524.19475805284;92349.61475577872;121921;125072.37463812623;128241;63058.36941630525;105155.4742226284;109465.45267273612;109515.45242273738;112325;9151.908480915106;9934.751631209278;9936;9957.651482198089;9979.351342162736;55684;57718.42281577131;58905.70547147237;59709;63922.76270330516;69867;74087;86658.56670716606;89865.20269594702;89985;13739.106958047694;13785.724285514434;13826.198318691502;14093.718125637635;14097.083689560146;14899.47851825181;15413.952010169958;15420.768688469907;15424.383024679337;15937.282822272793;13966.37151328213;14179;14401.063930844462;14725.129917417416;14924.253787310374;11698.824517632413;14202.431902724189;14264.215468148936;14390.712185756436;14708.779368309699;13519.594412167651;13616.455341786217;13840.552391912393;14018.719625607635;14345.784813228018;9597.520123993912;11267.830982535104;11365.659030229106;11568.132390070947;12050.205094195819;9884.654037108667;10660.573577056832;10738.194635402533;11080.944595276973;11750.70623234426 10:31:07 INFO - PROCESS | 6429 | __end_tp_report 10:31:07 INFO - PROCESS | 6429 | __start_cc_report 10:31:07 INFO - PROCESS | 6429 | _x_x_mozilla_cycle_collect,1207 10:31:07 INFO - PROCESS | 6429 | __end_cc_report 10:31:07 INFO - PROCESS | 6429 | __startTimestamp1468863067269__endTimestamp 10:31:07 INFO - PROCESS | 6429 | 10:31:07 INFO - PROCESS | 6429 | ------- Summary: start ------- 10:31:07 INFO - PROCESS | 6429 | Number of tests: 6 10:31:07 INFO - PROCESS | 6429 | 10:31:07 INFO - PROCESS | 6429 | [#0] dojo.html Cycles:165 Average:5725.53 Median:5837.24 stddev:636.49 (10.9%) stddev-sans-first:605.28 10:31:07 INFO - PROCESS | 6429 | Values: 3140.8 3305.5 3311.9 3342.5 3371.0 5580.6 5620.9 5683.3 5694.7 5710.6 5687.6 5705.0 5783.0 5789.0 5835.3 5107.3 5155.5 5212.6 5223.8 5320.7 5310.4 5346.1 5347.8 5351.6 5380.6 4909.0 5168.5 5186.9 5211.4 5218.3 5174.9 5179.2 5180.6 5289.3 5316.5 5175.1 5179.2 5186.3 5256.1 5280.7 5615.7 5628.1 5746.3 5751.9 5816.9 5106.8 6115.3 6190.0 6193.4 6206.6 6198.8 6252.0 6270.7 6283.1 6291.8 5514.9 5632.8 5713.3 5757.2 5808.8 6308.0 6319.1 6339.3 6344.8 6345.9 6132.6 6133.8 6134.3 6162.9 6194.1 6120.1 6121.5 6163.7 6224.6 6242.9 5489.8 5995.8 6122.3 6240.1 6241.9 6182.4 6244.2 6279.9 6282.0 6291.0 4896.0 4916.3 4939.9 4978.5 4992.2 4432.2 4477.9 4511.8 4524.4 4524.6 5725.1 5734.5 5735.4 5742.8 5765.9 6246.6 6255.6 6271.3 6285.2 6292.9 5578.2 5633.9 5637.7 5639.9 5681.1 5431.3 5447.8 5455.8 5460.8 5474.2 5392.1 6333.2 6357.8 6368.7 6368.7 6059.7 6163.4 6187.8 6249.1 6315.5 6313.2 6322.5 6324.2 6338.1 6363.2 6118.8 6194.7 6200.0 6225.1 6225.5 6129.3 6184.9 6227.2 6238.8 6296.7 5956.0 6127.2 6128.4 6137.0 6161.8 6073.8 6095.6 6112.9 6122.5 6134.3 5763.0 5828.7 5837.2 5892.5 5895.4 5969.1 6018.6 6030.0 6030.3 6073.7 5770.9 5816.2 5850.2 5855.7 5861.2 10:31:07 INFO - PROCESS | 6429 | 10:31:07 INFO - PROCESS | 6429 | [#1] ext.html Cycles:180 Average:72831.29 Median:41354.48 stddev:161215.76 (389.8%) stddev-sans-first:161576.51 10:31:07 INFO - PROCESS | 6429 | Values: 484.8 506.1 524.2 530.3 548.4 38292.8 38916.6 40493.8 40881.0 42064.4 43743.0 43788.8 43843.8 43917.3 43943.6 9588.7 9771.9 9786.3 9791.5 9795.2 9699.1 9785.6 9791.0 9791.8 9791.8 9785.2 9839.3 9841.5 9843.4 9850.8 9743.6 9755.5 9791.1 9793.3 9795.0 9671.8 9747.3 9752.4 9753.8 9767.2 41421.0 41662.6 41710.8 41811.2 41860.4 3679.0 3681.2 3970.3 4048.8 4063.2 31709.8 32146.7 32226.5 32302.8 32878.5 3290.3 3292.5 3295.5 3302.2 3457.3 532180.0 532454.0 534808.0 535453.0 536199.0 61778.0 62202.7 62233.0 62255.7 62283.7 35116.8 35564.0 35567.8 37051.9 65151.0 16823.7 16905.6 16993.0 17014.3 17048.8 13000.5 13098.8 13099.8 13114.6 13125.0 18948.0 19549.5 19724.3 19744.9 19832.3 6386.7 6999.2 7142.8 8102.2 8196.1 905.3 966.3 987.3 1056.3 1068.1 31011.8 31129.0 31452.5 31555.8 31719.4 883.3 1050.7 1115.5 1126.2 1208.9 15356.0 15614.8 15637.3 15876.6 16139.9 699307.0 702565.0 709795.0 709975.0 713318.0 59132.7 59469.0 59556.7 59715.4 59782.7 1175.7 1267.6 1290.3 1323.2 1413.7 32103.4 32131.5 32221.7 32225.8 32674.7 51421.5 51567.7 52252.0 52255.7 52292.7 548583.0 551248.0 551607.0 552782.0 553093.0 23942.9 26785.5 27171.9 27268.3 27334.7 49043.3 49623.5 49989.0 50149.7 50652.7 39102.6 40222.0 40812.2 41418.2 42237.4 40305.0 40558.6 40577.0 41106.6 41290.8 39544.0 39703.8 40201.4 40492.0 42439.0 33338.0 33796.0 35286.0 36152.8 36210.3 25828.1 26135.0 26365.0 26458.2 26920.0 10:31:07 INFO - PROCESS | 6429 | 10:31:07 INFO - PROCESS | 6429 | [#2] jquery.html Cycles:180 Average:36518.20 Median:13927.60 stddev:105496.41 (757.5%) stddev-sans-first:105757.43 10:31:07 INFO - PROCESS | 6429 | Values: 367.3 384.6 391.5 395.1 417.3 15537.1 15603.5 20495.9 20996.0 21138.9 20432.7 20576.6 20926.7 21280.4 22035.6 5220.4 5833.7 6075.4 6078.1 6089.7 4209.7 4427.1 5949.8 6427.0 6768.7 6455.9 6768.8 6768.8 6771.5 6788.3 6594.9 6677.1 6687.4 6712.1 6724.2 5134.9 5442.7 5451.1 5836.1 5844.6 13485.9 13705.2 13722.6 17499.7 25599.4 2207.7 2222.6 2255.8 2743.8 2744.3 9582.0 10055.9 10060.9 10554.2 10569.3 1826.2 1892.8 1920.3 1996.7 1999.8 98174.0 128157.0 128613.4 134350.3 139854.0 11248.4 11420.7 11606.8 11675.1 11869.5 14641.5 14749.3 14758.7 14792.9 14811.6 8533.1 8676.7 8728.0 8782.8 8812.6 8607.7 8620.4 8621.2 8740.3 8770.7 4503.3 4577.6 4582.2 4651.7 4657.9 1583.0 1655.0 1750.0 1830.8 1925.6 390.5 397.7 877.5 900.4 992.1 11896.6 12261.4 12266.6 12344.0 12392.7 437.4 442.2 450.8 463.3 476.5 9991.6 12066.8 12365.9 12427.4 12675.1 617793.0 624364.0 629705.0 636465.0 639854.0 132481.0 135400.0 137144.2 141063.3 143393.3 988.4 1017.8 1034.2 1034.7 1036.5 20420.2 20860.8 20865.5 20888.4 20890.6 58328.1 58478.0 58520.4 58548.0 59451.7 87021.6 89054.6 89750.6 89778.0 89924.1 6400.3 8343.0 8422.2 8626.6 8705.0 10353.2 13411.3 13616.0 13667.4 13844.5 11565.3 11732.2 11751.8 11873.3 11973.9 11614.6 11618.2 11785.5 11880.5 11914.3 11196.7 11718.5 12031.5 12039.8 12068.8 5855.0 7584.6 7640.0 7663.5 8592.2 8205.2 9902.7 11496.3 11608.0 11806.9 10:31:07 INFO - PROCESS | 6429 | 10:31:07 INFO - PROCESS | 6429 | [#3] mootools.html Cycles:180 Average:5493.53 Median:5018.07 stddev:3085.88 (61.5%) stddev-sans-first:3078.14 10:31:07 INFO - PROCESS | 6429 | Values: 1260.7 1544.3 1576.0 1758.9 1883.8 7502.8 7950.0 7950.4 7957.3 7958.6 8078.5 8351.5 8352.0 8455.8 8487.2 2297.0 2300.7 2332.3 2333.7 2337.0 2285.4 2319.6 2323.8 2325.7 2332.2 2264.0 2300.7 2301.7 2315.2 2317.4 2279.1 2279.1 2279.2 2279.3 2280.1 1947.8 2261.2 2266.8 2267.7 2278.0 8499.8 8514.7 8550.7 8561.6 8570.9 4978.7 4981.4 5123.1 5131.4 5138.5 3395.9 3444.0 3538.1 3539.9 3543.3 1028.1 1046.6 1047.0 1053.6 1070.2 11072.0 11161.5 11163.6 11166.3 11184.8 4117.5 4370.0 4383.6 4393.7 4397.6 8733.3 8841.3 8847.0 8854.5 8854.8 5020.7 5022.2 5028.1 5029.1 5030.1 3954.6 4243.7 4653.5 4716.4 4744.9 3604.9 3624.7 3680.4 3689.0 3723.2 1120.5 1122.6 1133.1 1136.6 1136.9 591.5 804.7 923.3 954.6 974.1 5629.4 5720.6 5796.2 5874.7 5880.1 434.9 477.9 492.9 498.0 649.9 3188.0 3949.8 4014.8 4025.5 4042.2 9987.7 10498.4 10499.1 10516.9 10537.4 10056.3 10466.4 10470.8 10477.6 10494.8 7739.4 7977.3 8014.3 8089.0 8104.6 5046.7 5746.6 5853.6 5873.8 5893.7 9533.6 9579.6 9708.3 9718.0 9738.5 9555.3 9875.7 9887.5 9893.2 9905.4 7356.7 7474.3 7504.6 7507.1 7531.5 7677.1 7921.5 7985.0 8074.6 8146.3 6317.9 7347.4 7357.2 7463.7 7515.8 7179.5 7270.0 7331.3 7387.2 7399.9 6817.6 6859.4 6935.4 6935.8 6937.3 4921.3 4928.8 4953.3 5009.2 5016.1 4972.8 5020.0 5053.8 5072.9 5103.6 10:31:07 INFO - PROCESS | 6429 | 10:31:07 INFO - PROCESS | 6429 | [#4] prototype.html Cycles:180 Average:4598.55 Median:5186.75 stddev:1412.52 (27.2%) stddev-sans-first:1379.20 10:31:07 INFO - PROCESS | 6429 | Values: 303.3 314.3 317.1 319.4 322.1 4715.0 5414.6 5431.4 5474.6 5533.9 4523.6 4755.9 4836.4 5395.7 5443.9 3930.6 3976.9 4050.3 4178.5 4194.5 3421.7 4117.2 4129.4 4145.8 4154.1 4157.9 4158.4 4164.3 4165.3 4193.3 3430.5 3787.6 3882.4 4071.7 4084.3 3529.3 3550.5 3558.5 3588.5 3597.9 5253.6 5281.8 5308.8 5314.8 5335.2 4467.7 4734.0 4746.5 4750.5 4782.9 4698.0 5357.8 5642.2 5702.9 5715.6 3300.8 3451.9 3900.8 3950.6 3995.3 5031.0 5541.9 5788.8 5818.7 5950.0 3566.9 3953.4 3969.7 3992.4 4021.9 3595.1 3965.5 4050.4 4060.1 4077.6 4225.8 4579.6 4667.0 4720.1 4757.0 5110.1 5729.2 5729.8 5736.9 5751.0 1303.2 1395.8 1396.0 1396.1 1398.8 772.9 860.9 878.4 879.9 906.7 5069.2 5205.3 5229.2 5253.3 5255.4 4862.9 5693.8 5740.0 5763.4 5787.0 4504.0 4983.7 5084.6 5215.8 5285.9 2582.3 3015.5 3044.0 3051.6 3052.9 5945.9 5981.4 6000.2 6023.7 6029.9 5250.0 5464.9 5591.4 5898.4 5989.5 5130.3 5942.8 5990.8 6022.9 6078.5 5041.7 5556.8 5783.5 5790.8 5825.4 4450.9 5127.0 5177.9 5486.8 5780.5 5613.2 6057.8 6060.2 6072.0 6090.4 3306.8 3673.7 3930.9 3978.2 3983.6 5120.6 5900.9 5909.4 5933.4 5951.8 4783.8 5001.3 5791.4 5793.6 5819.9 5453.0 5677.8 5682.0 5699.5 5711.0 5305.3 5543.2 5558.7 5573.8 5588.8 5539.9 5596.7 5667.6 5742.3 5757.8 5126.8 5195.6 5618.7 5636.7 5658.7 10:31:07 INFO - PROCESS | 6429 | 10:31:07 INFO - PROCESS | 6429 | [#5] yui.html Cycles:180 Average:23357.32 Median:4468.86 stddev:36020.71 (806.0%) stddev-sans-first:36081.76 10:31:07 INFO - PROCESS | 6429 | Values: 749.4 785.5 794.4 818.1 847.0 15372.2 16086.0 16202.4 16221.4 16223.8 14156.6 14930.1 14947.9 14970.8 15103.2 708.9 731.3 731.6 874.1 882.9 698.9 891.7 892.3 894.2 895.1 935.7 938.5 938.8 939.8 941.6 739.0 912.1 938.8 941.8 943.7 695.5 696.8 697.9 698.1 700.4 14949.0 14968.0 14977.5 14977.7 15030.0 13970.4 13983.5 14064.9 14090.8 14109.3 11598.5 12551.6 12596.7 12685.4 12809.4 4490.1 4581.4 4589.1 4689.1 4745.0 96905.0 98628.0 98967.0 99545.5 104737.5 11156.6 11165.9 11178.8 11275.9 11523.5 21779.9 21808.0 21822.2 21825.5 21848.9 6660.0 7753.7 7945.7 8098.0 8106.8 4279.6 4341.7 4422.6 4447.6 4522.1 3358.4 3467.9 3468.4 3470.8 3472.7 569.8 643.4 651.7 651.8 653.3 493.3 641.2 675.6 696.8 720.1 8058.4 9278.4 9534.7 9553.6 9658.4 518.3 568.5 631.5 664.0 682.9 4234.3 4591.3 4604.5 4628.6 4629.2 135325.0 139410.3 141010.0 141903.3 146531.0 80524.2 92349.6 121921.0 125072.4 128241.0 63058.4 105155.5 109465.5 109515.5 112325.0 9151.9 9934.8 9936.0 9957.7 9979.4 55684.0 57718.4 58905.7 59709.0 63922.8 69867.0 74087.0 86658.6 89865.2 89985.0 13739.1 13785.7 13826.2 14093.7 14097.1 14899.5 15414.0 15420.8 15424.4 15937.3 13966.4 14179.0 14401.1 14725.1 14924.3 11698.8 14202.4 14264.2 14390.7 14708.8 13519.6 13616.5 13840.6 14018.7 14345.8 9597.5 11267.8 11365.7 11568.1 12050.2 9884.7 10660.6 10738.2 11080.9 11750.7 10:31:07 INFO - PROCESS | 6429 | -------- Summary: end -------- 10:31:07 INFO - PROCESS | 6429 | 10:31:07 INFO - TEST-INFO | 6429: exit 0 10:31:07 INFO - TEST-OK | dromaeo_css | took 1116202ms 10:31:07 INFO - TEST-START | kraken 10:31:07 INFO - operating with platform_type : mac_ 10:31:07 INFO - Initialising browser for kraken test... 10:31:07 INFO - TEST-INFO | started process 6432 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/tmpk5E7i0/profile http://localhost:57521/getInfo.html) 10:31:10 INFO - PROCESS | 6432 | __metrics Screen width/height:1600/1200 10:31:10 INFO - PROCESS | 6432 | colorDepth:24 10:31:10 INFO - PROCESS | 6432 | Browser inner width/height: 1024/689 10:31:10 INFO - PROCESS | 6432 | __metrics 10:31:10 INFO - PROCESS | 6432 | 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" 10:31:10 INFO - PROCESS | 6432 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:31:11 INFO - TEST-INFO | 6432: exit 0 10:31:11 INFO - Browser initialized. 10:31:11 INFO - Running cycle 1/1 for kraken test... 10:31:11 INFO - TEST-INFO | started process 6433 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/T/tmpk5E7i0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:31:23 INFO - PROCESS | 6433 | RSS: Main: 243879936 10:31:23 INFO - PROCESS | 6433 | 10:31:25 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:57521/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57521/tests/kraken/driver.html?testName=audio-beat-detection) 10:31:26 INFO - PROCESS | 6433 | RSS: Main: 294285312 10:31:26 INFO - PROCESS | 6433 | 10:31:29 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:57521/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57521/tests/kraken/driver.html?testName=audio-dft) 10:31:29 INFO - PROCESS | 6433 | RSS: Main: 304713728 10:31:29 INFO - PROCESS | 6433 | 10:31:33 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:57521/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57521/tests/kraken/driver.html?testName=audio-fft) 10:31:33 INFO - PROCESS | 6433 | RSS: Main: 389709824 10:31:33 INFO - PROCESS | 6433 | 10:31:35 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:57521/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57521/tests/kraken/driver.html?testName=audio-oscillator) 10:31:36 INFO - PROCESS | 6433 | RSS: Main: 291094528 10:31:36 INFO - PROCESS | 6433 | 10:31:38 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:57521/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57521/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:31:39 INFO - PROCESS | 6433 | RSS: Main: 294961152 10:31:39 INFO - PROCESS | 6433 | 10:31:42 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:57521/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57521/tests/kraken/driver.html?testName=imaging-darkroom) 10:31:42 INFO - PROCESS | 6433 | RSS: Main: 330178560 10:31:42 INFO - PROCESS | 6433 | 10:31:45 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:57521/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57521/tests/kraken/driver.html?testName=imaging-desaturate) 10:31:46 INFO - PROCESS | 6433 | RSS: Main: 333230080 10:31:46 INFO - PROCESS | 6433 | 10:31:48 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:57521/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57521/tests/kraken/driver.html?testName=json-parse-financial) 10:31:49 INFO - PROCESS | 6433 | RSS: Main: 332472320 10:31:49 INFO - PROCESS | 6433 | 10:31:51 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:57521/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57521/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:31:51 INFO - PROCESS | 6433 | RSS: Main: 311652352 10:31:51 INFO - PROCESS | 6433 | 10:31:53 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:57521/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57521/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:31:54 INFO - PROCESS | 6433 | RSS: Main: 318291968 10:31:54 INFO - PROCESS | 6433 | 10:31:56 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:57521/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57521/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:31:57 INFO - PROCESS | 6433 | RSS: Main: 316379136 10:31:57 INFO - PROCESS | 6433 | 10:32:00 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:57521/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57521/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:32:00 INFO - PROCESS | 6433 | RSS: Main: 325619712 10:32:00 INFO - PROCESS | 6433 | 10:32:03 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:57521/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57521/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:32:04 INFO - PROCESS | 6433 | RSS: Main: 328376320 10:32:04 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | Cycle 1(1): loaded http://localhost:57521/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57521/tests/kraken/driver.html?testName=ai-astar) 10:32:06 INFO - PROCESS | 6433 | RSS: Main: 372076544 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | __start_tp_report 10:32:06 INFO - PROCESS | 6433 | _x_x_mozilla_page_load 10:32:06 INFO - PROCESS | 6433 | _x_x_mozilla_page_load_details 10:32:06 INFO - PROCESS | 6433 | |i|pagename|runs| 10:32:06 INFO - PROCESS | 6433 | |0;ai-astar;100;113;112;111;112;113;112;111;114;110 10:32:06 INFO - PROCESS | 6433 | |1;audio-beat-detection;143;108;107;159;108;108;128;107;141;107 10:32:06 INFO - PROCESS | 6433 | |2;audio-dft;185;185;187;186;186;183;180;183;187;184 10:32:06 INFO - PROCESS | 6433 | |3;audio-fft;80;76;57;88;58;57;59;67;83;56 10:32:06 INFO - PROCESS | 6433 | |4;audio-oscillator;83;93;92;72;74;69;98;71;68;103 10:32:06 INFO - PROCESS | 6433 | |5;imaging-gaussian-blur;89;86;90;87;89;86;89;88;88;85 10:32:06 INFO - PROCESS | 6433 | |6;imaging-darkroom;95;94;95;95;95;95;95;95;94;94 10:32:06 INFO - PROCESS | 6433 | |7;imaging-desaturate;87;86;87;86;85;87;86;84;85;87 10:32:06 INFO - PROCESS | 6433 | |8;json-parse-financial;55;54;52;53;54;53;53;54;53;52 10:32:06 INFO - PROCESS | 6433 | |9;json-stringify-tinderbox;65;76;73;77;78;75;72;72;73;76 10:32:06 INFO - PROCESS | 6433 | |10;stanford-crypto-aes;84;86;83;87;103;82;81;83;83;80 10:32:06 INFO - PROCESS | 6433 | |11;stanford-crypto-ccm;151;137;153;147;144;124;112;151;236;149 10:32:06 INFO - PROCESS | 6433 | |12;stanford-crypto-pbkdf2;186;226;172;173;176;171;186;236;173;171 10:32:06 INFO - PROCESS | 6433 | |13;stanford-crypto-sha256-iterative;68;69;66;68;69;68;67;66;68;68 10:32:06 INFO - PROCESS | 6433 | __end_tp_report 10:32:06 INFO - PROCESS | 6433 | __start_cc_report 10:32:06 INFO - PROCESS | 6433 | _x_x_mozilla_cycle_collect,2033 10:32:06 INFO - PROCESS | 6433 | __end_cc_report 10:32:06 INFO - PROCESS | 6433 | __startTimestamp1468863126351__endTimestamp 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | ------- Summary: start ------- 10:32:06 INFO - PROCESS | 6433 | Number of tests: 14 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.00 stddev:3.97 (3.5%) stddev-sans-first:1.22 10:32:06 INFO - PROCESS | 6433 | Values: 100.0 113.0 112.0 111.0 112.0 113.0 112.0 111.0 114.0 110.0 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | [#1] audio-beat-detection Cycles:10 Average:121.60 Median:118.00 stddev:19.63 (16.6%) stddev-sans-first:19.23 10:32:06 INFO - PROCESS | 6433 | Values: 143.0 108.0 107.0 159.0 108.0 108.0 128.0 107.0 141.0 107.0 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.50 stddev:2.17 (1.2%) stddev-sans-first:2.30 10:32:06 INFO - PROCESS | 6433 | Values: 185.0 185.0 187.0 186.0 186.0 183.0 180.0 183.0 187.0 184.0 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | [#3] audio-fft Cycles:10 Average:68.10 Median:71.50 stddev:12.48 (17.4%) stddev-sans-first:12.47 10:32:06 INFO - PROCESS | 6433 | Values: 80.0 76.0 57.0 88.0 58.0 57.0 59.0 67.0 83.0 56.0 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | [#4] audio-oscillator Cycles:10 Average:82.30 Median:78.50 stddev:13.20 (16.8%) stddev-sans-first:14.00 10:32:06 INFO - PROCESS | 6433 | Values: 83.0 93.0 92.0 72.0 74.0 69.0 98.0 71.0 68.0 103.0 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:88.50 stddev:1.64 (1.8%) stddev-sans-first:1.67 10:32:06 INFO - PROCESS | 6433 | Values: 89.0 86.0 90.0 87.0 89.0 86.0 89.0 88.0 88.0 85.0 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 10:32:06 INFO - PROCESS | 6433 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 94.0 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:86.50 stddev:1.05 (1.2%) stddev-sans-first:1.05 10:32:06 INFO - PROCESS | 6433 | Values: 87.0 86.0 87.0 86.0 85.0 87.0 86.0 84.0 85.0 87.0 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.50 stddev:0.95 (1.8%) stddev-sans-first:0.78 10:32:06 INFO - PROCESS | 6433 | Values: 55.0 54.0 52.0 53.0 54.0 53.0 53.0 54.0 53.0 52.0 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | [#9] json-stringify-tinderbox Cycles:10 Average:73.70 Median:75.50 stddev:3.71 (4.9%) stddev-sans-first:2.24 10:32:06 INFO - PROCESS | 6433 | Values: 65.0 76.0 73.0 77.0 78.0 75.0 72.0 72.0 73.0 76.0 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:83.00 stddev:6.60 (7.9%) stddev-sans-first:6.98 10:32:06 INFO - PROCESS | 6433 | Values: 84.0 86.0 83.0 87.0 103.0 82.0 81.0 83.0 83.0 80.0 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:150.00 stddev:32.90 (21.9%) stddev-sans-first:34.89 10:32:06 INFO - PROCESS | 6433 | Values: 151.0 137.0 153.0 147.0 144.0 124.0 112.0 151.0 236.0 149.0 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.00 Median:181.00 stddev:23.98 (13.2%) stddev-sans-first:25.43 10:32:06 INFO - PROCESS | 6433 | Values: 186.0 226.0 172.0 173.0 176.0 171.0 186.0 236.0 173.0 171.0 10:32:06 INFO - PROCESS | 6433 | 10:32:06 INFO - PROCESS | 6433 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.06 (1.6%) stddev-sans-first:1.12 10:32:06 INFO - PROCESS | 6433 | Values: 68.0 69.0 66.0 68.0 69.0 68.0 67.0 66.0 68.0 68.0 10:32:06 INFO - PROCESS | 6433 | -------- Summary: end -------- 10:32:06 INFO - PROCESS | 6433 | 10:32:07 INFO - TEST-INFO | 6433: exit 0 10:32:07 INFO - TEST-OK | kraken | took 59412ms 10:32:07 INFO - SUITE-END | took 1175s 10:32:07 INFO - Completed test suite (00:19:35) 10:32:07 INFO - javascript benchmark 10:32:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3140.7958482698623, 3305.520699498571, 3311.9337613247735, 3342.5153352763864, 3371.039253812664, 5580.6372585782, 5620.915686264704, 5683.318001839785, 5694.743736531856, 5710.628809127404, 5687.6303040302355, 5705.030144875371, 5783.045797443417, 5788.986927287719, 5835.328937172227, 5107.259447380127, 5155.458676838915, 5212.609054320922, 5223.764930578114, 5320.733963301839, 5310.362756469229, 5346.064438723208, 5347.759350829222, 5351.598630102739, 5380.596455265852, 4908.950910490903, 5168.508991645806, 5186.870328241824, 5211.374635043781, 5218.347706536683, 5174.948250517522, 5179.249008893727, 5180.611454140954, 5289.3123893893935, 5316.4683531646915, 5175.068487672217, 5179.171332586806, 5186.325777648882, 5256.132738098209, 5280.683159010433, 5615.719214039282, 5628.099504079328, 5746.25298711169, 5751.884962300773, 5816.8836623267725, 5106.846794596168, 6115.327313995457, 6190.009598464224, 6193.411625895533, 6206.627602343874, 6198.81403557894, 6252.0, 6270.717817698214, 6283.088952101965, 6291.7797877074545, 5514.91727624082, 5632.802851900205, 5713.342965558931, 5757.165211044375, 5808.796692115798, 6307.959186734138, 6319.083732858801, 6339.334369891095, 6344.8413789654705, 6345.8730825384155, 6132.63204207749, 6133.846653833601, 6134.263888333428, 6162.876742465126, 6194.101855230966, 6120.096412477688, 6121.4529735759925, 6163.691815409212, 6224.564280500412, 6242.906356404657, 5489.752961116759, 5995.770866972261, 6122.295935967331, 6240.095186197976, 6241.875162496724, 6182.381761823782, 6244.1570387997035, 6279.937200627935, 6281.9948808190475, 6291.02489114188, 4895.951040489621, 4916.311716359713, 4939.90120197594, 4978.452370239271, 4992.151334273182, 4432.246518091933, 4477.932831007537, 4511.8195272189405, 4524.389207457017, 4524.5701658342405, 5725.055365864586, 5734.5125664319, 5735.397783232784, 5742.7702891883855, 5765.94234057654, 6246.570969704821, 6255.56211065221, 6271.310155882849, 6285.151504546919, 6292.937070629327, 5578.24693666358, 5633.887322253614, 5637.661740295595, 5639.887202255932, 5681.147827825859, 5431.293931788921, 5447.782088716486, 5455.754491047913, 5460.754266058037, 5474.178873169057, 5392.137258038773, 6333.1766870306565, 6357.777477788348, 6368.745250190033, 6368.745250190126, 6059.666718330534, 6163.3836616338, 6187.783427580013, 6249.125122482947, 6315.494760419064, 6313.210848643919, 6322.46259067966, 6324.209473815773, 6338.11266422692, 6363.236411630634, 6118.785842495491, 6194.659293738888, 6199.969000154971, 6225.128482012612, 6225.470834979077, 6129.264488261437, 6184.8763024738355, 6227.221597300338, 6238.844028899314, 6296.748130074929, 5955.970220148872, 6127.234095738033, 6128.417800308964, 6137.018077107641, 6161.84595385119, 6073.817785466443, 6095.634261944298, 6112.877742445037, 6122.510199184144, 6134.263888333428, 5763.0, 5828.70856457167, 5837.241158649348, 5892.528597712087, 5895.380984996686, 5969.104634304889, 6018.578699490994, 6029.969850150721, 6030.336662967158, 6073.726682299395, 5770.855728606818, 5816.185733997159, 5850.151727999501, 5855.7364918579615, 5861.208736820475], "value": 5684.656143593992, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.83757941089743, 506.12945733338654, 524.1639584862144, 530.3000039947271, 548.3693752184989, 38292.808535957316, 38916.61083389165, 40493.79753101238, 40881.0, 42064.36903446451, 43743.0, 43788.781056094755, 43843.78078109605, 43917.341239881425, 43943.56056439435, 9588.664396746133, 9771.853422198672, 9786.26603004774, 9791.461469619167, 9795.216382689403, 9699.127078562928, 9785.608575656965, 9791.020897910188, 9791.755206119833, 9791.804163916717, 9785.217182625373, 9839.262055345878, 9841.45871977041, 9843.36018158821, 9850.802983940317, 9743.610255589769, 9755.463449510275, 9791.118799308026, 9793.314467987244, 9794.999999999964, 9671.758206044835, 9747.317687761859, 9752.414855108716, 9753.756156096084, 9767.218622510252, 41420.964475888046, 41662.583374166476, 41710.79144604288, 41811.1637767246, 41860.372094418606, 3679.0, 3681.1717363593243, 3970.285348637244, 4048.817803198863, 4063.227986682521, 31709.8414507926, 32146.678533214836, 32226.516602250984, 32302.838485807657, 32878.506822397685, 3290.2596915693775, 3292.522584225274, 3295.505674148873, 3302.190963214004, 3457.343104810078, 532180.0, 532454.0, 534808.0, 535453.0, 536199.0, 61778.0, 62202.68898655568, 62233.0, 62255.6887215561, 62283.6885815568, 35116.82441587776, 35564.0, 35567.82216088903, 37051.88844334674, 65151.0, 16823.66352672964, 16905.577360566083, 16993.0, 17014.31942722302, 17048.82951170497, 13000.479980800663, 13098.803517947239, 13099.803502947463, 13114.60656180316, 13124.950003999656, 18947.957862317704, 19549.511262218275, 19724.30964916221, 19744.901275493532, 19832.305869294793, 6386.744530218739, 6999.23008469068, 7142.78572642652, 8102.230288122619, 8196.13940536247, 905.2667339455063, 966.3332300712486, 987.2595553334999, 1056.3292309383596, 1068.0707784227773, 31011.84494077515, 31129.0, 31452.52821207684, 31555.842220789207, 31719.365612688078, 883.3110174064241, 1050.6742909698019, 1115.4924509348227, 1126.1610100475198, 1208.945597448117, 15355.97162809028, 15614.84385156134, 15637.296321665552, 15876.60308492297, 16139.919300403422, 699307.0, 702565.0, 709795.0, 709975.0, 713318.0, 59132.70433647804, 59469.0, 59556.70221648864, 59715.402845971854, 59782.701086494286, 1175.735459521601, 1267.6387229639693, 1290.3354772292278, 1323.1994643240894, 1413.6960553481051, 32103.357932841678, 32131.51802723008, 32221.67778322187, 32225.838870805495, 32674.673253267163, 51421.48578514167, 51567.74216129045, 52251.95496090133, 52255.73872130767, 52292.73853630707, 548583.0, 551248.0, 551607.0, 552782.0, 553093.0, 23942.880285599156, 26785.464290714466, 27171.86414067917, 27268.31829204286, 27334.726652734014, 49043.264351035476, 49623.50376496333, 49989.0, 50149.74925125351, 50652.74673626608, 39102.60897391103, 40221.994450138984, 40812.1837563253, 41418.1716365665, 42237.36643950405, 40304.99237519086, 40558.594414056664, 40577.0, 41106.588934111474, 41290.79354603208, 39544.0, 39703.80148099356, 40201.39697904592, 40492.0, 42439.0, 33338.0, 33796.0, 35286.0, 36152.819235903655, 36210.27579448449, 25828.062895283303, 26135.0, 26365.0, 26458.206253812416, 26920.0], "value": 20389.01227846141, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.280130943351, 384.5808069204567, 391.47151345683324, 395.1070580488096, 417.3385184482595, 15537.145456999875, 15603.531894043168, 20495.87272700001, 20996.0, 21138.89430552849, 20432.693509597368, 20576.588468230664, 20926.686099708517, 21280.36158915235, 22035.559288814213, 5220.42802137122, 5833.679147646888, 6075.422834830684, 6078.088286757, 6089.6955152242435, 4209.747415155085, 4427.092446048552, 5949.762009519614, 6427.0, 6768.729250829961, 6455.935440645604, 6768.7630932917355, 6768.7969360919005, 6771.525993180479, 6788.287229840869, 6594.901076483857, 6677.131972843499, 6687.364700353459, 6712.093867327946, 6724.193096828363, 5134.871628209287, 5442.727863606843, 5451.100568406208, 5836.095405212199, 5844.620099693507, 13485.865141348559, 13705.246211458365, 13722.588322350346, 17499.737503937453, 25599.360015999562, 2207.668849672546, 2222.5999320122373, 2255.8308126890383, 2743.779018336841, 2744.341358074048, 9581.952090239574, 10055.949720251425, 10060.94969525155, 10554.208434367376, 10569.3129946554, 1826.1873466307497, 1892.8391086757642, 1920.270297287022, 1996.7204591357222, 1999.7600287965392, 98174.0, 128157.0, 128613.35693321568, 134350.2824858763, 139854.0, 11248.412865041086, 11420.657380278604, 11606.825897611543, 11675.065994720402, 11869.46587403569, 14641.48754793577, 14749.336279867432, 14758.704825903636, 14792.852071479147, 14811.629709257355, 8533.146685331541, 8676.6529338827, 8727.956360218159, 8782.780430489289, 8812.603432845537, 8607.741767746978, 8620.439671421338, 8621.22408983182, 8740.344474164463, 8770.736877893672, 4503.279475284005, 4577.5880170784785, 4582.152301824173, 4651.67438279324, 4657.86026419208, 1582.968340633204, 1654.983450165483, 1750.0296735905044, 1830.8260715232032, 1925.6424220924214, 390.4631132193234, 397.73351854257714, 877.4735158904733, 900.4192295969063, 992.1417973453056, 11896.643100706993, 12261.448234829453, 12266.634931496646, 12344.0, 12392.690182745504, 437.4182337491129, 442.23272622000803, 450.83995181710674, 463.29579039859226, 476.53061734191795, 9991.550380232908, 12066.818997715041, 12365.876341236652, 12427.37863106841, 12675.11274210815, 617793.0, 624364.0, 629705.0, 636465.0, 639854.0, 132481.0, 135400.0, 137144.2284137317, 141063.294683528, 143393.28303358625, 988.4020167798523, 1017.8473229015707, 1034.1726618705156, 1034.7464871106627, 1036.5387402605768, 20420.183192672128, 20860.791392085885, 20865.478363041046, 20888.37334879956, 20890.582188356453, 58328.125078124714, 58478.0, 58520.4147958532, 58548.0, 59451.702741487745, 87021.56489217513, 89054.55472722594, 89750.55124724595, 89778.0, 89924.10075899419, 6400.295967443484, 8343.0, 8422.157784221774, 8626.568671566522, 8704.956475217583, 10353.223508236762, 13411.262380569162, 13616.0, 13667.38496767628, 13844.515441959484, 11565.34992379402, 11732.178747487595, 11751.823722644338, 11873.346965916957, 11973.940130299292, 11614.593489227836, 11618.244814087057, 11785.528578856749, 11880.524779008743, 11914.285142891455, 11196.66410007701, 11718.531258749554, 12031.4585843639, 12039.75920481568, 12068.75862482763, 5855.0, 7584.582847943416, 7640.0, 7663.540187588763, 8592.183742544448, 8205.179482051746, 9902.702918912444, 11496.310221386744, 11608.01331886799, 11806.940965295118], "value": 9326.200341474207, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1260.7100366915604, 1544.2741911301687, 1575.9527214183572, 1758.9120543972806, 1883.7833649130355, 7502.774916752499, 7949.960250198756, 7950.40371972103, 7957.323627491663, 7958.641861116249, 8078.515289082659, 8351.4989100654, 8352.039515455725, 8455.830883382328, 8487.193716596928, 2296.9770302297015, 2300.6779050932882, 2332.3469428559947, 2333.6616190652344, 2337.041812856729, 2285.4172186092524, 2319.5708793873105, 2323.8140948724144, 2325.7209134903837, 2332.230363979891, 2263.977360226393, 2300.6779050932882, 2301.723793144825, 2315.2128276386024, 2317.3627252505526, 2279.1225378229337, 2279.1453205048106, 2279.2022792022826, 2279.31620513846, 2280.133549251282, 1947.7857435682063, 2261.1746712450004, 2266.77332266772, 2267.682524446579, 2277.9886100569556, 8499.787505312417, 8514.701985430542, 8550.657973681045, 8561.614727337223, 8570.871436928452, 4978.6514943953935, 4981.402231732215, 5123.077845987721, 5131.4098878629065, 5138.511841375063, 3395.898123056312, 3443.9827800860835, 3538.097785064818, 3539.94690079649, 3543.344481270973, 1028.0850043461326, 1046.5552140340326, 1046.984295235572, 1053.5522402978704, 1070.2187403195633, 11072.003519683187, 11161.497732602053, 11163.553457861757, 11166.274192177483, 11184.776304473866, 4117.547069822317, 4370.0, 4383.583559561836, 4393.670474714345, 4397.560243975641, 8733.344999124964, 8841.336899732545, 8846.955765221133, 8854.513001784964, 8854.822903541894, 5020.698758074527, 5022.196448568212, 5028.120078986163, 5029.06962211992, 5030.094582975026, 3954.5847685992558, 4243.7029407941145, 4653.511381304914, 4716.434027916672, 4744.905101897943, 3604.9279014419567, 3624.655657712513, 3680.3743363628246, 3688.9631103689157, 3723.236736469017, 1120.523777394604, 1122.6014764758556, 1133.0822034152363, 1136.5851464215502, 1136.9488373023235, 591.4596806715168, 804.6982381606897, 923.3444254579324, 954.5561313989028, 974.0697633759743, 5629.408912064257, 5720.628159169724, 5796.188533605298, 5874.676892770854, 5880.0591905295805, 434.8977990172307, 477.86858613881463, 492.9334539837148, 497.965142440033, 649.9447546958562, 3187.9681203188134, 3949.822257998397, 4014.8394064237104, 4025.516937967404, 4042.15114825884, 9987.700368988797, 10498.370097794157, 10499.107575856138, 10516.894831051592, 10537.367757934548, 10056.34633748804, 10466.424346660855, 10470.842937355947, 10477.633282835064, 10494.790104197873, 7739.380849532024, 7977.321927636216, 8014.278714915686, 8088.9595552021865, 8104.554249516215, 5046.722430266371, 5746.569007324384, 5853.590248682552, 5873.823785286449, 5893.705314734332, 9533.618655253713, 9579.617834394905, 9708.271879608916, 9717.951410242904, 9738.513074346396, 9555.331126821056, 9875.654352097643, 9887.505624718591, 9893.159081477866, 9905.405675659484, 7356.668949897375, 7474.289942455458, 7504.6247687614305, 7507.061617297838, 7531.510451820614, 7677.078750549969, 7921.485103468256, 7985.0, 8074.596270186585, 8146.307563857138, 6317.873642527215, 7347.412207023318, 7357.1907090218965, 7463.701451941862, 7515.849683006419, 7179.461540384387, 7269.963650181715, 7331.303526165006, 7387.224341444071, 7399.889001664872, 6817.5909445433435, 6859.382655561023, 6935.445164386938, 6935.791926242221, 6937.271586483346, 4921.3356196912955, 4928.753562321798, 4953.2570114483115, 5009.173486374707, 5016.097102521581, 4972.776225069808, 5020.046191223656, 5053.772580233825, 5072.923906141485, 5103.5661968733075], "value": 4354.302906823792, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.3114829337404, 314.31008935386836, 317.0599173450718, 319.43141208648615, 322.061191626409, 4714.962708204198, 5414.590507224287, 5431.429699881515, 5474.644148130369, 5533.916991245134, 4523.615492683118, 4755.928661070078, 4836.37127173467, 5395.7032363220005, 5443.918341224875, 3930.646241838234, 3976.9262299179204, 4050.250703619825, 4178.456800615931, 4194.4547208862805, 3421.743369247304, 4117.2177286315655, 4129.421880936671, 4145.834166633331, 4154.127633197032, 4157.875263742093, 4158.438610787551, 4164.333706606943, 4165.250254954107, 4193.287141185991, 3430.451127819562, 3787.621237876218, 3882.378819388898, 4071.735337203102, 4084.3458399348165, 3529.29414117175, 3550.50878630973, 3558.5373901392654, 3588.5155504006884, 3597.94603080954, 5253.605979551511, 5281.78518940642, 5308.840734777924, 5314.787408503656, 5335.1730481775385, 4467.709598876063, 4733.9763301183275, 4746.5080832562835, 4750.45369782476, 4782.928256076161, 4697.976510117427, 5357.812476563302, 5642.178910544728, 5702.885942281173, 5715.6284841485585, 3300.752443566742, 3451.947156117383, 3900.7659540427667, 3950.5851885551892, 3995.2808494470983, 5030.974845125751, 5541.944580554183, 5788.768449262025, 5818.679972601505, 5949.9702501487645, 3566.894262778495, 3953.367461206193, 3969.702272329587, 3992.3811809169624, 4021.8592349267633, 3595.1012246938267, 3965.493112617187, 4050.3519436890547, 4060.127072679388, 4077.571854955246, 4225.81677130404, 4579.610733087725, 4667.019925815617, 4720.103180395714, 4757.0, 5110.058177892452, 5729.166666666627, 5729.799457018984, 5736.881308144872, 5751.0, 1303.1855090568395, 1395.8464568897414, 1395.9948836837461, 1396.0785881318282, 1398.8461269260374, 772.9372113344152, 860.9354298427643, 878.3544095090103, 879.9308839759594, 906.7143849687327, 5069.214271787879, 5205.29728486657, 5229.215617657305, 5253.264542963988, 5255.372313843079, 4862.930155365814, 5693.772249109989, 5740.0, 5763.423657634287, 5787.032408980883, 4504.0, 4983.700977941335, 5084.593232541388, 5215.817446389358, 5285.867853303698, 2582.341502916744, 3015.517517197238, 3044.0106965236387, 3051.61854768154, 3052.9084127476212, 5945.91892837512, 5981.401859814071, 6000.219971403777, 6023.698815059317, 6029.879402411927, 5249.976254630386, 5464.9180262296095, 5591.41290164535, 5898.4101589840675, 5989.520838332923, 5130.30740849988, 5942.762289508458, 5990.7903223386975, 6022.879542409128, 6078.513718902565, 5041.747912604282, 5556.805511807229, 5783.467381143916, 5790.7973220937065, 5825.446582574648, 4450.933236001398, 5126.9743651283, 5177.945012442677, 5486.835394938158, 5780.479756821906, 5613.1860880171325, 6057.818265452043, 6060.151578778887, 6072.0, 6090.390960903874, 3306.7850589711593, 3673.651003154696, 3930.9017274568364, 3978.1645854370904, 3983.585827031362, 5120.564751996122, 5900.911486327795, 5909.438603332676, 5933.436323549256, 5951.750132472056, 4783.808647654055, 5001.324821149244, 5791.391903850035, 5793.623414478046, 5819.854503637443, 5452.991196628636, 5677.801276955287, 5681.977244096102, 5699.5155411790465, 5711.029125048834, 5305.283786688748, 5543.223948647112, 5558.694271814928, 5573.832785016456, 5588.804391846428, 5539.861503462445, 5596.664200148004, 5667.631603945731, 5742.3109226891365, 5757.827265182052, 5126.846459546572, 5195.5583775378, 5618.7190640467, 5636.661800291996, 5658.660480371191], "value": 4146.945035073477, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.3817600479605, 785.4855069929198, 794.3724457678435, 818.1491249100935, 847.0321564026982, 15372.154531500762, 16086.034837909734, 16202.432914847983, 16221.432249871257, 16223.756643650326, 14156.646083847909, 14930.104193748355, 14947.925260373682, 14970.775438368433, 15103.244837758122, 708.9432845372357, 731.3454458259881, 731.6232140447673, 874.1040433555593, 882.9072947340533, 698.8637215742907, 891.6522556203086, 892.3307519360479, 894.1773568317163, 895.0601868038566, 935.672514619881, 938.474454305591, 938.7512309238058, 939.8120375924842, 941.5762906691982, 739.0134170881852, 912.0742446416892, 938.7840796616782, 941.8210539997447, 943.7451887990272, 695.537467584056, 696.8048946295046, 697.8988046733195, 698.1029377250209, 700.3766647683566, 14949.0, 14968.0, 14977.475788347356, 14977.700445991019, 15030.0, 13970.441182352795, 13983.510577129751, 14064.859351406458, 14090.788638170436, 14109.294535273195, 11598.49219601455, 12551.560695375618, 12596.748065038739, 12685.429155688016, 12809.423575939012, 4490.146872094324, 4581.358609794632, 4589.082183563267, 4689.119042543149, 4744.952550474485, 96905.0, 98628.0137198626, 98967.0, 99545.50227248817, 104737.47631261795, 11156.553737850558, 11165.944170279097, 11178.835429905408, 11275.94362028201, 11523.53905843757, 21779.891100544395, 21808.0, 21822.236221732164, 21825.454363641034, 21848.89075554644, 6660.0, 7753.689852405841, 7945.682172713143, 8098.028236611526, 8106.797330066677, 4279.593438623326, 4341.6526677865695, 4422.601965823092, 4447.577480139446, 4522.072975040109, 3358.445856433661, 3467.8612855486003, 3468.4277094279664, 3470.7743996640643, 3472.73954453417, 569.8347479230977, 643.3791391307411, 651.6546230497844, 651.8109748172976, 653.330336405179, 493.3438526759401, 641.1985018726592, 675.598019178588, 696.7805141380449, 720.0711082703255, 8058.435909486397, 9278.39690420134, 9534.713958581253, 9553.61785528585, 9658.3722058066, 518.2925306956054, 568.4770011589347, 631.5200447659812, 663.9575865890506, 682.853186564891, 4234.280172370704, 4591.265397536378, 4604.516525764813, 4628.60656844172, 4629.236176030984, 135325.0, 139410.30294848664, 141010.0, 141903.2904835469, 146531.0, 80524.19475805284, 92349.61475577872, 121921.0, 125072.37463812623, 128241.0, 63058.36941630525, 105155.4742226284, 109465.45267273612, 109515.45242273738, 112325.0, 9151.908480915106, 9934.751631209278, 9936.0, 9957.651482198089, 9979.351342162736, 55684.0, 57718.42281577131, 58905.70547147237, 59709.0, 63922.76270330516, 69867.0, 74087.0, 86658.56670716606, 89865.20269594702, 89985.0, 13739.106958047694, 13785.724285514434, 13826.198318691502, 14093.718125637635, 14097.083689560146, 14899.47851825181, 15413.952010169958, 15420.768688469907, 15424.383024679337, 15937.282822272793, 13966.37151328213, 14179.0, 14401.063930844462, 14725.129917417416, 14924.253787310374, 11698.824517632413, 14202.431902724189, 14264.215468148936, 14390.712185756436, 14708.779368309699, 13519.594412167651, 13616.455341786217, 13840.552391912393, 14018.719625607635, 14345.784813228018, 9597.520123993912, 11267.830982535104, 11365.659030229106, 11568.132390070947, 12050.205094195819, 9884.654037108667, 10660.573577056832, 10738.194635402533, 11080.944595276973, 11750.70623234426], "value": 7822.83509076103, "unit": "score"}], "name": "dromaeo_css", "value": 7311.009352618043, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 113.0, 112.0, 111.0, 112.0, 113.0, 112.0, 111.0, 114.0, 110.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 108.0, 107.0, 159.0, 108.0, 108.0, 128.0, 107.0, 141.0, 107.0], "value": 121.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 187.0, 186.0, 186.0, 183.0, 180.0, 183.0, 187.0, 184.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 57.0, 88.0, 58.0, 57.0, 59.0, 67.0, 83.0, 56.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 93.0, 92.0, 72.0, 74.0, 69.0, 98.0, 71.0, 68.0, 103.0], "value": 82.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 87.0, 89.0, 86.0, 89.0, 88.0, 88.0, 85.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 86.0, 85.0, 87.0, 86.0, 84.0, 85.0, 87.0], "value": 86.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 52.0, 53.0, 54.0, 53.0, 53.0, 54.0, 53.0, 52.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 73.0, 77.0, 78.0, 75.0, 72.0, 72.0, 73.0, 76.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 83.0, 87.0, 103.0, 82.0, 81.0, 83.0, 83.0, 80.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 137.0, 153.0, 147.0, 144.0, 124.0, 112.0, 151.0, 236.0, 149.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 226.0, 172.0, 173.0, 176.0, 171.0, 186.0, 236.0, 173.0, 171.0], "value": 187.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 66.0, 68.0, 69.0, 68.0, 67.0, 66.0, 68.0, 68.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1453.1000000000001, "alertThreshold": 2.0}]} 10:32:07 INFO - Return code: 0 10:32:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:32:07 INFO - # TBPL SUCCESS # 10:32:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:32:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:32:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:32:07 INFO - 2016-07-18 10:32:07.659 screenresolution[6437:108879] starting screenresolution argv=screenresolution get 10:32:07 INFO - 2016-07-18 10:32:07.662 screenresolution[6437:108879] Display 0: 1600x1200x32@60 10:32:07 INFO - 2016-07-18 10:32:07.668 screenresolution[6438:108882] starting screenresolution argv=screenresolution list 10:32:07 INFO - Available Modes on Display 0 10:32:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:32:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:32:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:32:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:32:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:32:07 INFO - Intel Iris: 10:32:07 INFO - Chipset Model: Intel Iris 10:32:07 INFO - Type: GPU 10:32:07 INFO - Bus: Built-In 10:32:07 INFO - VRAM (Dynamic, Max): 1536 MB 10:32:07 INFO - Vendor: Intel (0x8086) 10:32:07 INFO - Device ID: 0x0a2e 10:32:07 INFO - Revision ID: 0x0009 10:32:07 INFO - Displays: 10:32:07 INFO - Display: 10:32:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:32:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:32:07 INFO - Display Serial Number: 1600x1200 60 10:32:07 INFO - Main Display: Yes 10:32:07 INFO - Mirror: Off 10:32:07 INFO - Online: Yes 10:32:07 INFO - Rotation: Supported 10:32:07 INFO - Return code: 0 10:32:07 INFO - Running post-action listener: _resource_record_post_action 10:32:07 INFO - ##### 10:32:07 INFO - ##### Finished run-tests step (success) 10:32:07 INFO - ##### 10:32:07 INFO - Running post-run listener: _resource_record_post_run 10:32:07 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 2739712; Write bytes: 703982592; Read time: 56; Write time: 1996 10:32:07 INFO - TinderboxPrint: CPU usage
53.4% 10:32:07 INFO - TinderboxPrint: I/O read bytes / time
2,739,712 / 56 10:32:07 INFO - TinderboxPrint: I/O write bytes / time
703,982,592 / 1,996 10:32:07 INFO - TinderboxPrint: CPU idle
2,255.9 (46.6%) 10:32:07 INFO - TinderboxPrint: CPU system
966.0 (19.9%) 10:32:07 INFO - TinderboxPrint: CPU user
1,623.7 (33.5%) 10:32:07 INFO - TinderboxPrint: Swap in / out
409,833,472 / 0 10:32:07 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 388766208; Write bytes: 382662144; Read time: 29238; Write time: 679 10:32:07 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 2293248; Write bytes: 294045184; Read time: 44; Write time: 1266 10:32:08 INFO - Running post-run listener: _upload_blobber_files 10:32:08 INFO - Blob upload gear active. 10:32:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:32:08 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 10:32:08 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'] 10:32:08 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 10:32:08 INFO - (blobuploader) - INFO - Open directory for files ... 10:32:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:32:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:32:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:32:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:32:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:32:09 INFO - (blobuploader) - INFO - Done attempting. 10:32:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:32:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:32:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:32:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:32:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:32:10 INFO - (blobuploader) - INFO - Done attempting. 10:32:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:32:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:32:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:32:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:32:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:32:16 INFO - (blobuploader) - INFO - Done attempting. 10:32:16 INFO - (blobuploader) - INFO - Iteration through files over. 10:32:16 INFO - Return code: 0 10:32:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:32:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:32:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a593e7cff380a57b5530886daf808be1244a6ac3635340efe1fd83c9ac566dbb6d78aa13c28918b761deafc50f28181d6823cf7e581bdbe8572a1b4fea99dd5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6feb2f7c874c1dd9380ef9d8b8df3d314448ac17ad266f9823030329d76180fba74e4151f97de4b4ac99c99a849205fbf39619bb7f599b95d843a24d3d0bf89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bfbdee527d912d7ebdf6bac274bd0d6523a80d8c0b28cb10753a5238ab9c41b82a47bc3e495329d82789808d9e0920f7717c4834ee362c350d3d25b70297e16"} 10:32:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:32:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:32:16 INFO - Contents: 10:32:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a593e7cff380a57b5530886daf808be1244a6ac3635340efe1fd83c9ac566dbb6d78aa13c28918b761deafc50f28181d6823cf7e581bdbe8572a1b4fea99dd5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6feb2f7c874c1dd9380ef9d8b8df3d314448ac17ad266f9823030329d76180fba74e4151f97de4b4ac99c99a849205fbf39619bb7f599b95d843a24d3d0bf89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bfbdee527d912d7ebdf6bac274bd0d6523a80d8c0b28cb10753a5238ab9c41b82a47bc3e495329d82789808d9e0920f7717c4834ee362c350d3d25b70297e16"} 10:32:16 INFO - Running post-run listener: copy_logs_to_upload_dir 10:32:16 INFO - Copying logs to upload dir... 10:32:16 INFO - mkdir: /builds/slave/test/build/upload/logs 10:32:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.793954 ========= master_lag: 0.77 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-18 10:32:17.522573) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 10:32:17.526381) ========= 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.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners TMPDIR=/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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/a593e7cff380a57b5530886daf808be1244a6ac3635340efe1fd83c9ac566dbb6d78aa13c28918b761deafc50f28181d6823cf7e581bdbe8572a1b4fea99dd5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6feb2f7c874c1dd9380ef9d8b8df3d314448ac17ad266f9823030329d76180fba74e4151f97de4b4ac99c99a849205fbf39619bb7f599b95d843a24d3d0bf89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bfbdee527d912d7ebdf6bac274bd0d6523a80d8c0b28cb10753a5238ab9c41b82a47bc3e495329d82789808d9e0920f7717c4834ee362c350d3d25b70297e16"} build_url:https://queue.taskcluster.net/v1/task/N0nih6YsTGama-aLQ0cFhQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011289 build_url: 'https://queue.taskcluster.net/v1/task/N0nih6YsTGama-aLQ0cFhQ/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/a593e7cff380a57b5530886daf808be1244a6ac3635340efe1fd83c9ac566dbb6d78aa13c28918b761deafc50f28181d6823cf7e581bdbe8572a1b4fea99dd5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6feb2f7c874c1dd9380ef9d8b8df3d314448ac17ad266f9823030329d76180fba74e4151f97de4b4ac99c99a849205fbf39619bb7f599b95d843a24d3d0bf89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bfbdee527d912d7ebdf6bac274bd0d6523a80d8c0b28cb10753a5238ab9c41b82a47bc3e495329d82789808d9e0920f7717c4834ee362c350d3d25b70297e16"}' ========= master_lag: 0.25 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 10:32:17.788875) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:32:17.789218) ========= 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.kswAVs0NKZ/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.pq2Yt9ZauA/Listeners TMPDIR=/var/folders/n5/8vlsdw_12tddqc4b5pnh50t000000w/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.005507 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 10:32:17.815439) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 10:32:17.815754) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 10:32:17.816097) ========= ========= Total master_lag: 1.11 =========