builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-019 starttime: 1482045318.58 results: success (0) buildid: 20161217231440 builduid: 0a4bf8a060894e47a64ea1c36f5fe2ad revision: ad85a32325fb0867bd384965285bc71d92c38984 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 23:15:18.575359) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 23:15:18.575845) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 23:15:18.576111) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 23:15:18.591010) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 23:15:18.591290) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-17 23:15:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 111M=0s 2016-12-17 23:15:18 (111 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111367 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 23:15:18.717934) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 23:15:18.718204) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013847 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 23:15:18.768733) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-17 23:15:18.769073) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ad85a32325fb0867bd384965285bc71d92c38984 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ad85a32325fb0867bd384965285bc71d92c38984 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-17 23:15:18,902 truncating revision to first 12 chars 2016-12-17 23:15:18,902 Setting DEBUG logging. 2016-12-17 23:15:18,902 attempt 1/10 2016-12-17 23:15:18,902 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/ad85a32325fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-17 23:15:19,899 unpacking tar archive at: mozilla-inbound-ad85a32325fb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.326827 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-17 23:15:20.116653) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 23:15:20.116949) ========= script_repo_revision: ad85a32325fb0867bd384965285bc71d92c38984 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 23:15:20.117371) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 23:15:20.117694) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 23:15:20.127693) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-17 23:15:20.127964) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:15:20 INFO - MultiFileLogger online at 20161217 23:15:20 in /builds/slave/test 23:15:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 23:15:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:15:20 INFO - {'append_to_log': False, 23:15:20 INFO - 'base_work_dir': '/builds/slave/test', 23:15:20 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 23:15:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:15:20 INFO - 'branch': 'Mozilla-Inbound', 23:15:20 INFO - 'buildbot_json_path': 'buildprops.json', 23:15:20 INFO - 'config_files': ('talos/linux_config.py',), 23:15:20 INFO - 'default_actions': ('clobber', 23:15:20 INFO - 'read-buildbot-config', 23:15:20 INFO - 'download-and-extract', 23:15:20 INFO - 'populate-webroot', 23:15:20 INFO - 'create-virtualenv', 23:15:20 INFO - 'install', 23:15:20 INFO - 'run-tests'), 23:15:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:15:20 INFO - 'download_minidump_stackwalk': True, 23:15:20 INFO - 'download_symbols': 'ondemand', 23:15:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:15:20 INFO - 'tooltool.py': '/tools/tooltool.py', 23:15:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:15:20 INFO - '/tools/misc-python/virtualenv.py')}, 23:15:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:15:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:15:20 INFO - 'installer_path': 'installer.exe', 23:15:20 INFO - 'log_level': 'info', 23:15:20 INFO - 'log_name': 'talos', 23:15:20 INFO - 'log_to_console': True, 23:15:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:15:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:15:20 INFO - 'opt_config_files': (), 23:15:20 INFO - 'pip_index': False, 23:15:20 INFO - 'sps_profile': False, 23:15:20 INFO - 'sps_profile_interval': 0, 23:15:20 INFO - 'suite': 'dromaeojs', 23:15:20 INFO - 'system_bits': '32', 23:15:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:15:20 INFO - 'title': 'talos-linux64-ix-019', 23:15:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:15:20 INFO - 'use_talos_json': True, 23:15:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:15:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:15:20 INFO - 'work_dir': 'build'} 23:15:20 INFO - [mozharness: 2016-12-18 07:15:20.356395Z] Running clobber step. 23:15:20 INFO - Running pre-action listener: _resource_record_pre_action 23:15:20 INFO - Running main action method: clobber 23:15:20 INFO - rmtree: /builds/slave/test/build 23:15:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:15:20 INFO - Running post-action listener: _resource_record_post_action 23:15:20 INFO - [mozharness: 2016-12-18 07:15:20.804526Z] Finished clobber step (success) 23:15:20 INFO - [mozharness: 2016-12-18 07:15:20.804627Z] Running read-buildbot-config step. 23:15:20 INFO - Running pre-action listener: _resource_record_pre_action 23:15:20 INFO - Running main action method: read_buildbot_config 23:15:20 INFO - Using buildbot properties: 23:15:20 INFO - { 23:15:20 INFO - "project": "", 23:15:20 INFO - "product": "firefox", 23:15:20 INFO - "script_repo_revision": "production", 23:15:20 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 23:15:20 INFO - "repository": "", 23:15:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 23:15:20 INFO - "buildid": "20161217231440", 23:15:20 INFO - "stage_platform": "linux64-pgo", 23:15:20 INFO - "basedir": "/builds/slave/test", 23:15:20 INFO - "buildnumber": 2289, 23:15:20 INFO - "platform": "ubuntu64_hw", 23:15:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 23:15:20 INFO - "slavebuilddir": "test", 23:15:20 INFO - "branch": "mozilla-inbound", 23:15:20 INFO - "revision": "ad85a32325fb0867bd384965285bc71d92c38984", 23:15:20 INFO - "slavename": "talos-linux64-ix-019", 23:15:20 INFO - "builduid": "0a4bf8a060894e47a64ea1c36f5fe2ad", 23:15:20 INFO - "repo_path": "integration/mozilla-inbound" 23:15:20 INFO - } 23:15:20 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 23:15:20 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 23:15:20 INFO - Running post-action listener: _resource_record_post_action 23:15:20 INFO - [mozharness: 2016-12-18 07:15:20.806996Z] Finished read-buildbot-config step (success) 23:15:20 INFO - [mozharness: 2016-12-18 07:15:20.807086Z] Running download-and-extract step. 23:15:20 INFO - Running pre-action listener: _resource_record_pre_action 23:15:20 INFO - Running main action method: download_and_extract 23:15:20 INFO - mkdir: /builds/slave/test/build/tests 23:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:20 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:15:20 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:15:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:15:20 INFO - Downloaded 1361 bytes. 23:15:20 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:15:20 INFO - Using the following test package requirements: 23:15:20 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 23:15:20 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:15:20 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:15:20 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:15:20 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:15:20 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:15:20 INFO - u'jsshell-linux-x86_64.zip'], 23:15:20 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:15:20 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:15:20 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 23:15:20 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:15:20 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:15:20 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:15:20 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:15:20 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:15:20 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:15:20 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:15:20 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:15:20 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:15:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 23:15:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:15:20 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 23:15:21 INFO - Expected file size: 21829291 23:15:21 INFO - Obtained file size: 21829291 23:15:25 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:15:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 23:15:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:15:25 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 23:15:25 INFO - Expected file size: 21829291 23:15:25 INFO - Obtained file size: 21829291 23:15:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 23:15:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:15:29 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 23:15:30 INFO - Expected file size: 14409734 23:15:30 INFO - Obtained file size: 14409734 23:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:30 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:15:30 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:15:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:15:31 INFO - Downloaded 59809433 bytes. 23:15:31 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:15:31 INFO - mkdir: /builds/slave/test/properties 23:15:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:15:31 INFO - Writing to file /builds/slave/test/properties/build_url 23:15:31 INFO - Contents: 23:15:31 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:15:31 INFO - Running post-action listener: _resource_record_post_action 23:15:31 INFO - Running post-action listener: set_extra_try_arguments 23:15:31 INFO - [mozharness: 2016-12-18 07:15:31.964720Z] Finished download-and-extract step (success) 23:15:31 INFO - [mozharness: 2016-12-18 07:15:31.964949Z] Running populate-webroot step. 23:15:31 INFO - Running pre-action listener: _resource_record_pre_action 23:15:31 INFO - Running main action method: populate_webroot 23:15:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:15:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:15:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:15:31 INFO - '--tppagecycles', 23:15:31 INFO - '7'], 23:15:31 INFO - 'tests': ['tsvgm']}}, 23:15:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:15:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:15:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:15:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:15:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:15:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:15:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:15:31 INFO - 'talos_options': ['--disable-e10s'], 23:15:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:15:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:15:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:15:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:15:31 INFO - 'talos_options': ['--disable-e10s'], 23:15:31 INFO - 'tests': ['damp', 'tps']}, 23:15:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:15:31 INFO - 'tests': ['damp', 'tps']}, 23:15:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:15:31 INFO - 'tests': ['dromaeo_dom']}, 23:15:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:15:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:15:31 INFO - 'tests': ['basic_compositor_video']}, 23:15:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:15:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:15:31 INFO - 'tests': ['a11yr', 23:15:31 INFO - 'ts_paint', 23:15:31 INFO - 'tpaint', 23:15:31 INFO - 'sessionrestore', 23:15:31 INFO - 'sessionrestore_no_auto_restore', 23:15:31 INFO - 'tabpaint']}, 23:15:31 INFO - 'other-e10s': {'tests': ['a11yr', 23:15:31 INFO - 'ts_paint', 23:15:31 INFO - 'tpaint', 23:15:31 INFO - 'sessionrestore', 23:15:31 INFO - 'sessionrestore_no_auto_restore', 23:15:31 INFO - 'tabpaint']}, 23:15:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:15:31 INFO - 'ts_paint', 23:15:31 INFO - 'tpaint', 23:15:31 INFO - 'sessionrestore', 23:15:31 INFO - 'sessionrestore_no_auto_restore', 23:15:31 INFO - 'tabpaint']}, 23:15:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:15:31 INFO - 'ts_paint', 23:15:31 INFO - 'tpaint', 23:15:31 INFO - 'sessionrestore', 23:15:31 INFO - 'sessionrestore_no_auto_restore', 23:15:31 INFO - 'tabpaint']}, 23:15:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:15:31 INFO - 'tests': ['a11yr', 23:15:31 INFO - 'ts_paint', 23:15:31 INFO - 'tpaint', 23:15:31 INFO - 'sessionrestore', 23:15:31 INFO - 'sessionrestore_no_auto_restore', 23:15:31 INFO - 'tabpaint']}, 23:15:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:15:31 INFO - 'tests': ['a11yr', 23:15:31 INFO - 'ts_paint', 23:15:31 INFO - 'tpaint', 23:15:31 INFO - 'sessionrestore', 23:15:31 INFO - 'sessionrestore_no_auto_restore', 23:15:31 INFO - 'tabpaint']}, 23:15:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:15:31 INFO - 'tests': ['tsvgx', 23:15:31 INFO - 'tsvgr_opacity', 23:15:31 INFO - 'tart', 23:15:31 INFO - 'tscrollx', 23:15:31 INFO - 'cart', 23:15:31 INFO - 'tsvg_static']}, 23:15:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:15:31 INFO - 'tsvgr_opacity', 23:15:31 INFO - 'tart', 23:15:31 INFO - 'tscrollx', 23:15:31 INFO - 'cart', 23:15:31 INFO - 'tsvg_static']}, 23:15:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:15:31 INFO - 'talos_options': ['--disable-e10s'], 23:15:31 INFO - 'tests': ['tp5o']}, 23:15:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:15:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:15:31 INFO - 'talos_options': ['--disable-e10s', 23:15:31 INFO - '--xperf_path', 23:15:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:15:31 INFO - 'tests': ['tp5n']}, 23:15:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:15:31 INFO - 'talos_options': ['--xperf_path', 23:15:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:15:31 INFO - 'tests': ['tp5n']}}, 23:15:31 INFO - 'talos.zip': {'path': '', 23:15:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:15:31 INFO - Running post-action listener: _resource_record_post_action 23:15:31 INFO - [mozharness: 2016-12-18 07:15:31.980677Z] Finished populate-webroot step (success) 23:15:31 INFO - [mozharness: 2016-12-18 07:15:31.980766Z] Running create-virtualenv step. 23:15:31 INFO - Running pre-action listener: _resource_record_pre_action 23:15:31 INFO - Running main action method: create_virtualenv 23:15:31 INFO - Creating virtualenv /builds/slave/test/build/venv 23:15:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:15:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:15:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:15:32 INFO - Using real prefix '/usr' 23:15:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:15:32 INFO - Installing distribute.............................................................................................................................................................................................done. 23:15:34 INFO - Installing pip.................done. 23:15:34 INFO - Return code: 0 23:15:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:15:34 INFO - Reading from file tmpfile_stdout 23:15:34 INFO - Output received: 23:15:34 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 23:15:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2023a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2285630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230e850>, '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 0x22836d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2283b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:15: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', 'pip>=1.5'] in /builds/slave/test/build 23:15: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 pip>=1.5 23:15:34 INFO - Using env: {'DISPLAY': ':0', 23:15:34 INFO - 'HOME': '/home/cltbld', 23:15:34 INFO - 'LANG': 'en_US.UTF-8', 23:15:34 INFO - 'LANGUAGE': 'en_US:en', 23:15:34 INFO - 'LOGNAME': 'cltbld', 23:15:34 INFO - 'MAIL': '/var/mail/cltbld', 23:15:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:34 INFO - 'MOZ_NO_REMOTE': '1', 23:15:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:15:34 INFO - 'NO_EM_RESTART': '1', 23:15:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:15:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:34 INFO - 'PWD': '/builds/slave/test', 23:15:34 INFO - 'SHELL': '/bin/bash', 23:15:34 INFO - 'SHLVL': '1', 23:15:34 INFO - 'TERM': 'linux', 23:15:34 INFO - 'TMOUT': '86400', 23:15:34 INFO - 'USER': 'cltbld', 23:15:34 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 23:15:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:34 INFO - '_': '/tools/buildbot/bin/python'} 23:15:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:15:34 INFO - Cleaning up... 23:15:35 INFO - Return code: 0 23:15:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:15:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:35 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 0x1f131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2023a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2285630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230e850>, '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 0x22836d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2283b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:15:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:15:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:15:35 INFO - Using env: {'DISPLAY': ':0', 23:15:35 INFO - 'HOME': '/home/cltbld', 23:15:35 INFO - 'LANG': 'en_US.UTF-8', 23:15:35 INFO - 'LANGUAGE': 'en_US:en', 23:15:35 INFO - 'LOGNAME': 'cltbld', 23:15:35 INFO - 'MAIL': '/var/mail/cltbld', 23:15:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:35 INFO - 'MOZ_NO_REMOTE': '1', 23:15:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:15:35 INFO - 'NO_EM_RESTART': '1', 23:15:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:15:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:35 INFO - 'PWD': '/builds/slave/test', 23:15:35 INFO - 'SHELL': '/bin/bash', 23:15:35 INFO - 'SHLVL': '1', 23:15:35 INFO - 'TERM': 'linux', 23:15:35 INFO - 'TMOUT': '86400', 23:15:35 INFO - 'USER': 'cltbld', 23:15:35 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 23:15:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:35 INFO - '_': '/tools/buildbot/bin/python'} 23:15:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:35 INFO - Downloading/unpacking psutil>=3.1.1 23:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:15:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:15:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:15:37 INFO - Installing collected packages: psutil 23:15:37 INFO - Running setup.py install for psutil 23:15:37 INFO - building 'psutil._psutil_linux' extension 23:15:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 23:15:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 23:15:37 INFO - building 'psutil._psutil_posix' extension 23:15:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 23:15:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 23:15:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:15:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:15:38 INFO - Successfully installed psutil 23:15:38 INFO - Cleaning up... 23:15:38 INFO - Return code: 0 23:15:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2023a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2285630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230e850>, '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 0x22836d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2283b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:15:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:15:38 INFO - Using env: {'DISPLAY': ':0', 23:15:38 INFO - 'HOME': '/home/cltbld', 23:15:38 INFO - 'LANG': 'en_US.UTF-8', 23:15:38 INFO - 'LANGUAGE': 'en_US:en', 23:15:38 INFO - 'LOGNAME': 'cltbld', 23:15:38 INFO - 'MAIL': '/var/mail/cltbld', 23:15:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:38 INFO - 'MOZ_NO_REMOTE': '1', 23:15:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:15:38 INFO - 'NO_EM_RESTART': '1', 23:15:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:15:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:38 INFO - 'PWD': '/builds/slave/test', 23:15:38 INFO - 'SHELL': '/bin/bash', 23:15:38 INFO - 'SHLVL': '1', 23:15:38 INFO - 'TERM': 'linux', 23:15:38 INFO - 'TMOUT': '86400', 23:15:38 INFO - 'USER': 'cltbld', 23:15:38 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 23:15:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:38 INFO - '_': '/tools/buildbot/bin/python'} 23:15:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:15:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:15:40 INFO - Installing collected packages: mozsystemmonitor 23:15:40 INFO - Running setup.py install for mozsystemmonitor 23:15:40 INFO - Successfully installed mozsystemmonitor 23:15:40 INFO - Cleaning up... 23:15:40 INFO - Return code: 0 23:15:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15: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', '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 0x1f131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2023a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2285630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230e850>, '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 0x22836d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2283b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:15: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:15: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 jsonschema==2.5.1 23:15:40 INFO - Using env: {'DISPLAY': ':0', 23:15:40 INFO - 'HOME': '/home/cltbld', 23:15:40 INFO - 'LANG': 'en_US.UTF-8', 23:15:40 INFO - 'LANGUAGE': 'en_US:en', 23:15:40 INFO - 'LOGNAME': 'cltbld', 23:15:40 INFO - 'MAIL': '/var/mail/cltbld', 23:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:40 INFO - 'MOZ_NO_REMOTE': '1', 23:15:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:15:40 INFO - 'NO_EM_RESTART': '1', 23:15:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:40 INFO - 'PWD': '/builds/slave/test', 23:15:40 INFO - 'SHELL': '/bin/bash', 23:15:40 INFO - 'SHLVL': '1', 23:15:40 INFO - 'TERM': 'linux', 23:15:40 INFO - 'TMOUT': '86400', 23:15:40 INFO - 'USER': 'cltbld', 23:15:40 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 23:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:40 INFO - '_': '/tools/buildbot/bin/python'} 23:15:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:40 INFO - Downloading/unpacking jsonschema==2.5.1 23:15: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 23:15: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 23:15: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 23:15: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 23:15: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 23:15: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 23:15:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:15:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:42 INFO - Downloading functools32-3.2.3-2.tar.gz 23:15:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:15:42 INFO - warning: no files found matching '*.txt' 23:15:42 INFO - no previously-included directories found matching 'build' 23:15:42 INFO - no previously-included directories found matching 'dist' 23:15:42 INFO - no previously-included directories found matching '.git*' 23:15:42 INFO - Installing collected packages: jsonschema, functools32 23:15:42 INFO - Running setup.py install for functools32 23:15:43 INFO - warning: no files found matching '*.txt' 23:15:43 INFO - no previously-included directories found matching 'build' 23:15:43 INFO - no previously-included directories found matching 'dist' 23:15:43 INFO - no previously-included directories found matching '.git*' 23:15:43 INFO - Successfully installed jsonschema functools32 23:15:43 INFO - Cleaning up... 23:15:43 INFO - Return code: 0 23:15:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:43 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2023a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2285630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230e850>, '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 0x22836d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2283b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:15:43 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:15:43 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 functools32==3.2.3-2 23:15:43 INFO - Using env: {'DISPLAY': ':0', 23:15:43 INFO - 'HOME': '/home/cltbld', 23:15:43 INFO - 'LANG': 'en_US.UTF-8', 23:15:43 INFO - 'LANGUAGE': 'en_US:en', 23:15:43 INFO - 'LOGNAME': 'cltbld', 23:15:43 INFO - 'MAIL': '/var/mail/cltbld', 23:15:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:43 INFO - 'MOZ_NO_REMOTE': '1', 23:15:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:15:43 INFO - 'NO_EM_RESTART': '1', 23:15:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:15:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:43 INFO - 'PWD': '/builds/slave/test', 23:15:43 INFO - 'SHELL': '/bin/bash', 23:15:43 INFO - 'SHLVL': '1', 23:15:43 INFO - 'TERM': 'linux', 23:15:43 INFO - 'TMOUT': '86400', 23:15:43 INFO - 'USER': 'cltbld', 23:15:43 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 23:15:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:43 INFO - '_': '/tools/buildbot/bin/python'} 23:15:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:15:43 INFO - Cleaning up... 23:15:43 INFO - Return code: 0 23:15:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:43 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 0x1f131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2023a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2285630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230e850>, '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 0x22836d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2283b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:15:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:15:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:15:43 INFO - Using env: {'DISPLAY': ':0', 23:15:43 INFO - 'HOME': '/home/cltbld', 23:15:43 INFO - 'LANG': 'en_US.UTF-8', 23:15:43 INFO - 'LANGUAGE': 'en_US:en', 23:15:43 INFO - 'LOGNAME': 'cltbld', 23:15:43 INFO - 'MAIL': '/var/mail/cltbld', 23:15:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:43 INFO - 'MOZ_NO_REMOTE': '1', 23:15:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:15:43 INFO - 'NO_EM_RESTART': '1', 23:15:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:15:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:43 INFO - 'PWD': '/builds/slave/test', 23:15:43 INFO - 'SHELL': '/bin/bash', 23:15:43 INFO - 'SHLVL': '1', 23:15:43 INFO - 'TERM': 'linux', 23:15:43 INFO - 'TMOUT': '86400', 23:15:43 INFO - 'USER': 'cltbld', 23:15:43 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 23:15:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:43 INFO - '_': '/tools/buildbot/bin/python'} 23:15:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:43 INFO - Downloading/unpacking blobuploader==1.2.4 23:15: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 23:15: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 23:15: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 23:15: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 23:15: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 23:15: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 23:15:45 INFO - Downloading blobuploader-1.2.4.tar.gz 23:15:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:15:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:15:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:46 INFO - Downloading docopt-0.6.1.tar.gz 23:15:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:15:46 INFO - Installing collected packages: blobuploader, requests, docopt 23:15:46 INFO - Running setup.py install for blobuploader 23:15:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:15:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:15:46 INFO - Running setup.py install for requests 23:15:46 INFO - Running setup.py install for docopt 23:15:46 INFO - Successfully installed blobuploader requests docopt 23:15:46 INFO - Cleaning up... 23:15:46 INFO - Return code: 0 23:15:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:15:46 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:46 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:46 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 0x1f131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2023a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2285630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230e850>, '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 0x22836d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2283b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:15:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:15:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:15:46 INFO - Using env: {'DISPLAY': ':0', 23:15:46 INFO - 'HOME': '/home/cltbld', 23:15:46 INFO - 'LANG': 'en_US.UTF-8', 23:15:46 INFO - 'LANGUAGE': 'en_US:en', 23:15:46 INFO - 'LOGNAME': 'cltbld', 23:15:46 INFO - 'MAIL': '/var/mail/cltbld', 23:15:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:46 INFO - 'MOZ_NO_REMOTE': '1', 23:15:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:15:46 INFO - 'NO_EM_RESTART': '1', 23:15:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:15:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:46 INFO - 'PWD': '/builds/slave/test', 23:15:46 INFO - 'SHELL': '/bin/bash', 23:15:46 INFO - 'SHLVL': '1', 23:15:46 INFO - 'TERM': 'linux', 23:15:46 INFO - 'TMOUT': '86400', 23:15:46 INFO - 'USER': 'cltbld', 23:15:46 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 23:15:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:46 INFO - '_': '/tools/buildbot/bin/python'} 23:15:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:15:47 INFO - Running setup.py (path:/tmp/pip-SOunUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:15:47 INFO - Running setup.py (path:/tmp/pip-GZFLTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:15:47 INFO - Running setup.py (path:/tmp/pip-hqaehF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:15:47 INFO - Running setup.py (path:/tmp/pip-QFeKrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:15:47 INFO - Running setup.py (path:/tmp/pip-uN3eHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:15:47 INFO - Running setup.py (path:/tmp/pip-gioicg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:15:47 INFO - Running setup.py (path:/tmp/pip-e0leHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:15:47 INFO - Running setup.py (path:/tmp/pip-3_Ve5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:15:47 INFO - Running setup.py (path:/tmp/pip-5wsgsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:15:47 INFO - Running setup.py (path:/tmp/pip-cwnfuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:15:47 INFO - Running setup.py (path:/tmp/pip-3esi0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:15:47 INFO - Running setup.py (path:/tmp/pip-0FC0HD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:15:48 INFO - Running setup.py (path:/tmp/pip-JdV4qn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:15:48 INFO - Running setup.py (path:/tmp/pip-17yD1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:15:48 INFO - Running setup.py (path:/tmp/pip-0TAhQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:15:48 INFO - Running setup.py (path:/tmp/pip-jaUoOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:15:48 INFO - Running setup.py (path:/tmp/pip-KvaXUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:15:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:15:48 INFO - Running setup.py install for manifestparser 23:15:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:15:48 INFO - Running setup.py install for mozcrash 23:15:48 INFO - Running setup.py install for mozdebug 23:15:48 INFO - Running setup.py install for mozdevice 23:15:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:15:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:15:48 INFO - Running setup.py install for mozfile 23:15:48 INFO - Running setup.py install for mozhttpd 23:15:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:15:49 INFO - Running setup.py install for mozinfo 23:15:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:15:49 INFO - Running setup.py install for mozInstall 23:15:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:15:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:15:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:15:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:15:49 INFO - Running setup.py install for mozleak 23:15:49 INFO - Running setup.py install for mozlog 23:15:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:15:49 INFO - Running setup.py install for moznetwork 23:15:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:15:49 INFO - Running setup.py install for mozprocess 23:15:49 INFO - Running setup.py install for mozprofile 23:15:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:15:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:15:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:15:49 INFO - Running setup.py install for mozrunner 23:15:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:15:49 INFO - Running setup.py install for mozscreenshot 23:15:49 INFO - Running setup.py install for moztest 23:15:50 INFO - Running setup.py install for mozversion 23:15:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:15:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:15:50 INFO - Cleaning up... 23:15:50 INFO - Return code: 0 23:15:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15: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 0x1f131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2023a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2285630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230e850>, '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 0x22836d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2283b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:15: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 23:15: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 23:15:50 INFO - Using env: {'DISPLAY': ':0', 23:15:50 INFO - 'HOME': '/home/cltbld', 23:15:50 INFO - 'LANG': 'en_US.UTF-8', 23:15:50 INFO - 'LANGUAGE': 'en_US:en', 23:15:50 INFO - 'LOGNAME': 'cltbld', 23:15:50 INFO - 'MAIL': '/var/mail/cltbld', 23:15:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:50 INFO - 'MOZ_NO_REMOTE': '1', 23:15:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:15:50 INFO - 'NO_EM_RESTART': '1', 23:15:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:15:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:50 INFO - 'PWD': '/builds/slave/test', 23:15:50 INFO - 'SHELL': '/bin/bash', 23:15:50 INFO - 'SHLVL': '1', 23:15:50 INFO - 'TERM': 'linux', 23:15:50 INFO - 'TMOUT': '86400', 23:15:50 INFO - 'USER': 'cltbld', 23:15:50 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 23:15:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:50 INFO - '_': '/tools/buildbot/bin/python'} 23:15:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:15:50 INFO - Running setup.py (path:/tmp/pip-6Quldu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:15: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)) 23:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:15:50 INFO - Running setup.py (path:/tmp/pip-2S_PXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:15:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:15:50 INFO - Running setup.py (path:/tmp/pip-42kgqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:15: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)) 23:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:15:50 INFO - Running setup.py (path:/tmp/pip-qZCM8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:15:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:15:50 INFO - Running setup.py (path:/tmp/pip-_5KLu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:15:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:15:50 INFO - Running setup.py (path:/tmp/pip-sB1xXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:15:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:15:50 INFO - Running setup.py (path:/tmp/pip-h3ffBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:15:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:15:50 INFO - Running setup.py (path:/tmp/pip-o0I6sF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:15: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)) 23:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:15:51 INFO - Running setup.py (path:/tmp/pip-CSmFeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:15: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)) 23:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:15:51 INFO - Running setup.py (path:/tmp/pip-vmc5Yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:15:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:15:51 INFO - Running setup.py (path:/tmp/pip-IfIQCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:15: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)) 23:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:15:51 INFO - Running setup.py (path:/tmp/pip-x9NGIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:15: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)) 23:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:15:51 INFO - Running setup.py (path:/tmp/pip-dyeU5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:15:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:15:51 INFO - Running setup.py (path:/tmp/pip-PMWcE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:15:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:15:51 INFO - Running setup.py (path:/tmp/pip-X020iG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:15:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:15:51 INFO - Running setup.py (path:/tmp/pip-HEWrmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:15:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:15:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:15:51 INFO - Running setup.py (path:/tmp/pip-WKU6Nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:15:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:15:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:15:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:15:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:15:51 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:15:51 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:15:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:15:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:15:53 INFO - Downloading blessings-1.6.tar.gz 23:15:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:15:53 INFO - Installing collected packages: blessings 23:15:53 INFO - Running setup.py install for blessings 23:15:53 INFO - Successfully installed blessings 23:15:53 INFO - Cleaning up... 23:15:53 INFO - Return code: 0 23:15:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:15:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:15:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:15:54 INFO - Reading from file tmpfile_stdout 23:15:54 INFO - Current package versions: 23:15:54 INFO - argparse == 1.2.1 23:15:54 INFO - blessings == 1.6 23:15:54 INFO - blobuploader == 1.2.4 23:15:54 INFO - docopt == 0.6.1 23:15:54 INFO - functools32 == 3.2.3-2 23:15:54 INFO - jsonschema == 2.5.1 23:15:54 INFO - manifestparser == 1.1 23:15:54 INFO - mozInstall == 1.12 23:15:54 INFO - mozcrash == 1.0 23:15:54 INFO - mozdebug == 0.1 23:15:54 INFO - mozdevice == 0.49 23:15:54 INFO - mozfile == 1.2 23:15:54 INFO - mozhttpd == 0.7 23:15:54 INFO - mozinfo == 0.9 23:15:54 INFO - mozleak == 0.1 23:15:54 INFO - mozlog == 3.4 23:15:54 INFO - moznetwork == 0.27 23:15:54 INFO - mozprocess == 0.23 23:15:54 INFO - mozprofile == 0.28 23:15:54 INFO - mozrunner == 6.13 23:15:54 INFO - mozscreenshot == 0.1 23:15:54 INFO - mozsystemmonitor == 0.3 23:15:54 INFO - moztest == 0.7 23:15:54 INFO - mozversion == 1.4 23:15:54 INFO - psutil == 3.1.1 23:15:54 INFO - requests == 1.2.3 23:15:54 INFO - wsgiref == 0.1.2 23:15:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:54 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 0x1f131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2023a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2285630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230e850>, '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 0x22836d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2283b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:15:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:15:54 INFO - Using env: {'DISPLAY': ':0', 23:15:54 INFO - 'HOME': '/home/cltbld', 23:15:54 INFO - 'LANG': 'en_US.UTF-8', 23:15:54 INFO - 'LANGUAGE': 'en_US:en', 23:15:54 INFO - 'LOGNAME': 'cltbld', 23:15:54 INFO - 'MAIL': '/var/mail/cltbld', 23:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:54 INFO - 'MOZ_NO_REMOTE': '1', 23:15:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:15:54 INFO - 'NO_EM_RESTART': '1', 23:15:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:54 INFO - 'PWD': '/builds/slave/test', 23:15:54 INFO - 'SHELL': '/bin/bash', 23:15:54 INFO - 'SHLVL': '1', 23:15:54 INFO - 'TERM': 'linux', 23:15:54 INFO - 'TMOUT': '86400', 23:15:54 INFO - 'USER': 'cltbld', 23:15:54 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 23:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:54 INFO - '_': '/tools/buildbot/bin/python'} 23:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:15:54 INFO - Cleaning up... 23:15:54 INFO - Return code: 0 23:15:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:15:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:15:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:15:54 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 0x1f131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2023a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2285630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x230e850>, '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 0x22836d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2283b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:15:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:15:54 INFO - Using env: {'DISPLAY': ':0', 23:15:54 INFO - 'HOME': '/home/cltbld', 23:15:54 INFO - 'LANG': 'en_US.UTF-8', 23:15:54 INFO - 'LANGUAGE': 'en_US:en', 23:15:54 INFO - 'LOGNAME': 'cltbld', 23:15:54 INFO - 'MAIL': '/var/mail/cltbld', 23:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:15:54 INFO - 'MOZ_NO_REMOTE': '1', 23:15:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:15:54 INFO - 'NO_EM_RESTART': '1', 23:15:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:15:54 INFO - 'PWD': '/builds/slave/test', 23:15:54 INFO - 'SHELL': '/bin/bash', 23:15:54 INFO - 'SHLVL': '1', 23:15:54 INFO - 'TERM': 'linux', 23:15:54 INFO - 'TMOUT': '86400', 23:15:54 INFO - 'USER': 'cltbld', 23:15:54 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 23:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:15:54 INFO - '_': '/tools/buildbot/bin/python'} 23:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:15:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:15:54 INFO - Cleaning up... 23:15:54 INFO - Return code: 0 23:15:54 INFO - Running post-action listener: _resource_record_post_action 23:15:54 INFO - Running post-action listener: _start_resource_monitoring 23:15:54 INFO - Starting resource monitoring. 23:15:54 INFO - [mozharness: 2016-12-18 07:15:54.630542Z] Finished create-virtualenv step (success) 23:15:54 INFO - [mozharness: 2016-12-18 07:15:54.631061Z] Running install step. 23:15:54 INFO - Running pre-action listener: _resource_record_pre_action 23:15:54 INFO - Running main action method: install 23:15:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:15:54 INFO - Reading from file tmpfile_stdout 23:15:54 INFO - Detecting whether we're running mozinstall >=1.0... 23:15:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:15:54 INFO - Reading from file tmpfile_stdout 23:15:54 INFO - Output received: 23:15:54 INFO - Usage: mozinstall [options] installer 23:15:54 INFO - Options: 23:15:54 INFO - -h, --help show this help message and exit 23:15:54 INFO - -d DEST, --destination=DEST 23:15:54 INFO - Directory to install application into. [default: 23:15:54 INFO - "/builds/slave/test"] 23:15:54 INFO - --app=APP Application being installed. [default: firefox] 23:15:54 INFO - mkdir: /builds/slave/test/build/application 23:15:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:16:11 INFO - Reading from file tmpfile_stdout 23:16:11 INFO - Output received: 23:16:11 INFO - /builds/slave/test/build/application/firefox/firefox 23:16:11 INFO - Running post-action listener: _resource_record_post_action 23:16:11 INFO - [mozharness: 2016-12-18 07:16:11.932290Z] Finished install step (success) 23:16:11 INFO - [mozharness: 2016-12-18 07:16:11.932567Z] Running run-tests step. 23:16:11 INFO - Running pre-action listener: _resource_record_pre_action 23:16:11 INFO - Running main action method: run_tests 23:16:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:16:11 INFO - Python 2.7.3 23:16:11 INFO - Return code: 0 23:16:11 INFO - grabbing minidump binary from tooltool 23:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:16:11 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/linux64/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 0x230e850>, '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 0x22836d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2283b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:16:11 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:16:11 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/linux64/releng.manifest -o -c /builds/tooltool_cache 23:16:11 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 23:16:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:16:12 INFO - Return code: 0 23:16:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:16:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:16:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:16:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:16:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:16:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-019 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:16:12 INFO - Using env: {'DISPLAY': ':0', 23:16:12 INFO - 'HOME': '/home/cltbld', 23:16:12 INFO - 'LANG': 'en_US.UTF-8', 23:16:12 INFO - 'LANGUAGE': 'en_US:en', 23:16:12 INFO - 'LOGNAME': 'cltbld', 23:16:12 INFO - 'MAIL': '/var/mail/cltbld', 23:16:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:16:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:12 INFO - 'MOZ_NO_REMOTE': '1', 23:16:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:16:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:16:12 INFO - 'NO_EM_RESTART': '1', 23:16:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:12 INFO - 'PWD': '/builds/slave/test', 23:16:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:16:12 INFO - 'SHELL': '/bin/bash', 23:16:12 INFO - 'SHLVL': '1', 23:16:12 INFO - 'TERM': 'linux', 23:16:12 INFO - 'TMOUT': '86400', 23:16:12 INFO - 'USER': 'cltbld', 23:16:12 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774', 23:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:12 INFO - '_': '/tools/buildbot/bin/python'} 23:16:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:16:12 INFO - mozversion application_buildid: 20161217213010 23:16:12 INFO - mozversion application_changeset: ad85a32325fb0867bd384965285bc71d92c38984 23:16:12 INFO - mozversion application_display_name: Nightly 23:16:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:16:12 INFO - mozversion application_name: Firefox 23:16:12 INFO - mozversion application_remotingname: firefox 23:16:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:16:12 INFO - mozversion application_vendor: Mozilla 23:16:12 INFO - mozversion application_version: 53.0a1 23:16:12 INFO - mozversion platform_buildid: 20161217213010 23:16:12 INFO - mozversion platform_changeset: ad85a32325fb0867bd384965285bc71d92c38984 23:16:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:16:12 INFO - mozversion platform_version: 53.0a1 23:16:12 INFO - using testdate: 1482045372 23:16:12 INFO - actual date: 1482045372 23:16:12 INFO - starting webserver on 'localhost:36824' 23:16:12 INFO - SUITE-START | Running 2 tests 23:16:12 INFO - TEST-START | dromaeo_css 23:16:12 INFO - operating with platform_type : linux_ 23:16:12 INFO - Initialising browser for dromaeo_css test... 23:16:12 INFO - TEST-INFO | started process 7388 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgyNasI/profile http://localhost:36824/getInfo.html) 23:16:16 INFO - PROCESS | 7388 | __metrics Screen width/height:1600/1200 23:16:16 INFO - PROCESS | 7388 | colorDepth:24 23:16:16 INFO - PROCESS | 7388 | Browser inner width/height: 1024/697 23:16:16 INFO - PROCESS | 7388 | __metrics 23:16:17 INFO - PROCESS | 7388 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:16:17 INFO - PROCESS | 7388 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:16:17 INFO - PROCESS | 7388 | console.error: PushService: 23:16:17 INFO - PROCESS | 7388 | stateChangeProcessEnqueue: Error transitioning state 23:16:17 INFO - PROCESS | 7388 | UnknownError 23:16:18 INFO - TEST-INFO | 7388: exit 0 23:16:18 INFO - Browser initialized. 23:16:18 INFO - Running cycle 1/1 for dromaeo_css test... 23:16:18 INFO - TEST-INFO | started process 7472 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgyNasI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:16:30 INFO - PROCESS | 7472 | RSS: Main: 154337280 23:16:30 INFO - PROCESS | 7472 | 23:17:21 INFO - PROCESS | 7472 | 1482045441293 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 23:19:31 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:36824/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36824/tests/dromaeo/cssquery-ext.html) 23:19:33 INFO - PROCESS | 7472 | RSS: Main: 302891008 23:19:33 INFO - PROCESS | 7472 | 23:22:35 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:36824/tests/dromaeo/cssquery-ext.html (next: http://localhost:36824/tests/dromaeo/cssquery-jquery.html) 23:22:36 INFO - PROCESS | 7472 | RSS: Main: 258342912 23:22:36 INFO - PROCESS | 7472 | 23:25:38 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:36824/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36824/tests/dromaeo/cssquery-mootools.html) 23:25:39 INFO - PROCESS | 7472 | RSS: Main: 192622592 23:25:39 INFO - PROCESS | 7472 | 23:28:41 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:36824/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36824/tests/dromaeo/cssquery-prototype.html) 23:28:41 INFO - PROCESS | 7472 | RSS: Main: 207790080 23:28:41 INFO - PROCESS | 7472 | 23:31:44 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:36824/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36824/tests/dromaeo/cssquery-yui.html) 23:31:45 INFO - PROCESS | 7472 | RSS: Main: 192942080 23:31:45 INFO - PROCESS | 7472 | 23:34:48 INFO - PROCESS | 7472 | Cycle 1(1): loaded http://localhost:36824/tests/dromaeo/cssquery-yui.html (next: http://localhost:36824/tests/dromaeo/cssquery-dojo.html) 23:34:48 INFO - PROCESS | 7472 | RSS: Main: 267374592 23:34:48 INFO - PROCESS | 7472 | 23:34:48 INFO - PROCESS | 7472 | __start_tp_report 23:34:48 INFO - PROCESS | 7472 | _x_x_mozilla_page_load 23:34:48 INFO - PROCESS | 7472 | _x_x_mozilla_page_load_details 23:34:48 INFO - PROCESS | 7472 | |i|pagename|runs| 23:34:48 INFO - PROCESS | 7472 | |0;dojo.html;2935.5596660500923;2974.4051189762054;2990.102969109267;2994.820310781352;3006.443807895541;4968.801247950087;4991.201407774757;5166.715830629314;5238.607104467161;5321.1486162214105;5083.288339632454;5233.633645644806;5300.284461597683;5327.493888080636;5378.596605254602;4686.437627484706;4689.671722979376;4731.243001119821;4759.833405830796;4773.140834649762;4791.041791641668;4826.155422801014;4864.343313652665;4865.756712164395;4909.165441874872;4616.69991450558;4839.782209800567;4855.053264613402;4870.683405578644;4954.975225123887;4637.049404871993;4688.203005489075;4703.412073490814;4707.223308154151;4717.693349932278;4704.717716936961;4705.435347758291;4714.175019371596;4738.1708201064675;4771.689840160413;5167.741612919376;5202.375714914235;5217.295665085203;5219.373675158968;5220.92168617471;5412.269343638637;5432.076546987022;5442.292501974757;5520.696361700105;5565.749541270652;5571.9721401393135;5573.080441727151;5578.9163162552595;5595.412481689405;5620;5301.019311427398;5315.707636080053;5350.759215835296;5363.731813409353;5365.597580181502;5670.092785154438;5784;5786.594938354275;5787.160861675034;5799.33307669615;5383.192521121863;5424.294841670558;5518.751656175482;5524.640898341595;5576;5566.109422492382;5588.0779671354585;5594.049011668027;5638.802641907596;5688.715564221772;5618.073017951992;5630.887382252332;5631.958087753778;5633.887322253532;5634.85912852182;5390.056740070472;5602.915956260659;5635.971820140955;5649.12438572022;5685.204071430003;4218.367244913226;4266.1254442839145;4276.016516201291;4292.806823692941;4306.698531102856;3842.212346468967;3851.4607954886337;3856.6914646828186;3877.84488620452;3889.572147063821;5350.8127215547265;5352.678839269656;5354.116570765858;5356.892862142813;5357.223202635674;5468.28912241406;5516.972415137979;5633.564287884638;5635.943640563624;5637.633553818989;5238.92141617876;5325.360956685223;5330.626856120034;5359.758810853521;5408.8377348679605;4891.755412229374;4906.116898958222;4908;4912.238603016538;4913.385826771653;5613.88772224545;5797;5836.766529338779;5861.589688721749;5864.266966629171;5477.287952566141;5569.721513924369;5574;5586.916196257141;5613.915791263216;5819.301683797971;5824.242848429677;5832.854178645568;5876.853078672896;5892.499137573354;5546.833594992157;5550.639208451437;5552.52803511706;5552.8056518023295;5553.444655534414;5390.730463476889;5433.212184233185;5645.661260324394;5653.152027195953;5655.3213614364895;5520.06158952987;5520.420355862577;5523.7238138093735;5538.529225015757;5560.221568980427;5511.145772405283;5518.503334699896;5524.889502209853;5525.751341189736;5525.861853453695;5185.040767458032;5224.634275600672;5454.754536045967;5455.3453585568395;5507.118860982224;5453.127499600045;5465.180222966586;5475.479829416358;5522.0336441123445;5571;5290.1800220965815;5379.73101344939;5381.085215513293;5385.057614917296;5385.434529374516 23:34:48 INFO - PROCESS | 7472 | |1;ext.html;555.1977392667595;564.4722184757252;569.7094481814274;577.8382053025152;578.199194116149;30853.22866928325;38930.41604375937;40219.597804021985;40388.79805600968;43311;42720.35919461211;43146.99999999992;43147;43181;43205.35191972123;7781.26078022587;8525.829483410329;8704.869426958569;8705.95647021764;8708.477491350508;7745.496542724733;10072.446015469146;10284.125849302782;10331.070203681667;10593;10351.171906247482;10408.687739367793;10425.582976680924;10425.843612345821;10459.529321180527;10100.191984641211;10138.138258248058;10161.644342448053;10204.795904081877;10215.284930054937;10166.593336266615;10240.129588984944;10259.43573103479;10263.281570290083;10273.434961077137;39547.20905581901;39794.80102599497;39825.20349592992;39848.80075599632;39894;3349.8492567834264;3351.312980838922;3397.6262611112757;3449.862005519776;3494.0740703713536;26080.478390432087;27789;28252.858735706395;28323.150305490868;28366;3953.596010570769;4030.5364883038533;4039.091204478969;4097.528784189826;4231.519919154283;310678;311127.444362779;312372;312524.81113538746;313186;49293.50706492961;49503;49695.25457118146;49883.501164988615;49935.75032124816;49620.25569616459;49863;49994.75002624963;50008.24987625189;50072.74963625159;13642.590722278348;13988.160710357412;13997.160170389605;14006.159630422208;14012.859871401359;10381.896181038244;10606.469676516142;11005.064569511682;11091.168162387854;11138.053265472363;15383.769243461356;15582.298796554182;15771.605709857116;15961;15968.441104561285;6255.343188965184;6415.166028416276;6445.677716114175;6452.451541619015;6532.640704761188;860.9913900861036;957.2485598805044;978.7312264943051;1010.6260683546988;1070.0797314309686;29729.256768580526;29729.85135074311;30150.24624384364;30230.697693023227;30356.54465183024;970.9029097090234;1027.753339198602;1065.3554599467366;1077.4289626497969;1087.7770057138268;15465;15969.36122555108;15998.520044398687;16156;16261.674766504604;495088;499763;502183;502277;504132;54085.45914540883;54573;54600.180997285075;54613.72693136509;54701;1214.5991822698488;1250.9249445033145;1288.1240756285629;1318.762622727899;1319.9274039927896;27083.86458067697;30377.240568985068;31035.361994230745;31077.689223107478;31646.841765791025;41483.37774933439;41740;41813.37279940742;41824.581754182065;41829.16341673089;359123;360030;360413;360638;360843;22597.43506412353;23235.186768463023;23308.417289567897;25974.22077337683;26611.201663950113;37373.4393984085;37982.81008594939;38032.61967380291;38036.80981595074;38448.423273650355;32150;32916.50625240671;32922.5061624071;33725.49411758874;35301.2343333657;34404;34464.65535344614;34581;34814.303713926085;35731;34169.31661366808;34971;35336.46995295021;35537.64462355343;35980.10049748777;29721.25696857596;31591.526127108573;31600.525992110597;31615.20961975969;31942.201444964063;24222.515549688556;24569.877150614848;24822;24945.875270623532;25115.246542603752 23:34:48 INFO - PROCESS | 7472 | |2;jquery.html;354.7782635852592;373.5386797305329;373.63383883793875;381.6584157179325;392.6505410184936;14057.367418466169;17520.47438576843;17805.46583602494;18355.908220458896;18640.440786776384;17458;17692.7609719382;17700.380486682967;17747.201375938082;17896.910515447405;5546.972265138668;5659.8585035374035;5662.122371032476;5666.150077488389;5714.114312281603;4054.108096218842;4112.9588704112875;5595.468430499096;5699.629524080921;5703.77184912603;5585.636933599303;5710.314762228539;5729.541636669056;5731.713414329267;5735;5709.257796486451;5711.657300561958;5718.456746609066;6151.169592105053;6202.751889924398;5399.730013499309;5431.619786614939;5440.401555828856;5443.1563107717975;5447.700376479292;21454.356369308945;21636.459088522755;21740.239091631876;21794;21893.781062189493;2241.767028134536;2331.5570041692026;2362.8464149830206;2961;3006.4137493188837;12914.612561623166;13807.378667959865;14013.369398376995;14405.135691858417;14503.92748036253;1886.462358227912;1886.76415448069;1907.8759880607715;1910.732497450358;1920.1455352368203;67568.3243167567;77589.30133733283;80514.59742701308;82012;83635;10303.742406439898;10306.33008854422;10474.004969527883;10546.841797373045;10614.416207108528;14501.492447764278;14553.854461455461;14564.49024284145;14572.562823115324;14583.854161458461;7472.9911461953025;7631.923680763232;7705.753245076495;7719.922800772033;7762.379009679212;7264.273572642694;7310.195878453366;7535.773926782205;7548.320651141424;7554.848903022019;4366.781660916941;4389.582989615981;4455.576720211575;4485.573870482299;4632.72203667781;1807.4396936949588;1850.3153833081576;1860.3116846766513;1904.5143488410367;1911.3119277060248;853.8377708235539;906.0622255965176;952.518977916148;992.8212921674024;997.5112195024531;17329.7400538992;17743.378981735576;18073.45779626613;18245;18645.160967756485;922.2844123037324;922.5202894494826;929.1962452478557;969.1374676537887;1008.3294609085023;9621.076533318796;9911.603535858485;9932.304738668225;9998.800023999625;10009.69970900874;454666;456254;458859;462615;465676;85259;87083.56458217668;87710.56144719363;87798.56100719456;88188.11811881927;992.9056739609725;1038.7403149212696;1068.5832525315134;1069.6844430892904;1070.272214893864;15157.195918755066;15363.07821530667;15784.526464206092;15859.920700396424;16108.919455403116;39706.80146599249;40138;40375.1924961505;40975;42373.15253694847;54491;54671.72664136654;55095.173572395644;55520.83906155439;56033;6199.380061993945;8233.917660823316;8291.170882911902;8395.412321137706;8397.496150230761;10398;12176.330301833485;12548.560800371943;12626.494940202656;12637.368131593568;10916.617918372818;10979.39613321243;11044.613438529614;11055.391953442639;11151.330920144817;10724.839127412939;11049.779004420026;11073.501692423699;11074.557017719524;11110.555577777122;10220;10308.12380947628;10593.629222977159;10759.3544387337;10906.127509799355;8167.591620418836;8236.629351679308;8290.87563686533;8512.361572882182;8524.616392262487;9325.487098209393;10774.138068954308;10965.451727413438;11138.94430527842;11293 23:34:48 INFO - PROCESS | 7472 | |3;mootools.html;1329.4549234813728;1391.71469848681;1392.9721405571888;1527.6486408126132;1718.6304944436952;7118.216996130428;8024.237697418743;8311.044229913561;8319.084900660937;8556.871646925301;8291.378146639012;8362.16378362165;8366.581670916461;8369.539675317856;8373.497590144596;1878.6994080947052;1940;2168.750593681727;2199.8900054997184;2200.020990659157;2114.164904862578;2181.2801775414155;2189.441692368444;2207.0950910126853;2210.2595630463816;2161.5784921940226;2191.9259562814186;2193.4187440328324;2195.078067211775;2197.044285735702;2134.6157691615504;2171.0338899189865;2177.6733489976473;2197.3298144066052;2222.7777222277805;2075.086961736846;2088.363049269972;2109.4620871677776;2112.6302896992966;2145.538709177534;8395.580220988926;8439;8446.788830279293;8468.195521425454;8479;4873.561379475864;5003.274525193863;5004.649674522785;5008.524190201925;5008.6744361616375;3078.5382192671054;3159.873605055795;3323.80057196569;3344.66553344666;3357.429237029686;969.9175570076552;1016.6441745389128;1051.0645525482328;1055.6463584699136;1056.514003558364;11427.828582571268;11477.598284060101;11485.0811935045;11486.483108260232;11494.252873563191;4083.265012297815;4347.804348804311;4403.647708183337;4407.911841763211;4449.57729015743;9718.12536871685;9735.902640973642;9738.367006144577;9740.75648108803;9742.220622350194;5000.67495612784;5047.570956468668;5052.115879721034;5057.317262169634;5090.41460232078;4443.644508439317;4532.818687252539;4650.813967441332;4659.743714095689;4669.159551280734;3517.2262102337445;3555.8933232003083;3590.3716849551224;3616.72874534411;3651.2514934438377;1105.3202280597536;1107.363266121983;1111.1222134513805;1116.659418877234;1123.1239633086207;929.363386080537;935.9532023398802;943.5848226780195;947.1191791633709;992.0030369478628;5544.390117087117;5574.693391863487;5637.548996080303;5775.971120144372;6024.668643224577;408.3629537920853;412.4020170752421;413.8158519458812;414.997925010373;425.1603083909316;3931.8427262909736;3939.2712348215673;3980.2238563480423;4005.118873847749;4094.4267802507675;9925.801483970281;10086.899131008597;10352.430616316175;10361.274710770174;10393.896061039293;10042.297039207331;10167.4916254187;10197.643082492077;10198.745031374126;10201.94899025515;7368;7700.537967721954;7757;7757.612119394008;7881.763547093597;5693.772249109989;5760.683162426107;5990.400959904149;6017.3681763414215;6068.453839154321;9280.62877484893;9302.255819534286;9481.431114133175;9548.761280968032;9582.808343833223;8872.201501864864;9288.767780805534;9295.209907157967;9511.334206605472;9545.427274363561;6716.529842910949;6907.481938854506;7553.433492487976;7691.538507689556;7772.378209743094;7481.925180748123;7864.921350786648;8057.677692892453;8068.023334283138;8075.475094118863;7084.36240738336;7138.39323657495;7203.495755297079;7216.855662886818;7296.379807716404;6744.865102698017;6759.526833121634;6879.5184337095925;6942.097527321416;7050.823729406806;6542.705578249085;6643.335666433318;6653.367930046637;6656.800295991128;6667.399934005963;4601.608863246662;4603.194440972749;4704.952950470589;4759.381280433521;4848.660593758403;4963.950360496493;5019.799208031638;5072.264521644267;5137.075326441127;5157.200633901752 23:34:48 INFO - PROCESS | 7472 | |4;prototype.html;366.8459247116211;379.4422199366931;379.5881468606561;383.034752423892;386.49948316929573;5051.6716413433105;5192.1432963560965;5236.26692263083;5275.947240527598;5292.761825717837;4998.400191976968;5143.4599367066385;5143.742812859361;5170.870728231796;5216.6087543434205;4094.97952510237;4104.774237416941;4133.855315063974;4134.875953721393;4138.5033795944455;4070.796460176994;4119.3409054551275;4123.917521649565;4126.958730412703;4180.540140584533;4122.6083522065355;4128.2981893078095;4162.854300099497;4174.373843923406;4175.519815221258;4122.4640796696385;4132.855350062749;4133.751974881517;4136.834526618932;4188.895277618053;3763.473113764075;3769.095417099904;3785.772853628791;3786.7917264550438;3790.6019867914024;4933.1860243059855;5008.198688209905;5046.697198168122;5065.518775716338;5091.159958606825;4701;4713.670043096985;4731.313959475891;4763.976180119112;4775.904481910342;5383.730813459311;5388.622796404254;5399.78400863965;5409.566464886808;5412.539934105605;3850.5379354477363;4005.83976640934;4026.3693204252327;4033.616806403387;4038.1531020630387;5644.774209031634;5660;5664.49019588239;5673.6595804251465;5681.34664513578;4227.7040607157205;4227.87316380509;4230.175115852379;4243.087736136746;4246.745195288293;4113.444684967491;4227.921879920601;4242.851500197478;4253.659707223414;4305.634021108178;4685.929711054337;4717.575418212377;4728.085125524168;4734.218853889139;4755.904881902343;5390.272313237662;5424.566034717213;5428.4842939920645;5449.5640348772795;5462.590305727086;1341.1148641896496;1350.59482155353;1412.3785534364847;1413.6465883529117;1436.3177490691837;975.3952549419264;993.5181437003048;1002.0730823987782;1009.268280496634;1014.644874294006;5052.216906379517;5073.365829271341;5100.795968161232;5119.513646203604;5123.641345105882;5297.867553311198;5307.6019298552765;5313.628046036741;5321.627486076015;5342.813001544927;4879.5120487951635;4928.728919909439;5015.398152221756;5132.871678208075;5134.974325128351;2777.6944536101014;2782.38787466757;2825.488363725438;2828.872700728472;2844.5590933405356;5457.645253058538;5460.863478412992;5473.67157970523;5481.972590137024;5498.917516237259;5223.388659864819;5270.518660593017;5351.598630102758;5402.8919421611345;5410.646368829572;5369.436209197978;5423.945760542344;5424.535375448686;5444.700541470178;5471.50756431923;5264.6314757966575;5284.894302114013;5286.550643195372;5286.8942621148135;5308.708021058725;5015.689644834538;5248.816291429782;5265.763040663102;5278.208268759563;5283.630145889904;5400.918986215132;5475.835724928259;5483.698396588066;5484.506394424521;5497.257870187634;4047.575004624472;4064.6138616831354;4068.389741538793;4077.2049450356894;4084.162746636992;5059.595232381327;5264.052470555337;5312.468753124656;5313.362396512443;5334.599905007062;5241.135212690016;5275.577953763766;5461;5461.617686761889;5474.288342515448;5319.574434045345;5374.623776335619;5427.755750991294;5456.890862182813;5458.727063646881;5133.948660513347;5338.839834804962;5345.893082138257;5373.898350838147;5379.784808607612;5432;5448.610604295879;5500.779968801363;5513.531349835149;5526.50261476469;5391.272178255886;5405.567554595702;5418.810341638024;5437.5318663959715;5438.592105592017 23:34:48 INFO - PROCESS | 7472 | |5;yui.html;717.3615482220824;750.0249675421953;753.9924600753998;775.8603451378754;778.9571573563453;15614;16497.67004659906;16501.17494125295;16648.417305394312;16669.49991500254;13333.06668533203;13379.732405351888;13387.665308367272;13392.330383480836;13395.464181432731;649.7401039584157;667.3560014585923;753.4876284126792;761.1855314813139;768.3852917665873;779.6608475313228;782.7377828427484;784.674360140541;786.2530595933857;788.1448628238339;826.6982551368767;828.4159667434475;828.4283844147519;828.8466633672759;828.9927737798573;685.1675214614243;701.1656129206228;803.4777394693438;824.2005254902765;825.6119623776815;607.2015299880643;607.5504126946072;607.6020206764576;607.6354187487516;607.9878402431926;15394.384224631114;15411.537653870402;15417.614559636098;15425.228738563026;15435.768463473058;12561.685957851036;12961.15752476086;13652.726945461134;14343.28283585827;14432.422703091865;12826.743465130645;12852.742945141139;12920;13217.801732974012;13237;3279.6149327569683;3453.689167974869;4441.267190913528;4549.431321084864;4561.224591819366;78726.6063669678;80351;81825;83400;83674.03052526868;10709;11036.668899933014;11160.832587511193;11208.27146235492;12325.322107284006;19826.70259946102;20582.897085514775;20774.792252077285;20779.68830467544;21083.051262693214;8330.500169989698;8537.786555336166;8664.263537599249;8670.523121228392;8675.262602678717;4501.234790085693;4596.287575425815;4674.415698037746;4688.671792974527;4736.052789442126;3300.290437555937;3358.059743271888;3372.9325413491592;3386.408388057609;3396.609389920191;583.9474447299679;588.5762251179144;590.5600327755849;592.5585438848086;616.0543565626741;662.4799532367068;701.4388489208715;706.5478094019832;712.8752468318128;731.7365748330596;9086.227670647937;9265.907340926504;9563.569639366246;9620.13418792298;9783.364081334832;605.6638565596137;649.3571364349355;682.6211452643731;689.7961014558438;696.9163700355888;4478.596926276651;4689.226277664147;4721.716696998123;4722.504137065627;4780.616234299676;81966;82243.17756822475;85044;85676.57161714276;87665;71880;72866.27133728594;73460;76224.61887690525;76535;62113;64180.35819641744;65573.34426655767;66956.6652166736;69676;8753;8878.600462979311;9044;9135.725928222164;9357.204637605608;40060.59939400685;40887.795561022;41766.582334176266;42173.578264218195;42855;57810.13284800816;57937.71031144817;58769.11846322394;59676;60388.698056509435;11560.479778409823;11605.245659032136;11905.16663833558;11929.522819087138;11939.223950443193;13987.860121399062;14151.929240353733;14716.55850324492;14742.778858316919;14895.627609309853;12922.160059596095;12926.353682316034;12959.287239201934;12969.092163548838;13230.272335021666;13467.259300738162;13593.79609305881;13771.862281377058;13855.307234638429;13979.161250325013;12313.199642023239;13518.797218041935;13556.796648050089;13850.376733046836;14015.789763153356;10757.246992710749;10821.404822734825;10973.177011723992;11063.778724425627;11215.887841121485;9702.805943881223;9741.902580974383;10827.458627068772;10952.671419857417;11144.2199046066 23:34:48 INFO - PROCESS | 7472 | __end_tp_report 23:34:48 INFO - PROCESS | 7472 | __start_cc_report 23:34:48 INFO - PROCESS | 7472 | _x_x_mozilla_cycle_collect,1593 23:34:48 INFO - PROCESS | 7472 | __end_cc_report 23:34:48 INFO - PROCESS | 7472 | __startTimestamp1482046488474__endTimestamp 23:34:48 INFO - PROCESS | 7472 | 23:34:48 INFO - PROCESS | 7472 | ------- Summary: start ------- 23:34:48 INFO - PROCESS | 7472 | Number of tests: 6 23:34:48 INFO - PROCESS | 7472 | 23:34:48 INFO - PROCESS | 7472 | [#0] dojo.html Cycles:165 Average:5206.24 Median:5390.06 stddev:595.18 (11.0%) stddev-sans-first:569.73 23:34:48 INFO - PROCESS | 7472 | Values: 2935.6 2974.4 2990.1 2994.8 3006.4 4968.8 4991.2 5166.7 5238.6 5321.1 5083.3 5233.6 5300.3 5327.5 5378.6 4686.4 4689.7 4731.2 4759.8 4773.1 4791.0 4826.2 4864.3 4865.8 4909.2 4616.7 4839.8 4855.1 4870.7 4955.0 4637.0 4688.2 4703.4 4707.2 4717.7 4704.7 4705.4 4714.2 4738.2 4771.7 5167.7 5202.4 5217.3 5219.4 5220.9 5412.3 5432.1 5442.3 5520.7 5565.7 5572.0 5573.1 5578.9 5595.4 5620.0 5301.0 5315.7 5350.8 5363.7 5365.6 5670.1 5784.0 5786.6 5787.2 5799.3 5383.2 5424.3 5518.8 5524.6 5576.0 5566.1 5588.1 5594.0 5638.8 5688.7 5618.1 5630.9 5632.0 5633.9 5634.9 5390.1 5602.9 5636.0 5649.1 5685.2 4218.4 4266.1 4276.0 4292.8 4306.7 3842.2 3851.5 3856.7 3877.8 3889.6 5350.8 5352.7 5354.1 5356.9 5357.2 5468.3 5517.0 5633.6 5635.9 5637.6 5238.9 5325.4 5330.6 5359.8 5408.8 4891.8 4906.1 4908.0 4912.2 4913.4 5613.9 5797.0 5836.8 5861.6 5864.3 5477.3 5569.7 5574.0 5586.9 5613.9 5819.3 5824.2 5832.9 5876.9 5892.5 5546.8 5550.6 5552.5 5552.8 5553.4 5390.7 5433.2 5645.7 5653.2 5655.3 5520.1 5520.4 5523.7 5538.5 5560.2 5511.1 5518.5 5524.9 5525.8 5525.9 5185.0 5224.6 5454.8 5455.3 5507.1 5453.1 5465.2 5475.5 5522.0 5571.0 5290.2 5379.7 5381.1 5385.1 5385.4 23:34:48 INFO - PROCESS | 7472 | 23:34:48 INFO - PROCESS | 7472 | [#1] ext.html Cycles:180 Average:53429.34 Median:18538.40 stddev:105809.27 (570.8%) stddev-sans-first:106031.63 23:34:48 INFO - PROCESS | 7472 | Values: 555.2 564.5 569.7 577.8 578.2 30853.2 38930.4 40219.6 40388.8 43311.0 42720.4 43147.0 43147.0 43181.0 43205.4 7781.3 8525.8 8704.9 8706.0 8708.5 7745.5 10072.4 10284.1 10331.1 10593.0 10351.2 10408.7 10425.6 10425.8 10459.5 10100.2 10138.1 10161.6 10204.8 10215.3 10166.6 10240.1 10259.4 10263.3 10273.4 39547.2 39794.8 39825.2 39848.8 39894.0 3349.8 3351.3 3397.6 3449.9 3494.1 26080.5 27789.0 28252.9 28323.2 28366.0 3953.6 4030.5 4039.1 4097.5 4231.5 310678.0 311127.4 312372.0 312524.8 313186.0 49293.5 49503.0 49695.3 49883.5 49935.8 49620.3 49863.0 49994.8 50008.2 50072.7 13642.6 13988.2 13997.2 14006.2 14012.9 10381.9 10606.5 11005.1 11091.2 11138.1 15383.8 15582.3 15771.6 15961.0 15968.4 6255.3 6415.2 6445.7 6452.5 6532.6 861.0 957.2 978.7 1010.6 1070.1 29729.3 29729.9 30150.2 30230.7 30356.5 970.9 1027.8 1065.4 1077.4 1087.8 15465.0 15969.4 15998.5 16156.0 16261.7 495088.0 499763.0 502183.0 502277.0 504132.0 54085.5 54573.0 54600.2 54613.7 54701.0 1214.6 1250.9 1288.1 1318.8 1319.9 27083.9 30377.2 31035.4 31077.7 31646.8 41483.4 41740.0 41813.4 41824.6 41829.2 359123.0 360030.0 360413.0 360638.0 360843.0 22597.4 23235.2 23308.4 25974.2 26611.2 37373.4 37982.8 38032.6 38036.8 38448.4 32150.0 32916.5 32922.5 33725.5 35301.2 34404.0 34464.7 34581.0 34814.3 35731.0 34169.3 34971.0 35336.5 35537.6 35980.1 29721.3 31591.5 31600.5 31615.2 31942.2 24222.5 24569.9 24822.0 24945.9 25115.2 23:34:48 INFO - PROCESS | 7472 | 23:34:48 INFO - PROCESS | 7472 | [#2] jquery.html Cycles:180 Average:27463.74 Median:39922.40 stddev:75837.39 (190.0%) stddev-sans-first:76022.82 23:34:48 INFO - PROCESS | 7472 | Values: 354.8 373.5 373.6 381.7 392.7 14057.4 17520.5 17805.5 18355.9 18640.4 17458.0 17692.8 17700.4 17747.2 17896.9 5547.0 5659.9 5662.1 5666.2 5714.1 4054.1 4113.0 5595.5 5699.6 5703.8 5585.6 5710.3 5729.5 5731.7 5735.0 5709.3 5711.7 5718.5 6151.2 6202.8 5399.7 5431.6 5440.4 5443.2 5447.7 21454.4 21636.5 21740.2 21794.0 21893.8 2241.8 2331.6 2362.8 2961.0 3006.4 12914.6 13807.4 14013.4 14405.1 14503.9 1886.5 1886.8 1907.9 1910.7 1920.1 67568.3 77589.3 80514.6 82012.0 83635.0 10303.7 10306.3 10474.0 10546.8 10614.4 14501.5 14553.9 14564.5 14572.6 14583.9 7473.0 7631.9 7705.8 7719.9 7762.4 7264.3 7310.2 7535.8 7548.3 7554.8 4366.8 4389.6 4455.6 4485.6 4632.7 1807.4 1850.3 1860.3 1904.5 1911.3 853.8 906.1 952.5 992.8 997.5 17329.7 17743.4 18073.5 18245.0 18645.2 922.3 922.5 929.2 969.1 1008.3 9621.1 9911.6 9932.3 9998.8 10009.7 454666.0 456254.0 458859.0 462615.0 465676.0 85259.0 87083.6 87710.6 87798.6 88188.1 992.9 1038.7 1068.6 1069.7 1070.3 15157.2 15363.1 15784.5 15859.9 16108.9 39706.8 40138.0 40375.2 40975.0 42373.2 54491.0 54671.7 55095.2 55520.8 56033.0 6199.4 8233.9 8291.2 8395.4 8397.5 10398.0 12176.3 12548.6 12626.5 12637.4 10916.6 10979.4 11044.6 11055.4 11151.3 10724.8 11049.8 11073.5 11074.6 11110.6 10220.0 10308.1 10593.6 10759.4 10906.1 8167.6 8236.6 8290.9 8512.4 8524.6 9325.5 10774.1 10965.5 11138.9 11293.0 23:34:48 INFO - PROCESS | 7472 | 23:34:48 INFO - PROCESS | 7472 | [#3] mootools.html Cycles:180 Average:5436.65 Median:5001.97 stddev:3102.65 (62.0%) stddev-sans-first:3096.00 23:34:48 INFO - PROCESS | 7472 | Values: 1329.5 1391.7 1393.0 1527.6 1718.6 7118.2 8024.2 8311.0 8319.1 8556.9 8291.4 8362.2 8366.6 8369.5 8373.5 1878.7 1940.0 2168.8 2199.9 2200.0 2114.2 2181.3 2189.4 2207.1 2210.3 2161.6 2191.9 2193.4 2195.1 2197.0 2134.6 2171.0 2177.7 2197.3 2222.8 2075.1 2088.4 2109.5 2112.6 2145.5 8395.6 8439.0 8446.8 8468.2 8479.0 4873.6 5003.3 5004.6 5008.5 5008.7 3078.5 3159.9 3323.8 3344.7 3357.4 969.9 1016.6 1051.1 1055.6 1056.5 11427.8 11477.6 11485.1 11486.5 11494.3 4083.3 4347.8 4403.6 4407.9 4449.6 9718.1 9735.9 9738.4 9740.8 9742.2 5000.7 5047.6 5052.1 5057.3 5090.4 4443.6 4532.8 4650.8 4659.7 4669.2 3517.2 3555.9 3590.4 3616.7 3651.3 1105.3 1107.4 1111.1 1116.7 1123.1 929.4 936.0 943.6 947.1 992.0 5544.4 5574.7 5637.5 5776.0 6024.7 408.4 412.4 413.8 415.0 425.2 3931.8 3939.3 3980.2 4005.1 4094.4 9925.8 10086.9 10352.4 10361.3 10393.9 10042.3 10167.5 10197.6 10198.7 10201.9 7368.0 7700.5 7757.0 7757.6 7881.8 5693.8 5760.7 5990.4 6017.4 6068.5 9280.6 9302.3 9481.4 9548.8 9582.8 8872.2 9288.8 9295.2 9511.3 9545.4 6716.5 6907.5 7553.4 7691.5 7772.4 7481.9 7864.9 8057.7 8068.0 8075.5 7084.4 7138.4 7203.5 7216.9 7296.4 6744.9 6759.5 6879.5 6942.1 7050.8 6542.7 6643.3 6653.4 6656.8 6667.4 4601.6 4603.2 4705.0 4759.4 4848.7 4964.0 5019.8 5072.3 5137.1 5157.2 23:34:48 INFO - PROCESS | 7472 | 23:34:48 INFO - PROCESS | 7472 | [#4] prototype.html Cycles:180 Average:4545.93 Median:5133.41 stddev:1275.64 (24.8%) stddev-sans-first:1240.05 23:34:48 INFO - PROCESS | 7472 | Values: 366.8 379.4 379.6 383.0 386.5 5051.7 5192.1 5236.3 5275.9 5292.8 4998.4 5143.5 5143.7 5170.9 5216.6 4095.0 4104.8 4133.9 4134.9 4138.5 4070.8 4119.3 4123.9 4127.0 4180.5 4122.6 4128.3 4162.9 4174.4 4175.5 4122.5 4132.9 4133.8 4136.8 4188.9 3763.5 3769.1 3785.8 3786.8 3790.6 4933.2 5008.2 5046.7 5065.5 5091.2 4701.0 4713.7 4731.3 4764.0 4775.9 5383.7 5388.6 5399.8 5409.6 5412.5 3850.5 4005.8 4026.4 4033.6 4038.2 5644.8 5660.0 5664.5 5673.7 5681.3 4227.7 4227.9 4230.2 4243.1 4246.7 4113.4 4227.9 4242.9 4253.7 4305.6 4685.9 4717.6 4728.1 4734.2 4755.9 5390.3 5424.6 5428.5 5449.6 5462.6 1341.1 1350.6 1412.4 1413.6 1436.3 975.4 993.5 1002.1 1009.3 1014.6 5052.2 5073.4 5100.8 5119.5 5123.6 5297.9 5307.6 5313.6 5321.6 5342.8 4879.5 4928.7 5015.4 5132.9 5135.0 2777.7 2782.4 2825.5 2828.9 2844.6 5457.6 5460.9 5473.7 5482.0 5498.9 5223.4 5270.5 5351.6 5402.9 5410.6 5369.4 5423.9 5424.5 5444.7 5471.5 5264.6 5284.9 5286.6 5286.9 5308.7 5015.7 5248.8 5265.8 5278.2 5283.6 5400.9 5475.8 5483.7 5484.5 5497.3 4047.6 4064.6 4068.4 4077.2 4084.2 5059.6 5264.1 5312.5 5313.4 5334.6 5241.1 5275.6 5461.0 5461.6 5474.3 5319.6 5374.6 5427.8 5456.9 5458.7 5133.9 5338.8 5345.9 5373.9 5379.8 5432.0 5448.6 5500.8 5513.5 5526.5 5391.3 5405.6 5418.8 5437.5 5438.6 23:34:48 INFO - PROCESS | 7472 | 23:34:48 INFO - PROCESS | 7472 | [#5] yui.html Cycles:180 Average:18008.36 Median:4729.28 stddev:23752.20 (502.2%) stddev-sans-first:23783.34 23:34:48 INFO - PROCESS | 7472 | Values: 717.4 750.0 754.0 775.9 779.0 15614.0 16497.7 16501.2 16648.4 16669.5 13333.1 13379.7 13387.7 13392.3 13395.5 649.7 667.4 753.5 761.2 768.4 779.7 782.7 784.7 786.3 788.1 826.7 828.4 828.4 828.8 829.0 685.2 701.2 803.5 824.2 825.6 607.2 607.6 607.6 607.6 608.0 15394.4 15411.5 15417.6 15425.2 15435.8 12561.7 12961.2 13652.7 14343.3 14432.4 12826.7 12852.7 12920.0 13217.8 13237.0 3279.6 3453.7 4441.3 4549.4 4561.2 78726.6 80351.0 81825.0 83400.0 83674.0 10709.0 11036.7 11160.8 11208.3 12325.3 19826.7 20582.9 20774.8 20779.7 21083.1 8330.5 8537.8 8664.3 8670.5 8675.3 4501.2 4596.3 4674.4 4688.7 4736.1 3300.3 3358.1 3372.9 3386.4 3396.6 583.9 588.6 590.6 592.6 616.1 662.5 701.4 706.5 712.9 731.7 9086.2 9265.9 9563.6 9620.1 9783.4 605.7 649.4 682.6 689.8 696.9 4478.6 4689.2 4721.7 4722.5 4780.6 81966.0 82243.2 85044.0 85676.6 87665.0 71880.0 72866.3 73460.0 76224.6 76535.0 62113.0 64180.4 65573.3 66956.7 69676.0 8753.0 8878.6 9044.0 9135.7 9357.2 40060.6 40887.8 41766.6 42173.6 42855.0 57810.1 57937.7 58769.1 59676.0 60388.7 11560.5 11605.2 11905.2 11929.5 11939.2 13987.9 14151.9 14716.6 14742.8 14895.6 12922.2 12926.4 12959.3 12969.1 13230.3 13467.3 13593.8 13771.9 13855.3 13979.2 12313.2 13518.8 13556.8 13850.4 14015.8 10757.2 10821.4 10973.2 11063.8 11215.9 9702.8 9741.9 10827.5 10952.7 11144.2 23:34:48 INFO - PROCESS | 7472 | -------- Summary: end -------- 23:34:48 INFO - PROCESS | 7472 | 23:34:49 INFO - TEST-INFO | 7472: exit 0 23:34:49 INFO - TEST-OK | dromaeo_css | took 1117081ms 23:34:49 INFO - TEST-START | kraken 23:34:49 INFO - operating with platform_type : linux_ 23:34:49 INFO - Initialising browser for kraken test... 23:34:49 INFO - TEST-INFO | started process 7771 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWhIaWF/profile http://localhost:36824/getInfo.html) 23:34:53 INFO - PROCESS | 7771 | __metrics Screen width/height:1600/1200 23:34:53 INFO - PROCESS | 7771 | colorDepth:24 23:34:53 INFO - PROCESS | 7771 | Browser inner width/height: 1024/697 23:34:53 INFO - PROCESS | 7771 | __metrics 23:34:54 INFO - PROCESS | 7771 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:34:54 INFO - PROCESS | 7771 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:34:54 INFO - PROCESS | 7771 | console.error: PushService: 23:34:54 INFO - PROCESS | 7771 | stateChangeProcessEnqueue: Error transitioning state 23:34:54 INFO - PROCESS | 7771 | UnknownError 23:34:55 INFO - TEST-INFO | 7771: exit 0 23:34:55 INFO - Browser initialized. 23:34:55 INFO - Running cycle 1/1 for kraken test... 23:34:55 INFO - TEST-INFO | started process 7855 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWhIaWF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:35:07 INFO - PROCESS | 7855 | RSS: Main: 154169344 23:35:07 INFO - PROCESS | 7855 | 23:35:09 INFO - PROCESS | 7855 | Cycle 1(1): loaded http://localhost:36824/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36824/tests/kraken/driver.html?testName=audio-beat-detection) 23:35:10 INFO - PROCESS | 7855 | RSS: Main: 208429056 23:35:10 INFO - PROCESS | 7855 | 23:35:13 INFO - PROCESS | 7855 | Cycle 1(1): loaded http://localhost:36824/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36824/tests/kraken/driver.html?testName=audio-dft) 23:35:13 INFO - PROCESS | 7855 | RSS: Main: 186208256 23:35:13 INFO - PROCESS | 7855 | 23:35:16 INFO - PROCESS | 7855 | Cycle 1(1): loaded http://localhost:36824/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36824/tests/kraken/driver.html?testName=audio-fft) 23:35:17 INFO - PROCESS | 7855 | RSS: Main: 268234752 23:35:17 INFO - PROCESS | 7855 | 23:35:19 INFO - PROCESS | 7855 | Cycle 1(1): loaded http://localhost:36824/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36824/tests/kraken/driver.html?testName=audio-oscillator) 23:35:20 INFO - PROCESS | 7855 | RSS: Main: 180596736 23:35:20 INFO - PROCESS | 7855 | 23:35:22 INFO - PROCESS | 7855 | Cycle 1(1): loaded http://localhost:36824/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36824/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:35:22 INFO - PROCESS | 7855 | RSS: Main: 183373824 23:35:22 INFO - PROCESS | 7855 | 23:35:25 INFO - PROCESS | 7855 | Cycle 1(1): loaded http://localhost:36824/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36824/tests/kraken/driver.html?testName=imaging-darkroom) 23:35:26 INFO - PROCESS | 7855 | RSS: Main: 222826496 23:35:26 INFO - PROCESS | 7855 | 23:35:29 INFO - PROCESS | 7855 | Cycle 1(1): loaded http://localhost:36824/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36824/tests/kraken/driver.html?testName=imaging-desaturate) 23:35:30 INFO - PROCESS | 7855 | RSS: Main: 222519296 23:35:30 INFO - PROCESS | 7855 | 23:35:33 INFO - PROCESS | 7855 | Cycle 1(1): loaded http://localhost:36824/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36824/tests/kraken/driver.html?testName=json-parse-financial) 23:35:33 INFO - PROCESS | 7855 | RSS: Main: 221290496 23:35:33 INFO - PROCESS | 7855 | 23:35:35 INFO - PROCESS | 7855 | Cycle 1(1): loaded http://localhost:36824/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36824/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:35:35 INFO - PROCESS | 7855 | RSS: Main: 184094720 23:35:35 INFO - PROCESS | 7855 | 23:35:37 INFO - PROCESS | 7855 | Cycle 1(1): loaded http://localhost:36824/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36824/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:35:38 INFO - PROCESS | 7855 | RSS: Main: 190730240 23:35:38 INFO - PROCESS | 7855 | 23:35:40 INFO - PROCESS | 7855 | Cycle 1(1): loaded http://localhost:36824/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36824/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:35:40 INFO - PROCESS | 7855 | RSS: Main: 199368704 23:35:40 INFO - PROCESS | 7855 | 23:35:43 INFO - PROCESS | 7855 | Cycle 1(1): loaded http://localhost:36824/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36824/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:35:44 INFO - PROCESS | 7855 | RSS: Main: 209031168 23:35:44 INFO - PROCESS | 7855 | 23:35:47 INFO - PROCESS | 7855 | Cycle 1(1): loaded http://localhost:36824/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36824/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:35:47 INFO - PROCESS | 7855 | RSS: Main: 205008896 23:35:47 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | Cycle 1(1): loaded http://localhost:36824/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36824/tests/kraken/driver.html?testName=ai-astar) 23:35:49 INFO - PROCESS | 7855 | RSS: Main: 255664128 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | __start_tp_report 23:35:49 INFO - PROCESS | 7855 | _x_x_mozilla_page_load 23:35:49 INFO - PROCESS | 7855 | _x_x_mozilla_page_load_details 23:35:49 INFO - PROCESS | 7855 | |i|pagename|runs| 23:35:49 INFO - PROCESS | 7855 | |0;ai-astar;109;113;112;112;112;112;112;112;112;112 23:35:49 INFO - PROCESS | 7855 | |1;audio-beat-detection;154;119;126;156;119;121;125;124;143;119 23:35:49 INFO - PROCESS | 7855 | |2;audio-dft;162;161;160;159;161;163;159;162;163;162 23:35:49 INFO - PROCESS | 7855 | |3;audio-fft;75;76;76;104;75;75;76;76;90;74 23:35:49 INFO - PROCESS | 7855 | |4;audio-oscillator;83;83;107;84;84;83;97;84;83;111 23:35:49 INFO - PROCESS | 7855 | |5;imaging-gaussian-blur;130;128;129;127;127;127;127;127;127;127 23:35:49 INFO - PROCESS | 7855 | |6;imaging-darkroom;147;146;147;147;145;147;146;146;145;147 23:35:49 INFO - PROCESS | 7855 | |7;imaging-desaturate;96;96;96;96;96;96;96;96;97;95 23:35:49 INFO - PROCESS | 7855 | |8;json-parse-financial;51;51;53;50;54;53;51;52;52;53 23:35:49 INFO - PROCESS | 7855 | |9;json-stringify-tinderbox;63;64;63;63;63;62;63;63;66;63 23:35:49 INFO - PROCESS | 7855 | |10;stanford-crypto-aes;80;78;79;82;80;79;88;79;82;78 23:35:49 INFO - PROCESS | 7855 | |11;stanford-crypto-ccm;126;133;124;125;130;126;124;125;169;134 23:35:49 INFO - PROCESS | 7855 | |12;stanford-crypto-pbkdf2;164;162;164;162;169;167;164;167;165;166 23:35:49 INFO - PROCESS | 7855 | |13;stanford-crypto-sha256-iterative;64;63;65;64;62;63;63;63;64;63 23:35:49 INFO - PROCESS | 7855 | __end_tp_report 23:35:49 INFO - PROCESS | 7855 | __start_cc_report 23:35:49 INFO - PROCESS | 7855 | _x_x_mozilla_cycle_collect,1657 23:35:49 INFO - PROCESS | 7855 | __end_cc_report 23:35:49 INFO - PROCESS | 7855 | __startTimestamp1482046549543__endTimestamp 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | ------- Summary: start ------- 23:35:49 INFO - PROCESS | 7855 | Number of tests: 14 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:1.03 (0.9%) stddev-sans-first:0.33 23:35:49 INFO - PROCESS | 7855 | Values: 109.0 113.0 112.0 112.0 112.0 112.0 112.0 112.0 112.0 112.0 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | [#1] audio-beat-detection Cycles:10 Average:130.60 Median:125.50 stddev:14.68 (11.7%) stddev-sans-first:12.89 23:35:49 INFO - PROCESS | 7855 | Values: 154.0 119.0 126.0 156.0 119.0 121.0 125.0 124.0 143.0 119.0 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | [#2] audio-dft Cycles:10 Average:161.20 Median:162.00 stddev:1.48 (0.9%) stddev-sans-first:1.54 23:35:49 INFO - PROCESS | 7855 | Values: 162.0 161.0 160.0 159.0 161.0 163.0 159.0 162.0 163.0 162.0 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | [#3] audio-fft Cycles:10 Average:79.70 Median:76.00 stddev:9.72 (12.8%) stddev-sans-first:10.16 23:35:49 INFO - PROCESS | 7855 | Values: 75.0 76.0 76.0 104.0 75.0 75.0 76.0 76.0 90.0 74.0 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:83.50 stddev:10.97 (13.1%) stddev-sans-first:11.35 23:35:49 INFO - PROCESS | 7855 | Values: 83.0 83.0 107.0 84.0 84.0 83.0 97.0 84.0 83.0 111.0 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | [#5] imaging-gaussian-blur Cycles:10 Average:127.60 Median:127.00 stddev:1.07 (0.8%) stddev-sans-first:0.71 23:35:49 INFO - PROCESS | 7855 | Values: 130.0 128.0 129.0 127.0 127.0 127.0 127.0 127.0 127.0 127.0 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.83 23:35:49 INFO - PROCESS | 7855 | Values: 147.0 146.0 147.0 147.0 145.0 147.0 146.0 146.0 145.0 147.0 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 23:35:49 INFO - PROCESS | 7855 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 97.0 95.0 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.50 stddev:1.25 (2.4%) stddev-sans-first:1.27 23:35:49 INFO - PROCESS | 7855 | Values: 51.0 51.0 53.0 50.0 54.0 53.0 51.0 52.0 52.0 53.0 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | [#9] json-stringify-tinderbox Cycles:10 Average:63.30 Median:63.00 stddev:1.06 (1.7%) stddev-sans-first:1.12 23:35:49 INFO - PROCESS | 7855 | Values: 63.0 64.0 63.0 63.0 63.0 62.0 63.0 63.0 66.0 63.0 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:80.00 stddev:2.99 (3.7%) stddev-sans-first:3.17 23:35:49 INFO - PROCESS | 7855 | Values: 80.0 78.0 79.0 82.0 80.0 79.0 88.0 79.0 82.0 78.0 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | [#11] stanford-crypto-ccm Cycles:10 Average:131.60 Median:128.00 stddev:13.64 (10.7%) stddev-sans-first:14.32 23:35:49 INFO - PROCESS | 7855 | Values: 126.0 133.0 124.0 125.0 130.0 126.0 124.0 125.0 169.0 134.0 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:165.50 stddev:2.26 (1.4%) stddev-sans-first:2.37 23:35:49 INFO - PROCESS | 7855 | Values: 164.0 162.0 164.0 162.0 169.0 167.0 164.0 167.0 165.0 166.0 23:35:49 INFO - PROCESS | 7855 | 23:35:49 INFO - PROCESS | 7855 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:63.50 stddev:0.84 (1.3%) stddev-sans-first:0.87 23:35:49 INFO - PROCESS | 7855 | Values: 64.0 63.0 65.0 64.0 62.0 63.0 63.0 63.0 64.0 63.0 23:35:49 INFO - PROCESS | 7855 | -------- Summary: end -------- 23:35:49 INFO - PROCESS | 7855 | 23:35:50 INFO - TEST-INFO | 7855: exit 0 23:35:50 INFO - TEST-OK | kraken | took 61344ms 23:35:50 INFO - SUITE-END | took 1178s 23:35:50 INFO - Completed test suite (00:19:38) 23:35:50 INFO - javascript benchmark 23:35:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2935.5596660500923, 2974.4051189762054, 2990.102969109267, 2994.820310781352, 3006.443807895541, 4968.801247950087, 4991.201407774757, 5166.715830629314, 5238.607104467161, 5321.1486162214105, 5083.288339632454, 5233.633645644806, 5300.284461597683, 5327.493888080636, 5378.596605254602, 4686.437627484706, 4689.671722979376, 4731.243001119821, 4759.833405830796, 4773.140834649762, 4791.041791641668, 4826.155422801014, 4864.343313652665, 4865.756712164395, 4909.165441874872, 4616.69991450558, 4839.782209800567, 4855.053264613402, 4870.683405578644, 4954.975225123887, 4637.049404871993, 4688.203005489075, 4703.412073490814, 4707.223308154151, 4717.693349932278, 4704.717716936961, 4705.435347758291, 4714.175019371596, 4738.1708201064675, 4771.689840160413, 5167.741612919376, 5202.375714914235, 5217.295665085203, 5219.373675158968, 5220.92168617471, 5412.269343638637, 5432.076546987022, 5442.292501974757, 5520.696361700105, 5565.749541270652, 5571.9721401393135, 5573.080441727151, 5578.9163162552595, 5595.412481689405, 5620.0, 5301.019311427398, 5315.707636080053, 5350.759215835296, 5363.731813409353, 5365.597580181502, 5670.092785154438, 5784.0, 5786.594938354275, 5787.160861675034, 5799.33307669615, 5383.192521121863, 5424.294841670558, 5518.751656175482, 5524.640898341595, 5576.0, 5566.109422492382, 5588.0779671354585, 5594.049011668027, 5638.802641907596, 5688.715564221772, 5618.073017951992, 5630.887382252332, 5631.958087753778, 5633.887322253532, 5634.85912852182, 5390.056740070472, 5602.915956260659, 5635.971820140955, 5649.12438572022, 5685.204071430003, 4218.367244913226, 4266.1254442839145, 4276.016516201291, 4292.806823692941, 4306.698531102856, 3842.212346468967, 3851.4607954886337, 3856.6914646828186, 3877.84488620452, 3889.572147063821, 5350.8127215547265, 5352.678839269656, 5354.116570765858, 5356.892862142813, 5357.223202635674, 5468.28912241406, 5516.972415137979, 5633.564287884638, 5635.943640563624, 5637.633553818989, 5238.92141617876, 5325.360956685223, 5330.626856120034, 5359.758810853521, 5408.8377348679605, 4891.755412229374, 4906.116898958222, 4908.0, 4912.238603016538, 4913.385826771653, 5613.88772224545, 5797.0, 5836.766529338779, 5861.589688721749, 5864.266966629171, 5477.287952566141, 5569.721513924369, 5574.0, 5586.916196257141, 5613.915791263216, 5819.301683797971, 5824.242848429677, 5832.854178645568, 5876.853078672896, 5892.499137573354, 5546.833594992157, 5550.639208451437, 5552.52803511706, 5552.8056518023295, 5553.444655534414, 5390.730463476889, 5433.212184233185, 5645.661260324394, 5653.152027195953, 5655.3213614364895, 5520.06158952987, 5520.420355862577, 5523.7238138093735, 5538.529225015757, 5560.221568980427, 5511.145772405283, 5518.503334699896, 5524.889502209853, 5525.751341189736, 5525.861853453695, 5185.040767458032, 5224.634275600672, 5454.754536045967, 5455.3453585568395, 5507.118860982224, 5453.127499600045, 5465.180222966586, 5475.479829416358, 5522.0336441123445, 5571.0, 5290.1800220965815, 5379.73101344939, 5381.085215513293, 5385.057614917296, 5385.434529374516], "value": 5165.594083477883, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.1977392667595, 564.4722184757252, 569.7094481814274, 577.8382053025152, 578.199194116149, 30853.22866928325, 38930.41604375937, 40219.597804021985, 40388.79805600968, 43311.0, 42720.35919461211, 43146.99999999992, 43147.0, 43181.0, 43205.35191972123, 7781.26078022587, 8525.829483410329, 8704.869426958569, 8705.95647021764, 8708.477491350508, 7745.496542724733, 10072.446015469146, 10284.125849302782, 10331.070203681667, 10593.0, 10351.171906247482, 10408.687739367793, 10425.582976680924, 10425.843612345821, 10459.529321180527, 10100.191984641211, 10138.138258248058, 10161.644342448053, 10204.795904081877, 10215.284930054937, 10166.593336266615, 10240.129588984944, 10259.43573103479, 10263.281570290083, 10273.434961077137, 39547.20905581901, 39794.80102599497, 39825.20349592992, 39848.80075599632, 39894.0, 3349.8492567834264, 3351.312980838922, 3397.6262611112757, 3449.862005519776, 3494.0740703713536, 26080.478390432087, 27789.0, 28252.858735706395, 28323.150305490868, 28366.0, 3953.596010570769, 4030.5364883038533, 4039.091204478969, 4097.528784189826, 4231.519919154283, 310678.0, 311127.444362779, 312372.0, 312524.81113538746, 313186.0, 49293.50706492961, 49503.0, 49695.25457118146, 49883.501164988615, 49935.75032124816, 49620.25569616459, 49863.0, 49994.75002624963, 50008.24987625189, 50072.74963625159, 13642.590722278348, 13988.160710357412, 13997.160170389605, 14006.159630422208, 14012.859871401359, 10381.896181038244, 10606.469676516142, 11005.064569511682, 11091.168162387854, 11138.053265472363, 15383.769243461356, 15582.298796554182, 15771.605709857116, 15961.0, 15968.441104561285, 6255.343188965184, 6415.166028416276, 6445.677716114175, 6452.451541619015, 6532.640704761188, 860.9913900861036, 957.2485598805044, 978.7312264943051, 1010.6260683546988, 1070.0797314309686, 29729.256768580526, 29729.85135074311, 30150.24624384364, 30230.697693023227, 30356.54465183024, 970.9029097090234, 1027.753339198602, 1065.3554599467366, 1077.4289626497969, 1087.7770057138268, 15465.0, 15969.36122555108, 15998.520044398687, 16156.0, 16261.674766504604, 495088.0, 499763.0, 502183.0, 502277.0, 504132.0, 54085.45914540883, 54573.0, 54600.180997285075, 54613.72693136509, 54701.0, 1214.5991822698488, 1250.9249445033145, 1288.1240756285629, 1318.762622727899, 1319.9274039927896, 27083.86458067697, 30377.240568985068, 31035.361994230745, 31077.689223107478, 31646.841765791025, 41483.37774933439, 41740.0, 41813.37279940742, 41824.581754182065, 41829.16341673089, 359123.0, 360030.0, 360413.0, 360638.0, 360843.0, 22597.43506412353, 23235.186768463023, 23308.417289567897, 25974.22077337683, 26611.201663950113, 37373.4393984085, 37982.81008594939, 38032.61967380291, 38036.80981595074, 38448.423273650355, 32150.0, 32916.50625240671, 32922.5061624071, 33725.49411758874, 35301.2343333657, 34404.0, 34464.65535344614, 34581.0, 34814.303713926085, 35731.0, 34169.31661366808, 34971.0, 35336.46995295021, 35537.64462355343, 35980.10049748777, 29721.25696857596, 31591.526127108573, 31600.525992110597, 31615.20961975969, 31942.201444964063, 24222.515549688556, 24569.877150614848, 24822.0, 24945.875270623532, 25115.246542603752], "value": 18418.011898029872, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.7782635852592, 373.5386797305329, 373.63383883793875, 381.6584157179325, 392.6505410184936, 14057.367418466169, 17520.47438576843, 17805.46583602494, 18355.908220458896, 18640.440786776384, 17458.0, 17692.7609719382, 17700.380486682967, 17747.201375938082, 17896.910515447405, 5546.972265138668, 5659.8585035374035, 5662.122371032476, 5666.150077488389, 5714.114312281603, 4054.108096218842, 4112.9588704112875, 5595.468430499096, 5699.629524080921, 5703.77184912603, 5585.636933599303, 5710.314762228539, 5729.541636669056, 5731.713414329267, 5735.0, 5709.257796486451, 5711.657300561958, 5718.456746609066, 6151.169592105053, 6202.751889924398, 5399.730013499309, 5431.619786614939, 5440.401555828856, 5443.1563107717975, 5447.700376479292, 21454.356369308945, 21636.459088522755, 21740.239091631876, 21794.0, 21893.781062189493, 2241.767028134536, 2331.5570041692026, 2362.8464149830206, 2961.0, 3006.4137493188837, 12914.612561623166, 13807.378667959865, 14013.369398376995, 14405.135691858417, 14503.92748036253, 1886.462358227912, 1886.76415448069, 1907.8759880607715, 1910.732497450358, 1920.1455352368203, 67568.3243167567, 77589.30133733283, 80514.59742701308, 82012.0, 83635.0, 10303.742406439898, 10306.33008854422, 10474.004969527883, 10546.841797373045, 10614.416207108528, 14501.492447764278, 14553.854461455461, 14564.49024284145, 14572.562823115324, 14583.854161458461, 7472.9911461953025, 7631.923680763232, 7705.753245076495, 7719.922800772033, 7762.379009679212, 7264.273572642694, 7310.195878453366, 7535.773926782205, 7548.320651141424, 7554.848903022019, 4366.781660916941, 4389.582989615981, 4455.576720211575, 4485.573870482299, 4632.72203667781, 1807.4396936949588, 1850.3153833081576, 1860.3116846766513, 1904.5143488410367, 1911.3119277060248, 853.8377708235539, 906.0622255965176, 952.518977916148, 992.8212921674024, 997.5112195024531, 17329.7400538992, 17743.378981735576, 18073.45779626613, 18245.0, 18645.160967756485, 922.2844123037324, 922.5202894494826, 929.1962452478557, 969.1374676537887, 1008.3294609085023, 9621.076533318796, 9911.603535858485, 9932.304738668225, 9998.800023999625, 10009.69970900874, 454666.0, 456254.0, 458859.0, 462615.0, 465676.0, 85259.0, 87083.56458217668, 87710.56144719363, 87798.56100719456, 88188.11811881927, 992.9056739609725, 1038.7403149212696, 1068.5832525315134, 1069.6844430892904, 1070.272214893864, 15157.195918755066, 15363.07821530667, 15784.526464206092, 15859.920700396424, 16108.919455403116, 39706.80146599249, 40138.0, 40375.1924961505, 40975.0, 42373.15253694847, 54491.0, 54671.72664136654, 55095.173572395644, 55520.83906155439, 56033.0, 6199.380061993945, 8233.917660823316, 8291.170882911902, 8395.412321137706, 8397.496150230761, 10398.0, 12176.330301833485, 12548.560800371943, 12626.494940202656, 12637.368131593568, 10916.617918372818, 10979.39613321243, 11044.613438529614, 11055.391953442639, 11151.330920144817, 10724.839127412939, 11049.779004420026, 11073.501692423699, 11074.557017719524, 11110.555577777122, 10220.0, 10308.12380947628, 10593.629222977159, 10759.3544387337, 10906.127509799355, 8167.591620418836, 8236.629351679308, 8290.87563686533, 8512.361572882182, 8524.616392262487, 9325.487098209393, 10774.138068954308, 10965.451727413438, 11138.94430527842, 11293.0], "value": 8862.244768172803, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1329.4549234813728, 1391.71469848681, 1392.9721405571888, 1527.6486408126132, 1718.6304944436952, 7118.216996130428, 8024.237697418743, 8311.044229913561, 8319.084900660937, 8556.871646925301, 8291.378146639012, 8362.16378362165, 8366.581670916461, 8369.539675317856, 8373.497590144596, 1878.6994080947052, 1940.0, 2168.750593681727, 2199.8900054997184, 2200.020990659157, 2114.164904862578, 2181.2801775414155, 2189.441692368444, 2207.0950910126853, 2210.2595630463816, 2161.5784921940226, 2191.9259562814186, 2193.4187440328324, 2195.078067211775, 2197.044285735702, 2134.6157691615504, 2171.0338899189865, 2177.6733489976473, 2197.3298144066052, 2222.7777222277805, 2075.086961736846, 2088.363049269972, 2109.4620871677776, 2112.6302896992966, 2145.538709177534, 8395.580220988926, 8439.0, 8446.788830279293, 8468.195521425454, 8479.0, 4873.561379475864, 5003.274525193863, 5004.649674522785, 5008.524190201925, 5008.6744361616375, 3078.5382192671054, 3159.873605055795, 3323.80057196569, 3344.66553344666, 3357.429237029686, 969.9175570076552, 1016.6441745389128, 1051.0645525482328, 1055.6463584699136, 1056.514003558364, 11427.828582571268, 11477.598284060101, 11485.0811935045, 11486.483108260232, 11494.252873563191, 4083.265012297815, 4347.804348804311, 4403.647708183337, 4407.911841763211, 4449.57729015743, 9718.12536871685, 9735.902640973642, 9738.367006144577, 9740.75648108803, 9742.220622350194, 5000.67495612784, 5047.570956468668, 5052.115879721034, 5057.317262169634, 5090.41460232078, 4443.644508439317, 4532.818687252539, 4650.813967441332, 4659.743714095689, 4669.159551280734, 3517.2262102337445, 3555.8933232003083, 3590.3716849551224, 3616.72874534411, 3651.2514934438377, 1105.3202280597536, 1107.363266121983, 1111.1222134513805, 1116.659418877234, 1123.1239633086207, 929.363386080537, 935.9532023398802, 943.5848226780195, 947.1191791633709, 992.0030369478628, 5544.390117087117, 5574.693391863487, 5637.548996080303, 5775.971120144372, 6024.668643224577, 408.3629537920853, 412.4020170752421, 413.8158519458812, 414.997925010373, 425.1603083909316, 3931.8427262909736, 3939.2712348215673, 3980.2238563480423, 4005.118873847749, 4094.4267802507675, 9925.801483970281, 10086.899131008597, 10352.430616316175, 10361.274710770174, 10393.896061039293, 10042.297039207331, 10167.4916254187, 10197.643082492077, 10198.745031374126, 10201.94899025515, 7368.0, 7700.537967721954, 7757.0, 7757.612119394008, 7881.763547093597, 5693.772249109989, 5760.683162426107, 5990.400959904149, 6017.3681763414215, 6068.453839154321, 9280.62877484893, 9302.255819534286, 9481.431114133175, 9548.761280968032, 9582.808343833223, 8872.201501864864, 9288.767780805534, 9295.209907157967, 9511.334206605472, 9545.427274363561, 6716.529842910949, 6907.481938854506, 7553.433492487976, 7691.538507689556, 7772.378209743094, 7481.925180748123, 7864.921350786648, 8057.677692892453, 8068.023334283138, 8075.475094118863, 7084.36240738336, 7138.39323657495, 7203.495755297079, 7216.855662886818, 7296.379807716404, 6744.865102698017, 6759.526833121634, 6879.5184337095925, 6942.097527321416, 7050.823729406806, 6542.705578249085, 6643.335666433318, 6653.367930046637, 6656.800295991128, 6667.399934005963, 4601.608863246662, 4603.194440972749, 4704.952950470589, 4759.381280433521, 4848.660593758403, 4963.950360496493, 5019.799208031638, 5072.264521644267, 5137.075326441127, 5157.200633901752], "value": 4266.863587218232, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.8459247116211, 379.4422199366931, 379.5881468606561, 383.034752423892, 386.49948316929573, 5051.6716413433105, 5192.1432963560965, 5236.26692263083, 5275.947240527598, 5292.761825717837, 4998.400191976968, 5143.4599367066385, 5143.742812859361, 5170.870728231796, 5216.6087543434205, 4094.97952510237, 4104.774237416941, 4133.855315063974, 4134.875953721393, 4138.5033795944455, 4070.796460176994, 4119.3409054551275, 4123.917521649565, 4126.958730412703, 4180.540140584533, 4122.6083522065355, 4128.2981893078095, 4162.854300099497, 4174.373843923406, 4175.519815221258, 4122.4640796696385, 4132.855350062749, 4133.751974881517, 4136.834526618932, 4188.895277618053, 3763.473113764075, 3769.095417099904, 3785.772853628791, 3786.7917264550438, 3790.6019867914024, 4933.1860243059855, 5008.198688209905, 5046.697198168122, 5065.518775716338, 5091.159958606825, 4701.0, 4713.670043096985, 4731.313959475891, 4763.976180119112, 4775.904481910342, 5383.730813459311, 5388.622796404254, 5399.78400863965, 5409.566464886808, 5412.539934105605, 3850.5379354477363, 4005.83976640934, 4026.3693204252327, 4033.616806403387, 4038.1531020630387, 5644.774209031634, 5660.0, 5664.49019588239, 5673.6595804251465, 5681.34664513578, 4227.7040607157205, 4227.87316380509, 4230.175115852379, 4243.087736136746, 4246.745195288293, 4113.444684967491, 4227.921879920601, 4242.851500197478, 4253.659707223414, 4305.634021108178, 4685.929711054337, 4717.575418212377, 4728.085125524168, 4734.218853889139, 4755.904881902343, 5390.272313237662, 5424.566034717213, 5428.4842939920645, 5449.5640348772795, 5462.590305727086, 1341.1148641896496, 1350.59482155353, 1412.3785534364847, 1413.6465883529117, 1436.3177490691837, 975.3952549419264, 993.5181437003048, 1002.0730823987782, 1009.268280496634, 1014.644874294006, 5052.216906379517, 5073.365829271341, 5100.795968161232, 5119.513646203604, 5123.641345105882, 5297.867553311198, 5307.6019298552765, 5313.628046036741, 5321.627486076015, 5342.813001544927, 4879.5120487951635, 4928.728919909439, 5015.398152221756, 5132.871678208075, 5134.974325128351, 2777.6944536101014, 2782.38787466757, 2825.488363725438, 2828.872700728472, 2844.5590933405356, 5457.645253058538, 5460.863478412992, 5473.67157970523, 5481.972590137024, 5498.917516237259, 5223.388659864819, 5270.518660593017, 5351.598630102758, 5402.8919421611345, 5410.646368829572, 5369.436209197978, 5423.945760542344, 5424.535375448686, 5444.700541470178, 5471.50756431923, 5264.6314757966575, 5284.894302114013, 5286.550643195372, 5286.8942621148135, 5308.708021058725, 5015.689644834538, 5248.816291429782, 5265.763040663102, 5278.208268759563, 5283.630145889904, 5400.918986215132, 5475.835724928259, 5483.698396588066, 5484.506394424521, 5497.257870187634, 4047.575004624472, 4064.6138616831354, 4068.389741538793, 4077.2049450356894, 4084.162746636992, 5059.595232381327, 5264.052470555337, 5312.468753124656, 5313.362396512443, 5334.599905007062, 5241.135212690016, 5275.577953763766, 5461.0, 5461.617686761889, 5474.288342515448, 5319.574434045345, 5374.623776335619, 5427.755750991294, 5456.890862182813, 5458.727063646881, 5133.948660513347, 5338.839834804962, 5345.893082138257, 5373.898350838147, 5379.784808607612, 5432.0, 5448.610604295879, 5500.779968801363, 5513.531349835149, 5526.50261476469, 5391.272178255886, 5405.567554595702, 5418.810341638024, 5437.5318663959715, 5438.592105592017], "value": 4156.041083606203, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.3615482220824, 750.0249675421953, 753.9924600753998, 775.8603451378754, 778.9571573563453, 15614.0, 16497.67004659906, 16501.17494125295, 16648.417305394312, 16669.49991500254, 13333.06668533203, 13379.732405351888, 13387.665308367272, 13392.330383480836, 13395.464181432731, 649.7401039584157, 667.3560014585923, 753.4876284126792, 761.1855314813139, 768.3852917665873, 779.6608475313228, 782.7377828427484, 784.674360140541, 786.2530595933857, 788.1448628238339, 826.6982551368767, 828.4159667434475, 828.4283844147519, 828.8466633672759, 828.9927737798573, 685.1675214614243, 701.1656129206228, 803.4777394693438, 824.2005254902765, 825.6119623776815, 607.2015299880643, 607.5504126946072, 607.6020206764576, 607.6354187487516, 607.9878402431926, 15394.384224631114, 15411.537653870402, 15417.614559636098, 15425.228738563026, 15435.768463473058, 12561.685957851036, 12961.15752476086, 13652.726945461134, 14343.28283585827, 14432.422703091865, 12826.743465130645, 12852.742945141139, 12920.0, 13217.801732974012, 13237.0, 3279.6149327569683, 3453.689167974869, 4441.267190913528, 4549.431321084864, 4561.224591819366, 78726.6063669678, 80351.0, 81825.0, 83400.0, 83674.03052526868, 10709.0, 11036.668899933014, 11160.832587511193, 11208.27146235492, 12325.322107284006, 19826.70259946102, 20582.897085514775, 20774.792252077285, 20779.68830467544, 21083.051262693214, 8330.500169989698, 8537.786555336166, 8664.263537599249, 8670.523121228392, 8675.262602678717, 4501.234790085693, 4596.287575425815, 4674.415698037746, 4688.671792974527, 4736.052789442126, 3300.290437555937, 3358.059743271888, 3372.9325413491592, 3386.408388057609, 3396.609389920191, 583.9474447299679, 588.5762251179144, 590.5600327755849, 592.5585438848086, 616.0543565626741, 662.4799532367068, 701.4388489208715, 706.5478094019832, 712.8752468318128, 731.7365748330596, 9086.227670647937, 9265.907340926504, 9563.569639366246, 9620.13418792298, 9783.364081334832, 605.6638565596137, 649.3571364349355, 682.6211452643731, 689.7961014558438, 696.9163700355888, 4478.596926276651, 4689.226277664147, 4721.716696998123, 4722.504137065627, 4780.616234299676, 81966.0, 82243.17756822475, 85044.0, 85676.57161714276, 87665.0, 71880.0, 72866.27133728594, 73460.0, 76224.61887690525, 76535.0, 62113.0, 64180.35819641744, 65573.34426655767, 66956.6652166736, 69676.0, 8753.0, 8878.600462979311, 9044.0, 9135.725928222164, 9357.204637605608, 40060.59939400685, 40887.795561022, 41766.582334176266, 42173.578264218195, 42855.0, 57810.13284800816, 57937.71031144817, 58769.11846322394, 59676.0, 60388.698056509435, 11560.479778409823, 11605.245659032136, 11905.16663833558, 11929.522819087138, 11939.223950443193, 13987.860121399062, 14151.929240353733, 14716.55850324492, 14742.778858316919, 14895.627609309853, 12922.160059596095, 12926.353682316034, 12959.287239201934, 12969.092163548838, 13230.272335021666, 13467.259300738162, 13593.79609305881, 13771.862281377058, 13855.307234638429, 13979.161250325013, 12313.199642023239, 13518.797218041935, 13556.796648050089, 13850.376733046836, 14015.789763153356, 10757.246992710749, 10821.404822734825, 10973.177011723992, 11063.778724425627, 11215.887841121485, 9702.805943881223, 9741.902580974383, 10827.458627068772, 10952.671419857417, 11144.2199046066], "value": 7140.2725172014125, "unit": "score"}], "name": "dromaeo_css", "value": 6887.727059115628, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 113.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 119.0, 126.0, 156.0, 119.0, 121.0, 125.0, 124.0, 143.0, 119.0], "value": 130.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 159.0, 161.0, 163.0, 159.0, 162.0, 163.0, 162.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 104.0, 75.0, 75.0, 76.0, 76.0, 90.0, 74.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 107.0, 84.0, 84.0, 83.0, 97.0, 84.0, 83.0, 111.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 128.0, 129.0, 127.0, 127.0, 127.0, 127.0, 127.0, 127.0, 127.0], "value": 127.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 147.0, 145.0, 147.0, 146.0, 146.0, 145.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 95.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 53.0, 50.0, 54.0, 53.0, 51.0, 52.0, 52.0, 53.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 63.0, 63.0, 62.0, 63.0, 63.0, 66.0, 63.0], "value": 63.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 79.0, 82.0, 80.0, 79.0, 88.0, 79.0, 82.0, 78.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 133.0, 124.0, 125.0, 130.0, 126.0, 124.0, 125.0, 169.0, 134.0], "value": 131.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 164.0, 162.0, 169.0, 167.0, 164.0, 167.0, 165.0, 166.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 65.0, 64.0, 62.0, 63.0, 63.0, 63.0, 64.0, 63.0], "value": 63.4, "unit": "score"}], "name": "kraken", "value": 1498.8999999999999, "alertThreshold": 2.0}]} 23:35:50 INFO - Return code: 0 23:35:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:35:50 INFO - # TBPL SUCCESS # 23:35:50 INFO - Running post-action listener: _resource_record_post_action 23:35:50 INFO - [mozharness: 2016-12-18 07:35:50.884619Z] Finished run-tests step (success) 23:35:50 INFO - Running post-run listener: _resource_record_post_run 23:35:50 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 406327296; Read time: 8; Write time: 439608 23:35:50 INFO - TinderboxPrint: CPU usage
13.2% 23:35:50 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 23:35:50 INFO - TinderboxPrint: I/O write bytes / time
406,327,296 / 439,608 23:35:50 INFO - TinderboxPrint: CPU idle
8,286.4 (86.8%) 23:35:50 INFO - TinderboxPrint: CPU user
1,211.6 (12.7%) 23:35:50 INFO - TinderboxPrint: Swap in / out
0 / 0 23:35:50 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 116228096; Read time: 0; Write time: 141044 23:35:50 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 290099200; Read time: 0; Write time: 298564 23:35:51 INFO - Running post-run listener: _upload_blobber_files 23:35:51 INFO - Blob upload gear active. 23:35:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:35:51 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:35:51 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:35:51 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:35:51 INFO - (blobuploader) - INFO - Open directory for files ... 23:35:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:35:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:35:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:35:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:35:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:35:52 INFO - (blobuploader) - INFO - Done attempting. 23:35:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:35:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:35:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:35:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:35:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:35:53 INFO - (blobuploader) - INFO - Done attempting. 23:35:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:35:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:35:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:35:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:35:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:35:54 INFO - (blobuploader) - INFO - Done attempting. 23:35:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:35:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:35:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:35:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:35:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:35:58 INFO - (blobuploader) - INFO - Done attempting. 23:35:58 INFO - (blobuploader) - INFO - Iteration through files over. 23:35:58 INFO - Return code: 0 23:35:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:35:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:35:58 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5649d21e8d359318103940cc220567a0a100b7fb71f1f846d1293056629d815a225de35fadedd2fb640ee65bcad0da837df6ae513136c0357c618e2c67ed18c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4e69b92077257cffbc15721c226981680832da19e50325e2fd4094ec2a5fdead8054aa087c157f246efeeb2b23ad4787451500080b06bd065fa2c4a4c0dc99c3", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d80809d0bb11580c358bf0557fe032aa9398885f6eb91a6c1f1ab2f0d5ac15332e0b7c8cd8476a893c6cf898b408dcc1be058d36ba6a7ef28d2345c895d4bc71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/db269af66681ec368467c33cd783fddaf83660736ded20a276514687bf2055ae539eac62211ca3cd8f07c9f7de0f5a294b99416d561afe447a567e9ba59cd166"} 23:35:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:35:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:35:58 INFO - Contents: 23:35:58 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5649d21e8d359318103940cc220567a0a100b7fb71f1f846d1293056629d815a225de35fadedd2fb640ee65bcad0da837df6ae513136c0357c618e2c67ed18c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4e69b92077257cffbc15721c226981680832da19e50325e2fd4094ec2a5fdead8054aa087c157f246efeeb2b23ad4787451500080b06bd065fa2c4a4c0dc99c3", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d80809d0bb11580c358bf0557fe032aa9398885f6eb91a6c1f1ab2f0d5ac15332e0b7c8cd8476a893c6cf898b408dcc1be058d36ba6a7ef28d2345c895d4bc71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/db269af66681ec368467c33cd783fddaf83660736ded20a276514687bf2055ae539eac62211ca3cd8f07c9f7de0f5a294b99416d561afe447a567e9ba59cd166"} 23:35:58 INFO - Running post-run listener: copy_logs_to_upload_dir 23:35:58 INFO - Copying logs to upload dir... 23:35:58 INFO - mkdir: /builds/slave/test/build/upload/logs 23:35:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.227013 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-17 23:35:58.372511) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 23:35:58.375961) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5649d21e8d359318103940cc220567a0a100b7fb71f1f846d1293056629d815a225de35fadedd2fb640ee65bcad0da837df6ae513136c0357c618e2c67ed18c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4e69b92077257cffbc15721c226981680832da19e50325e2fd4094ec2a5fdead8054aa087c157f246efeeb2b23ad4787451500080b06bd065fa2c4a4c0dc99c3", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d80809d0bb11580c358bf0557fe032aa9398885f6eb91a6c1f1ab2f0d5ac15332e0b7c8cd8476a893c6cf898b408dcc1be058d36ba6a7ef28d2345c895d4bc71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/db269af66681ec368467c33cd783fddaf83660736ded20a276514687bf2055ae539eac62211ca3cd8f07c9f7de0f5a294b99416d561afe447a567e9ba59cd166"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011396 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482039010/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5649d21e8d359318103940cc220567a0a100b7fb71f1f846d1293056629d815a225de35fadedd2fb640ee65bcad0da837df6ae513136c0357c618e2c67ed18c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4e69b92077257cffbc15721c226981680832da19e50325e2fd4094ec2a5fdead8054aa087c157f246efeeb2b23ad4787451500080b06bd065fa2c4a4c0dc99c3", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d80809d0bb11580c358bf0557fe032aa9398885f6eb91a6c1f1ab2f0d5ac15332e0b7c8cd8476a893c6cf898b408dcc1be058d36ba6a7ef28d2345c895d4bc71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/db269af66681ec368467c33cd783fddaf83660736ded20a276514687bf2055ae539eac62211ca3cd8f07c9f7de0f5a294b99416d561afe447a567e9ba59cd166"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 23:35:58.401661) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 23:35:58.402814) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1482040793.852485-2059241774 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004401 ========= master_lag: 0.49 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 23:35:58.893771) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 23:35:58.894073) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 23:35:58.897723) ========= ========= Total master_lag: 0.59 =========